early console in setup code
early console in extract_kernel
input_data: 0x0000000001fb43b4
input_len: 0x0000000000776ad8
output: 0x0000000001000000
output_len: 0x00000000017188c8
kernel_total_size: 0x0000000001407000

Decompressing Linux... Parsing ELF... done.
Booting the kernel.
[    0.000000] Linux version 4.12.0-rc2-00039-gbeeeccc (kbuild@lkp-wsx02) (gcc version 6.2.0 20160901 (Debian 6.2.0-3) ) #1 SMP Tue May 30 14:14:11 CST 2017
[    0.000000] Command line: ip=::::vm-lkp-wsx03-8G-9::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml ARCH=x86_64 kconfig=x86_64-kexec branch=linux-devel/devel-hourly-2017053006 commit=beeeccca9bebcec386cc31c250cff8a06cf27034 BOOT_IMAGE=/pkg/linux/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/vmlinuz-4.12.0-rc2-00039-gbeeeccc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-8G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/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 earlyprintk=ttyS0,115200 console=ttyS0,115200 console=tty0 vga=normal rw
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffddfff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bffde000-0x00000000bfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable
[    0.000000] tsc: Fast TSC calibration failed
[    0.000000] tsc: Unable to calibrate against PIT
[    0.000000] tsc: No reference (HPET/PMTIMER) available
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 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] AGP: No AGP bridge found
[    0.000000] e820: last_pfn = 0x240000 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 00C0000000 mask FFC0000000 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  WC  UC- UC  
[    0.000000] e820: last_pfn = 0xbffde max_arch_pfn = 0x400000000
[    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 [ffff8800000f6b80]
[    0.000000]   mpc: f6b90-f6c7c
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] BRK [0x023e2000, 0x023e2fff] PGTABLE
[    0.000000] BRK [0x023e3000, 0x023e3fff] PGTABLE
[    0.000000] BRK [0x023e4000, 0x023e4fff] PGTABLE
[    0.000000] BRK [0x023e5000, 0x023e5fff] PGTABLE
[    0.000000] BRK [0x023e6000, 0x023e6fff] PGTABLE
[    0.000000] BRK [0x023e7000, 0x023e7fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x75489000-0x7fffffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6940 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x00000000BFFE1936 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x00000000BFFE180A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x00000000BFFE0040 0017CA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x00000000BFFE0000 000040
[    0.000000] ACPI: APIC 0x00000000BFFE187E 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x00000000BFFE18FE 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fd000 (        fee00000)
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000023fffffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x23fff6000-0x23fffafff]
[    0.000000] cma: Reserved 200 MiB at 0x0000000233400000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 2:3ffee001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 4762767440 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x000000023fffffff]
[    0.000000]   Device   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x00000000bffddfff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000023fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000023fffffff]
[    0.000000] On node 0 totalpages: 2097020
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 12224 pages used for memmap
[    0.000000]   DMA32 zone: 782302 pages, LIFO batch:31
[    0.000000]   Normal zone: 20480 pages used for memmap
[    0.000000]   Normal zone: 1310720 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000)
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xbffde000-0xbfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff]
[    0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff]
[    0.000000] e820: [mem 0xc0000000-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:512 nr_cpumask_bits:512 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 37 pages/cpu @ffff88023fc00000 s112664 r8192 d30696 u1048576
[    0.000000] pcpu-alloc: s112664 r8192 d30696 u1048576 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 23fc0ca40
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 2064231
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: ip=::::vm-lkp-wsx03-8G-9::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml ARCH=x86_64 kconfig=x86_64-kexec branch=linux-devel/devel-hourly-2017053006 commit=beeeccca9bebcec386cc31c250cff8a06cf27034 BOOT_IMAGE=/pkg/linux/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/vmlinuz-4.12.0-rc2-00039-gbeeeccc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-8G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/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 earlyprintk=ttyS0,115200 console=ttyS0,115200 console=tty0 vga=normal rw
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] AGP: Checking aperture...
[    0.000000] AGP: No AGP bridge found
[    0.000000] Memory: 7789780K/8388080K available (10243K kernel code, 1447K rwdata, 3608K rodata, 1564K init, 804K bss, 393500K reserved, 204800K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] ftrace: allocating 44665 entries in 175 pages
[    0.004000] Hierarchical RCU implementation.
[    0.004000] 	RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
[    0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.004000] NR_IRQS:33024 nr_irqs:440 16
[    0.004000] Console: colour VGA+ 80x25
[    0.004000] console [tty0] enabled
[    0.004000] bootconsole [earlyser0] disabled
[    0.000000] Linux version 4.12.0-rc2-00039-gbeeeccc (kbuild@lkp-wsx02) (gcc version 6.2.0 20160901 (Debian 6.2.0-3) ) #1 SMP Tue May 30 14:14:11 CST 2017
[    0.000000] Command line: ip=::::vm-lkp-wsx03-8G-9::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml ARCH=x86_64 kconfig=x86_64-kexec branch=linux-devel/devel-hourly-2017053006 commit=beeeccca9bebcec386cc31c250cff8a06cf27034 BOOT_IMAGE=/pkg/linux/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/vmlinuz-4.12.0-rc2-00039-gbeeeccc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-8G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/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 earlyprintk=ttyS0,115200 console=ttyS0,115200 console=tty0 vga=normal rw
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffddfff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bffde000-0x00000000bfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000023fffffff] usable
[    0.000000] tsc: Fast TSC calibration failed
[    0.000000] tsc: Unable to calibrate against PIT
[    0.000000] tsc: No reference (HPET/PMTIMER) available
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 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] AGP: No AGP bridge found
[    0.000000] e820: last_pfn = 0x240000 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 00C0000000 mask FFC0000000 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  WC  UC- UC  
[    0.000000] e820: last_pfn = 0xbffde max_arch_pfn = 0x400000000
[    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 [ffff8800000f6b80]
[    0.000000]   mpc: f6b90-f6c7c
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] BRK [0x023e2000, 0x023e2fff] PGTABLE
[    0.000000] BRK [0x023e3000, 0x023e3fff] PGTABLE
[    0.000000] BRK [0x023e4000, 0x023e4fff] PGTABLE
[    0.000000] BRK [0x023e5000, 0x023e5fff] PGTABLE
[    0.000000] BRK [0x023e6000, 0x023e6fff] PGTABLE
[    0.000000] BRK [0x023e7000, 0x023e7fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x75489000-0x7fffffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6940 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x00000000BFFE1936 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x00000000BFFE180A 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x00000000BFFE0040 0017CA (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x00000000BFFE0000 000040
[    0.000000] ACPI: APIC 0x00000000BFFE187E 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x00000000BFFE18FE 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff5fd000 (        fee00000)
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000023fffffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x23fff6000-0x23fffafff]
[    0.000000] cma: Reserved 200 MiB at 0x0000000233400000
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 2:3ffee001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 4762767440 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x000000023fffffff]
[    0.000000]   Device   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x00000000bffddfff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000023fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000023fffffff]
[    0.000000] On node 0 totalpages: 2097020
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 12224 pages used for memmap
[    0.000000]   DMA32 zone: 782302 pages, LIFO batch:31
[    0.000000]   Normal zone: 20480 pages used for memmap
[    0.000000]   Normal zone: 1310720 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000)
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xbffde000-0xbfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff]
[    0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff]
[    0.000000] e820: [mem 0xc0000000-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:512 nr_cpumask_bits:512 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 37 pages/cpu @ffff88023fc00000 s112664 r8192 d30696 u1048576
[    0.000000] pcpu-alloc: s112664 r8192 d30696 u1048576 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 23fc0ca40
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 2064231
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: ip=::::vm-lkp-wsx03-8G-9::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml ARCH=x86_64 kconfig=x86_64-kexec branch=linux-devel/devel-hourly-2017053006 commit=beeeccca9bebcec386cc31c250cff8a06cf27034 BOOT_IMAGE=/pkg/linux/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/vmlinuz-4.12.0-rc2-00039-gbeeeccc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-8G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/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 earlyprintk=ttyS0,115200 console=ttyS0,115200 console=tty0 vga=normal rw
[    0.000000] sysrq: sysrq always enabled.
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] AGP: Checking aperture...
[    0.000000] AGP: No AGP bridge found
[    0.000000] Memory: 7789780K/8388080K available (10243K kernel code, 1447K rwdata, 3608K rodata, 1564K init, 804K bss, 393500K reserved, 204800K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] ftrace: allocating 44665 entries in 175 pages
[    0.004000] Hierarchical RCU implementation.
[    0.004000] 	RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
[    0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.004000] NR_IRQS:33024 nr_irqs:440 16
[    0.004000] Console: colour VGA+ 80x25
[    0.004000] console [tty0] enabled
[    0.004000] bootconsole [earlyser0] disabled
[    0.004000] console [ttyS0] enabled
[    0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.004000] hpet clockevent registered
[    0.004064] tsc: Detected 2260.998 MHz processor
[    0.008035] Calibrating delay loop (skipped) preset value.. 4521.99 BogoMIPS (lpj=9043992)
[    0.012041] pid_max: default: 32768 minimum: 301
[    0.016162] ACPI: Core revision 20170303
[    0.038883] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.051721] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[    0.080101] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.097260] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.100197] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes)
[    0.109873] CPU: Physical Processor ID: 0
[    0.112252] mce: CPU supports 10 MCE banks
[    0.116449] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.124038] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.129237] Freeing SMP alternatives memory: 40K
[    0.148357] smpboot: Max logical packages: 2
[    0.158490] x2apic enabled
[    0.160039] Switched APIC routing to physical x2apic.
[    0.168224] enabled ExtINT on CPU#0
[    0.184580] ENABLING IO-APIC IRQs
[    0.188644] init IO_APIC IRQs
[    0.192039]  apic 0 pin 0 not connected
[    0.196166] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:0)
[    0.200159] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:0)
[    0.204000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:0)
[    0.204000]  apic 0 pin 16 not connected
[    0.204000]  apic 0 pin 17 not connected
[    0.204000]  apic 0 pin 18 not connected
[    0.204000]  apic 0 pin 19 not connected
[    0.204000]  apic 0 pin 20 not connected
[    0.204000]  apic 0 pin 21 not connected
[    0.204000]  apic 0 pin 22 not connected
[    0.204000]  apic 0 pin 23 not connected
[    0.204000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.204000] Using local APIC timer interrupts.
[    0.204000] calibrating APIC timer ...
[    0.208000] ... lapic delta = 6228611
[    0.208000] ... PM-Timer delta = 356900
[    0.208000] ... PM-Timer result ok
[    0.208000] ..... delta 6228611
[    0.208000] ..... mult: 267516805
[    0.208000] ..... calibration result: 3986311
[    0.208000] ..... CPU clock speed is 2254.1967 MHz.
[    0.208000] ..... host bus clock speed is 996.2311 MHz.
[    0.208545] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
[    0.216514] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
[    0.225427] smp: Bringing up secondary CPUs ...
[    0.228978] x86: Booting SMP configuration:
[    0.232061] .... node  #0, CPUs:      #1
[    0.004000] kvm-clock: cpu 1, msr 2:3ffee041, secondary cpu clock
[    0.004000] masked ExtINT on CPU#1
[    0.276126] KVM setup async PF for cpu 1
[    0.280000] kvm-stealtime: cpu 1, msr 23fd0ca40
[    0.308033] smp: Brought up 1 node, 2 CPUs
[    0.312033] smpboot: Total of 2 processors activated (9043.99 BogoMIPS)
[    0.325234] sched_clock: Marking stable (324000000, 0)->(6281573450, -5957573450)
[    0.371700] devtmpfs: initialized
[    0.387836] x86/mm: Memory block size: 128MB
[    0.463659] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.499924] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.530871] xor: measuring software checksum speed
[    0.586381]    prefetch64-sse:  1501.000 MB/sec
[    0.642221]    generic_sse:  1302.000 MB/sec
[    0.659620] xor: using function: prefetch64-sse (1501.000 MB/sec)
[    0.707806] NET: Registered protocol family 16
[    0.737979] cpuidle: using governor ladder
[    0.754027] cpuidle: using governor menu
[    0.769642] PCCT header not found.
[    0.791902] ACPI: bus type PCI registered
[    0.808950] PCI: Using configuration type 1 for base access
[    0.988987] raid6: sse2x1   gen()   487 MB/s
[    1.068808] raid6: sse2x1   xor()   265 MB/s
[    1.152477] raid6: sse2x2   gen()   612 MB/s
[    1.248169] raid6: sse2x2   xor()   410 MB/s
[    1.327894] raid6: sse2x4   gen()   543 MB/s
[    1.407638] raid6: sse2x4   xor()   389 MB/s
[    1.422155] raid6: using algorithm sse2x2 gen() 612 MB/s
[    1.437715] raid6: .... xor() 410 MB/s, rmw enabled
[    1.452755] raid6: using ssse3x2 recovery algorithm
[    1.468470] ACPI: Added _OSI(Module Device)
[    1.482367] ACPI: Added _OSI(Processor Device)
[    1.496566] ACPI: Added _OSI(3.0 _SCP Extensions)
[    1.511377] ACPI: Added _OSI(Processor Aggregator Device)
[    1.559634] ACPI: Interpreter enabled
[    1.576729] ACPI: (supports S0 S3 S4 S5)
[    1.590218] ACPI: Using IOAPIC for interrupt routing
[    1.621386] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    1.714903] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    1.733274] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    1.753274] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    1.772263] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    1.807156] PCI host bridge to bus 0000:00
[    1.822131] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    1.841545] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    1.859663] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    1.886428] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
[    1.913675] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.931368] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    1.955099] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    1.980113] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    2.059456] pci 0000:00:01.1: reg 0x20: [io  0xc080-0xc08f]
[    2.102484] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    2.125010] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    2.148963] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    2.167819] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    2.197249] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    2.220224] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    2.245301] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    2.268534] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    2.311966] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    2.373525] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    2.474217] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    2.494441] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    2.530699] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    2.565113] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    2.729306] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    2.770733] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    2.822097] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    2.867724] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    2.968481] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref]
[    3.039317] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000
[    3.067902] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f]
[    3.172500] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    3.195366] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    3.214752] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    3.234221] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    3.252856] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    3.272111] ACPI: Enabled 3 GPEs in block 00 to 0F
[    3.298385] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    3.316506] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    3.363262] pci 0000:00:02.0: vgaarb: bridge control possible
[    3.381673] vgaarb: loaded
[    3.394792] SCSI subsystem initialized
[    3.417997] libata version 3.00 loaded.
[    3.435324] ACPI: bus type USB registered
[    3.449746] usbcore: registered new interface driver usbfs
[    3.474248] usbcore: registered new interface driver hub
[    3.498455] usbcore: registered new device driver usb
[    3.522474] pps_core: LinuxPPS API ver. 1 registered
[    3.539709] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    3.595353] PTP clock support registered
[    3.609482] Advanced Linux Sound Architecture Driver Initialized.
[    3.797373] PCI: Using ACPI for IRQ routing
[    3.811888] PCI: pci_cache_line_size set to 64 bytes
[    3.828940] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    3.846178] e820: reserve RAM buffer [mem 0xbffde000-0xbfffffff]
[    3.890092] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    3.944590] clocksource: Switched to clocksource kvm-clock
[    4.154752] VFS: Disk quotas dquot_6.6.0
[    4.177795] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    4.206476] pnp: PnP ACPI init
[    4.232390] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    4.256669] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    4.289300] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    4.310105] pnp 00:03: [dma 2]
[    4.342289] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    4.375271] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    4.396626] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    4.421295] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    4.443135] pnp: PnP ACPI: found 7 devices
[    4.522106] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    4.552501] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    4.570322] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    4.587952] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    4.606617] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window]
[    4.625861] NET: Registered protocol family 2
[    4.642166] TCP established hash table entries: 65536 (order: 7, 524288 bytes)
[    4.696701] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    4.721232] TCP: Hash tables configured (established 65536 bind 65536)
[    4.741975] UDP hash table entries: 4096 (order: 5, 131072 bytes)
[    4.763006] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
[    4.786306] NET: Registered protocol family 1
[    4.808926] RPC: Registered named UNIX socket transport module.
[    4.860287] RPC: Registered udp transport module.
[    4.878561] RPC: Registered tcp transport module.
[    4.896910] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    4.919138] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    4.941018] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    4.959485] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    4.979895] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    5.024779] PCI: CLS 0 bytes, default 64
[    5.039921] Unpacking initramfs...
[   35.717533] Freeing initrd memory: 175580K
[   35.731371] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[   35.748894] software IO TLB [mem 0xbbfde000-0xbffde000] (64MB) mapped at [ffff8800bbfde000-ffff8800bffddfff]
[   35.789894] Scanning for low memory corruption every 60 seconds
[   35.811411] audit: initializing netlink subsys (disabled)
[   35.826711] audit: type=2000 audit(1496125016.859:1): state=initialized audit_enabled=0 res=1
[   35.827548] workingset: timestamp_bits=40 max_order=21 bucket_order=0
[   35.850013] NFS: Registering the id_resolver key type
[   35.850108] Key type id_resolver registered
[   35.850110] Key type id_legacy registered
[   35.850142] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[   35.851375] SGI XFS with security attributes, no debug enabled
[   35.874025] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[   35.874154] io scheduler noop registered
[   35.874178] io scheduler deadline registered (default)
[   35.874181] io scheduler mq-deadline registered
[   35.874183] io scheduler kyber registered
[   35.882110] gpio-mockup: probe of gpio-mockup failed with error -22
[   35.883766] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   35.894015] ACPI: Power Button [PWRF]
[   36.038772] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[   36.101599] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[   36.183629] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[   36.186680] Initializing Nozomi driver 2.1d
[   36.187127] Non-volatile memory driver v1.3
[   36.187489] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
[   36.213499] loop: module loaded
[   36.246392] rdac: device handler registered
[   36.246557] hp_sw: device handler registered
[   36.246580] emc: device handler registered
[   36.246814] alua: device handler registered
[   36.247456] ata_piix 0000:00:01.1: version 2.13
[   36.288889] scsi host0: ata_piix
[   36.289697] scsi host1: ata_piix
[   36.290023] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc080 irq 14
[   36.290087] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc088 irq 15
[   36.294847] tun: Universal TUN/TAP device driver, 1.6
[   36.295847] pcnet32: pcnet32.c:v1.35 21.Apr.2008 tsbogend@alpha.franken.de
[   36.296012] Atheros(R) L2 Ethernet Driver - version 2.2.3
[   36.296014] Copyright (c) 2007 Atheros Corporation.
[   36.296720] cnic: QLogic cnicDriver v2.5.22 (July 20, 2015)
[   36.296922] bnx2x: QLogic 5771x/578xx 10/20-Gigabit Ethernet Driver bnx2x 1.712.30-0 (2014/02/10)
[   36.297920] enic: Cisco VIC Ethernet NIC Driver, ver 2.3.0.31
[   36.298153] dmfe: Davicom DM9xxx net driver, version 1.36.4 (2002-01-17)
[   36.298276] v1.01-e (2.4 port) Sep-11-2006  Donald Becker <becker@scyld.com>
[   36.298276]   http://www.scyld.com/network/drivers.html
[   36.298671] uli526x: ULi M5261/M5263 net driver, version 0.9.3 (2005-7-29)
[   36.299581] vxge: Copyright(c) 2002-2010 Exar Corp.
[   36.299583] vxge: Driver version: 2.5.3.22640-k
[   36.299774] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
[   36.299776] e100: Copyright(c) 1999-2006 Intel Corporation
[   36.299902] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[   36.299903] e1000: Copyright (c) 1999-2006 Intel Corporation.
[   36.481183] ata2.01: NODEV after polling detection
[   36.484715] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[   36.498322] ata2.00: configured for MWDMA2
[   36.528202] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[   36.737863] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[   37.776637] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[   37.794623] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[   37.812773] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[   37.830204] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[   37.846567] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k
[   37.865595] igb: Copyright (c) 2007-2014 Intel Corporation.
[   37.881206] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[   37.925244] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[   37.948164] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.0.0-k
[   37.970841] ixgbe: Copyright (c) 1999-2016 Intel Corporation.
[   37.994901] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI
[   38.015778] ixgb: Copyright (c) 1999-2008 Intel Corporation.
[   38.032920] jme: JMicron JMC2XX ethernet driver version 1.0.8
[   38.048344] sky2: driver version 1.30
[   38.060916] myri10ge: Version 1.5.3-1.534
[   38.073848] ns83820.c: National Semiconductor DP83820 10/100/1000 driver.
[   38.091989] QLogic 1/10 GbE Converged/Intelligent Ethernet Driver v5.3.66
[   38.123327] QLogic/NetXen Network Driver v4.0.82
[   38.138948] tehuti: Tehuti Networks(R) Network Driver, 7.29.3
[   38.154862] tehuti: Options: hw_csum 
[   38.167693] tlan: ThunderLAN driver v1.17
[   38.180527] tlan: 0 devices installed, PCI: 0  EISA: 0
[   38.201917] PPP generic driver version 2.4.2
[   38.218367] PPP BSD Compression module registered
[   38.234186] PPP Deflate Compression module registered
[   38.250775] PPP MPPE Compression module registered
[   38.270184] NET: Registered protocol family 24
[   38.285380] SLIP: version 0.8.4-NET3.019-NEWTTY (dynamic channels, max=256) (6 bit encapsulation enabled).
[   38.316168] SLIP linefill/keepalive option.
[   38.330882] usbcore: registered new interface driver catc
[   38.348097] usbcore: registered new interface driver kaweth
[   38.365171] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver
[   38.403282] usbcore: registered new interface driver pegasus
[   38.421234] usbcore: registered new interface driver rtl8150
[   38.438572] usbcore: registered new interface driver asix
[   38.455680] usbcore: registered new interface driver ax88179_178a
[   38.473927] usbcore: registered new interface driver cdc_ether
[   38.491492] usbcore: registered new interface driver cdc_eem
[   38.509088] usbcore: registered new interface driver dm9601
[   38.538588] usbcore: registered new interface driver smsc75xx
[   38.555830] usbcore: registered new interface driver smsc95xx
[   38.572654] usbcore: registered new interface driver gl620a
[   38.589343] usbcore: registered new interface driver net1080
[   38.605834] usbcore: registered new interface driver plusb
[   38.622072] usbcore: registered new interface driver rndis_host
[   38.639214] usbcore: registered new interface driver cdc_subset
[   38.656097] usbcore: registered new interface driver zaurus
[   38.672297] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[   38.697778] usbcore: registered new interface driver int51x1
[   38.714398] usbcore: registered new interface driver kalmia
[   38.730504] usbcore: registered new interface driver ipheth
[   38.747001] usbcore: registered new interface driver sierra_net
[   38.764172] usbcore: registered new interface driver cx82310_eth
[   38.781441] usbcore: registered new interface driver cdc_ncm
[   38.797876] usbcore: registered new interface driver lg-vl600
[   38.817434] aoe: AoE v85 initialised.
[   38.830231] usbcore: registered new interface driver hwa-rc
[   38.852464] usbcore: registered new interface driver i1480-dfu-usb
[   38.869745] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   38.886271] ehci-pci: EHCI PCI platform driver
[   38.899004] uhci_hcd: USB Universal Host Controller Interface driver
[   38.914819] usbcore: registered new interface driver hwa-hc
[   38.931248] usbcore: registered new interface driver cdc_acm
[   38.947285] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[   39.118275] usbcore: registered new interface driver cdc_wdm
[   39.131695] usbcore: registered new interface driver usb-storage
[   39.148578] usbcore: registered new interface driver ums-alauda
[   39.165529] usbcore: registered new interface driver ums-cypress
[   39.191258] usbcore: registered new interface driver ums-datafab
[   39.208793] usbcore: registered new interface driver ums_eneub6250
[   39.244238] usbcore: registered new interface driver ums-freecom
[   39.267150] usbcore: registered new interface driver ums-isd200
[   39.283996] usbcore: registered new interface driver ums-jumpshot
[   39.300395] usbcore: registered new interface driver ums-karma
[   39.316990] usbcore: registered new interface driver ums-onetouch
[   39.335815] usbcore: registered new interface driver ums-realtek
[   39.352571] usbcore: registered new interface driver ums-sddr09
[   39.367607] usbcore: registered new interface driver ums-sddr55
[   39.384528] usbcore: registered new interface driver ums-usbat
[   39.401540] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[   39.450402] serio: i8042 KBD port at 0x60,0x64 irq 1
[   39.465929] serio: i8042 AUX port at 0x60,0x64 irq 12
[   39.486384] mousedev: PS/2 mouse device common for all mice
[   39.509813] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[   39.540989] rtc_cmos 00:00: RTC can wake from S4
[   39.562776] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[   39.588942] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[   39.631272] hidraw: raw HID events driver (C) Jiri Kosina
[   39.650388] usbcore: registered new interface driver usbhid
[   39.666369] usbhid: USB HID core driver
[   39.689504] NET: Registered protocol family 17
[   39.710826] Key type dns_resolver registered
[   39.736472] ... APIC ID:      00000000 (0)
[   39.749622] ... APIC VERSION: 01050014
[   39.761817] 0000000000000000000000000000000000000000000000000000000000000000
[   39.780353] 0000000000000000000000000000000000000000000000000000000000000000
[   39.798984] 0000000000000000000000000000000000000000000000000000000000008000
[   39.818526] number of MP IRQ sources: 15.
[   39.832362] number of IO-APIC #0 registers: 24.
[   39.847262] testing the IO APIC.......................
[   39.863308] IO APIC #0......
[   39.875392] .... register #00: 00000000
[   39.889103] .......    : physical APIC id: 00
[   39.903172] .......    : Delivery Type: 0
[   39.916971] .......    : LTS          : 0
[   39.930944] .... register #01: 00170011
[   39.944455] .......     : max redirection entries: 17
[   39.960560] .......     : PRQ implemented: 0
[   39.974946] .......     : IO APIC version: 11
[   39.990263] .... register #02: 00000000
[   40.003171] .......     : arbitration: 00
[   40.015547] .... IRQ redirection table:
[   40.033684] IOAPIC 0:
[   40.045768]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.072414]  pin01, enabled , edge , high, V(31), IRR(0), S(0), physical, D(00), M(0)
[   40.098363]  pin02, enabled , edge , high, V(30), IRR(0), S(0), physical, D(00), M(0)
[   40.124712]  pin03, disabled, edge , high, V(33), IRR(0), S(0), physical, D(00), M(0)
[   40.151260]  pin04, disabled, edge , high, V(34), IRR(0), S(0), physical, D(00), M(0)
[   40.177983]  pin05, disabled, level, high, V(35), IRR(0), S(0), physical, D(00), M(0)
[   40.205085]  pin06, enabled , edge , high, V(36), IRR(0), S(0), physical, D(00), M(0)
[   40.253431]  pin07, enabled , edge , high, V(37), IRR(0), S(0), physical, D(00), M(0)
[   40.295554]  pin08, enabled , edge , high, V(38), IRR(0), S(0), physical, D(00), M(0)
[   40.321218]  pin09, enabled , level, high, V(39), IRR(0), S(0), physical, D(00), M(0)
[   40.346592]  pin0a, disabled, level, high, V(3A), IRR(0), S(0), physical, D(00), M(0)
[   40.372096]  pin0b, disabled, level, high, V(3B), IRR(0), S(0), physical, D(00), M(0)
[   40.397793]  pin0c, enabled , edge , high, V(3C), IRR(0), S(0), physical, D(00), M(0)
[   40.423105]  pin0d, enabled , edge , high, V(3D), IRR(0), S(0), physical, D(00), M(0)
[   40.448511]  pin0e, enabled , edge , high, V(3E), IRR(0), S(0), physical, D(00), M(0)
[   40.474074]  pin0f, enabled , edge , high, V(3F), IRR(0), S(0), physical, D(00), M(0)
[   40.517401]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.543425]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.580491]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.606880]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.643398]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.686398]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.712265]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.738024]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0)
[   40.763791] IRQ to pin mappings:
[   40.791266] IRQ0 -> 0:2
[   40.802930] IRQ1 -> 0:1
[   40.814633] IRQ3 -> 0:3
[   40.825679] IRQ4 -> 0:4
[   40.836506] IRQ5 -> 0:5
[   40.847390] IRQ6 -> 0:6
[   40.858252] IRQ7 -> 0:7
[   40.869107] IRQ8 -> 0:8
[   40.879962] IRQ9 -> 0:9
[   40.891428] IRQ10 -> 0:10
[   40.902486] IRQ11 -> 0:11
[   40.913378] IRQ12 -> 0:12
[   40.924330] IRQ13 -> 0:13
[   40.935477] IRQ14 -> 0:14
[   40.946624] IRQ15 -> 0:15
[   40.957801] .................................... done.
[   40.974882] registered taskstats version 1
[   40.994227] Btrfs loaded, crc32c=crc32c-generic, integrity-checker=on
[   41.011723] BTRFS: selftest: sectorsize: 4096  nodesize: 4096
[   41.027994] BTRFS: selftest: Running btrfs free space cache tests
[   41.045165] BTRFS: selftest: Running extent only tests
[   41.088787] BTRFS: selftest: Running bitmap only tests
[   41.105599] BTRFS: selftest: Running bitmap and extent tests
[   41.122098] BTRFS: selftest: Running space stealing from bitmap to extent
[   41.147152] BTRFS: selftest: Free space cache tests finished
[   41.163509] BTRFS: selftest: Running extent buffer operation tests
[   41.180529] BTRFS: selftest: Running btrfs_split_item tests
[   41.206449] BTRFS: selftest: Running extent I/O tests
[   41.228359] BTRFS: selftest: Running find delalloc tests
[   41.247794] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[   42.025091] BTRFS: selftest: Running extent buffer bitmap tests
[   42.382251] BTRFS: selftest: Extent I/O tests finished
[   42.397739] BTRFS: selftest: Running btrfs_get_extent tests
[   42.415271] BTRFS: selftest: Running hole first btrfs_get_extent test
[   42.433579] BTRFS: selftest: Running outstanding_extents tests
[   42.450656] BTRFS: selftest: Running qgroup tests
[   42.465467] BTRFS: selftest: Qgroup basic add
[   42.479944] BTRFS: selftest: Qgroup multiple refs test
[   42.496221] BTRFS: selftest: Running free space tree tests
[   42.513163] ------------[ cut here ]------------
[   42.538761] WARNING: CPU: 1 PID: 1 at mm/util.c:357 kvmalloc_node+0x24/0x7f
[   42.566115] Modules linked in:
[   42.579911] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 4.12.0-rc2-00039-gbeeeccc #1
[   42.605667] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[   42.635708] task: ffff88022a2c0000 task.stack: ffffc90000c54000
[   42.652751] RIP: 0010:kvmalloc_node+0x24/0x7f
[   42.667140] RSP: 0000:ffffc90000c57b90 EFLAGS: 00010283
[   42.683218] RAX: 0000000001400040 RBX: 0000000001408040 RCX: 0000000000001000
[   42.701823] RDX: 00000000ffffffff RSI: 0000000001408040 RDI: 0000000000000001
[   42.720999] RBP: ffffc90000c57ba8 R08: 0000000000000000 R09: 0000000000000011
[   42.740154] R10: ffff88020554d010 R11: 0000000000001000 R12: 0000000000000001
[   42.758754] R13: 00000000ffffffff R14: ffff88020a0f2000 R15: 0000000000000000
[   42.778133] FS:  0000000000000000(0000) GS:ffff88023fd00000(0000) knlGS:0000000000000000
[   42.804770] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   42.821362] CR2: ffffc90000ce0000 CR3: 0000000002011000 CR4: 00000000000006e0
[   42.840607] Call Trace:
[   42.851886]  convert_free_space_to_bitmaps+0x56/0x390
[   42.867254]  ? btrfs_get_token_32+0x7a/0xcc
[   42.881701]  check_free_space_extents+0xbf/0x100
[   42.896515]  test_empty_block_group+0x3a/0x46
[   42.911003]  run_test+0x23a/0x2e0
[   42.923478]  ? run_test+0x23a/0x2e0
[   42.936727]  ? test_remove_all+0x83/0x83
[   42.950403]  ? test_remove_all+0x83/0x83
[   42.964114]  ? test_remove_all+0x83/0x83
[   42.977599]  run_test_both_formats+0x2e/0x98
[   42.991788]  ? test_remove_all+0x83/0x83
[   43.005463]  btrfs_test_free_space_tree+0x55/0x8d
[   43.020567]  ? test_remove_all+0x83/0x83
[   43.034244]  ? test_remove_beginning+0x9e/0x9e
[   43.048722]  ? test_remove_end+0xaa/0xaa
[   43.062722]  ? test_remove_middle+0xb2/0xb2
[   43.076884]  ? test_merge_left+0xed/0xed
[   43.090200]  ? test_merge_right+0xf2/0xf2
[   43.267571]  ? test_merge_both+0x106/0x106
[   43.281697]  ? test_merge_none+0x128/0x128
[   43.295691]  ? check_free_space_extents+0x100/0x100
[   43.311023]  btrfs_run_sanity_tests+0xec/0x144
[   43.325511]  ? set_debug_rodata+0x17/0x17
[   43.339667]  init_btrfs_fs+0xd8/0x13d
[   43.353089]  ? trace_event_define_fields_qgroup_meta_reserve+0x8b/0x8b
[   43.371334]  do_one_initcall+0x90/0x137
[   43.385326]  ? set_debug_rodata+0x17/0x17
[   43.398999]  kernel_init_freeable+0x179/0x1fc
[   43.412840]  ? rest_init+0x87/0x87
[   43.424958]  kernel_init+0xe/0xfa
[   43.436923]  ret_from_fork+0x2c/0x40
[   43.449300] Code: e8 de ec 00 00 5d c3 0f 1f 44 00 00 89 f0 55 25 c0 00 40 01 3d c0 00 40 01 48 89 e5 41 55 41 54 41 89 d5 53 49 89 fc 89 f3 74 02 <0f> ff 49 81 fc 00 10 00 00 89 de 76 1d 0f ba e3 0a 73 0f 81 ce 
[   43.500146] ---[ end trace b527b0c96cf35883 ]---
[   43.795684] BTRFS: selftest: sectorsize: 4096  nodesize: 8192
[   43.830458] BTRFS: selftest: Running btrfs free space cache tests
[   43.847790] BTRFS: selftest: Running extent only tests
[   43.863678] BTRFS: selftest: Running bitmap only tests
[   43.879328] BTRFS: selftest: Running bitmap and extent tests
[   43.896564] BTRFS: selftest: Running space stealing from bitmap to extent
[   43.917664] BTRFS: selftest: Free space cache tests finished
[   43.934254] BTRFS: selftest: Running extent buffer operation tests
[   43.951236] BTRFS: selftest: Running btrfs_split_item tests
[   43.967378] BTRFS: selftest: Running extent I/O tests
[   43.991323] BTRFS: selftest: Running find delalloc tests
[   44.754718] BTRFS: selftest: Running extent buffer bitmap tests
[   45.093510] BTRFS: selftest: Extent I/O tests finished
[   45.111886] BTRFS: selftest: Running btrfs_get_extent tests
[   45.130774] BTRFS: selftest: Running hole first btrfs_get_extent test
[   45.150319] BTRFS: selftest: Running outstanding_extents tests
[   45.167333] BTRFS: selftest: Running qgroup tests
[   45.181945] BTRFS: selftest: Qgroup basic add
[   45.196497] BTRFS: selftest: Qgroup multiple refs test
[   45.212635] BTRFS: selftest: Running free space tree tests
[   45.501927] BTRFS: selftest: sectorsize: 4096  nodesize: 16384
[   45.518615] BTRFS: selftest: Running btrfs free space cache tests
[   45.536506] BTRFS: selftest: Running extent only tests
[   45.551732] BTRFS: selftest: Running bitmap only tests
[   45.567638] BTRFS: selftest: Running bitmap and extent tests
[   45.591265] BTRFS: selftest: Running space stealing from bitmap to extent
[   45.612654] BTRFS: selftest: Free space cache tests finished
[   45.629341] BTRFS: selftest: Running extent buffer operation tests
[   45.655474] BTRFS: selftest: Running btrfs_split_item tests
[   45.671964] BTRFS: selftest: Running extent I/O tests
[   45.698830] BTRFS: selftest: Running find delalloc tests
[   46.490507] BTRFS: selftest: Running extent buffer bitmap tests
[   46.824699] BTRFS: selftest: Extent I/O tests finished
[   46.841360] BTRFS: selftest: Running btrfs_get_extent tests
[   46.859243] BTRFS: selftest: Running hole first btrfs_get_extent test
[   46.878334] BTRFS: selftest: Running outstanding_extents tests
[   46.898260] BTRFS: selftest: Running qgroup tests
[   46.913844] BTRFS: selftest: Qgroup basic add
[   46.929464] BTRFS: selftest: Qgroup multiple refs test
[   46.946190] BTRFS: selftest: Running free space tree tests
[   47.278993] BTRFS: selftest: sectorsize: 4096  nodesize: 32768
[   47.314773] BTRFS: selftest: Running btrfs free space cache tests
[   47.332886] BTRFS: selftest: Running extent only tests
[   47.348442] BTRFS: selftest: Running bitmap only tests
[   47.364000] BTRFS: selftest: Running bitmap and extent tests
[   47.379973] BTRFS: selftest: Running space stealing from bitmap to extent
[   47.401733] BTRFS: selftest: Free space cache tests finished
[   47.418460] BTRFS: selftest: Running extent buffer operation tests
[   47.435172] BTRFS: selftest: Running btrfs_split_item tests
[   47.451252] BTRFS: selftest: Running extent I/O tests
[   47.466833] BTRFS: selftest: Running find delalloc tests
[   48.239468] BTRFS: selftest: Running extent buffer bitmap tests
[   48.577344] BTRFS: selftest: Extent I/O tests finished
[   48.593027] BTRFS: selftest: Running btrfs_get_extent tests
[   48.609458] BTRFS: selftest: Running hole first btrfs_get_extent test
[   48.626115] BTRFS: selftest: Running outstanding_extents tests
[   48.644811] BTRFS: selftest: Running qgroup tests
[   48.662003] BTRFS: selftest: Qgroup basic add
[   48.676850] BTRFS: selftest: Qgroup multiple refs test
[   48.693212] BTRFS: selftest: Running free space tree tests
[   48.990216] BTRFS: selftest: sectorsize: 4096  nodesize: 65536
[   49.005864] BTRFS: selftest: Running btrfs free space cache tests
[   49.023201] BTRFS: selftest: Running extent only tests
[   49.038264] BTRFS: selftest: Running bitmap only tests
[   49.053633] BTRFS: selftest: Running bitmap and extent tests
[   49.070125] BTRFS: selftest: Running space stealing from bitmap to extent
[   49.091636] BTRFS: selftest: Free space cache tests finished
[   49.107517] BTRFS: selftest: Running extent buffer operation tests
[   49.124756] BTRFS: selftest: Running btrfs_split_item tests
[   49.141030] BTRFS: selftest: Running extent I/O tests
[   49.156677] BTRFS: selftest: Running find delalloc tests
[   49.965891] BTRFS: selftest: Running extent buffer bitmap tests
[   50.287647] BTRFS: selftest: Extent I/O tests finished
[   50.304985] BTRFS: selftest: Running btrfs_get_extent tests
[   50.323686] BTRFS: selftest: Running hole first btrfs_get_extent test
[   50.344975] BTRFS: selftest: Running outstanding_extents tests
[   50.362376] BTRFS: selftest: Running qgroup tests
[   50.378192] BTRFS: selftest: Qgroup basic add
[   50.392878] BTRFS: selftest: Qgroup multiple refs test
[   50.408851] BTRFS: selftest: Running free space tree tests
[   50.730575] console [netcon0] enabled
[   50.744774] netconsole: network logging started
[   50.760715] rtc_cmos 00:00: setting system clock to 2017-05-30 14:17:14 UTC (1496153834)
[   50.838877] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[   50.912496] Sending DHCP requests ., OK
[   50.940207] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15
[   50.965555] IP-Config: Complete:
[   50.978027]      device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2
[   51.007029]      host=vm-lkp-wsx03-8G-9, domain=, nis-domain=(none)
[   51.024694]      bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath=     nameserver0=10.0.2.3
[   51.062866] ALSA device list:
[   51.074956]   No soundcards found.
[   51.118536] Freeing unused kernel memory: 1564K
[   51.133418] Write protecting the kernel read-only data: 16384k
[   51.158266] Freeing unused kernel memory: 2032K
[   51.190286] Freeing unused kernel memory: 488K
[   51.204842] rodata_test: all tests were successful
[   51.250918] systemd[1]: RTC configured in localtime, applying delta of 480 minutes to system time.
[   51.291303] systemd[1]: Failed to insert module 'autofs4': No such file or directory
[   51.325005] random: systemd: uninitialized urandom read (16 bytes read)
[   51.361355] random: systemd: uninitialized urandom read (16 bytes read)
[   51.483918] random: systemd: uninitialized urandom read (16 bytes read)
[   51.535472] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
[   51.536901] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
[   51.542471] random: systemd-cryptse: uninitialized urandom read (16 bytes read)
[   51.665193] random: systemd: uninitialized urandom read (16 bytes read)
[   51.695627] random: systemd: uninitialized urandom read (16 bytes read)
[   51.713899] random: systemd: uninitialized urandom read (16 bytes read)
[   51.731466] random: systemd: uninitialized urandom read (16 bytes read)
[   54.453468] scsi 1:0:0:0: Attached scsi generic sg0 type 5
[   54.572236] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[   54.589353] cdrom: Uniform CD-ROM driver Revision: 3.20
[   54.674454] sr 1:0:0:0: Attached scsi CD-ROM sr0
LKP: HOSTNAME vm-lkp-wsx03-8G-9, MAC 52:54:00:12:34:56, kernel 4.12.0-rc2-00039-gbeeeccc 1, serial console /dev/ttyS0
[   57.160539] Kernel tests: Boot OK!
[   57.160568] 
[   57.388895] install debs round one: dpkg -i /opt/deb/libtext-charwidth-perl_0.04-7+b5_amd64.deb
[   57.388904] 
[   57.454259] /opt/deb/libtext-iconv-perl_1.7-5+b4_amd64.deb
[   57.454266] 
[   57.516736] /opt/deb/perl-base_5.24.1-2_amd64.deb
[   57.516743] 
[   57.554981] /opt/deb/liblocale-gettext-perl_1.07-3+b1_amd64.deb
[   57.555008] 
[   57.641875] /opt/deb/perl-modules-5.24_5.24.1-2_all.deb
[   57.641882] 
[   57.703578] /opt/deb/libgdbm3_1.8.3-14_amd64.deb
[   57.703607] 
[   57.742984] /opt/deb/libperl5.24_5.24.1-2_amd64.deb
[   57.743011] 
[   57.780740] /opt/deb/perl_5.24.1-2_amd64.deb
[   57.780767] 
[   57.833817] /opt/deb/libreadline7_7.0-2_amd64.deb
[   57.833845] 
[   57.913310] /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb
[   57.913338] 
[   57.991206] /opt/deb/libssl1.1_1.1.0e-1_amd64.deb
[   57.991213] 
[   58.025818] /opt/deb/libicu57_57.1-6_amd64.deb
[   58.025826] 
[   58.063859] /opt/deb/libxml2_2.9.4+dfsg1-2.2_amd64.deb
[   58.063866] 
[   58.103566] /opt/deb/openssl_1.1.0e-1_amd64.deb
[   58.103593] 
[   58.176863] /opt/deb/ca-certificates_20161130_all.deb
[   58.176870] 
[   58.255088] (Reading database ... 2184 files and directories currently installed.)
[   58.255096] 
[   58.323372] Preparing to unpack .../libtext-charwidth-perl_0.04-7+b5_amd64.deb ...
[   58.323399] 
[   58.414238] Unpacking libtext-charwidth-perl (0.04-7+b5) over (0.04-7+b4) ...
[   58.414265] 
[   58.489001] Preparing to unpack .../libtext-iconv-perl_1.7-5+b4_amd64.deb ...
[   58.489030] 
[   58.506535] Unpacking libtext-iconv-perl (1.7-5+b4) over (1.7-5+b3) ...
[   58.506563] 
[   58.521605] Preparing to unpack .../perl-base_5.24.1-2_amd64.deb ...
[   58.521632] 
[   58.534414] Unpacking perl-base (5.24.1-2) over (5.22.2-3) ...
[   58.534419] 
[   59.703220] Selecting previously unselected package perl-modules-5.24.
[   59.703250] 
[   59.748714] Preparing to unpack .../perl-modules-5.24_5.24.1-2_all.deb ...
[   59.748721] 
[   59.793470] Unpacking perl-modules-5.24 (5.24.1-2) ...
[   59.793497] 
[   63.332882] Selecting previously unselected package libgdbm3:amd64.
[   63.332912] 
[   63.382235] Preparing to unpack .../libgdbm3_1.8.3-14_amd64.deb ...
[   63.382264] 
[   63.418304] Unpacking libgdbm3:amd64 (1.8.3-14) ...
[   63.418332] 
[   63.490098] Selecting previously unselected package libperl5.24:amd64.
[   63.490126] 
[   63.503640] Preparing to unpack .../libperl5.24_5.24.1-2_amd64.deb ...
[   63.503667] 
[   63.513422] Unpacking libperl5.24:amd64 (5.24.1-2) ...
[   63.513427] 
[   69.101976] Selecting previously unselected package perl.
[   69.102006] 
[   69.147965] Preparing to unpack .../deb/perl_5.24.1-2_amd64.deb ...
[   69.147994] 
[   69.177739] Unpacking perl (5.24.1-2) ...
[   69.177746] 
[   69.405736] Selecting previously unselected package libreadline7:amd64.
[   69.405766] 
[   69.458455] Preparing to unpack .../libreadline7_7.0-2_amd64.deb ...
[   69.458484] 
[   69.483791] Unpacking libreadline7:amd64 (7.0-2) ...
[   69.483796] 
[   69.745141] Selecting previously unselected package libssl1.1:amd64.
[   69.745170] 
[   69.773267] Preparing to unpack .../libssl1.1_1.1.0e-1_amd64.deb ...
[   69.773295] 
[   69.830109] Unpacking libssl1.1:amd64 (1.1.0e-1) ...
[   69.830137] 
[   70.959749] Selecting previously unselected package libicu57:amd64.
[   70.959779] 
[   71.017406] Preparing to unpack .../deb/libicu57_57.1-6_amd64.deb ...
[   71.017433] 
[   71.051040] Unpacking libicu57:amd64 (57.1-6) ...
[   71.051169] 
[   80.305704] Selecting previously unselected package libxml2:amd64.
[   80.305712] 
[   80.348419] Preparing to unpack .../libxml2_2.9.4+dfsg1-2.2_amd64.deb ...
[   80.348447] 
[   80.386598] Unpacking libxml2:amd64 (2.9.4+dfsg1-2.2) ...
[   80.386624] 
[   81.384107] Selecting previously unselected package openssl.
[   81.384137] 
[   81.427135] Preparing to unpack .../deb/openssl_1.1.0e-1_amd64.deb ...
[   81.427142] 
[   81.462947] Unpacking openssl (1.1.0e-1) ...
[   81.462973] 
[   82.189830] Selecting previously unselected package ca-certificates.
[   82.189839] 
[   82.234680] Preparing to unpack .../ca-certificates_20161130_all.deb ...
[   82.234687] 
[   82.273248] Unpacking ca-certificates (20161130) ...
[   82.273275] 
[   82.497447] Setting up perl-base (5.24.1-2) ...
[   82.497477] 
[   82.543751] Setting up perl-modules-5.24 (5.24.1-2) ...
[   82.543779] 
[   82.580852] Setting up libgdbm3:amd64 (1.8.3-14) ...
[   82.580859] 
[   82.618185] Setting up libperl5.24:amd64 (5.24.1-2) ...
[   82.618191] 
[   82.651662] Setting up perl (5.24.1-2) ...
[   82.651668] 
[   82.714374] update-alternatives: using /usr/bin/prename to provide /usr/bin/rename (rename) in auto mode
[   82.714404] 
[   82.764498] Setting up libreadline7:amd64 (7.0-2) ...
[   82.764505] 
[   82.800469] Setting up libssl1.1:amd64 (1.1.0e-1) ...
[   82.800475] 
[   84.008579] Setting up libicu57:amd64 (57.1-6) ...
[   84.008608] 
[   84.043091] Setting up libxml2:amd64 (2.9.4+dfsg1-2.2) ...
[   84.043121] 
[   84.076565] Setting up openssl (1.1.0e-1) ...
[   84.076570] 
[   84.110208] Setting up ca-certificates (20161130) ...
[   84.110237] 
[  112.405335] Setting up libtext-charwidth-perl (0.04-7+b5) ...
[  112.405366] 
[  112.446420] Setting up libtext-iconv-perl (1.7-5+b4) ...
[  112.446426] 
[  112.485180] Processing triggers for libc-bin (2.23-5) ...
[  112.485186] 
[  112.526498] Processing triggers for ca-certificates (20161130) ...
[  112.526526] 
[  112.566677] Updating certificates in /etc/ssl/certs...
[  112.566684] 
[  117.774386] 0 added, 0 removed; done.
[  117.774416] 
[  117.815217] Running hooks in /etc/ca-certificates/update.d...
[  117.815244] 
[  117.845160] done.
[  117.845189] 
[  117.938855] dpkg: regarding .../liblocale-gettext-perl_1.07-3+b1_amd64.deb containing liblocale-gettext-perl, pre-dependency problem:
[  117.938864] 
[  118.005553]  liblocale-gettext-perl pre-depends on perl-base (>= 5.24.1~rc3-2)
[  118.005580] 
[  118.053350]   perl-base latest configured version is 5.22.2-3.
[  118.053377] 
[  118.080177] 
[  118.115396] dpkg: error processing archive /opt/deb/liblocale-gettext-perl_1.07-3+b1_amd64.deb (--install):
[  118.115424] 
[  118.167241]  pre-dependency problem - not installing liblocale-gettext-perl
[  118.167247] 
[  118.217113] dpkg: regarding .../gawk_1%3a4.1.4+dfsg-1_amd64.deb containing gawk, pre-dependency problem:
[  118.217121] 
[  118.265113]  gawk pre-depends on libreadline7 (>= 6.0)
[  118.265140] 
[  118.306847]   libreadline7:amd64 is unpacked, but has never been configured.
[  118.306854] 
[  118.334753] 
[  118.362556] dpkg: error processing archive /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb (--install):
[  118.362583] 
[  118.410361]  pre-dependency problem - not installing gawk
[  118.410388] 
[  118.444870] debconf: unable to initialize frontend: Dialog
[  118.444898] 
[  118.485570] debconf: (TERM is not set, so the dialog frontend is not usable.)
[  118.485598] 
[  118.520698] debconf: falling back to frontend: Readline
[  118.520703] 
[  118.557574] debconf: unable to initialize frontend: Readline
[  118.557581] 
[  118.595648] debconf: (This frontend requires a controlling tty.)
[  118.595654] 
[  118.630394] debconf: falling back to frontend: Teletype
[  118.630424] 
[  118.662699] debconf: unable to initialize frontend: Dialog
[  118.662705] 
[  118.700522] debconf: (TERM is not set, so the dialog frontend is not usable.)
[  118.700550] 
[  118.737466] debconf: falling back to frontend: Readline
[  118.737494] 
[  118.773398] debconf: unable to initialize frontend: Readline
[  118.773405] 
[  118.811612] debconf: (This frontend requires a controlling tty.)
[  118.811640] 
[  118.849391] debconf: falling back to frontend: Teletype
[  118.849398] 
[  118.885008] Updating certificates in /etc/ssl/certs...
[  118.885071] 
[  118.920751] 173 added, 0 removed; done.
[  118.920781] 
[  118.952862] Errors were encountered while processing:
[  118.952870] 
[  118.988581]  /opt/deb/liblocale-gettext-perl_1.07-3+b1_amd64.deb
[  118.988607] 
[  119.022443]  /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb
[  119.022470] 
[  119.051219] handle /opt/deb/keep-deb.lkp...
[  119.051246] 
[  119.093798] install debs round two: dpkg -i /opt/deb/libtext-charwidth-perl_0.04-7+b5_amd64.deb
[  119.093805] 
[  119.143961] (Reading database ... 5026 files and directories currently installed.)
[  119.143967] 
[  119.194231] Preparing to unpack .../libtext-charwidth-perl_0.04-7+b5_amd64.deb ...
[  119.194260] 
[  119.240726] Unpacking libtext-charwidth-perl (0.04-7+b5) over (0.04-7+b5) ...
[  119.240732] 
[  119.277577] Setting up libtext-charwidth-perl (0.04-7+b5) ...
[  119.277603] 
[  119.318023] install debs round two: dpkg -i /opt/deb/libtext-iconv-perl_1.7-5+b4_amd64.deb
[  119.318111] 
[  119.364714] (Reading database ... 5026 files and directories currently installed.)
[  119.364720] 
[  119.413524] Preparing to unpack .../libtext-iconv-perl_1.7-5+b4_amd64.deb ...
[  119.413552] 
[  119.453118] Unpacking libtext-iconv-perl (1.7-5+b4) over (1.7-5+b4) ...
[  119.453125] 
[  119.490712] Setting up libtext-iconv-perl (1.7-5+b4) ...
[  119.490739] 
[  119.533813] install debs round two: dpkg -i /opt/deb/perl-base_5.24.1-2_amd64.deb
[  119.533841] 
[  119.584625] (Reading database ... 5026 files and directories currently installed.)
[  119.584654] 
[  119.632906] Preparing to unpack .../perl-base_5.24.1-2_amd64.deb ...
[  119.632934] 
[  119.671892] Unpacking perl-base (5.24.1-2) over (5.24.1-2) ...
[  119.671919] 
[  120.466004] Setting up perl-base (5.24.1-2) ...
[  120.466101] 
[  120.514092] install debs round two: dpkg -i /opt/deb/liblocale-gettext-perl_1.07-3+b1_amd64.deb
[  120.514121] 
[  120.632466] (Reading database ... 5026 files and directories currently installed.)
[  120.632496] 
[  120.684646] Preparing to unpack .../liblocale-gettext-perl_1.07-3+b1_amd64.deb ...
[  120.684673] 
[  120.729495] Unpacking liblocale-gettext-perl (1.07-3+b1) over (1.07-3) ...
[  120.729500] 
[  120.935245] Setting up liblocale-gettext-perl (1.07-3+b1) ...
[  120.935274] 
[  120.983108] install debs round two: dpkg -i /opt/deb/perl-modules-5.24_5.24.1-2_all.deb
[  120.983137] 
[  121.040240] (Reading database ... 5038 files and directories currently installed.)
[  121.040246] 
[  121.092413] Preparing to unpack .../perl-modules-5.24_5.24.1-2_all.deb ...
[  121.092420] 
[  121.136622] Unpacking perl-modules-5.24 (5.24.1-2) over (5.24.1-2) ...
[  121.136650] 
[  124.028650] random: fast init done
[  124.264122] Setting up perl-modules-5.24 (5.24.1-2) ...
[  124.264152] 
[  124.306275] install debs round two: dpkg -i /opt/deb/libgdbm3_1.8.3-14_amd64.deb
[  124.306282] 
[  124.421146] (Reading database ... 5038 files and directories currently installed.)
[  124.421176] 
[  124.480413] Preparing to unpack .../libgdbm3_1.8.3-14_amd64.deb ...
[  124.480420] 
[  124.523258] Unpacking libgdbm3:amd64 (1.8.3-14) over (1.8.3-14) ...
[  124.523287] 
[  124.569018] Setting up libgdbm3:amd64 (1.8.3-14) ...
[  124.569084] 
[  124.607549] Processing triggers for libc-bin (2.23-5) ...
[  124.607578] 
[  124.664405] install debs round two: dpkg -i /opt/deb/libperl5.24_5.24.1-2_amd64.deb
[  124.664413] 
[  124.719016] (Reading database ... 5038 files and directories currently installed.)
[  124.719083] 
[  124.769080] Preparing to unpack .../libperl5.24_5.24.1-2_amd64.deb ...
[  124.769086] 
[  124.806999] Unpacking libperl5.24:amd64 (5.24.1-2) over (5.24.1-2) ...
[  124.807027] 
[  129.617594] Setting up libperl5.24:amd64 (5.24.1-2) ...
[  129.617625] 
[  129.655446] Processing triggers for libc-bin (2.23-5) ...
[  129.655474] 
[  129.697959] install debs round two: dpkg -i /opt/deb/perl_5.24.1-2_amd64.deb
[  129.697986] 
[  129.804936] (Reading database ... 5038 files and directories currently installed.)
[  129.804965] 
[  129.854717] Preparing to unpack .../deb/perl_5.24.1-2_amd64.deb ...
[  129.854745] 
[  129.893535] Unpacking perl (5.24.1-2) over (5.24.1-2) ...
[  129.893563] 
[  130.079222] Setting up perl (5.24.1-2) ...
[  130.079252] 
[  130.173884] install debs round two: dpkg -i /opt/deb/libreadline7_7.0-2_amd64.deb
[  130.173913] 
[  130.333012] (Reading database ... 5038 files and directories currently installed.)
[  130.333101] 
[  130.384862] Preparing to unpack .../libreadline7_7.0-2_amd64.deb ...
[  130.384890] 
[  130.425301] Unpacking libreadline7:amd64 (7.0-2) over (7.0-2) ...
[  130.425328] 
[  130.544025] Setting up libreadline7:amd64 (7.0-2) ...
[  130.544113] 
[  130.579182] Processing triggers for libc-bin (2.23-5) ...
[  130.579209] 
[  130.619489] install debs round two: dpkg -i /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb
[  130.619496] 
[  130.719462] (Reading database ... 5038 files and directories currently installed.)
[  130.719492] 
[  130.770700] Preparing to unpack .../gawk_1%3a4.1.4+dfsg-1_amd64.deb ...
[  130.770728] 
[  130.813021] Unpacking gawk (1:4.1.4+dfsg-1) over (1:4.1.1+dfsg-1) ...
[  130.813087] 
[  131.396004] Setting up gawk (1:4.1.4+dfsg-1) ...
[  131.396095] 
[  131.443288] install debs round two: dpkg -i /opt/deb/libssl1.1_1.1.0e-1_amd64.deb
[  131.443316] 
[  131.599076] (Reading database ... 5206 files and directories currently installed.)
[  131.599109] 
[  131.651544] Preparing to unpack .../libssl1.1_1.1.0e-1_amd64.deb ...
[  131.651573] 
[  131.694157] Unpacking libssl1.1:amd64 (1.1.0e-1) over (1.1.0e-1) ...
[  131.694184] 
[  132.797231] Setting up libssl1.1:amd64 (1.1.0e-1) ...
[  132.797261] 
[  134.488794] Processing triggers for libc-bin (2.23-5) ...
[  134.488824] 
[  134.531526] install debs round two: dpkg -i /opt/deb/libicu57_57.1-6_amd64.deb
[  134.531533] 
[  134.652628] (Reading database ... 5206 files and directories currently installed.)
[  134.652656] 
[  134.704862] Preparing to unpack .../deb/libicu57_57.1-6_amd64.deb ...
[  134.704868] 
[  134.743426] Unpacking libicu57:amd64 (57.1-6) over (57.1-6) ...
[  134.743454] 
[  143.278234] Setting up libicu57:amd64 (57.1-6) ...
[  143.278264] 
[  143.318310] Processing triggers for libc-bin (2.23-5) ...
[  143.318338] 
[  143.365457] install debs round two: dpkg -i /opt/deb/libxml2_2.9.4+dfsg1-2.2_amd64.deb
[  143.365488] 
[  143.451623] (Reading database ... 5206 files and directories currently installed.)
[  143.451650] 
[  143.501656] Preparing to unpack .../libxml2_2.9.4+dfsg1-2.2_amd64.deb ...
[  143.501684] 
[  143.545789] Unpacking libxml2:amd64 (2.9.4+dfsg1-2.2) over (2.9.4+dfsg1-2.2) ...
[  143.545796] 
[  144.406709] Setting up libxml2:amd64 (2.9.4+dfsg1-2.2) ...
[  144.406738] 
[  144.445302] Processing triggers for libc-bin (2.23-5) ...
[  144.445309] 
[  144.489280] install debs round two: dpkg -i /opt/deb/openssl_1.1.0e-1_amd64.deb
[  144.489308] 
[  144.582924] (Reading database ... 5206 files and directories currently installed.)
[  144.582953] 
[  144.630596] Preparing to unpack .../deb/openssl_1.1.0e-1_amd64.deb ...
[  144.630623] 
[  144.668534] Unpacking openssl (1.1.0e-1) over (1.1.0e-1) ...
[  144.668540] 
[  145.493536] Setting up openssl (1.1.0e-1) ...
[  145.493566] 
[  145.548890] install debs round two: dpkg -i /opt/deb/ca-certificates_20161130_all.deb
[  145.548920] 
[  145.702559] (Reading database ... 5206 files and directories currently installed.)
[  145.702590] 
[  145.761574] Preparing to unpack .../ca-certificates_20161130_all.deb ...
[  145.761602] 
[  145.824894] Unpacking ca-certificates (20161130) over (20161130) ...
[  145.824923] 
[  146.129111] Setting up ca-certificates (20161130) ...
[  146.129140] 
[  172.173660] Processing triggers for ca-certificates (20161130) ...
[  172.173690] 
[  172.218446] Updating certificates in /etc/ssl/certs...
[  172.218473] 
[  177.637468] 0 added, 0 removed; done.
[  177.637498] 
[  177.675960] Running hooks in /etc/ca-certificates/update.d...
[  177.675969] 
[  177.706298] done.
[  177.706326] 
[  177.804617] /lkp/lkp/src/bin/run-lkp
[  177.804647] 
[  177.904275] RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-8G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/0
[  177.904395] 
[  178.013602] job=/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml
[  178.013633] 
[  178.371568] run-job /lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml
[  178.371598] 
[  178.908173] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml&job_state=running -o /dev/null
[  178.908202] 
[  182.346560] kill 12296 dmesg --follow --decode 
[  182.346590] 
[  182.419287] wait for background monitors: 12300 12308 oom-killer nfs-hang
[  182.419315] 
[  187.436166] /lkp/lkp/src/bin/post-run: 187: /lkp/lkp/src/bin/post-run: cannot create /inn/result/boot/1/vm-lkp-wsx03-8G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/0/time-debug: Input/output error
[  187.436197] 
[  188.734487] sysrq: SysRq : Emergency Sync
[  188.749680] Emergency Sync complete

