[ 0.000000] Linux version 3.6.0-rc1-00010-g25cd20b (kbuild@snb) (gcc version 4.7.1 (Debian 4.7.1-6) ) #68 SMP PREEMPT Sat Aug 11 00:49:07 CST 2012 [ 0.000000] Command line: trinity=2m hung_task_panic=1 branch=miklos-vfs/overlayfs.v14 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-11-00-50-38-miklos-vfs:overlayfs.v14:1182ebc-25cd20b-x86_64-randconfig-s059-1-stoakley BOOT_IMAGE=kernel-tests/kernels/x86_64-randconfig-s059/25cd20b/vmlinuz-3.6.0-rc1-00010-g25cd20b [ 0.000000] Disabled fast string operations [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009f3ff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009f400-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000000fffcfff] usable [ 0.000000] BIOS-e820: [mem 0x000000000fffd000-0x000000000fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffbc000-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] No AGP bridge found [ 0.000000] e820: last_pfn = 0xfffd max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: write-back [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-FFFFF write-protect [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 00E0000000 mask FFE0000000 uncachable [ 0.000000] 1 disabled [ 0.000000] 2 disabled [ 0.000000] 3 disabled [ 0.000000] 4 disabled [ 0.000000] 5 disabled [ 0.000000] 6 disabled [ 0.000000] 7 disabled [ 0.000000] x86 PAT enabled: cpu 0, old 0x0, new 0x7010600070106 [ 0.000000] 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 0x000f8860-0x000f886f] mapped at [ffff8800000f8860] [ 0.000000] mpc: f8870-f898c [ 0.000000] initial memory mapped: [mem 0x00000000-0x1fffffff] [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x0fffcfff] [ 0.000000] [mem 0x00000000-0x0fdfffff] page 2M [ 0.000000] [mem 0x0fe00000-0x0fffcfff] page 4k [ 0.000000] kernel direct mapping tables up to 0xfffcfff @ [mem 0x0e854000-0x0e8d5fff] [ 0.000000] log_buf_len: 8388608 [ 0.000000] early log buf free: 128060(97%) [ 0.000000] RAMDISK: [mem 0x0e8d6000-0x0ffeffff] [ 0.000000] ACPI: RSDP 00000000000f8810 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000000fffde10 00034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000000ffffe40 00074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000000fffdfd0 01E22 (v01 BXPC BXDSDT 00000001 INTL 20090123) [ 0.000000] ACPI: FACS 000000000ffffe00 00040 [ 0.000000] ACPI: SSDT 000000000fffdf80 00044 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 000000000fffde90 0007A (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 000000000fffde50 00038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fb000 ( fee00000) [ 0.000000] kvm-clock: Using msrs 12 and 11 [ 0.000000] kvm-clock: cpu 0, msr 0:1e2d0c1, boot clock [ 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-0x0009efff] [ 0.000000] node 0: [mem 0x00100000-0x0fffcfff] [ 0.000000] On node 0 totalpages: 65420 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 6 pages reserved [ 0.000000] DMA zone: 3913 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 960 pages used for memmap [ 0.000000] DMA32 zone: 60477 pages, LIFO batch:15 [ 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: 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] e820: [mem 0x10000000-0xfffbbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 474 pages/cpu @ffff88000da00000 s1912256 r8192 d21056 u2097152 [ 0.000000] pcpu-alloc: s1912256 r8192 d21056 u2097152 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] kvm-clock: cpu 0, msr 0:dbd20c1, primary cpu clock [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64390 [ 0.000000] Kernel command line: trinity=2m hung_task_panic=1 branch=miklos-vfs/overlayfs.v14 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-11-00-50-38-miklos-vfs:overlayfs.v14:1182ebc-25cd20b-x86_64-randconfig-s059-1-stoakley BOOT_IMAGE=kernel-tests/kernels/x86_64-randconfig-s059/25cd20b/vmlinuz-3.6.0-rc1-00010-g25cd20b [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 1024 (order: 1, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 16384 (order: 5, 131072 bytes) [ 0.000000] __ex_table already sorted, skipping sort [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 194932k/262132k available (4402k kernel code, 452k absent, 66748k reserved, 8244k data, 2448k init) [ 0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] NR_IRQS:4352 nr_irqs:512 16 [ 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: 2 of 2 active objects replaced [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 2833.416 MHz processor [ 0.004000] Calibrating delay loop (skipped) preset value.. 5666.83 BogoMIPS (lpj=11333664) [ 0.004000] pid_max: default: 32768 minimum: 301 [ 0.004000] Security Framework initialized [ 0.004000] Yama: becoming mindful. [ 0.004174] Mount-cache hash table entries: 256 [ 0.006516] Disabled fast string operations [ 0.007077] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.007077] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.007077] tlb_flushall_shift is 0x6 [ 0.013262] ACPI: Core revision 20120711 [ 0.016550] ftrace: allocating 11289 entries in 45 pages [ 0.020371] Getting VERSION: 50014 [ 0.020823] Getting VERSION: 50014 [ 0.021259] Getting ID: 0 [ 0.021600] Getting ID: ff000000 [ 0.022023] Getting LVT0: 8700 [ 0.024015] Getting LVT1: 8400 [ 0.024473] enabled ExtINT on CPU#0 [ 0.025946] ENABLING IO-APIC IRQs [ 0.026377] init IO_APIC IRQs [ 0.026756] apic 2 pin 0 not connected [ 0.027270] IOAPIC[0]: Set routing entry (2-1 -> 0x41 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.028038] IOAPIC[0]: Set routing entry (2-2 -> 0x51 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.029051] IOAPIC[0]: Set routing entry (2-3 -> 0x61 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.030068] IOAPIC[0]: Set routing entry (2-4 -> 0x71 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.031078] IOAPIC[0]: Set routing entry (2-5 -> 0x81 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.032072] IOAPIC[0]: Set routing entry (2-6 -> 0x91 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.033071] IOAPIC[0]: Set routing entry (2-7 -> 0xa1 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.034068] IOAPIC[0]: Set routing entry (2-8 -> 0xb1 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.035066] IOAPIC[0]: Set routing entry (2-9 -> 0xc1 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.036033] IOAPIC[0]: Set routing entry (2-10 -> 0xd1 -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.037051] IOAPIC[0]: Set routing entry (2-11 -> 0xe1 -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.038112] IOAPIC[0]: Set routing entry (2-12 -> 0x22 -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.040033] IOAPIC[0]: Set routing entry (2-13 -> 0x42 -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.041067] IOAPIC[0]: Set routing entry (2-14 -> 0x52 -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.042083] IOAPIC[0]: Set routing entry (2-15 -> 0x62 -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.043100] apic 2 pin 16 not connected [ 0.043596] apic 2 pin 17 not connected [ 0.044009] apic 2 pin 18 not connected [ 0.044526] apic 2 pin 19 not connected [ 0.045017] apic 2 pin 20 not connected [ 0.045511] apic 2 pin 21 not connected [ 0.046001] apic 2 pin 22 not connected [ 0.046495] apic 2 pin 23 not connected [ 0.047142] ..TIMER: vector=0x51 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.048009] smpboot: CPU0: Intel Common KVM processor stepping 01 [ 0.049259] Using local APIC timer interrupts. [ 0.049259] calibrating APIC timer ... [ 0.052003] ... lapic delta = 6249955 [ 0.052003] ... PM-Timer delta = 357952 [ 0.052003] ... PM-Timer result ok [ 0.052003] ..... delta 6249955 [ 0.052003] ..... mult: 268416747 [ 0.052003] ..... calibration result: 3999971 [ 0.052003] ..... CPU clock speed is 2833.1231 MHz. [ 0.052003] ..... host bus clock speed is 999.3971 MHz. [ 0.052068] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.072437] Testing tracer nop: PASSED [ 0.084121] SMP alternatives: lockdep: fixing up alternatives [ 0.085243] smpboot: Booting Node 0, Processors #1 OK [ 0.004000] kvm-clock: cpu 1, msr 0:ddd20c1, secondary cpu clock [ 0.004000] masked ExtINT on CPU#1 [ 0.004000] Disabled fast string operations [ 0.120138] Brought up 2 CPUs [ 0.120536] smpboot: Total of 2 processors activated (11333.66 BogoMIPS) [ 0.124256] x86 PAT enabled: cpu 0, old 0x70106, new 0x7010600070106 [ 0.125311] CPU0 attaching sched-domain: [ 0.125852] domain 0: span 0-1 level CPU [ 0.126375] groups: 0 (cpu_power = 1023) 1 [ 0.126994] CPU1 attaching sched-domain: [ 0.127482] domain 0: span 0-1 level CPU [ 0.127995] groups: 1 0 (cpu_power = 1023) [ 0.132037] devtmpfs: initialized [ 0.137983] atomic64 test passed for x86-64 platform with CX8 and with SSE [ 0.139498] dummy: [ 0.140296] NET: Registered protocol family 16 [ 0.141519] ACPI: bus type pci registered [ 0.142213] PCI: Using configuration type 1 for base access [ 0.143016] mtrr: your CPUs had inconsistent variable MTRR settings [ 0.143781] mtrr: your CPUs had inconsistent MTRRdefType settings [ 0.144020] mtrr: probably your BIOS does not setup all CPUs. [ 0.144722] mtrr: corrected configuration. [ 0.148387] ACPI: Added _OSI(Module Device) [ 0.148879] ACPI: Added _OSI(Processor Device) [ 0.152022] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.152613] ACPI: Added _OSI(Processor Aggregator Device) [ 0.157063] ACPI: EC: Look up EC in DSDT [ 0.164381] ACPI: Interpreter enabled [ 0.164844] ACPI: (supports S0 S3 S5) [ 0.165427] ACPI: Using IOAPIC for interrupt routing [ 0.181163] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug [ 0.182440] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.183257] pci_root PNP0A03:00: host bridge window [io 0x0000-0x0cf7] (ignored) [ 0.184025] pci_root PNP0A03:00: host bridge window [io 0x0d00-0xffff] (ignored) [ 0.184926] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored) [ 0.185969] pci_root PNP0A03:00: host bridge window [mem 0xe0000000-0xfebfffff] (ignored) [ 0.186966] PCI: root bus 00: using default resources [ 0.188034] PCI host bridge to bus 0000:00 [ 0.188564] pci_bus 0000:00: busn_res: [bus 00-ff] is inserted under domain [bus 00-ff] [ 0.189571] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.190276] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 0.191027] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff] [ 0.191980] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.192469] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.193843] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.194936] pci 0000:00:01.1: reg 20: [io 0xc000-0xc00f] [ 0.196301] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.197609] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 0.198512] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB [ 0.199573] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 0.203209] pci 0000:00:02.0: reg 10: [mem 0xf0000000-0xf1ffffff pref] [ 0.205544] pci 0000:00:02.0: reg 14: [mem 0xf2000000-0xf2000fff] [ 0.214355] pci 0000:00:02.0: reg 30: [mem 0xf2010000-0xf201ffff pref] [ 0.216337] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.217220] pci 0000:00:03.0: reg 10: [mem 0xf2020000-0xf203ffff] [ 0.218143] pci 0000:00:03.0: reg 14: [io 0xc040-0xc07f] [ 0.219574] pci 0000:00:03.0: reg 30: [mem 0xf2040000-0xf2047fff pref] [ 0.220343] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.221271] pci 0000:00:04.0: reg 10: [io 0xc080-0xc0bf] [ 0.222032] pci 0000:00:04.0: reg 14: [mem 0xf2048000-0xf2048fff] [ 0.224061] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.224937] pci 0000:00:05.0: reg 10: [io 0xc0c0-0xc0ff] [ 0.225723] pci 0000:00:05.0: reg 14: [mem 0xf2049000-0xf2049fff] [ 0.227208] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.228106] pci 0000:00:06.0: reg 10: [io 0xc100-0xc13f] [ 0.228858] pci 0000:00:06.0: reg 14: [mem 0xf204a000-0xf204afff] [ 0.230381] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 [ 0.231259] pci 0000:00:07.0: reg 10: [io 0xc140-0xc17f] [ 0.232037] pci 0000:00:07.0: reg 14: [mem 0xf204b000-0xf204bfff] [ 0.233527] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 [ 0.234428] pci 0000:00:08.0: reg 10: [io 0xc180-0xc1bf] [ 0.235180] pci 0000:00:08.0: reg 14: [mem 0xf204c000-0xf204cfff] [ 0.236673] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000 [ 0.237579] pci 0000:00:09.0: reg 10: [io 0xc1c0-0xc1ff] [ 0.238356] pci 0000:00:09.0: reg 14: [mem 0xf204d000-0xf204dfff] [ 0.239797] pci 0000:00:0a.0: [8086:25ab] type 00 class 0x088000 [ 0.240120] pci 0000:00:0a.0: reg 10: [mem 0xf204e000-0xf204e00f] [ 0.241773] pci_bus 0000:00: on NUMA node 0 [ 0.242382] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [ 0.245414] pci0000:00: Unable to request _OSC control (_OSC support mask: 0x08) [ 0.265972] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.266914] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.267879] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.268393] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.269700] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.269700] vgaarb: loaded [ 0.272034] vgaarb: bridge control possible 0000:00:02.0 [ 0.272793] PCI: Using ACPI for IRQ routing [ 0.273325] PCI: pci_cache_line_size set to 64 bytes [ 0.274439] e820: reserve RAM buffer [mem 0x0009f400-0x0009ffff] [ 0.275188] e820: reserve RAM buffer [mem 0x0fffd000-0x0fffffff] [ 0.277107] Switching to clocksource kvm-clock [ 0.293220] Warning: could not register all branches stats [ 0.293924] Warning: could not register annotated branches stats [ 0.310096] pnp: PnP ACPI init [ 0.310527] ACPI: bus type pnp registered [ 0.311096] pnp 00:00: [bus 00-ff] [ 0.311524] pnp 00:00: [io 0x0cf8-0x0cff] [ 0.312093] pnp 00:00: [io 0x0000-0x0cf7 window] [ 0.312709] pnp 00:00: [io 0x0d00-0xffff window] [ 0.313296] pnp 00:00: [mem 0x000a0000-0x000bffff window] [ 0.313969] pnp 00:00: [mem 0xe0000000-0xfebfffff window] [ 0.314854] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active) [ 0.316101] pnp 00:01: [io 0x0070-0x0071] [ 0.316671] pnp 00:01: [irq 8] [ 0.317056] pnp 00:01: [io 0x0072-0x0077] [ 0.317721] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.318602] pnp 00:02: [io 0x0060] [ 0.319047] pnp 00:02: [io 0x0064] [ 0.319486] pnp 00:02: [irq 1] [ 0.320090] pnp 00:02: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.320980] pnp 00:03: [irq 12] [ 0.321531] pnp 00:03: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.322425] pnp 00:04: [io 0x03f2-0x03f5] [ 0.322965] pnp 00:04: [io 0x03f7] [ 0.323406] pnp 00:04: [irq 6] [ 0.323797] pnp 00:04: [dma 2] [ 0.324419] pnp 00:04: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.325328] pnp 00:05: [io 0x0378-0x037f] [ 0.325853] pnp 00:05: [irq 7] [ 0.326399] pnp 00:05: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.327299] pnp 00:06: [io 0x03f8-0x03ff] [ 0.327826] pnp 00:06: [irq 4] [ 0.328443] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.329357] pnp 00:07: [mem 0xfed00000-0xfed003ff] [ 0.330140] pnp 00:07: Plug and Play ACPI device, IDs PNP0103 (active) [ 0.331184] pnp: PnP ACPI: found 8 devices [ 0.331704] ACPI: ACPI bus type pnp unregistered [ 0.338516] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] [ 0.339207] pci_bus 0000:00: resource 5 [mem 0x00000000-0xffffffffff] [ 0.340114] NET: Registered protocol family 1 [ 0.340706] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.341454] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.342201] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.343015] pci 0000:00:02.0: Boot video device [ 0.343718] PCI: CLS 0 bytes, default 64 [ 0.344626] Unpacking initramfs... [ 1.691578] Freeing initrd memory: 23656k freed [ 1.741569] DMA-API: preallocated 32768 debug entries [ 1.742231] DMA-API: debugging enabled by kernel config [ 1.756604] audit: initializing netlink socket (disabled) [ 1.757382] type=2000 audit(1344618040.756:1): initialized [ 1.766709] Testing tracer function: PASSED [ 1.974421] Testing dynamic ftrace: PASSED [ 2.240334] Testing dynamic ftrace ops #1: (1 0 1 1 0) (1 1 2 1 0) (2 1 3 1 37) (2 2 4 1 153) PASSED [ 2.374205] Testing dynamic ftrace ops #2: (1 0 1 69 0) (1 1 2 151 0) (2 1 3 2 48) (2 2 4 114 159) PASSED [ 2.580292] Testing tracer irqsoff: PASSED [ 2.736251] Testing tracer branch: [ 2.740329] tsc: Refined TSC clocksource calibration: 2833.318 MHz [ 2.784799] ------------[ cut here ]------------ [ 2.785509] WARNING: at /c/kernel-tests/src/tip/kernel/lockdep.c:3493 check_flags+0xc0/0x1d0() [ 2.786661] Hardware name: Bochs [ 2.787155] Modules linked in: [ 2.787860] Pid: 0, comm: swapper/0 Not tainted 3.6.0-rc1-00010-g25cd20b #68 [ 2.788736] Call Trace: [ 2.788736] [] warn_slowpath_common+0xae/0xf0 [ 2.788736] [] warn_slowpath_null+0x1a/0x20 [ 2.788736] [] check_flags+0xc0/0x1d0 [ 2.788736] [] lock_acquire+0x72/0x230 [ 2.788736] [] ? rb_reserve_next_event+0x5f/0x370 [ 2.788736] [] rebalance_domains+0x7b/0x480 [ 2.788736] [] ? load_balance+0xdb0/0xdb0 [ 2.788736] [] ? ftrace_likely_update+0xe5/0x260 [ 2.788736] [] run_rebalance_domains+0x58/0x220 [ 2.788736] [] __do_softirq+0x127/0x6c0 [ 2.788736] [] call_softirq+0x1c/0x30 [ 2.788736] [] do_softirq+0x175/0x280 [ 2.788736] [] irq_exit+0xc7/0x130 [ 2.788736] [] scheduler_ipi+0xfd/0x150 [ 2.788736] [] smp_reschedule_interrupt+0x2a/0x30 [ 2.788736] [] reschedule_interrupt+0x6f/0x80 [ 2.788736] [] ? native_safe_halt+0x6/0x10 [ 2.788736] [] default_idle+0x56d/0x580 [ 2.788736] [] cpu_idle+0x156/0x160 [ 2.788736] [] rest_init+0x160/0x174 [ 2.788736] [] ? csum_partial_copy_generic+0x16c/0x16c [ 2.788736] [] start_kernel+0x61c/0x629 [ 2.788736] [] ? repair_env_string+0x9f/0x9f [ 2.788736] [] x86_64_start_reservations+0x165/0x169 [ 2.788736] [] x86_64_start_kernel+0x281/0x290 [ 2.788736] ---[ end trace 284bb615d494e199 ]--- [ 2.788736] possible reason: unannotated irqs-on. [ 2.788736] irq event stamp: 15967 [ 2.788736] hardirqs last enabled at (15966): [] __do_softirq+0x88/0x6c0 [ 2.788736] hardirqs last disabled at (15967): [] ftrace_likely_update+0x97/0x260 [ 2.788736] softirqs last enabled at (15964): [] _local_bh_enable+0x13/0x20 [ 2.788736] softirqs last disabled at (15965): [] call_softirq+0x1c/0x30 [ 2.892256] PASSED [ 2.893356] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 2.898248] VFS: Disk quotas dquot_6.5.2 [ 2.898878] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 2.900500] cryptomgr_test (27) used greatest stack depth: 6040 bytes left [ 2.903059] alg: No test for stdrng (krng) [ 2.904806] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.905734] ACPI: Power Button [PWRF] [ 2.953588] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 2.997778] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 3.039779] 00:06: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 3.040971] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.066116] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.066786] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.067518] cpuidle: using governor ladder [ 3.068211] cpuidle: using governor menu [ 3.089906] [ 3.089906] printing PIC contents [ 3.090530] ... PIC IMR: ffff [ 3.090924] ... PIC IRR: 1013 [ 3.091315] ... PIC ISR: 0000 [ 3.091708] ... PIC ELCR: 0c00 [ 3.092125] printing local APIC contents on CPU#0/0: [ 3.092772] ... APIC ID: 00000000 (0) [ 3.093280] ... APIC VERSION: 00050014 [ 3.093686] ... APIC TASKPRI: 00000000 (00) [ 3.093686] ... APIC PROCPRI: 00000000 [ 3.093686] ... APIC LDR: 01000000 [ 3.093686] ... APIC DFR: ffffffff [ 3.093686] ... APIC SPIV: 000001ff [ 3.093686] ... APIC ISR field: [ 3.093686] 0000000000000000000000000000000000000000000000000000000000000000 [ 3.093686] ... APIC TMR field: [ 3.093686] 0000000000000000000000000000000000000000000000000000000000000000 [ 3.093686] ... APIC IRR field: [ 3.093686] 0000000000000000000000000000000000000000000000000000000000008000 [ 3.093686] ... APIC ESR: 00000000 [ 3.093686] ... APIC ICR: 000008fd [ 3.093686] ... APIC ICR2: 02000000 [ 3.093686] ... APIC LVTT: 000000ef [ 3.093686] ... APIC LVTPC: 00010000 [ 3.093686] ... APIC LVT0: 00010700 [ 3.093686] ... APIC LVT1: 00000400 [ 3.093686] ... APIC LVTERR: 000000fe [ 3.093686] ... APIC TMICT: 00025c99 [ 3.093686] ... APIC TMCCT: 00000000 [ 3.093686] ... APIC TDCR: 00000003 [ 3.093686] [ 3.133696] number of MP IRQ sources: 15. [ 3.134209] number of IO-APIC #2 registers: 24. [ 3.134770] testing the IO APIC....................... [ 3.135408] IO APIC #2...... [ 3.135773] .... register #00: 00000000 [ 3.136325] ....... : physical APIC id: 00 [ 3.136863] ....... : Delivery Type: 0 [ 3.137354] ....... : LTS : 0 [ 3.137851] .... register #01: 00170011 [ 3.138321] ....... : max redirection entries: 17 [ 3.138941] ....... : PRQ implemented: 0 [ 3.139463] ....... : IO APIC version: 11 [ 3.140022] .... register #02: 00000000 [ 3.140535] ....... : arbitration: 00 [ 3.141034] .... IRQ redirection table: [ 3.141507] NR Dst Mask Trig IRR Pol Stat Dmod Deli Vect: [ 3.142195] 00 00 1 0 0 0 0 0 0 00 [ 3.142872] 01 03 0 0 0 0 0 1 1 41 [ 3.143541] 02 03 0 0 0 0 0 1 1 51 [ 3.144254] 03 01 0 0 0 0 0 1 1 61 [ 3.144949] 04 01 1 0 0 0 0 1 1 71 [ 3.145615] 05 01 1 1 0 0 0 1 1 81 [ 3.146287] 06 01 0 0 0 0 0 1 1 91 [ 3.146958] 07 01 0 0 0 0 0 1 1 A1 [ 3.147625] 08 01 0 0 0 0 0 1 1 B1 [ 3.173610] 09 01 0 1 0 0 0 1 1 C1 [ 3.174321] 0a 01 1 1 0 0 0 1 1 D1 [ 3.175003] 0b 01 1 1 0 0 0 1 1 E1 [ 3.175678] 0c 03 0 0 0 0 0 1 1 22 [ 3.176369] 0d 01 0 0 0 0 0 1 1 42 [ 3.177363] 0e 01 0 0 0 0 0 1 1 52 [ 3.179080] 0f 01 0 0 0 0 0 1 1 62 [ 3.179757] 10 00 1 0 0 0 0 0 0 00 [ 3.186901] 11 00 1 0 0 0 0 0 0 00 [ 3.187597] 12 00 1 0 0 0 0 0 0 00 [ 3.190590] 13 00 [ 3.195129] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 1 0 0 0 0 0 0 00 [ 3.201184] 14 00 1 0 0 0 0 0 0 00 [ 3.201867] 15 00 1 0 0 0 0 0 0 00 [ 3.202536] 16 00 1 0 0 0 0 0 0 00 [ 3.203257] 17 00 1 0 0 0 0 0 0 00 [ 3.203925] IRQ to pin mappings: [ 3.207164] IRQ0 -> 0:2 [ 3.207520] IRQ1 -> 0:1 [ 3.207880] IRQ3 -> 0:3 [ 3.210703] IRQ4 -> 0:4 [ 3.211076] IRQ5 -> 0:5 [ 3.211427] IRQ6 -> 0:6 [ 3.211783] IRQ7 -> 0:7 [ 3.213607] IRQ8 -> 0:8 [ 3.213964] IRQ9 -> 0:9 [ 3.214313] IRQ10 -> 0:10 [ 3.214714] IRQ11 -> 0:11 [ 3.215104] IRQ12 -> 0:12 [ 3.215470] IRQ13 -> 0:13 [ 3.215844] IRQ14 -> 0:14 [ 3.218900] IRQ15 -> 0:15 [ 3.219293] .................................... done. [ 3.220181] Running tests on trace events: [ 3.222934] Testing event kfree_skb: [ 3.240154] test-events (33) used greatest stack depth: 5912 bytes left OK [ 3.241844] Testing event consume_skb: OK [ 3.256854] Testing event skb_copy_datagram_iovec: OK [ 3.272805] Testing event net_dev_xmit: [ 3.288198] test-events (36) used greatest stack depth: 5832 bytes left OK [ 3.289797] Testing event net_dev_queue: OK [ 3.304806] Testing event netif_receive_skb: OK [ 3.320824] Testing event netif_rx: OK [ 3.336923] Testing event napi_poll: OK [ 3.364162] Testing event sock_rcvqueue_full: OK [ 3.384835] Testing event sock_exceed_buf_limit: OK [ 3.400854] Testing event udp_fail_queue_rcv_skb: OK [ 3.416726] Testing event mix_pool_bytes: OK [ 3.432830] Testing event mix_pool_bytes_nolock: OK [ 3.448931] Testing event credit_entropy_bits: OK [ 3.464928] Testing event get_random_bytes: OK [ 3.480879] Testing event extract_entropy: OK [ 3.496898] Testing event extract_entropy_user: OK [ 3.512975] Testing event regulator_enable: OK [ 3.528839] Testing event regulator_enable_delay: OK [ 3.544886] Testing event regulator_enable_complete: OK [ 3.560841] Testing event regulator_disable: OK [ 3.576840] Testing event regulator_disable_complete: OK [ 3.592821] Testing event regulator_set_voltage: OK [ 3.608864] Testing event regulator_set_voltage_complete: OK [ 3.624926] Testing event gpio_direction: OK [ 3.641113] Testing event gpio_value: OK [ 3.657223] Testing event writeback_nothread: OK [ 3.672895] Testing event writeback_queue: OK [ 3.688933] Testing event writeback_exec: OK [ 3.704854] Testing event writeback_start: OK [ 3.720846] Testing event writeback_written: OK [ 3.736860] Testing event writeback_wait: OK [ 3.752810] Testing event writeback_pages_written: OK [ 3.768859] Testing event writeback_nowork: OK [ 3.784814] Testing event writeback_wake_background: OK [ 3.800815] Testing event writeback_wake_thread: OK [ 3.890287] Testing event writeback_wake_forker_thread: OK [ 4.060775] Testing event writeback_bdi_register: OK [ 4.146311] Testing event writeback_bdi_unregister: OK [ 4.354830] Testing event writeback_thread_start: OK [ 4.488819] Testing event writeback_thread_stop: OK [ 4.752890] Testing event wbc_writepage: OK [ 4.768470] Testing event writeback_queue_io: OK [ 4.784468] Testing event global_dirty_state: OK [ 4.800470] Testing event bdi_dirty_ratelimit: OK [ 4.816500] Testing event balance_dirty_pages: OK [ 4.832541] Testing event writeback_sb_inodes_requeue: OK [ 4.848513] Testing event writeback_congestion_wait: OK [ 4.864507] Testing event writeback_wait_iff_congested: OK [ 4.880510] Testing event writeback_single_inode: OK [ 4.896490] Testing event mm_compaction_isolate_migratepages: OK [ 4.912490] Testing event mm_compaction_isolate_freepages: OK [ 4.928489] Testing event mm_compaction_migratepages: OK [ 4.944490] Testing event kmalloc: OK [ 4.960682] Testing event kmem_cache_alloc: OK [ 4.992823] Testing event kmalloc_node: OK [ 5.008762] Testing event kmem_cache_alloc_node: OK [ 5.024757] Testing event kfree: OK [ 5.040877] Testing event kmem_cache_free: OK [ 5.056858] Testing event mm_page_free: OK [ 5.072870] Testing event mm_page_free_batched: OK [ 5.088861] Testing event mm_page_alloc: OK [ 5.104861] Testing event mm_page_alloc_zone_locked: OK [ 5.120846] Testing event mm_page_pcpu_drain: OK [ 5.136873] Testing event mm_page_alloc_extfrag: OK [ 5.152890] Testing event mm_vmscan_kswapd_sleep: OK [ 5.168842] Testing event mm_vmscan_kswapd_wake: OK [ 5.184853] Testing event mm_vmscan_wakeup_kswapd: OK [ 5.200835] Testing event mm_vmscan_direct_reclaim_begin: OK [ 5.216826] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 5.232702] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 5.248722] Testing event mm_vmscan_direct_reclaim_end: OK [ 5.264855] Testing event mm_vmscan_memcg_reclaim_end: OK [ 5.280767] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 5.296751] Testing event mm_shrink_slab_start: OK [ 5.312832] Testing event mm_shrink_slab_end: OK [ 5.330217] Testing event mm_vmscan_lru_isolate: OK [ 5.344852] Testing event mm_vmscan_memcg_isolate: OK [ 5.360713] Testing event mm_vmscan_writepage: OK [ 5.376854] Testing event mm_vmscan_lru_shrink_inactive: OK [ 5.392859] Testing event oom_score_adj_update: OK [ 5.409151] Testing event cpu_idle: OK [ 5.425538] Testing event cpu_frequency: OK [ 5.440715] Testing event machine_suspend: OK [ 5.456925] Testing event wakeup_source_activate: OK [ 5.472833] Testing event wakeup_source_deactivate: OK [ 5.488850] Testing event clock_enable: OK [ 5.504718] Testing event clock_disable: OK [ 5.520715] Testing event clock_set_rate: OK [ 5.536723] Testing event power_domain_target: OK [ 5.552739] Testing event ftrace_test_filter: OK [ 5.568740] Testing event module_load: OK [ 5.584867] Testing event module_free: OK [ 5.600875] Testing event module_request: OK [ 5.616919] Testing event lock_acquire: OK [ 5.632973] Testing event lock_release: OK [ 5.648930] Testing event lock_contended: OK [ 5.664862] Testing event lock_acquired: OK [ 5.680838] Testing event sched_kthread_stop: OK [ 5.696848] Testing event sched_kthread_stop_ret: OK [ 5.712846] Testing event sched_wakeup: OK [ 5.728739] Testing event sched_wakeup_new: OK [ 5.745067] Testing event sched_switch: OK [ 5.760776] Testing event sched_migrate_task: OK [ 5.776857] Testing event sched_process_free: OK [ 5.792838] Testing event sched_process_exit: OK [ 5.808868] Testing event sched_wait_task: OK [ 5.824895] Testing event sched_process_wait: OK [ 5.840844] Testing event sched_process_fork: OK [ 5.856834] Testing event sched_process_exec: OK [ 5.872850] Testing event sched_stat_wait: OK [ 5.888856] Testing event sched_stat_sleep: OK [ 5.904859] Testing event sched_stat_iowait: OK [ 5.920863] Testing event sched_stat_blocked: OK [ 5.936848] Testing event sched_stat_runtime: OK [ 5.952856] Testing event sched_pi_setprio: OK [ 5.968823] Testing event rcu_utilization: OK [ 5.991382] Testing event rcu_grace_period: OK [ 6.009592] Testing event rcu_grace_period_init: OK [ 6.024895] Testing event rcu_preempt_task: OK [ 6.040841] Testing event rcu_unlock_preempted_task: OK [ 6.056861] Testing event rcu_quiescent_state_report: OK [ 6.072982] Testing event rcu_fqs: OK [ 6.088940] Testing event rcu_dyntick: OK [ 6.105436] Testing event rcu_prep_idle: OK [ 6.121736] Testing event rcu_callback: OK [ 6.136855] Testing event rcu_kfree_callback: OK [ 6.152842] Testing event rcu_batch_start: OK [ 6.168960] Testing event rcu_invoke_callback: OK [ 6.184842] Testing event rcu_invoke_kfree_callback: OK [ 6.200844] Testing event rcu_batch_end: OK [ 6.216956] Testing event rcu_torture_read: OK [ 6.232853] Testing event rcu_barrier: OK [ 6.248851] Testing event workqueue_queue_work: OK [ 6.264847] Testing event workqueue_activate_work: OK [ 6.280888] Testing event workqueue_execute_start: OK [ 6.296856] Testing event workqueue_execute_end: OK [ 6.312843] Testing event signal_generate: OK [ 6.328946] Testing event signal_deliver: OK [ 6.344845] Testing event timer_init: OK [ 6.360797] Testing event timer_start: OK [ 6.377170] Testing event timer_expire_entry: OK [ 6.392821] Testing event timer_expire_exit: OK [ 6.447164] Testing event timer_cancel: OK [ 6.465142] Testing event hrtimer_init: OK [ 6.480846] Testing event hrtimer_start: OK [ 6.496798] Testing event hrtimer_expire_entry: OK [ 6.512801] Testing event hrtimer_expire_exit: OK [ 6.528827] Testing event hrtimer_cancel: OK [ 6.545119] Testing event itimer_state: OK [ 6.560911] Testing event itimer_expire: OK [ 6.576916] Testing event irq_handler_entry: OK [ 6.592898] Testing event irq_handler_exit: OK [ 6.608915] Testing event softirq_entry: OK [ 6.624824] Testing event softirq_exit: OK [ 6.640825] Testing event softirq_raise: OK [ 6.656824] Testing event console: OK [ 6.672894] Testing event task_newtask: OK [ 6.688911] Testing event task_rename: OK [ 6.704917] Testing event sys_enter: OK [ 6.721476] Testing event sys_exit: OK [ 6.737269] Testing event emulate_vsyscall: OK [ 6.752896] Running tests on trace event systems: [ 6.753478] Testing event system skb: OK [ 6.773282] Testing event system net: OK [ 6.789267] Testing event system napi: OK [ 6.804843] Testing event system sock: OK [ 6.820937] Testing event system udp: OK [ 6.836762] Testing event system random: OK [ 6.853395] Testing event system regulator: OK [ 6.869501] Testing event system gpio: OK [ 6.885657] Testing event system writeback: OK [ 6.907608] Testing event system compaction: OK [ 6.925099] Testing event system kmem: OK [ 6.942196] Testing event system vmscan: [ 6.960207] test-events (208) used greatest stack depth: 5824 bytes left OK [ 6.962893] Testing event system oom: OK [ 6.981217] Testing event system power: OK [ 6.998139] Testing event system test: OK [ 7.012724] Testing event system module: OK [ 7.029094] Testing event system lock: OK [ 7.045260] Testing event system sched: OK [ 7.066541] Testing event system rcu: OK [ 7.096607] Testing event system workqueue: OK [ 7.113344] Testing event system signal: OK [ 7.129070] Testing event system timer: OK [ 7.151363] Testing event system irq: OK [ 7.169560] Testing event system printk: OK [ 7.184827] Testing event system task: OK [ 7.200994] Testing event system raw_syscalls: OK [ 7.216967] Testing event system vsyscall: OK [ 7.232837] Running tests on all trace events: [ 7.233379] Testing all events: OK [ 7.572772] Running tests again, along with the function tracer [ 7.573656] Running tests on trace events: [ 7.574300] Testing event kfree_skb: [ 7.636196] test-events (225) used greatest stack depth: 5168 bytes left OK [ 7.639485] Testing event consume_skb: OK [ 7.658077] Testing event skb_copy_datagram_iovec: OK [ 7.678081] Testing event net_dev_xmit: OK [ 7.698372] Testing event net_dev_queue: OK [ 7.717677] Testing event netif_receive_skb: OK [ 7.738179] Testing event netif_rx: OK [ 7.757667] Testing event napi_poll: OK [ 7.773812] Testing event sock_rcvqueue_full: OK [ 7.794042] Testing event sock_exceed_buf_limit: OK [ 7.814217] Testing event udp_fail_queue_rcv_skb: OK [ 7.834038] Testing event mix_pool_bytes: OK [ 7.854174] Testing event mix_pool_bytes_nolock: OK [ 7.874179] Testing event credit_entropy_bits: OK [ 7.894173] Testing event get_random_bytes: OK [ 7.914182] Testing event extract_entropy: OK [ 7.934210] Testing event extract_entropy_user: OK [ 7.953703] Testing event regulator_enable: OK [ 7.973923] Testing event regulator_enable_delay: OK [ 7.994185] Testing event regulator_enable_complete: [ 8.012351] test-events (244) used greatest stack depth: 5144 bytes left OK [ 8.015148] Testing event regulator_disable: OK [ 8.034159] Testing event regulator_disable_complete: OK [ 8.054157] Testing event regulator_set_voltage: OK [ 8.073741] Testing event regulator_set_voltage_complete: OK [ 8.094174] Testing event gpio_direction: OK [ 8.114860] Testing event gpio_value: OK [ 8.135036] Testing event writeback_nothread: OK [ 8.154141] Testing event writeback_queue: OK [ 8.174108] Testing event writeback_exec: OK [ 8.194055] Testing event writeback_start: OK [ 8.214017] Testing event writeback_written: OK [ 8.233674] Testing event writeback_wait: OK [ 8.253573] Testing event writeback_pages_written: OK [ 8.274034] Testing event writeback_nowork: OK [ 8.294087] Testing event writeback_wake_background: OK [ 8.313781] Testing event writeback_wake_thread: OK [ 8.334214] Testing event writeback_wake_forker_thread: OK [ 8.353753] Testing event writeback_bdi_register: OK [ 8.374177] Testing event writeback_bdi_unregister: OK [ 8.393784] Testing event writeback_thread_start: OK [ 8.413604] Testing event writeback_thread_stop: OK [ 8.434013] Testing event wbc_writepage: OK [ 8.454041] Testing event writeback_queue_io: OK [ 8.473977] Testing event global_dirty_state: OK [ 8.502975] Testing event bdi_dirty_ratelimit: OK [ 8.530027] Testing event balance_dirty_pages: OK [ 8.549849] Testing event writeback_sb_inodes_requeue: OK [ 8.570037] Testing event writeback_congestion_wait: OK [ 8.589634] Testing event writeback_wait_iff_congested: OK [ 8.609597] Testing event writeback_single_inode: OK [ 8.630005] Testing event mm_compaction_isolate_migratepages: OK [ 8.649939] Testing event mm_compaction_isolate_freepages: OK [ 8.669972] Testing event mm_compaction_migratepages: OK [ 8.689615] Testing event kmalloc: OK [ 8.710260] Testing event kmem_cache_alloc: OK [ 8.730007] Testing event kmalloc_node: OK [ 8.749867] Testing event kmem_cache_alloc_node: OK [ 8.769763] Testing event kfree: OK [ 8.790129] Testing event kmem_cache_free: