VM crashes with linux-image-4.5.0-0.bpo.2-amd64:amd64/jessie-backports on KVM-Host

classic Classic list List threaded Threaded
3 messages Options
Reply | Threaded
Open this post in threaded view
|

VM crashes with linux-image-4.5.0-0.bpo.2-amd64:amd64/jessie-backports on KVM-Host

Rolf Kutz-2
Hello,

I already send this to debian-backports last week,
but maybe debian-kernel ist more appropriate.

After installing linux-image-4.5.0-0.bpo.2-amd64 on my KVM Host, I experience
problems with a virtual machine. The virtual machine starts up ok moѕt of the
time, but crashes after rebooting the VM. Switching the VM off and on again makes
it start up ok, but again crash on reboot. I don't see any errors in the
KVM-host logs. I've seen the bug with Kernel linux-image-4.5.0-0.bpo.2-amd64 and
linux-image-4.4.0-0.bpo.1-amd64 on the VM.

Going back to linux-image-4.4.0-0.bpo.1-amd64 on the KVM-Server makes the problem
disappear. Other VMs on that server run fine with both kernels. The VM
in question is an NFS4-Server running Debian
Jessie. The KVM-Images are on a BTRFS-Partition.

If you need additional info and logs, I'll try to provide them. See below for
the boot-logs.

regards
Rolf

10:08:47 rk@freckle:~$ virsh console storage
Connected to domain storage
Escape character is ^]

Debian GNU/Linux 8 storage ttyS0

storage login: rk
Passwort: Letzte Anmeldung: Samstag, den 28. Mai 2016, 09:57:46 CEST
von rkutz-nb.lan.zsze.de auf pts/0
Linux storage 4.5.0-0.bpo.2-amd64 #1 SMP Debian 4.5.3-2~bpo8+1 (2016-05-13) x86_64

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
Keine Nachrichten.
10:09:02 rk@storage:~$ dmesg [    0.000000] Linux version
4.5.0-0.bpo.2-amd64 ([hidden email]) (gcc version
4.9.2 (Debian 4.9.2-10) ) #1 SMP Debian 4.5.3-2~bpo8+1 (2016-05-13)
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-0.bpo.2-amd64 root=UUID=4c43ff54-ff7c-470f-bf9c-391e4357a3b5 ro text console=tty0 console=ttyS0,115200n8
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'lazy' FPU context switches.
[    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-0x00000000bffdffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-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] e820: last_pfn = 0x140000 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- WT  [    0.000000] e820: last_pfn = 0xbffe0 max_arch_pfn =
0x400000000
[    0.000000] found SMP MP-table at [mem 0x000f0e70-0x000f0e7f] mapped at [ffff8800000f0e70]
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] BRK [0x01d4a000, 0x01d4afff] PGTABLE
[    0.000000] BRK [0x01d4b000, 0x01d4bfff] PGTABLE
[    0.000000] BRK [0x01d4c000, 0x01d4cfff] PGTABLE
[    0.000000] BRK [0x01d4d000, 0x01d4dfff] PGTABLE
[    0.000000] RAMDISK: [mem 0x36218000-0x37103fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F0C30 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x00000000BFFE1927 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x00000000BFFE0B37 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x00000000BFFE0040 000AF7 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x00000000BFFE0000 000040
[    0.000000] ACPI: SSDT 0x00000000BFFE0BAB 000CBC (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 0x00000000BFFE1867 000088 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x00000000BFFE18EF 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000013fffffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x13fff8000-0x13fffcfff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 1:3fff0001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 7729218211 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-0x000000013fffffff]
[    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-0x00000000bffdffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000013fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff]
[    0.000000] On node 0 totalpages: 1048446
[    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: 782304 pages, LIFO batch:31
[    0.000000]   Normal zone: 4096 pages used for memmap
[    0.000000]   Normal zone: 262144 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] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 3 CPUs, 1 hotplug CPUs
[    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 0xbffe0000-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:3 nr_node_ids:1
[    0.000000] PERCPU: Embedded 33 pages/cpu @ffff88013fc00000 s94296 r8192 d32680 u524288
[    0.000000] pcpu-alloc: s94296 r8192 d32680 u524288 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 - [    0.000000] KVM setup async
PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 13fc0dac0
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 1032041
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-0.bpo.2-amd64 root=UUID=4c43ff54-ff7c-470f-bf9c-391e4357a3b5 ro text console=tty0 console=ttyS0,115200n8
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 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: 4032492K/4193784K available (5883K kernel code, 1169K rwdata, 2940K rodata, 1328K init, 832K bss, 161292K reserved, 0K cma-reserved)
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 64.
[    0.000000]  RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=3.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=3
[    0.000000] NR_IRQS:33024 nr_irqs:448 16
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] console [ttyS0] enabled
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Detected 2399.998 MHz processor
[    0.404585] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=9599992)
[    0.410294] pid_max: default: 32768 minimum: 301
[    0.412236] ACPI: Core revision 20160108
[    0.416888] ACPI: 2 ACPI AML tables successfully acquired and loaded

