early console in setup code Probing EDD (edd=off to disable)... ok [ 0.000000] Linux version 4.14.0-03308-g670ffcc (kbuild@lkp-wsm-ep2) (gcc version 5.4.1 20171010 (Debian 5.5.0-3)) #1 SMP Wed Nov 15 09:17:46 CST 2017 [ 0.000000] Command line: ip=::::vm-lkp-wsx03-2G-17::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-2G-17/boot-1-debian-x86_64-2016-08-31.cgz-670ffccb2f9183eb6cb32fe92257aea52b3f8a7d-20171115-77396-16gkch1-30.yaml ARCH=x86_64 kconfig=x86_64-randconfig-g0-10270533 branch=linus/master commit=670ffccb2f9183eb6cb32fe92257aea52b3f8a7d BOOT_IMAGE=/pkg/linux/x86_64-randconfig-g0-10270533/gcc-5/670ffccb2f9183eb6cb32fe92257aea52b3f8a7d/vmlinuz-4.14.0-03308-g670ffcc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-2G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-g0-10270533/gcc-5/670ffccb2f9183eb6cb32fe92257aea52b3f8a7d/30 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 [ 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-0x000000007ffdbfff] usable [ 0.000000] BIOS-e820: [mem 0x000000007ffdc000-0x000000007fffffff] 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] 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 = 0x7ffdc 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: 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 0x000f6a70-0x000f6a7f] mapped at [ffffffffff200a70] [ 0.000000] mpc: f6a80-f6b74 [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] Using GB pages for direct mapping [ 0.000000] BRK [0x25f07000, 0x25f07fff] PGTABLE [ 0.000000] BRK [0x25f08000, 0x25f08fff] PGTABLE [ 0.000000] BRK [0x25f09000, 0x25f09fff] PGTABLE [ 0.000000] BRK [0x25f0a000, 0x25f0afff] PGTABLE [ 0.000000] BRK [0x25f0b000, 0x25f0bfff] PGTABLE [ 0.000000] RAMDISK: [mem 0x72b83000-0x7ffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F6860 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000007FFE1628 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000007FFE147C 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000007FFE0040 00143C (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000007FFE0000 000040 [ 0.000000] ACPI: APIC 0x000000007FFE1570 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000007FFE15F0 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fd000 ( fee00000) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000007ffdbfff] [ 0.000000] NODE_DATA(0) allocated [mem 0x7ffd8000-0x7ffdbfff] [ 0.000000] kvm-clock: cpu 0, msr 0:7ffd6001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 34568881915 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] Zone ranges: [ 0.000000] DMA32 [mem 0x0000000000001000-0x000000007ffdbfff] [ 0.000000] Normal empty [ 0.000000] Device 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-0x000000007ffdbfff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff] [ 0.000000] On node 0 totalpages: 524154 [ 0.000000] DMA32 zone: 8192 pages used for memmap [ 0.000000] DMA32 zone: 21 pages reserved [ 0.000000] DMA32 zone: 524154 pages, LIFO batch:31 [ 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] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000) [ 0.000000] e820: [mem 0x80000000-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: 1910969940391419 ns [ 0.000000] random: fast init done [ 0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] percpu: Embedded 46 pages/cpu @ffff88005f800000 s147840 r8192 d32384 u1048576 [ 0.000000] pcpu-alloc: s147840 r8192 d32384 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 5f815a00 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 515941 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: ip=::::vm-lkp-wsx03-2G-17::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-2G-17/boot-1-debian-x86_64-2016-08-31.cgz-670ffccb2f9183eb6cb32fe92257aea52b3f8a7d-20171115-77396-16gkch1-30.yaml ARCH=x86_64 kconfig=x86_64-randconfig-g0-10270533 branch=linus/master commit=670ffccb2f9183eb6cb32fe92257aea52b3f8a7d BOOT_IMAGE=/pkg/linux/x86_64-randconfig-g0-10270533/gcc-5/670ffccb2f9183eb6cb32fe92257aea52b3f8a7d/vmlinuz-4.14.0-03308-g670ffcc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-2G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-g0-10270533/gcc-5/670ffccb2f9183eb6cb32fe92257aea52b3f8a7d/30 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 [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] Memory: 1447472K/2096616K available (23268K kernel code, 26476K rwdata, 16580K rodata, 2284K init, 44124K bss, 649144K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] [ 0.000000] ********************************************************** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ** ** [ 0.000000] ** trace_printk() being used. Allocating extra memory. ** [ 0.000000] ** ** [ 0.000000] ** This means that this is a DEBUG kernel and it is ** [ 0.000000] ** unsafe for production use. ** [ 0.000000] ** ** [ 0.000000] ** If you see this message and you are not debugging ** [ 0.000000] ** the kernel, report this immediately to your vendor! ** [ 0.000000] ** ** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ********************************************************** [ 0.001000] Hierarchical RCU implementation. [ 0.001000] RCU event tracing is enabled. [ 0.001000] RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2. [ 0.001000] RCU callback double-/use-after-free debug enabled. [ 0.001000] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.001000] Tasks RCU enabled. [ 0.001000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.001000] NR_IRQS: 4352, nr_irqs: 56, preallocated irqs: 16 [ 0.001000] console [ttyS0] enabled [ 0.001000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.001000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.001000] ... MAX_LOCK_DEPTH: 48 [ 0.001000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.001000] ... CLASSHASH_SIZE: 4096 [ 0.001000] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.001000] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.001000] ... CHAINHASH_SIZE: 32768 [ 0.001000] memory used by lock dependency info: 7359 kB [ 0.001000] per task-struct memory footprint: 1920 bytes [ 0.001000] ODEBUG: selftest passed [ 0.001000] ACPI: Core revision 20170831 [ 0.001000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001000] hpet clockevent registered [ 0.004263] APIC: Switch to symmetric I/O mode setup [ 0.010356] enabled ExtINT on CPU#0 [ 0.056353] ENABLING IO-APIC IRQs [ 0.069361] init IO_APIC IRQs [ 0.070266] apic 0 pin 0 not connected [ 0.084385] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.099000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.099000] apic 0 pin 16 not connected [ 0.099000] apic 0 pin 17 not connected [ 0.099000] apic 0 pin 18 not connected [ 0.099000] apic 0 pin 19 not connected [ 0.099000] apic 0 pin 20 not connected [ 0.099000] apic 0 pin 21 not connected [ 0.099000] apic 0 pin 22 not connected [ 0.099000] apic 0 pin 23 not connected [ 0.099000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.099203] tsc: Detected 2260.998 MHz processor [ 0.102270] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=2260998) [ 0.105197] pid_max: default: 4096 minimum: 301 [ 0.223353] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.271490] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.286481] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.296746] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.523461] CPU: Physical Processor ID: 0 [ 0.525485] mce: CPU supports 10 MCE banks [ 0.536328] numa_add_cpu cpu 0 node 0: mask now 0 [ 0.539320] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.541326] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.584647] Freeing SMP alternatives memory: 20K [ 0.707419] smpboot: Max logical packages: 2 [ 0.719000] TSC deadline timer enabled [ 0.719000] smpboot: CPU0: Genuine Intel(R) CPU 000 @ 2.27GHz (family: 0x6, model: 0x2f, stepping: 0x1) [ 0.742864] Performance Events: Westmere events, Intel PMU driver. [ 0.745000] core: CPUID marked event: 'bus cycles' unavailable [ 0.747775] ... version: 2 [ 0.748296] ... bit width: 48 [ 0.749317] ... generic registers: 4 [ 0.750642] ... value mask: 0000ffffffffffff [ 0.751358] ... max period: 000000007fffffff [ 0.752291] ... fixed-purpose events: 3 [ 0.753319] ... event mask: 000000070000000f [ 0.762644] Hierarchical SRCU implementation. [ 0.800511] smp: Bringing up secondary CPUs ... [ 1.065301] x86: Booting SMP configuration: [ 1.066839] .... node #0, CPUs: #1 [ 0.001000] kvm-clock: cpu 1, msr 0:7ffd6041, secondary cpu clock [ 0.001000] masked ExtINT on CPU#1 [ 0.001000] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 1.111000] KVM setup async PF for cpu 1 [ 1.111000] kvm-stealtime: cpu 1, msr 5f915a00 [ 1.138000] smp: Brought up 1 node, 2 CPUs [ 1.139363] smpboot: Total of 2 processors activated (9043.99 BogoMIPS) [ 1.256365] pgdatinit0 (19) used greatest stack depth: 30504 bytes left [ 1.288612] devtmpfs: initialized [ 1.423650] x86/mm: Memory block size: 128MB [ 1.880323] gcov: version magic: 0x3530342a [ 2.272000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 2.275000] futex hash table entries: 16 (order: -1, 2048 bytes) [ 2.485413] regulator-dummy: Failed to create debugfs directory [ 2.585000] NET: Registered protocol family 16 [ 3.056291] cpuidle: using governor ladder [ 3.072000] cpuidle: using governor menu [ 3.174346] ACPI: bus type PCI registered [ 3.195000] dca service started, version 1.12.1 [ 3.205000] PCI: Using configuration type 1 for base access [ 5.548548] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 5.549842] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 5.687000] ACPI: Added _OSI(Module Device) [ 5.688000] ACPI: Added _OSI(Processor Device) [ 5.688412] ACPI: Added _OSI(3.0 _SCP Extensions) [ 5.689420] ACPI: Added _OSI(Processor Aggregator Device) [ 8.975262] ACPI: Interpreter enabled [ 9.001295] ACPI: (supports S0 S3 S5) [ 9.002322] ACPI: Using IOAPIC for interrupt routing [ 9.019000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 9.276000] ACPI: Enabled 2 GPEs in block 00 to 0F [ 17.546000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 17.547000] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments] [ 17.575575] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 18.565421] PCI host bridge to bus 0000:00 [ 18.578000] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 18.587000] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 18.602000] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 18.616000] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] [ 18.629000] pci_bus 0000:00: root bus resource [bus 00-ff] [ 18.640997] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 18.992000] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 19.280000] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 19.387572] pci 0000:00:01.1: reg 0x20: [io 0xc0c0-0xc0cf] [ 19.436909] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 19.445640] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 19.454648] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 19.462550] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 19.863355] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 19.905359] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 19.923000] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 20.436583] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 20.503000] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 20.599000] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 20.783000] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 21.366000] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 21.370415] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 21.373392] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 21.384620] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 21.915840] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 21.980000] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 22.046000] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 22.179874] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] [ 22.424000] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 22.462746] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf] [ 22.498905] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] [ 22.644971] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] [ 23.024929] pci 0000:00:06.0: [8086:25ab] type 00 class 0x088000 [ 23.039605] pci 0000:00:06.0: reg 0x10: [mem 0xfebf3000-0xfebf300f] [ 23.988619] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 24.278000] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 24.591000] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 25.057000] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 25.243000] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 25.693000] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 25.693000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 25.694494] pci 0000:00:02.0: vgaarb: bridge control possible [ 25.695296] vgaarb: loaded [ 25.811419] ACPI: bus type USB registered [ 25.845323] usbcore: registered new interface driver usbfs [ 25.874323] usbcore: registered new interface driver hub [ 25.891000] usbcore: registered new device driver usb [ 25.931557] EDAC MC: Ver: 3.0.0 [ 26.061388] FPGA manager framework [ 26.085387] PCI: Using ACPI for IRQ routing [ 26.096000] PCI: pci_cache_line_size set to 64 bytes [ 26.114548] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 26.130464] e820: reserve RAM buffer [mem 0x7ffdc000-0x7fffffff] [ 26.328772] clocksource: Switched to clocksource kvm-clock [ 52.797085] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper/0:1] [ 52.797085] Modules linked in: [ 52.797085] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.14.0-03308-g670ffcc #1 [ 52.797085] task: ffff88005ec38000 task.stack: ffff88005ec40000 [ 52.797085] RIP: 0010:update_event_printk+0x286/0x700 [ 52.797085] RSP: 0000:ffff88005ec47d00 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff11 [ 52.797085] RAX: dffffc0000000000 RBX: ffffffff97858b9f RCX: ffffffff94d62d81 [ 52.797085] RDX: 1ffffffff2d3f331 RSI: 0000000000000008 RDI: ffffffff969f9988 [ 52.797085] RBP: 0000000000000002 R08: fffffbfff313545e R09: 226c616d726f4e22 [ 52.797085] R10: 0000000000000000 R11: fffffbfff313545d R12: 0000000000000000 [ 52.797085] R13: ffffffff9939a0f0 R14: 0000000000000000 R15: 0000000000000011 [ 52.797085] FS: 0000000000000000(0000) GS:ffff88005f800000(0000) knlGS:0000000000000000 [ 52.797085] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 52.797085] CR2: 0000000000000000 CR3: 0000000021413001 CR4: 00000000000206f0 [ 52.797085] Call Trace: [ 52.797085] trace_event_eval_update+0x1fa/0x248 [ 52.797085] trace_insert_eval_map+0x51/0x5d [ 52.797085] tracer_init_tracefs+0x24b/0x3cb [ 52.797085] ? tracer_alloc_buffers+0x9bf/0x9bf [ 52.797085] do_one_initcall+0x18a/0x386 [ 52.797085] ? initcall_blacklisted+0x1c0/0x1c0 [ 52.797085] ? print_unlock_imbalance_bug+0x169/0x169 [ 52.797085] kernel_init_freeable+0x2f3/0x4f2 [ 52.797085] ? rest_init+0x1d4/0x1d4 [ 52.797085] kernel_init+0x13/0x2d0 [ 52.797085] ? rest_init+0x1d4/0x1d4 [ 52.797085] ret_from_fork+0x1f/0x30 [ 52.797085] Code: 41 bc 01 00 00 00 eb 17 48 ff 05 87 ec 1f 05 40 80 fd 5f 74 eb 48 ff 05 82 ec 1f 05 45 31 e4 49 63 ec be 08 00 00 00 48 83 c5 02 <48> 8d 3c ed 88 a2 9a 98 e8 b9 55 17 00 48 ff 04 ed 88 a2 9a 98 [ 53.138843] Kernel panic - not syncing: softlockup: hung tasks [ 53.138843] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G L 4.14.0-03308-g670ffcc #1 [ 53.138843] Call Trace: [ 53.138843] [ 53.138843] dump_stack+0x11a/0x178 [ 53.138843] panic+0x23c/0x59d [ 53.138843] ? refcount_error_report+0x22f/0x22f [ 53.138843] ? kasan_check_write+0x1e/0x26 [ 53.138843] ? watchdog_timer_fn+0x589/0x5d3 [ 53.138843] watchdog_timer_fn+0x5b4/0x5d3 [ 53.138843] ? watchdog+0x29/0x29 [ 53.138843] __run_hrtimer+0x747/0xbb4 [ 53.138843] hrtimer_run_queues+0x203/0x264 [ 53.138843] ? hrtimer_get_next_event+0x193/0x193 [ 53.138843] ? __asan_loadN+0x19/0x21 [ 53.138843] ? ftrace_likely_update+0x99/0xc3 [ 53.138843] run_local_timers+0x31/0x147 [ 53.138843] update_process_times+0x31/0xaf [ 53.138843] tick_nohz_handler+0x245/0x308 [ 53.138843] ? tick_do_update_jiffies64+0x30b/0x30b [ 53.138843] smp_apic_timer_interrupt+0x3ca/0x6ca [ 53.138843] apic_timer_interrupt+0xac/0xc0 [ 53.138843] [ 53.138843] RIP: 0010:update_event_printk+0x286/0x700 [ 53.138843] RSP: 0000:ffff88005ec47d00 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff11 [ 53.138843] RAX: dffffc0000000000 RBX: ffffffff97858b9f RCX: ffffffff94d62d81 [ 53.138843] RDX: 1ffffffff2d3f331 RSI: 0000000000000008 RDI: ffffffff969f9988 [ 53.138843] RBP: 0000000000000002 R08: fffffbfff313545e R09: 226c616d726f4e22 [ 53.138843] R10: 0000000000000000 R11: fffffbfff313545d R12: 0000000000000000 [ 53.138843] R13: ffffffff9939a0f0 R14: 0000000000000000 R15: 0000000000000011 [ 53.138843] ? update_event_printk+0x23a/0x700 [ 53.138843] trace_event_eval_update+0x1fa/0x248 [ 53.138843] trace_insert_eval_map+0x51/0x5d [ 53.138843] tracer_init_tracefs+0x24b/0x3cb [ 53.138843] ? tracer_alloc_buffers+0x9bf/0x9bf [ 53.138843] do_one_initcall+0x18a/0x386 [ 53.138843] ? initcall_blacklisted+0x1c0/0x1c0 [ 53.138843] ? print_unlock_imbalance_bug+0x169/0x169 [ 53.138843] kernel_init_freeable+0x2f3/0x4f2 [ 53.138843] ? rest_init+0x1d4/0x1d4 [ 53.138843] kernel_init+0x13/0x2d0 [ 53.138843] ? rest_init+0x1d4/0x1d4 [ 53.138843] ret_from_fork+0x1f/0x30 Elapsed time: 140 #!/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/debian/debian-x86_64-2016-08-31.cgz /lkp/lkp/lkp-x86_64.cgz /osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-08-01.cgz /osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz /osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.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-wsx03-2G-17-0 256G qemu-img create -f qcow2 disk-vm-lkp-wsx03-2G-17-1 256G kvm=( qemu-system-x86_64 -enable-kvm -cpu host -kernel $kernel -initrd initrd.img -m 2048 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23646-:22 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -drive file=disk-vm-lkp-wsx03-2G-17-0,media=disk,if=virtio -drive file=disk-vm-lkp-wsx03-2G-17-1,media=disk,if=virtio -serial stdio -display none -monitor null ) append=( ip=::::vm-lkp-wsx03-2G-17::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=x86_64 kconfig=x86_64-randconfig-g0-10270533 branch=linus/master commit=670ffccb2f9183eb6cb32fe92257aea52b3f8a7d BOOT_IMAGE=/pkg/linux/x86_64-randconfig-g0-10270533/gcc-5/670ffccb2f9183eb6cb32fe92257aea52b3f8a7d/vmlinuz-4.14.0-03308-g670ffcc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-2G/debian-x86_64-2016-08-31.cgz/x86_64-randconfig-g0-10270533/gcc-5/670ffccb2f9183eb6cb32fe92257aea52b3f8a7d/30 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 ) "${kvm[@]}" -append "${append[*]}"