early console in setup code
[    0.000000] Linux version 4.13.0-09302-ge4dace3 (kbuild@xian) (gcc version 4.9.4 (Debian 4.9.4-2)) #1 SMP Sun Sep 17 19:02:15 CST 2017
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   NSC Geode by NSC
[    0.000000]   Cyrix CyrixInstead
[    0.000000]   UMC UMC UMC UMC
[    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-0x0000000013fddfff] usable
[    0.000000] BIOS-e820: [mem 0x0000000013fde000-0x0000000013ffffff] 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] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] Hypervisor detected: KVM
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x13fde max_arch_pfn = 0x1000000
[    0.000000] MTRR: 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 0x000f6b80-0x000f6b8f] mapped at [ff800b80]
[    0.000000]   mpc: f6b90-f6c7c
[    0.000000] initial memory mapped: [mem 0x00000000-0x037fffff]
[    0.000000] Base memory trampoline at [c009b000] 9b000 size 16384
[    0.000000] BRK [0x032e0000, 0x032e0fff] PGTABLE
[    0.000000] BRK [0x032e1000, 0x032e3fff] PGTABLE
[    0.000000] BRK [0x032e4000, 0x032e4fff] PGTABLE
[    0.000000] BRK [0x032e5000, 0x032e5fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x13d07000-0x13fcffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6960 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000013FE1936 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000013FE180A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000013FE0040 0017CA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000013FE0000 000040
[    0.000000] ACPI: APIC 0x0000000013FE187E 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000013FE18FE 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to         ffffc000 (        fee00000)
[    0.000000] 0MB HIGHMEM available.
[    0.000000] 319MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 13fde000
[    0.000000]   low ram: 0 - 13fde000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:13fdc001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 214614970 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000001000-0x0000000013fddfff]
[    0.000000]   HighMem  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-0x0000000013fddfff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fddfff]
[    0.000000] On node 0 totalpages: 81788
[    0.000000] free_area_init_node: node 0, pgdat c25ca7c0, node_mem_map d3a37024
[    0.000000]   Normal zone: 720 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 81788 pages, LIFO batch:15
[    0.000000] Using APIC driver default
[    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 ffffb000 (fec00000)
[    0.000000] e820: [mem 0x14000000-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: 7645519600211568 ns
[    0.000000] setup_percpu: NR_CPUS:32 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 341 pages/cpu @d378d000 s1372224 r0 d24512 u1396736
[    0.000000] pcpu-alloc: s1372224 r0 d24512 u1396736 alloc=341*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 1378fb80
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 81068
[    0.000000] Kernel command line: ip=::::vm-ivb41-yocto-i386-4::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-ivb41-yocto-i386-4/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-e4dace3615526fd66c86dd535ee4bc9e8c706e37-20170917-89721-1j2vk17-1.yaml ARCH=i386 kconfig=i386-randconfig-c0-09171336 branch=linux-devel/devel-catchup-201709171342 commit=e4dace3615526fd66c86dd535ee4bc9e8c706e37 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-09171336/gcc-4.9/e4dace3615526fd66c86dd535ee4bc9e8c706e37/vmlinuz-4.13.0-09302-ge4dace3 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-09171336/gcc-4.9/e4dace3615526fd66c86dd535ee4bc9e8c706e37/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 
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] allocated 331636 bytes of page_ext
[    0.000000] Initializing HighMem for node 0 (00000000:00000000)
[    0.000000] Memory: 282144K/327152K available (13539K kernel code, 1823K rwdata, 7020K rodata, 2352K init, 10680K bss, 45008K reserved, 0K cma-reserved, 0K highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xffa77000 - 0xfffff000   (5664 kB)
[    0.000000]     pkmap   : 0xff800000 - 0xffa00000   (2048 kB)
[    0.000000]     vmalloc : 0xd47de000 - 0xff7fe000   ( 688 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd3fde000   ( 319 MB)
[    0.000000]       .init : 0xc2602000 - 0xc284e000   (2352 kB)
[    0.000000]       .data : 0xc1d38e4e - 0xc25dff60   (8860 kB)
[    0.000000]       .text : 0xc1000000 - 0xc1d38e4e   (13539 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    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.004000] Running RCU self tests
[    0.004000] Hierarchical RCU implementation.
[    0.004000] 	RCU event tracing is enabled.
[    0.004000] 	RCU lockdep checking is enabled.
[    0.004000] 	RCU restricting CPUs from NR_CPUS=32 to nr_cpu_ids=2.
[    0.004000] 	RCU callback double-/use-after-free debug enabled.
[    0.004000] 	RCU debug extended QS entry/exit.
[    0.004000] 	RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout).
[    0.004000] 	Tasks RCU enabled.
[    0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.004000] NR_IRQS: 2304, nr_irqs: 56, preallocated irqs: 16
[    0.004000] CPU 0 irqstacks, hard=d3050000 soft=d3052000
[    0.004000] console [ttyS0] enabled
[    0.004000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.004000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.004000] ... MAX_LOCK_DEPTH:          48
[    0.004000] ... MAX_LOCKDEP_KEYS:        8191
[    0.004000] ... CLASSHASH_SIZE:          4096
[    0.004000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.004000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.004000] ... CHAINHASH_SIZE:          32768
[    0.004000]  memory used by lock dependency info: 4655 kB
[    0.004000]  per task-struct memory footprint: 2304 bytes
[    0.004000] ------------------------
[    0.004000] | Locking API testsuite:
[    0.004000] ----------------------------------------------------------------------------
[    0.004000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]               recursive read-lock:             |  ok  |             |  ok  |
[    0.004000]            recursive read-lock #2:             |  ok  |             |  ok  |
[    0.004000]             mixed read-write-lock:             |  ok  |             |  ok  |
[    0.004000]             mixed write-read-lock:             |  ok  |             |  ok  |
[    0.004000]   mixed read-lock/lock-write ABBA:             |FAILED|             |  ok  |
[    0.004000]    mixed read-lock/lock-read ABBA:             |  ok  |             |  ok  |
[    0.004000]  mixed write-lock/lock-write ABBA:             |  ok  |             |  ok  |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.004000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.004000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.004000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.004000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.004000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.004000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.004000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.004000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.004000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.004000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.004000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.004000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.004000]       hard-irq read-recursion/123:  ok  |
[    0.004000]       soft-irq read-recursion/123:  ok  |
[    0.004000]       hard-irq read-recursion/132:  ok  |
[    0.004000]       soft-irq read-recursion/132:  ok  |
[    0.004000]       hard-irq read-recursion/213:  ok  |
[    0.004000]       soft-irq read-recursion/213:  ok  |
[    0.004000]       hard-irq read-recursion/231:  ok  |
[    0.004000]       soft-irq read-recursion/231:  ok  |
[    0.004000]       hard-irq read-recursion/312:  ok  |
[    0.004000]       soft-irq read-recursion/312:  ok  |
[    0.004000]       hard-irq read-recursion/321:  ok  |
[    0.004000]       soft-irq read-recursion/321:  ok  |
[    0.004000]   --------------------------------------------------------------------------
[    0.004000]   | Wound/wait tests |
[    0.004000]   ---------------------
[    0.004000]                   ww api failures:  ok  |  ok  |  ok  |
[    0.004000]                ww contexts mixing:  ok  |  ok  |
[    0.004000]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[    0.004000]                locking mismatches:  ok  |  ok  |  ok  |
[    0.004000]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.004000]            spinlock nest unlocked:  ok  |
[    0.004000]   -----------------------------------------------------
[    0.004000]                                  |block | try  |context|
[    0.004000]   -----------------------------------------------------
[    0.004000]                           context:  ok  |  ok  |  ok  |
[    0.004000]                               try:  ok  |  ok  |  ok  |
[    0.004000]                             block:  ok  |  ok  |  ok  |
[    0.004000]                          spinlock:  ok  |  ok  |  ok  |
[    0.004000] -------------------------------------------------------
[    0.004000] Good, all 261 testcases passed! |
[    0.004000] ---------------------------------
[    0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.004000] hpet clockevent registered
[    0.004022] tsc: Detected 2693.508 MHz processor
[    0.004478] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=10774032)
[    0.004776] pid_max: default: 32768 minimum: 301
[    0.005242] ACPI: Core revision 20170728
[    0.011319] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.012113] Security Framework initialized
[    0.012511] Yama: becoming mindful.
[    0.012948] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.013576] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.015067] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.015576] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.016184] Freeing SMP alternatives memory: 32K
[    0.017260] smpboot: Max logical packages: 2
[    0.017705] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.018245] enabled ExtINT on CPU#0
[    0.019336] ENABLING IO-APIC IRQs
[    0.019679] init IO_APIC IRQs
[    0.020016]  apic 0 pin 0 not connected
[    0.020421] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.021230] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.021996] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.022762] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.023523] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    0.024000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    0.024000]  apic 0 pin 16 not connected
[    0.024000]  apic 0 pin 17 not connected
[    0.024000]  apic 0 pin 18 not connected
[    0.024000]  apic 0 pin 19 not connected
[    0.024000]  apic 0 pin 20 not connected
[    0.024000]  apic 0 pin 21 not connected
[    0.024000]  apic 0 pin 22 not connected
[    0.024000]  apic 0 pin 23 not connected
[    0.024000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.024000] Using local APIC timer interrupts.
[    0.024000] calibrating APIC timer ...
[    0.024000] ... lapic delta = 6500007
[    0.024000] ... PM-Timer delta = 372278
[    0.024000] APIC calibration not consistent with PM-Timer: 104ms instead of 100ms
[    0.024000] APIC delta adjusted to PM-Timer: 6249908 (6500007)
[    0.024000] TSC delta adjusted to PM-Timer: 269351080 (280129518)
[    0.024000] ..... delta 6249908
[    0.024000] ..... mult: 268431504
[    0.024000] ..... calibration result: 3999941
[    0.024000] ..... CPU clock speed is 2693.2043 MHz.
[    0.024000] ..... host bus clock speed is 999.3941 MHz.
[    0.024063] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.026310] Performance Events: PMU not available due to virtualization, using software events only.
[    0.029943] random: fast init done
[    0.030526] Hierarchical SRCU implementation.
[    0.033012] smp: Bringing up secondary CPUs ...
[    0.036371] CPU 1 irqstacks, hard=d3286000 soft=d3288000
[    0.037227] x86: Booting SMP configuration:
[    0.037877] .... node  #0, CPUs:      #1
[    0.000000] Initializing CPU#1
[    0.000000] ------------[ cut here ]------------
[    0.000000] WARNING: CPU: 1 PID: 0 at arch/x86/mm/tlb.c:237 initialize_tlbstate_and_flush+0x43/0x101
[    0.000000] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.13.0-09302-ge4dace3 #1
[    0.000000] task: d30dc300 task.stack: d30de000
[    0.000000] EIP: initialize_tlbstate_and_flush+0x43/0x101
[    0.000000] EFLAGS: 00210087 CPU: 1
[    0.000000] EAX: 02859000 EBX: c2488858 ECX: 00000002 EDX: 00000000
[    0.000000] ESI: 0241a000 EDI: 00000001 EBP: d30dff78 ESP: d30dff68
[    0.000000]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[    0.000000] CR0: 80050033 CR2: 00000000 CR3: 0241a000 CR4: 000006b0
[    0.000000] Call Trace:
[    0.000000]  cpu_init+0x106/0x238
[    0.000000]  start_secondary+0x9/0x11b
[    0.000000]  startup_32_smp+0x164/0x166
[    0.000000] Code: c2 e8 54 de ca 00 89 45 f0 89 55 f4 83 3d 48 27 40 c2 00 75 02 0f 0b 0f 20 d8 8d 76 00 89 c6 8b 43 20 e8 63 f6 ff ff 39 c6 74 02 <0f> ff a1 80 e5 5d c2 a8 01 74 11 64 a1 e8 bf 84 c2 a9 00 00 02
[    0.000000] ---[ end trace 35013a8e82407c23 ]---
[    0.004000] kvm-clock: cpu 1, msr 0:13fdc021, secondary cpu clock
[    0.004000] masked ExtINT on CPU#1
[    0.057300] KVM setup async PF for cpu 1
[    0.057300] kvm-stealtime: cpu 1, msr 138e4b80
[    0.057300] smp: Brought up 1 node, 2 CPUs
[    0.057300] smpboot: Total of 2 processors activated (10774.03 BogoMIPS)
[    0.060530] devtmpfs: initialized
[    0.062906] evm: security.capability
[    0.066285] workqueue: round-robin CPU selection forced, expect performance impact
[    0.068200] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.069255] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.070735] xor: measuring software checksum speed
[    0.108013]    pIII_sse  : 16467.000 MB/sec
[    0.147076]    prefetch64-sse: 17271.000 MB/sec
[    0.147728] xor: using function: prefetch64-sse (17271.000 MB/sec)
[    0.148042] pinctrl core: initialized pinctrl subsystem
[    0.150441] regulator-dummy: Failed to create debugfs directory
[    0.153347] NET: Registered protocol family 16
[    0.159130] EISA bus registered
[    0.162821] ACPI: bus type PCI registered
[    0.163473] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.164532] PCI: PCI BIOS revision 2.10 entry at 0xfd3e3, last bus=0
[    0.285839] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.356016] raid6: mmxx1    gen()  3015 MB/s
[    0.424035] raid6: mmxx2    gen()  3344 MB/s
[    0.492028] raid6: sse1x1   gen()  2458 MB/s
[    0.560020] raid6: sse1x2   gen()  3738 MB/s
[    0.628021] raid6: sse2x1   gen()  8046 MB/s
[    0.696033] raid6: sse2x1   xor()  3653 MB/s
[    0.764021] raid6: sse2x2   gen()  7715 MB/s
[    0.836050] raid6: sse2x2   xor()  4421 MB/s
[    0.836769] raid6: using algorithm sse2x1 gen() 8046 MB/s
[    0.837593] raid6: .... xor() 3653 MB/s, rmw enabled
[    0.840025] raid6: using intx1 recovery algorithm
[    0.842578] gpio-f7188x: Not a Fintek device at 0x0000002e
[    0.844074] gpio-f7188x: Not a Fintek device at 0x0000004e
[    0.846025] ACPI: Added _OSI(Module Device)
[    0.846684] ACPI: Added _OSI(Processor Device)
[    0.847368] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.848027] ACPI: Added _OSI(Processor Aggregator Device)
[    0.872570] ACPI: Interpreter enabled
[    0.873579] ACPI: (supports S0 S3 S5)
[    0.874114] ACPI: Using IOAPIC for interrupt routing
[    0.874890] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.932378] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.933434] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments]
[    0.934572] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.942206] acpiphp: Slot [3] registered
[    0.943115] acpiphp: Slot [4] registered
[    0.944109] acpiphp: Slot [5] registered
[    0.945111] acpiphp: Slot [6] registered
[    0.945988] acpiphp: Slot [7] registered
[    0.946858] acpiphp: Slot [8] registered
[    0.947792] acpiphp: Slot [9] registered
[    0.948243] acpiphp: Slot [10] registered
[    0.949136] acpiphp: Slot [11] registered
[    0.950032] acpiphp: Slot [12] registered
[    0.950999] acpiphp: Slot [13] registered
[    0.952296] acpiphp: Slot [14] registered
[    0.953176] acpiphp: Slot [15] registered
[    0.954059] acpiphp: Slot [16] registered
[    0.954944] acpiphp: Slot [17] registered
[    0.956109] acpiphp: Slot [18] registered
[    0.957062] acpiphp: Slot [19] registered
[    0.957948] acpiphp: Slot [20] registered
[    0.958824] acpiphp: Slot [21] registered
[    0.959777] acpiphp: Slot [22] registered
[    0.960238] acpiphp: Slot [23] registered
[    0.961127] acpiphp: Slot [24] registered
[    0.962011] acpiphp: Slot [25] registered
[    0.962966] acpiphp: Slot [26] registered
[    0.964304] acpiphp: Slot [27] registered
[    0.965199] acpiphp: Slot [28] registered
[    0.966097] acpiphp: Slot [29] registered
[    0.966989] acpiphp: Slot [30] registered
[    0.968143] acpiphp: Slot [31] registered
[    0.968948] PCI host bridge to bus 0000:00
[    0.969639] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.970748] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.971908] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.972026] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window]
[    0.973258] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.974309] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.978471] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.982604] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.990242] pci 0000:00:01.1: reg 0x20: [io  0xc080-0xc08f]
[    0.994868] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.996026] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.997145] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.998303] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    1.001360] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    1.003099] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    1.004046] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    1.007201] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    1.011320] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    1.018467] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    1.030601] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    1.033983] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    1.037271] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    1.040022] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    1.052020] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    1.054671] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    1.057462] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    1.062914] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    1.072018] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref]
[    1.078725] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000
[    1.081116] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f]
[    1.091494] pci_bus 0000:00: on NUMA node 0
[    1.096765] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    1.098628] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    1.100435] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    1.102215] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    1.103507] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    1.110819] SCSI subsystem initialized
[    1.112283] libata version 3.00 loaded.
[    1.112892] ACPI: bus type USB registered
[    1.113655] usbcore: registered new interface driver usbfs
[    1.114557] usbcore: registered new interface driver hub
[    1.116124] usbcore: registered new device driver usb
[    1.117714] pps_core: LinuxPPS API ver. 1 registered
[    1.118467] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.120454] FPGA manager framework
[    1.120989] Advanced Linux Sound Architecture Driver Initialized.
[    1.121957] PCI: Using ACPI for IRQ routing
[    1.122500] PCI: pci_cache_line_size set to 32 bytes
[    1.123323] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    1.124034] e820: reserve RAM buffer [mem 0x13fde000-0x13ffffff]
[    1.125890] Bluetooth: Core ver 2.22
[    1.126422] NET: Registered protocol family 31
[    1.127027] Bluetooth: HCI device and connection manager initialized
[    1.128046] Bluetooth: HCI socket layer initialized
[    1.128693] Bluetooth: L2CAP socket layer initialized
[    1.129449] NET: Registered protocol family 8
[    1.130080] NET: Registered protocol family 20
[    1.131026] nfc: nfc_init: NFC Core ver 0.1
[    1.132153] NET: Registered protocol family 39
[    1.136102] clocksource: Switched to clocksource kvm-clock
[    1.477417] VFS: Disk quotas dquot_6.6.0
[    1.478199] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.479871] pnp: PnP ACPI init
[    1.480986] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    1.482415] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    1.483815] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    1.484978] pnp 00:03: [dma 2]
[    1.485674] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    1.487233] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    1.488821] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    1.490350] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    1.494132] pnp: PnP ACPI: found 7 devices
[    1.532923] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.534786] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.535698] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    1.536643] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    1.537650] pci_bus 0000:00: resource 7 [mem 0x14000000-0xfebfffff window]
[    1.539371] NET: Registered protocol family 1
[    1.540107] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.540999] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.541883] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    1.542858] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    1.544167] PCI: CLS 0 bytes, default 32
[    1.545556] Unpacking initramfs...
[    3.025054] Freeing initrd memory: 2852K
[    3.027239] sta2x11_mfd_init
[    3.031349] kvm: no hardware support
[    3.031912] has_svm: not amd
[    3.032649] kvm: no hardware support
[    3.036159] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
[    3.037137] apm: disabled - APM is not SMP safe.
[    3.042390] PCLMULQDQ-NI instructions are not detected.
[    3.044941] NatSemi SCx200 Driver
[    3.045762] 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
[    3.048440] spin_lock-torture: Creating torture_shuffle task
[    3.049618] spin_lock-torture: Creating torture_stutter task
[    3.049622] spin_lock-torture: torture_shuffle task started
[    3.051591] spin_lock-torture: Creating lock_torture_writer task
[    3.051606] spin_lock-torture: torture_stutter task started
[    3.053623] spin_lock-torture: Creating lock_torture_writer task
[    3.054568] spin_lock-torture: lock_torture_writer task started
[    3.055497] spin_lock-torture: Creating lock_torture_writer task
[    3.055643] spin_lock-torture: lock_torture_writer task started
[    3.056525] spin_lock-torture: Creating lock_torture_writer task
[    3.056685] spin_lock-torture: lock_torture_writer task started
[    3.057524] spin_lock-torture: lock_torture_writer task started
[    3.060591] spin_lock-torture: Creating lock_torture_stats task
[    3.061289] spin_lock-torture: lock_torture_stats task started
[    3.556225] 
[    3.556508] ======================================================
[    3.557397] WARNING: possible circular locking dependency detected
[    3.558282] 4.13.0-09302-ge4dace3 #1 Tainted: G        W      
[    3.559121] ------------------------------------------------------
[    3.560011] kworker/0:1/22 is trying to acquire lock:
[    3.560144]  (ww_class_mutex){+.+.}, at: [<c10c9b4b>] test_abba_work+0x34/0xaf
[    3.560144] 
[    3.560144] but now in release context of a crosslock acquired at the following:
[    3.560144]  ((complete)&abba.b_ready){+.+.}, at: [<c1d2ee68>] wait_for_completion+0x19/0x1c
[    3.560144] 
[    3.560144] which lock already depends on the new lock.
[    3.560144] 
[    3.560144] 
[    3.560144] the existing dependency chain (in reverse order) is:
[    3.560144] 
[    3.560144] -> #1 ((complete)&abba.b_ready){+.+.}:
[    3.560144]        __lock_acquire+0xbbb/0xe4d
[    3.560144]        lock_acquire+0x101/0x185
[    3.568106]        __wait_for_common+0x3f/0x177
[    3.568106]        wait_for_completion+0x19/0x1c
[    3.568106]        test_abba+0x11d/0x1fa
[    3.568106]        test_ww_mutex_init+0xb6/0x3e0
[    3.568106]        do_one_initcall+0xa4/0x1ac
[    3.568106]        kernel_init_freeable+0x12c/0x1ff
[    3.568106]        kernel_init+0xd/0xd0
[    3.568106]        ret_from_fork+0x19/0x30
[    3.568106] 
[    3.568106] -> #0 (ww_class_mutex){+.+.}:
[    3.568106]        __ww_mutex_lock+0x71/0x6ce
[    3.568106]        ww_mutex_lock+0x3e/0x5e
[    3.568106]        test_abba_work+0x34/0xaf
[    3.568106]        process_one_work+0x247/0x4be
[    3.568106]        worker_thread+0x16d/0x20d
[    3.568106] 
[    3.568106] other info that might help us debug this:
[    3.568106] 
[    3.568106]  Possible unsafe locking scenario by crosslock:
[    3.568106] 
[    3.568106]        CPU0                    CPU1
[    3.568106]        ----                    ----
[    3.568106]   lock(ww_class_mutex);
[    3.568106]   lock((complete)&abba.b_ready);
[    3.568106]                                lock(ww_class_mutex);
[    3.568106]                                unlock((complete)&abba.b_ready);
[    3.568106] 
[    3.568106]  *** DEADLOCK ***
[    3.568106] 
[    3.568106] 5 locks held by kworker/0:1/22:
[    3.568106]  #0:  ("events"){+.+.}, at: [<c1098d97>] process_one_work+0x151/0x4be
[    3.568106]  #1:  ((&abba.work)){+.+.}, at: [<c1098dbc>] process_one_work+0x176/0x4be
[    3.568106]  #2:  (ww_class_acquire){+.+.}, at: [<c10c9b3b>] test_abba_work+0x24/0xaf
[    3.568106]  #3:  (ww_class_mutex){+.+.}, at: [<c10c9b4b>] test_abba_work+0x34/0xaf
[    3.568106]  #4:  (&x->wait#7){....}, at: [<c10b7c7e>] complete+0x17/0x4a
[    3.568106] 
[    3.568106] stack backtrace:
[    3.568106] CPU: 0 PID: 22 Comm: kworker/0:1 Tainted: G        W       4.13.0-09302-ge4dace3 #1
[    3.568106] Workqueue: events test_abba_work
[    3.568106] Call Trace:
[    3.568106]  dump_stack+0x88/0xb9
[    3.568106]  print_circular_bug+0x246/0x251
[    3.568106]  check_prev_add+0x56/0x1c1
[    3.568106]  lock_commit_crosslock+0x314/0x33c
[    3.568106]  ? graph_unlock+0x66/0x66
[    3.568106]  complete+0x21/0x4a
[    3.568106]  test_abba_work+0x3f/0xaf
[    3.568106]  process_one_work+0x247/0x4be
[    3.568106]  worker_thread+0x16d/0x20d
[    3.568106]  ? __kthread_parkme+0xb/0x76
[    3.568106]  kthread+0x11b/0x120
[    3.568106]  ? process_scheduled_works+0x29/0x29
[    3.568106]  ? __list_add+0x24/0x24
[    3.568106]  ret_from_fork+0x19/0x30
[    4.064165] tsc: Refined TSC clocksource calibration: 2693.509 MHz
[    4.065130] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34aa491a, max_idle_ns: 440795256568 ns
[    9.652310] torture_init_begin: Refusing rcu init: spin_lock running.
[    9.653150] torture_init_begin: One torture test at a time!
[    9.654379] audit: initializing netlink subsys (disabled)
[    9.671335] audit: type=2000 audit(1505646244.518:1): state=initialized audit_enabled=0 res=1
[    9.673461] workingset: timestamp_bits=14 max_order=17 bucket_order=3
[   19.710328] ntfs: driver 2.1.32 [Flags: R/W].
[   19.713002] fuse init (API version 7.26)
[   19.714094] JFS: nTxBlock = 2226, nTxLock = 17814
[   19.715936] SGI XFS with security attributes, debug enabled
[   19.717784] NILFS version 2 loaded
[   19.718529] OCFS2 User DLM kernel interface loaded
[   19.723604] gfs2: GFS2 installed
[   19.732035] NET: Registered protocol family 38
[   19.732823] Key type asymmetric registered
[   19.733497] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[   19.734758] io scheduler noop registered
[   19.735384] io scheduler cfq registered (default)
[   19.736130] io scheduler mq-deadline registered
[   19.736839] io scheduler bfq registered
[   19.737426] test_string_helpers: Running tests...
[   19.754650] test_hash: __hash_32() has no arch implementation to test.
[   19.756280] test_hash: hash_32() has no arch implementation to test.
[   19.757224] test_hash: hash_64() has no arch implementation to test.
[   19.758171] test_hash: 33152 tests passed.
[   19.758845] test_siphash: self-tests: pass
[   19.759713] test passed
[   19.760395] test_printf: all 260 tests passed
[   19.761055] test_uuid: all 18 tests passed
[   19.761686] ------------[ cut here ]------------
[   19.762380] kernel BUG at arch/x86/mm/physaddr.c:75!
[   19.763384] invalid opcode: 0000 [#1] SMP
[   19.763982] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G        W       4.13.0-09302-ge4dace3 #1
[   19.764094] task: d3094040 task.stack: d3096000
[   19.764094] EIP: __phys_addr+0x2d/0x5a
[   19.764094] EFLAGS: 00210202 CPU: 0
[   19.764094] EAX: 00000001 EBX: d47de000 ECX: fffff000 EDX: ff7fe000
[   19.764094] ESI: 147de000 EDI: c2661dc5 EBP: d3097f0c ESP: d3097f04
[   19.764094]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[   19.764094] CR0: 80050033 CR2: 00000000 CR3: 02859000 CR4: 000006b0
[   19.764094] Call Trace:
[   19.764094]  test_debug_virtual_init+0x28/0xb1
[   19.764094]  do_one_initcall+0xa4/0x1ac
[   19.764094]  ? kernel_init_freeable+0xfd/0x1ff
[   19.764094]  kernel_init_freeable+0x12c/0x1ff
[   19.764094]  ? rest_init+0xae/0xae
[   19.764094]  kernel_init+0xd/0xd0
[   19.764094]  ret_from_fork+0x19/0x30
[   19.764094] Code: e5 56 53 e8 b6 6c ff ff 8d b0 00 00 00 40 3d ff ff ff bf 77 02 0f 0b 89 c3 80 3d 08 b9 5d c2 00 74 0b e8 9b ff ff ff 84 c0 74 02 <0f> 0b a1 28 45 1a c3 85 c0 74 1c 89 f2 c1 ea 0c 39 c2 76 02 0f
[   19.764094] EIP: __phys_addr+0x2d/0x5a SS:ESP: 0068:d3097f04
[   19.778781] ---[ end trace 35013a8e82407c24 ]---
[   19.779481] Kernel panic - not syncing: Fatal exception
[   19.780301] Kernel Offset: disabled

Elapsed time: 20

initrds=(
	/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
	/lkp/scheduled/vm-ivb41-yocto-i386-4/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-e4dace3615526fd66c86dd535ee4bc9e8c706e37-20170917-89721-1j2vk17-1.cgz
	/lkp/lkp/lkp-i386.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz
	/osimage/pkg/static/trinity-i386.cgz
)

cat "${initrds[@]}" > /fs/sda5/initrd-vm-ivb41-yocto-i386-4

kvm=(
	qemu-system-i386
	-enable-kvm
	-kernel /pkg/linux/i386-randconfig-c0-09171336/gcc-4.9/e4dace3615526fd66c86dd535ee4bc9e8c706e37/vmlinuz-4.13.0-09302-ge4dace3
	-initrd /fs/sda5/initrd-vm-ivb41-yocto-i386-4
	-m 320
	-smp 2
	-device e1000,netdev=net0
	-netdev user,id=net0
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-drive file=/fs/sda5/disk0-vm-ivb41-yocto-i386-4,media=disk,if=virtio
	-pidfile /dev/shm/kboot/pid-vm-ivb41-yocto-i386-4
	-serial file:/dev/shm/kboot/vm-ivb41-yocto-i386-4/serial
	-serial file:/dev/shm/kboot/vm-ivb41-yocto-i386-4/kmsg
	-daemonize
	-display none
	-monitor null
)

append=(
	ip=::::vm-ivb41-yocto-i386-4::dhcp
	root=/dev/ram0
	user=lkp
	job=/lkp/scheduled/vm-ivb41-yocto-i386-4/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-e4dace3615526fd66c86dd535ee4bc9e8c706e37-20170917-89721-1j2vk17-1.yaml
	ARCH=i386
	kconfig=i386-randconfig-c0-09171336
	branch=linux-devel/devel-catchup-201709171342
	commit=e4dace3615526fd66c86dd535ee4bc9e8c706e37
	BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-09171336/gcc-4.9/e4dace3615526fd66c86dd535ee4bc9e8c706e37/vmlinuz-4.13.0-09302-ge4dace3
	max_uptime=1500
	RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-09171336/gcc-4.9/e4dace3615526fd66c86dd535ee4bc9e8c706e37/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,115200
	vga=normal
	rw
	drbd.minor_count=8
)

"${kvm[@]}" -append "${append[*]}"