[    0.420469] Security Framework initialized
[    0.422166] Yama: disabled by default; enable with sysctl kernel.yama.*
[    0.424696] AppArmor: AppArmor disabled by boot time parameter
[    0.427863] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.433499] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.436961] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.441261] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.444591] Disabling memory control group subsystem
[    0.446814] mce: CPU supports 10 MCE banks
[    0.448572] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.450780] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.454907] Freeing SMP alternatives memory: 24K (ffffffff81c72000 - ffffffff81c78000)
[    0.465758] ftrace: allocating 24125 entries in 95 pages
[    0.500758] x2apic enabled
[    0.502426] Switched APIC routing to physical x2apic.
[    0.506056] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.508686] TSC deadline timer enabled
[    0.508715] smpboot: CPU0: Intel Westmere E56xx/L56xx/X56xx (Nehalem-C) (family: 0x6, model: 0x2c, stepping: 0x1)
[    0.513653] Performance Events: unsupported p6 CPU model 44 no PMU driver, software events only.
[    0.519178] x86: Booting SMP configuration:
[    0.521072] .... node  #0, CPUs:      #1
[    0.523067] kvm-clock: cpu 1, msr 1:3fff0041, secondary cpu clock
[    0.545191] x86: Booted up 1 node, 2 CPUs
[    0.545201] KVM setup async PF for cpu 1
[    0.545208] kvm-stealtime: cpu 1, msr 13fc8dac0
[    0.550450] smpboot: Total of 2 processors activated (9599.99 BogoMIPS)
[    0.553673] devtmpfs: initialized
[    0.561188] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.567245] pinctrl core: initialized pinctrl subsystem
[    0.569583] NET: Registered protocol family 16
[    0.583523] cpuidle: using governor ladder
[    0.595644] cpuidle: using governor menu
[    0.598377] ACPI: bus type PCI registered
[    0.600062] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.602693] PCI: Using configuration type 1 for base access
[    0.617221] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.620308] ACPI: Added _OSI(Module Device)
[    0.622044] ACPI: Added _OSI(Processor Device)
[    0.623798] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.625609] ACPI: Added _OSI(Processor Aggregator Device)
[    0.631956] ACPI: Interpreter enabled
[    0.633516] ACPI: (supports S0 S3 S4 S5)
[    0.635143] ACPI: Using IOAPIC for interrupt routing
[    0.637062] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.646809] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.649136] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.651683] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.654123] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.660705] acpiphp: Slot [3] registered
[    0.662375] acpiphp: Slot [4] registered
[    0.664041] acpiphp: Slot [5] registered
[    0.665667] acpiphp: Slot [6] registered
[    0.667345] acpiphp: Slot [7] registered
[    0.668977] acpiphp: Slot [8] registered
[    0.670617] acpiphp: Slot [9] registered
[    0.672269] acpiphp: Slot [10] registered
[    0.673935] acpiphp: Slot [11] registered
[    0.675618] acpiphp: Slot [12] registered
[    0.677295] acpiphp: Slot [13] registered
[    0.678951] acpiphp: Slot [14] registered
[    0.680614] acpiphp: Slot [15] registered
[    0.682284] acpiphp: Slot [16] registered
[    0.683945] acpiphp: Slot [17] registered
[    0.685604] acpiphp: Slot [18] registered
[    0.687330] acpiphp: Slot [19] registered
[    0.688981] acpiphp: Slot [20] registered
[    0.692841] acpiphp: Slot [21] registered
[    0.694530] acpiphp: Slot [22] registered
[    0.696206] acpiphp: Slot [23] registered
[    0.697885] acpiphp: Slot [24] registered
[    0.699573] acpiphp: Slot [25] registered
[    0.701227] acpiphp: Slot [26] registered
[    0.702918] acpiphp: Slot [27] registered
[    0.704574] acpiphp: Slot [28] registered
[    0.706233] acpiphp: Slot [29] registered
[    0.707928] acpiphp: Slot [30] registered
[    0.709580] acpiphp: Slot [31] registered
[    0.711237] PCI host bridge to bus 0000:00
[    0.712905] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.715365] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff window]
[    0.717879] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff window]
[    0.749677] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf window]
[    0.752139] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff window]
[    0.756149] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.759078] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
[    0.762016] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.764145] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.764896] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.765861] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.774695] pci 0000:00:01.1: reg 0x20: [io  0xc120-0xc12f]
[    0.778250] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.780906] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.783367] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.787765] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.790540] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300
[    0.799439] pci 0000:00:01.2: reg 0x20: [io  0xc080-0xc09f]
[    0.803387] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.804072] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.807087] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.810123] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000
[    0.817679] pci 0000:00:02.0: reg 0x10: [mem 0xf4000000-0xf7ffffff]
[    0.827102] pci 0000:00:02.0: reg 0x14: [mem 0xf8000000-0xfbffffff]
[    0.834400] pci 0000:00:02.0: reg 0x18: [mem 0xfc050000-0xfc051fff]
[    0.841704] pci 0000:00:02.0: reg 0x1c: [io  0xc0a0-0xc0bf]
[    0.864118] pci 0000:00:02.0: reg 0x30: [mem 0xfc040000-0xfc04ffff pref]
[    0.864601] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
[    0.868059] pci 0000:00:03.0: reg 0x10: [io  0xc0c0-0xc0df]
[    0.871391] pci 0000:00:03.0: reg 0x14: [mem 0xfc052000-0xfc052fff]
[    0.888005] pci 0000:00:03.0: reg 0x30: [mem 0xfc000000-0xfc03ffff pref]
[    0.888731] pci 0000:00:05.0: [1af4:1003] type 00 class 0x078000
[    0.892193] pci 0000:00:05.0: reg 0x10: [io  0xc0e0-0xc0ff]
[    0.895546] pci 0000:00:05.0: reg 0x14: [mem 0xfc053000-0xfc053fff]
[    0.912719] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
[    0.916189] pci 0000:00:06.0: reg 0x10: [io  0xc000-0xc03f]
[    0.919520] pci 0000:00:06.0: reg 0x14: [mem 0xfc054000-0xfc054fff]
[    0.936539] pci 0000:00:07.0: [1af4:1002] type 00 class 0x00ff00
[    0.938385] pci 0000:00:07.0: reg 0x10: [io  0xc100-0xc11f]
[    0.949626] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
[    0.953128] pci 0000:00:08.0: reg 0x10: [io  0xc040-0xc07f]
[    0.956405] pci 0000:00:08.0: reg 0x14: [mem 0xfc055000-0xfc055fff]
[    0.974832] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.978050] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.981195] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.984312] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.987450] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.990750] ACPI: Enabled 16 GPEs in block 00 to 0F
[    0.993232] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.995381] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.998557] vgaarb: loaded
[    0.999781] vgaarb: bridge control possible 0000:00:02.0
[    1.002030] PCI: Using ACPI for IRQ routing
[    1.005952] PCI: pci_cache_line_size set to 64 bytes
[    1.006284] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    1.006288] e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff]
[    1.006588] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    1.009187] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    1.011796] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    1.016383] clocksource: Switched to clocksource kvm-clock
[    1.031232] VFS: Disk quotas dquot_6.6.0
[    1.033000] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.037956] pnp: PnP ACPI init
[    1.039438] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    1.039515] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    1.039583] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    1.039640] pnp 00:03: [dma 2]
[    1.039677] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    1.039834] pnp 00:04: Plug and Play ACPI device, IDs PNP0501 (active)
[    1.040317] pnp: PnP ACPI: found 5 devices
[    1.052071] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.055520] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.055524] pci_bus 0000:00: resource 5 [io  0x0d00-0xadff window]
[    1.055527] pci_bus 0000:00: resource 6 [io  0xae0f-0xaeff window]
[    1.055530] pci_bus 0000:00: resource 7 [io  0xaf20-0xafdf window]
[    1.055533] pci_bus 0000:00: resource 8 [io  0xafe4-0xffff window]
[    1.055536] pci_bus 0000:00: resource 9 [mem 0x000a0000-0x000bffff window]
[    1.055539] pci_bus 0000:00: resource 10 [mem 0xc0000000-0xfebfffff window]
[    1.055670] NET: Registered protocol family 2
[    1.057725] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    1.060757] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    1.063303] TCP: Hash tables configured (established 32768 bind 32768)
[    1.065792] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    1.070204] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    1.072846] NET: Registered protocol family 1
[    1.074615] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.076868] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.079052] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    1.138382] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[    1.198305] pci 0000:00:02.0: Video device with shadowed ROM
[    1.198406] PCI: CLS 0 bytes, default 64
[    1.198516] Unpacking initramfs...
[    1.577416] Freeing initrd memory: 15280K (ffff880036218000 - ffff880037104000)
[    1.580520] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    1.582902] software IO TLB [mem 0xbbfe0000-0xbffe0000] (64MB) mapped at [ffff8800bbfe0000-ffff8800bffdffff]
[    1.589497] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    1.591971] audit: initializing netlink subsys (disabled)
[    1.594079] audit: type=2000 audit(1464422826.664:1): initialized
[    1.596680] Initialise system trusted keyring
[    1.598767] zbud: loaded
[    1.601405] Key type asymmetric registered
[    1.603097] Asymmetric key parser 'x509' registered
[    1.605082] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    1.608057] io scheduler noop registered
[    1.609695] io scheduler deadline registered
[    1.611469] io scheduler cfq registered (default)
[    1.613528] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    1.615643] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    1.620257] intel_idle: does not run on family 6 model 44
[    1.620290] GHES: HEST is not enabled!
[    1.622037] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.655994] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    1.659957] Linux agpgart interface v0.103
[    1.661792] AMD IOMMUv2 driver by Joerg Roedel <[hidden email]>
[    1.664030] AMD IOMMUv2 functionality not available on this system
[    1.666704] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    1.670973] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.672928] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.675232] mousedev: PS/2 mouse device common for all mice
[    1.677862] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    1.683646] rtc_cmos 00:00: RTC can wake from S4
[    1.686057] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    1.688619] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[    1.691228] ledtrig-cpu: registered to indicate activity on CPUs
[    1.693840] NET: Registered protocol family 10
[    1.695957] mip6: Mobile IPv6
[    1.697318] NET: Registered protocol family 17
[    1.699095] mpls_gso: MPLS GSO support
[    1.701011] microcode: CPU0 sig=0x206c1, pf=0x1, revision=0x1
[    1.703221] microcode: CPU1 sig=0x206c1, pf=0x1, revision=0x1
[    1.705697] microcode: Microcode Update Driver: v2.01 <[hidden email]>, Peter Oruba
[    1.709550] registered taskstats version 1
[    1.711238] Loading compiled-in X.509 certificates
[    1.715896] Loaded X.509 cert 'Debian Project: Ben Hutchings: 008a018dca80932630'
[    1.718956] zswap: loaded using pool lzo/zbud
[    1.721660] rtc_cmos 00:00: setting system clock to 2016-05-28 08:07:06 UTC (1464422826)
[    1.724931] PM: Hibernation image not present or could not be loaded.
[    1.726966] Freeing unused kernel memory: 1328K (ffffffff81b26000 - ffffffff81c72000)
[    1.730137] Write protecting the kernel read-only data: 10240k
[    1.732748] Freeing unused kernel memory: 248K (ffff8800015c2000 - ffff880001600000)
[    1.740328] Freeing unused kernel memory: 1156K (ffff8800018df000 - ffff880001a00000)
[    1.757185] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.779460] systemd-udevd[65]: starting version 215
[    1.782103] random: systemd-udevd urandom read with 2 bits of entropy available
[    1.794611] virtio: module verification failed: signature and/or required key missing - tainting kernel
[    1.811084] SCSI subsystem initialized
[    1.819720] ACPI: bus type USB registered
[    1.830796] usbcore: registered new interface driver usbfs
[    1.838790] usbcore: registered new interface driver hub
[    1.846976] libata version 3.00 loaded.
[    1.855536] usbcore: registered new device driver usb
[    1.859935] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.863523] uhci_hcd: USB Universal Host Controller Interface driver
[    1.889158] FDC 0 is a S82078B
[    1.902963] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
[    1.907527] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver
[    1.911869] ata_piix 0000:00:01.1: version 2.13
[    1.914506] scsi host0: ata_piix
[    1.916167] scsi host1: ata_piix
[    1.917733] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14
[    1.920253] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15
[    1.975579] uhci_hcd 0000:00:01.2: UHCI Host Controller
[    1.977727] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
[    1.980739] uhci_hcd 0000:00:01.2: detected 2 ports
[    1.982827] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c080
[    1.985169] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[    1.987786] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.990822] usb usb1: Product: UHCI Host Controller
[    1.992808] usb usb1: Manufacturer: Linux 4.5.0-0.bpo.2-amd64 uhci_hcd
[    1.995359] usb usb1: SerialNumber: 0000:00:01.2
[    1.997613] hub 1-0:1.0: USB hub found
[    2.001512] hub 1-0:1.0: 2 ports detected
[    2.055966] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[    2.058430] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver
[    2.077462] ata2.01: NODEV after polling detection
[    2.077932] ata2.00: ATAPI: QEMU DVD-ROM, 2.1.2, max UDMA/100
[    2.080945] ata2.00: configured for MWDMA2
[    2.083672] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.1. PQ: 0 ANSI: 5
[    2.117473] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
[    2.119807] virtio-pci 0000:00:06.0: virtio_pci: leaving for legacy driver
[    2.173758] virtio-pci 0000:00:07.0: virtio_pci: leaving for legacy driver
[    2.229423] virtio-pci 0000:00:08.0: virtio_pci: leaving for legacy driver
[    2.238680]  vda: vda1 vda2 < vda5 >
[    2.246073]  vdb: vdb1
[    2.254288] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    2.259099] cdrom: Uniform CD-ROM driver Revision: 3.20
[    2.261897] sr 1:0:0:0: Attached scsi CD-ROM sr0
[    2.263253] sr 1:0:0:0: Attached scsi generic sg0 type 5
[    2.588533] tsc: Refined TSC clocksource calibration: 2399.997 MHz
[    2.591016] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229834d6ac9, max_idle_ns: 440795285103 ns
[    2.670705] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
[    2.842165] PM: Starting manual resume from disk
[    2.844154] PM: Hibernation image partition 254:5 present
[    2.844156] PM: Looking for hibernation image.
[    2.844498] PM: Image not found (code -22)
[    2.844502] PM: Hibernation image not present or could not be loaded.
[    3.283240] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)
[    6.307634] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR)
[    6.312583] systemd[1]: Detected virtualization 'kvm'.
[    6.316890] systemd[1]: Detected architecture 'x86-64'.
[    7.256189] systemd[1]: Inserted module 'autofs4'
[    7.290052] systemd[1]: Set hostname to <storage>.
[   12.064742] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
[   12.070983] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[   12.074193] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[   12.077298] systemd[1]: Expecting device dev-ttyS0.device...
[   12.083338] systemd[1]: Starting Remote File Systems (Pre).
[   12.087321] systemd[1]: Reached target Remote File Systems (Pre).
[   12.119941] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
[   12.123243] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[   12.126442] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
[   12.132763] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[   12.136588] systemd[1]: Starting Encrypted Volumes.
[   12.140278] systemd[1]: Reached target Encrypted Volumes.
[   12.142516] systemd[1]: Expecting device dev-disk-by\x2duuid-eebd40c0\x2d0a36\x2d4581\x2daab4\x2d6bd20a19da36.device...
[   12.151045] systemd[1]: Expecting device dev-vdb1.device...
[   12.154825] systemd[1]: Starting Root Slice.
[   12.158362] systemd[1]: Created slice Root Slice.
[   12.160305] systemd[1]: Starting User and Session Slice.
[   12.164279] systemd[1]: Created slice User and Session Slice.
[   12.166544] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[   12.171102] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[   12.173714] systemd[1]: Starting Delayed Shutdown Socket.
[   12.179868] systemd[1]: Listening on Delayed Shutdown Socket.
[   12.182212] systemd[1]: Starting Journal Socket (/dev/log).
[   12.186594] systemd[1]: Listening on Journal Socket (/dev/log).
[   12.188950] systemd[1]: Starting udev Control Socket.
[   12.192677] systemd[1]: Listening on udev Control Socket.
[   12.194827] systemd[1]: Starting udev Kernel Socket.
[   12.198456] systemd[1]: Listening on udev Kernel Socket.
[   12.200599] systemd[1]: Starting Journal Socket.
[   12.204138] systemd[1]: Listening on Journal Socket.
[   12.206226] systemd[1]: Starting System Slice.
[   12.211898] systemd[1]: Created slice System Slice.
[   12.213942] systemd[1]: Started File System Check on Root Device.
[   12.216289] systemd[1]: Starting system-getty.slice.
[   12.220172] systemd[1]: Created slice system-getty.slice.
[   12.222330] systemd[1]: Starting system-serial\x2dgetty.slice.
[   12.226535] systemd[1]: Created slice system-serial\x2dgetty.slice.
[   12.229017] systemd[1]: Starting Increase datagram queue length...
[   12.268326] systemd[1]: Starting Load Kernel Modules...
[   12.291068] systemd[1]: Started Set Up Additional Binary Formats.
[   12.293537] systemd[1]: Mounting POSIX Message Queue File System...
[   12.298424] systemd[1]: Starting udev Coldplug all Devices...
[   12.303567] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[   12.312371] systemd[1]: Mounting Huge Pages File System...
[   12.317165] systemd[1]: Mounting Debug File System...
[   12.321495] systemd[1]: Starting Slices.
[   12.324788] systemd[1]: Reached target Slices.
[   12.654272] systemd[1]: Started Load Kernel Modules.
[   12.656746] systemd[1]: Mounted Configuration File System.
[   12.658931] systemd[1]: Starting Apply Kernel Variables...
[   12.672783] systemd[1]: Mounted FUSE Control File System.
[   12.685880] systemd[1]: Started Create list of required static device nodes for the current kernel.
[   12.689751] systemd[1]: Starting Create Static Device Nodes in /dev...
[   13.345915] systemd[1]: Started Apply Kernel Variables.
[   13.694383] systemd[1]: Started udev Coldplug all Devices.
[   13.863429] systemd[1]: Mounted Debug File System.
[   13.867709] systemd[1]: Mounted Huge Pages File System.
[   13.874584] systemd[1]: Mounted POSIX Message Queue File System.
[   13.879905] systemd[1]: Started Increase datagram queue length.
[   13.891199] systemd[1]: Starting Syslog Socket.
[   13.895398] systemd[1]: Listening on Syslog Socket.
[   13.897368] systemd[1]: Starting Journal Service...
[   13.923219] systemd[1]: Started Journal Service.
[   14.720647] systemd-udevd[172]: starting version 215
[   16.246241] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
[   16.249551] ACPI: Power Button [PWRF]
[   16.473988] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[   16.505888] input: PC Speaker as /devices/platform/pcspkr/input/input4
[   16.679854] [drm] Initialized drm 1.1.0 20060810
[   16.879230] Adding 392188k swap on /dev/vda5.  Priority:-1 extents:1 across:392188k FS
[   16.930297] ppdev: user-space parallel port driver
[   16.975515] [drm] Device Version 0.0
[   16.979894] [drm] Compression level 0 log level 0
[   16.985329] [drm] Currently using mode #0, list at 0x488
[   16.991131] [drm] 12286 io pages at offset 0x1000000
[   16.997187] [drm] 16777216 byte draw area at offset 0x0
[   17.003456] [drm] RAM header offset: 0x3ffe000
[   17.011343] [drm] rom modes offset 0x488 for 128 modes
[   17.019792] [TTM] Zone  kernel: Available graphics memory: 1699632 kiB
[   17.023421] [TTM] Initializing pool allocator
[   17.025212] [TTM] Initializing DMA pool allocator
[   17.027234] [drm] qxl: 16M of VRAM memory size
[   17.033883] [drm] qxl: 63M of IO pages memory ready (VRAM domain)
[   17.044373] [drm] qxl: 64M of Surface memory size
[   17.061450] [drm] main mem slot 1 [f4000000,3ffe000]
[   17.067808] [drm] surface mem slot 2 [f8000000,4000000]
[   17.073988] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   17.081851] [drm] No driver support for vblank timestamp query.
[   17.093640] [drm] fb mappable at 0xF4000000, size 3145728
[   17.102272] [drm] fb: depth 24, pitch 4096, width 1024, height 768
[   17.111160] fbcon: qxldrmfb (fb0) is primary device
[   17.216919] Console: switching to colour frame buffer device 128x48
[   17.237108] qxl 0000:00:02.0: fb0: qxldrmfb frame buffer device
[   17.256511] [drm] Initialized qxl 0.1.0 20120117 for 0000:00:02.0 on minor 0
[   17.449130] SSE version of gcm_enc/dec engaged.
[   17.560200] alg: No test for fips(ansi_cprng) (fips_ansi_cprng)
[   18.527152] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro
[   19.117665] EXT4-fs (vdb1): mounted filesystem with ordered data mode. Opts: (null)
[   19.169568] systemd-journald[170]: Received request to flush runtime journal from PID 1
[   27.503633] RPC: Registered named UNIX socket transport module.
[   27.505537] RPC: Registered udp transport module.
[   27.506947] RPC: Registered tcp transport module.
[   27.508306] RPC: Registered tcp NFSv4.1 backchannel transport module.
[   27.763407] FS-Cache: Loaded
[   28.023532] FS-Cache: Netfs 'nfs' registered for caching
[   28.126902] Installing knfsd (copyright (C) 1996 [hidden email]).
[   30.202869] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[   30.205586] NFSD: starting 90-second grace period (net ffffffff81ae7440)
[   43.847366] random: nonblocking pool is initialized
10:09:04 rk@storage:~$ sudo reboot
[sudo] password for rk:          Starting Synchronise Hardware Clock
to System Clock...
[  OK  ] Removed slice system-ifup.slice.
[  OK  ] Stopped target Graphical Interface.
[  OK  ] Stopped target Multi-User System.
          Stopping Deferred execution scheduler...
          Stopping Regular background program processing daemon...
          Stopping OpenBSD Secure Shell server...
          Stopping A high performance web server and a reverse proxy server...
          Stopping Munin Node...
[  OK  ] Stopped target Login Prompts.
          Stopping Getty on tty1...
          Stopping Serial Getty on ttyS0...
          Stopping Login Service...
          Stopping LSB: Kernel NFS server support...
          Stopping LSB: Set sysfs variables from /etc/sysfs.conf...
          Stopping LSB: start Samba daemons for the AD DC...
          Stopping LSB: start Winbind daemon...
          Stopping LSB: start Samba SMB/CIFS daemon (smbd)...
[  OK  ] Stopped target Mail Transport Agent.
          Stopping LSB: Postfix Mail Transport Agent...
          Stopping LSB: daemon to balance interrupts for SMP systems...
          Stopping D-Bus System Message Bus...
          Stopping System Logging Service...
          Stopping ACPI event daemon...
          Starting Unattended Upgrades...
[  OK  ] Stopped Deferred execution scheduler.
[  OK  ] Stopped Regular background program processing daemon.
[  OK  ] Stopped OpenBSD Secure Shell server.
[  OK  ] Stopped Login Service.
[  OK  ] Stopped D-Bus System Message Bus.
[  OK  ] Stopped System Logging Service.
[  128.016624] nfsd: last server has exited, flushing export cache
[  OK  ] Stopped Getty on tty1.
[  OK  ] Stopped Serial Getty on ttyS0.
[  OK  ] Stopped ACPI event daemon.
[  OK  ] Stopped Munin Node.
[  OK  ] Stopped A high performance web server and a reverse proxy server.
[  OK  ] Stopped LSB: Kernel NFS server support.
[  OK  ] Stopped LSB: Set sysfs variables from /etc/sysfs.conf.
[  OK  ] Stopped LSB: daemon to balance interrupts for SMP systems.
[  OK  ] Removed slice system-serial\x2dgetty.slice.
[  OK  ] Removed slice system-getty.slice.
          Stopping /etc/rc.local Compatibility...
[  OK  ] Stopped /etc/rc.local Compatibility.
          Stopping Permit User Sessions...
[  OK  ] Stopped Permit User Sessions.
[  OK  ] Stopped LSB: Postfix Mail Transport Agent.
[  OK  ] Stopped LSB: start Winbind daemon.
[  OK  ] Started Synchronise Hardware Clock to System Clock.
[  OK  ] Stopped LSB: start Samba SMB/CIFS daemon (smbd).
[  OK  ] Stopped LSB: start Samba daemons for the AD DC.
          Stopping LSB: start Samba NetBIOS nameserver (nmbd)...
[  OK  ] Started Unattended Upgrades.
[  OK  ] Stopped LSB: start Samba NetBIOS nameserver (nmbd).
[  OK  ] Stopped target Basic System.
[  OK  ] Stopped target Slices.
[  OK  ] Removed slice User and Session Slice.
[  OK  ] Stopped target Paths.
[  OK  ] Stopped target Timers.
[  OK  ] Stopped target Sockets.
[  OK  ] Closed D-Bus System Message Bus Socket.
[  OK  ] Closed Syslog Socket.
[  OK  ] Closed ACPID Listen Socket.
[  OK  ] Stopped target System Initialization.
[  OK  ] Stopped target Encrypted Volumes.
          Stopping Apply Kernel Variables...
