early console in setup code Probing EDD (edd=off to disable)... ok early console in extract_kernel input_data: 0x0000000004863255 input_len: 0x0000000000edf1be output: 0x0000000001000000 output_len: 0x00000000033010c0 kernel_total_size: 0x0000000004762000 Decompressing Linux... Parsing ELF... done. Booting the kernel. [ 0.000000] Linux version 4.14.0-12891-gc8a0739 (kbuild@xian) (gcc version 6.4.0 20171026 (Debian 6.4.0-9)) #1 PREEMPT Mon Nov 20 16:23:57 CST 2017 [ 0.000000] Command line: ip=::::vm-ivb41-yocto-ia32-10::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-ivb41-yocto-ia32-10/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-c8a0739b185d11d6e2ca7ad9f5835841d1cfc765-20171120-85010-9w47-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-h0-11121445 branch=linus/master commit=c8a0739b185d11d6e2ca7ad9f5835841d1cfc765 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-h0-11121445/gcc-6/c8a0739b185d11d6e2ca7ad9f5835841d1cfc765/vmlinuz-4.14.0-12891-gc8a0739 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/x86_64-randconfig-h0-11121445/gcc-6/c8a0739b185d11d6e2ca7ad9f5835841d1cfc765/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=norma [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 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-0x000000001a3ddfff] usable [ 0.000000] BIOS-e820: [mem 0x000000001a3de000-0x000000001a3fffff] 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] random: fast init done [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x1a3de 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 WC UC- UC WB WP UC- WT [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] BRK [0x0573d000, 0x0573dfff] PGTABLE [ 0.000000] BRK [0x0573e000, 0x0573efff] PGTABLE [ 0.000000] BRK [0x0573f000, 0x0573ffff] PGTABLE [ 0.000000] BRK [0x05740000, 0x05740fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1a054000-0x1a3cffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000001A3E15CE 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000001A3E142A 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000001A3E0040 0013EA (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000001A3E0000 000040 [ 0.000000] ACPI: APIC 0x000000001A3E151E 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000001A3E1596 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fc000 ( fee00000) [ 0.000000] kvm-clock: cpu 0, msr 0:1a3dc001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 713147631 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-0x000000001a3ddfff] [ 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-0x000000001a3ddfff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001a3ddfff] [ 0.000000] On node 0 totalpages: 107388 [ 0.000000] DMA32 zone: 1680 pages used for memmap [ 0.000000] DMA32 zone: 21 pages reserved [ 0.000000] DMA32 zone: 107388 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] mapped IOAPIC to ffffffffff5fb000 (fec00000) [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 3ae7600 [ 0.000000] e820: [mem 0x1a400000-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] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 105687 [ 0.000000] Kernel command line: ip=::::vm-ivb41-yocto-ia32-10::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-ivb41-yocto-ia32-10/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-c8a0739b185d11d6e2ca7ad9f5835841d1cfc765-20171120-85010-9w47-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-h0-11121445 branch=linus/master commit=c8a0739b185d11d6e2ca7ad9f5835841d1cfc765 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-h0-11121445/gcc-6/c8a0739b185d11d6e2ca7ad9f5835841d1cfc765/vmlinuz-4.14.0-12891-gc8a0739 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/x86_64-randconfig-h0-11121445/gcc-6/c8a0739b185d11d6e2ca7ad9f5835841d1cfc765/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 vg [ 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] Calgary: detecting Calgary via BIOS EBDA area [ 0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing! [ 0.000000] Memory: 343776K/429552K available (19804K kernel code, 11165K rwdata, 10448K rodata, 1352K init, 22764K bss, 85776K reserved, 0K cma-reserved) [ 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] Preemptible hierarchical RCU implementation. [ 0.004000] RCU lockdep checking is enabled. [ 0.004000] RCU callback double-/use-after-free debug enabled. [ 0.004000] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.004000] Tasks RCU enabled. [ 0.004000] NR_IRQS: 4352, nr_irqs: 48, preallocated irqs: 16 [ 0.004000] console [ttyS0] enabled [ 0.004000] bootconsole [earlyser0] disabled [ 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: 7391 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] ACPI: Core revision 20170831 [ 0.004000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.004000] hpet clockevent registered [ 0.004057] APIC: Switch to symmetric I/O mode setup [ 0.005115] enabled ExtINT on CPU#0 [ 0.008121] ENABLING IO-APIC IRQs [ 0.008822] init IO_APIC IRQs [ 0.009387] apic 0 pin 0 not connected [ 0.010197] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.011773] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.012000] apic 0 pin 16 not connected [ 0.012000] apic 0 pin 17 not connected [ 0.012000] apic 0 pin 18 not connected [ 0.012000] apic 0 pin 19 not connected [ 0.012000] apic 0 pin 20 not connected [ 0.012000] apic 0 pin 21 not connected [ 0.012000] apic 0 pin 22 not connected [ 0.012000] apic 0 pin 23 not connected [ 0.012000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.012000] tsc: Detected 2693.508 MHz processor [ 0.012021] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=10774032) [ 0.013498] pid_max: default: 4096 minimum: 301 [ 0.014759] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.016036] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.018855] mce: CPU supports 10 MCE banks [ 0.020109] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.021070] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.022136] CPU: Intel Xeon E3-12xx v2 (Ivy Bridge) (family: 0x6, model: 0x3a, stepping: 0x9) [ 0.028133] Performance Events: unsupported p6 CPU model 58 no PMU driver, software events only. [ 0.030236] Hierarchical SRCU implementation. [ 0.031962] NMI watchdog: Perf event create on CPU 0 failed with -2 [ 0.032016] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.033507] TSC deadline timer enabled [ 0.035628] devtmpfs: initialized [ 0.036000] x86/mm: Memory block size: 128MB [ 0.039357] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.040030] futex hash table entries: 16 (order: -2, 1408 bytes) [ 0.042262] regulator-dummy: no parameters [ 0.043459] regulator-dummy: no parameters [ 0.044176] regulator-dummy: Failed to create debugfs directory [ 0.046894] NET: Registered protocol family 16 [ 0.050784] cpuidle: using governor menu [ 0.052439] ACPI: bus type PCI registered [ 0.053924] dca service started, version 1.12.1 [ 0.054928] PCI: Using configuration type 1 for base access [ 0.138381] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.141305] gpio-f7188x: Not a Fintek device at 0x0000002e [ 0.142214] gpio-f7188x: Not a Fintek device at 0x0000004e [ 0.143302] ACPI: Added _OSI(Module Device) [ 0.143754] ACPI: Added _OSI(Processor Device) [ 0.144083] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.144581] ACPI: Added _OSI(Processor Aggregator Device) [ 0.151844] ACPI: Interpreter enabled [ 0.152126] ACPI: (supports S0 S3 S5) [ 0.152638] ACPI: Using IOAPIC for interrupt routing [ 0.153242] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.154759] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.176935] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.177623] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments] [ 0.178378] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.179391] PCI host bridge to bus 0000:00 [ 0.179844] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.180015] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.180733] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.181511] pci_bus 0000:00: root bus resource [mem 0x1a400000-0xfebfffff window] [ 0.182292] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.182982] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.184744] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.186394] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.190693] pci 0000:00:01.1: reg 0x20: [io 0xc080-0xc08f] [ 0.192971] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.193725] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.194400] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.195139] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.196536] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.198313] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.199077] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.200656] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.203420] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 0.207183] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 0.214057] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.215637] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.219070] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.221826] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.229159] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.230705] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.232896] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 0.235169] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 0.241818] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] [ 0.245163] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000 [ 0.246434] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f] [ 0.252797] pci_bus 0000:00: on NUMA node 0 [ 0.255233] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.256315] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.257334] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.258492] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.259301] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.261980] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.262646] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.263552] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.264011] vgaarb: loaded [ 0.265588] SCSI subsystem initialized [ 0.266309] libata version 3.00 loaded. [ 0.266892] media: Linux media interface: v0.10 [ 0.267447] Linux video capture interface: v2.00 [ 0.268092] pps_core: LinuxPPS API ver. 1 registered [ 0.268628] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.269670] EDAC MC: Ver: 3.0.0 [ 0.270332] EDAC DEBUG: edac_mc_sysfs_init: device mc created [ 0.271521] PCI: Using ACPI for IRQ routing [ 0.271977] PCI: pci_cache_line_size set to 64 bytes [ 0.272142] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.272826] e820: reserve RAM buffer [mem 0x1a3de000-0x1bffffff] [ 0.274886] clocksource: Switched to clocksource kvm-clock [ 0.278082] Warning: could not register all branches stats [ 0.278685] Warning: could not register annotated branches stats [ 0.331773] FS-Cache: Loaded [ 0.332495] CacheFiles: Loaded [ 0.332947] pnp: PnP ACPI init [ 0.333568] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.334476] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.335420] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.336201] pnp 00:03: [dma 2] [ 0.336614] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.337548] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.338516] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.339452] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.341120] pnp: PnP ACPI: found 7 devices [ 0.347502] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.348859] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.349505] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.350189] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.350929] pci_bus 0000:00: resource 7 [mem 0x1a400000-0xfebfffff window] [ 0.352263] NET: Registered protocol family 1 [ 0.352747] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.353380] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.354051] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.354785] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 0.355955] PCI: CLS 0 bytes, default 64 [ 0.356989] Unpacking initramfs... [ 1.559441] Freeing initrd memory: 3568K [ 1.564569] Scanning for low memory corruption every 60 seconds [ 1.566110] cryptomgr_test (24) used greatest stack depth: 14784 bytes left [ 1.570990] cryptomgr_test (47) used greatest stack depth: 14528 bytes left [ 1.579421] AVX2 or AES-NI instructions are not detected. [ 1.580659] spin_lock-torture:--- Start of test [debug]: nwriters_stress=2 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 1.582499] spin_lock-torture: Creating torture_shuffle task [ 1.583310] spin_lock-torture: Creating torture_stutter task [ 1.584003] spin_lock-torture: torture_shuffle task started [ 1.586194] spin_lock-torture: Creating lock_torture_writer task [ 1.586931] spin_lock-torture: torture_stutter task started [ 1.587538] spin_lock-torture: Creating lock_torture_writer task [ 1.588337] spin_lock-torture: lock_torture_writer task started [ 1.588989] spin_lock-torture: Creating lock_torture_stats task [ 1.589710] spin_lock-torture: lock_torture_writer task started [ 1.590645] spin_lock-torture: lock_torture_stats task started [ 2.100149] [ 2.100363] ====================================================== [ 2.101009] WARNING: possible circular locking dependency detected [ 2.101645] 4.14.0-12891-gc8a0739 #1 Not tainted [ 2.102150] ------------------------------------------------------ [ 2.102783] kworker/0:1/14 is trying to acquire lock: [ 2.103312] (ww_class_mutex){+.+.}, at: [] test_abba_work+0xd9/0x35e [ 2.104009] [ 2.104009] but now in release context of a crosslock acquired at the following: [ 2.104009] ((completion)&abba.b_ready){+.+.}, at: [] test_abba+0x1a4/0x3c0 [ 2.104009] [ 2.104009] which lock already depends on the new lock. [ 2.104009] [ 2.104009] the existing dependency chain (in reverse order) is: [ 2.104009] [ 2.104009] -> #1 ((completion)&abba.b_ready){+.+.}: [ 2.104009] wait_for_completion+0x5e/0x220 [ 2.104009] test_abba+0x1a4/0x3c0 [ 2.104009] test_ww_mutex_init+0xcc/0x3e1 [ 2.104009] do_one_initcall+0xac/0x1c7 [ 2.104009] kernel_init_freeable+0x116/0x1db [ 2.104009] kernel_init+0xa/0x160 [ 2.104009] ret_from_fork+0x24/0x30 [ 2.104009] [ 2.104009] -> #0 (ww_class_mutex){+.+.}: [ 2.104009] test_abba_work+0xd9/0x35e [ 2.104009] [ 2.104009] other info that might help us debug this: [ 2.104009] [ 2.104009] Possible unsafe locking scenario by crosslock: [ 2.104009] [ 2.104009] CPU0 CPU1 [ 2.104009] ---- ---- [ 2.104009] lock(ww_class_mutex); [ 2.104009] lock((completion)&abba.b_ready); [ 2.104009] lock(ww_class_mutex); [ 2.104009] unlock((completion)&abba.b_ready); [ 2.104009] [ 2.104009] *** DEADLOCK *** [ 2.104009] [ 2.104009] 5 locks held by kworker/0:1/14: [ 2.104009] #0: ((wq_completion)"events"){+.+.}, at: [] process_one_work+0x32b/0xae0 [ 2.104009] #1: ((work_completion)(&abba.work)){+.+.}, at: [] process_one_work+0x32b/0xae0 [ 2.104009] #2: (ww_class_acquire){+.+.}, at: [] process_one_work+0x4ec/0xae0 [ 2.104009] #3: (ww_class_mutex){+.+.}, at: [] test_abba_work+0xd9/0x35e [ 2.104009] #4: (&x->wait#5){....}, at: [] complete+0x18/0x70 [ 2.104009] [ 2.104009] stack backtrace: [ 2.104009] CPU: 0 PID: 14 Comm: kworker/0:1 Not tainted 4.14.0-12891-gc8a0739 #1 [ 2.104009] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 2.104009] Workqueue: events test_abba_work [ 2.104009] Call Trace: [ 2.104009] print_circular_bug+0x2d8/0x2f0 [ 2.104009] check_prev_add+0x150/0x700 [ 2.104009] ? zap_class+0xe0/0xe0 [ 2.104009] ? lock_commit_crosslock+0x4df/0x600 [ 2.104009] lock_commit_crosslock+0x4df/0x600 [ 2.104009] ? do_raw_spin_lock+0xbe/0x100 [ 2.104009] complete+0x25/0x70 [ 2.104009] test_abba_work+0xe5/0x35e [ 2.104009] ? process_one_work+0x4ec/0xae0 [ 2.104009] process_one_work+0x4ec/0xae0 [ 2.104009] worker_thread+0x3d8/0x6c0 [ 2.104009] ? process_one_work+0xae0/0xae0 [ 2.104009] kthread+0x1a1/0x1b0 [ 2.104009] ? __kthread_bind_mask+0x40/0x40 [ 2.104009] ret_from_fork+0x24/0x30 [ 2.592101] tsc: Refined TSC clocksource calibration: 2693.508 MHz [ 2.592788] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d349e8249, max_idle_ns: 440795288087 ns [ 8.156610] torture_init_begin: Refusing rcu init: spin_lock running. [ 8.157659] torture_init_begin: One torture test at a time! [ 8.161633] Initialise system trusted keyrings [ 8.164172] workingset: timestamp_bits=46 max_order=17 bucket_order=0 [ 8.166192] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 8.166947] ntfs: driver 2.1.32 [Flags: R/W]. [ 8.167485] romfs: ROMFS MTD (C) 2007 Red Hat, Inc. [ 8.168103] qnx6: QNX6 filesystem 1.0.0 registered. [ 8.168631] fuse init (API version 7.26) [ 8.169475] orangefs_debugfs_init: called with debug mask: :none: :0: [ 8.170345] orangefs_init: module version upstream loaded [ 8.171045] JFS: nTxBlock = 2713, nTxLock = 21709 [ 8.177527] NILFS version 2 loaded [ 8.181152] gfs2: GFS2 installed [ 8.199749] Key type asymmetric registered [ 8.200547] Asymmetric key parser 'x509' registered [ 8.201227] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) [ 8.202070] io scheduler noop registered [ 8.202578] io scheduler cfq registered (default) [ 8.203158] io scheduler mq-deadline registered [ 8.203894] io scheduler kyber registered [ 8.204596] io scheduler bfq registered [ 8.205062] test_string_helpers: Running tests... [ 8.207982] test_hexdump: all 1184 tests passed [ 8.209071] test_firmware: interface ready [ 8.219311] test_hash: __hash_32() has no arch implementation to test. [ 8.220040] test_hash: hash_32() has no arch implementation to test. [ 8.220695] test_hash: hash_64() has no arch implementation to test. [ 8.221394] test_hash: 33152 tests passed. [ 8.221850] test_siphash: self-tests: pass [ 8.224201] Running rhashtable test nelem=8, max_size=0, shrinking=0 [ 8.224872] Test 00: [ 8.225193] Adding 50000 keys [ 8.325510] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.339975] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.340909] Deleting 50000 keys [ 8.368761] Duration of test: 143159635 ns [ 8.369439] Test 01: [ 8.369764] Adding 50000 keys [ 8.433414] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.450626] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.451503] Deleting 50000 keys [ 8.474992] Duration of test: 104882295 ns [ 8.475652] Test 02: [ 8.475985] Adding 50000 keys [ 8.537958] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.551236] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.552148] Deleting 50000 keys [ 8.575510] Duration of test: 99179841 ns [ 8.576144] Test 03: [ 8.576466] Adding 50000 keys [ 8.636772] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.650086] Traversal complete: counted=50000, nelems=50000, entries=50000, table-jumps=0 [ 8.650959] Deleting 50000 keys [ 8.674517] Duration of test: 97716334 ns [ 8.684990] test if its possible to exceed max_size 8192: no, ok [ 8.685835] Average test time: 111234526 [ 8.686257] Testing concurrent rhashtable access from 10 threads [ 12.020571] test 3125 add/delete pairs into rhlist [ 12.099284] test 3125 random rhlist add/delete operations [ 12.128710] Started 10 threads, 0 failed, rhltable test returns 0 [ 12.129522] test passed [ 12.213786] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 951 [ 12.219662] test_bitmap: all 460506 tests passed [ 12.220175] test_uuid: all 18 tests passed [ 12.221935] crc32: CRC_LE_BITS = 8, CRC_BE BITS = 8 [ 12.222444] crc32: self tests passed, processed 225944 bytes in 641192 nsec [ 12.223768] crc32c: CRC_LE_BITS = 8 [ 12.224159] crc32c: self tests passed, processed 225944 bytes in 301014 nsec [ 12.255821] crc32_combine: 8373 self tests passed [ 12.291241] crc32c_combine: 8373 self tests passed [ 12.292036] xz_dec_test: module loaded [ 12.292440] xz_dec_test: Create a device node with 'mknod xz_dec_test c 242 0' and write .xz files to it. [ 12.293508] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 12.294380] glob: 64 self-tests passed, 0 failed [ 12.295210] rbtree testing [ 12.300436] -> test 1 (latency of nnodes insert+delete): 14065 cycles [ 12.305792] -> test 2 (latency of nnodes cached insert+delete): 11822 cycles [ 12.307025] -> test 3 (latency of inorder traversal): 1308 cycles [ 12.307670] -> test 4 (latency to fetch first node) [ 12.308209] non-cached: 24 cycles [ 12.308632] cached: 0 cycles [ 12.357715] augmented rbtree testing [ 12.365362] -> test 1 (latency of nnodes insert+delete): 20587 cycles [ 12.373176] -> test 2 (latency of nnodes cached insert+delete): 18195 cycles [ 12.440250] gpio_it87: no device [ 12.441263] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 12.442026] switchtec: loaded. [ 12.443275] rivafb_setup START [ 12.443644] nvidiafb_setup START [ 12.444096] VIA Graphics Integration Chipset framebuffer 2.4 initializing [ 12.445104] no IO addresses supplied [ 12.445712] hgafb: HGA card not detected. [ 12.446153] hgafb: probe of hgafb.0 failed with error -22 [ 12.447001] intel_idle: Please enable MWAIT in BIOS SETUP [ 12.447856] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 12.448704] ACPI: Power Button [PWRF] [ 12.449342] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1 [ 12.450150] ACPI: Power Button [PWRF] [ 12.451658] ioatdma: Intel(R) QuickData Technology Driver 4.00 [ 12.635512] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11 [ 13.057003] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 13.084020] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 13.085178] console [ttyS0] disabled [ 13.109089] console [ttyS0] enabled [ 13.136048] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 13.160684] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 13.163038] Non-volatile memory driver v1.3 [ 13.163717] Linux agpgart interface v0.103 [ 13.164573] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds). [ 13.165934] drm_mm: Testing DRM range manger (struct drm_mm), with random_seed=0xb1b9544d max_iterations=8192 max_prime=128 [ 13.167115] drm_mm: igt_sanitycheck - ok! [ 34.774604] drm_mm: lookup failed for node 0 + 1 [ 34.778491] [drm] amdgpu kernel modesetting enabled. [ 34.779755] [drm] Initialized vgem 1.0.0 20120112 for virtual device on minor 0 [ 34.781150] [drm] Found bochs VGA, ID 0xb0c0. [ 34.781658] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000. [ 34.782753] [TTM] Zone kernel: Available graphics memory: 173672 kiB [ 34.783516] [TTM] Initializing pool allocator [ 34.784032] [TTM] Initializing DMA pool allocator [ 34.788143] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device [ 34.788913] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 1 [ 34.790061] [TTM] Finalizing pool allocator [ 34.790541] [TTM] Finalizing DMA pool allocator [ 34.791141] [TTM] Zone kernel: Used memory at exit: 0 kiB [ 34.792216] [drm] Found bochs VGA, ID 0xb0c0. [ 34.792689] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000. [ 34.793612] [TTM] Zone kernel: Available graphics memory: 173672 kiB [ 34.794296] [TTM] Initializing pool allocator [ 34.794798] [TTM] Initializing DMA pool allocator [ 34.796318] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device [ 34.797083] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 1 [ 34.799449] MM: desc_per_page = 128 [ 34.801366] null: module loaded [ 34.801742] dummy-irq: no IRQ given. Use irq=N [ 34.802477] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti [ 34.803408] c2port c2port0: C2 port uc added [ 34.803886] c2port c2port0: uc flash has 30 blocks x 512 bytes (15360 bytes total) [ 34.806250] hp_sw: device handler registered [ 34.806770] emc: device handler registered [ 34.807231] st: Version 20160209, fixed bufsize 32768, s/g segs 256 [ 34.807990] osst :I: Tape driver with OnStream support version 0.99.4 [ 34.807990] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $ [ 34.809727] SCSI Media Changer driver v0.25 [ 34.811135] ata_piix 0000:00:01.1: version 2.13 [ 34.814328] scsi host0: ata_piix [ 34.815168] scsi host1: ata_piix [ 34.815621] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc080 irq 14 [ 34.816359] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc088 irq 15 [ 34.976971] WARNING: CPU: 0 PID: 1 at drivers/ata/libata-core.c:6613 ata_host_detach+0xe0/0x1d0 [ 34.978101] CPU: 0 PID: 1 Comm: swapper Not tainted 4.14.0-12891-gc8a0739 #1 [ 34.978888] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 34.979744] task: ffff880018c58040 task.stack: ffff880018c5c000 [ 34.980020] RIP: 0010:ata_host_detach+0xe0/0x1d0 [ 34.980020] RSP: 0000:ffff880018c5fd40 EFLAGS: 00010246 [ 34.980020] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000 [ 34.980020] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff83335ad8 [ 34.980020] RBP: ffff880018f28000 R08: 0000000000000001 R09: 0000000000000001 [ 34.980020] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 34.980020] R13: ffff880018075a18 R14: ffffffff8392f0d0 R15: ffffffff8392f148 [ 34.980020] FS: 0000000000000000(0000) GS:ffffffff8308a000(0000) knlGS:0000000000000000 [ 34.980020] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 34.980020] CR2: 0000000000000000 CR3: 0000000003066000 CR4: 00000000001406f0 [ 34.980020] Call Trace: [ 34.980020] pci_device_remove+0x8c/0x190 [ 34.980020] driver_probe_device+0x31a/0x5d0 [ 34.980020] ? driver_probe_device+0x5d0/0x5d0 [ 34.980020] __driver_attach+0xe2/0x130 [ 34.980020] bus_for_each_dev+0x83/0xf0 [ 34.980020] bus_add_driver+0x156/0x2c0 [ 34.980020] ? do_early_param+0xbb/0xbb [ 34.980020] driver_register+0xec/0x190 [ 34.980020] ? pdc_sata_pci_driver_init+0x1a/0x1a [ 34.980020] piix_init+0x1a/0x3c [ 34.980020] do_one_initcall+0xac/0x1c7 [ 34.980020] ? do_early_param+0xbb/0xbb [ 34.980020] kernel_init_freeable+0x116/0x1db [ 34.980020] ? rest_init+0xd0/0xd0 [ 34.980020] kernel_init+0xa/0x160 [ 34.980020] ret_from_fork+0x24/0x30 [ 34.980020] Code: c3 31 c9 31 d2 0f b6 db 89 44 24 04 89 de e8 48 30 2c ff 48 63 c3 48 83 c0 02 48 83 04 c5 28 f1 92 83 01 8b 44 24 04 85 c0 75 02 <0f> ff 48 c7 c7 a8 5a 33 83 31 c9 31 d2 89 de e8 1c 30 2c ff 48 [ 34.980020] ---[ end trace 792e53bfc1fc5f4c ]--- [ 34.997351] ata2.01: NODEV after polling detection [ 34.998186] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 34.999298] ata2.00: configured for MWDMA2 [ 35.000504] ata2.00: disabled [ 35.001606] scsi_eh_1 (198) used greatest stack depth: 14072 bytes left [ 35.005606] scsi host0: ata_piix [ 35.006406] scsi host1: ata_piix [ 35.006914] ata3: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc080 irq 14 [ 35.007665] ata4: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc088 irq 15 [ 35.010417] Rounding down aligned max_sectors from 4294967295 to 4294967288 [ 35.011788] SSFDC read-only Flash Translation layer [ 35.012444] mtdoops: mtd device (mtddev=name/number) must be supplied [ 35.013597] Ramix PMC551 PCI Mezzanine Ram Driver. (C) 1999,2000 Nortel Networks. [ 35.014429] pmc551: not detected [ 35.015724] ftl_cs: FTL header not found. [ 35.020017] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 35.021661] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 35.022379] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 35.074044] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 [ 35.076255] apanel: Fujitsu BIOS signature 'FJKEYINF' not found... [ 35.077644] i2c /dev entries driver [ 35.079800] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3 [ 35.081189] pps pps0: new PPS source ktimer [ 35.081651] pps pps0: ktimer PPS source registered [ 35.082202] Driver for 1-wire Dallas network protocol. [ 35.082874] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko [ 35.083830] __power_supply_register: Expected proper parent device for 'test_ac' [ 35.084658] power_supply test_ac: uevent [ 35.085126] power_supply test_ac: POWER_SUPPLY_NAME=test_ac [ 35.085704] __power_supply_register: Expected proper parent device for 'test_battery' [ 35.086664] power_supply test_battery: uevent [ 35.087160] power_supply test_battery: POWER_SUPPLY_NAME=test_battery [ 35.087985] (NULL device *): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info(). [ 35.089360] __power_supply_register: Expected proper parent device for 'test_usb' [ 35.090262] power_supply test_usb: uevent [ 35.090672] power_supply test_usb: POWER_SUPPLY_NAME=test_usb [ 35.092351] applesmc: supported laptop not found! [ 35.092906] applesmc: driver init failed (ret=-19)! [ 35.095316] pc87360: PC8736x not detected, module not inserted [ 35.096031] sch56xx_common: Unsupported device id: 0xff [ 35.096606] sch56xx_common: Unsupported device id: 0xff [ 35.097959] acquirewdt: WDT driver for Acquire single board computer initialising [ 35.098938] acquirewdt: I/O address 0x0043 already in use [ 35.099551] acquirewdt: probe of acquirewdt failed with error -5 [ 35.100208] power_supply test_ac: power_supply_changed [ 35.100228] power_supply test_ac: power_supply_changed_work [ 35.100246] power_supply test_ac: uevent [ 35.100247] power_supply test_ac: POWER_SUPPLY_NAME=test_ac [ 35.100259] power_supply test_ac: prop ONLINE=1 [ 35.103086] sp5100_tco: SP5100/SB800 TCO WatchDog Timer Driver v0.05 [ 35.103960] ib700wdt: WDT driver for IB700 single board computer initialising [ 35.104788] power_supply test_usb: power_supply_changed [ 35.104797] power_supply test_battery: power_supply_changed [ 35.104829] power_supply test_usb: power_supply_changed_work [ 35.104849] power_supply test_usb: uevent [ 35.104851] power_supply test_usb: POWER_SUPPLY_NAME=test_usb [ 35.104858] power_supply test_usb: prop ONLINE=1 [ 35.104867] power_supply test_battery: power_supply_changed_work [ 35.104881] power_supply test_battery: uevent [ 35.104883] power_supply test_battery: POWER_SUPPLY_NAME=test_battery [ 35.104886] power_supply test_battery: prop STATUS=Discharging [ 35.104889] power_supply test_battery: prop CHARGE_TYPE=Fast [ 35.104892] power_supply test_battery: prop HEALTH=Good [ 35.104894] power_supply test_battery: prop PRESENT=1 [ 35.104897] power_supply test_battery: prop TECHNOLOGY=Li-ion [ 35.104900] power_supply test_battery: prop CHARGE_FULL_DESIGN=100 [ 35.104902] power_supply test_battery: prop CHARGE_FULL=100 [ 35.104905] power_supply test_battery: prop CHARGE_NOW=50 [ 35.104907] power_supply test_battery: prop CAPACITY=50 [ 35.104910] power_supply test_battery: prop CAPACITY_LEVEL=Normal [ 35.104913] power_supply test_battery: prop TIME_TO_EMPTY_AVG=3600 [ 35.104915] power_supply test_battery: prop TIME_TO_FULL_NOW=3600 [ 35.104918] power_supply test_battery: prop MODEL_NAME=Test battery [ 35.104920] power_supply test_battery: prop MANUFACTURER=Linux [ 35.104923] power_supply test_battery: prop SERIAL_NUMBER=4.14.0-12891-gc8a0739 [ 35.104941] power_supply test_battery: prop TEMP=26 [ 35.104943] power_supply test_battery: prop VOLTAGE_NOW=3300 [ 35.121140] wafer5823wdt: WDT driver for Wafer 5823 single board computer initialising [ 35.121985] wafer5823wdt: I/O address 0x0443 already in use [ 35.122575] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11 [ 35.123323] it87_wdt: no device [ 35.123663] sc1200wdt: build 20020303 [ 35.124132] sc1200wdt: io parameter must be specified [ 35.124687] pc87413_wdt: Version 1.1 at io 0x2E [ 35.125177] pc87413_wdt: cannot register miscdev on minor=130 (err=-16) [ 35.125872] nv_tco: NV TCO WatchDog Timer Driver v0.01 [ 35.126592] sbc60xxwdt: I/O address 0x0443 already in use [ 35.127234] w83977f_wdt: driver v1.00 [ 35.127655] w83977f_wdt: cannot register miscdev on minor=130 (err=-16) [ 35.128350] machzwd: MachZ ZF-Logic Watchdog driver initializing [ 35.129042] machzwd: no ZF-Logic found [ 35.129453] sbc_epx_c3: cannot register miscdev on minor=130 (err=-16) [ 35.130333] EDAC DEBUG: i5000_init: MC: [ 35.130798] EDAC DEBUG: i5400_init: MC: [ 35.131267] EDAC DEBUG: i7300_init: [ 35.131735] EDAC DEBUG: i3000_init: i3000 pci_get_device fail [ 35.132436] EDAC DEBUG: i3200_init: i3200 pci_get_device fail [ 35.133126] EDAC DEBUG: x38_init: x38 pci_get_device fail [ 35.134200] leds_ss4200: no LED devices found [ 35.135473] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 35.136302] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 35.138012] fw_cfg: probe of QEMU0002:00 failed with error -16 [ 35.138768] hidraw: raw HID events driver (C) Jiri Kosina [ 35.140461] hdaps: supported laptop not found! [ 35.140982] hdaps: driver init failed (ret=-19)! [ 35.145267] Board is not the VME system controller [ 35.145781] VME geographical address is set to 0 [ 35.147757] CR/CSR Offset: 0 [ 35.148376] start plist test [ 35.153938] end plist test [ 35.155026] ... APIC ID: 00000000 (0) [ 35.155480] ... APIC VERSION: 01050014 [ 35.155865] 0000000000000000000000000000000000000000000000000000000000000000 [ 35.156021] 0000000000000000000000000000000000000000000000000000000000000000 [ 35.156021] 0000000000000000000000000000000000000000000000000000000000004000 [ 35.158144] number of MP IRQ sources: 15. [ 35.158606] number of IO-APIC #0 registers: 24. [ 35.159125] testing the IO APIC....................... [ 35.159692] IO APIC #0...... [ 35.160038] .... register #00: 00000000 [ 35.160469] ....... : physical APIC id: 00 [ 35.160967] ....... : Delivery Type: 0 [ 35.161418] ....... : LTS : 0 [ 35.161841] .... register #01: 00170011 [ 35.162274] ....... : max redirection entries: 17 [ 35.162847] ....... : PRQ implemented: 0 [ 35.163330] ....... : IO APIC version: 11 [ 35.163777] .... register #02: 00000000 [ 35.164221] ....... : arbitration: 00 [ 35.164663] .... IRQ redirection table: [ 35.165084] IOAPIC 0: [ 35.165321] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.166162] pin01, enabled , edge , high, V(24), IRR(0), S(0), logical , D(01), M(1) [ 35.167030] pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(1) [ 35.167888] pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.169300] ata4.01: NODEV after polling detection [ 35.169571] ata4.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 35.170016] ata4.00: configured for MWDMA2 [ 35.170766] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 [ 35.174178] pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.175066] pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.175860] pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.176733] pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.177570] pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.178411] pin09, enabled , level, high, V(20), IRR(0), S(0), logical , D(01), M(1) [ 35.179258] pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.180627] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray [ 35.180630] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 35.180986] sr 1:0:0:0: Attached scsi CD-ROM sr0 [ 35.183449] pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.184317] pin0c, enabled , edge , high, V(23), IRR(0), S(0), logical , D(01), M(1) [ 35.185165] pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.185988] pin0e, enabled , edge , high, V(21), IRR(0), S(0), logical , D(01), M(1) [ 35.186834] pin0f, enabled , edge , high, V(22), IRR(0), S(0), logical , D(01), M(1) [ 35.187705] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.188553] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.189437] pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.190278] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.191097] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.191907] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.193320] pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.194217] pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 35.195067] IRQ to pin mappings: [ 35.195455] IRQ0 -> 0:2 [ 35.195715] IRQ1 -> 0:1 [ 35.196044] IRQ3 -> 0:3 [ 35.196333] IRQ4 -> 0:4 [ 35.196654] IRQ5 -> 0:5 [ 35.196943] IRQ6 -> 0:6 [ 35.197199] IRQ7 -> 0:7 [ 35.197471] IRQ8 -> 0:8 [ 35.197735] IRQ9 -> 0:9 [ 35.198016] IRQ10 -> 0:10 [ 35.198310] IRQ11 -> 0:11 [ 35.198599] IRQ12 -> 0:12 [ 35.198900] IRQ13 -> 0:13 [ 35.199206] IRQ14 -> 0:14 [ 35.199481] IRQ15 -> 0:15 [ 35.199736] .................................... done. [ 35.200377] AVX version of gcm_enc/dec engaged. [ 35.200896] AES CTR mode by8 optimization enabled [ 35.204683] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray [ 35.205983] sr 1:0:0:0: Attached scsi CD-ROM sr0 [ 35.206683] sr 1:0:0:0: Attached scsi generic sg0 type 5 [ 35.208977] sched_clock: Marking stable (35208022618, 0)->(35897786671, -689764053) [ 35.210417] Loading compiled-in X.509 certificates [ 35.210975] page_owner is disabled [ 35.211732] Key type trusted registered [ 35.212532] Key type encrypted registered [ 35.213927] BIOS EDD facility v0.16 2004-Jun-25, 1 devices found [ 35.217605] Freeing unused kernel memory: 1352K [ 35.218139] Write protecting the kernel read-only data: 32768k [ 35.219661] Freeing unused kernel memory: 652K [ 35.226949] Freeing unused kernel memory: 1840K [ 35.235687] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 35.239731] mount (232) used greatest stack depth: 13984 bytes left ifconfig: socket: Address family not supported by protocol mkdir: can't create directory '/var/lock': No such file or directory mkdir: can't create directory '/var/lock/lkp-bootstrap.lock': No such file or directory sleep: invalid number '333.3' / # PATH=/sbin:/usr/sbin:/bin:/usr/bin:/lkp/lkp/src/bin LKP: HOSTNAME vm-ivb41-yocto-ia32-10, MAC , kernel , serial console /dev/ttyS0 [ 35.285192] ip (267) used greatest stack depth: 13896 bytes left [ 36.273620] Kernel tests: Boot OK! [ 36.273620] LKP: waiting for network... [ 52.408066] cat (347) used greatest stack depth: 13888 bytes left [ 55.514746] sysrq: SysRq : Emergency Sync [ 55.517154] Emergency Sync complete [ 55.517617] sysrq: SysRq : Resetting Elapsed time: 60 #!/bin/bash # To reproduce, # 1) save job-script and this script (both are attached in 0day report email) # 2) run this script with your compiled kernel and optional env $INSTALL_MOD_PATH kernel=$1 initrds=( /osimage/yocto/yocto-tiny-i386-2016-04-22.cgz /lkp/lkp/lkp-i386.cgz /osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz /osimage/pkg/debian-x86_64-2016-08-31.cgz/trinity-static-i386-x86_64-6ddabfd2_2017-11-10.cgz ) HTTP_PREFIX=https://github.com/0day-ci/lkp-qemu/raw/master wget --timestamping "${initrds[@]/#/$HTTP_PREFIX}" { cat "${initrds[@]//*\//}" [[ $INSTALL_MOD_PATH ]] && ( cd "$INSTALL_MOD_PATH" find lib | cpio -o -H newc --quiet | gzip ) echo job-script | cpio -o -H newc --quiet | gzip } > initrd.img qemu-img create -f qcow2 disk-vm-ivb41-yocto-ia32-10-0 256G kvm=( qemu-system-x86_64 -enable-kvm -cpu IvyBridge -kernel $kernel -initrd initrd.img -m 420 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -drive file=disk-vm-ivb41-yocto-ia32-10-0,media=disk,if=virtio -serial stdio -display none -monitor null ) append=( ip=::::vm-ivb41-yocto-ia32-10::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=x86_64 kconfig=x86_64-randconfig-h0-11121445 branch=linus/master commit=c8a0739b185d11d6e2ca7ad9f5835841d1cfc765 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-h0-11121445/gcc-6/c8a0739b185d11d6e2ca7ad9f5835841d1cfc765/vmlinuz-4.14.0-12891-gc8a0739 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/x86_64-randconfig-h0-11121445/gcc-6/c8a0739b185d11d6e2ca7ad9f5835841d1cfc765/0 result_service=tmpfs debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw drbd.minor_count=8 ) "${kvm[@]}" -append "${append[*]}"