early console in setup code Probing EDD (edd=off to disable)... ok early console in extract_kernel input_data: 0x0000000004b85255 input_len: 0x0000000000b60250 output: 0x0000000001000000 output_len: 0x000000000356e008 kernel_total_size: 0x0000000004705000 Decompressing Linux... Parsing ELF... done. Booting the kernel. [ 0.000000] Linux version 4.15.0-rc3 (kbuild@roam) (gcc version 6.4.0 20171026 (Debian 6.4.0-9)) #31 PREEMPT Thu Dec 14 06:07:53 CST 2017 [ 0.000000] Command line: ip=::::vm-lkp-hsw01-yocto-x86_64-4::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-4/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-50c4c4e268a2d7a3e58ebb698ac74da0de40ae36-20171214-69423-476gqy-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-r0-12140554 branch=linus/master commit=50c4c4e268a2d7a3e58ebb698ac74da0de40ae36 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r0-12140554/gcc-6/50c4c4e268a2d7a3e58ebb698ac74da0de40ae36/vmlinuz-4.15.0-rc3 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-r0-12140554/gcc-6/50c4c4e268a2d7a3e58ebb698ac74da0de40ae36/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] Centaur CentaurHauls [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 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-0x000000001ffddfff] usable [ 0.000000] BIOS-e820: [mem 0x000000001ffde000-0x000000001fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] bootconsole [earlyser0] enabled [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x1ffde max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] Base memory trampoline at [ (ptrval)] 99000 size 24576 [ 0.000000] BRK [0x056ef000, 0x056effff] PGTABLE [ 0.000000] BRK [0x056f0000, 0x056f0fff] PGTABLE [ 0.000000] BRK [0x056f1000, 0x056f1fff] PGTABLE [ 0.000000] BRK [0x056f2000, 0x056f2fff] PGTABLE [ 0.000000] BRK [0x056f3000, 0x056f3fff] PGTABLE [ 0.000000] BRK [0x056f4000, 0x056f4fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1a206000-0x1ffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000001FFE15CE 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000001FFE142A 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000001FFE0040 0013EA (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000001FFE0000 000040 [ 0.000000] ACPI: APIC 0x000000001FFE151E 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000001FFE1596 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fd000 ( fee00000) [ 0.000000] kvm-clock: cpu 0, msr 0:1ffdc001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 1447678443 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001ffddfff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x000000001ffddfff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffddfff] [ 0.000000] On node 0 totalpages: 130940 [ 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: 1984 pages used for memmap [ 0.000000] DMA32 zone: 126942 pages, LIFO batch:31 [ 0.000000] Reserved but unavailable: 98 pages [ 0.000000] kasan: KernelAddressSanitizer initialized [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 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] mapped IOAPIC to ffffffffff5fc000 (fec00000) [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 368a740 [ 0.000000] e820: [mem 0x20000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns [ 0.000000] random: fast init done [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 128871 [ 0.000000] Kernel command line: ip=::::vm-lkp-hsw01-yocto-x86_64-4::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-4/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-50c4c4e268a2d7a3e58ebb698ac74da0de40ae36-20171214-69423-476gqy-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-r0-12140554 branch=linus/master commit=50c4c4e268a2d7a3e58ebb698ac74da0de40ae36 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r0-12140554/gcc-6/50c4c4e268a2d7a3e58ebb698ac74da0de40ae36/vmlinuz-4.15.0-rc3 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-r0-12140554/gcc-6/50c4c4e268a2d7a3e58ebb698ac74da0de40ae36/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 consol [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.000000] Memory: 270184K/523760K available (30221K kernel code, 7059K rwdata, 7160K rodata, 1396K init, 19972K bss, 253576K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.003333] Preemptible hierarchical RCU implementation. [ 0.003333] RCU event tracing is enabled. [ 0.003333] RCU priority boosting: priority 1 delay 500 ms. [ 0.003333] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.003333] Tasks RCU enabled. [ 0.003333] NR_IRQS: 4352, nr_irqs: 48, preallocated irqs: 16 [ 0.003333] Offload RCU callbacks from CPUs: . [ 0.003333] console [ttyS0] enabled [ 0.003333] bootconsole [earlyser0] disabled [ 0.003333] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.003333] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.003333] ... MAX_LOCK_DEPTH: 48 [ 0.003333] ... MAX_LOCKDEP_KEYS: 8191 [ 0.003333] ... CLASSHASH_SIZE: 4096 [ 0.003333] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.003333] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.003333] ... CHAINHASH_SIZE: 32768 [ 0.003333] memory used by lock dependency info: 7871 kB [ 0.003333] per task-struct memory footprint: 2688 bytes [ 0.003333] ------------------------ [ 0.003333] | Locking API testsuite: [ 0.003333] ---------------------------------------------------------------------------- [ 0.003333] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] A-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.003333] A-B-B-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.003333] A-B-B-C-C-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.003333] A-B-C-A-B-C deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.003333] A-B-B-C-C-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.003333] A-B-C-D-B-D-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.003333] A-B-C-D-B-C-D-A deadlock:failed|failed| ok |failed|failed|failed|failed| [ 0.003333] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] recursive read-lock: | ok | |failed| [ 0.003333] recursive read-lock #2: | ok | |failed| [ 0.003333] mixed read-write-lock: |failed| |failed| [ 0.003333] mixed write-read-lock: |failed| |failed| [ 0.003333] mixed read-lock/lock-write ABBA: |failed| |failed| [ 0.003333] mixed read-lock/lock-read ABBA: | ok | |failed| [ 0.003333] mixed write-lock/lock-write ABBA: |failed| |failed| [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] hard-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.003333] soft-irqs-on + irq-safe-A/12:failed|failed| ok | [ 0.003333] hard-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.003333] soft-irqs-on + irq-safe-A/21:failed|failed| ok | [ 0.003333] sirq-safe-A => hirqs-on/12:failed|failed| ok | [ 0.003333] sirq-safe-A => hirqs-on/21:failed|failed| ok | [ 0.003333] hard-safe-A + irqs-on/12:failed|failed| ok | [ 0.003333] soft-safe-A + irqs-on/12:failed|failed| ok | [ 0.003333] hard-safe-A + irqs-on/21:failed|failed| ok | [ 0.003333] soft-safe-A + irqs-on/21:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #1/123:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #1/132:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #1/213:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #1/231:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #1/312:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #1/321:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #2/123:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #2/132:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #2/213:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #2/231:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #2/312:failed|failed| ok | [ 0.003333] hard-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.003333] soft-safe-A + unsafe-B #2/321:failed|failed| ok | [ 0.003333] hard-irq lock-inversion/123:failed|failed| ok | [ 0.003333] soft-irq lock-inversion/123:failed|failed| ok | [ 0.003333] hard-irq lock-inversion/132:failed|failed| ok | [ 0.003333] soft-irq lock-inversion/132:failed|failed| ok | [ 0.003333] hard-irq lock-inversion/213:failed|failed| ok | [ 0.003333] soft-irq lock-inversion/213:failed|failed| ok | [ 0.003333] hard-irq lock-inversion/231:failed|failed| ok | [ 0.003333] soft-irq lock-inversion/231:failed|failed| ok | [ 0.003333] hard-irq lock-inversion/312:failed|failed| ok | [ 0.003333] soft-irq lock-inversion/312:failed|failed| ok | [ 0.003333] hard-irq lock-inversion/321:failed|failed| ok | [ 0.003333] soft-irq lock-inversion/321:failed|failed| ok | [ 0.003333] hard-irq read-recursion/123: ok | [ 0.003333] soft-irq read-recursion/123: ok | [ 0.003333] hard-irq read-recursion/132: ok | [ 0.003333] soft-irq read-recursion/132: ok | [ 0.003333] hard-irq read-recursion/213: ok | [ 0.003333] soft-irq read-recursion/213: ok | [ 0.003333] hard-irq read-recursion/231: ok | [ 0.003333] soft-irq read-recursion/231: ok | [ 0.003333] hard-irq read-recursion/312: ok | [ 0.003333] soft-irq read-recursion/312: ok | [ 0.003333] hard-irq read-recursion/321: ok | [ 0.003333] soft-irq read-recursion/321: ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] | Wound/wait tests | [ 0.003333] --------------------- [ 0.003333] ww api failures: ok | ok | ok | [ 0.003333] ww contexts mixing:failed| ok | [ 0.003333] finishing ww context: ok | ok | ok | ok | [ 0.003333] locking mismatches: ok | ok | ok | [ 0.003333] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.003333] spinlock nest unlocked: ok | [ 0.003333] ----------------------------------------------------- [ 0.003333] |block | try |context| [ 0.003333] ----------------------------------------------------- [ 0.003333] context:failed| ok | ok | [ 0.003333] try:failed| ok |failed| [ 0.003333] block:failed| ok |failed| [ 0.003333] spinlock:failed| ok |failed| [ 0.003333] -------------------------------------------------------- [ 0.003333] 153 out of 262 testcases failed, as expected. | [ 0.003333] ---------------------------------------------------- [ 0.003333] ACPI: Core revision 20170831 [ 0.003333] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.003333] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.003333] hpet clockevent registered [ 0.003352] APIC: Switch to symmetric I/O mode setup [ 0.003837] x2apic enabled [ 0.004207] Switched APIC routing to physical x2apic. [ 0.004562] enabled ExtINT on CPU#0 [ 0.005321] ENABLING IO-APIC IRQs [ 0.005586] init IO_APIC IRQs [ 0.005804] apic 0 pin 0 not connected [ 0.006114] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:0) [ 0.006708] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0) [ 0.007277] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:0) [ 0.007846] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:0) [ 0.008409] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:0) [ 0.008976] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:0) [ 0.009541] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:0) [ 0.009999] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:0) [ 0.009999] apic 0 pin 16 not connected [ 0.009999] apic 0 pin 17 not connected [ 0.009999] apic 0 pin 18 not connected [ 0.009999] apic 0 pin 19 not connected [ 0.009999] apic 0 pin 20 not connected [ 0.009999] apic 0 pin 21 not connected [ 0.009999] apic 0 pin 22 not connected [ 0.009999] apic 0 pin 23 not connected [ 0.009999] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009999] tsc: Detected 2593.992 MHz processor [ 0.009999] Calibrating delay loop (skipped) preset value.. 5190.15 BogoMIPS (lpj=8646640) [ 0.009999] pid_max: default: 4096 minimum: 301 [ 0.009999] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.009999] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.010822] mce: CPU supports 10 MCE banks [ 0.011167] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.011536] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.011948] CPU: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) [ 0.014351] Performance Events: PMU not available due to virtualization, using software events only. [ 0.017523] Hierarchical SRCU implementation. [ 0.020393] NMI watchdog: Perf event create on CPU 0 failed with -2 [ 0.020836] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.021326] Using local APIC timer interrupts. [ 0.021326] calibrating APIC timer ... [ 0.023333] ... lapic delta = 6253428 [ 0.023333] ... PM-Timer delta = 358144 [ 0.023333] ... PM-Timer result ok [ 0.023333] ..... delta 6253428 [ 0.023333] ..... mult: 268582714 [ 0.023333] ..... calibration result: 3335161 [ 0.023333] ..... CPU clock speed is 2595.2084 MHz. [ 0.023333] ..... host bus clock speed is 1000.2161 MHz. [ 0.024505] devtmpfs: initialized [ 0.035192] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.036630] futex hash table entries: 16 (order: -2, 1536 bytes) [ 0.037007] prandom: seed boundary self test passed [ 0.038744] prandom: 100 self tests passed [ 0.039030] pinctrl core: initialized pinctrl subsystem [ 0.040600] regulator-dummy: no parameters [ 0.042211] NET: Registered protocol family 16 [ 0.043784] audit: initializing netlink subsys (disabled) [ 0.045148] workqueue: round-robin CPU selection forced, expect performance impact [ 0.045767] audit: type=2000 audit(1513241517.210:1): state=initialized audit_enabled=0 res=1 [ 0.047132] ACPI: bus type PCI registered [ 0.047579] dca service started, version 1.12.1 [ 0.047972] PCI: Using configuration type 1 for base access [ 0.064860] ACPI: Added _OSI(Module Device) [ 0.065191] ACPI: Added _OSI(Processor Device) [ 0.065501] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.065829] ACPI: Added _OSI(Processor Aggregator Device) [ 0.079377] ACPI: Interpreter enabled [ 0.079783] ACPI: (supports S0 S3 S5) [ 0.080027] ACPI: Using IOAPIC for interrupt routing [ 0.080506] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.082929] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.141232] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.141719] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.142198] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.147698] PCI host bridge to bus 0000:00 [ 0.148026] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.148503] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.148975] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.149500] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window] [ 0.150017] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.150456] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.152978] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.156165] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.160016] pci 0000:00:01.1: reg 0x20: [io 0xc080-0xc08f] [ 0.161960] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.162454] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.162908] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.163342] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.165809] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.166862] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.167976] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.170442] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.172609] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 0.175515] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 0.182213] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.185110] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.186679] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.188619] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.195045] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.197714] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.200014] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 0.203343] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 0.208193] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] [ 0.212163] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000 [ 0.213967] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f] [ 0.219684] pci_bus 0000:00: on NUMA node 0 [ 0.225533] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.227358] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.229085] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.230861] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.231840] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.235360] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.235794] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.236388] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.236673] vgaarb: loaded [ 0.238125] pps_core: LinuxPPS API ver. 1 registered [ 0.238485] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.239151] PTP clock support registered [ 0.239851] PCI: Using ACPI for IRQ routing [ 0.240009] PCI: pci_cache_line_size set to 64 bytes [ 0.240461] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.240892] e820: reserve RAM buffer [mem 0x1ffde000-0x1fffffff] [ 0.242962] clocksource: Switched to clocksource kvm-clock [ 0.243333] VFS: Disk quotas dquot_6.6.0 [ 0.243333] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.243333] FS-Cache: Loaded [ 0.243333] CacheFiles: Loaded [ 0.243333] pnp: PnP ACPI init [ 0.243388] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.244298] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.245111] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.245697] pnp 00:03: [dma 2] [ 0.246060] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.247154] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.248205] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.249208] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.252703] pnp: PnP ACPI: found 7 devices [ 0.259050] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.260076] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.260533] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.260968] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.261440] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window] [ 0.262599] NET: Registered protocol family 2 [ 0.263689] TCP established hash table entries: 4096 (order: 3, 32768 bytes) [ 0.264303] TCP bind hash table entries: 4096 (order: 6, 327680 bytes) [ 0.264949] TCP: Hash tables configured (established 4096 bind 4096) [ 0.265508] UDP hash table entries: 256 (order: 3, 49152 bytes) [ 0.265968] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes) [ 0.266656] NET: Registered protocol family 1 [ 0.267036] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.267465] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.267888] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.268356] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 0.268965] PCI: CLS 0 bytes, default 64 [ 0.269587] Unpacking initramfs... [ 2.647951] debug: unmapping init [mem 0xffff88001a206000-0xffff88001ffcffff] [ 2.654549] Scanning for low memory corruption every 60 seconds [ 2.656977] AVX or AES-NI instructions are not detected. [ 2.657364] CPU feature 'AVX registers' is not supported. [ 2.657735] CPU feature 'AVX registers' is not supported. [ 2.658560] rcu-torture:--- Start of test: nreaders=1 nfakewriters=4 stat_interval=60 verbose=1 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/1 test_boost_interval=7 test_boost_duration=4 shutdown_secs=0 stall_cpu=0 stall_cpu_holdoff=10 stall_cpu_irqsoff=0 n_barrier_cbs=0 onoff_interval=0 onoff_holdoff=0 [ 2.660811] rcu-torture: Creating rcu_torture_writer task [ 2.661340] rcu-torture: Creating rcu_torture_fakewriter task [ 2.661784] rcu-torture: rcu_torture_writer task started [ 2.661786] rcu-torture: GP expediting controlled from boot/sysfs for rcu, [ 2.661788] rcu-torture: Disabled dynamic grace-period expediting. [ 2.663127] rcu-torture: Creating rcu_torture_fakewriter task [ 2.663608] rcu-torture: rcu_torture_fakewriter task started [ 2.664038] rcu-torture: Creating rcu_torture_fakewriter task [ 2.664484] rcu-torture: rcu_torture_fakewriter task started [ 2.665069] rcu-torture: Creating rcu_torture_fakewriter task [ 2.665528] rcu-torture: rcu_torture_fakewriter task started [ 2.665962] rcu-torture: Creating rcu_torture_reader task [ 2.666380] rcu-torture: rcu_torture_fakewriter task started [ 2.666804] rcu-torture: Creating rcu_torture_stats task [ 2.667227] rcu-torture: rcu_torture_reader task started [ 2.667660] rcu-torture: Creating torture_shuffle task [ 2.668857] rcu-torture: rcu_torture_stats task started [ 2.669251] rcu-torture: Creating torture_stutter task [ 2.669655] rcu-torture: torture_shuffle task started [ 2.670052] rcu-torture: Creating rcu_torture_boost task [ 2.670468] rcu-torture: torture_stutter task started [ 2.670852] rcu-torture: Creating rcu_torture_cbflood task [ 2.671291] rcu-torture: rcu_torture_boost started [ 2.673898] Initialise system trusted keyrings [ 2.674427] Key type blacklist registered [ 2.675217] rcu-torture: rcu_torture_cbflood task started [ 2.675705] workingset: timestamp_bits=62 max_order=17 bucket_order=0 [ 2.693829] ntfs: driver 2.1.32 [Flags: R/O DEBUG]. [ 2.695647] JFS: nTxBlock = 2110, nTxLock = 16886 [ 2.698043] SGI XFS with ACLs, security attributes, realtime, no debug enabled [ 2.702207] 9p: Installing v9fs 9p2000 file system support [ 2.702789] FS-Cache: Netfs '9p' registered for caching [ 2.704175] FS-Cache: Netfs 'ceph' registered for caching [ 2.704573] ceph: loaded (mds proto 32) [ 2.710830] NET: Registered protocol family 38 [ 2.711329] Key type asymmetric registered [ 2.711674] Asymmetric key parser 'x509' registered [ 2.712257] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 2.712791] io scheduler noop registered (default) [ 2.713128] io scheduler mq-deadline registered [ 2.713446] io scheduler kyber registered [ 2.717263] test_hexdump: all 1184 tests passed [ 2.717622] [ 2.717622] Start testing find_bit() with random-filled bitmap [ 2.721104] find_next_bit: 6601314 cycles, 163808 iterations [ 2.724152] find_next_zero_bit: 6731896 cycles, 163873 iterations [ 2.727375] find_last_bit: 7211366 cycles, 163809 iterations [ 3.682037] tsc: Refined TSC clocksource calibration: 2593.984 MHz [ 3.682490] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x256408f279e, max_idle_ns: 440795272493 ns [ 4.770921] find_first_bit: 5299789985 cycles, 163809 iterations [ 4.773145] [ 4.773145] Start testing find_bit() with sparse bitmap [ 4.773674] find_next_bit: 101147 cycles, 655 iterations [ 4.779829] find_next_zero_bit: 14973908 cycles, 327026 iterations [ 4.780338] find_last_bit: 90949 cycles, 655 iterations [ 4.789319] find_first_bit: 22273463 cycles, 655 iterations [ 4.793374] Running rhashtable test nelem=8, max_size=0, shrinking=0 [ 4.793855] Test 00: [ 4.794156] Adding 50000 keys [ 4.897901] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 4.934712] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 4.935307] Deleting 50000 keys [ 4.980699] Duration of test: 186212285 ns [ 4.981105] Test 01: [ 4.981446] Adding 50000 keys [ 5.088860] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 5.124522] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 5.125138] Deleting 50000 keys [ 5.170051] Duration of test: 188376184 ns [ 5.170469] Test 02: [ 5.170805] Adding 50000 keys [ 5.261980] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 5.296297] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 5.296907] Deleting 50000 keys [ 5.341788] Duration of test: 170753992 ns [ 5.342202] Test 03: [ 5.342533] Adding 50000 keys [ 5.432000] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 5.480562] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 5.481174] Deleting 50000 keys [ 5.527622] Duration of test: 184856662 ns [ 5.542443] test if its possible to exceed max_size 8192: no, ok [ 5.543211] Average test time: 182549780 [ 5.543491] Testing concurrent rhashtable access from 10 threads [ 11.331966] test 3125 add/delete pairs into rhlist [ 11.415111] test 3125 random rhlist add/delete operations [ 11.453140] Started 10 threads, 0 failed, rhltable test returns 0 [ 11.454186] test_printf: all 240 tests passed [ 11.456171] crc32: CRC_LE_BITS = 8, CRC_BE BITS = 8 [ 11.456596] crc32: self tests passed, processed 225944 bytes in 816933 nsec [ 11.457899] crc32c: CRC_LE_BITS = 8 [ 11.458149] crc32c: self tests passed, processed 225944 bytes in 410329 nsec [ 11.513008] crc32_combine: 8373 self tests passed [ 11.567697] crc32c_combine: 8373 self tests passed [ 11.571116] ioatdma: Intel(R) QuickData Technology Driver 4.00 [ 11.638297] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 11.661992] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 11.686195] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 11.689098] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $ [ 11.689606] ac.o: No PCI boards found. [ 11.689912] ac.o: For an ISA board you must supply memory and irq parameters. [ 11.690555] Non-volatile memory driver v1.3 [ 11.692302] lkdtm: No crash points registered, enable through debugfs [ 11.692929] Phantom Linux Driver, version n0.9.8, init OK [ 11.694962] Uniform Multi-Platform E-IDE driver [ 11.695412] piix 0000:00:01.1: IDE controller (0x8086:0x7010 rev 0x00) [ 11.696153] piix 0000:00:01.1: not 100% native mode: will probe irqs later [ 11.697291] ide0: BM-DMA at 0xc080-0xc087 [ 11.697626] ide1: BM-DMA at 0xc088-0xc08f [ 11.697959] Probing IDE interface ide0... [ 12.273662] Probing IDE interface ide1... [ 13.017157] hdc: QEMU DVD-ROM, ATAPI CD/DVD-ROM drive [ 13.697858] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO0 [ 13.698694] hdc: MWDMA2 mode selected [ 13.699355] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [ 13.699980] ide1 at 0x170-0x177,0x376 on irq 15 [ 13.702344] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports [ 13.705186] MACsec IEEE 802.1AE [ 13.708976] mdio_bus fixed-0: GPIO lookup for consumer reset [ 13.709638] mdio_bus fixed-0: using lookup tables for GPIO lookup [ 13.710343] mdio_bus fixed-0: lookup for GPIO reset failed [ 13.711051] libphy: Fixed MDIO Bus: probed [ 13.712627] tun: Universal TUN/TAP device driver, 1.6 [ 13.713661] vxcan: Virtual CAN Tunnel driver [ 13.714476] Atheros(R) L2 Ethernet Driver - version 2.2.3 [ 13.715092] Copyright (c) 2007 Atheros Corporation. [ 13.716105] bnx2x: QLogic 5771x/578xx 10/20-Gigabit Ethernet Driver bnx2x 1.712.30-0 (2014/02/10) [ 13.717762] enic: Cisco VIC Ethernet NIC Driver, ver 2.3.0.45 [ 13.718622] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI [ 13.719300] e100: Copyright(c) 1999-2006 Intel Corporation [ 13.720056] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 13.720928] igb: Copyright (c) 2007-2014 Intel Corporation. [ 13.721686] sky2: driver version 1.30 [ 13.723237] tlan: ThunderLAN driver v1.17 [ 13.723826] tlan: 0 devices installed, PCI: 0 EISA: 0 [ 13.725045] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 13.727224] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 13.728249] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 13.730833] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 13.732416] rtc_cmos 00:00: RTC can wake from S4 [ 13.733264] rtc rtc0: alarm rollover: day [ 13.734026] rtc rtc0: rtc_cmos: dev (0:0) [ 13.734528] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 [ 13.735488] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs [ 13.739460] pps pps0: new PPS source ktimer [ 13.739972] pps pps0: ktimer PPS source registered [ 13.742374] f71882fg: Not a Fintek device [ 13.742904] f71882fg: Not a Fintek device [ 13.745230] pc87360: PC8736x not detected, module not inserted [ 13.748122] NET: Registered protocol family 23 [ 13.749569] intel_telemetry_core Init [ 13.752663] NET: Registered protocol family 26 [ 13.753367] can: controller area network core (rev 20170425 abi 9) [ 13.754486] NET: Registered protocol family 29 [ 13.755566] NET: Registered protocol family 33 [ 13.756079] Key type rxrpc registered [ 13.756500] Key type rxrpc_s registered [ 13.757012] 9pnet: Installing 9P2000 support [ 13.757797] Key type dns_resolver registered [ 13.758317] Key type ceph registered [ 13.759609] libceph: loaded (mon/osd proto 15/24) [ 13.761907] ... APIC ID: 00000000 (0) [ 13.762375] ... APIC VERSION: 01050014 [ 13.762814] 0000000000000000000000000000000000000000000000000000000000000000 [ 13.763625] 0000000000000000000000000000000000000000000000000000000000000000 [ 13.764128] 0000000000000000000000000000000000000000000000000000000000004000 [ 13.765278] number of MP IRQ sources: 15. [ 13.765733] number of IO-APIC #0 registers: 24. [ 13.766246] testing the IO APIC....................... [ 13.766873] IO APIC #0...... [ 13.767206] .... register #00: 00000000 [ 13.767677] ....... : physical APIC id: 00 [ 13.768179] ....... : Delivery Type: 0 [ 13.768619] ....... : LTS : 0 [ 13.769076] .... register #01: 00170011 [ 13.769514] ....... : max redirection entries: 17 [ 13.770069] ....... : PRQ implemented: 0 [ 13.770535] ....... : IO APIC version: 11 [ 13.771043] .... register #02: 00000000 [ 13.771484] ....... : arbitration: 00 [ 13.771934] .... IRQ redirection table: [ 13.772361] IOAPIC 0: [ 13.772640] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.773504] pin01, enabled , edge , high, V(24), IRR(0), S(0), physical, D(00), M(0) [ 13.774404] pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0) [ 13.775271] pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.776130] pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.777007] pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.777912] pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.778785] pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.779655] pin08, enabled , edge , high, V(25), IRR(0), S(0), physical, D(00), M(0) [ 13.780518] pin09, enabled , level, high, V(20), IRR(0), S(0), physical, D(00), M(0) [ 13.781422] pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.782296] pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.783175] pin0c, enabled , edge , high, V(23), IRR(0), S(0), physical, D(00), M(0) [ 13.784039] pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.784943] pin0e, enabled , edge , high, V(21), IRR(0), S(0), physical, D(00), M(0) [ 13.785818] pin0f, enabled , edge , high, V(22), IRR(0), S(0), physical, D(00), M(0) [ 13.786689] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.787563] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.788443] pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.789307] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.790190] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.791078] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.791942] pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.792822] pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 13.793669] IRQ to pin mappings: [ 13.794053] IRQ0 -> 0:2 [ 13.794369] IRQ1 -> 0:1 [ 13.794665] IRQ3 -> 0:3 [ 13.794967] IRQ4 -> 0:4 [ 13.795253] IRQ5 -> 0:5 [ 13.795546] IRQ6 -> 0:6 [ 13.795845] IRQ7 -> 0:7 [ 13.796126] IRQ8 -> 0:8 [ 13.796409] IRQ9 -> 0:9 [ 13.796696] IRQ10 -> 0:10 [ 13.797012] IRQ11 -> 0:11 [ 13.797323] IRQ12 -> 0:12 [ 13.797657] IRQ13 -> 0:13 [ 13.797984] IRQ14 -> 0:14 [ 13.798301] IRQ15 -> 0:15 [ 13.798605] .................................... done. [ 13.799231] sched_clock: Marking stable (13797643591, 0)->(14461085135, -663441544) [ 13.801245] Loading compiled-in X.509 certificates [ 13.801884] page_owner is disabled [ 13.802299] kAFS: Red Hat AFS client v0.1 registering. [ 13.811005] kAFS: failed to register: -97 [ 13.813310] console [netcon0] enabled [ 13.813753] netconsole: network logging started [ 13.814394] rtc_cmos 00:00: setting system clock to 2017-12-14 16:52:10 UTC (1513270330) [ 13.815603] BIOS EDD facility v0.16 2004-Jun-25, 1 devices found [ 13.818389] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 13.827442] ### dt-test ### start of unittest - you will see error messages [ 13.828878] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 13.830264] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 13.831638] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 13.832469] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 13.833293] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 13.834212] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 13.839959] ### dt-test ### end of unittest - 168 passed, 0 failed [ 26.022544] debug: unmapping init [mem 0xffffffff84211000-0xffffffff8436dfff] [ 26.023446] Write protecting the kernel read-only data: 38912k [ 26.026439] debug: unmapping init [mem 0xffff880002d88000-0xffff880002dfffff] [ 26.027188] debug: unmapping init [mem 0xffff8800034fe000-0xffff8800035fffff] [ 26.048654] x86/mm: Checked W+X mappings: passed, no W+X pages found. /etc/rcS.d/S00fbsetup: line 3: /sbin/modprobe: not found Please wait: booting... Starting udev [ 26.127654] udevd[157]: starting version 3.1.5 LKP: HOSTNAME vm-lkp-hsw01-yocto-x86_64-4, MAC 5a:86:b1:75:cc:78, kernel 4.15.0-rc3 31, serial console /dev/ttyS0 Poky (Yocto Project Reference Distro) 2.1 vm-lkp-hsw01-yocto-x86_64-4 /dev/ttyS0 [ 28.412181] LKP: waiting for network... [ 45.424806] /lkp/lkp/src/bin/run-lkp [ 45.424806] RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-r0-12140554/gcc-6/50c4c4e268a2d7a3e58ebb698ac74da0de40ae36/0 [ 45.424806] job=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-4/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-50c4c4e268a2d7a3e58ebb698ac74da0de40ae36-20171214-69423-476gqy-0.yaml [ 45.424806] run-job /lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-4/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-50c4c4e268a2d7a3e58ebb698ac74da0de40ae36-20171214-69423-476gqy-0.yaml [ 45.424806] skip http request: cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-hsw01-yocto-x86_64-4/trinity-300s-yocto-minimal-x86_64-2016-04-22.cgz-50c4c4e268a2d7a3e58ebb698ac74da0de40ae36-20171214-69423-476gqy-0.yaml&job_state=running -O /dev/null [ 56.840272] trinity-main uses obsolete (PF_INET,SOCK_PACKET) [ 56.863802] can: request_module (can-proto-1) failed. [ 56.864977] can: request_module (can-proto-2) failed. [ 56.874130] can: request_module (can-proto-1) failed. [ 56.875688] can: request_module (can-proto-1) failed. [ 56.876844] can: request_module (can-proto-1) failed. [ 56.879072] can: request_module (can-proto-1) failed. [ 56.880057] can: request_module (can-proto-1) failed. [ 56.881533] can: request_module (can-proto-2) failed. [ 56.882542] can: request_module (can-proto-2) failed. [ 56.883616] can: request_module (can-proto-2) failed. [ 62.847610] rcu-torture: rtc: 0000000039a2a53a ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 2 barrier: 0/0:0 cbflood: 1 [ 62.849709] rcu-torture: Reader Pipe: 3 0 0 0 0 0 0 0 0 0 0 [ 62.850136] rcu-torture: Reader Batch: 3 0 0 0 0 0 0 0 0 0 0 [ 62.850561] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 [ 124.661324] rcu-torture: rtc: 0000000039a2a53a ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 2 barrier: 0/0:0 cbflood: 1 [ 124.662981] rcu-torture: Reader Pipe: 3 0 0 0 0 0 0 0 0 0 0 [ 124.663412] rcu-torture: Reader Batch: 3 0 0 0 0 0 0 0 0 0 0 [ 124.663845] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 [ 124.664342] ??? Writer stall state RTWS_STUTTER(8) g21137 c21136 f0x0 ->state 0x0 cpu 0 [ 124.674909] rcu_torture_wri R running task 0 45 2 0x80080000 [ 124.675458] Call Trace: [ 124.675650] ? __schedule+0x533/0xc50 [ 124.675924] ? __sched_text_start+0x8/0x8 [ 124.676214] ? __sched_text_start+0x8/0x8 [ 124.676505] preempt_schedule_irq+0xf7/0x210 [ 124.676819] retint_kernel+0x1a/0x1c [ 124.677084] RIP: 0010:___might_sleep+0xe/0x3e0 [ 124.677404] RSP: 0000:ffff88000caffe18 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff11 [ 124.708014] RAX: ffff88000ae471a0 RBX: ffff88001ffdc01c RCX: ffffffffffffff11 [ 124.708566] RDX: ffffffff8117ea29 RSI: 0000000000000010 RDI: 0000000000000246 [ 124.709077] RBP: 1ffffffff06d6acb R08: 0000000000000247 R09: 0000000000000000 [ 124.709575] R10: ffff88000ae471a0 R11: dffffc0000000000 R12: 0000000000092c4f [ 124.710078] R13: 1ffffffff06d6aca R14: 0000000000000000 R15: ffff88000caffe18 [ 124.710591] ? ___might_sleep+0x39/0x3e0 [ 124.710881] ? stutter_wait+0x14f/0x1e0 [ 124.711174] ? rcu_torture_writer+0x392/0xc20 [ 124.737308] ? lock_downgrade+0x6a0/0x6a0 [ 124.737650] ? rcu_torture_pipe_update+0x390/0x390 [ 124.738800] ? __kthread_parkme+0x164/0x230 [ 124.739106] ? rcu_torture_pipe_update+0x390/0x390 [ 124.739442] ? rcu_torture_pipe_update+0x390/0x390 [ 124.739785] ? kthread+0x2fd/0x400 [ 124.740030] ? kthread_create_on_node+0xf0/0xf0 [ 124.740351] ? kthread_create_on_node+0xf0/0xf0 [ 124.740672] ? ret_from_fork+0x1f/0x30 [ 124.740949] rcu_preempt: wait state: 3 ->state: 0x402 [ 124.762965] rcu_sched: wait state: 1 ->state: 0x402 [ 124.763365] rcu_bh: wait state: 1 ->state: 0x402 [ 166.090516] ================================================================== [ 166.092419] BUG: KASAN: use-after-free in fib_table_flush+0x76c/0x870 [ 166.092907] Read of size 8 at addr ffff880012fc0b18 by task kworker/u2:3/173 [ 166.093402] [ 166.093528] CPU: 0 PID: 173 Comm: kworker/u2:3 Not tainted 4.15.0-rc3 #31 [ 166.094018] Workqueue: netns cleanup_net [ 166.094298] Call Trace: [ 166.094489] print_address_description+0xa6/0x370 [ 166.094867] ? fib_table_flush+0x76c/0x870 [ 166.095159] kasan_report+0x226/0x330 [ 166.095420] fib_table_flush+0x76c/0x870 [ 166.095698] ? fib_table_flush_external+0x5a0/0x5a0 [ 166.096067] ? ip_fib_net_exit+0x94/0x360 [ 166.096350] ip_fib_net_exit+0x228/0x360 [ 166.096629] ? ip_fib_net_exit+0x360/0x360 [ 166.096930] ops_exit_list+0xa8/0x160 [ 166.097233] cleanup_net+0x414/0x860 [ 166.097487] ? net_drop_ns+0x80/0x80 [ 166.097748] ? kvm_sched_clock_read+0x5/0x10 [ 166.098051] ? native_sched_clock_from_tsc+0x40/0x70 [ 166.098399] ? sched_clock_cpu+0xf/0x70 [ 166.098672] ? __lock_acquire+0x3b2/0x1fc0 [ 166.099054] ? lock_downgrade+0x6a0/0x6a0 [ 166.099337] ? lock_acquire+0x117/0x260 [ 166.099609] ? process_one_work+0x70f/0x11c0 [ 166.099938] process_one_work+0x791/0x11c0 [ 166.100229] ? kvm_sched_clock_read+0x5/0x10 [ 166.100532] ? sched_clock+0x2d/0x40 [ 166.100792] ? cancel_delayed_work_sync+0x20/0x20 [ 166.101123] worker_thread+0xe8/0x1070 [ 166.101392] ? __kthread_parkme+0x164/0x230 [ 166.101689] ? process_one_work+0x11c0/0x11c0 [ 166.102006] kthread+0x2fd/0x400 [ 166.102240] ? kthread_create_on_node+0xf0/0xf0 [ 166.102561] ret_from_fork+0x1f/0x30 [ 166.102855] [ 166.102972] Allocated by task 1907: [ 166.103235] __kmalloc+0xf6/0x1a0 [ 166.103475] fib_trie_table+0xe8/0x240 [ 166.103748] fib_net_init+0x1bc/0x570 [ 166.104032] ops_init+0x1c0/0x360 [ 166.104269] setup_net+0x23c/0x530 [ 166.104512] copy_net_ns+0x170/0x350 [ 166.104779] create_new_namespaces+0x343/0x730 [ 166.105091] unshare_nsproxy_namespaces+0xa1/0x150 [ 166.105427] SyS_unshare+0x338/0x6c0 [ 166.105682] do_syscall_64+0x21f/0xb80 [ 166.105954] return_from_SYSCALL_64+0x0/0x65 [ 166.106253] [ 166.106367] Freed by task 11: [ 166.106581] kfree+0x102/0x1d0 [ 166.106838] rcu_do_batch+0x331/0x7f0 [ 166.107102] rcu_cpu_kthread+0x12a/0x160 [ 166.107381] smpboot_thread_fn+0x3c1/0x820 [ 166.107669] kthread+0x2fd/0x400 [ 166.107928] ret_from_fork+0x1f/0x30 [ 166.108181] [ 166.108295] The buggy address belongs to the object at ffff880012fc0ae0 [ 166.108295] which belongs to the cache kmalloc-64 of size 64 [ 166.109179] The buggy address is located 56 bytes inside of [ 166.109179] 64-byte region [ffff880012fc0ae0, ffff880012fc0b20) [ 166.109972] The buggy address belongs to the page: [ 166.110310] page:00000000674cca39 count:1 mapcount:0 mapping: (null) index:0x0 [ 166.110910] flags: 0x480000000100(slab) [ 166.111184] raw: 0000480000000100 0000000000000000 0000000000000000 00000001002a002a [ 166.111715] raw: dead000000000100 dead000000000200 ffff880014801780 0000000000000000 [ 166.112274] page dumped because: kasan: bad access detected [ 166.112660] [ 166.112776] Memory state around the buggy address: [ 166.113121] ffff880012fc0a00: fc fc fc fc fb fb fb fb fb fb fb fb fc fc fc fc [ 166.113619] ffff880012fc0a80: 00 00 00 00 00 00 00 00 fc fc fc fc fb fb fb fb [ 166.114132] >ffff880012fc0b00: fb fb fb fb fc fc fc fc fb fb fb fb fb fb fb fb [ 166.114632] ^ [ 166.114950] ffff880012fc0b80: fc fc fc fc fb fb fb fb fb fb fb fb fc fc fc fc [ 166.115446] ffff880012fc0c00: fb fb fb fb fb fb fb fb fc fc fc fc fb fb fb fb [ 166.115965] ================================================================== [ 166.116460] Disabling lock debugging due to kernel taint Elapsed time: 170 #!/bin/bash # To reproduce, # 1) save job-script and this script (both are attached in 0day report email) # 2) run this script with your compiled kernel and optional env $INSTALL_MOD_PATH kernel=$1 initrds=( /osimage/yocto/yocto-minimal-x86_64-2016-04-22.cgz /lkp/lkp/lkp-x86_64.cgz /osimage/pkg/debian-x86_64-2016-08-31.cgz/trinity-static-x86_64-x86_64-6ddabfd2_2017-11-10.cgz ) HTTP_PREFIX=https://github.com/0day-ci/lkp-qemu/raw/master wget --timestamping "${initrds[@]/#/$HTTP_PREFIX}" { cat "${initrds[@]//*\//}" [[ $INSTALL_MOD_PATH ]] && ( cd "$INSTALL_MOD_PATH" find lib | cpio -o -H newc --quiet | gzip ) echo job-script | cpio -o -H newc --quiet | gzip } > initrd.img qemu-img create -f qcow2 disk-vm-lkp-hsw01-yocto-x86_64-4-0 256G kvm=( qemu-system-x86_64 -enable-kvm -kernel $kernel -initrd initrd.img -m 512 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -drive file=disk-vm-lkp-hsw01-yocto-x86_64-4-0,media=disk,if=virtio -serial stdio -display none -monitor null ) append=( ip=::::vm-lkp-hsw01-yocto-x86_64-4::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=x86_64 kconfig=x86_64-randconfig-r0-12140554 branch=linus/master commit=50c4c4e268a2d7a3e58ebb698ac74da0de40ae36 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-r0-12140554/gcc-6/50c4c4e268a2d7a3e58ebb698ac74da0de40ae36/vmlinuz-4.15.0-rc3 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-x86_64/yocto-minimal-x86_64-2016-04-22.cgz/x86_64-randconfig-r0-12140554/gcc-6/50c4c4e268a2d7a3e58ebb698ac74da0de40ae36/0 result_service=tmpfs debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw drbd.minor_count=8 ) "${kvm[@]}" -append "${append[*]}"