[  OK  ] Stopped Apply Kernel Variables.
          Stopping Load Kernel Modules...
[  OK  ] Stopped Load Kernel Modules.
          Stopping Update UTMP about System Boot/Shutdown...
          Stopping LSB: NFS support files common to client and server...
[  OK  ] Stopped target Swap.
          Deactivating swap /dev/disk/by-uuid/eebd40c0-0a36-45...6bd20a19da36...
          Deactivating swap /dev/disk/by-uuid/eebd40c0-0a36-45...6bd20a19da36...
[  OK  ] Stopped target Remote File Systems.
[  OK  ] Stopped target Remote File Systems (Pre).
[  OK  ] Stopped Update UTMP about System Boot/Shutdown.
[  OK  ] Deactivated swap /dev/vda5.
[  OK  ] Deactivated swap /dev/disk/by-uuid/eebd40c0-0a36-458...4-6bd20a19da36.
          Stopping Create Volatile Files and Directories...
[  OK  ] Stopped Create Volatile Files and Directories.
[  OK  ] Unmounted /run/rpc_pipefs.
[  OK  ] Stopped LSB: NFS support files common to client and server.
[  OK  ] Stopped target RPC Port Mapper.
          Stopping LSB: RPC portmapper replacement...
[  OK  ] Stopped LSB: RPC portmapper replacement.
[  OK  ] Stopped target Network is Online.
[  OK  ] Stopped target Network.
          Stopping LSB: Raise network interfaces....
[  OK  ] Stopped LSB: Raise network interfaces..
          Stopping LSB: IPv6 Recursive DNS Server discovery...
          Stopping Load/Save Random Seed...
[  OK  ] Stopped Load/Save Random Seed.
[  OK  ] Stopped LSB: IPv6 Recursive DNS Server discovery.
[  OK  ] Stopped target Local File Systems.
          Unmounting /proc/fs/nfsd...
          Unmounting /srv/storage...
[  OK  ] Unmounted /proc/fs/nfsd.
[  OK  ] Unmounted /srv/storage.
[  OK  ] Reached target Unmount All Filesystems.
[  OK  ] Stopped target Local File Systems (Pre).
          Stopping Create Static Device Nodes in /dev...
[  OK  ] Stopped Create Static Device Nodes in /dev.
          Stopping Remount Root and Kernel File Systems...
[  OK  ] Stopped Remount Root and Kernel File Systems.
[  OK  ] Reached target Shutdown.
[  131.966894] systemd-shutdown[1]: Sending SIGTERM to remaining processes...
[  131.976014] systemd-journald[170]: Received SIGTERM from PID 1 (systemd-shutdow).
[  131.980901] systemd-shutdown[1]: Sending SIGKILL to remaining processes...
[  131.990305] systemd-shutdown[1]: Unmounting file systems.
[  131.992267] systemd-shutdown[1]: Unmounting /dev/mqueue.
[  132.004478] systemd-shutdown[1]: Unmounting /dev/hugepages.
[  132.016580] systemd-shutdown[1]: Unmounting /sys/kernel/debug.
[  132.490760] EXT4-fs (vda1): re-mounted. Opts: (null)
[  132.496746] EXT4-fs (vda1): re-mounted. Opts: (null)
[  132.498932] EXT4-fs (vda1): re-mounted. Opts: (null)
[  132.501091] systemd-shutdown[1]: All filesystems unmounted.
[  132.503443] systemd-shutdown[1]: Deactivating swaps.
[  132.505900] systemd-shutdown[1]: All swaps deactivated.
[  132.507564] systemd-shutdown[1]: Detaching loop devices.
[  132.510032] systemd-shutdown[1]: All loop devices detached.
[  132.511740] systemd-shutdown[1]: Detaching DM devices.
[  132.513481] systemd-shutdown[1]: All DM devices detached.
[  132.519018] systemd-shutdown[1]: Rebooting.
[  132.520385] Unregister pv shared memory for cpu 0
[  132.521769] Unregister pv shared memory for cpu 1
[  132.524601] reboot: Restarting system
[  132.525726] reboot: machine restart
[    0.000000] Linux version 4.5.0-0.bpo.2-amd64 ([hidden email]) (gcc version 4.9.2 (Debian 4.9.2-10) ) #1 SMP Debian 4.5.3-2~bpo8+1 (2016-05-13)
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-0.bpo.2-amd64 root=UUID=4c43ff54-ff7c-470f-bf9c-391e4357a3b5 ro text console=tty0 console=ttyS0,115200n8
[    0.000000] x86/fpu: Legacy x87 FPU detected.
[    0.000000] x86/fpu: Using 'lazy' FPU context switches.
[    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-0x00000000bffdffff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: last_pfn = 0x140000 max_arch_pfn = 0x400000000
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  
UC- WT  [    0.000000] e820: last_pfn = 0xbffe0 max_arch_pfn =
0x400000000
[    0.000000] found SMP MP-table at [mem 0x000f0e70-0x000f0e7f] mapped at [ffff8800000f0e70]
[    0.000000] RAMDISK: [mem 0x36218000-0x37103fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F0C30 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x00000000BFFE1927 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x00000000BFFE0B37 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x00000000BFFE0040 000AF7 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x00000000BFFE0000 000040
[    0.000000] ACPI: SSDT 0x00000000BFFE0BAB 000CBC (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 0x00000000BFFE1867 000088 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x00000000BFFE18EF 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000013fffffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x13fff8000-0x13fffcfff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 1:3fff0001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 146729586697 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-0x000000013fffffff]
[    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-0x00000000bffdffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x000000013fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff]
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    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] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 3 CPUs, 1 hotplug CPUs
[    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 0xbffe0000-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:3 nr_node_ids:1
[    0.000000] PERCPU: Embedded 33 pages/cpu @ffff88013fc00000 s94296 r8192 d32680 u524288
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 13fc0dac0
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 1032041
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-0.bpo.2-amd64 root=UUID=4c43ff54-ff7c-470f-bf9c-391e4357a3b5 ro text console=tty0 console=ttyS0,115200n8
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 4032492K/4193784K available (5883K kernel code, 1169K rwdata, 2940K rodata, 1328K init, 832K bss, 161292K reserved, 0K cma-reserved)
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 64.
[    0.000000]  RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=3.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=3
[    0.000000] NR_IRQS:33024 nr_irqs:448 16
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] console [ttyS0] enabled
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.000000] tsc: Detected 2399.998 MHz processor
[    0.356115] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=9599992)
[    0.361507] pid_max: default: 32768 minimum: 301
[    0.363442] ACPI: Core revision 20160108
[    0.368058] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.371020] [    0.372090] Security Framework initialized
[    0.373728] Yama: disabled by default; enable with sysctl kernel.yama.*
[    0.376174] AppArmor: AppArmor disabled by boot time parameter
[    0.378784] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.383220] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.386552] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.391363] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.394597] Disabling memory control group subsystem
[    0.396967] mce: CPU supports 10 MCE banks
[    0.399140] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.401494] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.406375] Freeing SMP alternatives memory: 24K (ffffffff81c72000 - ffffffff81c78000)
[    0.418321] ftrace: allocating 24125 entries in 95 pages
[    0.457345] x2apic enabled
[    0.459100] Switched APIC routing to physical x2apic.
[    0.462668] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.465464] smpboot: CPU0: Intel Westmere E56xx/L56xx/X56xx (Nehalem-C) (family: 0x6, model: 0x2c, stepping: 0x1)
[    0.470914] Performance Events: unsupported p6 CPU model 44 no PMU driver, software events only.
[    0.476347] x86: Booting SMP configuration:
[    0.478497] .... node  #0, CPUs:      #1
[    0.480639] kvm-clock: cpu 1, msr 1:3fff0041, secondary cpu clock
[    0.502666] x86: Booted up 1 node, 2 CPUs
[    0.502671] KVM setup async PF for cpu 1
[    0.502677] kvm-stealtime: cpu 1, msr 13fc8dac0
[    0.507806] smpboot: Total of 2 processors activated (9599.99 BogoMIPS)
[    0.510868] devtmpfs: initialized
[    0.518372] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.524142] pinctrl core: initialized pinctrl subsystem
[    0.526703] NET: Registered protocol family 16
[    0.540700] cpuidle: using governor ladder
[    0.552812] cpuidle: using governor menu
[    0.556962] ACPI: bus type PCI registered
[    0.558677] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.561517] PCI: Using configuration type 1 for base access
[    0.573698] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.576879] ACPI: Added _OSI(Module Device)
[    0.578688] ACPI: Added _OSI(Processor Device)
[    0.580477] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.582375] ACPI: Added _OSI(Processor Aggregator Device)
[    0.586767] ACPI: Interpreter enabled
[    0.588803] ACPI: (supports S0 S3 S4 S5)
[    0.590465] ACPI: Using IOAPIC for interrupt routing
[    0.592460] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.602440] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.604858] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.607424] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.609869] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.614890] acpiphp: Slot [3] registered
[    0.616568] acpiphp: Slot [4] registered
[    0.620390] acpiphp: Slot [5] registered
[    0.622020] acpiphp: Slot [6] registered
[    0.623811] acpiphp: Slot [7] registered
[    0.625733] acpiphp: Slot [8] registered
[    0.627643] acpiphp: Slot [9] registered
[    0.629550] acpiphp: Slot [10] registered
[    0.631547] acpiphp: Slot [11] registered
[    0.633489] acpiphp: Slot [12] registered
[    0.635428] acpiphp: Slot [13] registered
[    0.637116] acpiphp: Slot [14] registered
[    0.638777] acpiphp: Slot [15] registered
[    0.640427] acpiphp: Slot [16] registered
[    0.642121] acpiphp: Slot [17] registered
[    0.643780] acpiphp: Slot [18] registered
[    0.645435] acpiphp: Slot [19] registered
[    0.647155] acpiphp: Slot [20] registered
[    0.648803] acpiphp: Slot [21] registered
[    0.652625] acpiphp: Slot [22] registered
[    0.654302] acpiphp: Slot [23] registered
[    0.655958] acpiphp: Slot [24] registered
[    0.657649] acpiphp: Slot [25] registered
[    0.659366] acpiphp: Slot [26] registered
[    0.661336] acpiphp: Slot [27] registered
[    0.663319] acpiphp: Slot [28] registered
[    0.665262] acpiphp: Slot [29] registered
[    0.667220] acpiphp: Slot [30] registered
[    0.669205] acpiphp: Slot [31] registered
[    0.671142] PCI host bridge to bus 0000:00
[    0.672788] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.675268] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff window]
[    0.677716] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff window]
[    0.710977] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf window]
[    0.714923] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff window]
[    0.717386] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.720559] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
[    0.724019] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.741297] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.745610] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.748001] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.750605] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.768689] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.771568] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.943143] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.946375] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.949465] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.952598] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.955621] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.958888] ACPI: Enabled 16 GPEs in block 00 to 0F
[    0.963541] vgaarb: setting as boot device: PCI:0000:00:02.0
[    0.965695] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.968881] vgaarb: loaded
[    0.970138] vgaarb: bridge control possible 0000:00:02.0
[    0.972433] PCI: Using ACPI for IRQ routing
[    0.975182] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.978202] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.981208] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.987277] clocksource: Switched to clocksource kvm-clock
[    1.001133] VFS: Disk quotas dquot_6.6.0
[    1.003057] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.006195] pnp: PnP ACPI init
[    1.008631] pnp: PnP ACPI: found 5 devices
[    1.020185] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.025821] NET: Registered protocol family 2
[    1.027886] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    1.030919] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    1.033918] TCP: Hash tables configured (established 32768 bind 32768)
[    1.036825] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    1.039488] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    1.042477] NET: Registered protocol family 1
[    1.044298] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.046516] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.048694] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    1.107886] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[    1.167829] Unpacking initramfs...
[    1.530938] Freeing initrd memory: 15280K (ffff880036218000 - ffff880037104000)
[    1.533969] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    1.536393] software IO TLB [mem 0xbbfe0000-0xbffe0000] (64MB) mapped at [ffff8800bbfe0000-ffff8800bffdffff]
[    1.540853] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    1.545736] audit: initializing netlink subsys (disabled)
[    1.547861] audit: type=2000 audit(1464422965.231:1): initialized
[    1.551111] Initialise system trusted keyring
[    1.553534] zbud: loaded
[    1.556386] Key type asymmetric registered
[    1.558168] Asymmetric key parser 'x509' registered
[    1.560274] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    1.563408] io scheduler noop registered
[    1.565266] io scheduler deadline registered
[    1.567051] io scheduler cfq registered (default)
[    1.569121] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    1.571353] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    1.576271] GHES: HEST is not enabled!
[    1.578007] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.609957] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    1.613871] Linux agpgart interface v0.103
[    1.615713] AMD IOMMUv2 driver by Joerg Roedel <[hidden email]>
[    1.617915] AMD IOMMUv2 functionality not available on this system
[    1.620606] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    1.624922] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.626873] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.629172] mousedev: PS/2 mouse device common for all mice
[    1.631792] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    1.635499] rtc_cmos 00:00: RTC can wake from S4
[    1.639956] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    1.642371] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[    1.644952] ledtrig-cpu: registered to indicate activity on CPUs
[    1.647741] NET: Registered protocol family 10
[    1.650492] mip6: Mobile IPv6
[    1.652294] NET: Registered protocol family 17
[    1.654635] mpls_gso: MPLS GSO support
[    1.656557] microcode: CPU0 sig=0x206c1, pf=0x1, revision=0x1
[    1.658811] microcode: CPU1 sig=0x206c1, pf=0x1, revision=0x1
[    1.661091] microcode: Microcode Update Driver: v2.01 <[hidden email]>, Peter Oruba
[    1.664633] registered taskstats version 1
[    1.666395] Loading compiled-in X.509 certificates
[    1.668874] Loaded X.509 cert 'Debian Project: Ben Hutchings: 008a018dca80932630'
[    1.674387] zswap: loaded using pool lzo/zbud
[    1.677200] rtc_cmos 00:00: setting system clock to 2016-05-28 08:09:25 UTC (1464422965)
[    1.682569] Freeing unused kernel memory: 1328K (ffffffff81b26000 - ffffffff81c72000)
[    1.685806] Write protecting the kernel read-only data: 10240k
[    1.688437] Freeing unused kernel memory: 248K (ffff8800015c2000 - ffff880001600000)
[    1.696111] Freeing unused kernel memory: 1156K (ffff8800018df000 - ffff880001a00000)
[    1.713027] x86/mm: Checked W+X mappings: passed, no W+X pages found.
Loading, please wait...
[    1.733553] systemd-udevd[65]: starting version 215
[    1.738016] random: systemd-udevd urandom read with 2 bits of entropy available
[    1.747969] scsi_mod: module verification failed: signature and/or required key missing - tainting kernel
[    1.754878] ACPI: bus type USB registered
[    1.763607] usbcore: registered new interface driver usbfs
[    1.771129] SCSI subsystem initialized
[    1.782243] usbcore: registered new interface driver hub
[    1.792148] usbcore: registered new device driver usb
[    1.801639] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.814902] uhci_hcd: USB Universal Host Controller Interface driver
[    1.828128] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
[    1.829003] FDC 0 is a S82078B
[    1.834626] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver
[    1.840444] scsi host0: ata_piix
[    1.842010] scsi host1: ata_piix
[    1.843554] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14
[    1.846019] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15
[    1.901720] uhci_hcd 0000:00:01.2: UHCI Host Controller
[    1.903808] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
[    1.906710] uhci_hcd 0000:00:01.2: detected 2 ports
[    1.908742] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c080
[    1.911029] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[    1.913654] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.916660] usb usb1: Product: UHCI Host Controller
[    1.918695] usb usb1: Manufacturer: Linux 4.5.0-0.bpo.2-amd64 uhci_hcd
[    1.921332] usb usb1: SerialNumber: 0000:00:01.2
[    1.925735] hub 1-0:1.0: USB hub found
[    1.927729] hub 1-0:1.0: 2 ports detected
[    1.981707] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[    1.984186] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver
[    2.012349] ata2.00: ATAPI: QEMU DVD-ROM, 2.1.2, max UDMA/100
[    2.015327] ata2.00: configured for MWDMA2
[    2.017997] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.1. PQ: 0 ANSI: 5
[    2.044528] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
[    2.046832] virtio-pci 0000:00:06.0: virtio_pci: leaving for legacy driver
[    2.100847] virtio-pci 0000:00:07.0: virtio_pci: leaving for legacy driver
[    2.156786] virtio-pci 0000:00:08.0: virtio_pci: leaving for legacy driver
[    2.167493]  vda: vda1 vda2 < vda5 >
[    2.172983] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    2.175548] cdrom: Uniform CD-ROM driver Revision: 3.20
[    2.183142] sr 1:0:0:0: Attached scsi generic sg0 type 5
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... [    2.265207] PM: Starting manual resume from disk
done.
Begin: Will now check root file system ... fsck from util-linux 2.25.2
[/sbin/fsck.ext4 (1) -- /dev/vda1] fsck.ext4 -a -C0 /dev/vda1
/dev/vda1: clean, 52725/499712 files, 643179/1998336 blocks
done.
[    2.410152] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[    2.492183] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR)
[    2.497207] systemd[1]: Detected virtualization 'kvm'.
[    2.499266] systemd[1]: Detected architecture 'x86-64'.

