early console in setup code Probing EDD (edd=off to disable)... ok early console in extract_kernel input_data: 0x0000000003b60276 input_len: 0x0000000000c3632e output: 0x0000000001000000 output_len: 0x0000000002acd958 kernel_total_size: 0x0000000003ba3000 Decompressing Linux... Parsing ELF... done. Booting the kernel. [ 0.000000] Linux version 4.14.0-12872-g020aae3 (kbuild@xian) (gcc version 6.4.0 20171026 (Debian 6.4.0-9)) #1 SMP PREEMPT Mon Nov 20 16:18:11 CST 2017 [ 0.000000] Command line: ip=::::vm-vp-quantal-x86_64-7::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-7/boot-1-quantal-core-x86_64.cgz-020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb-20171120-48765-11sj5aa-2.yaml ARCH=x86_64 kconfig=x86_64-randconfig-u0-11010248 branch=linus/master commit=020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb BOOT_IMAGE=/pkg/linux/x86_64-randconfig-u0-11010248/gcc-6/020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb/vmlinuz-4.14.0-12872-g020aae3 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-u0-11010248/gcc-6/020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb/2 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,115200 vga=normal rw drbd.minor_count=8 [ 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-0x000000001ffdffff] usable [ 0.000000] BIOS-e820: [mem 0x000000001ffe0000-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 = 0x1ffe0 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 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 0x000f6a80-0x000f6a8f] mapped at [fffffffffd200a80] [ 0.000000] mpc: f6a90-f6b74 [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] BRK [0x04b8d000, 0x04b8dfff] PGTABLE [ 0.000000] BRK [0x04b8e000, 0x04b8efff] PGTABLE [ 0.000000] BRK [0x04b8f000, 0x04b8ffff] PGTABLE [ 0.000000] BRK [0x04b90000, 0x04b90fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1e7a9000-0x1ffdffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000001FFE1628 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000001FFE147C 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000001FFE0040 00143C (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000001FFE0000 000040 [ 0.000000] ACPI: APIC 0x000000001FFE1570 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000001FFE15F0 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fd000 ( fee00000) [ 0.000000] cma: dma_contiguous_reserve(limit 1ffe0000) [ 0.000000] kvm-clock: cpu 0, msr 0:1e6a8001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 6474701740 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-0x000000001ffdffff] [ 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-0x000000001ffdffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001ffdffff] [ 0.000000] On node 0 totalpages: 130942 [ 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: 126944 pages, LIFO batch:31 [ 0.000000] Reserved but unavailable: 98 pages [ 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] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000) [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 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] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] percpu: Embedded 484 pages/cpu @ffff88001d200000 s1949824 r0 d32640 u2097152 [ 0.000000] pcpu-alloc: s1949824 r0 d32640 u2097152 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1d20ca00 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 128873 [ 0.000000] Kernel command line: ip=::::vm-vp-quantal-x86_64-7::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-7/boot-1-quantal-core-x86_64.cgz-020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb-20171120-48765-11sj5aa-2.yaml ARCH=x86_64 kconfig=x86_64-randconfig-u0-11010248 branch=linus/master commit=020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb BOOT_IMAGE=/pkg/linux/x86_64-randconfig-u0-11010248/gcc-6/020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb/vmlinuz-4.14.0-12872-g020aae3 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-u0-11010248/gcc-6/020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb/2 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,115200 vga=normal rw drbd.minor_ [ 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: 423860K/523768K available (16822K kernel code, 4477K rwdata, 6516K rodata, 3068K init, 21248K bss, 99908K reserved, 0K cma-reserved) [ 0.003333] Preemptible hierarchical RCU implementation. [ 0.003333] RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2. [ 0.003333] RCU priority boosting: priority 1 delay 500 ms. [ 0.003333] RCU debug extended QS entry/exit. [ 0.003333] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.003333] Tasks RCU enabled. [ 0.003333] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.003333] NR_IRQS: 4352, nr_irqs: 440, 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] ODEBUG: selftest passed [ 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.003357] APIC: Switch to symmetric I/O mode setup [ 0.004313] enabled ExtINT on CPU#0 [ 0.007202] ENABLING IO-APIC IRQs [ 0.007843] init IO_APIC IRQs [ 0.008376] apic 0 pin 0 not connected [ 0.009100] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.010042] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.011530] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.013333] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.013333] apic 0 pin 16 not connected [ 0.013333] apic 0 pin 17 not connected [ 0.013333] apic 0 pin 18 not connected [ 0.013333] apic 0 pin 19 not connected [ 0.013333] apic 0 pin 20 not connected [ 0.013333] apic 0 pin 21 not connected [ 0.013333] apic 0 pin 22 not connected [ 0.013333] apic 0 pin 23 not connected [ 0.013333] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.013333] tsc: Detected 2693.508 MHz processor [ 0.013344] Calibrating delay loop (skipped) preset value.. 5389.47 BogoMIPS (lpj=8978360) [ 0.015370] pid_max: default: 32768 minimum: 301 [ 0.016767] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.018766] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.020626] CPU: Physical Processor ID: 0 [ 0.021729] mce: CPU supports 10 MCE banks [ 0.023396] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.026677] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.028501] Freeing SMP alternatives memory: 40K [ 0.030843] smpboot: Max logical packages: 2 [ 0.033356] Using local APIC timer interrupts. [ 0.033356] calibrating APIC timer ... [ 0.039999] ... lapic delta = 15830956 [ 0.039999] ... PM-Timer delta = 906672 [ 0.039999] APIC calibration not consistent with PM-Timer: 253ms instead of 100ms [ 0.039999] APIC delta adjusted to PM-Timer: 6250059 (15830956) [ 0.039999] TSC delta adjusted to PM-Timer: 269349591 (682243341) [ 0.039999] ..... delta 6250059 [ 0.039999] ..... mult: 268438016 [ 0.039999] ..... calibration result: 3333364 [ 0.039999] ..... CPU clock speed is 2693.2550 MHz. [ 0.039999] ..... host bus clock speed is 1000.0364 MHz. [ 0.043267] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) [ 0.053370] Performance Events: PMU not available due to virtualization, using software events only. [ 0.070027] Hierarchical SRCU implementation. [ 0.083449] NMI watchdog: Perf event create on CPU 0 failed with -2 [ 0.084888] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.096683] smp: Bringing up secondary CPUs ... [ 0.126731] x86: Booting SMP configuration: [ 0.127445] .... node #0, CPUs: #1 [ 0.003333] kvm-clock: cpu 1, msr 0:1e6a8081, secondary cpu clock [ 0.003333] masked ExtINT on CPU#1 [ 0.143435] KVM setup async PF for cpu 1 [ 0.144042] kvm-stealtime: cpu 1, msr 1d40ca00 [ 0.146679] smp: Brought up 1 node, 2 CPUs [ 0.147420] ---------------- [ 0.147932] | NMI testsuite: [ 0.148441] -------------------- [ 0.149017] remote IPI: ok | [ 0.150030] local IPI: ok | [ 0.150597] -------------------- [ 0.151090] Good, all 2 testcases passed! | [ 0.151761] --------------------------------- [ 0.152623] smpboot: Total of 2 processors activated (10778.95 BogoMIPS) [ 0.163656] devtmpfs: initialized [ 0.173563] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.175272] futex hash table entries: 512 (order: 4, 65536 bytes) [ 0.176820] prandom: seed boundary self test passed [ 0.180081] prandom: 100 self tests passed [ 0.183755] regulator-dummy: no parameters [ 0.184710] regulator-dummy: no parameters [ 0.185517] regulator-dummy: Failed to create debugfs directory [ 0.186891] RTC time: 16:24:18, date: 11/20/17 [ 0.190149] NET: Registered protocol family 16 [ 0.192591] audit: initializing netlink subsys (disabled) [ 0.200048] workqueue: round-robin CPU selection forced, expect performance impact [ 0.201290] audit: type=2000 audit(1511166258.751:1): state=initialized audit_enabled=0 res=1 [ 0.203550] cpuidle: using governor menu [ 0.211540] ACPI: bus type PCI registered [ 0.213403] PCI: Using configuration type 1 for base access [ 0.270282] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.289826] ACPI: Added _OSI(Module Device) [ 0.290009] ACPI: Added _OSI(Processor Device) [ 0.290695] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.291421] ACPI: Added _OSI(Processor Aggregator Device) [ 0.306994] ACPI: Interpreter enabled [ 0.307746] ACPI: (supports S0 S3 S4 S5) [ 0.308399] ACPI: Using IOAPIC for interrupt routing [ 0.309512] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.367213] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.368224] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.369129] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.375321] PCI host bridge to bus 0000:00 [ 0.375975] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.376682] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.377707] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.378817] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window] [ 0.380024] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.380975] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.383814] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.386217] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.392244] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 0.400058] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.401289] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.402312] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.403345] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.405559] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.407405] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.408667] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.411053] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.414422] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 0.423354] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 0.433362] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.437617] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.440013] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.442319] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.453351] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.460638] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 0.463344] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 0.469409] pci_bus 0000:00: on NUMA node 0 [ 0.476736] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.478191] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.479589] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.480565] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.481810] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.487852] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.487852] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.490020] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.490931] vgaarb: loaded [ 0.494353] SCSI subsystem initialized [ 0.496786] libata version 3.00 loaded. [ 0.500143] Linux video capture interface: v2.00 [ 0.503586] FPGA manager framework [ 0.504230] Advanced Linux Sound Architecture Driver Initialized. [ 0.505268] PCI: Using ACPI for IRQ routing [ 0.505930] PCI: pci_cache_line_size set to 64 bytes [ 0.506857] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.507799] e820: reserve RAM buffer [mem 0x1ffe0000-0x1fffffff] [ 0.509898] nfc: nfc_init: NFC Core ver 0.1 [ 0.510666] NET: Registered protocol family 39 [ 0.521417] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.522208] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.541003] clocksource: Switched to clocksource kvm-clock [ 0.542309] FS-Cache: Loaded [ 0.543011] CacheFiles: Loaded [ 0.543598] pnp: PnP ACPI init [ 0.544366] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.545586] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.546858] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.547948] pnp 00:03: [dma 2] [ 0.548541] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.549879] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.564358] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.565676] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.568103] pnp: PnP ACPI: found 7 devices [ 0.574747] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.588813] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.589752] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.590667] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.591613] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window] [ 0.592831] NET: Registered protocol family 1 [ 0.593659] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 0.594737] cfg80211: Problem loading in-kernel X.509 certificate (-19) [ 0.604241] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 0.605597] cfg80211: failed to load regulatory.db [ 0.606475] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.607451] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.608363] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.609373] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 0.610714] PCI: CLS 0 bytes, default 64 [ 0.611567] Unpacking initramfs... [ 6.911369] Freeing initrd memory: 24796K [ 6.945638] PCLMULQDQ-NI instructions are not detected. [ 6.946649] CPU feature 'AVX registers' is not supported. [ 6.947520] CPU feature 'AVX registers' is not supported. [ 6.952701] spin_lock-torture:--- Start of test [debug]: 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 [ 6.955508] spin_lock-torture: Creating torture_shuffle task [ 6.971949] spin_lock-torture: Creating torture_stutter task [ 6.973197] spin_lock-torture: torture_shuffle task started [ 6.974331] spin_lock-torture: Creating lock_torture_writer task [ 6.975478] spin_lock-torture: torture_stutter task started [ 6.976430] spin_lock-torture: Creating lock_torture_writer task [ 6.977580] spin_lock-torture: lock_torture_writer task started [ 6.978623] spin_lock-torture: Creating lock_torture_writer task [ 6.979746] spin_lock-torture: lock_torture_writer task started [ 6.980824] spin_lock-torture: Creating lock_torture_writer task [ 6.981936] spin_lock-torture: lock_torture_writer task started [ 6.982952] spin_lock-torture: Creating lock_torture_stats task [ 6.984147] spin_lock-torture: lock_torture_stats task started [ 6.985145] spin_lock-torture: lock_torture_writer task started [ 7.946810] tsc: Refined TSC clocksource calibration: 2693.510 MHz [ 7.947963] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34b60feb, max_idle_ns: 440795225049 ns [ 13.698533] torture_init_begin: Refusing rcu init: spin_lock running. [ 13.704356] torture_init_begin: One torture test at a time! [ 13.718876] Initialise system trusted keyrings [ 13.737817] workingset: timestamp_bits=46 max_order=17 bucket_order=0 [ 13.739024] zbud: loaded [ 13.739964] fuse init (API version 7.26) [ 13.743724] JFS: nTxBlock = 3505, nTxLock = 28043 [ 13.764407] NILFS version 2 loaded [ 13.843487] Key type asymmetric registered [ 13.844193] Asymmetric key parser 'x509' registered [ 13.845083] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 13.853178] io scheduler noop registered [ 13.853761] io scheduler deadline registered [ 13.854381] io scheduler cfq registered (default) [ 13.855029] io scheduler mq-deadline registered [ 13.855645] io scheduler kyber registered [ 13.856243] test_string_helpers: Running tests... [ 13.861932] test_hexdump: all 1184 tests passed [ 13.863180] test_firmware: interface ready [ 13.865861] Running rhashtable test nelem=8, max_size=0, shrinking=0 [ 13.866961] Test 00: [ 13.867379] Adding 50000 keys [ 14.175679] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 14.256032] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 14.257831] Deleting 50000 keys [ 14.395451] Duration of test: 527558843 ns [ 14.396191] Test 01: [ 14.396802] Adding 50000 keys [ 14.549442] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 14.638780] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 14.640133] Deleting 50000 keys [ 14.831235] Duration of test: 433943124 ns [ 14.832029] Test 02: [ 14.836849] Adding 50000 keys [ 15.156722] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 15.375884] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 15.379703] Deleting 50000 keys [ 15.699639] Duration of test: 862255894 ns [ 15.704437] Test 03: [ 15.704917] Adding 50000 keys [ 16.021999] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 16.152880] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 16.162382] Deleting 50000 keys [ 16.356489] Duration of test: 651075785 ns [ 16.459624] test if its possible to exceed max_size 8192: no, ok [ 16.460729] Average test time: 618708411 [ 16.461385] Testing concurrent rhashtable access from 10 threads [ 30.477387] test 3125 add/delete pairs into rhlist [ 30.820859] test 3125 random rhlist add/delete operations [ 30.962628] Started 10 threads, 0 failed, rhltable test returns 0 [ 30.964216] test passed [ 30.965140] test_printf: all 260 tests passed [ 30.966401] crc32: CRC_LE_BITS = 64, CRC_BE BITS = 64 [ 30.969188] crc32: self tests passed, processed 225944 bytes in 254036 nsec [ 30.970610] crc32c: CRC_LE_BITS = 64 [ 30.971206] crc32c: self tests passed, processed 225944 bytes in 126814 nsec [ 31.026616] crc32_combine: 8373 self tests passed [ 31.084506] crc32c_combine: 8373 self tests passed [ 31.085691] xz_dec_test: module loaded [ 31.086286] xz_dec_test: Create a device node with 'mknod xz_dec_test c 248 0' and write .xz files to it. [ 31.089129] glob: 64 self-tests passed, 0 failed [ 31.090096] interval tree insert/remove [ 31.125949] -> 96525 cycles [ 31.127038] interval tree search [ 31.500846] -> 1006787 cycles (2692 results) [ 31.503545] gpio_it87: no device [ 31.504564] gpio-mockup: probe of gpio-mockup failed with error -22 [ 31.532685] cpcihp_zt5550: ZT5550 CompactPCI Hot Plug Driver version: 0.2 [ 31.533843] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 31.535001] switchtec: loaded. [ 31.548537] rivafb_setup START [ 31.549345] VIA Graphics Integration Chipset framebuffer 2.4 initializing [ 31.551142] vmlfb: initializing [ 31.551748] Could not find Carillo Ranch MCH device. [ 31.557656] no IO addresses supplied [ 31.580734] uvesafb: failed to execute /sbin/v86d [ 31.581467] uvesafb: make sure that the v86d helper is installed and executable [ 31.582505] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2) [ 31.583521] uvesafb: vbe_init() failed with -22 [ 31.584195] uvesafb: probe of uvesafb.0 failed with error -22 [ 31.585429] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 31.605824] ACPI: Power Button [PWRF] [ 31.607115] button: probe of LNXPWRBN:00 failed with error -22 [ 31.608199] Warning: Processor Platform Limit event detected, but not handled. [ 31.609347] Consider compiling CPUfreq support into your kernel. [ 32.152405] HDLC line discipline maxframe=4096 [ 32.153729] N_HDLC line discipline registered. [ 32.154590] r3964: Philips r3964 Driver $Revision: 1.10 $ [ 32.155340] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 32.246398] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 32.262303] console [ttyS0] disabled [ 32.355128] console [ttyS0] enabled [ 32.404180] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 32.477619] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 32.489651] Cyclades driver 2.6 [ 32.498391] MOXA Intellio family driver version 6.0k [ 32.499339] RocketPort device driver module, version 2.09, 12-June-2003 [ 32.500668] No rocketport ports found; unloading driver [ 32.504085] SyncLink GT [ 32.504517] SyncLink GT, tty major#241 [ 32.505873] SyncLink GT no devices found [ 32.506524] SyncLink MultiPort driver $Revision: 4.38 $ [ 32.593994] SyncLink MultiPort driver $Revision: 4.38 $, tty major#240 [ 32.595104] SyncLink serial driver $Revision: 4.38 $ [ 32.682961] SyncLink serial driver $Revision: 4.38 $, tty major#239 [ 32.684686] lp: driver loaded but no devices found [ 32.685546] Applicom driver: $Id: ac.c,v 1.30 2000/03/22 16:03:57 dwmw2 Exp $ [ 32.690273] ac.o: No PCI boards found. [ 32.690901] ac.o: For an ISA board you must supply memory and irq parameters. [ 32.696842] ppdev: user-space parallel port driver [ 32.710082] telclk_interrupt = 0xf non-mcpbl0010 hw. [ 32.710938] smapi::smapi_init, ERROR invalid usSmapiID [ 32.711744] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine [ 32.713237] mwave: mwavedd::mwave_init: Error: Failed to initialize board data [ 32.714501] mwave: mwavedd::mwave_init: Error: Failed to initialize [ 32.715590] Linux agpgart interface v0.103 [ 32.717306] SyncLink PC Card driver $Revision: 4.34 $, tty major#237 [ 32.719020] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds). [ 32.727045] parport_pc 00:04: reported by Plug and Play ACPI [ 32.728445] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] [ 32.730122] lp0: using parport0 (interrupt-driven). [ 32.730959] lp0: console ready [ 32.731742] console [lp0] disabled [ 32.732654] parport_pc 00:04: reported by Plug and Play ACPI [ 32.733927] parport1: PC-style at 0x378, irq 7 [PCSPP(,...)] [ 32.735468] lp1: using parport1 (interrupt-driven). [ 32.754266] dummy-irq: no IRQ given. Use irq=N [ 32.755404] Phantom Linux Driver, version n0.9.8, init OK [ 32.761535] nfcsim 0.2 initialized [ 32.762254] Uniform Multi-Platform E-IDE driver [ 32.782867] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports [ 32.784731] ide-gd driver 1.18 [ 32.786000] Loading iSCSI transport class v2.0-870. [ 32.804277] st: Version 20160209, fixed bufsize 32768, s/g segs 256 [ 32.805658] osst :I: Tape driver with OnStream support version 0.99.4 [ 32.805658] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $ [ 32.817658] ata_piix 0000:00:01.1: version 2.13 [ 32.838882] scsi host0: ata_piix [ 32.841363] scsi host1: ata_piix [ 32.842185] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14 [ 32.843317] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15 [ 33.000681] ata2.01: NODEV after polling detection [ 33.002052] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 33.003979] ata2.00: configured for MWDMA2 [ 33.005024] ata2.00: disabled [ 33.016892] INFO: trying to register non-static key. [ 33.017745] the code is fine but needs lockdep annotation. [ 33.018798] turning off the locking correctness validator. [ 33.019713] CPU: 0 PID: 72 Comm: kworker/u4:5 Not tainted 4.14.0-12872-g020aae3 #1 [ 33.020085] scsi host0: ata_piix [ 33.020729] Workqueue: events_unbound async_run_entry_fn [ 33.020729] Call Trace: [ 33.020729] dump_stack+0x7b/0xa9 [ 33.020729] register_lock_class+0x3bb/0x583 [ 33.020729] ? hrtimer_active+0x92/0x99 [ 33.020729] ? hrtimer_try_to_cancel+0x23/0xb6 [ 33.026718] scsi host1: ata_piix [ 33.027134] ata3: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14 [ 33.027138] ata4: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15 [ 33.028426] Error: Driver 'pata_platform' is already registered, aborting... [ 33.033725] Rounding down aligned max_sectors from 4294967295 to 4294967288 [ 33.040417] __lock_acquire+0x58/0x86c [ 33.048261] SSFDC read-only Flash Translation layer [ 33.048264] mtdoops: mtd device (mtddev=name/number) must be supplied [ 33.048276] device id = 2440 [ 33.048280] device id = 2480 [ 33.048283] device id = 24c0 [ 33.048286] device id = 24d0 [ 33.048289] device id = 25a1 [ 33.048292] device id = 2670 [ 33.048413] platform physmap-flash.0: failed to claim resource 0: [mem 0x08000000-0x07ffffff] [ 33.048781] Ramix PMC551 PCI Mezzanine Ram Driver. (C) 1999,2000 Nortel Networks. [ 33.048784] pmc551: not detected [ 33.060052] lock_acquire+0x61/0x7c [ 33.060052] ? ata_port_wait_eh+0x7b/0xe1 [ 33.060052] _raw_spin_lock_irqsave+0x47/0x81 [ 33.060052] ? ata_port_wait_eh+0x7b/0xe1 [ 33.060052] ata_port_wait_eh+0x7b/0xe1 [ 33.060052] ? print_dl_stats+0x27/0x27 [ 33.060052] ata_port_probe+0x2c/0x4f [ 33.060052] async_port_probe+0x33/0x59 [ 33.060052] async_run_entry_fn+0x3d/0x127 [ 33.060052] process_one_work+0x244/0x529 [ 33.060052] ? process_one_work+0x216/0x529 [ 33.060052] worker_thread+0x56/0x4d9 [ 33.060052] kthread+0x115/0x152 [ 33.060052] ? process_one_work+0x529/0x529 [ 33.060052] ? __kthread_create_on_node+0x190/0x190 [ 33.060052] ret_from_fork+0x1f/0x30 BUG: kernel hang in test stage Elapsed time: 1590 #!/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/quantal/quantal-core-x86_64.cgz /lkp/lkp/lkp-x86_64.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 kvm=( qemu-system-x86_64 -enable-kvm -kernel $kernel -initrd initrd.img -m 512 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -serial stdio -display none -monitor null ) append=( ip=::::vm-vp-quantal-x86_64-7::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=x86_64 kconfig=x86_64-randconfig-u0-11010248 branch=linus/master commit=020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb BOOT_IMAGE=/pkg/linux/x86_64-randconfig-u0-11010248/gcc-6/020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb/vmlinuz-4.14.0-12872-g020aae3 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-u0-11010248/gcc-6/020aae3ee58c1af0e7ffc4e2cc9fe4dc630338cb/2 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[*]}"