early console in setup code early console in decompress_kernel Decompressing Linux... Parsing ELF... done. Booting the kernel. [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.18.0-rc2-ge754569 (kbuild@lkp-nhm1) (gcc version 4.9.1 (Debian 4.9.1-19) ) #509 SMP PREEMPT Wed Dec 10 17:26:00 CST 2014 [ 0.000000] Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-12101521/linux-devel:devel-lkp-nhm1-smoke-201412101539:e754569101f392e1924d8ce6f087ea4cfad91d69:bisect-linux-0/.vmlinuz-e754569101f392e1924d8ce6f087ea4cfad91d69-20141210172745-8-client1 branch=linux-devel/devel-lkp-nhm1-smoke-201412101539 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-12101521/e754569101f392e1924d8ce6f087ea4cfad91d69/vmlinuz-3.18.0-rc2-ge754569 drbd.minor_count=8 [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000013fdffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000013fe0000-0x0000000013ffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] bootconsole [earlyser0] enabled [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] AGP: No AGP bridge found [ 0.000000] e820: last_pfn = 0x13fe0 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 0080000000 mask FF80000000 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 0x7040600070406, 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 0x000f0eb0-0x000f0ebf] mapped at [ffff8800000f0eb0] [ 0.000000] mpc: f0ec0-f0fa4 [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] [mem 0x00000000-0x000fffff] page 4k [ 0.000000] BRK [0x05956000, 0x05956fff] PGTABLE [ 0.000000] BRK [0x05957000, 0x05957fff] PGTABLE [ 0.000000] BRK [0x05958000, 0x05958fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x12600000-0x127fffff] [ 0.000000] [mem 0x12600000-0x127fffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x10000000-0x125fffff] [ 0.000000] [mem 0x10000000-0x125fffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x00100000-0x0fffffff] [ 0.000000] [mem 0x00100000-0x001fffff] page 4k [ 0.000000] [mem 0x00200000-0x0fffffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x12800000-0x13fdffff] [ 0.000000] [mem 0x12800000-0x13dfffff] page 2M [ 0.000000] [mem 0x13e00000-0x13fdffff] page 4k [ 0.000000] BRK [0x05959000, 0x05959fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x12925000-0x13fd7fff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F0C90 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000013FE18BD 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000013FE0B37 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000013FE0040 000AF7 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x0000000013FE0000 000040 [ 0.000000] ACPI: SSDT 0x0000000013FE0BAB 000C5A (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x0000000013FE1805 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000013FE1885 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fc000 ( fee00000) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000013fdffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x12912000-0x12924fff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:13fdf001, primary cpu clock [ 0.000000] [ffffea0000000000-ffffea00005fffff] PMD -> [ffff880011a00000-ffff880011ffffff] on node 0 [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-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 0x00001000-0x0009efff] [ 0.000000] node 0: [mem 0x00100000-0x13fdffff] [ 0.000000] Initmem setup node 0 [mem 0x00001000-0x13fdffff] [ 0.000000] On node 0 totalpages: 81790 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 1216 pages used for memmap [ 0.000000] DMA32 zone: 77792 pages, LIFO batch:15 [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fc000 ( 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[0x00] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 0, 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 0, 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 0, 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 0, 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 0, 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 0, 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 0, APIC INT 01 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, 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 0, APIC INT 06 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, 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 0, APIC INT 0c [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, 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 ffffffffff5fb000 (fec00000) [ 0.000000] e820: [mem 0x14000000-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 39 pages/cpu @ffff880012600000 s130752 r0 d28992 u1048576 [ 0.000000] pcpu-alloc: s130752 r0 d28992 u1048576 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1260db40 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 80489 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-12101521/linux-devel:devel-lkp-nhm1-smoke-201412101539:e754569101f392e1924d8ce6f087ea4cfad91d69:bisect-linux-0/.vmlinuz-e754569101f392e1924d8ce6f087ea4cfad91d69-20141210172745-8-client1 branch=linux-devel/devel-lkp-nhm1-smoke-201412101539 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-12101521/e754569101f392e1924d8ce6f087ea4cfad91d69/vmlinuz-3.18.0-rc2-ge754569 drbd.minor_count=8 [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] AGP: Checking aperture... [ 0.000000] AGP: No AGP bridge found [ 0.000000] Calgary: detecting Calgary via BIOS EBDA area [ 0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing! [ 0.000000] Memory: 222120K/327160K available (30325K kernel code, 16684K rwdata, 14984K rodata, 1660K init, 11288K bss, 105040K reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS:4352 nr_irqs:440 0 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] bootconsole [earlyser0] disabled [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.18.0-rc2-ge754569 (kbuild@lkp-nhm1) (gcc version 4.9.1 (Debian 4.9.1-19) ) #509 SMP PREEMPT Wed Dec 10 17:26:00 CST 2014 [ 0.000000] Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-12101521/linux-devel:devel-lkp-nhm1-smoke-201412101539:e754569101f392e1924d8ce6f087ea4cfad91d69:bisect-linux-0/.vmlinuz-e754569101f392e1924d8ce6f087ea4cfad91d69-20141210172745-8-client1 branch=linux-devel/devel-lkp-nhm1-smoke-201412101539 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-12101521/e754569101f392e1924d8ce6f087ea4cfad91d69/vmlinuz-3.18.0-rc2-ge754569 drbd.minor_count=8 [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000013fdffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000013fe0000-0x0000000013ffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] bootconsole [earlyser0] enabled [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] AGP: No AGP bridge found [ 0.000000] e820: last_pfn = 0x13fe0 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 0080000000 mask FF80000000 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 0x7040600070406, 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 0x000f0eb0-0x000f0ebf] mapped at [ffff8800000f0eb0] [ 0.000000] mpc: f0ec0-f0fa4 [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] [mem 0x00000000-0x000fffff] page 4k [ 0.000000] BRK [0x05956000, 0x05956fff] PGTABLE [ 0.000000] BRK [0x05957000, 0x05957fff] PGTABLE [ 0.000000] BRK [0x05958000, 0x05958fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x12600000-0x127fffff] [ 0.000000] [mem 0x12600000-0x127fffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x10000000-0x125fffff] [ 0.000000] [mem 0x10000000-0x125fffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x00100000-0x0fffffff] [ 0.000000] [mem 0x00100000-0x001fffff] page 4k [ 0.000000] [mem 0x00200000-0x0fffffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x12800000-0x13fdffff] [ 0.000000] [mem 0x12800000-0x13dfffff] page 2M [ 0.000000] [mem 0x13e00000-0x13fdffff] page 4k [ 0.000000] BRK [0x05959000, 0x05959fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x12925000-0x13fd7fff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F0C90 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000013FE18BD 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000013FE0B37 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000013FE0040 000AF7 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x0000000013FE0000 000040 [ 0.000000] ACPI: SSDT 0x0000000013FE0BAB 000C5A (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x0000000013FE1805 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000013FE1885 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fc000 ( fee00000) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000013fdffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x12912000-0x12924fff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:13fdf001, primary cpu clock [ 0.000000] [ffffea0000000000-ffffea00005fffff] PMD -> [ffff880011a00000-ffff880011ffffff] on node 0 [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-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 0x00001000-0x0009efff] [ 0.000000] node 0: [mem 0x00100000-0x13fdffff] [ 0.000000] Initmem setup node 0 [mem 0x00001000-0x13fdffff] [ 0.000000] On node 0 totalpages: 81790 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 1216 pages used for memmap [ 0.000000] DMA32 zone: 77792 pages, LIFO batch:15 [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fc000 ( 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[0x00] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 0, 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 0, 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 0, 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 0, 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 0, 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 0, 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 0, APIC INT 01 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, 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 0, APIC INT 06 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, 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 0, APIC INT 0c [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, 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 ffffffffff5fb000 (fec00000) [ 0.000000] e820: [mem 0x14000000-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 39 pages/cpu @ffff880012600000 s130752 r0 d28992 u1048576 [ 0.000000] pcpu-alloc: s130752 r0 d28992 u1048576 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1260db40 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 80489 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-12101521/linux-devel:devel-lkp-nhm1-smoke-201412101539:e754569101f392e1924d8ce6f087ea4cfad91d69:bisect-linux-0/.vmlinuz-e754569101f392e1924d8ce6f087ea4cfad91d69-20141210172745-8-client1 branch=linux-devel/devel-lkp-nhm1-smoke-201412101539 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-12101521/e754569101f392e1924d8ce6f087ea4cfad91d69/vmlinuz-3.18.0-rc2-ge754569 drbd.minor_count=8 [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] AGP: Checking aperture... [ 0.000000] AGP: No AGP bridge found [ 0.000000] Calgary: detecting Calgary via BIOS EBDA area [ 0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing! [ 0.000000] Memory: 222120K/327160K available (30325K kernel code, 16684K rwdata, 14984K rodata, 1660K init, 11288K bss, 105040K reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS:4352 nr_irqs:440 0 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] bootconsole [earlyser0] disabled [ 0.000000] console [ttyS0] enabled [ 0.000000] ------------------------ [ 0.000000] | Locking API testsuite: [ 0.000000] ---------------------------------------------------------------------------- [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] A-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed| [ 0.000000] double unlock:failed|failed|failed| ok |failed|failed| [ 0.000000] initialize held:failed|failed|failed|failed|failed|failed| [ 0.000000] bad unlock order: ok | ok | ok | ok | ok | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] recursive read-lock: | ok | |failed| [ 0.000000] recursive read-lock #2: | ok | |failed| [ 0.000000] mixed read-write-lock: |failed| |failed| [ 0.000000] mixed write-read-lock: |failed| |failed| [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] hard-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.000000] soft-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.000000] hard-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.000000] soft-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.000000] sirq-safe-A => hirqs-on/12:failed|failed| ok | [ 0.000000] sirq-safe-A => hirqs-on/21:failed|failed| ok | [ 0.000000] hard-safe-A + irqs-on/12:failed|failed| ok | [ 0.000000] soft-safe-A + irqs-on/12:failed|failed| ok | [ 0.000000] hard-safe-A + irqs-on/21:failed|failed| ok | [ 0.000000] soft-safe-A + irqs-on/21:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.000000] hard-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.000000] soft-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/123:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/123:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/132:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/132:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/213:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/213:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/231:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/231:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/312:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/312:failed|failed| ok | [ 0.000000] hard-irq lock-inversion/321:failed|failed| ok | [ 0.000000] soft-irq lock-inversion/321:failed|failed| 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] | Wound/wait tests | [ 0.000000] --------------------- [ 0.000000] ww api failures: ok | ok | ok | [ 0.000000] ww contexts mixing:failed| ok | [ 0.000000] finishing ww context: ok | ok | ok | ok | [ 0.000000] locking mismatches: ok | ok | ok | [ 0.000000] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.000000] spinlock nest unlocked:failed| [ 0.000000] ----------------------------------------------------- [ 0.000000] |block | try |context| [ 0.000000] ----------------------------------------------------- [ 0.000000] context:failed| ok | ok | [ 0.000000] try:failed| ok |failed| [ 0.000000] block:failed| ok |failed| [ 0.000000] spinlock:failed| ok |failed| [ 0.000000] -------------------------------------------------------- [ 0.000000] 153 out of 253 testcases failed, as expected. | [ 0.000000] ---------------------------------------------------- [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 2992.588 MHz processor [ 0.020000] Calibrating delay loop (skipped) preset value.. 5985.17 BogoMIPS (lpj=29925880) [ 0.020000] pid_max: default: 32768 minimum: 301 [ 0.020000] ACPI: Core revision 20140926 [ 0.090318] ACPI: All ACPI Tables successfully acquired [ 0.092597] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.094780] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.096766] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.099583] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.101851] Initializing cgroup subsys debug [ 0.103348] numa_add_cpu cpu 0 node 0: mask now 0 [ 0.104699] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.104699] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.108254] Freeing SMP alternatives memory: 32K (ffffffff84e47000 - ffffffff84e4f000) [ 0.115234] Getting VERSION: 1050014 [ 0.116587] Getting VERSION: 1050014 [ 0.117725] Getting ID: 0 [ 0.120027] Getting ID: ff000000 [ 0.121099] Getting LVT0: 8700 [ 0.122130] Getting LVT1: 8400 [ 0.123231] enabled ExtINT on CPU#0 [ 0.125290] ENABLING IO-APIC IRQs [ 0.126398] init IO_APIC IRQs [ 0.127415] apic 0 pin 0 not connected [ 0.128691] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.130053] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.132375] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.134702] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.138147] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.140047] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.142363] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.144693] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.147069] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.150058] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.152370] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.154716] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.157239] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.160047] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.162411] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.164773] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.167142] apic 0 pin 16 not connected [ 0.168335] apic 0 pin 17 not connected [ 0.170006] apic 0 pin 18 not connected [ 0.171195] apic 0 pin 19 not connected [ 0.172383] apic 0 pin 20 not connected [ 0.173568] apic 0 pin 21 not connected [ 0.174759] apic 0 pin 22 not connected [ 0.175958] apic 0 pin 23 not connected [ 0.177313] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.180006] smpboot: CPU0: Intel Common KVM processor (fam: 0f, model: 06, stepping: 01) [ 0.187399] Using local APIC timer interrupts. [ 0.187399] calibrating APIC timer ... [ 0.200000] ... lapic delta = 6249974 [ 0.200000] ... PM-Timer delta = 358011 [ 0.200000] ... PM-Timer result ok [ 0.200000] ..... delta 6249974 [ 0.200000] ..... mult: 268434339 [ 0.200000] ..... calibration result: 9999958 [ 0.200000] ..... CPU clock speed is 2993.0522 MHz. [ 0.200000] ..... host bus clock speed is 999.9958 MHz. [ 0.200208] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.240322] Testing tracer nop: PASSED [ 0.260016] NMI watchdog: disabled (cpu0): hardware events not enabled [ 0.280887] x86: Booting SMP configuration: [ 0.282266] .... node #0, CPUs: #1 [ 0.262064] kvm-clock: cpu 1, msr 0:13fdf041, secondary cpu clock [ 0.262064] masked ExtINT on CPU#1 [ 0.262064] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 0.300000] TSC synchronization [CPU#0 -> CPU#1]: [ 0.300000] Measured 159 cycles TSC warp between CPUs, turning off TSC clock. [ 0.300000] tsc: Marking TSC unstable due to check_tsc_sync_source failed [ 0.300494] x86: Booted up 1 node, 2 CPUs [ 0.300440] KVM setup async PF for cpu 1 [ 0.300440] kvm-stealtime: cpu 1, msr 1270db40 [ 0.304438] smpboot: Total of 2 processors activated (11970.35 BogoMIPS) [ 0.314202] gcov: version magic: 0x3430392a [ 0.350064] Testing tracer function: PASSED [ 0.514476] Testing ftrace regs(no arch support): PASSED [ 0.580058] Testing tracer irqsoff: PASSED [ 0.674259] xor: measuring software checksum speed [ 0.770019] prefetch64-sse: 10201.600 MB/sec [ 0.870008] generic_sse: 9280.000 MB/sec [ 0.871266] xor: using function: prefetch64-sse (10201.600 MB/sec) [ 0.874887] regulator-dummy: no parameters [ 0.876480] RTC time: 17:29:24, date: 12/10/14 [ 0.878762] NET: Registered protocol family 16 [ 0.890046] cpuidle: using governor ladder [ 0.900029] cpuidle: using governor menu [ 0.910028] cpuidle: using governor select [ 0.912294] ACPI: bus type PCI registered [ 0.914583] PCI: Using configuration type 1 for base access [ 1.080233] Running resizable hashtable tests... [ 1.081629] Adding 2048 keys [ 1.343822] Traversal complete: counted=2048, nelems=2048, entries=2048 [ 1.345897] Table expansion iteration 0... [ 1.370065] Verifying lookups... [ 1.371442] Table expansion iteration 1... [ 1.410080] Verifying lookups... [ 1.411372] Table expansion iteration 2... [ 1.440129] Verifying lookups... [ 1.441390] Table expansion iteration 3... [ 1.470231] Verifying lookups... [ 1.471503] Table shrinkage iteration 0... [ 1.480036] Verifying lookups... [ 1.481295] Table shrinkage iteration 1... [ 1.490032] Verifying lookups... [ 1.491297] Table shrinkage iteration 2... [ 1.500029] Verifying lookups... [ 1.501302] Table shrinkage iteration 3... [ 1.510028] Verifying lookups... [ 1.511321] Deleting 2048 keys [ 1.780036] raid6: sse2x1 1579 MB/s [ 1.950041] raid6: sse2x2 1841 MB/s [ 2.120026] raid6: sse2x4 1776 MB/s [ 2.121213] raid6: using algorithm sse2x2 (1841 MB/s) [ 2.122609] raid6: using intx1 recovery algorithm [ 2.124302] gpio-f7188x: Not a Fintek device at 0x0000002e [ 2.125837] gpio-f7188x: Not a Fintek device at 0x0000004e [ 2.129840] ACPI: Added _OSI(Module Device) [ 2.130029] ACPI: Added _OSI(Processor Device) [ 2.131394] ACPI: Added _OSI(3.0 _SCP Extensions) [ 2.132785] ACPI: Added _OSI(Processor Aggregator Device) [ 2.136465] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:3) [ 2.165107] ACPI: Interpreter enabled [ 2.166319] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20140926/hwxface-580) [ 2.169136] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20140926/hwxface-580) [ 2.172014] ACPI: (supports S0 S3 S5) [ 2.173145] ACPI: Using IOAPIC for interrupt routing [ 2.174875] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 2.298970] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 2.300060] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments] [ 2.301882] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 2.309345] PCI host bridge to bus 0000:00 [ 2.310034] pci_bus 0000:00: root bus resource [bus 00-ff] [ 2.311524] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7] [ 2.313143] pci_bus 0000:00: root bus resource [io 0x0d00-0xadff] [ 2.314763] pci_bus 0000:00: root bus resource [io 0xae0f-0xaeff] [ 2.316411] pci_bus 0000:00: root bus resource [io 0xaf20-0xafdf] [ 2.318030] pci_bus 0000:00: root bus resource [io 0xafe4-0xffff] [ 2.320032] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff] [ 2.321786] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff] [ 2.323604] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 2.330305] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 2.335887] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 2.339964] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 2.340971] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 2.342751] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 2.344434] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 2.346289] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 2.352834] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 2.355035] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 2.357230] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 2.362630] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 2.366589] pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfdffffff pref] [ 2.369548] pci 0000:00:02.0: reg 0x14: [mem 0xfebf0000-0xfebf0fff] [ 2.375442] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 2.382693] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 2.385158] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 2.387663] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 2.393365] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 2.398757] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 2.400508] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 2.420534] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 2.425129] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 2.429725] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 2.433629] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 2.436770] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 2.447894] ACPI: Enabled 16 GPEs in block 00 to 0F [ 2.456057] vgaarb: setting as boot device: PCI:0000:00:02.0 [ 2.457775] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 2.460021] vgaarb: loaded [ 2.460970] vgaarb: bridge control possible 0000:00:02.0 [ 2.466022] SCSI subsystem initialized [ 2.471207] pps_core: LinuxPPS API ver. 1 registered [ 2.472641] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 2.475574] wmi: Mapper loaded [ 2.475574] Advanced Linux Sound Architecture Driver Initialized. [ 2.480290] PCI: Using ACPI for IRQ routing [ 2.481534] PCI: pci_cache_line_size set to 64 bytes [ 2.483084] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 2.484672] e820: reserve RAM buffer [mem 0x13fe0000-0x13ffffff] [ 2.491315] Switched to clocksource kvm-clock [ 2.493982] Warning: could not register all branches stats [ 2.495487] Warning: could not register annotated branches stats [ 2.568182] FS-Cache: Loaded [ 2.569867] CacheFiles: Loaded [ 2.571284] pnp: PnP ACPI init [ 2.573060] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:3) [ 2.576143] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 2.578284] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:3) [ 2.581182] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 2.583265] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:3) [ 2.586231] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 2.588724] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:3) [ 2.592192] pnp 00:03: [dma 2] [ 2.593995] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 2.596651] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:3) [ 2.599568] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 2.602076] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:3) [ 2.604986] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 2.614879] pnp: PnP ACPI: found 6 devices [ 2.666076] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7] [ 2.667588] pci_bus 0000:00: resource 5 [io 0x0d00-0xadff] [ 2.669076] pci_bus 0000:00: resource 6 [io 0xae0f-0xaeff] [ 2.670591] pci_bus 0000:00: resource 7 [io 0xaf20-0xafdf] [ 2.672077] pci_bus 0000:00: resource 8 [io 0xafe4-0xffff] [ 2.673601] pci_bus 0000:00: resource 9 [mem 0x000a0000-0x000bffff] [ 2.675218] pci_bus 0000:00: resource 10 [mem 0x14000000-0xfebfffff] [ 2.677002] NET: Registered protocol family 1 [ 2.678354] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 2.679921] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 2.681492] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 2.683127] pci 0000:00:02.0: Video device with shadowed ROM [ 2.684674] PCI: CLS 0 bytes, default 64 [ 2.686517] Trying to unpack rootfs image as initramfs... [ 4.058382] Freeing initrd memory: 23244K (ffff880012925000 - ffff880013fd8000) [ 4.067477] des3_ede-x86_64: performance on this CPU would be suboptimal: disabling des3_ede-x86_64. [ 4.074236] camellia-x86_64: performance on this CPU would be suboptimal: disabling camellia-x86_64. [ 4.077253] twofish-x86_64-3way: performance on this CPU would be suboptimal: disabling twofish-x86_64-3way. [ 4.087940] sha1_ssse3: Neither AVX nor AVX2 nor SSSE3 is available/usable. [ 4.089745] sha256_ssse3: Neither AVX nor SSSE3 is available/usable. [ 4.091558] AVX instructions are not detected. [ 4.092846] AVX instructions are not detected. [ 4.094136] AVX instructions are not detected. [ 4.095430] AVX2 instructions are not detected. [ 4.096953] spin_lock-torture:--- Start of test: nwriters_stress=4 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 4.101260] spin_lock-torture: Creating torture_shuffle task [ 4.103163] spin_lock-torture: Creating torture_stutter task [ 4.103248] spin_lock-torture: torture_shuffle task started [ 4.106545] spin_lock-torture: Creating lock_torture_writer task [ 4.106623] spin_lock-torture: torture_stutter task started [ 4.110006] spin_lock-torture: Creating lock_torture_writer task [ 4.110153] spin_lock-torture: lock_torture_writer task started [ 4.110330] spin_lock-torture: Creating lock_torture_writer task [ 4.110407] spin_lock-torture: lock_torture_writer task started [ 4.117201] spin_lock-torture: Creating lock_torture_writer task [ 4.118417] spin_lock-torture: lock_torture_writer task started [ 4.120959] spin_lock-torture: Creating lock_torture_stats task [ 4.121034] spin_lock-torture: lock_torture_writer task started [ 4.124609] spin_lock-torture: lock_torture_stats task started [ 4.131029] futex hash table entries: 512 (order: 3, 32768 bytes) [ 5.889662] zpool: loaded [ 5.918601] ntfs: driver 2.1.31 [Flags: R/O]. [ 5.924499] fuse init (API version 7.23) [ 5.933147] NILFS version 2 loaded [ 5.968446] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 5.970810] io scheduler noop registered [ 5.973121] io scheduler deadline registered (default) [ 5.976491] io scheduler cfq registered [ 5.977725] start plist test [ 5.984572] end plist test [ 5.991961] test_string_helpers: Running tests... [ 5.998917] test_firmware: interface ready [ 6.001582] crc32: CRC_LE_BITS = 8, CRC_BE BITS = 8 [ 6.002950] crc32: self tests passed, processed 225944 bytes in 709302 nsec [ 6.005338] crc32c: CRC_LE_BITS = 8 [ 6.006448] crc32c: self tests passed, processed 225944 bytes in 329885 nsec [ 6.060243] crc32_combine: 8373 self tests passed [ 6.100944] crc32c_combine: 8373 self tests passed [ 6.102685] xz_dec_test: module loaded [ 6.103839] xz_dec_test: Create a device node with 'mknod xz_dec_test c 249 0' and write .xz files to it. [ 6.106701] rbtree testing -> 57688 cycles [ 8.134904] augmented rbtree testing -> 70764 cycles [ 10.643872] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 10.645462] cpcihp_zt5550: ZT5550 CompactPCI Hot Plug Driver version: 0.2 [ 10.647448] cpcihp_generic: Generic port I/O CompactPCI Hot Plug Driver version: 0.1 [ 10.649692] cpcihp_generic: not configured, disabling. [ 10.654767] cr_bllcd: INTEL CARILLO RANCH LPC not found. [ 10.656291] cr_bllcd: Carillo Ranch Backlight Driver Initialized. [ 10.660247] VIA Graphics Integration Chipset framebuffer 2.4 initializing [ 10.664042] vmlfb: initializing [ 10.665699] no IO addresses supplied [ 10.668266] ipmi message handler version 39.2 [ 10.669760] IPMI System Interface driver. [ 10.671387] ipmi_si: Adding default-specified kcs state machine [ 10.673086] ipmi_si: Trying default-specified kcs state machine at i/o address 0xca2, slave address 0x0, irq 0 [ 10.675778] ipmi_si: Interface detection failed [ 10.680171] ipmi_si: Adding default-specified smic state machine [ 10.681915] ipmi_si: Trying default-specified smic state machine at i/o address 0xca9, slave address 0x0, irq 0 [ 10.686634] ipmi_si: Interface detection failed [ 10.690145] ipmi_si: Adding default-specified bt state machine [ 10.691829] ipmi_si: Trying default-specified bt state machine at i/o address 0xe4, slave address 0x0, irq 0 [ 10.694893] ipmi_si: Interface detection failed [ 10.710518] ipmi_si: Unable to find any System Interface(s) [ 10.714089] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 10.716730] ACPI: Power Button [PWRF] [ 10.742291] r3964: Philips r3964 Driver $Revision: 1.10 $ [ 10.744315] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 10.771842] serial 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 10.783603] serial: Freescale lpuart driver [ 10.785520] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $ [ 10.787700] ac.o: No PCI boards found. [ 10.788866] ac.o: For an ISA board you must supply memory and irq parameters. [ 10.791663] Non-volatile memory driver v1.3 [ 10.793207] smapi::smapi_init, ERROR invalid usSmapiID [ 10.794719] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine [ 10.797639] mwave: mwavedd::mwave_init: Error: Failed to initialize board data [ 10.800379] mwave: mwavedd::mwave_init: Error: Failed to initialize [ 10.802038] Linux agpgart interface v0.103 [ 10.819958] [drm] Initialized drm 1.1.0 20060810 [ 10.822191] [drm] radeon kernel modesetting enabled. [ 10.882514] brd: module loaded [ 10.907436] loop: module loaded [ 10.908529] HP CISS Driver (v 3.6.26) [ 10.912789] skd: v2.2.1-b0260 loaded [ 10.914369] MM: desc_per_page = 128 [ 10.916334] mtip32xx Version 1.3.1 [ 10.923579] nullb0: unknown partition table [ 10.929148] nullb1: unknown partition table [ 10.932034] null: module loaded [ 10.934503] Phantom Linux Driver, version n0.9.8, init OK [ 10.938196] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti [ 10.942969] Guest personality initialized and is inactive [ 10.945383] VMCI host device registered (name=vmci, major=10, minor=59) [ 10.947098] Initialized host personality [ 10.949168] mic_init not running on X100 ret -19 [ 10.957000] rdac: device handler registered [ 10.958987] hp_sw: device handler registered [ 10.960322] emc: device handler registered [ 10.961590] alua: device handler registered [ 10.963573] Adaptec aacraid driver 1.2-0[30300]-ms [ 10.965825] isci: Intel(R) C600 SAS Controller Driver - version 1.2.0 [ 10.968096] scsi: Detection failed (no card) [ 10.970636] megaraid cmm: 2.20.2.7 (Release Date: Sun Jul 16 00:01:03 EST 2006) [ 10.973749] megaraid: 2.20.5.1 (Release Date: Thu Nov 16 15:32:35 EST 2006) [ 10.976135] megasas: 06.805.06.00-rc1 Thu. Sep. 4 17:00:00 PDT 2014 [ 10.978138] mpt2sas version 18.100.00.00 loaded [ 10.980484] mpt3sas version 04.100.00.00 loaded [ 10.983675] 3ware Storage Controller device driver for Linux v1.26.02.003. [ 10.985964] 3ware 9000 Storage Controller device driver for Linux v2.26.02.014. [ 10.988415] LSI 3ware SAS/SATA-RAID Controller device driver for Linux v3.26.02.000. [ 10.991309] stex: Promise SuperTrak EX Driver version: 4.6.0000.4 [ 10.993416] esas2r: driver will not be loaded because no ATTO esas2r devices were found [ 10.997551] VMware PVSCSI driver - version 1.0.5.0-k [ 10.999601] osst :I: Tape driver with OnStream support version 0.99.4 [ 10.999601] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $ [ 11.034005] scsi_debug: host protection [ 11.035238] scsi host0: scsi_debug, version 1.84 [20140706], dev_size_mb=8, opts=0x0 [ 11.053071] scsi 0:0:0:0: Direct-Access Linux scsi_debug 0184 PQ: 0 ANSI: 6 [ 11.069710] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 11.082440] Rounding down aligned max_sectors from 4294967295 to 4294967288 [ 11.090852] HSI/SSI char device loaded [ 11.092521] Fusion MPT base driver 3.04.20 [ 11.093814] Copyright (c) 1999-2008 LSI Corporation [ 11.095438] Fusion MPT SPI Host driver 3.04.20 [ 11.097345] Fusion MPT SAS Host driver 3.04.20 [ 11.098866] Fusion MPT misc device (ioctl) driver 3.04.20 [ 11.100108] sd 0:0:0:0: [sda] 16384 512-byte logical blocks: (8.38 MB/8.00 MiB) [ 11.105372] mptctl: Registered with Fusion MPT base driver [ 11.106939] mptctl: /dev/mptctl @ (major,minor=10,220) [ 11.110285] sd 0:0:0:0: [sda] Write Protect is off [ 11.111665] sd 0:0:0:0: [sda] Mode Sense: 73 00 10 08 [ 11.112342] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 11.135419] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 11.137695] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 11.148359] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA [ 11.220107] sda: unknown partition table [ 11.229933] mousedev: PS/2 mouse device common for all mice [ 11.234621] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 11.243311] rtc_cmos 00:00: RTC can wake from S4 [ 11.246347] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 [ 11.248347] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs [ 11.260811] i2c-parport-light: adapter type unspecified [ 11.264080] pps pps0: new PPS source ktimer [ 11.265338] pps pps0: ktimer PPS source registered [ 11.266697] pps_ldisc: PPS line discipline registered [ 11.269356] Driver for 1-wire Dallas network protocol. [ 11.270250] sd 0:0:0:0: [sda] Attached SCSI disk [ 11.273144] 1-Wire driver for the DS2760 battery monitor chip - (c) 2004-2005, Szabolcs Gyurko [ 11.280460] applesmc: supported laptop not found! [ 11.286064] applesmc: driver init failed (ret=-19)! [ 11.299057] acquirewdt: WDT driver for Acquire single board computer initialising [ 11.302020] acquirewdt: I/O address 0x0043 already in use [ 11.303551] acquirewdt: probe of acquirewdt failed with error -5 [ 11.305516] advantechwdt: WDT driver for Advantech single board computer initialising [ 11.309070] advantechwdt: initialized. timeout=60 sec (nowayout=1) [ 11.310806] alim7101_wdt: Steve Hill [ 11.312291] alim7101_wdt: ALi M7101 PMU not present - WDT not set [ 11.313897] sp5100_tco: SP5100/SB800 TCO WatchDog Timer Driver v0.05 [ 11.316416] ib700wdt: WDT driver for IB700 single board computer initialising [ 11.318908] ib700wdt: START method I/O 443 is not available [ 11.320537] ib700wdt: probe of ib700wdt failed with error -5 [ 11.322403] wafer5823wdt: WDT driver for Wafer 5823 single board computer initialising [ 11.324691] wafer5823wdt: I/O address 0x0443 already in use [ 11.326364] i6300esb: Intel 6300ESB WatchDog Timer Driver v0.05 [ 11.328146] i6300esb: cannot register miscdev on minor=130 (err=-16) [ 11.329880] i6300ESB timer: probe of 0000:00:04.0 failed with error -16 [ 11.331800] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11 [ 11.333604] it87_wdt: no device [ 11.334871] pc87413_wdt: Version 1.1 at io 0x2E [ 11.336188] pc87413_wdt: cannot register miscdev on minor=130 (err=-16) [ 11.337918] nv_tco: NV TCO WatchDog Timer Driver v0.01 [ 11.340200] sbc60xxwdt: I/O address 0x0443 already in use [ 11.341712] cpu5wdt: misc_register failed [ 11.343021] smsc37b787_wdt: SMsC 37B787 watchdog component driver 1.1 initialising... [ 11.346373] smsc37b787_wdt: Unable to register miscdev on minor 130 [ 11.348023] w83977f_wdt: driver v1.00 [ 11.349166] w83977f_wdt: cannot register miscdev on minor=130 (err=-16) [ 11.350917] machzwd: MachZ ZF-Logic Watchdog driver initializing [ 11.352519] machzwd: no ZF-Logic found [ 11.354970] md: linear personality registered for level -1 [ 11.356479] md: raid0 personality registered for level 0 [ 11.357942] md: raid1 personality registered for level 1 [ 11.359409] md: raid10 personality registered for level 10 [ 11.361877] md: raid6 personality registered for level 6 [ 11.363343] md: raid5 personality registered for level 5 [ 11.364797] md: raid4 personality registered for level 4 [ 11.366281] md: faulty personality registered for level -5 [ 11.373529] device-mapper: ioctl: 4.28.0-ioctl (2014-09-17) initialised: dm-devel@redhat.com [ 11.378105] device-mapper: raid: Loading target version 1.6.0 [ 11.381620] device-mapper: cache-policy-mq: version 1.2.0 loaded [ 11.383234] device-mapper: cache cleaner: version 1.0.0 loaded [ 11.389225] leds_ss4200: no LED devices found [ 11.395145] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 11.397411] Initializing Realtek PCIE storage driver... [ 11.404802] input: Speakup as /devices/virtual/input/input3 [ 11.408414] initialized device: /dev/synth, node (MAJOR 10, MINOR 25) [ 11.410371] speakup 3.1.6: initialized [ 11.411547] synth name on entry is: (null) [ 11.412967] dgap: dgap-1.3-16, Digi International Part Number 40002347_C [ 11.414715] dgap: For the tools package please visit http://www.digi.com [ 11.417456] FPGA DOWNLOAD ---> [ 11.418505] FPGA image file name: xlinx_fpga_firmware.bit [ 11.420577] GPIO INIT FAIL!! [ 11.421814] hdaps: supported laptop not found! [ 11.423122] hdaps: driver init failed (ret=-19)! [ 11.441675] ASIHPI driver 4.10.01 [ 11.450266] [ 11.450266] printing PIC contents [ 11.451964] ... PIC IMR: ffff [ 11.453006] ... PIC IRR: 1113 [ 11.454051] ... PIC ISR: 0000 [ 11.455093] ... PIC ELCR: 0c00 [ 11.456157] printing local APIC contents on CPU#0/0: [ 11.457586] ... APIC ID: 00000000 (0) [ 11.458817] ... APIC VERSION: 01050014 [ 11.459990] ... APIC TASKPRI: 00000000 (00) [ 11.461241] ... APIC PROCPRI: 00000000 [ 11.462460] ... APIC LDR: 01000000 [ 11.463625] ... APIC DFR: ffffffff [ 11.464721] ... APIC SPIV: 000001ff [ 11.465852] ... APIC ISR field: [ 11.466147] 0000000000000000000000000000000000000000000000000000000000000000 [ 11.466147] ... APIC TMR field: [ 11.466147] 000000000e200000000000000000000000000000000000000000000000000000 [ 11.466147] ... APIC IRR field: [ 11.466147] 0000000000000000000000000000000000000000000000000000000000008000 [ 11.466147] ... APIC ESR: 00000000 [ 11.466147] ... APIC ICR: 000008fd [ 11.466147] ... APIC ICR2: 02000000 [ 11.466147] ... APIC LVTT: 000000ef [ 11.466147] ... APIC LVTPC: 00010000 [ 11.466147] ... APIC LVT0: 00010700 [ 11.466147] ... APIC LVT1: 00000400 [ 11.466147] ... APIC LVTERR: 000000fe [ 11.466147] ... APIC TMICT: 001c8689 [ 11.466147] ... APIC TMCCT: 00000000 [ 11.466147] ... APIC TDCR: 00000003 [ 11.466147] [ 11.514567] number of MP IRQ sources: 15. [ 11.515922] number of IO-APIC #0 registers: 24. [ 11.517240] testing the IO APIC....................... [ 11.518693] IO APIC #0...... [ 11.519703] .... register #00: 00000000 [ 11.520933] ....... : physical APIC id: 00 [ 11.522223] ....... : Delivery Type: 0 [ 11.523457] ....... : LTS : 0 [ 11.524674] .... register #01: 00170011 [ 11.525862] ....... : max redirection entries: 17 [ 11.527291] ....... : PRQ implemented: 0 [ 11.528586] ....... : IO APIC version: 11 [ 11.529873] .... register #02: 00000000 [ 11.531209] ....... : arbitration: 00 [ 11.532451] .... IRQ redirection table: [ 11.533657] 1 0 0 0 0 0 0 00 [ 11.534995] 0 0 0 0 0 1 1 31 [ 11.536338] 0 0 0 0 0 1 1 30 [ 11.537667] 0 0 0 0 0 1 1 33 [ 11.538999] 1 0 0 0 0 1 1 34 [ 11.540418] 1 1 0 0 0 1 1 35 [ 11.541750] 0 0 0 0 0 1 1 36 [ 11.543080] 0 0 0 0 0 1 1 37 [ 11.544420] 0 0 0 0 0 1 1 38 [ 11.545759] 0 1 0 0 0 1 1 39 [ 11.547084] 1 1 0 0 0 1 1 3A [ 11.548415] 1 1 0 0 0 1 1 3B [ 11.549742] 0 0 0 0 0 1 1 3C [ 11.551092] 0 0 0 0 0 1 1 3D [ 11.552428] 0 0 0 0 0 1 1 3E [ 11.553757] 0 0 0 0 0 1 1 3F [ 11.555086] 1 0 0 0 0 0 0 00 [ 11.556423] 1 0 0 0 0 0 0 00 [ 11.557751] 1 0 0 0 0 0 0 00 [ 11.559074] 1 0 0 0 0 0 0 00 [ 11.560431] 1 0 0 0 0 0 0 00 [ 11.561758] 1 0 0 0 0 0 0 00 [ 11.563088] 1 0 0 0 0 0 0 00 [ 11.564428] 1 0 0 0 0 0 0 00 [ 11.565748] IRQ to pin mappings: [ 11.566821] IRQ0 -> 0:2 [ 11.568010] IRQ1 -> 0:1 [ 11.569194] IRQ3 -> 0:3 [ 11.570418] IRQ4 -> 0:4 [ 11.571605] IRQ5 -> 0:5 [ 11.572787] IRQ6 -> 0:6 [ 11.573971] IRQ7 -> 0:7 [ 11.575155] IRQ8 -> 0:8 [ 11.576352] IRQ9 -> 0:9 [ 11.577539] IRQ10 -> 0:10 [ 11.578754] IRQ11 -> 0:11 [ 11.579976] IRQ12 -> 0:12 [ 11.581229] IRQ13 -> 0:13 [ 11.582458] IRQ14 -> 0:14 [ 11.583685] IRQ15 -> 0:15 [ 11.584904] .................................... done. [ 11.588813] Running tests on trace events: [ 11.590104] Testing event thermal_apic_exit: OK [ 11.631264] Testing event thermal_apic_entry: OK [ 11.671085] Testing event threshold_apic_exit: OK [ 11.741147] Testing event threshold_apic_entry: OK [ 11.801187] Testing event call_function_single_exit: OK [ 11.843415] Testing event call_function_single_entry: OK [ 11.883662] Testing event call_function_exit: OK [ 11.926012] Testing event call_function_entry: OK [ 11.961611] Testing event irq_work_exit: OK [ 12.001312] Testing event irq_work_entry: OK [ 12.098875] Testing event x86_platform_ipi_exit: OK [ 12.160117] Testing event x86_platform_ipi_entry: OK [ 12.211317] Testing event error_apic_exit: OK [ 12.279721] Testing event error_apic_entry: OK [ 12.349067] Testing event spurious_apic_exit: OK [ 12.419232] Testing event spurious_apic_entry: OK [ 12.556878] Testing event reschedule_exit: OK [ 12.632668] Testing event reschedule_entry: OK [ 12.702744] Testing event local_timer_exit: OK [ 12.754952] Testing event local_timer_entry: OK [ 12.801714] Testing event nmi_handler: OK [ 12.846439] Testing event emulate_vsyscall: OK [ 12.891284] Testing event sys_exit: OK [ 12.931382] Testing event sys_enter: OK [ 12.971309] Testing event ipi_exit: OK [ 13.017881] Testing event ipi_entry: OK [ 13.062336] Testing event ipi_raise: OK [ 13.105621] Testing event tlb_flush: OK [ 13.147519] Testing event page_fault_kernel: OK [ 13.191378] Testing event page_fault_user: OK [ 13.238132] Testing event task_rename: OK [ 13.286825] Testing event task_newtask: OK [ 13.332180] Testing event softirq_raise: OK [ 13.380262] Testing event softirq_exit: OK [ 13.434446] Testing event softirq_entry: OK [ 13.472378] Testing event irq_handler_exit: OK [ 13.530615] Testing event irq_handler_entry: OK [ 13.585094] Testing event signal_deliver: OK [ 13.637647] Testing event signal_generate: OK [ 13.682019] Testing event workqueue_execute_end: OK [ 13.721997] Testing event workqueue_execute_start: OK [ 13.803725] Testing event workqueue_activate_work: OK [ 13.847252] Testing event workqueue_queue_work: OK [ 13.902591] Testing event sched_wake_idle_without_ipi: OK [ 13.964923] Testing event sched_swap_numa: OK [ 14.039603] Testing event sched_stick_numa: OK [ 14.083318] Testing event sched_move_numa: OK [ 14.121434] Testing event sched_pi_setprio: OK [ 14.166840] Testing event sched_stat_runtime: OK [ 14.227588] Testing event sched_stat_blocked: OK [ 14.277176] Testing event sched_stat_iowait: OK [ 14.321421] Testing event sched_stat_sleep: OK [ 14.361351] Testing event sched_stat_wait: OK [ 14.401398] Testing event sched_process_exec: OK [ 14.441422] Testing event sched_process_fork: OK [ 14.481361] Testing event sched_process_wait: OK [ 14.521437] Testing event sched_wait_task: OK [ 14.561454] Testing event sched_process_exit: OK [ 14.601407] Testing event sched_process_free: OK [ 14.669507] Testing event sched_migrate_task: OK [ 14.725622] Testing event sched_switch: OK [ 14.775654] Testing event sched_wakeup_new: OK [ 14.827581] Testing event sched_wakeup: OK [ 14.887613] Testing event sched_kthread_stop_ret: OK [ 14.945617] Testing event sched_kthread_stop: OK [ 15.001300] Testing event console: OK [ 15.045750] Testing event rcu_barrier: OK [ 15.108991] Testing event rcu_torture_read: OK [ 15.162787] Testing event rcu_batch_end: OK [ 15.216004] Testing event rcu_invoke_kfree_callback: OK [ 15.261955] Testing event rcu_invoke_callback: OK [ 15.315979] Testing event rcu_batch_start: OK [ 15.362462] Testing event rcu_kfree_callback: OK [ 15.406404] Testing event rcu_callback: OK [ 15.465939] Testing event rcu_prep_idle: OK [ 15.505175] Testing event rcu_dyntick: OK [ 15.566266] Testing event rcu_fqs: OK [ 15.620736] Testing event rcu_quiescent_state_report: OK [ 15.666091] Testing event rcu_unlock_preempted_task: OK [ 15.722243] Testing event rcu_preempt_task: OK [ 15.775979] Testing event rcu_nocb_wake: OK [ 15.822185] Testing event rcu_grace_period_init: OK [ 15.866060] Testing event rcu_future_grace_period: OK [ 15.924122] Testing event rcu_grace_period: OK [ 15.963370] Testing event rcu_utilization: OK [ 16.002712] Testing event tick_stop: OK [ 16.042394] Testing event itimer_expire: OK [ 16.082180] Testing event itimer_state: OK [ 16.122524] Testing event hrtimer_cancel: OK [ 16.162661] Testing event hrtimer_expire_exit: OK [ 16.202030] Testing event hrtimer_expire_entry: OK [ 16.241369] Testing event hrtimer_start: OK [ 16.281364] Testing event hrtimer_init: OK [ 16.322459] Testing event timer_cancel: OK [ 16.362757] Testing event timer_expire_exit: OK [ 16.402094] Testing event timer_expire_entry: OK [ 16.451672] Testing event timer_start: OK [ 16.493012] Testing event timer_init: OK [ 16.541784] Testing event ftrace_test_filter: OK [ 16.582705] Testing event dev_pm_qos_remove_request: OK [ 16.621582] Testing event dev_pm_qos_update_request: OK [ 16.662438] Testing event dev_pm_qos_add_request: OK [ 16.702047] Testing event pm_qos_update_flags: OK [ 16.742010] Testing event pm_qos_update_target: OK [ 16.784418] Testing event pm_qos_update_request_timeout: OK [ 16.822363] Testing event pm_qos_remove_request: OK [ 16.864202] Testing event pm_qos_update_request: OK [ 16.902250] Testing event pm_qos_add_request: OK [ 16.942088] Testing event power_domain_target: OK [ 16.981335] Testing event clock_set_rate: OK [ 17.021167] Testing event clock_disable: OK [ 17.072629] Testing event clock_enable: OK [ 17.112027] Testing event wakeup_source_deactivate: OK [ 17.152076] Testing event wakeup_source_activate: OK [ 17.191881] Testing event suspend_resume: OK [ 17.248847] Testing event device_pm_callback_end: OK [ 17.302764] Testing event device_pm_callback_start: OK [ 17.343523] Testing event cpu_frequency: OK [ 17.382662] Testing event pstate_sample: OK [ 17.431419] Testing event cpu_idle: OK [ 17.472120] Testing event rpm_return_int: OK [ 17.511834] Testing event rpm_idle: OK [ 17.551411] Testing event rpm_resume: OK [ 17.601626] Testing event rpm_suspend: OK [ 17.641451] Testing event benchmark_event: OK [ 17.751541] Testing event user_exit: OK [ 17.792073] Testing event user_enter: OK [ 17.831421] Testing event mm_filemap_add_to_page_cache: OK [ 17.872297] Testing event mm_filemap_delete_from_page_cache: OK [ 17.912155] Testing event oom_score_adj_update: OK [ 17.952277] Testing event mm_lru_activate: OK [ 17.991397] Testing event mm_lru_insertion: OK [ 18.031516] Testing event mm_vmscan_lru_shrink_inactive: OK [ 18.071790] Testing event mm_vmscan_writepage: OK [ 18.112101] Testing event mm_vmscan_memcg_isolate: OK [ 18.162003] Testing event mm_vmscan_lru_isolate: OK [ 18.201992] Testing event mm_shrink_slab_end: OK [ 18.242610] Testing event mm_shrink_slab_start: OK [ 18.281925] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 18.321759] Testing event mm_vmscan_memcg_reclaim_end: OK [ 18.361324] Testing event mm_vmscan_direct_reclaim_end: OK [ 18.401339] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 18.441190] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 18.481186] Testing event mm_vmscan_direct_reclaim_begin: OK [ 18.521327] Testing event mm_vmscan_wakeup_kswapd: OK [ 18.561454] Testing event mm_vmscan_kswapd_wake: OK [ 18.601441] Testing event mm_vmscan_kswapd_sleep: OK [ 18.641441] Testing event mm_page_alloc_extfrag: OK [ 18.681393] Testing event mm_page_pcpu_drain: OK [ 18.721358] Testing event mm_page_alloc_zone_locked: OK [ 18.761334] Testing event mm_page_alloc: OK [ 18.801368] Testing event mm_page_free_batched: OK [ 18.841321] Testing event mm_page_free: OK [ 18.881359] Testing event kmem_cache_free: OK [ 18.921330] Testing event kfree: OK [ 18.961314] Testing event kmem_cache_alloc_node: OK [ 19.001307] Testing event kmalloc_node: OK [ 19.041870] Testing event kmem_cache_alloc: OK [ 19.081347] Testing event kmalloc: OK [ 19.121638] Testing event mm_compaction_end: OK [ 19.161232] Testing event mm_compaction_begin: OK [ 19.201310] Testing event mm_compaction_migratepages: OK [ 19.241293] Testing event mm_compaction_isolate_freepages: OK [ 19.281314] Testing event mm_compaction_isolate_migratepages: OK [ 19.321306] Testing event mm_numa_migrate_ratelimit: OK [ 19.364100] Testing event mm_migrate_pages: OK [ 19.401762] Testing event writeback_single_inode: OK [ 19.441358] Testing event writeback_single_inode_start: OK [ 19.481337] Testing event writeback_wait_iff_congested: OK [ 19.521252] Testing event writeback_congestion_wait: OK [ 19.561285] Testing event writeback_sb_inodes_requeue: OK [ 19.601423] Testing event balance_dirty_pages: OK [ 19.641416] Testing event bdi_dirty_ratelimit: OK [ 19.681327] Testing event global_dirty_state: OK [ 19.721329] Testing event writeback_queue_io: OK [ 19.761348] Testing event wbc_writepage: OK [ 19.801466] Testing event writeback_bdi_unregister: OK [ 19.841357] Testing event writeback_bdi_register: OK [ 19.881306] Testing event writeback_wake_background: OK [ 19.921281] Testing event writeback_nowork: OK [ 19.961327] Testing event writeback_pages_written: OK [ 20.001437] Testing event writeback_wait: OK [ 20.041356] Testing event writeback_written: OK [ 20.081328] Testing event writeback_start: OK [ 20.121414] Testing event writeback_exec: OK [ 20.171886] Testing event writeback_queue: OK [ 20.212154] Testing event writeback_write_inode: OK [ 20.251744] Testing event writeback_write_inode_start: OK [ 20.292416] Testing event writeback_dirty_inode: OK [ 20.332367] Testing event writeback_dirty_inode_start: OK [ 20.371730] Testing event writeback_dirty_page: OK [ 20.412285] Testing event time_out_leases: OK [ 20.451711] Testing event generic_delete_lease: OK [ 20.491980] Testing event generic_add_lease: OK [ 20.532100] Testing event break_lease_unblock: OK [ 20.572148] Testing event break_lease_block: OK [ 20.611803] Testing event break_lease_noblock: OK [ 20.651846] Testing event ext3_load_inode: OK [ 20.691912] Testing event ext3_get_blocks_exit: OK [ 20.731644] Testing event ext3_get_blocks_enter: OK [ 20.771988] Testing event ext3_truncate_exit: OK [ 20.812427] Testing event ext3_truncate_enter: OK [ 20.851364] Testing event ext3_unlink_exit: OK [ 20.901991] Testing event ext3_unlink_enter: OK [ 20.942062] Testing event ext3_direct_IO_exit: OK [ 20.992314] Testing event ext3_direct_IO_enter: OK [ 21.032072] Testing event ext3_read_block_bitmap: OK [ 21.071954] Testing event ext3_forget: OK [ 21.115622] Testing event ext3_reserved: OK [ 21.153257] Testing event ext3_alloc_new_reservation: OK [ 21.192265] Testing event ext3_discard_reservation: OK [ 21.232053] Testing event ext3_rsv_window_add: OK [ 21.271958] Testing event ext3_sync_fs: OK [ 21.311658] Testing event ext3_sync_file_exit: OK [ 21.352204] Testing event ext3_sync_file_enter: OK [ 21.391957] Testing event ext3_free_blocks: OK [ 21.432247] Testing event ext3_allocate_blocks: OK [ 21.471948] Testing event ext3_request_blocks: OK [ 21.512118] Testing event ext3_discard_blocks: OK [ 21.551550] Testing event ext3_invalidatepage: OK [ 21.592171] Testing event ext3_releasepage: OK [ 21.632072] Testing event ext3_readpage: OK [ 21.672253] Testing event ext3_journalled_writepage: OK [ 21.712372] Testing event ext3_writeback_writepage: OK [ 21.751996] Testing event ext3_ordered_writepage: OK [ 21.791584] Testing event ext3_journalled_write_end: OK [ 21.831786] Testing event ext3_writeback_write_end: OK [ 21.872574] Testing event ext3_ordered_write_end: OK [ 21.912092] Testing event ext3_write_begin: OK [ 21.953815] Testing event ext3_mark_inode_dirty: OK [ 21.992121] Testing event ext3_drop_inode: OK [ 22.031733] Testing event ext3_evict_inode: OK [ 22.072373] Testing event ext3_allocate_inode: OK [ 22.112043] Testing event ext3_request_inode: OK [ 22.151313] Testing event ext3_free_inode: OK [ 22.191355] Testing event ext4_es_shrink: OK [ 22.231261] Testing event ext4_collapse_range: OK [ 22.271241] Testing event ext4_es_shrink_scan_exit: OK [ 22.311267] Testing event ext4_es_shrink_scan_enter: OK [ 22.351374] Testing event ext4_es_shrink_count: OK [ 22.391877] Testing event ext4_es_lookup_extent_exit: OK [ 22.431657] Testing event ext4_es_lookup_extent_enter: OK [ 22.472171] Testing event ext4_es_find_delayed_extent_range_exit: OK [ 22.521983] Testing event ext4_es_find_delayed_extent_range_enter: OK [ 22.561406] Testing event ext4_es_remove_extent: OK [ 22.602685] Testing event ext4_es_cache_extent: OK [ 22.641434] Testing event ext4_es_insert_extent: OK [ 22.681294] Testing event ext4_ext_remove_space_done: OK [ 22.721369] Testing event ext4_ext_remove_space: OK [ 22.761356] Testing event ext4_ext_rm_idx: OK [ 22.801262] Testing event ext4_ext_rm_leaf: OK [ 22.841593] Testing event ext4_remove_blocks: OK [ 22.881933] Testing event ext4_ext_show_extent: OK [ 22.922233] Testing event ext4_get_reserved_cluster_alloc: OK [ 22.972041] Testing event ext4_find_delalloc_range: OK [ 23.011925] Testing event ext4_ext_in_cache: OK [ 23.051731] Testing event ext4_ext_put_in_cache: OK [ 23.091680] Testing event ext4_get_implied_cluster_alloc_exit: OK [ 23.131981] Testing event ext4_ext_handle_unwritten_extents: OK [ 23.171758] Testing event ext4_trim_all_free: OK [ 23.211713] Testing event ext4_trim_extent: OK [ 23.252023] Testing event ext4_journal_start_reserved: OK [ 23.291694] Testing event ext4_journal_start: OK [ 23.333401] Testing event ext4_load_inode: OK [ 23.381750] Testing event ext4_ext_load_extent: OK [ 23.421940] Testing event ext4_ind_map_blocks_exit: OK [ 23.462517] Testing event ext4_ext_map_blocks_exit: OK [ 23.501657] Testing event ext4_ind_map_blocks_enter: OK [ 23.543149] Testing event ext4_ext_map_blocks_enter: OK [ 23.581789] Testing event ext4_ext_convert_to_initialized_fastpath: OK [ 23.622294] Testing event ext4_ext_convert_to_initialized_enter: OK [ 23.661585] Testing event ext4_truncate_exit: OK [ 23.701802] Testing event ext4_truncate_enter: OK [ 23.751856] Testing event ext4_unlink_exit: OK [ 23.791799] Testing event ext4_unlink_enter: OK [ 23.831935] Testing event ext4_fallocate_exit: OK [ 23.871929] Testing event ext4_zero_range: OK [ 23.911723] Testing event ext4_punch_hole: OK [ 23.951461] Testing event ext4_fallocate_enter: OK [ 23.991253] Testing event ext4_direct_IO_exit: OK [ 24.072157] Testing event ext4_direct_IO_enter: OK [ 24.111417] Testing event ext4_load_inode_bitmap: OK [ 24.151236] Testing event ext4_read_block_bitmap_load: OK [ 24.192831] Testing event ext4_mb_buddy_bitmap_load: OK [ 24.233724] Testing event ext4_mb_bitmap_load: OK [ 24.273421] Testing event ext4_da_release_space: OK [ 24.312081] Testing event ext4_da_reserve_space: OK [ 24.352263] Testing event ext4_da_update_reserve_space: OK [ 24.392239] Testing event ext4_forget: OK [ 24.432131] Testing event ext4_mballoc_free: OK [ 24.473593] Testing event ext4_mballoc_discard: OK [ 24.511874] Testing event ext4_mballoc_prealloc: OK [ 24.552250] Testing event ext4_mballoc_alloc: OK [ 24.591959] Testing event ext4_alloc_da_blocks: OK [ 24.631720] Testing event ext4_sync_fs: OK [ 24.671626] Testing event ext4_sync_file_exit: OK [ 24.711784] Testing event ext4_sync_file_enter: OK [ 24.752254] Testing event ext4_free_blocks: OK [ 24.792177] Testing event ext4_allocate_blocks: OK [ 24.833119] Testing event ext4_request_blocks: OK [ 24.871695] Testing event ext4_mb_discard_preallocations: OK [ 24.922088] Testing event ext4_discard_preallocations: OK [ 24.962142] Testing event ext4_mb_release_group_pa: OK [ 25.001868] Testing event ext4_mb_release_inode_pa: OK [ 25.041840] Testing event ext4_mb_new_group_pa: OK [ 25.082012] Testing event ext4_mb_new_inode_pa: OK [ 25.122120] Testing event ext4_discard_blocks: OK [ 25.173071] Testing event ext4_journalled_invalidatepage: OK [ 25.212247] Testing event ext4_invalidatepage: OK [ 25.251966] Testing event ext4_releasepage: OK [ 25.292214] Testing event ext4_readpage: OK [ 25.332114] Testing event ext4_writepage: OK [ 25.371671] Testing event ext4_writepages_result: OK [ 25.412047] Testing event ext4_da_write_pages_extent: OK [ 25.451827] Testing event ext4_da_write_pages: OK [ 25.492077] Testing event ext4_writepages: OK [ 25.531788] Testing event ext4_da_write_end: OK [ 25.571935] Testing event ext4_journalled_write_end: OK [ 25.612113] Testing event ext4_write_end: OK [ 25.651698] Testing event ext4_da_write_begin: OK [ 25.691771] Testing event ext4_write_begin: OK [ 25.731779] Testing event ext4_begin_ordered_truncate: OK [ 25.782009] Testing event ext4_mark_inode_dirty: OK [ 25.835380] Testing event ext4_drop_inode: OK [ 25.871697] Testing event ext4_evict_inode: OK [ 25.911821] Testing event ext4_allocate_inode: OK [ 25.954639] Testing event ext4_request_inode: OK [ 25.996013] Testing event ext4_free_inode: OK [ 26.035610] Testing event journal_write_superblock: OK [ 26.084357] Testing event jbd_cleanup_journal_tail: OK [ 26.121225] Testing event jbd_do_submit_data: OK [ 26.161448] Testing event jbd_end_commit: OK [ 26.201341] Testing event jbd_drop_transaction: OK [ 26.241432] Testing event jbd_commit_logging: OK [ 26.281567] Testing event jbd_commit_flushing: OK [ 26.321252] Testing event jbd_commit_locking: OK [ 26.361264] Testing event jbd_start_commit: OK [ 26.419494] Testing event jbd_checkpoint: OK [ 26.469387] Testing event jbd2_lock_buffer_stall: OK [ 26.519416] Testing event jbd2_write_superblock: OK [ 26.569380] Testing event jbd2_update_log_tail: OK [ 26.619393] Testing event jbd2_checkpoint_stats: OK [ 26.669417] Testing event jbd2_run_stats: OK [ 26.719387] Testing event jbd2_handle_stats: OK [ 26.769416] Testing event jbd2_handle_extend: OK [ 26.817392] Testing event jbd2_handle_start: OK [ 26.861186] Testing event jbd2_submit_inode_data: OK [ 26.901265] Testing event jbd2_end_commit: OK [ 26.941170] Testing event jbd2_drop_transaction: OK [ 26.981237] Testing event jbd2_commit_logging: OK [ 27.021304] Testing event jbd2_commit_flushing: OK [ 27.061167] Testing event jbd2_commit_locking: OK [ 27.101260] Testing event jbd2_start_commit: OK [ 27.177628] Testing event jbd2_checkpoint: OK [ 27.243967] Testing event btrfs_qgroup_record_ref: OK [ 27.297105] Testing event btrfs_qgroup_account: OK [ 27.346079] Testing event btrfs_workqueue_destroy: OK [ 27.428535] Testing event btrfs_workqueue_alloc: OK [ 27.490072] Testing event btrfs_ordered_sched: OK [ 27.546097] Testing event btrfs_all_work_done: OK [ 27.596408] Testing event btrfs_work_sched: OK [ 27.643820] Testing event btrfs_work_queued: OK [ 27.704036] Testing event free_extent_state: OK [ 27.766941] Testing event alloc_extent_state: OK [ 27.816929] Testing event btrfs_setup_cluster: OK [ 27.866073] Testing event btrfs_failed_cluster_setup: OK [ 27.916882] Testing event btrfs_find_cluster: OK [ 27.966130] Testing event btrfs_reserve_extent_cluster: OK [ 28.016175] Testing event btrfs_reserve_extent: OK [ 28.075104] Testing event find_free_extent: OK [ 28.125924] Testing event btrfs_reserved_extent_free: OK [ 28.176508] Testing event btrfs_reserved_extent_alloc: OK [ 28.227165] Testing event btrfs_space_reservation: OK [ 28.289689] Testing event btrfs_cow_block: OK [ 28.336100] Testing event btrfs_chunk_free: OK [ 28.385781] Testing event btrfs_chunk_alloc: OK [ 28.436444] Testing event run_delayed_ref_head: OK [ 28.486316] Testing event add_delayed_ref_head: OK [ 28.535775] Testing event run_delayed_data_ref: OK [ 28.586300] Testing event add_delayed_data_ref: OK [ 28.636321] Testing event run_delayed_tree_ref: OK [ 28.684884] Testing event add_delayed_tree_ref: OK [ 28.736386] Testing event btrfs_sync_fs: OK [ 28.786422] Testing event btrfs_sync_file: OK [ 28.847459] Testing event btrfs_writepage_end_io_hook: OK [ 28.919646] Testing event __extent_writepage: OK [ 29.000557] Testing event btrfs_ordered_extent_put: OK [ 29.107213] Testing event btrfs_ordered_extent_start: OK [ 29.184935] Testing event btrfs_ordered_extent_remove: OK [ 29.250364] Testing event btrfs_ordered_extent_add: OK [ 29.326343] Testing event btrfs_get_extent: OK [ 29.390624] Testing event btrfs_inode_evict: OK [ 29.470229] Testing event btrfs_inode_request: OK [ 29.563312] Testing event btrfs_inode_new: OK [ 29.683346] Testing event btrfs_transaction_commit: OK [ 29.809572] Testing event block_rq_remap: OK [ 29.910443] Testing event block_bio_remap: OK [ 30.011609] Testing event block_split: OK [ 30.094163] Testing event block_unplug: OK [ 30.164633] Testing event block_plug: OK [ 30.221488] Testing event block_sleeprq: OK [ 30.291216] Testing event block_getrq: OK [ 30.364470] Testing event block_bio_queue: OK [ 30.541147] Testing event block_bio_frontmerge: OK [ 30.729120] Testing event block_bio_backmerge: OK [ 30.829604] Testing event block_bio_complete: OK [ 30.872628] Testing event block_bio_bounce: OK [ 30.911809] Testing event block_rq_issue: OK [ 30.951880] Testing event block_rq_insert: OK [ 30.991878] Testing event block_rq_complete: OK [ 31.032364] Testing event block_rq_requeue: OK [ 31.085538] Testing event block_rq_abort: OK [ 31.128362] Testing event block_dirty_buffer: OK [ 31.180574] Testing event block_touch_buffer: OK [ 31.233534] Testing event swiotlb_bounced: OK [ 31.313391] Testing event gpio_value: OK [ 31.360793] Testing event gpio_direction: OK [ 31.413122] Testing event regulator_set_voltage_complete: OK [ 31.486487] Testing event regulator_set_voltage: OK [ 31.549384] Testing event regulator_disable_complete: OK [ 31.629973] Testing event regulator_disable: OK [ 31.693422] Testing event regulator_enable_complete: OK [ 31.773364] Testing event regulator_enable_delay: OK [ 31.826086] Testing event regulator_enable: OK [ 31.889632] Testing event urandom_read: OK [ 31.958299] Testing event random_read: OK [ 32.036246] Testing event extract_entropy_user: OK [ 32.093876] Testing event extract_entropy: OK [ 32.136001] Testing event get_random_bytes_arch: OK [ 32.187141] Testing event get_random_bytes: OK [ 32.227451] Testing event xfer_secondary_pool: OK [ 32.278310] Testing event add_disk_randomness: OK [ 32.345717] Testing event add_input_randomness: OK [ 32.400788] Testing event debit_entropy: OK [ 32.442121] Testing event push_to_pool: OK [ 32.482402] Testing event credit_entropy_bits: OK [ 32.538566] Testing event mix_pool_bytes_nolock: OK [ 32.586580] Testing event mix_pool_bytes: OK [ 32.632525] Testing event add_device_randomness: OK [ 32.676958] Testing event drm_vblank_event_delivered: OK [ 32.728252] Testing event drm_vblank_event_queued: OK [ 32.785262] Testing event drm_vblank_event: OK [ 32.827286] Testing event radeon_semaphore_wait: OK [ 32.873692] Testing event radeon_semaphore_signale: OK [ 32.912862] Testing event radeon_fence_wait_end: OK [ 32.951990] Testing event radeon_fence_wait_begin: OK [ 32.991953] Testing event radeon_fence_emit: OK [ 33.032457] Testing event radeon_vm_flush: OK [ 33.071912] Testing event radeon_vm_set_page: OK [ 33.112904] Testing event radeon_vm_bo_update: OK [ 33.161905] Testing event radeon_vm_grab_id: OK [ 33.239611] Testing event radeon_cs: OK [ 33.281566] Testing event radeon_bo_create: OK [ 33.321968] Testing event regcache_drop_region: OK [ 33.361609] Testing event regmap_async_complete_done: OK [ 33.402569] Testing event regmap_async_complete_start: OK [ 33.442044] Testing event regmap_async_io_complete: OK [ 33.481808] Testing event regmap_async_write_start: OK [ 33.521956] Testing event regmap_cache_bypass: OK [ 33.574022] Testing event regmap_cache_only: OK [ 33.632235] Testing event regcache_sync: OK [ 33.682659] Testing event regmap_hw_write_done: OK [ 33.722385] Testing event regmap_hw_write_start: OK [ 33.763179] Testing event regmap_hw_read_done: OK [ 33.801856] Testing event regmap_hw_read_start: OK [ 33.841817] Testing event regmap_reg_read_cache: OK [ 33.881835] Testing event regmap_reg_read: OK [ 33.931892] Testing event regmap_reg_write: OK [ 33.976357] Testing event fence_wait_end: OK [ 34.012236] Testing event fence_wait_start: OK [ 34.095422] Testing event fence_signaled: OK [ 34.132511] Testing event fence_enable_signal: OK [ 34.172599] Testing event fence_destroy: OK [ 34.212130] Testing event fence_init: OK [ 34.252309] Testing event fence_emit: OK [ 34.292403] Testing event fence_annotate_wait_on: OK [ 34.331938] Testing event scsi_eh_wakeup: OK [ 34.382418] Testing event scsi_dispatch_cmd_timeout: OK [ 34.422080] Testing event scsi_dispatch_cmd_done: OK [ 34.483050] Testing event scsi_dispatch_cmd_error: OK [ 34.522152] Testing event scsi_dispatch_cmd_start: OK [ 34.565156] Testing event target_cmd_complete: OK [ 34.606296] Testing event target_sequencer_start: OK [ 34.652079] Testing event spi_transfer_stop: OK [ 34.702089] Testing event spi_transfer_start: OK [ 34.742557] Testing event spi_message_done: OK [ 34.792233] Testing event spi_message_start: OK [ 34.835351] Testing event spi_message_submit: OK [ 34.872432] Testing event spi_master_busy: OK [ 34.921973] Testing event spi_master_idle: OK [ 34.982192] Testing event smbus_result: OK [ 35.033116] Testing event smbus_reply: OK [ 35.072228] Testing event smbus_read: OK [ 35.112088] Testing event smbus_write: OK [ 35.152715] Testing event i2c_result: OK [ 35.202902] Testing event i2c_reply: OK [ 35.252620] Testing event i2c_read: OK [ 35.292407] Testing event i2c_write: OK [ 35.332763] Testing event thermal_zone_trip: OK [ 35.382247] Testing event cdev_update: OK [ 35.422120] Testing event thermal_temperature: OK [ 35.463351] Testing event bcache_writeback_collision: OK [ 35.502174] Testing event bcache_writeback: OK [ 35.542199] Testing event bcache_alloc_fail: OK [ 35.582342] Testing event bcache_alloc: OK [ 35.622208] Testing event bcache_invalidate: OK [ 35.662243] Testing event bcache_keyscan: OK [ 35.702257] Testing event bcache_btree_set_root: OK [ 35.752524] Testing event bcache_btree_node_compact: OK [ 35.802072] Testing event bcache_btree_node_split: OK [ 35.853795] Testing event bcache_btree_insert_key: OK [ 35.902196] Testing event bcache_gc_copy_collision: OK [ 35.941988] Testing event bcache_gc_copy: OK [ 35.982034] Testing event bcache_gc_end: OK [ 36.032103] Testing event bcache_gc_start: OK [ 36.081978] Testing event bcache_btree_gc_coalesce: OK [ 36.121960] Testing event bcache_btree_node_free: OK [ 36.162375] Testing event bcache_btree_node_alloc_fail: OK [ 36.202205] Testing event bcache_btree_node_alloc: OK [ 36.242067] Testing event bcache_btree_write: OK [ 36.282111] Testing event bcache_btree_read: OK [ 36.322079] Testing event bcache_btree_cache_cannibalize: OK [ 36.362249] Testing event bcache_journal_write: OK [ 36.402191] Testing event bcache_journal_entry_full: OK [ 36.441595] Testing event bcache_journal_full: OK [ 36.481364] Testing event bcache_journal_replay_key: OK [ 36.521350] Testing event bcache_cache_insert: OK [ 36.561385] Testing event bcache_read_retry: OK [ 36.601366] Testing event bcache_write: OK [ 36.641459] Testing event bcache_read: OK [ 36.681368] Testing event bcache_bypass_congested: OK [ 36.721335] Testing event bcache_bypass_sequential: OK [ 36.761412] Testing event bcache_request_end: OK [ 36.801398] Testing event bcache_request_start: OK [ 36.842319] Testing event aer_event: OK [ 36.881460] Testing event mc_event: OK [ 36.921234] Testing event hda_unsol_event: OK [ 36.961329] Testing event hda_power_count: OK [ 37.001408] Testing event hda_power_up: OK [ 37.042257] Testing event hda_power_down: OK [ 37.082034] Testing event hda_bus_reset: OK [ 37.122562] Testing event hda_get_response: OK [ 37.171920] Testing event hda_send_cmd: OK [ 37.211479] Testing event azx_get_position: OK [ 37.269882] Testing event azx_pcm_trigger: OK [ 37.311974] Testing event udp_fail_queue_rcv_skb: OK [ 37.351786] Testing event sock_exceed_buf_limit: OK [ 37.391962] Testing event sock_rcvqueue_full: OK [ 37.433129] Testing event napi_poll: OK [ 37.472540] Testing event netif_rx_ni_entry: OK [ 37.516547] Testing event netif_rx_entry: OK [ 37.551824] Testing event netif_receive_skb_entry: OK [ 37.592250] Testing event napi_gro_receive_entry: OK [ 37.632015] Testing event napi_gro_frags_entry: OK [ 37.671890] Testing event netif_rx: OK [ 37.712382] Testing event netif_receive_skb: OK [ 37.751896] Testing event net_dev_queue: OK [ 37.802608] Testing event net_dev_xmit: OK [ 37.841992] Testing event net_dev_start_xmit: OK [ 37.881943] Testing event skb_copy_datagram_iovec: OK [ 37.922020] Testing event consume_skb: OK [ 37.962355] Testing event kfree_skb: OK [ 38.002143] Running tests on trace event systems: [ 38.003547] Testing event system skb: OK [ 38.072945] Testing event system net: OK [ 38.116975] Testing event system napi: OK [ 38.151983] Testing event system sock: OK [ 38.192170] Testing event system udp: OK [ 38.236321] Testing event system hda_intel: OK [ 38.272037] Testing event system hda: OK [ 38.312928] Testing event system ras: OK [ 38.352033] Testing event system bcache: OK [ 38.408282] Testing event system thermal: OK [ 38.452449] Testing event system i2c: OK [ 38.493569] Testing event system spi: OK [ 38.533032] Testing event system target: OK [ 38.574206] Testing event system scsi: OK [ 38.613149] Testing event system fence: OK [ 38.654186] Testing event system regmap: OK [ 38.706128] Testing event system radeon: OK [ 38.754106] Testing event system drm: OK [ 38.792355] Testing event system random: OK [ 38.835010] Testing event system regulator: OK [ 38.883836] Testing event system gpio: OK [ 38.923590] Testing event system swiotlb: OK [ 38.962090] Testing event system block: OK [ 39.018409] Testing event system btrfs: OK [ 39.107096] Testing event system jbd2: OK [ 39.154824] Testing event system jbd: OK [ 39.203895] Testing event system ext4: OK [ 39.282285] Testing event system ext3: OK [ 39.339959] Testing event system filelock: OK [ 39.383089] Testing event system writeback: OK [ 39.437516] Testing event system migrate: OK [ 39.472791] Testing event system compaction: OK [ 39.515193] Testing event system kmem: OK [ 39.566020] Testing event system vmscan: OK [ 39.613769] Testing event system pagemap: OK [ 39.652065] Testing event system oom: OK [ 39.692050] Testing event system filemap: OK [ 39.732280] Testing event system context_tracking: OK [ 39.772058] Testing event system benchmark: OK [ 39.881939] Testing event system rpm: OK [ 39.923155] Testing event system power: OK [ 39.995862] Testing event system test: OK [ 40.031877] Testing event system timer: OK [ 40.090397] Testing event system rcu: OK [ 40.166873] Testing event system printk: OK [ 40.211844] Testing event system sched: OK [ 40.266299] Testing event system workqueue: OK [ 40.312443] Testing event system signal: OK [ 40.352738] Testing event system irq: OK [ 40.395388] Testing event system task: OK [ 40.432000] Testing event system exceptions: OK [ 40.472089] Testing event system tlb: OK [ 40.513791] Testing event system ipi: OK [ 40.556243] Testing event system raw_syscalls: OK [ 40.592975] Testing event system vsyscall: OK [ 40.631847] Testing event system nmi: OK [ 40.671907] Testing event system irq_vectors: OK [ 40.715551] Running tests on all trace events: [ 40.716905] Testing all events: OK [ 41.175309] Running tests again, along with the function tracer [ 41.177676] Running tests on trace events: [ 41.179382] Testing event thermal_apic_exit: OK [ 41.357214] Testing event thermal_apic_entry: OK [ 41.505598] Testing event threshold_apic_exit: OK [ 41.654778] Testing event threshold_apic_entry: OK [ 41.757800] Testing event call_function_single_exit: OK [ 41.876785] Testing event call_function_single_entry: OK [ 41.977352] Testing event call_function_exit: OK [ 42.197577] Testing event call_function_entry: OK [ 42.329970] Testing event irq_work_exit: OK [ 42.468821] Testing event irq_work_entry: OK [ 42.619417] Testing event x86_platform_ipi_exit: OK [ 42.794921] Testing event x86_platform_ipi_entry: OK [ 42.911840] Testing event error_apic_exit: OK [ 42.997089] Testing event error_apic_entry: OK [ 43.186845] Testing event spurious_apic_exit: OK [ 43.255363] Testing event spurious_apic_entry: OK [ 43.339178] Testing event reschedule_exit: OK [ 43.458245] Testing event reschedule_entry: OK [ 43.616040] Testing event local_timer_exit: OK [ 43.719381] Testing event local_timer_entry: OK [ 43.924215] Testing event nmi_handler: OK [ 44.029586] Testing event emulate_vsyscall: OK [ 44.209851] Testing event sys_exit: OK [ 44.385682] Testing event sys_enter: OK [ 44.474526] Testing event ipi_exit: OK [ 44.606289] Testing event ipi_entry: OK [ 44.728178] Testing event ipi_raise: OK [ 44.842188] Testing event tlb_flush: OK [ 44.994041] Testing event page_fault_kernel: OK [ 45.068931] Testing event page_fault_user: OK [ 45.177950] Testing event task_rename: OK [ 45.256037] Testing event task_newtask: OK [ 45.344251] Testing event softirq_raise: OK [ 45.499979] Testing event softirq_exit: OK [ 45.585065] Testing event softirq_entry: OK [ 45.657658] Testing event irq_handler_exit: OK [ 45.779895] Testing event irq_handler_entry: OK [ 45.898705] Testing event signal_deliver: OK [ 46.069793] Testing event signal_generate: OK [ 46.273410] Testing event workqueue_execute_end: OK [ 46.394544] Testing event workqueue_execute_start: OK [ 46.507264] Testing event workqueue_activate_work: OK [ 46.617155] Testing event workqueue_queue_work: OK [ 46.725520] Testing event sched_wake_idle_without_ipi: OK [ 46.823940] Testing event sched_swap_numa: OK [ 46.897554] Testing event sched_stick_numa: OK [ 47.017590] Testing event sched_move_numa: OK [ 47.167751] Testing event sched_pi_setprio: OK [ 47.296744] Testing event sched_stat_runtime: OK [ 47.397780] Testing event sched_stat_blocked: OK [ 47.488745] Testing event sched_stat_iowait: OK [ 47.599901] Testing event sched_stat_sleep: OK [ 47.732443] Testing event sched_stat_wait: OK [ 47.842872] Testing event sched_process_exec: OK [ 47.993684] Testing event sched_process_fork: OK [ 48.159951] Testing event sched_process_wait: OK [ 48.268385] Testing event sched_wait_task: OK [ 48.351042] Testing event sched_process_exit: OK [ 48.448278] Testing event sched_process_free: OK [ 48.556986] Testing event sched_migrate_task: OK [ 48.668749] Testing event sched_switch: OK [ 48.737768] Testing event sched_wakeup_new: OK [ 48.866495] Testing event sched_wakeup: OK [ 48.967172] Testing event sched_kthread_stop_ret: OK [ 49.147227] Testing event sched_kthread_stop: OK [ 49.266436] Testing event console: OK [ 49.409814] Testing event rcu_barrier: OK [ 49.556780] Testing event rcu_torture_read: OK [ 49.687898] Testing event rcu_batch_end: OK [ 49.828108] Testing event rcu_invoke_kfree_callback: OK [ 49.898840] Testing event rcu_invoke_callback: OK [ 50.006711] Testing event rcu_batch_start: OK [ 50.218010] Testing event rcu_kfree_callback: OK [ 50.370887] Testing event rcu_callback: OK [ 50.494185] Testing event rcu_prep_idle: OK [ 50.549064] Testing event rcu_dyntick: OK [ 50.709799] Testing event rcu_fqs: OK [ 50.811234] Testing event rcu_quiescent_state_report: OK [ 50.928124] Testing event rcu_unlock_preempted_task: OK [ 51.074178] Testing event rcu_preempt_task: OK [ 51.229022] Testing event rcu_nocb_wake: OK [ 51.327084] Testing event rcu_grace_period_init: OK [ 51.416184] Testing event rcu_future_grace_period: OK [ 51.528457] Testing event rcu_grace_period: OK [ 51.635150] Testing event rcu_utilization: OK [ 51.708084] Testing event tick_stop: OK [ 51.779943] Testing event itimer_expire: OK [ 51.838586] Testing event itimer_state: OK [ 51.906425] Testing event hrtimer_cancel: OK [ 51.979439] Testing event hrtimer_expire_exit: OK [ 52.064616] Testing event hrtimer_expire_entry: OK [ 52.186806] Testing event hrtimer_start: OK [ 52.266544] Testing event hrtimer_init: OK [ 52.368380] Testing event timer_cancel: OK [ 52.457656] Testing event timer_expire_exit: OK [ 52.526298] Testing event timer_expire_entry: OK [ 52.624036] Testing event timer_start: OK [ 52.739541] Testing event timer_init: OK [ 52.811475] Testing event ftrace_test_filter: OK [ 52.886287] Testing event dev_pm_qos_remove_request: OK [ 52.975798] Testing event dev_pm_qos_update_request: OK [ 53.148463] Testing event dev_pm_qos_add_request: OK [ 53.228673] Testing event pm_qos_update_flags: OK [ 53.315849] Testing event pm_qos_update_target: OK [ 53.376825] Testing event pm_qos_update_request_timeout: OK [ 53.427966] Testing event pm_qos_remove_request: OK [ 53.477819] Testing event pm_qos_update_request: OK [ 53.543177] Testing event pm_qos_add_request: OK [ 53.595718] Testing event power_domain_target: OK [ 53.646436] Testing event clock_set_rate: OK [ 53.697613] Testing event clock_disable: OK [ 53.746644] Testing event clock_enable: OK [ 53.797771] Testing event wakeup_source_deactivate: OK [ 53.848856] Testing event wakeup_source_activate: OK [ 53.919794] Testing event suspend_resume: OK [ 54.093707] Testing event device_pm_callback_end: OK [ 54.176082] Testing event device_pm_callback_start: OK [ 54.271767] Testing event cpu_frequency: OK [ 54.374482] Testing event pstate_sample: OK [ 54.479059] Testing event cpu_idle: OK [ 54.631431] Testing event rpm_return_int: OK [ 54.839979] Testing event rpm_idle: OK [ 54.973202] Testing event rpm_resume: OK [ 55.085786] Testing event rpm_suspend: OK [ 55.172549] Testing event benchmark_event: OK [ 55.327571] Testing event user_exit: OK [ 55.414172] Testing event user_enter: OK [ 55.497906] Testing event mm_filemap_add_to_page_cache: OK [ 55.622700] Testing event mm_filemap_delete_from_page_cache: OK [ 55.718028] Testing event oom_score_adj_update: OK [ 55.834704] Testing event mm_lru_activate: OK [ 55.919674] Testing event mm_lru_insertion: OK [ 55.993981] Testing event mm_vmscan_lru_shrink_inactive: OK [ 56.128370] Testing event mm_vmscan_writepage: OK [ 56.243097] Testing event mm_vmscan_memcg_isolate: OK [ 56.416656] Testing event mm_vmscan_lru_isolate: OK [ 56.586098] Testing event mm_shrink_slab_end: OK [ 56.638683] Testing event mm_shrink_slab_start: OK [ 56.697618] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 56.763855] Testing event mm_vmscan_memcg_reclaim_end: OK [ 56.919655] Testing event mm_vmscan_direct_reclaim_end: OK [ 57.139778] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 57.274519] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 57.349317] Testing event mm_vmscan_direct_reclaim_begin: OK [ 57.552446] Testing event mm_vmscan_wakeup_kswapd: OK [ 57.693219] Testing event mm_vmscan_kswapd_wake: OK [ 57.848291] Testing event mm_vmscan_kswapd_sleep: OK [ 58.129143] Testing event mm_page_alloc_extfrag: OK [ 58.275648] Testing event mm_page_pcpu_drain: OK [ 58.408426] Testing event mm_page_alloc_zone_locked: OK [ 58.589852] Testing event mm_page_alloc: OK [ 58.750032] Testing event mm_page_free_batched: OK [ 58.895658] Testing event mm_page_free: OK [ 59.087187] Testing event kmem_cache_free: OK [ 59.209355] Testing event kfree: OK [ 59.367952] Testing event kmem_cache_alloc_node: OK [ 59.495822] Testing event kmalloc_node: OK [ 59.667106] Testing event kmem_cache_alloc: OK [ 59.818352] Testing event kmalloc: OK [ 59.919889] Testing event mm_compaction_end: OK [ 60.087411] Testing event mm_compaction_begin: OK [ 60.254686] Testing event mm_compaction_migratepages: OK [ 60.428233] Testing event mm_compaction_isolate_freepages: OK [ 60.513567] Testing event mm_compaction_isolate_migratepages: OK [ 60.625848] Testing event mm_numa_migrate_ratelimit: OK [ 60.739784] Testing event mm_migrate_pages: OK [ 60.853180] Testing event writeback_single_inode: OK [ 60.918308] Testing event writeback_single_inode_start: OK [ 61.028357] Testing event writeback_wait_iff_congested: OK [ 61.113045] Testing event writeback_congestion_wait: OK [ 61.165650] Testing event writeback_sb_inodes_requeue: OK [ 61.226391] Testing event balance_dirty_pages: OK [ 61.309337] Testing event bdi_dirty_ratelimit: OK [ 61.377742] Testing event global_dirty_state: OK [ 61.483449] Testing event writeback_queue_io: OK [ 61.547106] Testing event wbc_writepage: OK [ 61.601716] Testing event writeback_bdi_unregister: OK [ 61.681741] Testing event writeback_bdi_register: OK [ 61.785834] Testing event writeback_wake_background: OK [ 61.876071] Testing event writeback_nowork: OK [ 62.011662] Testing event writeback_pages_written: OK [ 62.076483] Testing event writeback_wait: OK [ 62.135758] Testing event writeback_written: OK [ 62.218090] Testing event writeback_start: OK [ 62.292391] Testing event writeback_exec: OK [ 62.407052] Testing event writeback_queue: OK [ 62.548437] Testing event writeback_write_inode: OK [ 62.666956] Testing event writeback_write_inode_start: OK [ 62.787008] Testing event writeback_dirty_inode: OK [ 62.857729] Testing event writeback_dirty_inode_start: OK [ 62.916832] Testing event writeback_dirty_page: OK [ 62.966468] Testing event time_out_leases: OK [ 63.118571] Testing event generic_delete_lease: OK [ 63.268578] Testing event generic_add_lease: OK [ 63.362512] Testing event break_lease_unblock: OK [ 63.418202] Testing event break_lease_block: OK [ 63.477900] Testing event break_lease_noblock: OK [ 63.556029] Testing event ext3_load_inode: OK [ 63.648215] Testing event ext3_get_blocks_exit: OK [ 63.745463] Testing event ext3_get_blocks_enter: OK [ 63.844182] Testing event ext3_truncate_exit: OK [ 64.003616] Testing event ext3_truncate_enter: [ 64.123553] Writes: Total: 4 Max/Min: 0/0 Fail: 0 [ 64.149471] OK [ 64.152019] Testing event ext3_unlink_exit: OK [ 64.309672] Testing event ext3_unlink_enter: OK [ 64.447350] Testing event ext3_direct_IO_exit: OK [ 64.569117] Testing event ext3_direct_IO_enter: OK [ 64.641057] Testing event ext3_read_block_bitmap: OK [ 64.729445] Testing event ext3_forget: OK [ 64.807559] Testing event ext3_reserved: OK [ 65.006428] Testing event ext3_alloc_new_reservation: OK [ 65.127503] Testing event ext3_discard_reservation: OK [ 65.239834] Testing event ext3_rsv_window_add: OK [ 65.324404] Testing event ext3_sync_fs: OK [ 65.426233] Testing event ext3_sync_file_exit: OK [ 65.537786] Testing event ext3_sync_file_enter: OK [ 65.613197] Testing event ext3_free_blocks: OK [ 65.679198] Testing event ext3_allocate_blocks: OK [ 65.763372] Testing event ext3_request_blocks: OK [ 65.851620] Testing event ext3_discard_blocks: OK [ 65.936743] Testing event ext3_invalidatepage: OK [ 65.989257] Testing event ext3_releasepage: OK [ 66.093709] Testing event ext3_readpage: OK [ 66.170366] Testing event ext3_journalled_writepage: OK [ 66.264051] Testing event ext3_writeback_writepage: OK [ 66.366497] Testing event ext3_ordered_writepage: OK [ 66.447230] Testing event ext3_journalled_write_end: OK [ 66.541289] Testing event ext3_writeback_write_end: OK [ 66.648646] Testing event ext3_ordered_write_end: OK [ 66.723582] Testing event ext3_write_begin: OK [ 66.803221] Testing event ext3_mark_inode_dirty: OK [ 66.891763] Testing event ext3_drop_inode: OK [ 66.986437] Testing event ext3_evict_inode: OK [ 67.068087] Testing event ext3_allocate_inode: OK [ 67.147482] Testing event ext3_request_inode: OK [ 67.249217] Testing event ext3_free_inode: OK [ 67.334752] Testing event ext4_es_shrink: OK [ 67.414575] Testing event ext4_collapse_range: OK [ 67.511324] Testing event ext4_es_shrink_scan_exit: OK [ 67.669072] Testing event ext4_es_shrink_scan_enter: OK [ 67.752989] Testing event ext4_es_shrink_count: OK [ 67.821796] Testing event ext4_es_lookup_extent_exit: OK [ 67.948599] Testing event ext4_es_lookup_extent_enter: OK [ 68.010780] Testing event ext4_es_find_delayed_extent_range_exit: OK [ 68.089866] Testing event ext4_es_find_delayed_extent_range_enter: OK [ 68.177664] Testing event ext4_es_remove_extent: OK [ 68.338893] Testing event ext4_es_cache_extent: OK [ 68.458404] Testing event ext4_es_insert_extent: OK [ 68.577984] Testing event ext4_ext_remove_space_done: OK [ 68.788996] Testing event ext4_ext_remove_space: OK [ 68.969515] Testing event ext4_ext_rm_idx: OK [ 69.089766] Testing event ext4_ext_rm_leaf: OK [ 69.226163] Testing event ext4_remove_blocks: OK [ 69.334974] Testing event ext4_ext_show_extent: OK [ 69.421836] Testing event ext4_get_reserved_cluster_alloc: OK [ 69.577511] Testing event ext4_find_delalloc_range: OK [ 69.679398] Testing event ext4_ext_in_cache: OK [ 69.809155] Testing event ext4_ext_put_in_cache: OK [ 69.938538] Testing event ext4_get_implied_cluster_alloc_exit: OK [ 70.129801] Testing event ext4_ext_handle_unwritten_extents: OK [ 70.277128] Testing event ext4_trim_all_free: OK [ 70.445768] Testing event ext4_trim_extent: OK [ 70.588741] Testing event ext4_journal_start_reserved: OK [ 70.738462] Testing event ext4_journal_start: OK [ 70.875640] Testing event ext4_load_inode: OK [ 71.016441] Testing event ext4_ext_load_extent: OK [ 71.138498] Testing event ext4_ind_map_blocks_exit: OK [ 71.241497] Testing event ext4_ext_map_blocks_exit: OK [ 71.378556] Testing event ext4_ind_map_blocks_enter: OK [ 71.483933] Testing event ext4_ext_map_blocks_enter: OK [ 71.627594] Testing event ext4_ext_convert_to_initialized_fastpath: OK [ 71.734139] Testing event ext4_ext_convert_to_initialized_enter: OK [ 71.834810] Testing event ext4_truncate_exit: OK [ 72.004846] Testing event ext4_truncate_enter: OK [ 72.128134] Testing event ext4_unlink_exit: OK [ 72.298323] Testing event ext4_unlink_enter: OK [ 72.426659] Testing event ext4_fallocate_exit: OK [ 72.580031] Testing event ext4_zero_range: OK [ 72.737571] Testing event ext4_punch_hole: OK [ 72.886531] Testing event ext4_fallocate_enter: OK [ 72.993623] Testing event ext4_direct_IO_exit: OK [ 73.146285] Testing event ext4_direct_IO_enter: OK [ 73.269688] Testing event ext4_load_inode_bitmap: OK [ 73.405402] Testing event ext4_read_block_bitmap_load: OK [ 73.479392] Testing event ext4_mb_buddy_bitmap_load: OK [ 73.525953] Testing event ext4_mb_bitmap_load: OK [ 73.675659] Testing event ext4_da_release_space: OK [ 73.726280] Testing event ext4_da_reserve_space: OK [ 73.775897] Testing event ext4_da_update_reserve_space: OK [ 73.933437] Testing event ext4_forget: OK [ 74.032668] Testing event ext4_mballoc_free: OK [ 74.157724] Testing event ext4_mballoc_discard: OK [ 74.348534] Testing event ext4_mballoc_prealloc: OK [ 74.497689] Testing event ext4_mballoc_alloc: OK [ 74.558980] Testing event ext4_alloc_da_blocks: OK [ 74.698008] Testing event ext4_sync_fs: OK [ 74.833997] Testing event ext4_sync_file_exit: OK [ 74.967447] Testing event ext4_sync_file_enter: OK [ 75.078861] Testing event ext4_free_blocks: OK [ 75.207939] Testing event ext4_allocate_blocks: OK [ 75.343873] Testing event ext4_request_blocks: OK [ 75.496439] Testing event ext4_mb_discard_preallocations: OK [ 75.609931] Testing event ext4_discard_preallocations: OK [ 75.741816] Testing event ext4_mb_release_group_pa: OK [ 75.845210] Testing event ext4_mb_release_inode_pa: OK [ 75.970877] Testing event ext4_mb_new_group_pa: OK [ 76.145388] Testing event ext4_mb_new_inode_pa: OK [ 76.299662] Testing event ext4_discard_blocks: OK [ 76.469964] Testing event ext4_journalled_invalidatepage: OK [ 76.589419] Testing event ext4_invalidatepage: OK [ 76.748823] Testing event ext4_releasepage: OK [ 76.916482] Testing event ext4_readpage: OK [ 77.109936] Testing event ext4_writepage: OK [ 77.307219] Testing event ext4_writepages_result: OK [ 77.454836] Testing event ext4_da_write_pages_extent: OK [ 77.643761] Testing event ext4_da_write_pages: OK [ 77.787903] Testing event ext4_writepages: OK [ 77.938888] Testing event ext4_da_write_end: OK [ 78.017521] Testing event ext4_journalled_write_end: OK [ 78.194722] Testing event ext4_write_end: OK [ 78.338592] Testing event ext4_da_write_begin: OK [ 78.484765] Testing event ext4_write_begin: OK [ 78.609571] Testing event ext4_begin_ordered_truncate: OK [ 78.761209] Testing event ext4_mark_inode_dirty: OK [ 78.913732] Testing event ext4_drop_inode: OK [ 79.068213] Testing event ext4_evict_inode: OK [ 79.166263] Testing event ext4_allocate_inode: OK [ 79.247129] Testing event ext4_request_inode: OK [ 79.387005] Testing event ext4_free_inode: OK [ 79.527849] Testing event journal_write_superblock: OK [ 79.676797] Testing event jbd_cleanup_journal_tail: OK [ 79.877055] Testing event jbd_do_submit_data: OK [ 80.078973] Testing event jbd_end_commit: OK [ 80.201978] Testing event jbd_drop_transaction: OK [ 80.313868] Testing event jbd_commit_logging: OK [ 80.468465] Testing event jbd_commit_flushing: OK [ 80.616737] Testing event jbd_commit_locking: OK [ 80.755033] Testing event jbd_start_commit: OK [ 80.875114] Testing event jbd_checkpoint: OK [ 81.027535] Testing event jbd2_lock_buffer_stall: OK [ 81.156121] Testing event jbd2_write_superblock: OK [ 81.337380] Testing event jbd2_update_log_tail: OK [ 81.458668] Testing event jbd2_checkpoint_stats: OK [ 81.588759] Testing event jbd2_run_stats: OK [ 81.732544] Testing event jbd2_handle_stats: OK [ 81.886792] Testing event jbd2_handle_extend: OK [ 82.068594] Testing event jbd2_handle_start: OK [ 82.198197] Testing event jbd2_submit_inode_data: OK [ 82.337381] Testing event jbd2_end_commit: OK [ 82.415289] Testing event jbd2_drop_transaction: OK [ 82.475134] Testing event jbd2_commit_logging: OK [ 82.525916] Testing event jbd2_commit_flushing: OK [ 82.586871] Testing event jbd2_commit_locking: OK [ 82.646838] Testing event jbd2_start_commit: OK [ 82.698119] Testing event jbd2_checkpoint: OK [ 82.745558] Testing event btrfs_qgroup_record_ref: OK [ 82.799474] Testing event btrfs_qgroup_account: OK [ 82.849001] Testing event btrfs_workqueue_destroy: OK [ 82.900906] Testing event btrfs_workqueue_alloc: OK [ 82.949462] Testing event btrfs_ordered_sched: OK [ 83.029641] Testing event btrfs_all_work_done: OK [ 83.259075] Testing event btrfs_work_sched: OK [ 83.458043] Testing event btrfs_work_queued: OK [ 83.578250] Testing event free_extent_state: OK [ 83.778946] Testing event alloc_extent_state: OK [ 83.976852] Testing event btrfs_setup_cluster: OK [ 84.128256] Testing event btrfs_failed_cluster_setup: OK [ 84.267373] Testing event btrfs_find_cluster: OK [ 84.316197] Testing event btrfs_reserve_extent_cluster: OK [ 84.370937] Testing event btrfs_reserve_extent: OK [ 84.504993] Testing event find_free_extent: OK [ 84.625641] Testing event btrfs_reserved_extent_free: OK [ 84.835524] Testing event btrfs_reserved_extent_alloc: OK [ 84.978323] Testing event btrfs_space_reservation: OK [ 85.332843] Testing event btrfs_cow_block: OK [ 85.469653] Testing event btrfs_chunk_free: OK [ 85.586781] Testing event btrfs_chunk_alloc: OK [ 85.725558] Testing event run_delayed_ref_head: OK [ 85.911044] Testing event add_delayed_ref_head: OK [ 85.975998] Testing event run_delayed_data_ref: OK [ 86.178244] Testing event add_delayed_data_ref: OK [ 86.380856] Testing event run_delayed_tree_ref: OK [ 86.563032] Testing event add_delayed_tree_ref: OK [ 86.719198] Testing event btrfs_sync_fs: OK [ 86.879073] Testing event btrfs_sync_file: OK [ 87.014567] Testing event btrfs_writepage_end_io_hook: OK [ 87.167195] Testing event __extent_writepage: OK [ 87.296725] Testing event btrfs_ordered_extent_put: OK [ 87.449975] Testing event btrfs_ordered_extent_start: OK [ 87.623529] Testing event btrfs_ordered_extent_remove: OK [ 87.785350] Testing event btrfs_ordered_extent_add: OK [ 87.967330] Testing event btrfs_get_extent: OK [ 88.157331] Testing event btrfs_inode_evict: OK [ 88.309800] Testing event btrfs_inode_request: OK [ 88.429971] Testing event btrfs_inode_new: OK [ 88.596991] Testing event btrfs_transaction_commit: OK [ 88.794211] Testing event block_rq_remap: OK [ 88.938008] Testing event block_bio_remap: OK [ 89.149956] Testing event block_split: OK [ 89.258414] Testing event block_unplug: OK [ 89.415177] Testing event block_plug: OK [ 89.633044] Testing event block_sleeprq: OK [ 89.803970] Testing event block_getrq: OK [ 89.942617] Testing event block_bio_queue: OK [ 90.083663] Testing event block_bio_frontmerge: OK [ 90.229574] Testing event block_bio_backmerge: OK [ 90.435899] Testing event block_bio_complete: OK [ 90.638332] Testing event block_bio_bounce: OK [ 90.789446] Testing event block_rq_issue: OK [ 90.955806] Testing event block_rq_insert: OK [ 91.129140] Testing event block_rq_complete: OK [ 91.306242] Testing event block_rq_requeue: OK [ 91.432616] Testing event block_rq_abort: OK [ 91.611401] Testing event block_dirty_buffer: OK [ 91.725396] Testing event block_touch_buffer: OK [ 91.884355] Testing event swiotlb_bounced: OK [ 92.089811] Testing event gpio_value: OK [ 92.228808] Testing event gpio_direction: OK [ 92.388208] Testing event regulator_set_voltage_complete: OK [ 92.528519] Testing event regulator_set_voltage: OK [ 92.691010] Testing event regulator_disable_complete: OK [ 92.836825] Testing event regulator_disable: OK [ 92.909953] Testing event regulator_enable_complete: OK [ 93.155713] Testing event regulator_enable_delay: OK [ 93.256799] Testing event regulator_enable: OK [ 93.423660] Testing event urandom_read: OK [ 93.637983] Testing event random_read: OK [ 93.814149] Testing event extract_entropy_user: OK [ 93.978404] Testing event extract_entropy: OK [ 94.189478] Testing event get_random_bytes_arch: OK [ 94.338612] Testing event get_random_bytes: OK [ 94.478351] Testing event xfer_secondary_pool: OK [ 94.677998] Testing event add_disk_randomness: OK [ 94.845510] Testing event add_input_randomness: OK [ 94.931487] Testing event debit_entropy: OK [ 95.116857] Testing event push_to_pool: OK [ 95.256688] Testing event credit_entropy_bits: OK [ 95.471819] Testing event mix_pool_bytes_nolock: OK [ 95.668351] Testing event mix_pool_bytes: OK [ 95.808042] Testing event add_device_randomness: OK [ 95.978686] Testing event drm_vblank_event_delivered: OK [ 96.199709] Testing event drm_vblank_event_queued: OK [ 96.378937] Testing event drm_vblank_event: OK [ 96.518698] Testing event radeon_semaphore_wait: OK [ 96.656399] Testing event radeon_semaphore_signale: OK [ 96.855596] Testing event radeon_fence_wait_end: OK [ 96.969490] Testing event radeon_fence_wait_begin: OK [ 97.061802] Testing event radeon_fence_emit: OK [ 97.248138] Testing event radeon_vm_flush: OK [ 97.448988] Testing event radeon_vm_set_page: OK [ 97.556144] Testing event radeon_vm_bo_update: OK [ 97.645098] Testing event radeon_vm_grab_id: OK [ 97.809624] Testing event radeon_cs: OK [ 97.996782] Testing event radeon_bo_create: OK [ 98.159280] Testing event regcache_drop_region: OK [ 98.276609] Testing event regmap_async_complete_done: OK [ 98.509538] Testing event regmap_async_complete_start: OK [ 98.649652] Testing event regmap_async_io_complete: OK [ 98.812502] Testing event regmap_async_write_start: OK [ 99.034978] Testing event regmap_cache_bypass: OK [ 99.198987] Testing event regmap_cache_only: OK [ 99.367345] Testing event regcache_sync: OK [ 99.498427] Testing event regmap_hw_write_done: OK [ 99.586590] Testing event regmap_hw_write_start: OK [ 99.787657] Testing event regmap_hw_read_done: OK [ 99.929136] Testing event regmap_hw_read_start: OK [ 100.093361] Testing event regmap_reg_read_cache: OK [ 100.236169] Testing event regmap_reg_read: OK [ 100.418891] Testing event regmap_reg_write: OK [ 100.619144] Testing event fence_wait_end: OK [ 100.761101] Testing event fence_wait_start: OK [ 100.887407] Testing event fence_signaled: OK [ 101.078821] Testing event fence_enable_signal: OK [ 101.242153] Testing event fence_destroy: OK [ 101.417691] Testing event fence_init: OK [ 101.578299] Testing event fence_emit: OK [ 101.749748] Testing event fence_annotate_wait_on: OK [ 101.879806] Testing event scsi_eh_wakeup: OK [ 101.996113] Testing event scsi_dispatch_cmd_timeout: OK [ 102.229681] Testing event scsi_dispatch_cmd_done: OK [ 102.371367] Testing event scsi_dispatch_cmd_error: OK [ 102.548056] Testing event scsi_dispatch_cmd_start: OK [ 102.739170] Testing event target_cmd_complete: OK [ 102.947468] Testing event target_sequencer_start: OK [ 103.098973] Testing event spi_transfer_stop: OK [ 103.266721] Testing event spi_transfer_start: OK [ 103.425711] Testing event spi_message_done: OK [ 103.588915] Testing event spi_message_start: OK [ 103.746083] Testing event spi_message_submit: OK [ 103.921973] Testing event spi_master_busy: OK [ 104.049351] Testing event spi_master_idle: OK [ 104.185831] Testing event smbus_result: OK [ 104.360597] Testing event smbus_reply: OK [ 104.557146] Testing event smbus_read: OK [ 104.747349] Testing event smbus_write: OK [ 104.841800] Testing event i2c_result: OK [ 104.934857] Testing event i2c_reply: OK [ 105.037182] Testing event i2c_read: OK [ 105.247917] Testing event i2c_write: OK [ 105.413885] Testing event thermal_zone_trip: OK [ 105.573721] Testing event cdev_update: OK [ 105.737302] Testing event thermal_temperature: OK [ 105.932321] Testing event bcache_writeback_collision: OK [ 106.156384] Testing event bcache_writeback: OK [ 106.326109] Testing event bcache_alloc_fail: OK [ 106.553061] Testing event bcache_alloc: OK [ 106.685556] Testing event bcache_invalidate: OK [ 106.858917] Testing event bcache_keyscan: OK [ 107.016772] Testing event bcache_btree_set_root: OK [ 107.188048] Testing event bcache_btree_node_compact: OK [ 107.389553] Testing event bcache_btree_node_split: OK [ 107.564469] Testing event bcache_btree_insert_key: OK [ 107.698752] Testing event bcache_gc_copy_collision: OK [ 107.842049] Testing event bcache_gc_copy: OK [ 108.078345] Testing event bcache_gc_end: OK [ 108.202194] Testing event bcache_gc_start: OK [ 108.409195] Testing event bcache_btree_gc_coalesce: OK [ 108.657162] Testing event bcache_btree_node_free: OK [ 108.775644] Testing event bcache_btree_node_alloc_fail: OK [ 108.997595] Testing event bcache_btree_node_alloc: OK [ 109.091612] Testing event bcache_btree_write: OK [ 109.218750] Testing event bcache_btree_read: OK [ 109.373356] Testing event bcache_btree_cache_cannibalize: OK [ 109.536215] Testing event bcache_journal_write: OK [ 109.707033] Testing event bcache_journal_entry_full: OK [ 109.825502] Testing event bcache_journal_full: OK [ 109.974890] Testing event bcache_journal_replay_key: OK [ 110.168444] Testing event bcache_cache_insert: OK [ 110.308988] Testing event bcache_read_retry: OK [ 110.460781] Testing event bcache_write: OK [ 110.654655] Testing event bcache_read: OK [ 110.814657] Testing event bcache_bypass_congested: OK [ 110.947804] Testing event bcache_bypass_sequential: OK [ 111.109567] Testing event bcache_request_end: OK [ 111.288799] Testing event bcache_request_start: OK [ 111.468265] Testing event aer_event: OK [ 111.585441] Testing event mc_event: OK [ 111.757953] Testing event hda_unsol_event: OK [ 111.949811] Testing event hda_power_count: OK [ 112.142606] Testing event hda_power_up: OK [ 112.329695] Testing event hda_power_down: OK [ 112.496296] Testing event hda_bus_reset: OK [ 112.621017] Testing event hda_get_response: OK [ 112.829106] Testing event hda_send_cmd: OK [ 112.976382] Testing event azx_get_position: OK [ 113.124090] Testing event azx_pcm_trigger: OK [ 113.356127] Testing event udp_fail_queue_rcv_skb: OK [ 113.527346] Testing event sock_exceed_buf_limit: OK [ 113.667747] Testing event sock_rcvqueue_full: OK [ 113.839735] Testing event napi_poll: OK [ 114.017549] Testing event netif_rx_ni_entry: OK [ 114.226957] Testing event netif_rx_entry: OK [ 114.368482] Testing event netif_receive_skb_entry: OK [ 114.618799] Testing event napi_gro_receive_entry: OK [ 114.789405] Testing event napi_gro_frags_entry: OK [ 114.936028] Testing event netif_rx: OK [ 115.067383] Testing event netif_receive_skb: OK [ 115.226959] Testing event net_dev_queue: OK [ 115.379661] Testing event net_dev_xmit: OK [ 115.517885] Testing event net_dev_start_xmit: OK [ 115.667933] Testing event skb_copy_datagram_iovec: OK [ 115.848172] Testing event consume_skb: OK [ 115.965181] Testing event kfree_skb: OK [ 116.127567] Running tests on trace event systems: [ 116.129351] Testing event system skb: OK [ 116.419927] Testing event system net: OK [ 116.677962] Testing event system napi: OK [ 116.846641] Testing event system sock: OK [ 117.009836] Testing event system udp: OK [ 117.076466] Testing event system hda_intel: OK [ 117.246670] Testing event system hda: OK [ 117.467581] Testing event system ras: OK [ 117.643415] Testing event system bcache: OK [ 118.087230] Testing event system thermal: OK [ 118.297803] Testing event system i2c: OK [ 118.538159] Testing event system spi: OK [ 118.762806] Testing event system target: OK [ 118.975076] Testing event system scsi: OK [ 119.177031] Testing event system fence: OK [ 119.417797] Testing event system regmap: OK [ 119.838818] Testing event system radeon: OK [ 120.151629] Testing event system drm: OK [ 120.314993] Testing event system random: OK [ 120.595380] Testing event system regulator: OK [ 120.807262] Testing event system gpio: OK [ 121.078908] Testing event system swiotlb: OK [ 121.206009] Testing event system block: OK [ 121.504842] Testing event system btrfs: OK [ 122.281992] Testing event system jbd2: OK [ 122.597252] Testing event system jbd: OK [ 122.915237] Testing event system ext4: OK [ 123.585358] Testing event system ext3: OK [ 123.764934] Testing event system filelock: OK [ 123.842034] Testing event system writeback: OK [ 123.998625] Testing event system migrate: [ 124.123762] Writes: Total: 4 Max/Min: 0/0 Fail: 0 [ 124.138935] OK [ 124.143567] Testing event system compaction: OK [ 124.259797] Testing event system kmem: OK [ 124.390639] Testing event system vmscan: OK [ 124.535022] Testing event system pagemap: OK [ 124.621830] Testing event system oom: OK [ 124.801618] Testing event system filemap: OK [ 124.896254] Testing event system context_tracking: OK [ 124.998434] Testing event system benchmark: OK [ 125.193134] Testing event system rpm: OK [ 125.377600] Testing event system power: OK [ 125.656833] Testing event system test: OK [ 125.847686] Testing event system timer: OK [ 126.156378] Testing event system rcu: OK [ 126.502638] Testing event system printk: OK [ 126.642311] Testing event system sched: OK [ 126.853768] Testing event system workqueue: OK [ 126.951796] Testing event system signal: OK [ 127.093863] Testing event system irq: OK [ 127.196102] Testing event system task: OK [ 127.290245] Testing event system exceptions: OK [ 127.385575] Testing event system tlb: OK [ 127.525613] Testing event system ipi: OK [ 127.652935] Testing event system raw_syscalls: OK [ 127.717257] Testing event system vsyscall: OK [ 127.787555] Testing event system nmi: OK [ 127.849852] Testing event system irq_vectors: OK [ 128.125948] Running tests on all trace events: [ 128.129835] Testing all events: OK [ 133.700477] Testing ftrace filter: OK [ 133.896050] Btrfs loaded, assert=on [ 133.897396] BTRFS: selftest: Running btrfs free space cache tests [ 133.899024] BTRFS: selftest: Running extent only tests [ 133.913931] BTRFS: selftest: Running bitmap only tests [ 133.915599] BTRFS: selftest: Running bitmap and extent tests [ 133.917327] BTRFS: selftest: Running space stealing from bitmap to extent [ 133.919744] BTRFS: selftest: Free space cache tests finished [ 133.921356] BTRFS: selftest: Running extent buffer operation tests [ 133.922658] BTRFS: selftest: Running btrfs_split_item tests [ 133.924827] BTRFS: selftest: Running find delalloc tests [ 184.122769] Writes: Total: 4 Max/Min: 0/0 Fail: 0 [ 245.451282] lock_torture_st invoked oom-killer: gfp_mask=0x40d0, order=2, oom_score_adj=0 [ 245.453757] lock_torture_st cpuset=/ mems_allowed=0 [ 245.455518] CPU: 1 PID: 54 Comm: lock_torture_st Not tainted 3.18.0-rc2-ge754569 #509 [ 245.457842] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014 [ 245.460739] 0000000000000000 ffff88000bb2fae8 ffffffff82d77375 0000000000000000 [ 245.463635] 0000000000000000 ffff88000bb2fb68 ffffffff82d7156c ffff88000bb2fbc8 [ 245.466528] 0000000000000000 ffff88000bb2fb58 0000000000000000 ffff8800108ec000 [ 245.469427] Call Trace: [ 245.470545] [] dump_stack+0xc5/0x175 [ 245.472060] [] dump_header+0xbe/0x363 [ 245.473717] [] out_of_memory+0x9bb/0x9e0 [ 245.475312] [] __alloc_pages_nodemask+0x1cec/0x1e80 [ 245.477083] [] ? try_to_del_timer_sync+0x98/0xb0 [ 245.478814] [] alloc_pages_current+0x21a/0x2a0 [ 245.480595] [] alloc_kmem_pages+0x1e/0x30 [ 245.482201] [] kmalloc_order+0x20/0x70 [ 245.483762] [] kmalloc_order_trace+0x2c/0x1d0 [ 245.485440] [] __kmalloc+0x92/0x6b0 [ 245.486938] [] ? internal_add_timer+0x360/0x360 [ 245.488647] [] ? lock_torture_cleanup+0x400/0x400 [ 245.494715] [] lock_torture_stats_print+0x7c/0x230 [ 245.496479] [] ? lock_torture_cleanup+0x400/0x400 [ 245.498216] [] lock_torture_stats+0x54/0xd0 [ 245.499858] [] kthread+0x18e/0x1a0 [ 245.501476] [] ? __async_schedule+0x80/0x260 [ 245.503130] [] ? __kthread_unpark+0xd0/0xd0 [ 245.504768] [] ret_from_fork+0x7c/0xb0 [ 245.506328] [] ? __kthread_unpark+0xd0/0xd0 [ 245.508088] Mem-Info: [ 245.509017] Node 0 DMA per-cpu: [ 245.510425] CPU 0: hi: 0, btch: 1 usd: 0 [ 245.511842] CPU 1: hi: 0, btch: 1 usd: 0 [ 245.513255] Node 0 DMA32 per-cpu: [ 245.514545] CPU 0: hi: 90, btch: 15 usd: 14 [ 245.515956] CPU 1: hi: 90, btch: 15 usd: 0 [ 245.517397] active_anon:0 inactive_anon:0 isolated_anon:0 [ 245.517397] active_file:1914 inactive_file:26617 isolated_file:0 [ 245.517397] unevictable:16885 dirty:0 writeback:0 unstable:0 [ 245.517397] free:700 slab_reclaimable:6078 slab_unreclaimable:5341 [ 245.517397] mapped:0 shmem:0 pagetables:0 bounce:0 [ 245.517397] free_cma:0 [ 245.525543] Node 0 DMA free:1004kB min:128kB low:160kB high:192kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:12868kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15992kB managed:15908kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:96kB slab_unreclaimable:700kB kernel_stack:64kB pagetables:0kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:85356 all_unreclaimable? yes [ 245.535985] lowmem_reserve[]: 0 224 224 224 [ 245.538003] Node 0 DMA32 free:1796kB min:1848kB low:2308kB high:2772kB active_anon:0kB inactive_anon:0kB active_file:7656kB inactive_file:93600kB unevictable:67540kB isolated(anon):0kB isolated(file):0kB present:311168kB managed:229488kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:24216kB slab_unreclaimable:20664kB kernel_stack:992kB pagetables:0kB unstable:0kB bounce:0kB free_cma:0kB writeback_tmp:0kB pages_scanned:731484 all_unreclaimable? yes [ 245.560132] lowmem_reserve[]: 0 0 0 0 [ 245.561963] Node 0 DMA: 1*4kB (R) 1*8kB (R) 0*16kB 1*32kB (R) 1*64kB (R) 1*128kB (R) 1*256kB (R) 1*512kB (R) 0*1024kB 0*2048kB 0*4096kB = 1004kB [ 245.567848] Node 0 DMA32: 0*4kB 1*8kB (R) 1*16kB (R) 0*32kB 0*64kB 0*128kB 1*256kB (R) 1*512kB (R) 1*1024kB (R) 0*2048kB 0*4096kB = 1816kB [ 245.583595] 45422 total pagecache pages [ 245.584829] 81790 pages RAM [ 245.585862] 0 pages HighMem/MovableOnly [ 245.587093] 20420 pages reserved [ 245.588207] [ pid ] uid tgid total_vm rss nr_ptes swapents oom_score_adj name [ 245.590672] Kernel panic - not syncing: Out of memory and no killable processes... [ 245.590672] [ 245.593560] CPU: 0 PID: 54 Comm: lock_torture_st Not tainted 3.18.0-rc2-ge754569 #509 [ 245.595889] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014 [ 245.598673] ffffffff83c7e1c8 ffff88000bb2fae8 ffffffff82d77375 ffffffff83aaf958 [ 245.600574] ffffffff83c7e1c8 ffff88000bb2fb68 ffffffff82d70db5 ffff88000bb2fbc8 [ 245.600574] 0000000000000008 ffff88000bb2fb78 ffff88000bb2fb18 ffff8800108ec000 [ 245.600574] Call Trace: [ 245.600574] [] dump_stack+0xc5/0x175 [ 245.600574] [] panic+0x164/0x3e3 [ 245.600574] [] out_of_memory+0x9d1/0x9e0 [ 245.600574] [] __alloc_pages_nodemask+0x1cec/0x1e80 [ 245.600574] [] ? try_to_del_timer_sync+0x98/0xb0 [ 245.600574] [] alloc_pages_current+0x21a/0x2a0 [ 245.600574] [] alloc_kmem_pages+0x1e/0x30 [ 245.600574] [] kmalloc_order+0x20/0x70 [ 245.600574] [] kmalloc_order_trace+0x2c/0x1d0 [ 245.600574] [] __kmalloc+0x92/0x6b0 [ 245.600574] [] ? internal_add_timer+0x360/0x360 [ 245.600574] [] ? lock_torture_cleanup+0x400/0x400 [ 245.600574] [] lock_torture_stats_print+0x7c/0x230 [ 245.600574] [] ? lock_torture_cleanup+0x400/0x400 [ 245.600574] [] lock_torture_stats+0x54/0xd0 [ 245.600574] [] kthread+0x18e/0x1a0 [ 245.600574] [] ? __async_schedule+0x80/0x260 [ 245.600574] [] ? __kthread_unpark+0xd0/0xd0 [ 245.600574] [] ret_from_fork+0x7c/0xb0 [ 245.600574] [] ? __kthread_unpark+0xd0/0xd0 [ 245.600574] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff) Elapsed time: 255 qemu-system-x86_64 -cpu kvm64 -enable-kvm -kernel /kernel/x86_64-randconfig-n0-12101521/e754569101f392e1924d8ce6f087ea4cfad91d69/vmlinuz-3.18.0-rc2-ge754569 -append 'hung_task_panic=1 earlyprintk=ttyS0,115200 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-12101521/linux-devel:devel-lkp-nhm1-smoke-201412101539:e754569101f392e1924d8ce6f087ea4cfad91d69:bisect-linux-0/.vmlinuz-e754569101f392e1924d8ce6f087ea4cfad91d69-20141210172745-8-client1 branch=linux-devel/devel-lkp-nhm1-smoke-201412101539 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-12101521/e754569101f392e1924d8ce6f087ea4cfad91d69/vmlinuz-3.18.0-rc2-ge754569 drbd.minor_count=8' -initrd /kernel-tests/initrd/quantal-core-x86_64.cgz -m 320 -smp 2 -net nic,vlan=1,model=e1000 -net user,vlan=1 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-quantal-client1-11 -serial file:/dev/shm/kboot/serial-quantal-client1-11 -daemonize -display none -monitor null