Welcome to Debian GNU/Linux 8 (jessie)!

[    2.513511] systemd[1]: Inserted module 'autofs4'
[    2.518369] systemd[1]: Set hostname to <storage>.
[    2.540498] tsc: Refined TSC clocksource calibration: 2399.980 MHz
[    2.543069] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22982479f67, max_idle_ns: 440795221274 ns
[    2.634716] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
[    2.635454] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
[    2.636088] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[    2.636199] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    2.636221] systemd[1]: Expecting device dev-ttyS0.device...
          Expecting device dev-ttyS0.device...
[    2.658433] systemd[1]: Starting Remote File Systems (Pre).
[  OK  ] Reached target Remote File Systems (Pre).
[    2.663876] systemd[1]: Reached target Remote File Systems (Pre).
[    2.666263] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
[    2.701393] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    2.706797] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
[  OK  ] Set up automount Arbitrary Executable File Formats F...utomount Point.
[    2.713953] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    2.718467] systemd[1]: Starting Encrypted Volumes.
[  OK  ] Reached target Encrypted Volumes.
[    2.723558] systemd[1]: Reached target Encrypted Volumes.
[    2.725693] systemd[1]: Expecting device dev-disk-by\x2duuid-eebd40c0\x2d0a36\x2d4581\x2daab4\x2d6bd20a19da36.device...
          Expecting device dev-disk-by\x2duuid-eebd40c0\x2d0a3...9da36.device...
[    2.732348] systemd[1]: Expecting device dev-vdb1.device...
          Expecting device dev-vdb1.device...
[    2.738869] systemd[1]: Starting Root Slice.
[  OK  ] Created slice Root Slice.
[    2.742933] systemd[1]: Created slice Root Slice.
[    2.744877] systemd[1]: Starting User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    2.749831] systemd[1]: Created slice User and Session Slice.
[    2.752517] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    2.758597] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[    2.761460] systemd[1]: Starting Delayed Shutdown Socket.
[  OK  ] Listening on Delayed Shutdown Socket.
[    2.766014] systemd[1]: Listening on Delayed Shutdown Socket.
[    2.770448] systemd[1]: Starting Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    2.775245] systemd[1]: Listening on Journal Socket (/dev/log).
[    2.777978] systemd[1]: Starting udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    2.783183] systemd[1]: Listening on udev Control Socket.
[    2.785528] systemd[1]: Starting udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    2.789845] systemd[1]: Listening on udev Kernel Socket.
[    2.792005] systemd[1]: Starting Journal Socket.
[  OK  ] Listening on Journal Socket.
[    2.796246] systemd[1]: Listening on Journal Socket.
[    2.798314] systemd[1]: Starting System Slice.
[  OK  ] Created slice System Slice.
[    2.804575] systemd[1]: Created slice System Slice.
[    2.806614] systemd[1]: Started File System Check on Root Device.
[    2.808986] systemd[1]: Starting system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[    2.813432] systemd[1]: Created slice system-getty.slice.
[    2.815596] systemd[1]: Starting system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    2.820467] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    2.822936] systemd[1]: Starting Increase datagram queue length...
          Starting Increase datagram queue length...
[    2.844623] systemd[1]: Starting Load Kernel Modules...
          Starting Load Kernel Modules...
[    2.850230] systemd[1]: Started Set Up Additional Binary Formats.
[    2.853748] systemd[1]: Mounting POSIX Message Queue File System...
          Mounting POSIX Message Queue File System...
[    2.859803] systemd[1]: Starting udev Coldplug all Devices...
          Starting udev Coldplug all Devices...
[    2.865064] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[    2.872307] systemd-cgroups[151]: segfault at 15 ip 000056202b46e157 sp 00007ffeca612668 error 6          in systemd-cgroups-agent[56202b466000+36000]
[    2.878828] systemd-cgroups[153]: segfault at 15 ip 0000560f54ea1157 sp 00007ffee458dc18 error 6Starting Create list of required static device nodes...rrent kernel...
[    2.879974] systemd-cgroups[154]: segfault at 15 ip 0000559debe67157 sp 00007ffca4f34658 error 6 in systemd-cgroups-agent[559debe5f000+36000]
[    2.888278] systemd[1]: Mounting Huge Pages File System...

[    2.891561]  in systemd-cgroups-agent[560f54e99000+36000]
          Mounting Huge Pages File System...
[    2.897734] systemd[1]: Mounting Debug File System...
          Mounting Debug File System...[    2.903794] systemd-cgroups[157]: segfault at 15 ip 000055b0f134a157 sp 00007ffd6d0a99c8 error 6

[    2.907862] systemd[1]: Starting Slices.
[    2.908269] systemd-cgroups[158]: segfault at 15 ip 0000557f3c3dd157 sp 00007ffe98f97808 error 6 in systemd-cgroups-agent[557f3c3d5000+36000]
[    2.908367]  in systemd-cgroups-agent[55b0f1342000+36000]
[    2.912432] systemd-cgroups[161]: segfault at 15 ip 000055f1d8b02157 sp 00007fffbb01b0b8 error 6 in systemd-cgroups-agent[55f1d8afa000+36000]
[    2.913231] systemd-cgroups[159]: segfault at 15 ip 0000561c7b9d3157 sp 00007ffcade23e58 error 6 in systemd-cgroups-agent[561c7b9cb000+36000]
[  OK  ] Reached target Slices.
[    2.928283] systemd[1]: Reached target Slices.
[  OK  ] Mounted Debug File System.
[    2.935350] systemd[1]: Mounted Debug File System.
[  OK  ] Mounted Huge Pages File System.
[    2.939693] systemd[1]: Mounted Huge Pages File System.
[  OK  ] Mounted POSIX Message Queue File System.
[    2.944793] systemd[1]: Mounted POSIX Message Queue File System.
[  OK  ] Started Increase datagram queue length.
[    2.950584] systemd[1]: Started Increase datagram queue length.
[  OK  ] Started Load Kernel Modules.
[    2.955762] systemd[1]: Started Load Kernel Modules.
[  OK  ] Started Create list of required static device nodes ...current kernel.
[    2.964392] systemd[1]: Started Create list of required static device nodes for the current kernel.
[    2.969033] systemd[1]: Starting Create Static Device Nodes in /dev...
          Starting Create Static Device Nodes in /dev...[    2.973352] systemd-cgroups[164]: segfault at 15 ip 0000564999a00157 sp 00007fff9fccf348 error 6
  in systemd-cgroups-agent[5649999f8000+36000]
[    2.987649] systemd[1]: Mounted Configuration File System.
[    2.989963] systemd[1]: Starting Apply Kernel Variables...
          Starting Apply Kernel Variables...
[    2.998134] systemd[1]: Mounted FUSE Control File System.
[    3.000618] systemd[1]: Starting Syslog Socket.
[  OK  ] Listening on Syslog Socket.[    3.007540] systemd-cgroups[167]: segfault at 15 ip 000055917ce1e157 sp 00007fff11c1f818 error 6
[    3.010690] systemd[1]: Listening on Syslog Socket.
[    3.010734] systemd[1]: Starting Journal Service...

[    3.017756]  in systemd-cgroups-agent[55917ce16000+36000]
          Starting Journal Service...
[  OK  ] Started Journal Service.
[    3.026109] systemd[1]: Started Journal Service.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Started Apply Kernel Variables.
[    3.042805] systemd[1]: systemd-journald.service has no holdoff time, scheduling restart.
[  OK  ] Stopped Trigger Flushing of Journal to Persistent Storage.
[    3.049066] systemd[1]: Stopping Journal Service...
          Stopping Journal Service...
[  OK  ] Stopped Journal Service.
[    3.055114] systemd[1]: Starting Journal Service...
          Starting Journal Service...
[  OK  ] Started Journal Service.
[    3.073986] systemd[1]: Started Journal Service.
          Starting udev Kernel Device Manager...
[    3.080707] systemd[1]: systemd-journald.service has no holdoff time, scheduling restart.
[[    3.085337] systemd-udevd[173]: starting version 215
   OK  ] Stopped Trigger Flushing of Journal to Persistent Storage.
[    3.089673] systemd[1]: Stopping Journal Service...
          Stopping Journal Service...
[  OK  ] Stopped Journal Service.
[    3.101206] systemd[1]: Starting Journal Service...
          Starting Journal Service...
[  OK  ] Started Journal Service.
[    3.127619] systemd[1]: Started Journal Service.
[  OK  ] Started udev Kernel Device Manager.
[    3.140319] systemd[1]: systemd-journald.service has no holdoff time, scheduling restart.
[    3.148930] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
[[    3.155407] ACPI: Power Button [PWRF]
   OK  ] Stopped Trigger Flushing of Journal to Persistent Storage.