Elapsed time: 200

initrds=(
	/osimage/debian/debian-x86_64-2016-08-31.cgz
	/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.cgz
	/lkp/lkp/lkp-x86_64.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-05-01.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz
	/pkg/linux/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/modules.cgz
)

cat "${initrds[@]}" > /fs/sdc1/initrd-vm-lkp-wsx03-8G-9

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-cpu kvm64,+ssse3
	-kernel /pkg/linux/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/vmlinuz-4.12.0-rc2-00039-gbeeeccc
	-initrd /fs/sdc1/initrd-vm-lkp-wsx03-8G-9
	-m 8192
	-smp 2
	-device e1000,netdev=net0
	-netdev user,id=net0,hostfwd=tcp::23698-:22
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-drive file=/fs/sdc1/disk0-vm-lkp-wsx03-8G-9,media=disk,if=virtio
	-pidfile /dev/shm/kboot/pid-vm-lkp-wsx03-8G-9
	-serial file:/dev/shm/kboot/vm-lkp-wsx03-8G-9/serial
	-serial file:/dev/shm/kboot/vm-lkp-wsx03-8G-9/kmsg
	-daemonize
	-display none
	-monitor null
)

append=(
	ip=::::vm-lkp-wsx03-8G-9::dhcp
	root=/dev/ram0
	user=lkp
	job=/lkp/scheduled/vm-lkp-wsx03-8G-9/boot-1-debian-x86_64-2016-08-31.cgz-beeeccca9bebcec386cc31c250cff8a06cf27034-20170530-672-1vx5re6-0.yaml
	ARCH=x86_64
	kconfig=x86_64-kexec
	branch=linux-devel/devel-hourly-2017053006
	commit=beeeccca9bebcec386cc31c250cff8a06cf27034
	BOOT_IMAGE=/pkg/linux/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/vmlinuz-4.12.0-rc2-00039-gbeeeccc
	max_uptime=600
	RESULT_ROOT=/result/boot/1/vm-lkp-wsx03-8G/debian-x86_64-2016-08-31.cgz/x86_64-kexec/gcc-6/beeeccca9bebcec386cc31c250cff8a06cf27034/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
	earlyprintk=ttyS0,115200
	console=ttyS0,115200
	console=tty0
	vga=normal
	rw
)

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