[    3.161542] systemd[1]: Stopping Journal Service...
[    3.162513] drm: Unknown symbol  (err 0)
[    3.162525] BUG: unable to handle kernel paging request at ffffc900934f6a88
[    3.162531] IP: [<ffffffff813121e4>] strcmp+0x4/0x20
[    3.162533] PGD 13b091067 PUD 0 [    3.162535] Oops: 0000 [#1] SMP
[    3.162553] Modules linked in: button(E) autofs4(E) ext4(E)
crc16(E) mbcache(E) jbd2(E) sg(E) sr_mod(E) cdrom(E) virtio_blk(E)
virtio_net(E) ata_generic(E) uhci_hcd(E) ehci_hcd(E) crc32c_intel(E)
ata_piix(E) psmouse(E) libata(E) floppy(E) virtio_pci(E) usbcore(E)
virtio_ring(E) virtio(E) usb_common(E) scsi_mod(E)
[    3.162557] CPU: 1 PID: 182 Comm: systemd-udevd Tainted: G            E   4.5.0-0.bpo.2-amd64 #1 Debian 4.5.3-2~bpo8+1
[    3.162558] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[    3.162559] task: ffff880036cbae40 ti: ffff8800bb048000 task.ti: ffff8800bb048000
[    3.162562] RIP: 0010:[<ffffffff813121e4>]  [<ffffffff813121e4>] strcmp+0x4/0x20
[    3.162564] RSP: 0018:ffff8800bb04bbc0  EFLAGS: 00010282
[    3.162564] RAX: 0000000000000fb3 RBX: 00000000000007d9 RCX: 0000000000000010
[    3.162565] RDX: 0000000000000fb3 RSI: ffffffff818cdbbd RDI: ffffc900934f6a89
[    3.162566] RBP: ffffffff810f5b30 R08: ffffffff810f5b30 R09: 000000000000001c
[    3.162567] R10: 0000000000000163 R11: 0000000000016ee8 R12: 0000000000000010
[    3.162568] R13: 0000000000000fb3 R14: 0000000000000000 R15: ffffffff8189abe0
[    3.162570] FS:  00007fc4cd1ac880(0000) GS:ffff88013fc80000(0000) knlGS:0000000000000000
[    3.162571] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[    3.162572] CR2: ffffc900934f6a88 CR3: 0000000036c0d000 CR4: 00000000001006e0
[    3.162576] Stack:
[    3.162578]  ffffffff8131e106 ffffc900934f6a88 ffffffff81892e50 ffff8800bb04bcd0
[    3.162580]  0000000000000000 ffffffff816102c0 ffff8800bb04bcd0 ffff8800bb04bf1c
[    3.162582]  0000000000000000 ffffffff810f5c99 ffffffff816102c0 ffff8800bb04bd28
[    3.162583] Call Trace:
[    3.162588]  [<ffffffff8131e106>] ? bsearch+0x56/0x90
[    3.162593]  [<ffffffff810f5c99>] ? find_symbol_in_section+0x39/0xc0
[    3.162595]  [<ffffffff810f5c60>] ? mod_find_symname+0x70/0x70
[    3.162597]  [<ffffffff810f6579>] ? each_symbol_section+0x39/0x170
[    3.162599]  [<ffffffff810c9da8>] ? log_store+0x1a8/0x280
[    3.162602]  [<ffffffff810bdd15>] ? down_trylock+0x25/0x30
[    3.162604]  [<ffffffff810ca5c5>] ? console_trylock+0x15/0x70
[    3.162606]  [<ffffffff810f6782>] ? find_symbol+0x42/0xb0
[    3.162609]  [<ffffffff810f7c72>] ? resolve_symbol.isra.51+0x62/0x100
[    3.162611]  [<ffffffff810f98f8>] ? load_module+0x12e8/0x2660
[    3.162613]  [<ffffffff810f6ee2>] ? copy_module_from_fd.isra.54+0x102/0x150
[    3.162616]  [<ffffffff810fae5e>] ? SYSC_finit_module+0x8e/0xc0
[    3.162620]  [<ffffffff815ba2b6>] ? system_call_fast_compare_end+0xc/0x6b
[    3.162650] Code: 74 09 48 83 c2 01 80 3a 00 75 f7 48 83 c6 01 0f
b6 4e ff 48 83 c2 01 84 c9 88 4a ff 75 ed f3 c3 0f 1f 80 00 00 00 00
48 83 c7 01 <0f> b6 47 ff 48 83 c6 01 3a 46 ff 75 07 84 c0 75 eb 31 c0
c3 19 [    3.162653] RIP  [<ffffffff813121e4>] strcmp+0x4/0x20
[    3.162653]  RSP <ffff8800bb04bbc0>
[    3.162654] CR2: ffffc900934f6a88
[    3.162656] ---[ end trace e198ffde7e9d057e ]---
          Stopping Journal Service...
[  OK  ] Stopped Journal Service.
[    3.285938] systemd[1]: Starting Journal Service...
          Starting Journal Service...
[  OK  ] Started Journal Service.
[    3.305269] systemd[1]: Started Journal Service.
          Starting Copy rules generated while the root was ro...
          Starting LSB: Set preliminary keymap...
[    3.355351] systemd[1]: Unit systemd-journald.service entered failed state.
[  OK  ] Started Copy rules generated while the root was ro.
[    3.360846] systemd[1]: Started Copy rules generated while the root was ro.
[    3.363958] systemd[1]: systemd-journald.service has no holdoff time, scheduling restart.
[  OK  ] Stopped Trigger Flushing of Journal to Persistent Storage.
[    3.370823] systemd[1]: Stopping Journal Service...
          Stopping Journal Service...
[  OK  ] Stopped Journal Service.
[    3.383188] systemd[1]: Starting Journal Service...
          Starting Journal Service...
[  OK  ] Started Journal Service.
[    3.409503] systemd[1]: Started Journal Service.
[    3.435400] systemd[1]: Unit systemd-journald.service entered failed state.
[    3.441848] systemd[1]: systemd-journald.service has no holdoff time, scheduling restart.
[  OK  ] Stopped Trigger Flushing of Journal to Persistent Storage.
[    3.448723] systemd[1]: Stopping Journal Service...
          Stopping Journal Service...
[  OK  ] Stopped Journal Service.
[    3.454824] systemd[1]: Starting Journal Service...
          Starting Journal Service...
[    3.458670] systemd[1]: systemd-journald.service start request repeated too quickly, refusing to start.
[FAILED] Failed to start Journal Service.
See 'systemctl status systemd-journald.service' for details.
[    3.466849] systemd[1]: Failed to start Journal Service.
[DEPEND] Dependency failed for Trigger Flushing of Journal to...istent Storage.
[    3.474414] systemd[1]: Dependency failed for Trigger Flushing of Journal to Persistent Storage.
[    3.477932] systemd[1]: Unit systemd-journald.service entered failed state.
[    3.480700] systemd[1]: Starting Journal Service...
          Starting Journal Service...
[    3.484531] systemd[1]: systemd-journald.service start request repeated too quickly, refusing to start.
[FAILED] Failed to start Journal Service.
See 'systemctl status systemd-journald.service' for details.
[    3.492809] systemd[1]: Failed to start Journal Service.
[    3.494986] systemd[1]: Unit systemd-journald.socket entered failed state.
[    3.495017] systemd-udevd[173]: worker [177] terminated by signal 11 (Segmentation fault)
[    3.495025] systemd-udevd[173]: worker [177] failed while handling '/devices/pci0000:00/0000:00:00.0'
[    3.495071] systemd-udevd[173]: worker [178] terminated by signal 11 (Segmentation fault)
[    3.495075] systemd-udevd[173]: worker [178] failed while handling '/devices/pci0000:00/0000:00:01.0'
[    3.495099] systemd-udevd[173]: worker [179] terminated by signal 11 (Segmentation fault)
[    3.495104] systemd-udevd[173]: worker [179] failed while handling '/devices/pci0000:00/0000:00:01.1'
[    3.495128] systemd-udevd[173]: worker [180] terminated by signal 11 (Segmentation fault)
[    3.495132] systemd-udevd[173]: worker [180] failed while handling '/devices/pci0000:00/0000:00:01.2'
[    3.495155] systemd-udevd[173]: worker [181] terminated by signal 11 (Segmentation fault)
[    3.495159] systemd-udevd[173]: worker [181] failed while handling '/devices/pci0000:00/0000:00:01.3'
[    3.495182] systemd-udevd[173]: worker [182] terminated by signal 9 (Killed)
[    3.495186] systemd-udevd[173]: worker [182] failed while handling '/devices/pci0000:00/0000:00:02.0'
[    3.580773] systemd-udevd[227]: ctx=0x55ec92d517d0 path=/lib/modules/4.5.0-0.bpo.2-amd64/kernel/drivers/virtio/virtio_ring.ko error=No such file or directory
[    3.589032] Module len 50008 truncated
[    3.594201] systemd-udevd[184]: 'cdrom_id --lock-media /dev/sr0' [229] terminated by signal 4 (Illegal instruction)
[    3.596203] systemd-udevd[186]: ctx=0x55ec92d517d0 path=/lib/modules/4.5.0-0.bpo.2-amd64/kernel/drivers/virtio/virtio_ring.ko error=No such file or directory
[  OK  ] Started LSB: Set preliminary keymap.
[    3.610097] systemd[1]: Started LSB: Set preliminary keymap.
[    3.612753] EXT4-fs error (device vda1): ext4_lookup:1602: inode #275136: comm systemd-udevd: deleted inode referenced: 275177
[    3.616385] systemd[1]: Starting Remount Root and Kernel File Systems...
          Starting Remount Root and Kernel File Systems...
[    3.648381] EXT4-fs error (device vda1): ext4_lookup:1602: inode #275136: comm systemd-udevd: deleted inode referenced: 275177
[    3.657519] EXT4-fs (vda1): warning: mounting fs with errors, running e2fsck is recommended
[    3.673001] EXT4-fs error (device vda1): ext4_lookup:1602: inode #275136: comm systemd-udevd: deleted inode referenced: 275195
[  OK  ] Found device /dev/ttyS0.
[    3.686088] systemd[1]: Found device /dev/ttyS0.
[    3.715352] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro
[    3.717080] Aborting journal on device vda1-8.
[  OK  ] Started Remount Root and Kernel File Systems.
[    3.726361] systemd[1]: Started Remount Root and Kernel File Systems.
[    3.729060] systemd[1]: Started Various fixups to make systemd work better on Debian.
[    3.732571] systemd[1]: Starting Load/Save Random Seed...
          Starting Load/Save Random Seed...
[    3.747636] systemd[1]: Starting Local File Systems (Pre).
[  OK  ] Reached target Local File Systems (Pre).
[    3.753734] EXT4-fs (vda1): Remounting filesystem read-only
[    3.756396] systemd[1]: Reached target Local File Systems (Pre).
[    3.757140] systemd[243]: Failed at step EXEC spawning /lib/systemd/systemd-random-seed: Exec format error
[    3.757591] systemd[1]: systemd-random-seed.service: main process exited, code=exited, status=203/EXEC
[FAILED] Failed to start Load/Save Random Seed.
See 'systemctl status systemd-random-seed.service' for details.
[    3.782413] systemd[1]: Failed to start Load/Save Random Seed.
[    3.785379] systemd[1]: Unit systemd-random-seed.service entered failed state.
[  OK  ] Found device /dev/disk/by-uuid/eebd40c0-0a36-4581-aab4-6bd20a19da36.
[    3.796808] systemd[1]: Found device /dev/disk/by-uuid/eebd40c0-0a36-4581-aab4-6bd20a19da36.
[    3.800773] systemd[1]: Activating swap /dev/disk/by-uuid/eebd40c0-0a36-4581-aab4-6bd20a19da36...
          Activating swap /dev/disk/by-uuid/eebd40c0-0a36-4581...6bd20a19da36...
[    3.829275] systemd[247]: Failed at step EXEC spawning /sbin/swapon: Exec format error
[    3.833223] systemd[1]: dev-disk-by\x2duuid-eebd40c0\x2d0a36\x2d4581\x2daab4\x2d6bd20a19da36.swap swap process exited, code=exited status=203
[FAILED] Failed to activate swap /dev/disk/by-uuid/eebd40c0-0...4-6bd20a19da36.
See 'systemctl status dev-disk-by\x2duuid-eebd40c0\x2d0a36\...wap' for details.
[    3.843375] systemd[1]: Failed to activate swap /dev/disk/by-uuid/eebd40c0-0a36-4581-aab4-6bd20a19da36.
[DEPEND] Dependency failed for Swap.
[    3.849994] systemd[1]: Dependency failed for Swap.
[    3.854919] systemd[1]: Unit dev-disk-by\x2duuid-eebd40c0\x2d0a36\x2d4581\x2daab4\x2d6bd20a19da36.swap entered failed state.
[  *** ] A start job is running for dev-vdb1.device (1min 29s / 1min 30s)[   92.857214] systemd[1]: Job dev-vdb1.device/start timed out.
[ TIME ] Timed out waiting for device dev-vdb1.device.
[   92.864756] systemd[1]: Timed out waiting for device dev-vdb1.device.
[DEPEND] Dependency failed for /srv/storage.
[   92.873411] systemd[1]: Dependency failed for /srv/storage.
[DEPEND] Dependency failed for Local File Systems.
[   92.878349] systemd[1]: Dependency failed for Local File Systems.
[   92.881247] systemd[1]: Triggering OnFailure= dependencies of local-fs.target.
[   92.885244] systemd[1]: Stopped Daily Cleanup of Temporary Directories.
[  OK  ] Closed ACPID Listen Socket.
[   92.891053] systemd[1]: Closed ACPID Listen Socket.
[   92.893060] systemd[1]: Stopped ACPI Events Check.
[  OK  ] Stopped Getty on tty1.
[   92.897150] systemd[1]: Stopped Getty on tty1.
[  OK  ] Stopped Serial Getty on ttyS0.
[   92.903538] systemd[1]: Stopped Serial Getty on ttyS0.
[  OK  ] Stopped getty on tty2-tty6 if dbus and logind are not available.
[   92.909314] systemd[1]: Stopped getty on tty2-tty6 if dbus and logind are not available.
[  OK  ] Stopped target Graphical Interface.
[   92.915755] systemd[1]: Stopped target Graphical Interface.
[  OK  ] Stopped target Multi-User System.
[   92.926645] systemd[1]: Stopped target Multi-User System.
[  OK  ] Stopped Deferred execution scheduler.
[   92.934466] systemd[1]: Stopped Deferred execution scheduler.
[  OK  ] Stopped Regular background program processing daemon.
[   92.946054] systemd[1]: Stopped Regular background program processing daemon.
[  OK  ] Stopped OpenBSD Secure Shell server.
[   92.951183] systemd[1]: Stopped OpenBSD Secure Shell server.
[  OK  ] Stopped A high performance web server and a reverse proxy server.
[   92.956427] systemd[1]: Stopped A high performance web server and a reverse proxy server.
[  OK  ] Stopped Munin Node.
[   92.961898] systemd[1]: Stopped Munin Node.
[  OK  ] Stopped /etc/rc.local Compatibility.[   92.968155] random: nonblocking pool is initialized

[   92.971052] systemd[1]: Stopped /etc/rc.local Compatibility.
[  OK  ] Stopped Login Service.
[   92.975729] systemd[1]: Stopped Login Service.
[   92.977966] systemd[1]: Starting Login Prompts.
[  OK  ] Reached target Login Prompts.
[   92.982820] systemd[1]: Reached target Login Prompts.
[  OK  ] Stopped LSB: Kernel NFS server support.
[   92.987952] systemd[1]: Stopped LSB: Kernel NFS server support.
[  OK  ] Stopped LSB: Set sysfs variables from /etc/sysfs.conf.
[   92.993014] systemd[1]: Stopped LSB: Set sysfs variables from /etc/sysfs.conf.
[  OK  ] Stopped LSB: start Samba daemons for the AD DC.
[   93.001099] systemd[1]: Stopped LSB: start Samba daemons for the AD DC.
[  OK  ] Stopped LSB: start Winbind daemon.
[   93.006883] systemd[1]: Stopped LSB: start Winbind daemon.
[  OK  ] Stopped LSB: start Samba SMB/CIFS daemon (smbd).
[   93.012233] systemd[1]: Stopped LSB: start Samba SMB/CIFS daemon (smbd).
[  OK  ] Stopped target Mail Transport Agent.
[   93.017211] systemd[1]: Stopped target Mail Transport Agent.
[  OK  ] Stopped LSB: Postfix Mail Transport Agent.
[   93.021987] systemd[1]: Stopped LSB: Postfix Mail Transport Agent.
[  OK  ] Stopped LSB: daemon to balance interrupts for SMP systems.
[   93.029300] systemd[1]: Stopped LSB: daemon to balance interrupts for SMP systems.
[  OK  ] Stopped LSB: start Samba NetBIOS nameserver (nmbd).
[   93.035343] systemd[1]: Stopped LSB: start Samba NetBIOS nameserver (nmbd).
[  OK  ] Stopped D-Bus System Message Bus.
[   93.041297] systemd[1]: Stopped D-Bus System Message Bus.
[  OK  ] Closed D-Bus System Message Bus Socket.
[   93.046656] systemd[1]: Closed D-Bus System Message Bus Socket.
[  OK  ] Stopped Permit User Sessions.
[   93.051267] systemd[1]: Stopped Permit User Sessions.
[   93.053356] systemd[1]: Starting Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[   93.057789] systemd[1]: Reached target Remote File Systems.
[  OK  ] Stopped System Logging Service.
[   93.064216] systemd[1]: Stopped System Logging Service.
[  OK  ] Stopped target Basic System.
[   93.068548] systemd[1]: Stopped target Basic System.
[   93.070773] systemd[1]: Starting Paths.
[  OK  ] Reached target Paths.
[   93.075207] systemd[1]: Reached target Paths.
[   93.077411] systemd[1]: Starting Timers.
[  OK  ] Reached target Timers.
[   93.081919] systemd[1]: Reached target Timers.
[  OK  ] Stopped target System Initialization.
[   93.086197] systemd[1]: Stopped target System Initialization.
[   93.088503] systemd[1]: Starting Create Volatile Files and Directories...
          Starting Create Volatile Files and Directories...
[   93.115614] systemd[1]: Starting LSB: IPv6 Recursive DNS Server discovery...
          Starting LSB: IPv6 Recursive DNS Server discovery...[   93.121763] systemd-tmpfiles[251]: [/usr/lib/tmpfiles.d/man-db.conf:1] Unknown user 'man'.

[   93.129180] systemd[1]: Starting LSB: Prepare console...
[   93.132959] systemd-tmpfiles[251]: [/usr/lib/tmpfiles.d/munin-common.conf:8] Unknown user 'munin'.
          Starting LSB: Prepare console...
[   93.138431] systemd-tmpfiles[251]: [/usr/lib/tmpfiles.d/systemd.conf:19] Unknown user 'systemd-network'.
[   93.139260] systemd[1]: Stopping Syslog Socket.
[   93.144721] systemd-tmpfiles[251]: [/usr/lib/tmpfiles.d/systemd.conf:20] Unknown user 'systemd-network'.
[  OK  ] [   93.148512] systemd-tmpfiles[251]: [/usr/lib/tmpfiles.d/systemd.conf:21] Unknown user 'systemd-network'.
Closed Syslog Socket.
[   93.152172] systemd[1]: Closed Syslog Socket.
[   93.152217] systemd[1]: Starting Sockets.
[   93.154130] show_signal_msg: 18 callbacks suppressed
[   93.154139] systemd-cgroups[258]: segfault at 15 ip 0000563fc050f157 sp 00007ffe3a664578 error 6 in systemd-cgroups-agent[563fc0507000+36000]
[  OK  [   93.167041] systemd-tmpfiles[251]: chmod(/var) failed: Read-only file system
] Reached target Sockets.[   93.170327] systemd-tmpfiles[251]: chmod(/var/log) failed: Read-only file system

[   93.174252] systemd-tmpfiles[251]: chmod(/tmp) failed: Read-only file system
[   93.174962] systemd[1]: Reached target Sockets.
[   93.174994] systemd[1]: Starting Emergency Shell...
          Starting Emergency Shell...
[  OK  ] Started Emergency Shell.
[   93.188280] systemd-tmpfiles[251]: rm_rf(/tmp): Read-only file system
[   93.188458] systemd[1]: Started Emergency Shell.
[   93.188740] systemd[1]: Starting Emergency Mode.
[   93.194852] systemd-tmpfiles[251]: chmod(/var/lib) failed: Read-only file system
[  OK  ] Reached target Emergency Mode.
[   93.199065] systemd[1]: Reached target Emergency Mode.
[   93.199150] systemd-tmpfiles[251]: chmod(/var/lib/systemd) failed: Read-only file system
[   93.199450] systemd-tmpfiles[251]: chmod(/var/lib/systemd/coredump) failed: Read-only file system
[   93.199480] systemd-tmpfiles[251]: Failed to create file /var/log/wtmp: Read-only file system
[   93.199500] systemd-tmpfiles[251]: Failed to create file /var/log/btmp: Read-only file system
[   93.199520] systemd-tmpfiles[251]: chmod(/var/cache) failed: Read-only file system
[   93.199544] systemd-tmpfiles[251]: chmod(/var/lib/container) failed: Read-only file system
[   93.199564] systemd-tmpfiles[251]: chmod(/var/spool) failed: Read-only file system
[   93.199577] systemd-tmpfiles[251]: chmod(/tmp/.X11-unix) failed: Read-only file system
[   93.199590] systemd-tmpfiles[251]: chmod(/tmp/.ICE-unix) failed: Read-only file system
[   93.199603] systemd-tmpfiles[251]: chmod(/tmp/.XIM-unix) failed: Read-only file system
[   93.199615] systemd-tmpfiles[251]: chmod(/tmp/.font-unix) failed: Read-only file system
[   93.199628] systemd-tmpfiles[251]: chmod(/tmp/.Test-unix) failed: Read-only file system
[   93.203187] systemd-cgroups[272]: segfault at 15 ip 0000561624113157 sp 00007fffaf8feb88 error 6 in systemd-cgroups-agent[56162410b000+36000]
[   93.219188] systemd-cgroups[282]: segfault at 15 ip 00005638a4dec157 sp 00007fff45be4138 error 6 in systemd-cgroups-agent[5638a4de4000+36000]
[   93.258615] systemd[1]: systemd-tmpfiles-setup.service: main process exited, code=exited, status=1/FAILURE
[FAILED] Failed to start Create Volatile Files and Directories.
See 'systemctl status systemd-tmpfiles-setup.service' for details.
[   93.298504] systemd[1]: Failed to start Create Volatile Files and Directories.
[   93.301460] systemd[1]: Unit systemd-tmpfiles-setup.service entered failed state.
[   93.304721] systemd[1]: rdnssd.service: control process exited, code=exited status=2
[FAILED] Failed to start LSB: IPv6 Recursive DNS Server discovery.
See 'systemctl status rdnssd.service' for details.
[   93.312761] systemd[1]: Failed to start LSB: IPv6 Recursive DNS Server discovery.
[   93.318385] systemd[1]: Unit rdnssd.service entered failed state.
[  OK  ] Started LSB: Prepare console.
[   93.323617] systemd[1]: Started LSB: Prepare console.
[   93.326438] systemd[1]: Starting LSB: Set console font and keymap...
          Starting LSB: Set console font and keymap...
[   93.343623] systemd[1]: Starting LSB: Raise network interfaces....
          Starting LSB: Raise network interfaces....
[   93.352941] systemd[1]: Starting Update UTMP about System Boot/Shutdown...
          Starting Update UTMP about System Boot/Shutdown...
[   93.365127] systemd-update-utmp[290]: Failed to write utmp record: Read-only file system
[   93.369841] systemd[1]: systemd-update-utmp.service: main process exited, code=exited, status=1/FAILURE
[FAILED] Failed to start Update UTMP about System Boot/Shutdown.[   93.377035] systemd-cgroups[294]: segfault at 15 ip 000055c54077c157 sp 00007ffd67521018 error 6
See 'systemctl status systemd-update-utmp.service' for details.

[   93.386566] systemd[1]: Failed to start Update UTMP about System Boot/Shutdown.
[   93.388418]  in systemd-cgroups-agent[55c540774000+36000]
[DEPEND] Dependency failed for Update UTMP about System Runlevel Changes.
[   93.397382] systemd[1]: Dependency failed for Update UTMP about System Runlevel Changes.
[   93.401378] systemd[1]: Unit systemd-update-utmp.service entered failed state.
[   93.693928] rm[348]: segfault at 8 ip 00007f50fa720e68 sp 00007ffe85ed16a0 error 4 in ld-2.19.so[7f50fa716000+20000]
[  OK  ] Started LSB: Set console font and keymap.
[   93.701798] systemd[1]: Started LSB: Set console font and keymap.
[   93.704024] systemd-cgroups[349]: segfault at 15 ip 0000556131dc5157 sp 00007fffdadd4778 error 6 in systemd-cgroups-agent[556131dbd000+36000]




----- End forwarded message -----

--
Sous les pavés, la plage

Reply | Threaded
Open this post in threaded view
|

Re: VM crashes with linux-image-4.5.0-0.bpo.2-amd64:amd64/jessie-backports on KVM-Host

Nicholas D Steeves
Hi Rolf,

"On 30 May 2016 at 03:32, Rolf Kutz <[hidden email]> wrote:

>
> After installing linux-image-4.5.0-0.bpo.2-amd64 on my KVM Host, I
> experience
> problems with a virtual machine. The virtual machine starts up ok moѕt of
> the
> time, but crashes after rebooting the VM. Switching the VM off and on again
> makes
> it start up ok, but again crash on reboot. I don't see any errors in the
> KVM-host logs. I've seen the bug with Kernel linux-image-4.5.0-0.bpo.2-amd64
> and
> linux-image-4.4.0-0.bpo.1-amd64 on the VM.
>
> Going back to linux-image-4.4.0-0.bpo.1-amd64 on the KVM-Server makes the
> problem
> disappear. Other VMs on that server run fine with both kernels. The VM in
> question is an NFS4-Server running Debian
> Jessie. The KVM-Images are on a BTRFS-Partition.

Just to clarify: 1. What kernel version is the VM host system?  2.
Have you installed mcelog and edac-utils on the host system?  3. Are
there any kernel, mce, or edac errors on the *host* system when you
encounter this crash?

> 10:08:47 rk@freckle:~$ virsh console storage
> Connected to domain storage
> Escape character is ^]
>
> Debian GNU/Linux 8 storage ttyS0
>
> storage login: rk
> Passwort: Letzte Anmeldung: Samstag, den 28. Mai 2016, 09:57:46 CEST von
> rkutz-nb.lan.zsze.de auf pts/0
> Linux storage 4.5.0-0.bpo.2-amd64 #1 SMP Debian 4.5.3-2~bpo8+1 (2016-05-13)
> x86_64
>
...
> 10:09:02 rk@storage:~$ dmesg [    0.000000] Linux version
> 4.5.0-0.bpo.2-amd64 ([hidden email]) (gcc version 4.9.2
> (Debian 4.9.2-10) ) #1 SMP Debian 4.5.3-2~bpo8+1 (2016-05-13)
> [    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-0.bpo.2-amd64
> root=UUID=4c43ff54-ff7c-470f-bf9c-391e4357a3b5 ro text console=tty0
> console=ttyS0,115200n8
...
> [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS
> 1.7.5-20140531_083030-gandalf 04/01/2014
> [    0.000000] Hypervisor detected: KVM
...

> [    0.000000] ACPI: RSDP 0x00000000000F0C30 000014 (v00 BOCHS )
> [    0.000000] ACPI: RSDT 0x00000000BFFE1927 000034 (v01 BOCHS  BXPCRSDT
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: FACP 0x00000000BFFE0B37 000074 (v01 BOCHS  BXPCFACP
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: DSDT 0x00000000BFFE0040 000AF7 (v01 BOCHS  BXPCDSDT
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: FACS 0x00000000BFFE0000 000040
> [    0.000000] ACPI: SSDT 0x00000000BFFE0BAB 000CBC (v01 BOCHS  BXPCSSDT
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: APIC 0x00000000BFFE1867 000088 (v01 BOCHS  BXPCAPIC
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: HPET 0x00000000BFFE18EF 000038 (v01 BOCHS  BXPCHPET
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: Local APIC address 0xfee00000
> [    0.000000] No NUMA configuration found
> [    0.000000] Faking a node at [mem 0x0000000000000000-0x000000013fffffff]
> [    0.000000] NODE_DATA(0) allocated [mem 0x13fff8000-0x13fffcfff]
> [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
> [    0.000000] kvm-clock: cpu 0, msr 1:3fff0001, primary cpu clock
> [    0.000000] kvm-clock: using sched offset of 7729218211 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-0x000000013fffffff]
> [    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-0x00000000bffdffff]
> [    0.000000]   node   0: [mem 0x0000000100000000-0x000000013fffffff]
> [    0.000000] Initmem setup node 0 [mem
> 0x0000000000001000-0x000000013fffffff]
> [    0.000000] On node 0 totalpages: 1048446
> [    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: 782304 pages, LIFO batch:31
> [    0.000000]   Normal zone: 4096 pages used for memmap
> [    0.000000]   Normal zone: 262144 pages, LIFO batch:31
...
> [    0.000000] Booting paravirtualized kernel on KVM
...
> [    0.444591] Disabling memory control group subsystem

Ok, this might be why cgroups are failing later on.

...

> [    0.711237] PCI host bridge to bus 0000:00
> [    0.712905] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
> [    0.715365] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff window]
> [    0.717879] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff window]
> [    0.749677] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf window]
> [    0.752139] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff window]
> [    0.756149] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff
> window]
> [    0.759078] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff
> window]
> [    0.762016] pci_bus 0000:00: root bus resource [bus 00-ff]
> [    0.764145] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
> [    0.764896] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
> [    0.765861] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
> [    0.774695] pci 0000:00:01.1: reg 0x20: [io  0xc120-0xc12f]
> [    0.778250] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io
> 0x01f0-0x01f7]
> [    0.780906] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
> [    0.783367] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io
> 0x0170-0x0177]
> [    0.787765] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
> [    0.790540] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300
> [    0.799439] pci 0000:00:01.2: reg 0x20: [io  0xc080-0xc09f]
> [    0.803387] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
> [    0.804072] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4
> ACPI
> [    0.807087] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4
> SMB
> [    0.810123] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000
> [    0.817679] pci 0000:00:02.0: reg 0x10: [mem 0xf4000000-0xf7ffffff]
> [    0.827102] pci 0000:00:02.0: reg 0x14: [mem 0xf8000000-0xfbffffff]
> [    0.834400] pci 0000:00:02.0: reg 0x18: [mem 0xfc050000-0xfc051fff]
> [    0.841704] pci 0000:00:02.0: reg 0x1c: [io  0xc0a0-0xc0bf]
> [    0.864118] pci 0000:00:02.0: reg 0x30: [mem 0xfc040000-0xfc04ffff pref]
> [    0.864601] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
> [    0.868059] pci 0000:00:03.0: reg 0x10: [io  0xc0c0-0xc0df]
> [    0.871391] pci 0000:00:03.0: reg 0x14: [mem 0xfc052000-0xfc052fff]
> [    0.888005] pci 0000:00:03.0: reg 0x30: [mem 0xfc000000-0xfc03ffff pref]
> [    0.888731] pci 0000:00:05.0: [1af4:1003] type 00 class 0x078000
> [    0.892193] pci 0000:00:05.0: reg 0x10: [io  0xc0e0-0xc0ff]
> [    0.895546] pci 0000:00:05.0: reg 0x14: [mem 0xfc053000-0xfc053fff]
> [    0.912719] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
> [    0.916189] pci 0000:00:06.0: reg 0x10: [io  0xc000-0xc03f]
> [    0.919520] pci 0000:00:06.0: reg 0x14: [mem 0xfc054000-0xfc054fff]
> [    0.936539] pci 0000:00:07.0: [1af4:1002] type 00 class 0x00ff00
> [    0.938385] pci 0000:00:07.0: reg 0x10: [io  0xc100-0xc11f]
> [    0.949626] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
> [    0.953128] pci 0000:00:08.0: reg 0x10: [io  0xc040-0xc07f]
> [    0.956405] pci 0000:00:08.0: reg 0x14: [mem 0xfc055000-0xfc055fff]
...
> [    1.002030] PCI: Using ACPI for IRQ routing
> [    1.005952] PCI: pci_cache_line_size set to 64 bytes
> [    1.006284] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
> [    1.006288] e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff]
...

> [    1.074615] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
> [    1.076868] pci 0000:00:01.0: PIIX3: Enabling Passive Release
> [    1.079052] pci 0000:00:01.0: Activating ISA DMA hang workarounds
> [    1.138382] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
> [    1.198305] pci 0000:00:02.0: Video device with shadowed ROM
> [    1.198406] PCI: CLS 0 bytes, default 64
> [    1.198516] Unpacking initramfs...
> [    1.577416] Freeing initrd memory: 15280K (ffff880036218000 -
> ffff880037104000)
> [    1.580520] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
> [    1.582902] software IO TLB [mem 0xbbfe0000-0xbffe0000] (64MB) mapped at
> [ffff8800bbfe0000-ffff8800bffdffff]
...

> [    1.726966] Freeing unused kernel memory: 1328K (ffffffff81b26000 -
> ffffffff81c72000)
> [    1.730137] Write protecting the kernel read-only data: 10240k
> [    1.732748] Freeing unused kernel memory: 248K (ffff8800015c2000 -
> ffff880001600000)
> [    1.740328] Freeing unused kernel memory: 1156K (ffff8800018df000 -
> ffff880001a00000)
> [    1.757185] x86/mm: Checked W+X mappings: passed, no W+X pages found.
> [    1.779460] systemd-udevd[65]: starting version 215
> [    1.782103] random: systemd-udevd urandom read with 2 bits of entropy
> available
> [    1.794611] virtio: module verification failed: signature and/or required
> key missing - tainting kernel

Tainted kernel.  How did this key verification failure of virtio module happen?

> [    1.907527] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy
> driver
...

> [    2.058430] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy
> driver
> [    2.077462] ata2.01: NODEV after polling detection
> [    2.077932] ata2.00: ATAPI: QEMU DVD-ROM, 2.1.2, max UDMA/100
> [    2.080945] ata2.00: configured for MWDMA2
> [    2.083672] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM
> 2.1. PQ: 0 ANSI: 5
> [    2.117473] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
> [    2.119807] virtio-pci 0000:00:06.0: virtio_pci: leaving for legacy
> driver
> [    2.173758] virtio-pci 0000:00:07.0: virtio_pci: leaving for legacy
> driver
> [    2.229423] virtio-pci 0000:00:08.0: virtio_pci: leaving for legacy
> driver
> [    2.238680]  vda: vda1 vda2 < vda5 >
> [    2.246073]  vdb: vdb1
...
> [    3.283240] EXT4-fs (vda1): mounted filesystem with ordered data mode.
> Opts: (null)
> [    6.307634] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT
> +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR)
> [    6.312583] systemd[1]: Detected virtualization 'kvm'.
> [    6.316890] systemd[1]: Detected architecture 'x86-64'.
> [    7.256189] systemd[1]: Inserted module 'autofs4'
> [    7.290052] systemd[1]: Set hostname to <storage>.
...

> [   16.879230] Adding 392188k swap on /dev/vda5.  Priority:-1 extents:1
> across:392188k FS
> [   16.930297] ppdev: user-space parallel port driver
> [   16.975515] [drm] Device Version 0.0
> [   16.979894] [drm] Compression level 0 log level 0
> [   16.985329] [drm] Currently using mode #0, list at 0x488
> [   16.991131] [drm] 12286 io pages at offset 0x1000000
> [   16.997187] [drm] 16777216 byte draw area at offset 0x0
> [   17.003456] [drm] RAM header offset: 0x3ffe000
> [   17.011343] [drm] rom modes offset 0x488 for 128 modes
> [   17.019792] [TTM] Zone  kernel: Available graphics memory: 1699632 kiB
> [   17.023421] [TTM] Initializing pool allocator
> [   17.025212] [TTM] Initializing DMA pool allocator
> [   17.027234] [drm] qxl: 16M of VRAM memory size
> [   17.033883] [drm] qxl: 63M of IO pages memory ready (VRAM domain)
> [   17.044373] [drm] qxl: 64M of Surface memory size
> [   17.061450] [drm] main mem slot 1 [f4000000,3ffe000]
> [   17.067808] [drm] surface mem slot 2 [f8000000,4000000]
> [   17.073988] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
> [   17.081851] [drm] No driver support for vblank timestamp query.
> [   17.093640] [drm] fb mappable at 0xF4000000, size 3145728
> [   17.102272] [drm] fb: depth 24, pitch 4096, width 1024, height 768
> [   17.111160] fbcon: qxldrmfb (fb0) is primary device
> [   17.216919] Console: switching to colour frame buffer device 128x48
> [   17.237108] qxl 0000:00:02.0: fb0: qxldrmfb frame buffer device
> [   17.256511] [drm] Initialized qxl 0.1.0 20120117 for 0000:00:02.0 on
> minor 0
> [   17.449130] SSE version of gcm_enc/dec engaged.
> [   17.560200] alg: No test for fips(ansi_cprng) (fips_ansi_cprng)
> [   18.527152] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro
> [   19.117665] EXT4-fs (vdb1): mounted filesystem with ordered data mode.
> Opts: (null)
> [   19.169568] systemd-journald[170]: Received request to flush runtime
> journal from PID 1
> [   27.503633] RPC: Registered named UNIX socket transport module.
> [   27.505537] RPC: Registered udp transport module.
> [   27.506947] RPC: Registered tcp transport module.
> [   27.508306] RPC: Registered tcp NFSv4.1 backchannel transport module.
> [   27.763407] FS-Cache: Loaded
> [   28.023532] FS-Cache: Netfs 'nfs' registered for caching
> [   28.126902] Installing knfsd (copyright (C) 1996 [hidden email]).
> [   30.202869] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state
> recovery directory
> [   30.205586] NFSD: starting 90-second grace period (net ffffffff81ae7440)

I wonder if something could be going wrong with the caching?

> [   43.847366] random: nonblocking pool is initialized
> 10:09:04 rk@storage:~$ sudo reboot
> [sudo] password for rk:          Starting Synchronise Hardware Clock to
> System Clock...

Reboot occurs without incidence.

> [    0.000000] Linux version 4.5.0-0.bpo.2-amd64
> ([hidden email]) (gcc version 4.9.2 (Debian 4.9.2-10) ) #1
> SMP Debian 4.5.3-2~bpo8+1 (2016-05-13)
> [    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-0.bpo.2-amd64
> root=UUID=4c43ff54-ff7c-470f-bf9c-391e4357a3b5 ro text console=tty0
> console=ttyS0,115200n8
> [    0.000000] x86/fpu: Legacy x87 FPU detected.
> [    0.000000] x86/fpu: Using 'lazy' FPU context switches.
> [    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-0x00000000bffdffff] usable
> [    0.000000] BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff]
> reserved
> [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff]
> reserved
> [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff]
> reserved
> [    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable
> [    0.000000] NX (Execute Disable) protection: active
> [    0.000000] SMBIOS 2.8 present.
> [    0.000000] Hypervisor detected: KVM
> [    0.000000] e820: last_pfn = 0x140000 max_arch_pfn = 0x400000000
> [    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT
> [    0.000000] e820: last_pfn = 0xbffe0 max_arch_pfn = 0x400000000
> [    0.000000] found SMP MP-table at [mem 0x000f0e70-0x000f0e7f] mapped at
> [ffff8800000f0e70]
> [    0.000000] RAMDISK: [mem 0x36218000-0x37103fff]
> [    0.000000] ACPI: Early table checksum verification disabled
> [    0.000000] ACPI: RSDP 0x00000000000F0C30 000014 (v00 BOCHS )
> [    0.000000] ACPI: RSDT 0x00000000BFFE1927 000034 (v01 BOCHS  BXPCRSDT
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: FACP 0x00000000BFFE0B37 000074 (v01 BOCHS  BXPCFACP
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: DSDT 0x00000000BFFE0040 000AF7 (v01 BOCHS  BXPCDSDT
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: FACS 0x00000000BFFE0000 000040
> [    0.000000] ACPI: SSDT 0x00000000BFFE0BAB 000CBC (v01 BOCHS  BXPCSSDT
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: APIC 0x00000000BFFE1867 000088 (v01 BOCHS  BXPCAPIC
> 00000001 BXPC 00000001)
> [    0.000000] ACPI: HPET 0x00000000BFFE18EF 000038 (v01 BOCHS  BXPCHPET
> 00000001 BXPC 00000001)
> [    0.000000] No NUMA configuration found
> [    0.000000] Faking a node at [mem 0x0000000000000000-0x000000013fffffff]
> [    0.000000] NODE_DATA(0) allocated [mem 0x13fff8000-0x13fffcfff]
> [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
> [    0.000000] kvm-clock: cpu 0, msr 1:3fff0001, primary cpu clock
> [    0.000000] kvm-clock: using sched offset of 146729586697 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-0x000000013fffffff]
> [    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-0x00000000bffdffff]
> [    0.000000]   node   0: [mem 0x0000000100000000-0x000000013fffffff]
> [    0.000000] Initmem setup node 0 [mem
> 0x0000000000001000-0x000000013fffffff]
> [    0.000000] ACPI: PM-Timer IO Port: 0x608
> [    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] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
> [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
> [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
> [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
> [    0.000000] Using ACPI (MADT) for SMP configuration information
> [    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
> [    0.000000] smpboot: Allowing 3 CPUs, 1 hotplug CPUs
> [    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 0xbffe0000-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.394597] Disabling memory control group subsystem
> [    0.396967] mce: CPU supports 10 MCE banks
> [    0.399140] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
> [    0.401494] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
> [    0.406375] Freeing SMP alternatives memory: 24K (ffffffff81c72000 -
> ffffffff81c78000)
> [    0.418321] ftrace: allocating 24125 entries in 95 pages
> [    0.457345] x2apic enabled
> [    0.459100] Switched APIC routing to physical x2apic.
> [    0.462668] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
> [    0.465464] smpboot: CPU0: Intel Westmere E56xx/L56xx/X56xx (Nehalem-C)
> (family: 0x6, model: 0x2c, stepping: 0x1)
> [    0.470914] Performance Events: unsupported p6 CPU model 44 no PMU
> driver, software events only.
> [    0.476347] x86: Booting SMP configuration:
> [    0.478497] .... node  #0, CPUs:      #1
> [    0.480639] kvm-clock: cpu 1, msr 1:3fff0041, secondary cpu clock
> [    0.502666] x86: Booted up 1 node, 2 CPUs
> [    0.502671] KVM setup async PF for cpu 1
> [    0.502677] kvm-stealtime: cpu 1, msr 13fc8dac0
> [    0.507806] smpboot: Total of 2 processors activated (9599.99 BogoMIPS)
> [    0.510868] devtmpfs: initialized
> [    0.518372] clocksource: jiffies: mask: 0xffffffff max_cycles:
> 0xffffffff, max_idle_ns: 7645041785100000 ns
> [    0.524142] pinctrl core: initialized pinctrl subsystem
> [    0.526703] NET: Registered protocol family 16
> [    0.540700] cpuidle: using governor ladder
> [    0.552812] cpuidle: using governor menu
> [    0.556962] ACPI: bus type PCI registered
> [    0.558677] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
> [    0.561517] PCI: Using configuration type 1 for base access
> [    0.573698] HugeTLB registered 2 MB page size, pre-allocated 0 pages
> [    0.576879] ACPI: Added _OSI(Module Device)
> [    0.578688] ACPI: Added _OSI(Processor Device)
> [    0.580477] ACPI: Added _OSI(3.0 _SCP Extensions)
> [    0.582375] ACPI: Added _OSI(Processor Aggregator Device)
> [    0.586767] ACPI: Interpreter enabled
> [    0.588803] ACPI: (supports S0 S3 S4 S5)
> [    0.590465] ACPI: Using IOAPIC for interrupt routing
> [    0.592460] PCI: Using host bridge windows from ACPI; if necessary, use
> "pci=nocrs" and report a bug
> [    0.602440] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
> [    0.604858] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments
> MSI]
> [    0.607424] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
> [    0.609869] acpi PNP0A03:00: fail to add MMCONFIG information, can't
> access extended PCI configuration space under this bridge.
...

> [    0.671142] PCI host bridge to bus 0000:00
> [    0.672788] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
> [    0.675268] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff window]
> [    0.677716] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff window]
> [    0.710977] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf window]
> [    0.714923] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff window]
> [    0.717386] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff
> window]
> [    0.720559] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff
> window]
> [    0.724019] pci_bus 0000:00: root bus resource [bus 00-ff]
> [    0.741297] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io
> 0x01f0-0x01f7]
> [    0.745610] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
> [    0.748001] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io
> 0x0170-0x0177]
> [    0.750605] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
> [    0.768689] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4
> ACPI
> [    0.771568] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4
> SMB
...
> [    1.545736] audit: initializing netlink subsys (disabled)
> [    1.547861] audit: type=2000 audit(1464422965.231:1): initialized
> [    1.551111] Initialise system trusted keyring
> [    1.553534] zbud: loaded
> [    1.556386] Key type asymmetric registered
> [    1.558168] Asymmetric key parser 'x509' registered
...
> [    1.576271] GHES: HEST is not enabled!
...
> [    1.664633] registered taskstats version 1
> [    1.666395] Loading compiled-in X.509 certificates
> [    1.668874] Loaded X.509 cert 'Debian Project: Ben Hutchings:
> 008a018dca80932630'

Ok, given the key loaded this time, I expect that this time the kernel
won't be tainted.

> [    1.747969] scsi_mod: module verification failed: signature and/or
> required key missing - tainting kernel

Nope :-(

> [    1.834626] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy
> driver
...
> [    1.984186] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy
...

> [    2.046832] virtio-pci 0000:00:06.0: virtio_pci: leaving for legacy
> driver
> [    2.100847] virtio-pci 0000:00:07.0: virtio_pci: leaving for legacy
> driver
> [    2.156786] virtio-pci 0000:00:08.0: virtio_pci: leaving for legacy
> driver
> [    2.167493]  vda: vda1 vda2 < vda5 >
> [    2.172983] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
> [    2.175548] cdrom: Uniform CD-ROM driver Revision: 3.20
> [    2.183142] sr 1:0:0:0: Attached scsi generic sg0 type 5
...
> Begin: Will now check root file system ... fsck from util-linux 2.25.2
> [/sbin/fsck.ext4 (1) -- /dev/vda1] fsck.ext4 -a -C0 /dev/vda1 /dev/vda1:
> clean, 52725/499712 files, 643179/1998336 blocks
> done.
> [    2.410152] EXT4-fs (vda1): mounted filesystem with ordered data mode.
> Opts: (null)
> done.

So far so good (except the that possible scsi_mod issue).

...
> [    2.513511] systemd[1]: Inserted module 'autofs4'
> [    2.518369] systemd[1]: Set hostname to <storage>.
...

> [    2.865064] systemd[1]: Starting Create list of required static device
> nodes for the current kernel...
> [    2.872307] systemd-cgroups[151]: segfault at 15 ip 000056202b46e157 sp
> 00007ffeca612668 error 6          in
> systemd-cgroups-agent[56202b466000+36000]
> [    2.878828] systemd-cgroups[153]: segfault at 15 ip 0000560f54ea1157 sp
> 00007ffee458dc18 error 6Starting Create list of required static device
> nodes...rrent kernel...
> [    2.879974] systemd-cgroups[154]: segfault at 15 ip 0000559debe67157 sp
> 00007ffca4f34658 error 6 in systemd-cgroups-agent[559debe5f000+36000]
> [    2.888278] systemd[1]: Mounting Huge Pages File System...

/\ Ok, this is where the real badness starts to happen /\

> [    2.891561]  in systemd-cgroups-agent[560f54e99000+36000]
>          Mounting Huge Pages File System...
> [    2.897734] systemd[1]: Mounting Debug File System...
>          Mounting Debug File System...[    2.903794] systemd-cgroups[157]:
> segfault at 15 ip 000055b0f134a157 sp 00007ffd6d0a99c8 error 6
>
> [    2.907862] systemd[1]: Starting Slices.
> [    2.908269] systemd-cgroups[158]: segfault at 15 ip 0000557f3c3dd157 sp
> 00007ffe98f97808 error 6 in systemd-cgroups-agent[557f3c3d5000+36000]
> [    2.908367]  in systemd-cgroups-agent[55b0f1342000+36000]
> [    2.912432] systemd-cgroups[161]: segfault at 15 ip 000055f1d8b02157 sp
> 00007fffbb01b0b8 error 6 in systemd-cgroups-agent[55f1d8afa000+36000]
> [    2.913231] systemd-cgroups[159]: segfault at 15 ip 0000561c7b9d3157 sp
> 00007ffcade23e58 error 6 in systemd-cgroups-agent[561c7b9cb000+36000]
> [  OK  ] Reached target Slices.

And continues, but "OK".

>          Starting Journal Service...
> [  OK  ] Started Journal Service.
> [    3.026109] systemd[1]: Started Journal Service.
> [  OK  ] Started udev Coldplug all Devices.
> [  OK  ] Started Create Static Device Nodes in /dev.
> [  OK  ] Started Apply Kernel Variables.
> [    3.042805] systemd[1]: systemd-journald.service has no holdoff time,
> scheduling restart.
...
> [    3.161542] systemd[1]: Stopping Journal Service...

Is this normal?

> [    3.162513] drm: Unknown symbol  (err 0)

Is this normal?

> [    3.162525] BUG: unable to handle kernel paging request at
> ffffc900934f6a88
> [    3.162531] IP: [<ffffffff813121e4>] strcmp+0x4/0x20
> [    3.162533] PGD 13b091067 PUD 0 [    3.162535] Oops: 0000 [#1] SMP [
> 3.162553] Modules linked in: button(E) autofs4(E) ext4(E) crc16(E)
> mbcache(E) jbd2(E) sg(E) sr_mod(E) cdrom(E) virtio_blk(E) virtio_net(E)
> ata_generic(E) uhci_hcd(E) ehci_hcd(E) crc32c_intel(E) ata_piix(E)
> psmouse(E) libata(E) floppy(E) virtio_pci(E) usbcore(E) virtio_ring(E)
> virtio(E) usb_common(E) scsi_mod(E)
> [    3.162557] CPU: 1 PID: 182 Comm: systemd-udevd Tainted: G            E
> 4.5.0-0.bpo.2-amd64 #1 Debian 4.5.3-2~bpo8+1
> [    3.162558] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS
> 1.7.5-20140531_083030-gandalf 04/01/2014
> [    3.162559] task: ffff880036cbae40 ti: ffff8800bb048000 task.ti:
> ffff8800bb048000
> [    3.162562] RIP: 0010:[<ffffffff813121e4>]  [<ffffffff813121e4>]
> strcmp+0x4/0x20
> [    3.162564] RSP: 0018:ffff8800bb04bbc0  EFLAGS: 00010282
> [    3.162564] RAX: 0000000000000fb3 RBX: 00000000000007d9 RCX:
> 0000000000000010
> [    3.162565] RDX: 0000000000000fb3 RSI: ffffffff818cdbbd RDI:
> ffffc900934f6a89
> [    3.162566] RBP: ffffffff810f5b30 R08: ffffffff810f5b30 R09:
> 000000000000001c
> [    3.162567] R10: 0000000000000163 R11: 0000000000016ee8 R12:
> 0000000000000010
> [    3.162568] R13: 0000000000000fb3 R14: 0000000000000000 R15:
> ffffffff8189abe0
> [    3.162570] FS:  00007fc4cd1ac880(0000) GS:ffff88013fc80000(0000)
> knlGS:0000000000000000
> [    3.162571] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> [    3.162572] CR2: ffffc900934f6a88 CR3: 0000000036c0d000 CR4:
> 00000000001006e0
> [    3.162576] Stack:
> [    3.162578]  ffffffff8131e106 ffffc900934f6a88 ffffffff81892e50
> ffff8800bb04bcd0
> [    3.162580]  0000000000000000 ffffffff816102c0 ffff8800bb04bcd0
> ffff8800bb04bf1c
> [    3.162582]  0000000000000000 ffffffff810f5c99 ffffffff816102c0
> ffff8800bb04bd28

\//\ And the bad bad badness /\\/

> [    3.162583] Call Trace:
> [    3.162588]  [<ffffffff8131e106>] ? bsearch+0x56/0x90
> [    3.162593]  [<ffffffff810f5c99>] ? find_symbol_in_section+0x39/0xc0
> [    3.162595]  [<ffffffff810f5c60>] ? mod_find_symname+0x70/0x70
> [    3.162597]  [<ffffffff810f6579>] ? each_symbol_section+0x39/0x170
> [    3.162599]  [<ffffffff810c9da8>] ? log_store+0x1a8/0x280
> [    3.162602]  [<ffffffff810bdd15>] ? down_trylock+0x25/0x30
> [    3.162604]  [<ffffffff810ca5c5>] ? console_trylock+0x15/0x70
> [    3.162606]  [<ffffffff810f6782>] ? find_symbol+0x42/0xb0
> [    3.162609]  [<ffffffff810f7c72>] ? resolve_symbol.isra.51+0x62/0x100
> [    3.162611]  [<ffffffff810f98f8>] ? load_module+0x12e8/0x2660
> [    3.162613]  [<ffffffff810f6ee2>] ?

This makes me wonder if your ram is bad, of if your VM image is corrupt.

> copy_module_from_fd.isra.54+0x102/0x150
> [    3.162616]  [<ffffffff810fae5e>] ? SYSC_finit_module+0x8e/0xc0
> [    3.162620]  [<ffffffff815ba2b6>] ? system_call_fast_compare_end+0xc/0x6b
> [    3.162650] Code: 74 09 48 83 c2 01 80 3a 00 75 f7 48 83 c6 01 0f b6 4e
> ff 48 83 c2 01 84 c9 88 4a ff 75 ed f3 c3 0f 1f 80 00 00 00 00 48 83 c7 01
> <0f> b6 47 ff 48 83 c6 01 3a 46 ff 75 07 84 c0 75 eb 31 c0 c3 19 [
> 3.162653] RIP  [<ffffffff813121e4>] strcmp+0x4/0x20
> [    3.162653]  RSP <ffff8800bb04bbc0>
> [    3.162654] CR2: ffffc900934f6a88
> [    3.162656] ---[ end trace e198ffde7e9d057e ]---
...
> [    3.458670] systemd[1]: systemd-journald.service start request repeated
> too quickly, refusing to start.
> [FAILED] Failed to start Journal Service.
> See 'systemctl status systemd-journald.service' for details.

This could be informative. "systemctl status systemd-journald.service"

> [    3.466849] systemd[1]: Failed to start Journal Service.
> [DEPEND] Dependency failed for Trigger Flushing of Journal to...istent
> Storage.
> [    3.474414] systemd[1]: Dependency failed for Trigger Flushing of Journal
> to Persistent Storage.

Wasn't the journal disabled earlier?

> [    3.477932] systemd[1]: Unit systemd-journald.service entered failed
> state.
> [    3.480700] systemd[1]: Starting Journal Service...
>          Starting Journal Service...
> [    3.484531] systemd[1]: systemd-journald.service start request repeated
> too quickly, refusing to start.
> [FAILED] Failed to start Journal Service.
> See 'systemctl status systemd-journald.service' for details.
> [    3.492809] systemd[1]: Failed to start Journal Service.
> [    3.494986] systemd[1]: Unit systemd-journald.socket entered failed
> state.

We're in a loop.

> [    3.495017] systemd-udevd[173]: worker [177] terminated by signal 11
> (Segmentation fault)
> [    3.495025] systemd-udevd[173]: worker [177] failed while handling
> '/devices/pci0000:00/0000:00:00.0'
> [    3.495071] systemd-udevd[173]: worker [178] terminated by signal 11
> (Segmentation fault)
> [    3.495075] systemd-udevd[173]: worker [178] failed while handling
> '/devices/pci0000:00/0000:00:01.0'
> [    3.495099] systemd-udevd[173]: worker [179] terminated by signal 11
> (Segmentation fault)
> [    3.495104] systemd-udevd[173]: worker [179] failed while handling
> '/devices/pci0000:00/0000:00:01.1'
> [    3.495128] systemd-udevd[173]: worker [180] terminated by signal 11
> (Segmentation fault)
> [    3.495132] systemd-udevd[173]: worker [180] failed while handling
> '/devices/pci0000:00/0000:00:01.2'
> [    3.495155] systemd-udevd[173]: worker [181] terminated by signal 11
> (Segmentation fault)
> [    3.495159] systemd-udevd[173]: worker [181] failed while handling
> '/devices/pci0000:00/0000:00:01.3'
> [    3.495182] systemd-udevd[173]: worker [182] terminated by signal 9
> (Killed)
> [    3.495186] systemd-udevd[173]: worker [182] failed while handling
> '/devices/pci0000:00/0000:00:02.0'

A bunch of pci-related errors.  Are you using virtio and/or pcie
passthrough?  I wonder if there are complementary errors in the dmesg
of the host kernel?

> [    3.580773] systemd-udevd[227]: ctx=0x55ec92d517d0
> path=/lib/modules/4.5.0-0.bpo.2-amd64/kernel/drivers/virtio/virtio_ring.ko
> error=No such file or directory
> [    3.589032] Module len 50008 truncated

This makes me think your VM image is corrupted.

> [    3.594201] systemd-udevd[184]: 'cdrom_id --lock-media /dev/sr0' [229]
> terminated by signal 4 (Illegal instruction)
> [    3.596203] systemd-udevd[186]: ctx=0x55ec92d517d0
> path=/lib/modules/4.5.0-0.bpo.2-amd64/kernel/drivers/virtio/virtio_ring.ko
> error=No such file or directory

Well that's odd...again, I suspect VM image corruption.

> [    3.612753] EXT4-fs error (device vda1): ext4_lookup:1602: inode #275136:
> comm systemd-udevd: deleted inode referenced: 275177
> [    3.616385] systemd[1]: Starting Remount Root and Kernel File Systems...
>          Starting Remount Root and Kernel File Systems...
> [    3.648381] EXT4-fs error (device vda1): ext4_lookup:1602: inode #275136:
> comm systemd-udevd: deleted inode referenced: 275177
> [    3.657519] EXT4-fs (vda1): warning: mounting fs with errors, running
> e2fsck is recommended
> [    3.673001] EXT4-fs error (device vda1): ext4_lookup:1602: inode #275136:
> comm systemd-udevd: deleted inode referenced: 275195
> [  OK  ] Found device /dev/ttyS0.
> [    3.686088] systemd[1]: Found device /dev/ttyS0.
> [    3.715352] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro
> [    3.717080] Aborting journal on device vda1-8.
...
> /lib/systemd/systemd-random-seed: Exec format error

My gut feeling is your VM images are corrupted, and/or that you have
bad ram.  I also wonder if it could be a virtio and/or pcie
passthrough issue if either are enabled...

Could you please tell me a bit about your btrfs topology and which
features you've enabled?  Also, with what version of btrfs-progs did
you create the volume?  Have you ever run a btrfs-scrub, btrfs-check,
or btrfs-balance on the host? (running which kernel, with which
version of btrfs-progs...)  If you haven't yet, please don't run
either until we discuss this some more.  Was your btrfs volume created
with btrfs-convert?  Are you running the host's btrfs volume on top of
LVM, MD, and/or in combination with a caching layer?

In addition to answering all of these questions, could you please
trigger this again and send the host dmesg?

Finally, because you're testing btrfs, you have recent backups, and
backups from before this error manifested, right?

Cheers!
Nicholas

Reply | Threaded
Open this post in threaded view
|

Re: VM crashes with linux-image-4.5.0-0.bpo.2-amd64:amd64/jessie-backports on KVM-Host

Rolf Kutz-2
Hi Nicholaѕ,

thanks for your mail and sorry for my late answer.
I did some more examination and that fixed the
problem somehow.

On 30/05/16 22:43 -0400, Nicholas D Steeves wrote:

>"On 30 May 2016 at 03:32, Rolf Kutz <[hidden email]> wrote:
>> Going back to linux-image-4.4.0-0.bpo.1-amd64 on the KVM-Server makes the
>> problem
>> disappear. Other VMs on that server run fine with both kernels. The VM in
>> question is an NFS4-Server running Debian
>> Jessie. The KVM-Images are on a BTRFS-Partition.
>
>Just to clarify: 1. What kernel version is the VM host system?  2.
>Have you installed mcelog and edac-utils on the host system?  3. Are
>there any kernel, mce, or edac errors on the *host* system when you
>encounter this crash?

1.) linux-image-4.5.0-0.bpo.2-amd64 when the problem was
happening, linux-image-4.4.0-0.bpo.1-amd64 when
not.

2.) I had those utilities installed and they
didn't report a problem.

3.) No errors on the host system.

>> [    1.794611] virtio: module verification failed: signature and/or required
>> key missing - tainting kernel
>
>Tainted kernel.  How did this key verification failure of virtio module happen?

I don't know. But I don't see it any more with
virtio, but with scsi_mod on all my VMs like this:

>> [    1.747969] scsi_mod: module verification failed: signature and/or
>> required key missing - tainting kernel

[...]

>> [    3.162613]  [<ffffffff810f6ee2>] ?
>
>This makes me wonder if your ram is bad, of if your VM image is corrupt.


>A bunch of pci-related errors.  Are you using virtio and/or pcie
>passthrough?  I wonder if there are complementary errors in the dmesg
>of the host kernel?

No, there are no messages in dmesg. I'm using
virtio, but no passthrough.

>Well that's odd...again, I suspect VM image corruption.
>My gut feeling is your VM images are corrupted, and/or that you have
>bad ram.  I also wonder if it could be a virtio and/or pcie
>passthrough issue if either are enabled...

RAM seems to be fine. Using ECC-RAM and no errors
where reported. I also would't suspect the same
outcome every time, with a RAM error.

>Could you please tell me a bit about your btrfs topology and which
>features you've enabled?  

Some information about the btrfs topology:

# mount |grep btrfs
/dev/sdc1 on /srv/storage type btrfs (rw,noatime,compress=lzo,space_cache,autodefrag,subvolid=5,subvol=/)

# btrfs filesystem show
Label: 'BTRFS_RAID'  uuid: 206a4530-6aff-4383-bb84-8cbf740eac1d
         Total devices 2 FS bytes used 1.03TiB
         devid    1 size 3.64TiB used 1.07TiB path /dev/sdc1
         devid    2 size 3.64TiB used 1.07TiB path /dev/sdd1

# btrfs fi df /srv/storage/
Data, RAID1: total=1.06TiB, used=1.03TiB
Data, single: total=8.00MiB, used=0.00B
System, RAID1: total=8.00MiB, used=176.00KiB
System, single: total=4.00MiB, used=0.00B
Metadata, RAID1: total=3.00GiB, used=1.28GiB
Metadata, single: total=8.00MiB, used=0.00B
GlobalReserve, single: total=448.00MiB, used=0.00B

>Also, with what version of btrfs-progs did
>you create the volume?  

ii  btrfs-tools    3.17-1.1     amd64 Checksumming Copy on Write Files

I just noticed, that they where replaced by btrfs-progs, which I installed now.

>Have you ever run a btrfs-scrub, btrfs-check,
>or btrfs-balance on the host? (running which kernel, with which
>version of btrfs-progs...)  If you haven't yet, please don't run
>either until we discuss this some more.  Was your btrfs volume created
>with btrfs-convert?  Are you running the host's btrfs volume on top of
>LVM, MD, and/or in combination with a caching layer?

I did run a scrub whith the above tools. Don't
know the exact kernel version, but it was a kernel
from jessie-backports, probably 4.3.x. No errors
reported. Never did a balance or convert.

Do you think I should do it again with the newer
btrfs-progs?

>In addition to answering all of these questions, could you please
>trigger this again and send the host dmesg?

I did some testing and after installing some
different kernel versions on the VM, I couldn't
trigger the problem anymore. The system is stable
for weeks now. I suspect the VM-Image got
corrupted. I still can't explain, why it always
booted without problems the first time and crashed
on reboot.

>Finally, because you're testing btrfs, you have recent backups, and
>backups from before this error manifested, right?

Yes, I have backups of my data. :)

thanks and best regards
Rolf

--
People should not be afraid of their governments, governments should be
afraid of their people. - V