Arch Linux

ArgelErx

Member

Registered: 2013-12-30

Posts: 13

[SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Hello.

I am experiencing problems with qxl/Xfce4 and kernel >= 4.12.x

Doing this upgrade

core/linux                   4.11.9-1  -> 4.12.4-1

will break my virtual machines desktop enviroment using Xfce4. The desktop becomes unusable: no resizing (of the VM), no window manager decorations, windows open iconized, parts of the desktop do not update ...

Downgrading to 4.11.9-1 fixes all the issues.

When i first encountered this last week, i did find a report about an incompabilty between qxl and kernel 4.12 (can't find it right now). Today i tested this again, hoping that this upgrade

community/xf86-video-qxl     0.1.5-3   3 -> 4

would solve the issue. No luck.

My console based VMs work without problems.

Has anybody experienced similar problems?
Does anybody know whats going on here?
Has anybody a solution?

Currently i am pinning core/linux 4.11.9-1 inside my VMs.

Last edited by ArgelErx (2018-02-03 09:10:32)

Lone_Wolf

Forum Moderator

From: Netherlands, Europe

Registered: 2005-10-04

Posts: 11,925

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Is the problem in the VMs or the host ?

Does the problem also occur if you run openbox or twm ?

Last edited by Lone_Wolf (2017-08-12 16:35:18)

---

Disliking systemd intensely, but not satisfied with alternatives so focusing on taming systemd.

(A works at time B)  && (time C > time B ) ≠  (A works at time C)

ArgelErx

Member

Registered: 2013-12-30

Posts: 13

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Problem is in the VMs. Host uses linux-lts/KVM/QEMU/libvirt.

Cloned my Archlinux base VM (Archlinux after installation with minor additions).

Did a

yaourt -Sayu

kernel was upgraded.

core/linux 4.12.4-1 -> 4.12.5-1

Reboot of the VM.

Followed https://wiki.archlinux.org/index.php/Twm for installtion of twm. Did a

yaourt -S xf86-video-qxl xorg-twm xorg xorg-xinit xterm

Created ~/.xinitrc, did a

startx

Checked Xorg.log: qxl is used.

Almost the same problems: As soon as i start dragging around xterm windows the desktop and windows are not refreshed, the resizing grid starts covering the screen, rendering the desktop unusable.

The twm menu works, new windows are drawn as expected, mouse works as expected. Switching VTs (STRG+ALT+x) back and forth refreshes the desktop. Executeing commands inside xterm (in this case htop) works (until i drag the window around).

After twm menu the part of the desktop that was covered by the menu is refreshed.

Tried this with virt-manager and remote-viewer.

Did not try openbox (standalone or with Xfce4).

yaourt: https://aur.archlinux.org/packages/yaourt/, https://archlinux.fr/yaourt-en

Lone_Wolf

Forum Moderator

From: Netherlands, Europe

Registered: 2005-10-04

Posts: 11,925

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Sounds like it could have to do with absence of a compositor .

Try installing one of the standalone compositors or a WM that does compositing, https://wiki.archlinux.org/index.php/Xorg#Composite

---

Disliking systemd intensely, but not satisfied with alternatives so focusing on taming systemd.

(A works at time B)  && (time C > time B ) ≠  (A works at time C)

ArgelErx

Member

Registered: 2013-12-30

Posts: 13

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Enabled the Xfwm compositor: https://wiki.archlinux.org/index.php/Xf … te_manager.

Did a

yaourt -Sayu

extra/xorg-server                  1.19.3-2                   2 -> 3
core/linux                         4.11.9-1                   -> 4.12.6-1
extra/spice                        0.12.8-2                   -> 0.12.8+8+ga957a90b-1

Rebooted VM. No luck.

Lone_Wolf

Forum Moderator

From: Netherlands, Europe

Registered: 2005-10-04

Posts: 11,925

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

We'll have to look deeper then, post dmesg and/or journal and xorg log from a guest .

---

Disliking systemd intensely, but not satisfied with alternatives so focusing on taming systemd.

(A works at time B)  && (time C > time B ) ≠  (A works at time C)

ArgelErx

Member

Registered: 2013-12-30

Posts: 13

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

After Xfce4 session ist started.

journalctl -b

Aug 15 20:11:31 test kernel: Linux version 4.12.6-1-ARCH (builduser@tobias) (gcc version 7.1.1 20170630 (GCC) ) #1 SMP PREEMPT Sat Aug 12 09:16:22 CEST 2017
Aug 15 20:11:31 test kernel: Command line: BOOT_IMAGE=/vmlinuz-linux root=UUID=07e16958-47ec-425f-b64d-62a7c1b49124 rw cryptdevice=UUID=848f5472-261d-46f5-9ceb-8f98d83ad36a:cryptroot systemd.legacy_systemd_cgroup_controller=true quiet
Aug 15 20:11:31 test kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Aug 15 20:11:31 test kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Aug 15 20:11:31 test kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Aug 15 20:11:31 test kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Aug 15 20:11:31 test kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
Aug 15 20:11:31 test kernel: e820: BIOS-provided physical RAM map:
Aug 15 20:11:31 test kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
Aug 15 20:11:31 test kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
Aug 15 20:11:31 test kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
Aug 15 20:11:31 test kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffddfff] usable
Aug 15 20:11:31 test kernel: BIOS-e820: [mem 0x000000007ffde000-0x000000007fffffff] reserved
Aug 15 20:11:31 test kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
Aug 15 20:11:31 test kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
Aug 15 20:11:31 test kernel: NX (Execute Disable) protection: active
Aug 15 20:11:31 test kernel: SMBIOS 2.8 present.
Aug 15 20:11:31 test kernel: DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-20170228_101828-anatol 04/01/2014
Aug 15 20:11:31 test kernel: Hypervisor detected: KVM
Aug 15 20:11:31 test kernel: tsc: Fast TSC calibration using PIT
Aug 15 20:11:31 test kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Aug 15 20:11:31 test kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Aug 15 20:11:31 test kernel: e820: last_pfn = 0x7ffde max_arch_pfn = 0x400000000
Aug 15 20:11:31 test kernel: MTRR default type: write-back
Aug 15 20:11:31 test kernel: MTRR fixed ranges enabled:
Aug 15 20:11:31 test kernel:   00000-9FFFF write-back
Aug 15 20:11:31 test kernel:   A0000-BFFFF uncachable
Aug 15 20:11:31 test kernel:   C0000-FFFFF write-protect
Aug 15 20:11:31 test kernel: MTRR variable ranges enabled:
Aug 15 20:11:31 test kernel:   0 base 0080000000 mask FF80000000 uncachable
Aug 15 20:11:31 test kernel:   1 disabled
Aug 15 20:11:31 test kernel:   2 disabled
Aug 15 20:11:31 test kernel:   3 disabled
Aug 15 20:11:31 test kernel:   4 disabled
Aug 15 20:11:31 test kernel:   5 disabled
Aug 15 20:11:31 test kernel:   6 disabled
Aug 15 20:11:31 test kernel:   7 disabled
Aug 15 20:11:31 test kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
Aug 15 20:11:31 test kernel: found SMP MP-table at [mem 0x000f6a50-0x000f6a5f] mapped at [ffff8af7800f6a50]
Aug 15 20:11:31 test kernel: Scanning 1 areas for low memory corruption
Aug 15 20:11:31 test kernel: Base memory trampoline at [ffff8af780099000] 99000 size 24576
Aug 15 20:11:31 test kernel: BRK [0x67d5f000, 0x67d5ffff] PGTABLE
Aug 15 20:11:31 test kernel: BRK [0x67d60000, 0x67d60fff] PGTABLE
Aug 15 20:11:31 test kernel: BRK [0x67d61000, 0x67d61fff] PGTABLE
Aug 15 20:11:31 test kernel: BRK [0x67d62000, 0x67d62fff] PGTABLE
Aug 15 20:11:31 test kernel: BRK [0x67d63000, 0x67d63fff] PGTABLE
Aug 15 20:11:31 test kernel: BRK [0x67d64000, 0x67d64fff] PGTABLE
Aug 15 20:11:31 test kernel: RAMDISK: [mem 0x36e7c000-0x37735fff]
Aug 15 20:11:31 test kernel: ACPI: Early table checksum verification disabled
Aug 15 20:11:31 test kernel: ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS )
Aug 15 20:11:31 test kernel: ACPI: RSDT 0x000000007FFE1348 00002C (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
Aug 15 20:11:31 test kernel: ACPI: FACP 0x000000007FFE125C 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
Aug 15 20:11:31 test kernel: ACPI: DSDT 0x000000007FFE0040 00121C (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
Aug 15 20:11:31 test kernel: ACPI: FACS 0x000000007FFE0000 000040
Aug 15 20:11:31 test kernel: ACPI: APIC 0x000000007FFE12D0 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
Aug 15 20:11:31 test kernel: ACPI: Local APIC address 0xfee00000
Aug 15 20:11:31 test kernel: No NUMA configuration found
Aug 15 20:11:31 test kernel: Faking a node at [mem 0x0000000000000000-0x000000007ffddfff]
Aug 15 20:11:31 test kernel: NODE_DATA(0) allocated [mem 0x7ffd9000-0x7ffddfff]
Aug 15 20:11:31 test kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Aug 15 20:11:31 test kernel: kvm-clock: cpu 0, msr 0:7ffd7001, primary cpu clock
Aug 15 20:11:31 test kernel: kvm-clock: using sched offset of 101335195755 cycles
Aug 15 20:11:31 test kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Aug 15 20:11:31 test kernel: Zone ranges:
Aug 15 20:11:31 test kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Aug 15 20:11:31 test kernel:   DMA32    [mem 0x0000000001000000-0x000000007ffddfff]
Aug 15 20:11:31 test kernel:   Normal   empty
Aug 15 20:11:31 test kernel:   Device   empty
Aug 15 20:11:31 test kernel: Movable zone start for each node
Aug 15 20:11:31 test kernel: Early memory node ranges
Aug 15 20:11:31 test kernel:   node   0: [mem 0x0000000000001000-0x000000000009efff]
Aug 15 20:11:31 test kernel:   node   0: [mem 0x0000000000100000-0x000000007ffddfff]
Aug 15 20:11:31 test kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffddfff]
Aug 15 20:11:31 test kernel: On node 0 totalpages: 524156
Aug 15 20:11:31 test kernel:   DMA zone: 64 pages used for memmap
Aug 15 20:11:31 test kernel:   DMA zone: 21 pages reserved
Aug 15 20:11:31 test kernel:   DMA zone: 3998 pages, LIFO batch:0
Aug 15 20:11:31 test kernel:   DMA32 zone: 8128 pages used for memmap
Aug 15 20:11:31 test kernel:   DMA32 zone: 520158 pages, LIFO batch:31
Aug 15 20:11:31 test kernel: ACPI: PM-Timer IO Port: 0x608
Aug 15 20:11:31 test kernel: ACPI: Local APIC address 0xfee00000
Aug 15 20:11:31 test kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Aug 15 20:11:31 test kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Aug 15 20:11:31 test kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Aug 15 20:11:31 test kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Aug 15 20:11:31 test kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Aug 15 20:11:31 test kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Aug 15 20:11:31 test kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Aug 15 20:11:31 test kernel: ACPI: IRQ0 used by override.
Aug 15 20:11:31 test kernel: ACPI: IRQ5 used by override.
Aug 15 20:11:31 test kernel: ACPI: IRQ9 used by override.
Aug 15 20:11:31 test kernel: ACPI: IRQ10 used by override.
Aug 15 20:11:31 test kernel: ACPI: IRQ11 used by override.
Aug 15 20:11:31 test kernel: Using ACPI (MADT) for SMP configuration information
Aug 15 20:11:31 test kernel: smpboot: Allowing 1 CPUs, 0 hotplug CPUs
Aug 15 20:11:31 test kernel: PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
Aug 15 20:11:31 test kernel: PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
Aug 15 20:11:31 test kernel: PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
Aug 15 20:11:31 test kernel: PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
Aug 15 20:11:31 test kernel: e820: [mem 0x80000000-0xfeffbfff] available for PCI devices
Aug 15 20:11:31 test kernel: Booting paravirtualized kernel on KVM
Aug 15 20:11:31 test kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns
Aug 15 20:11:31 test kernel: setup_percpu: NR_CPUS:128 nr_cpumask_bits:128 nr_cpu_ids:1 nr_node_ids:1
Aug 15 20:11:31 test kernel: percpu: Embedded 35 pages/cpu @ffff8af7ffc00000 s106328 r8192 d28840 u2097152
Aug 15 20:11:31 test kernel: pcpu-alloc: s106328 r8192 d28840 u2097152 alloc=1*2097152
Aug 15 20:11:31 test kernel: pcpu-alloc: [0] 0 
Aug 15 20:11:31 test kernel: KVM setup async PF for cpu 0
Aug 15 20:11:31 test kernel: kvm-stealtime: cpu 0, msr 7fc0d900
Aug 15 20:11:31 test kernel: Built 1 zonelists in Node order, mobility grouping on.  Total pages: 515943
Aug 15 20:11:31 test kernel: Policy zone: DMA32
Aug 15 20:11:31 test kernel: Kernel command line: BOOT_IMAGE=/vmlinuz-linux root=UUID=07e16958-47ec-425f-b64d-62a7c1b49124 rw cryptdevice=UUID=848f5472-261d-46f5-9ceb-8f98d83ad36a:cryptroot systemd.legacy_systemd_cgroup_controller=true quiet
Aug 15 20:11:31 test kernel: PID hash table entries: 4096 (order: 3, 32768 bytes)
Aug 15 20:11:31 test kernel: Calgary: detecting Calgary via BIOS EBDA area
Aug 15 20:11:31 test kernel: Calgary: Unable to locate Rio Grande table in EBDA - bailing!
Aug 15 20:11:31 test kernel: Memory: 2040868K/2096624K available (6539K kernel code, 1124K rwdata, 2048K rodata, 1324K init, 912K bss, 55756K reserved, 0K cma-reserved)
Aug 15 20:11:31 test kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Aug 15 20:11:31 test kernel: ftrace: allocating 27929 entries in 110 pages
Aug 15 20:11:31 test kernel: Preemptible hierarchical RCU implementation.
Aug 15 20:11:31 test kernel:         RCU restricting CPUs from NR_CPUS=128 to nr_cpu_ids=1.
Aug 15 20:11:31 test kernel: RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
Aug 15 20:11:31 test kernel: NR_IRQS:8448 nr_irqs:256 16
Aug 15 20:11:31 test kernel: Console: colour dummy device 80x25
Aug 15 20:11:31 test kernel: console [tty0] enabled
Aug 15 20:11:31 test kernel: tsc: Detected 3400.014 MHz processor
Aug 15 20:11:31 test kernel: Calibrating delay loop (skipped) preset value.. 6802.36 BogoMIPS (lpj=11333380)
Aug 15 20:11:31 test kernel: pid_max: default: 32768 minimum: 301
Aug 15 20:11:31 test kernel: ACPI: Core revision 20170303
Aug 15 20:11:31 test kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Aug 15 20:11:31 test kernel: Security Framework initialized
Aug 15 20:11:31 test kernel: Yama: becoming mindful.
Aug 15 20:11:31 test kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
Aug 15 20:11:31 test kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
Aug 15 20:11:31 test kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
Aug 15 20:11:31 test kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
Aug 15 20:11:31 test kernel: mce: CPU supports 10 MCE banks
Aug 15 20:11:31 test kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
Aug 15 20:11:31 test kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
Aug 15 20:11:31 test kernel: Freeing SMP alternatives memory: 24K
Aug 15 20:11:31 test kernel: smpboot: Max logical packages: 1
Aug 15 20:11:31 test kernel: x2apic enabled
Aug 15 20:11:31 test kernel: Switched APIC routing to physical x2apic.
Aug 15 20:11:31 test kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Aug 15 20:11:31 test kernel: TSC deadline timer enabled
Aug 15 20:11:31 test kernel: smpboot: CPU0: Intel Core Processor (Haswell, no TSX) (family: 0x6, model: 0x3c, stepping: 0x1)
Aug 15 20:11:31 test kernel: Performance Events: unsupported p6 CPU model 60 no PMU driver, software events only.
Aug 15 20:11:31 test kernel: smp: Bringing up secondary CPUs ...
Aug 15 20:11:31 test kernel: smp: Brought up 1 node, 1 CPU
Aug 15 20:11:31 test kernel: smpboot: Total of 1 processors activated (6802.36 BogoMIPS)
Aug 15 20:11:31 test kernel: sched_clock: Marking stable (43333329, 0)->(66061460, -22728131)
Aug 15 20:11:31 test kernel: devtmpfs: initialized
Aug 15 20:11:31 test kernel: x86/mm: Memory block size: 128MB
Aug 15 20:11:31 test kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
Aug 15 20:11:31 test kernel: futex hash table entries: 256 (order: 2, 16384 bytes)
Aug 15 20:11:31 test kernel: pinctrl core: initialized pinctrl subsystem
Aug 15 20:11:31 test kernel: RTC time: 18:11:24, date: 08/15/17
Aug 15 20:11:31 test kernel: NET: Registered protocol family 16
Aug 15 20:11:31 test kernel: cpuidle: using governor ladder
Aug 15 20:11:31 test kernel: cpuidle: using governor menu
Aug 15 20:11:31 test kernel: PCCT header not found.
Aug 15 20:11:31 test kernel: ACPI: bus type PCI registered
Aug 15 20:11:31 test kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Aug 15 20:11:31 test kernel: PCI: Using configuration type 1 for base access
Aug 15 20:11:31 test kernel: HugeTLB registered 2 MB page size, pre-allocated 0 pages
Aug 15 20:11:31 test kernel: ACPI: Added _OSI(Module Device)
Aug 15 20:11:31 test kernel: ACPI: Added _OSI(Processor Device)
Aug 15 20:11:31 test kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Aug 15 20:11:31 test kernel: ACPI: Added _OSI(Processor Aggregator Device)
Aug 15 20:11:31 test kernel: ACPI: Interpreter enabled
Aug 15 20:11:31 test kernel: ACPI: (supports S0 S5)
Aug 15 20:11:31 test kernel: ACPI: Using IOAPIC for interrupt routing
Aug 15 20:11:31 test kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Aug 15 20:11:31 test kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Aug 15 20:11:31 test kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
Aug 15 20:11:31 test kernel: acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
Aug 15 20:11:31 test kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
Aug 15 20:11:31 test kernel: acpiphp: Slot [3] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [4] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [5] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [6] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [7] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [8] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [9] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [10] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [11] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [12] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [13] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [14] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [15] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [16] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [17] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [18] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [19] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [20] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [21] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [22] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [23] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [24] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [25] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [26] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [27] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [28] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [29] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [30] registered
Aug 15 20:11:31 test kernel: acpiphp: Slot [31] registered
Aug 15 20:11:31 test kernel: PCI host bridge to bus 0000:00
Aug 15 20:11:31 test kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Aug 15 20:11:31 test kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Aug 15 20:11:31 test kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Aug 15 20:11:31 test kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
Aug 15 20:11:31 test kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Aug 15 20:11:31 test kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
Aug 15 20:11:31 test kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
Aug 15 20:11:31 test kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
Aug 15 20:11:31 test kernel: pci 0000:00:01.1: reg 0x20: [io  0xc120-0xc12f]
Aug 15 20:11:31 test kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
Aug 15 20:11:31 test kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
Aug 15 20:11:31 test kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
Aug 15 20:11:31 test kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
Aug 15 20:11:31 test kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
Aug 15 20:11:31 test kernel: pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
Aug 15 20:11:31 test kernel: pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: reg 0x10: [mem 0xf4000000-0xf7ffffff]
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: reg 0x14: [mem 0xf8000000-0xfbffffff]
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfc058000-0xfc059fff]
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: reg 0x1c: [io  0xc040-0xc05f]
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfc040000-0xfc04ffff pref]
Aug 15 20:11:31 test kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
Aug 15 20:11:31 test kernel: pci 0000:00:03.0: reg 0x10: [io  0xc060-0xc07f]
Aug 15 20:11:31 test kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfc05a000-0xfc05afff]
Aug 15 20:11:31 test kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfc000000-0xfc03ffff pref]
Aug 15 20:11:31 test kernel: pci 0000:00:04.0: [8086:293e] type 00 class 0x040300
Aug 15 20:11:31 test kernel: pci 0000:00:04.0: reg 0x10: [mem 0xfc050000-0xfc053fff]
Aug 15 20:11:31 test kernel: pci 0000:00:05.0: [1033:0194] type 00 class 0x0c0330
Aug 15 20:11:31 test kernel: pci 0000:00:05.0: reg 0x10: [mem 0xfc054000-0xfc057fff 64bit]
Aug 15 20:11:31 test kernel: pci 0000:00:06.0: [8086:2922] type 00 class 0x010601
Aug 15 20:11:31 test kernel: pci 0000:00:06.0: reg 0x20: [io  0xc080-0xc09f]
Aug 15 20:11:31 test kernel: pci 0000:00:06.0: reg 0x24: [mem 0xfc05b000-0xfc05bfff]
Aug 15 20:11:31 test kernel: pci 0000:00:07.0: [1af4:1003] type 00 class 0x078000
Aug 15 20:11:31 test kernel: pci 0000:00:07.0: reg 0x10: [io  0xc0a0-0xc0bf]
Aug 15 20:11:31 test kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfc05c000-0xfc05cfff]
Aug 15 20:11:31 test kernel: pci 0000:00:09.0: [1af4:1002] type 00 class 0x00ff00
Aug 15 20:11:31 test kernel: pci 0000:00:09.0: reg 0x10: [io  0xc0c0-0xc0df]
Aug 15 20:11:31 test kernel: pci 0000:00:0a.0: [1af4:1005] type 00 class 0x00ff00
Aug 15 20:11:31 test kernel: pci 0000:00:0a.0: reg 0x10: [io  0xc0e0-0xc0ff]
Aug 15 20:11:31 test kernel: pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00
Aug 15 20:11:31 test kernel: pci 0000:00:0b.0: reg 0x10: [io  0xc100-0xc11f]
Aug 15 20:11:31 test kernel: pci 0000:00:0c.0: [1af4:1001] type 00 class 0x010000
Aug 15 20:11:31 test kernel: pci 0000:00:0c.0: reg 0x10: [io  0xc000-0xc03f]
Aug 15 20:11:31 test kernel: pci 0000:00:0c.0: reg 0x14: [mem 0xfc05d000-0xfc05dfff]
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
Aug 15 20:11:31 test kernel: ACPI: Enabled 2 GPEs in block 00 to 0F
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: vgaarb: bridge control possible
Aug 15 20:11:31 test kernel: vgaarb: loaded
Aug 15 20:11:31 test kernel: EDAC MC: Ver: 3.0.0
Aug 15 20:11:31 test kernel: PCI: Using ACPI for IRQ routing
Aug 15 20:11:31 test kernel: PCI: pci_cache_line_size set to 64 bytes
Aug 15 20:11:31 test kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
Aug 15 20:11:31 test kernel: e820: reserve RAM buffer [mem 0x7ffde000-0x7fffffff]
Aug 15 20:11:31 test kernel: NetLabel: Initializing
Aug 15 20:11:31 test kernel: NetLabel:  domain hash size = 128
Aug 15 20:11:31 test kernel: NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
Aug 15 20:11:31 test kernel: NetLabel:  unlabeled traffic allowed by default
Aug 15 20:11:31 test kernel: clocksource: Switched to clocksource kvm-clock
Aug 15 20:11:31 test kernel: VFS: Disk quotas dquot_6.6.0
Aug 15 20:11:31 test kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Aug 15 20:11:31 test kernel: pnp: PnP ACPI init
Aug 15 20:11:31 test kernel: pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
Aug 15 20:11:31 test kernel: pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
Aug 15 20:11:31 test kernel: pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
Aug 15 20:11:31 test kernel: pnp 00:03: [dma 2]
Aug 15 20:11:31 test kernel: pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
Aug 15 20:11:31 test kernel: pnp 00:04: Plug and Play ACPI device, IDs PNP0501 (active)
Aug 15 20:11:31 test kernel: pnp: PnP ACPI: found 5 devices
Aug 15 20:11:31 test kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Aug 15 20:11:31 test kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Aug 15 20:11:31 test kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Aug 15 20:11:31 test kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Aug 15 20:11:31 test kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
Aug 15 20:11:31 test kernel: NET: Registered protocol family 2
Aug 15 20:11:31 test kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes)
Aug 15 20:11:31 test kernel: TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
Aug 15 20:11:31 test kernel: TCP: Hash tables configured (established 16384 bind 16384)
Aug 15 20:11:31 test kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes)
Aug 15 20:11:31 test kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
Aug 15 20:11:31 test kernel: NET: Registered protocol family 1
Aug 15 20:11:31 test kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Aug 15 20:11:31 test kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release
Aug 15 20:11:31 test kernel: pci 0000:00:01.0: Activating ISA DMA hang workarounds
Aug 15 20:11:31 test kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
Aug 15 20:11:31 test kernel: PCI: CLS 0 bytes, default 64
Aug 15 20:11:31 test kernel: Unpacking initramfs...
Aug 15 20:11:31 test kernel: Freeing initrd memory: 8936K
Aug 15 20:11:31 test kernel: Scanning for low memory corruption every 60 seconds
Aug 15 20:11:31 test kernel: Initialise system trusted keyrings
Aug 15 20:11:31 test kernel: Key type blacklist registered
Aug 15 20:11:31 test kernel: workingset: timestamp_bits=40 max_order=19 bucket_order=0
Aug 15 20:11:31 test kernel: zbud: loaded
Aug 15 20:11:31 test kernel: Key type asymmetric registered
Aug 15 20:11:31 test kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Aug 15 20:11:31 test kernel: io scheduler noop registered
Aug 15 20:11:31 test kernel: io scheduler deadline registered
Aug 15 20:11:31 test kernel: io scheduler cfq registered (default)
Aug 15 20:11:31 test kernel: io scheduler mq-deadline registered
Aug 15 20:11:31 test kernel: io scheduler kyber registered
Aug 15 20:11:31 test kernel: io scheduler bfq registered
Aug 15 20:11:31 test kernel: vesafb: mode is 640x480x32, linelength=2560, pages=0
Aug 15 20:11:31 test kernel: vesafb: scrolling: redraw
Aug 15 20:11:31 test kernel: vesafb: Truecolor: size=8:8:8:8, shift=24:16:8:0
Aug 15 20:11:31 test kernel: vesafb: framebuffer at 0xf4000000, mapped to 0xffffa31c00600000, using 1216k, total 1216k
Aug 15 20:11:31 test kernel: Console: switching to colour frame buffer device 80x30
Aug 15 20:11:31 test kernel: fb0: VESA VGA frame buffer device
Aug 15 20:11:31 test kernel: intel_idle: does not run on family 6 model 60
Aug 15 20:11:31 test kernel: GHES: HEST is not enabled!
Aug 15 20:11:31 test kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Aug 15 20:11:31 test kernel: 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Aug 15 20:11:31 test kernel: Linux agpgart interface v0.103
Aug 15 20:11:31 test kernel: rtc_cmos 00:00: RTC can wake from S4
Aug 15 20:11:31 test kernel: rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
Aug 15 20:11:31 test kernel: rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram
Aug 15 20:11:31 test kernel: ledtrig-cpu: registered to indicate activity on CPUs
Aug 15 20:11:31 test kernel: vlv2_plat_clk vlv2_plat_clk: vlv2_plat_clk initialized
Aug 15 20:11:31 test kernel: NET: Registered protocol family 10
Aug 15 20:11:31 test kernel: Segment Routing with IPv6
Aug 15 20:11:31 test kernel: NET: Registered protocol family 17
Aug 15 20:11:31 test kernel: RAS: Correctable Errors collector initialized.
Aug 15 20:11:31 test kernel: registered taskstats version 1
Aug 15 20:11:31 test kernel: Loading compiled-in X.509 certificates
Aug 15 20:11:31 test kernel: zswap: loaded using pool lzo/zbud
Aug 15 20:11:31 test kernel:   Magic number: 13:664:190
Aug 15 20:11:31 test kernel: acpi device:1c: hash matches
Aug 15 20:11:31 test kernel: rtc_cmos 00:00: setting system clock to 2017-08-15 18:11:24 UTC (1502820684)
Aug 15 20:11:31 test kernel: PM: Hibernation image not present or could not be loaded.
Aug 15 20:11:31 test kernel: Freeing unused kernel memory: 1324K
Aug 15 20:11:31 test kernel: Write protecting the kernel read-only data: 10240k
Aug 15 20:11:31 test kernel: Freeing unused kernel memory: 1640K
Aug 15 20:11:31 test kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Aug 15 20:11:31 test kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Aug 15 20:11:31 test kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Aug 15 20:11:31 test kernel: SCSI subsystem initialized
Aug 15 20:11:31 test kernel: libata version 3.00 loaded.
Aug 15 20:11:31 test kernel: ACPI: bus type USB registered
Aug 15 20:11:31 test kernel: usbcore: registered new interface driver usbfs
Aug 15 20:11:31 test kernel: usbcore: registered new interface driver hub
Aug 15 20:11:31 test kernel: usbcore: registered new device driver usb
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
Aug 15 20:11:31 test kernel: virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver
Aug 15 20:11:31 test kernel: ata_piix 0000:00:01.1: version 2.13
Aug 15 20:11:31 test kernel: scsi host0: ata_piix
Aug 15 20:11:31 test kernel: scsi host1: ata_piix
Aug 15 20:11:31 test kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14
Aug 15 20:11:31 test kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15
Aug 15 20:11:31 test kernel: xhci_hcd 0000:00:05.0: xHCI Host Controller
Aug 15 20:11:31 test kernel: xhci_hcd 0000:00:05.0: new USB bus registered, assigned bus number 1
Aug 15 20:11:31 test kernel: xhci_hcd 0000:00:05.0: hcc params 0x00087001 hci version 0x100 quirks 0x00000014
Aug 15 20:11:31 test kernel: hub 1-0:1.0: USB hub found
Aug 15 20:11:31 test kernel: hub 1-0:1.0: 4 ports detected
Aug 15 20:11:31 test kernel: xhci_hcd 0000:00:05.0: xHCI Host Controller
Aug 15 20:11:31 test kernel: xhci_hcd 0000:00:05.0: new USB bus registered, assigned bus number 2
Aug 15 20:11:31 test kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
Aug 15 20:11:31 test kernel: hub 2-0:1.0: USB hub found
Aug 15 20:11:31 test kernel: hub 2-0:1.0: 4 ports detected
Aug 15 20:11:31 test kernel: ahci 0000:00:06.0: version 3.0
Aug 15 20:11:31 test kernel: FDC 0 is a S82078B
Aug 15 20:11:31 test kernel: AVX2 version of gcm_enc/dec engaged.
Aug 15 20:11:31 test kernel: AES CTR mode by8 optimization enabled
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
Aug 15 20:11:31 test kernel: ahci 0000:00:06.0: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode
Aug 15 20:11:31 test kernel: ahci 0000:00:06.0: flags: 64bit ncq only 
Aug 15 20:11:31 test kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Aug 15 20:11:31 test kernel: scsi host2: ahci
Aug 15 20:11:31 test kernel: scsi host3: ahci
Aug 15 20:11:31 test kernel: scsi host4: ahci
Aug 15 20:11:31 test kernel: scsi host5: ahci
Aug 15 20:11:31 test kernel: scsi host6: ahci
Aug 15 20:11:31 test kernel: scsi host7: ahci
Aug 15 20:11:31 test kernel: ata3: SATA max UDMA/133 abar m4096@0xfc05b000 port 0xfc05b100 irq 26
Aug 15 20:11:31 test kernel: ata4: SATA max UDMA/133 abar m4096@0xfc05b000 port 0xfc05b180 irq 26
Aug 15 20:11:31 test kernel: ata5: SATA max UDMA/133 abar m4096@0xfc05b000 port 0xfc05b200 irq 26
Aug 15 20:11:31 test kernel: ata6: SATA max UDMA/133 abar m4096@0xfc05b000 port 0xfc05b280 irq 26
Aug 15 20:11:31 test kernel: ata7: SATA max UDMA/133 abar m4096@0xfc05b000 port 0xfc05b300 irq 26
Aug 15 20:11:31 test kernel: ata8: SATA max UDMA/133 abar m4096@0xfc05b000 port 0xfc05b380 irq 26
Aug 15 20:11:31 test kernel: virtio-pci 0000:00:07.0: virtio_pci: leaving for legacy driver
Aug 15 20:11:31 test kernel: virtio-pci 0000:00:09.0: virtio_pci: leaving for legacy driver
Aug 15 20:11:31 test kernel: virtio-pci 0000:00:0a.0: virtio_pci: leaving for legacy driver
Aug 15 20:11:31 test kernel: virtio-pci 0000:00:0b.0: virtio_pci: leaving for legacy driver
Aug 15 20:11:31 test kernel: ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
Aug 15 20:11:31 test kernel: virtio-pci 0000:00:0c.0: virtio_pci: leaving for legacy driver
Aug 15 20:11:31 test kernel: random: fast init done
Aug 15 20:11:31 test kernel: random: crng init done
Aug 15 20:11:31 test kernel:  vda: vda1 vda2
Aug 15 20:11:31 test kernel: ata3: SATA link down (SStatus 0 SControl 300)
Aug 15 20:11:31 test kernel: ata4: SATA link down (SStatus 0 SControl 300)
Aug 15 20:11:31 test kernel: ata5: SATA link down (SStatus 0 SControl 300)
Aug 15 20:11:31 test kernel: ata6: SATA link down (SStatus 0 SControl 300)
Aug 15 20:11:31 test kernel: ata7: SATA link down (SStatus 0 SControl 300)
Aug 15 20:11:31 test kernel: ata8: SATA link down (SStatus 0 SControl 300)
Aug 15 20:11:31 test kernel: device-mapper: uevent: version 1.0.3
Aug 15 20:11:31 test kernel: device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com
Aug 15 20:11:31 test kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x31025ba73b4, max_idle_ns: 440795211168 ns
Aug 15 20:11:31 test kernel: NET: Registered protocol family 38
Aug 15 20:11:31 test kernel: EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
Aug 15 20:11:31 test kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
Aug 15 20:11:31 test systemd[1]: systemd 234 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 -SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN default-hierarchy=hybrid)
Aug 15 20:11:31 test systemd[1]: Detected virtualization kvm.
Aug 15 20:11:31 test systemd[1]: Detected architecture x86-64.
Aug 15 20:11:31 test systemd[1]: Set hostname to <test>.
Aug 15 20:11:31 test systemd[1]: Listening on LVM2 metadata daemon socket.
Aug 15 20:11:31 test systemd[1]: Started Forward Password Requests to Wall Directory Watch.
Aug 15 20:11:31 test systemd[1]: Listening on udev Kernel Socket.
Aug 15 20:11:31 test systemd[1]: Created slice System Slice.
Aug 15 20:11:31 test systemd[1]: Mounting POSIX Message Queue File System...
Aug 15 20:11:31 test systemd[1]: Listening on udev Control Socket.
Aug 15 20:11:31 test kernel: EXT4-fs (dm-0): re-mounted. Opts: data=ordered
Aug 15 20:11:31 test systemd-journald[245]: Journal started
Aug 15 20:11:31 test systemd-journald[245]: Runtime journal (/run/log/journal/9063f35047ce47a8835ec79a8261fe19) is 8.0M, max 100.2M, 92.2M free.
Aug 15 20:11:31 test systemd[1]: Started Apply Kernel Variables.
Aug 15 20:11:31 test systemd[1]: Mounted Kernel Debug File System.
Aug 15 20:11:31 test systemd[1]: Mounted Temporary Directory (/tmp).
Aug 15 20:11:31 test systemd[1]: Started Load/Save Random Seed.
Aug 15 20:11:31 test systemd[1]: Starting Flush Journal to Persistent Storage...
Aug 15 20:11:31 test systemd-journald[245]: Time spent on flushing to /var is 17.197ms for 428 entries.
Aug 15 20:11:31 test systemd-journald[245]: System journal (/var/log/journal/9063f35047ce47a8835ec79a8261fe19) is 296.1M, max 4.0G, 3.7G free.
Aug 15 20:11:31 test kernel: RPC: Registered named UNIX socket transport module.
Aug 15 20:11:31 test kernel: RPC: Registered udp transport module.
Aug 15 20:11:31 test kernel: RPC: Registered tcp transport module.
Aug 15 20:11:31 test kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Aug 15 20:11:31 test kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
Aug 15 20:11:31 test rpc.statd[291]: Version 2.1.1 starting
Aug 15 20:11:31 test systemd[1]: Mounted NFSD configuration filesystem.
Aug 15 20:11:31 test rpc.statd[291]: Flags: TI-RPC
Aug 15 20:11:31 test systemd[1]: Started Create System Users.
Aug 15 20:11:31 test rpc.statd[291]: Running as root.  chown /var/lib/nfs to choose different user
Aug 15 20:11:31 test systemd[1]: Starting Create Static Device Nodes in /dev...
Aug 15 20:11:31 test systemd[1]: Started Create Static Device Nodes in /dev.
Aug 15 20:11:31 test systemd[1]: Reached target Local File Systems (Pre).
Aug 15 20:11:31 test systemd[1]: Starting udev Kernel Device Manager...
Aug 15 20:11:31 test systemd[1]: Started udev Kernel Device Manager.
Aug 15 20:11:31 test systemd[1]: Starting Network Service...
Aug 15 20:11:31 test systemd-networkd[276]: Enumeration completed
Aug 15 20:11:31 test systemd[1]: Started Network Service.
Aug 15 20:11:31 test systemd[1]: Reached target Network.
Aug 15 20:11:31 test systemd[1]: Reached target Network is Online.
Aug 15 20:11:31 test systemd[1]: Starting NFS status monitor for NFSv2/3 locking....
Aug 15 20:11:31 test systemd[1]: Starting RPC Bind...
Aug 15 20:11:31 test systemd[1]: Started RPC Bind.
Aug 15 20:11:31 test systemd[1]: Started Flush Journal to Persistent Storage.
Aug 15 20:11:31 test systemd[1]: Started NFS status monitor for NFSv2/3 locking..
Aug 15 20:11:31 test systemd[1]: Started Rebuild Hardware Database.
Aug 15 20:11:31 test systemd[1]: Starting udev Coldplug all Devices...
Aug 15 20:11:32 test systemd[1]: Started udev Coldplug all Devices.
Aug 15 20:11:32 test kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
Aug 15 20:11:32 test kernel: ACPI: Power Button [PWRF]
Aug 15 20:11:32 test kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
Aug 15 20:11:32 test systemd-udevd[301]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Aug 15 20:11:32 test kernel: virtio_net virtio0 ens3: renamed from eth0
Aug 15 20:11:32 test systemd-networkd[276]: eth0: Renamed to ens3
Aug 15 20:11:32 test kernel: input: PC Speaker as /devices/platform/pcspkr/input/input3
Aug 15 20:11:32 test systemd[1]: Found device /dev/disk/by-uuid/57e40eff-f2a5-4039-90db-81c02954465b.
Aug 15 20:11:32 test systemd[1]: Starting File System Check on /dev/disk/by-uuid/57e40eff-f2a5-4039-90db-81c02954465b...
Aug 15 20:11:32 test systemd-fsck[313]: Boot: sauber, 361/25688 Dateien, 58452/102400 Blöcke
Aug 15 20:11:32 test systemd-udevd[300]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Aug 15 20:11:32 test systemd-networkd[276]: ens3: IPv6 successfully enabled
Aug 15 20:11:32 test systemd-networkd[276]: ens3: Gained carrier
Aug 15 20:11:32 test kernel: ppdev: user-space parallel port driver
Aug 15 20:11:32 test systemd-networkd[276]: ens3: DHCPv4 address xxx.xxx.xxx.106/24 via xxx.xxx.xxx.1
Aug 15 20:11:32 test kernel: [drm] Device Version 0.0
Aug 15 20:11:32 test kernel: [drm] Compression level 0 log level 0
Aug 15 20:11:32 test kernel: [drm] 12286 io pages at offset 0x1000000
Aug 15 20:11:32 test kernel: [drm] 16777216 byte draw area at offset 0x0
Aug 15 20:11:32 test kernel: [drm] RAM header offset: 0x3ffe000
Aug 15 20:11:32 test kernel: [TTM] Zone  kernel: Available graphics memory: 1026396 kiB
Aug 15 20:11:32 test kernel: [TTM] Initializing pool allocator
Aug 15 20:11:32 test kernel: [TTM] Initializing DMA pool allocator
Aug 15 20:11:32 test kernel: [drm] qxl: 16M of VRAM memory size
Aug 15 20:11:32 test kernel: [drm] qxl: 63M of IO pages memory ready (VRAM domain)
Aug 15 20:11:32 test kernel: [drm] qxl: 64M of Surface memory size
Aug 15 20:11:32 test kernel: [drm] main mem slot 1 [f4000000,3ffe000]
Aug 15 20:11:32 test kernel: [drm] surface mem slot 2 [f8000000,4000000]
Aug 15 20:11:32 test kernel: [drm] fb mappable at 0xF4000000, size 3145728
Aug 15 20:11:32 test kernel: [drm] fb: depth 24, pitch 4096, width 1024, height 768
Aug 15 20:11:32 test kernel: checking generic (f4000000 130000) vs hw (f4000000 1000000)
Aug 15 20:11:32 test kernel: fb: switching to qxldrmfb from VESA VGA
Aug 15 20:11:32 test kernel: Console: switching to colour dummy device 80x25
Aug 15 20:11:32 test kernel: fbcon: qxldrmfb (fb0) is primary device
Aug 15 20:11:32 test kernel: Console: switching to colour frame buffer device 128x48
Aug 15 20:11:32 test kernel: qxl 0000:00:02.0: fb0: qxldrmfb frame buffer device
Aug 15 20:11:32 test kernel: [drm] Initialized qxl 0.1.0 20120117 for 0000:00:02.0 on minor 0
Aug 15 20:11:32 test kernel: snd_hda_codec_generic hdaudioC0D0: autoconfig for Generic: line_outs=1 (0x3/0x0/0x0/0x0/0x0) type:line
Aug 15 20:11:32 test kernel: snd_hda_codec_generic hdaudioC0D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
Aug 15 20:11:32 test kernel: snd_hda_codec_generic hdaudioC0D0:    hp_outs=0 (0x0/0x0/0x0/0x0/0x0)
Aug 15 20:11:32 test kernel: snd_hda_codec_generic hdaudioC0D0:    mono: mono_out=0x0
Aug 15 20:11:32 test kernel: snd_hda_codec_generic hdaudioC0D0:    inputs:
Aug 15 20:11:32 test kernel: snd_hda_codec_generic hdaudioC0D0:      Line=0x5
Aug 15 20:11:32 test systemd[1]: Reached target Sound Card.
Aug 15 20:11:32 test systemd[1]: Started File System Check on /dev/disk/by-uuid/57e40eff-f2a5-4039-90db-81c02954465b.
Aug 15 20:11:32 test systemd[1]: Mounting /boot...
Aug 15 20:11:32 test systemd[1]: Mounted /boot.
Aug 15 20:11:32 test systemd[1]: Reached target Local File Systems.
Aug 15 20:11:32 test kernel: EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: data=ordered
Aug 15 20:11:32 test systemd[1]: Starting Create Volatile Files and Directories...
Aug 15 20:11:32 test systemd[1]: Starting Rebuild Dynamic Linker Cache...
Aug 15 20:11:32 test systemd[1]: Starting Rebuild Journal Catalog...
Aug 15 20:11:32 test systemd[1]: Starting NFS Mount Daemon...
Aug 15 20:11:32 test systemd[1]: Started Create Volatile Files and Directories.
Aug 15 20:11:32 test systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Aug 15 20:11:32 test systemd[1]: Mounting RPC Pipe File System...
Aug 15 20:11:32 test systemd[1]: Starting Network Time Synchronization...
Aug 15 20:11:32 test systemd[1]: Mounted RPC Pipe File System.
Aug 15 20:11:32 test systemd[1]: Started Rebuild Journal Catalog.
Aug 15 20:11:32 test systemd[1]: Starting NFSv4 ID-name mapping service...
Aug 15 20:11:32 test systemd[1]: Started Update UTMP about System Boot/Shutdown.
Aug 15 20:11:32 test rpc.idmapd[361]: rpc.idmapd: Skipping configuration file "/etc/idmapd.conf": No such file or directory
Aug 15 20:11:32 test rpc.idmapd[362]: Unable to open '/proc/sys/fs/nfs/idmap_cache_timeout' to set client cache expiration time to 0 seconds
Aug 15 20:11:32 test systemd[1]: Started NFSv4 ID-name mapping service.
Aug 15 20:11:32 test systemd[1]: Started NFS Mount Daemon.
Aug 15 20:11:32 test rpc.mountd[363]: Version 2.1.1 starting
Aug 15 20:11:32 test systemd[1]: Started Network Time Synchronization.
Aug 15 20:11:32 test systemd[1]: Reached target System Time Synchronized.
Aug 15 20:11:32 test systemd[1]: Started Rebuild Dynamic Linker Cache.
Aug 15 20:11:32 test systemd[1]: Starting Update is Completed...
Aug 15 20:11:32 test systemd[1]: Started Update is Completed.
Aug 15 20:11:32 test systemd[1]: Reached target System Initialization.
Aug 15 20:11:32 test systemd[1]: Started Daily man-db cache update.
Aug 15 20:11:32 test systemd[1]: Started Daily Cleanup of Temporary Directories.
Aug 15 20:11:32 test systemd[1]: Started Daily rotation of log files.
Aug 15 20:11:32 test systemd[1]: Listening on D-Bus System Message Bus Socket.
Aug 15 20:11:32 test systemd[1]: Reached target Sockets.
Aug 15 20:11:32 test systemd[1]: Reached target Basic System.
Aug 15 20:11:32 test systemd[1]: Started QEMU Guest Agent.
Aug 15 20:11:32 test systemd[1]: Started OpenSSH Daemon.
Aug 15 20:11:32 test systemd[1]: Starting Login Service...
Aug 15 20:11:32 test systemd[1]: Started D-Bus System Message Bus.
Aug 15 20:11:32 test sshd[369]: Server listening on 0.0.0.0 port 22.
Aug 15 20:11:32 test sshd[369]: Server listening on :: port 22.
Aug 15 20:11:32 test systemd[1]: Starting GSSAPI Proxy Daemon...
Aug 15 20:11:32 test systemd[1]: Starting Agent daemon for Spice guests...
Aug 15 20:11:32 test systemd[1]: Starting A lightweight DHCP and caching DNS server...
Aug 15 20:11:32 test systemd[1]: Started Daily verification of password and group files.
Aug 15 20:11:32 test systemd[1]: Reached target Timers.
Aug 15 20:11:32 test dnsmasq[375]: dnsmasq: syntax check OK.
Aug 15 20:11:32 test systemd-logind[370]: New seat seat0.
Aug 15 20:11:32 test systemd-logind[370]: Watching system buttons on /dev/input/event1 (Power Button)
Aug 15 20:11:32 test systemd-logind[370]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Aug 15 20:11:32 test systemd[1]: Started GSSAPI Proxy Daemon.
Aug 15 20:11:32 test systemd[1]: Started Login Service.
Aug 15 20:11:32 test systemd[1]: Started A lightweight DHCP and caching DNS server.
Aug 15 20:11:32 test systemd[1]: Started Agent daemon for Spice guests.
Aug 15 20:11:32 test dnsmasq[384]: started, version 2.77 cachesize 150
Aug 15 20:11:32 test dnsmasq[384]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify
Aug 15 20:11:32 test dnsmasq[384]: DBus support enabled: connected to system bus
Aug 15 20:11:32 test dnsmasq[384]: using local addresses only for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: read /etc/hosts - x addresses
Aug 15 20:11:32 test dnsmasq[384]: using local addresses only for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53 for domain x
Aug 15 20:11:32 test dnsmasq[384]: using nameserver xxx.xxx.xxx.xxx#53
Aug 15 20:11:32 test systemd[1]: Reached target Agent daemon for Spice guests.
Aug 15 20:11:32 test systemd[1]: Reached target NFS client services.
Aug 15 20:11:32 test systemd[1]: Reached target Remote File Systems (Pre).
Aug 15 20:11:32 test systemd[1]: Mounting /mnt/knecht_pacman...
Aug 15 20:11:32 test systemd[1]: Reached target Remote File Systems.
Aug 15 20:11:32 test systemd[1]: Starting Permit User Sessions...
Aug 15 20:11:32 test systemd[1]: Starting NFS server and services...
Aug 15 20:11:32 test systemd[1]: Started Permit User Sessions.
Aug 15 20:11:32 test systemd[1]: Reached target Multi-User System.
Aug 15 20:11:32 test systemd[1]: Starting Light Display Manager...
Aug 15 20:11:32 test systemd[1]: Started Light Display Manager.
Aug 15 20:11:32 test systemd[1]: Reached target Graphical Interface.
Aug 15 20:11:32 test lightdm[405]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Aug 15 20:11:32 test kernel: FS-Cache: Loaded
Aug 15 20:11:32 test kernel: NFSD: starting 90-second grace period (net ffffffffa4acdd80)
Aug 15 20:11:32 test systemd[1]: Started NFS server and services.
Aug 15 20:11:32 test kernel: FS-Cache: Netfs 'nfs' registered for caching
Aug 15 20:11:32 test systemd[1]: Starting Notify NFS peers of a restart...
Aug 15 20:11:32 test sm-notify[431]: Version 2.1.1 starting
Aug 15 20:11:32 test systemd[1]: Started Notify NFS peers of a restart.
Aug 15 20:11:32 test kernel: Key type dns_resolver registered
Aug 15 20:11:32 test kernel: NFS: Registering the id_resolver key type
Aug 15 20:11:32 test kernel: Key type id_resolver registered
Aug 15 20:11:32 test kernel: Key type id_legacy registered
Aug 15 20:11:32 test lightdm[405]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
Aug 15 20:11:32 test lightdm[451]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Aug 15 20:11:32 test lightdm[451]: pam_unix(lightdm-greeter:session): session opened for user lightdm by (uid=0)
Aug 15 20:11:32 test systemd[1]: Created slice User Slice of lightdm.
Aug 15 20:11:32 test systemd[1]: Starting User Manager for UID 620...
Aug 15 20:11:32 test systemd-logind[370]: New session c1 of user lightdm.
Aug 15 20:11:32 test systemd[1]: Started Session c1 of user lightdm.
Aug 15 20:11:32 test systemd[454]: pam_unix(systemd-user:session): session opened for user lightdm by (uid=0)
Aug 15 20:11:32 test systemd[454]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Aug 15 20:11:32 test systemd[454]: Starting D-Bus User Message Bus Socket.
Aug 15 20:11:32 test systemd[454]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Aug 15 20:11:32 test systemd[454]: Listening on GnuPG cryptographic agent and passphrase cache.
Aug 15 20:11:32 test systemd[454]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Aug 15 20:11:32 test systemd[454]: Reached target Paths.
Aug 15 20:11:32 test systemd[454]: Reached target Timers.
Aug 15 20:11:32 test systemd[454]: Listening on GnuPG network certificate management daemon.
Aug 15 20:11:32 test systemd[454]: Listening on Sound System.
Aug 15 20:11:32 test systemd[454]: Listening on D-Bus User Message Bus Socket.
Aug 15 20:11:32 test systemd[454]: Reached target Sockets.
Aug 15 20:11:32 test systemd[454]: Reached target Basic System.
Aug 15 20:11:32 test systemd[454]: Reached target Default.
Aug 15 20:11:32 test systemd[454]: Startup finished in 14ms.
Aug 15 20:11:32 test systemd[1]: Started User Manager for UID 620.
Aug 15 20:11:32 test systemd[1]: Mounted /mnt/knecht_pacman.
Aug 15 20:11:32 test systemd[1]: Startup finished in 7.260s (kernel) + 1.276s (userspace) = 8.536s.
Aug 15 20:11:32 test systemd[454]: Started D-Bus User Message Bus.
Aug 15 20:11:32 test dbus-daemon[467]: Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service'
Aug 15 20:11:32 test systemd[454]: Starting Virtual filesystem service...
Aug 15 20:11:32 test dbus-daemon[467]: Successfully activated service 'org.gtk.vfs.Daemon'
Aug 15 20:11:32 test systemd[454]: Started Virtual filesystem service.
Aug 15 20:11:32 test kernel: fuse init (API version 7.26)
Aug 15 20:11:33 test kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
Aug 15 20:11:33 test kernel: mousedev: PS/2 mouse device common for all mice
Aug 15 20:11:33 test systemd-networkd[276]: ens3: Gained IPv6LL
Aug 15 20:11:34 test systemd-networkd[276]: ens3: Configured
Aug 15 20:11:34 test systemd-timesyncd[356]: Network configuration changed, trying to establish connection.
Aug 15 20:11:36 test lightdm[497]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Aug 15 20:11:36 test systemd[1]: session-c1.scope: Killing process 451 (lightdm) with signal SIGTERM.
Aug 15 20:11:36 test systemd[1]: session-c1.scope: Killing process 461 (lightdm-gtk-gre) with signal SIGTERM.
Aug 15 20:11:36 test systemd[1]: session-c1.scope: Killing process 488 (at-spi-bus-laun) with signal SIGTERM.
Aug 15 20:11:36 test systemd[1]: session-c1.scope: Killing process 495 (dbus-daemon) with signal SIGTERM.
Aug 15 20:11:36 test systemd[1]: Stopping Session c1 of user lightdm.
Aug 15 20:11:36 test lightdm[451]: pam_unix(lightdm-greeter:session): session closed for user lightdm
Aug 15 20:11:36 test lightdm[405]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
Aug 15 20:11:36 test lightdm[497]: pam_unix(lightdm:session): session opened for user user by (uid=0)
Aug 15 20:11:36 test systemd[1]: Created slice User Slice of user.
Aug 15 20:11:36 test systemd[1]: Starting User Manager for UID 1000...
Aug 15 20:11:36 test systemd[511]: pam_unix(systemd-user:session): session opened for user user by (uid=0)
Aug 15 20:11:36 test systemd-logind[370]: New session c2 of user user.
Aug 15 20:11:36 test systemd[1]: Started Session c2 of user user.
Aug 15 20:11:36 test systemd[1]: Stopped Session c1 of user lightdm.
Aug 15 20:11:36 test systemd-logind[370]: Removed session c1.
Aug 15 20:11:36 test systemd[1]: Stopping User Manager for UID 620...
Aug 15 20:11:36 test systemd[454]: Stopped target Default.
Aug 15 20:11:36 test systemd[454]: Stopping D-Bus User Message Bus...
Aug 15 20:11:36 test systemd[454]: Stopping Virtual filesystem service...
Aug 15 20:11:36 test systemd[454]: Stopped D-Bus User Message Bus.
Aug 15 20:11:36 test systemd[511]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Aug 15 20:11:36 test systemd[511]: Reached target Paths.
Aug 15 20:11:36 test systemd[511]: Listening on Sound System.
Aug 15 20:11:36 test systemd[511]: Starting D-Bus User Message Bus Socket.
Aug 15 20:11:36 test systemd[511]: Listening on GnuPG cryptographic agent and passphrase cache.
Aug 15 20:11:36 test systemd[511]: Listening on GnuPG network certificate management daemon.
Aug 15 20:11:36 test systemd[511]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Aug 15 20:11:36 test systemd[511]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Aug 15 20:11:36 test systemd[511]: Reached target Timers.
Aug 15 20:11:36 test systemd[511]: Listening on D-Bus User Message Bus Socket.
Aug 15 20:11:36 test systemd[511]: Reached target Sockets.
Aug 15 20:11:36 test systemd[511]: Reached target Basic System.
Aug 15 20:11:36 test systemd[511]: Reached target Default.
Aug 15 20:11:36 test systemd[511]: Startup finished in 22ms.
Aug 15 20:11:36 test systemd[1]: Started User Manager for UID 1000.
Aug 15 20:11:36 test systemd[454]: Stopped Virtual filesystem service.
Aug 15 20:11:36 test systemd[454]: Stopped target Basic System.
Aug 15 20:11:36 test systemd[454]: Stopped target Paths.
Aug 15 20:11:36 test systemd[454]: Stopped target Sockets.
Aug 15 20:11:36 test systemd[454]: Closed Sound System.
Aug 15 20:11:36 test systemd[454]: Closed GnuPG cryptographic agent and passphrase cache.
Aug 15 20:11:36 test systemd[454]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Aug 15 20:11:36 test systemd[454]: Closed GnuPG network certificate management daemon.
Aug 15 20:11:36 test systemd[454]: Closed D-Bus User Message Bus Socket.
Aug 15 20:11:36 test systemd[454]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Aug 15 20:11:36 test systemd[454]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Aug 15 20:11:36 test systemd[454]: Reached target Shutdown.
Aug 15 20:11:36 test systemd[454]: Starting Exit the Session...
Aug 15 20:11:36 test systemd[454]: Stopped target Timers.
Aug 15 20:11:36 test systemd[454]: Received SIGRTMIN+24 from PID 521 (kill).
Aug 15 20:11:36 test systemd[1]: Stopped User Manager for UID 620.
Aug 15 20:11:36 test systemd[1]: Removed slice User Slice of lightdm.
Aug 15 20:11:37 test systemd[511]: Started D-Bus User Message Bus.
Aug 15 20:11:37 test dbus[371]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
Aug 15 20:11:37 test systemd[1]: Starting Authorization Manager...
Aug 15 20:11:37 test polkitd[545]: Started polkitd version 0.114
Aug 15 20:11:37 test polkitd[545]: Loading rules from directory /etc/polkit-1/rules.d
Aug 15 20:11:37 test polkitd[545]: Loading rules from directory /usr/share/polkit-1/rules.d
Aug 15 20:11:37 test polkitd[545]: Finished loading, compiling and executing 4 rules
Aug 15 20:11:37 test dbus[371]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Aug 15 20:11:37 test systemd[1]: Started Authorization Manager.
Aug 15 20:11:37 test polkitd[545]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Aug 15 20:11:37 test dbus-daemon[534]: Activating service name='org.xfce.Xfconf'
Aug 15 20:11:37 test dbus-daemon[534]: Successfully activated service 'org.xfce.Xfconf'
Aug 15 20:11:37 test systemd[511]: Started GnuPG cryptographic agent and passphrase cache.
Aug 15 20:11:37 test gpg-agent[558]: gpg-agent (GnuPG) 2.1.23 starting in supervised mode.
Aug 15 20:11:37 test gpg-agent[558]: using fd 3 for ssh socket (/run/user/1000/gnupg/S.gpg-agent.ssh)
Aug 15 20:11:37 test gpg-agent[558]: using fd 4 for extra socket (/run/user/1000/gnupg/S.gpg-agent.extra)
Aug 15 20:11:37 test gpg-agent[558]: using fd 5 for browser socket (/run/user/1000/gnupg/S.gpg-agent.browser)
Aug 15 20:11:37 test gpg-agent[558]: using fd 6 for std socket (/run/user/1000/gnupg/S.gpg-agent)
Aug 15 20:11:37 test gpg-agent[558]: listening on: std=6 extra=4 browser=5 ssh=3
Aug 15 20:11:37 test systemd[511]: Starting Sound Service...
Aug 15 20:11:37 test dbus[371]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service'
Aug 15 20:11:37 test systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Aug 15 20:11:37 test dbus[371]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Aug 15 20:11:37 test systemd[1]: Started RealtimeKit Scheduling Policy Service.
Aug 15 20:11:37 test rtkit-daemon[564]: Successfully called chroot.
Aug 15 20:11:37 test rtkit-daemon[564]: Successfully dropped privileges.
Aug 15 20:11:37 test rtkit-daemon[564]: Successfully limited resources.
Aug 15 20:11:37 test rtkit-daemon[564]: Running.
Aug 15 20:11:37 test rtkit-daemon[564]: Watchdog thread running.
Aug 15 20:11:37 test rtkit-daemon[564]: Canary thread running.
Aug 15 20:11:37 test rtkit-daemon[564]: Successfully made thread 562 of process 562 (/usr/bin/pulseaudio) owned by '1000' high priority at nice level -11.
Aug 15 20:11:37 test rtkit-daemon[564]: Supervising 1 threads of 1 processes of 1 users.
Aug 15 20:11:37 test pulseaudio[562]: N: [pulseaudio] alsa-util.c: Disabling timer-based scheduling because running inside a VM.
Aug 15 20:11:37 test rtkit-daemon[564]: Supervising 1 threads of 1 processes of 1 users.
Aug 15 20:11:37 test rtkit-daemon[564]: Successfully made thread 569 of process 562 (/usr/bin/pulseaudio) owned by '1000' RT at priority 5.
Aug 15 20:11:37 test rtkit-daemon[564]: Supervising 2 threads of 1 processes of 1 users.
Aug 15 20:11:37 test pulseaudio[562]: N: [pulseaudio] alsa-util.c: Disabling timer-based scheduling because running inside a VM.
Aug 15 20:11:37 test rtkit-daemon[564]: Supervising 2 threads of 1 processes of 1 users.
Aug 15 20:11:37 test rtkit-daemon[564]: Successfully made thread 570 of process 562 (/usr/bin/pulseaudio) owned by '1000' RT at priority 5.
Aug 15 20:11:37 test rtkit-daemon[564]: Supervising 3 threads of 1 processes of 1 users.
Aug 15 20:11:37 test dbus-daemon[534]: Activating service name='org.gnome.GConf'
Aug 15 20:11:37 test dbus-daemon[534]: Successfully activated service 'org.gnome.GConf'
Aug 15 20:11:37 test systemd[511]: Started Sound Service.
Aug 15 20:11:37 test dbus-daemon[534]: Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service'
Aug 15 20:11:37 test systemd[511]: Starting Virtual filesystem service...
Aug 15 20:11:37 test dbus-daemon[534]: Successfully activated service 'org.gtk.vfs.Daemon'
Aug 15 20:11:37 test systemd[511]: Started Virtual filesystem service.
Aug 15 20:11:37 test dbus-daemon[534]: Activating service name='org.freedesktop.thumbnails.Thumbnailer1'
Aug 15 20:11:37 test tumblerd[602]: Failed to load plugin "tumbler-ffmpeg-thumbnailer.so": libffmpegthumbnailer.so.4: cannot open shared object file: No such file or directory
Aug 15 20:11:37 test tumblerd[602]: Failed to load plugin "tumbler-raw-thumbnailer.so": libopenrawgnome.so.7: cannot open shared object file: No such file or directory
Aug 15 20:11:37 test tumblerd[602]: Failed to load plugin "tumbler-odf-thumbnailer.so": libgsf-1.so.114: cannot open shared object file: No such file or directory
Aug 15 20:11:37 test tumblerd[602]: Failed to load plugin "tumbler-poppler-thumbnailer.so": libpoppler-glib.so.8: cannot open shared object file: No such file or directory
Aug 15 20:11:37 test org.freedesktop.thumbnails.Thumbnailer1[534]: Registered thumbailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Aug 15 20:11:37 test org.freedesktop.thumbnails.Thumbnailer1[534]: Registered thumbailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Aug 15 20:11:37 test dbus-daemon[534]: Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service'
Aug 15 20:11:37 test systemd[511]: Starting Virtual filesystem service - disk device monitor...
Aug 15 20:11:37 test dbus[371]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service'
Aug 15 20:11:37 test systemd[1]: Starting Disk Manager...
Aug 15 20:11:37 test udisksd[608]: udisks daemon version 2.7.2 starting
Aug 15 20:11:37 test dbus[371]: [system] Successfully activated service 'org.freedesktop.UDisks2'
Aug 15 20:11:37 test systemd[1]: Started Disk Manager.
Aug 15 20:11:37 test udisksd[608]: Acquired the name org.freedesktop.UDisks2 on the system message bus
Aug 15 20:11:37 test dbus-daemon[534]: Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
Aug 15 20:11:37 test systemd[511]: Started Virtual filesystem service - disk device monitor.
Aug 15 20:11:37 test dbus-daemon[534]: Successfully activated service 'org.freedesktop.thumbnails.Thumbnailer1'
Aug 15 20:11:37 test spice-vdagentd[397]: opening vdagent virtio channel
Aug 15 20:11:37 test kernel: input: spice vdagent tablet as /devices/virtual/input/input5
Aug 15 20:11:37 test spice-vdagentd[397]: Set max clipboard: 104857600
Aug 15 20:11:37 test dbus-daemon[534]: Activating service name='ca.desrt.dconf'
Aug 15 20:11:37 test dbus-daemon[534]: Successfully activated service 'ca.desrt.dconf'
Aug 15 20:11:37 test spice-vdagentd[397]: Set max clipboard: 104857600
Aug 15 20:11:37 test spice-vdagent[647]: vdagent_audio_playback_sync mute=yes nchannels=2
Aug 15 20:11:37 test spice-vdagent[647]: vdagent-audio: (playback-left) 0 (%0.00)
Aug 15 20:11:37 test spice-vdagent[647]: vdagent-audio: (playback-right) 0 (%0.00)
Aug 15 20:11:37 test spice-vdagent[647]: vdagent_audio_record_sync mute=no nchannels=2
Aug 15 20:11:37 test spice-vdagent[647]: vdagent-audio: (capture-left) 65535 (%99.00)
Aug 15 20:11:37 test spice-vdagent[647]: vdagent-audio: (capture-right) 65535 (%99.00)
Aug 15 20:11:37 test dbus[371]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Aug 15 20:11:37 test dbus[371]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service not found.
Aug 15 20:11:37 test polkitd[545]: Registered Authentication Agent for unix-session:c2 (system bus name :1.27 [/usr/lib/polkit-gnome/polkit-gnome-authentication-agent-1], object path /org/gnome/PolicyKit1/AuthenticationAgent, locale de_DE.utf8)
Aug 15 20:11:41 test kernel: input: spice vdagent tablet as /devices/virtual/input/input6
Aug 15 20:11:50 test spice-vdagentd[397]: Error getting active session: No data available
Aug 15 20:11:50 test spice-vdagentd[397]: Error getting active session: No data available
Aug 15 20:11:50 test systemd[1]: Started Getty on tty2.
Aug 15 20:11:50 test spice-vdagentd[397]: closed vdagent virtio channel
Aug 15 20:11:56 test login[717]: pam_unix(login:session): session opened for user user by LOGIN(uid=0)
Aug 15 20:11:56 test systemd[1]: Started Session c3 of user user.
Aug 15 20:11:56 test systemd-logind[370]: New session c3 of user user.
Aug 15 20:11:56 test login[717]: LOGIN ON tty2 BY user
Aug 15 20:12:04 test dnsmasq[384]: query[A] 1.de.pool.ntp.org from 127.0.0.1
Aug 15 20:12:04 test dnsmasq[384]: forwarded 1.de.pool.ntp.org to xxx.xxx.xxx.xxx
Aug 15 20:12:04 test dnsmasq[384]: query[AAAA] 1.de.pool.ntp.org from 127.0.0.1
Aug 15 20:12:04 test dnsmasq[384]: forwarded 1.de.pool.ntp.org to xxx.xxx.xxx.xxx
Aug 15 20:12:04 test dnsmasq[384]: reply 1.de.pool.ntp.org is 46.163.88.246
Aug 15 20:12:04 test dnsmasq[384]: reply 1.de.pool.ntp.org is 213.95.200.107
Aug 15 20:12:04 test dnsmasq[384]: reply 1.de.pool.ntp.org is 78.46.204.247
Aug 15 20:12:04 test dnsmasq[384]: reply 1.de.pool.ntp.org is 88.198.12.78
Aug 15 20:12:04 test dnsmasq[384]: reply 1.de.pool.ntp.org is NODATA-IPv6
Aug 15 20:12:05 test systemd-timesyncd[356]: Synchronized to time server 46.163.88.246:123 (1.de.pool.ntp.org).
Aug 15 20:12:37 test sudo[743]:     user : TTY=tty2 ; PWD=/home/user ; USER=root ; COMMAND=/usr/bin/cat /var/log/Xorg.0.log
Aug 15 20:12:37 test sudo[743]: pam_unix(sudo:session): session opened for user root by user(uid=0)
Aug 15 20:12:37 test sudo[743]: pam_unix(sudo:session): session closed for user root
Aug 15 20:12:39 test dnsmasq[384]: query[A]    test from 127.0.0.1
Aug 15 20:12:39 test dnsmasq[384]: config test is NODATA-IPv4
Aug 15 20:12:39 test dnsmasq[384]: query[A] test from 127.0.0.1
Aug 15 20:12:39 test dnsmasq[384]: config test is NODATA-IPv4
Aug 15 20:18:38 test sudo[780]:     user : TTY=tty2 ; PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl -b
Aug 15 20:18:38 test sudo[780]: pam_unix(sudo:session): session opened for user root by user(uid=0)

This

Aug 15 20:11:50 test spice-vdagentd[397]: Error getting active session: No data available
Aug 15 20:11:50 test spice-vdagentd[397]: Error getting active session: No data available

does not happen on my working VMs.

Xorg.0.log

[     8.258] 
X.Org X Server 1.19.3
Release Date: 2017-03-15
[     8.258] X Protocol Version 11, Revision 0
[     8.258] Build Operating System: Linux 4.12.5-1-ARCH x86_64 
[     8.258] Current Operating System: Linux test 4.12.6-1-ARCH #1 SMP PREEMPT Sat Aug 12 09:16:22 CEST 2017 x86_64
[     8.258] Kernel command line: BOOT_IMAGE=/vmlinuz-linux root=UUID=07e16958-47ec-425f-b64d-62a7c1b49124 rw cryptdevice=UUID=848f5472-261d-46f5-9ceb-8f98d83ad36a:cryptroot systemd.legacy_systemd_cgroup_controller=true quiet
[     8.258] Build Date: 13 August 2017  09:37:50PM
[     8.258]  
[     8.258] Current version of pixman: 0.34.0
[     8.258] 	Before reporting problems, check http://wiki.x.org
	to make sure that you have the latest version.
[     8.258] Markers: (--) probed, (**) from config file, (==) default setting,
	(++) from command line, (!!) notice, (II) informational,
	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
[     8.258] (==) Log file: "/var/log/Xorg.0.log", Time: Tue Aug 15 20:11:32 2017
[     8.258] (==) Using config directory: "/etc/X11/xorg.conf.d"
[     8.258] (==) Using system config directory "/usr/share/X11/xorg.conf.d"
[     8.259] (==) No Layout section.  Using the first Screen section.
[     8.259] (==) No screen section available. Using defaults.
[     8.259] (**) |-->Screen "Default Screen Section" (0)
[     8.259] (**) |   |-->Monitor "<default monitor>"
[     8.259] (==) No monitor specified for screen "Default Screen Section".
	Using a default monitor configuration.
[     8.259] (==) Automatically adding devices
[     8.259] (==) Automatically enabling devices
[     8.259] (==) Automatically adding GPU devices
[     8.259] (==) Automatically binding GPU devices
[     8.259] (==) Max clients allowed: 256, resource mask: 0x1fffff
[     8.261] (WW) The directory "/usr/share/fonts/Type1/" does not exist.
[     8.261] 	Entry deleted from font path.
[     8.261] (WW) `fonts.dir' not found (or not valid) in "/usr/share/fonts/100dpi/".
[     8.261] 	Entry deleted from font path.
[     8.261] 	(Run 'mkfontdir' on "/usr/share/fonts/100dpi/").
[     8.261] (WW) `fonts.dir' not found (or not valid) in "/usr/share/fonts/75dpi/".
[     8.261] 	Entry deleted from font path.
[     8.261] 	(Run 'mkfontdir' on "/usr/share/fonts/75dpi/").
[     8.261] (==) FontPath set to:
	/usr/share/fonts/misc/,
	/usr/share/fonts/TTF/,
	/usr/share/fonts/OTF/
[     8.261] (==) ModulePath set to "/usr/lib/xorg/modules"
[     8.261] (II) The server relies on udev to provide the list of input devices.
	If no devices become available, reconfigure udev or disable AutoAddDevices.
[     8.261] (II) Loader magic: 0xd0d6faad40
[     8.261] (II) Module ABI versions:
[     8.261] 	X.Org ANSI C Emulation: 0.4
[     8.261] 	X.Org Video Driver: 23.0
[     8.261] 	X.Org XInput driver : 24.1
[     8.262] 	X.Org Server Extension : 10.0
[     8.262] (++) using VT number 7

[     8.262] (II) systemd-logind: logind integration requires -keeptty and -keeptty was not provided, disabling logind integration
[     8.262] (II) xfree86: Adding drm device (/dev/dri/card0)
[     8.264] (--) PCI:*(0:0:2:0) 1b36:0100:1af4:1100 rev 4, Mem @ 0xf4000000/67108864, 0xf8000000/67108864, 0xfc058000/8192, I/O @ 0x0000c040/32, BIOS @ 0x????????/131072
[     8.264] (WW) Open ACPI failed (/var/run/acpid.socket) (No such file or directory)
[     8.264] (II) LoadModule: "glx"
[     8.267] (II) Loading /usr/lib/xorg/modules/extensions/libglx.so
[     8.271] (II) Module glx: vendor="X.Org Foundation"
[     8.271] 	compiled for 1.19.3, module version = 1.0.0
[     8.271] 	ABI class: X.Org Server Extension, version 10.0
[     8.271] (==) Matched qxl as autoconfigured driver 0
[     8.271] (==) Matched qxl as autoconfigured driver 1
[     8.271] (==) Matched modesetting as autoconfigured driver 2
[     8.271] (==) Matched fbdev as autoconfigured driver 3
[     8.271] (==) Matched vesa as autoconfigured driver 4
[     8.271] (==) Assigned the driver to the xf86ConfigLayout
[     8.271] (II) LoadModule: "qxl"
[     8.271] (II) Loading /usr/lib/xorg/modules/drivers/qxl_drv.so
[     8.272] (II) Module qxl: vendor="X.Org Foundation"
[     8.272] 	compiled for 1.19.3, module version = 0.1.5
[     8.272] 	Module class: X.Org Video Driver
[     8.272] 	ABI class: X.Org Video Driver, version 23.0
[     8.272] (II) LoadModule: "modesetting"
[     8.272] (II) Loading /usr/lib/xorg/modules/drivers/modesetting_drv.so
[     8.272] (II) Module modesetting: vendor="X.Org Foundation"
[     8.272] 	compiled for 1.19.3, module version = 1.19.3
[     8.272] 	Module class: X.Org Video Driver
[     8.272] 	ABI class: X.Org Video Driver, version 23.0
[     8.272] (II) LoadModule: "fbdev"
[     8.272] (II) Loading /usr/lib/xorg/modules/drivers/fbdev_drv.so
[     8.273] (II) Module fbdev: vendor="X.Org Foundation"
[     8.273] 	compiled for 1.19.3, module version = 0.4.4
[     8.273] 	Module class: X.Org Video Driver
[     8.273] 	ABI class: X.Org Video Driver, version 23.0
[     8.273] (II) LoadModule: "vesa"
[     8.273] (II) Loading /usr/lib/xorg/modules/drivers/vesa_drv.so
[     8.273] (II) Module vesa: vendor="X.Org Foundation"
[     8.273] 	compiled for 1.19.3, module version = 2.3.4
[     8.273] 	Module class: X.Org Video Driver
[     8.273] 	ABI class: X.Org Video Driver, version 23.0
[     8.273] (II) qxl: Driver for QXL virtual graphics: QXL 1
[     8.273] (II) modesetting: Driver for Modesetting Kernel Drivers: kms
[     8.273] (II) FBDEV: driver for framebuffer: fbdev
[     8.273] (II) VESA: driver for VESA chipsets: vesa
[     8.276] (II) [KMS] Kernel modesetting enabled.
[     8.276] (WW) Falling back to old probe method for modesetting
[     8.276] (WW) Falling back to old probe method for fbdev
[     8.276] (II) Loading sub module "fbdevhw"
[     8.276] (II) LoadModule: "fbdevhw"
[     8.276] (II) Loading /usr/lib/xorg/modules/libfbdevhw.so
[     8.277] (II) Module fbdevhw: vendor="X.Org Foundation"
[     8.277] 	compiled for 1.19.3, module version = 0.0.2
[     8.277] 	ABI class: X.Org Video Driver, version 23.0
[     8.277] (WW) Falling back to old probe method for vesa
[     8.277] (II) qxl(0): Creating default Display subsection in Screen section
	"Default Screen Section" for depth/fbbpp 24/32
[     8.277] (==) qxl(0): Depth 24, (--) framebuffer bpp 32
[     8.277] (==) qxl(0): RGB weight 888
[     8.277] (==) qxl(0): Default visual is TrueColor
[     8.277] (==) qxl(0): Using gamma correction (1.0, 1.0, 1.0)
[     8.277] (II) qxl(0): Deferred Frames: Disabled
[     8.277] (II) qxl(0): Offscreen Surfaces: Enabled
[     8.277] (II) qxl(0): Image Cache: Enabled
[     8.277] (II) qxl(0): Fallback Cache: Enabled
[     8.277] (==) qxl(0): DPI set to (96, 96)
[     8.277] (II) Loading sub module "fb"
[     8.277] (II) LoadModule: "fb"
[     8.277] (II) Loading /usr/lib/xorg/modules/libfb.so
[     8.277] (II) Module fb: vendor="X.Org Foundation"
[     8.277] 	compiled for 1.19.3, module version = 1.0.0
[     8.277] 	ABI class: X.Org ANSI C Emulation, version 0.4
[     8.277] (II) Loading sub module "ramdac"
[     8.277] (II) LoadModule: "ramdac"
[     8.277] (II) Module "ramdac" already built-in
[     8.277] (II) qxl(0): Output Virtual-0 has no monitor section
[     8.277] (II) qxl(0): Output Virtual-1 has no monitor section
[     8.277] (II) qxl(0): Output Virtual-2 has no monitor section
[     8.277] (II) qxl(0): Output Virtual-3 has no monitor section
[     8.278] (II) qxl(0): EDID for output Virtual-0
[     8.278] (II) qxl(0): Printing probed modes for output Virtual-0
[     8.278] (II) qxl(0): Modeline "1024x768"x59.9   63.53  1024 1072 1176 1328  768 771 775 798 -hsync +vsync (47.8 kHz P)
[     8.278] (II) qxl(0): Modeline "1920x1200"x60.0  193.48  1920 2056 2256 2592  1200 1203 1209 1245 -hsync +vsync (74.6 kHz)
[     8.278] (II) qxl(0): Modeline "1920x1080"x60.0  173.11  1920 2048 2248 2576  1080 1083 1088 1120 -hsync +vsync (67.2 kHz)
[     8.278] (II) qxl(0): Modeline "1600x1200"x60.0  161.23  1600 1712 1880 2160  1200 1203 1207 1245 -hsync +vsync (74.6 kHz)
[     8.278] (II) qxl(0): Modeline "1680x1050"x60.0  146.36  1680 1784 1960 2240  1050 1053 1059 1089 -hsync +vsync (65.3 kHz)
[     8.278] (II) qxl(0): Modeline "1400x1050"x60.0  121.79  1400 1488 1632 1864  1050 1053 1057 1089 -hsync +vsync (65.3 kHz)
[     8.278] (II) qxl(0): Modeline "1280x1024"x59.9  109.10  1280 1368 1496 1712  1024 1027 1034 1063 -hsync +vsync (63.7 kHz)
[     8.278] (II) qxl(0): Modeline "1440x900"x60.0  106.68  1440 1528 1672 1904  900 903 909 934 -hsync +vsync (56.0 kHz)
[     8.278] (II) qxl(0): Modeline "1280x960"x60.0  101.34  1280 1360 1488 1696  960 963 967 996 -hsync +vsync (59.8 kHz)
[     8.278] (II) qxl(0): Modeline "1280x854"x60.0   89.34  1280 1352 1480 1680  854 857 867 887 -hsync +vsync (53.2 kHz)
[     8.278] (II) qxl(0): Modeline "1280x800"x60.0   83.71  1280 1352 1480 1680  800 803 809 831 -hsync +vsync (49.8 kHz)
[     8.278] (II) qxl(0): Modeline "1280x720"x60.0   74.65  1280 1344 1472 1664  720 723 728 748 -hsync +vsync (44.9 kHz)
[     8.278] (II) qxl(0): Modeline "1152x768"x59.9   71.95  1152 1216 1328 1504  768 771 781 798 -hsync +vsync (47.8 kHz)
[     8.278] (II) qxl(0): Modeline "800x600"x60.0   38.31  800 832 912 1024  600 603 607 624 -hsync +vsync (37.4 kHz)
[     8.278] (II) qxl(0): Modeline "848x480"x59.9   31.65  848 872 952 1056  480 483 493 500 -hsync +vsync (30.0 kHz)
[     8.278] (II) qxl(0): Modeline "720x480"x59.9   26.85  720 744 808 896  480 483 493 500 -hsync +vsync (30.0 kHz)
[     8.278] (II) qxl(0): Modeline "640x480"x59.9   23.98  640 664 720 800  480 483 487 500 -hsync +vsync (30.0 kHz)
[     8.278] (II) qxl(0): EDID for output Virtual-1
[     8.278] (II) qxl(0): EDID for output Virtual-2
[     8.278] (II) qxl(0): EDID for output Virtual-3
[     8.278] (II) qxl(0): Output Virtual-0 connected
[     8.278] (II) qxl(0): Output Virtual-1 disconnected
[     8.278] (II) qxl(0): Output Virtual-2 disconnected
[     8.278] (II) qxl(0): Output Virtual-3 disconnected
[     8.278] (II) qxl(0): Using exact sizes for initial modes
[     8.278] (II) qxl(0): Output Virtual-0 using initial mode 1024x768 +0+0
[     8.278] (II) qxl(0): PreInit complete
[     8.278] (II) UnloadModule: "modesetting"
[     8.278] (II) Unloading modesetting
[     8.278] (II) UnloadModule: "fbdev"
[     8.278] (II) Unloading fbdev
[     8.278] (II) UnloadSubModule: "fbdevhw"
[     8.278] (II) Unloading fbdevhw
[     8.278] (II) UnloadModule: "vesa"
[     8.278] (II) Unloading vesa
[     8.278] (--) Depth 24 pixmap format is 32 bpp
[     8.278] (II) UXA(0): Driver registered support for the following operations:
[     8.278] (II)         solid
[     8.278] (II)         copy
[     8.278] (II)         composite (RENDER acceleration)
[     8.278] (II)         put_image
[     8.279] (II) qxl(0): RandR 1.2 enabled, ignore the following RandR disabled message.
[     8.279] resizing primary to 1024x768
[     8.279] primary is 0xd0d8599640
[     8.280] (--) RandR disabled
[     8.286] (II) AIGLX: Screen 0 is not DRI2 capable
[     8.286] (EE) AIGLX: reverting to software rendering
[     8.348] (II) IGLX: enabled GLX_MESA_copy_sub_buffer
[     8.348] (II) IGLX: Loaded and initialized swrast
[     8.348] (II) GLX: Initialized DRISWRAST GL provider for screen 0
[     8.348] (II) qxl(0): Setting screen physical size to 270 x 203
[     8.375] (II) config/udev: Adding input device Power Button (/dev/input/event1)
[     8.375] (**) Power Button: Applying InputClass "evdev keyboard catchall"
[     8.375] (**) Power Button: Applying InputClass "libinput keyboard catchall"
[     8.375] (**) Power Button: Applying InputClass "system-keyboard"
[     8.375] (II) LoadModule: "libinput"
[     8.375] (II) Loading /usr/lib/xorg/modules/input/libinput_drv.so
[     8.378] (II) Module libinput: vendor="X.Org Foundation"
[     8.378] 	compiled for 1.19.3, module version = 0.25.1
[     8.378] 	Module class: X.Org XInput Driver
[     8.378] 	ABI class: X.Org XInput driver, version 24.1
[     8.378] (II) Using input driver 'libinput' for 'Power Button'
[     8.378] (**) Power Button: always reports core events
[     8.378] (**) Option "Device" "/dev/input/event1"
[     8.378] (**) Option "_source" "server/udev"
[     8.378] (II) event1  - (II) Power Button: (II) is tagged by udev as: Keyboard
[     8.378] (II) event1  - (II) Power Button: (II) device is a keyboard
[     8.378] (II) event1  - (II) Power Button: (II) device removed
[     8.383] (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/LNXPWRBN:00/input/input2/event1"
[     8.383] (II) XINPUT: Adding extended input device "Power Button" (type: KEYBOARD, id 6)
[     8.383] (**) Option "xkb_layout" "de"
[     8.394] (II) event1  - (II) Power Button: (II) is tagged by udev as: Keyboard
[     8.394] (II) event1  - (II) Power Button: (II) device is a keyboard
[     8.394] (II) config/udev: Adding input device AT Translated Set 2 keyboard (/dev/input/event0)
[     8.394] (**) AT Translated Set 2 keyboard: Applying InputClass "evdev keyboard catchall"
[     8.394] (**) AT Translated Set 2 keyboard: Applying InputClass "libinput keyboard catchall"
[     8.394] (**) AT Translated Set 2 keyboard: Applying InputClass "system-keyboard"
[     8.394] (II) Using input driver 'libinput' for 'AT Translated Set 2 keyboard'
[     8.394] (**) AT Translated Set 2 keyboard: always reports core events
[     8.394] (**) Option "Device" "/dev/input/event0"
[     8.394] (**) Option "_source" "server/udev"
[     8.394] (II) event0  - (II) AT Translated Set 2 keyboard: (II) is tagged by udev as: Keyboard
[     8.394] (II) event0  - (II) AT Translated Set 2 keyboard: (II) device is a keyboard
[     8.394] (II) event0  - (II) AT Translated Set 2 keyboard: (II) device removed
[     8.403] (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio0/input/input0/event0"
[     8.403] (II) XINPUT: Adding extended input device "AT Translated Set 2 keyboard" (type: KEYBOARD, id 7)
[     8.403] (**) Option "xkb_layout" "de"
[     8.403] (II) event0  - (II) AT Translated Set 2 keyboard: (II) is tagged by udev as: Keyboard
[     8.403] (II) event0  - (II) AT Translated Set 2 keyboard: (II) device is a keyboard
[     8.403] (II) config/udev: Adding input device PC Speaker (/dev/input/event2)
[     8.403] (II) No input driver specified, ignoring this device.
[     8.403] (II) This device may have been added with another device file.
[     8.940] (II) config/udev: Adding input device ImExPS/2 Generic Explorer Mouse (/dev/input/mouse0)
[     8.940] (II) No input driver specified, ignoring this device.
[     8.940] (II) This device may have been added with another device file.
[     8.956] (II) config/udev: Adding input device ImExPS/2 Generic Explorer Mouse (/dev/input/event3)
[     8.956] (**) ImExPS/2 Generic Explorer Mouse: Applying InputClass "evdev pointer catchall"
[     8.956] (**) ImExPS/2 Generic Explorer Mouse: Applying InputClass "libinput pointer catchall"
[     8.956] (II) Using input driver 'libinput' for 'ImExPS/2 Generic Explorer Mouse'
[     8.956] (**) ImExPS/2 Generic Explorer Mouse: always reports core events
[     8.956] (**) Option "Device" "/dev/input/event3"
[     8.956] (**) Option "_source" "server/udev"
[     8.956] (II) event3  - (II) ImExPS/2 Generic Explorer Mouse: (II) is tagged by udev as: Mouse
[     8.956] (II) event3  - (II) ImExPS/2 Generic Explorer Mouse: (II) device is a pointer
[     8.956] (II) event3  - (II) ImExPS/2 Generic Explorer Mouse: (II) device removed
[     8.970] (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio1/input/input4/event3"
[     8.970] (II) XINPUT: Adding extended input device "ImExPS/2 Generic Explorer Mouse" (type: MOUSE, id 8)
[     8.970] (**) Option "AccelerationScheme" "none"
[     8.970] (**) ImExPS/2 Generic Explorer Mouse: (accel) selected scheme none/0
[     8.970] (**) ImExPS/2 Generic Explorer Mouse: (accel) acceleration factor: 2.000
[     8.970] (**) ImExPS/2 Generic Explorer Mouse: (accel) acceleration threshold: 4
[     8.970] (II) event3  - (II) ImExPS/2 Generic Explorer Mouse: (II) is tagged by udev as: Mouse
[     8.970] (II) event3  - (II) ImExPS/2 Generic Explorer Mouse: (II) device is a pointer
[    13.617] (II) config/udev: Adding input device spice vdagent tablet (/dev/input/js0)
[    13.617] (II) No input driver specified, ignoring this device.
[    13.617] (II) This device may have been added with another device file.
[    13.617] (II) config/udev: Adding input device spice vdagent tablet (/dev/input/mouse1)
[    13.618] (II) No input driver specified, ignoring this device.
[    13.618] (II) This device may have been added with another device file.
[    13.637] (II) config/udev: Adding input device spice vdagent tablet (/dev/input/event4)
[    13.637] (**) spice vdagent tablet: Applying InputClass "evdev pointer catchall"
[    13.637] (**) spice vdagent tablet: Applying InputClass "libinput pointer catchall"
[    13.637] (II) Using input driver 'libinput' for 'spice vdagent tablet'
[    13.637] (**) spice vdagent tablet: always reports core events
[    13.637] (**) Option "Device" "/dev/input/event4"
[    13.637] (**) Option "_source" "server/udev"
[    13.637] (II) event4  - (II) spice vdagent tablet: (II) is tagged by udev as: Mouse
[    13.637] (II) event4  - (II) spice vdagent tablet: (II) device is a pointer
[    13.637] (II) event4  - (II) spice vdagent tablet: (II) device removed
[    13.646] (**) Option "config_info" "udev:/sys/devices/virtual/input/input5/event4"
[    13.646] (II) XINPUT: Adding extended input device "spice vdagent tablet" (type: MOUSE, id 9)
[    13.646] (**) Option "AccelerationScheme" "none"
[    13.646] (**) spice vdagent tablet: (accel) selected scheme none/0
[    13.646] (**) spice vdagent tablet: (accel) acceleration factor: 2.000
[    13.646] (**) spice vdagent tablet: (accel) acceleration threshold: 4
[    13.647] (II) event4  - (II) spice vdagent tablet: (II) is tagged by udev as: Mouse
[    13.647] (II) event4  - (II) spice vdagent tablet: (II) device is a pointer
[    17.262] (II) qxl(0): Allocate new frame buffer 1316x955 stride
[    17.288] (II) config/udev: removing device spice vdagent tablet
[    17.288] (II) event4  - (II) spice vdagent tablet: (II) device removed
[    17.297] (II) UnloadModule: "libinput"
[    17.322] (II) config/udev: Adding input device spice vdagent tablet (/dev/input/js0)
[    17.322] (II) No input driver specified, ignoring this device.
[    17.322] (II) This device may have been added with another device file.
[    17.322] (II) config/udev: Adding input device spice vdagent tablet (/dev/input/mouse1)
[    17.323] (II) No input driver specified, ignoring this device.
[    17.323] (II) This device may have been added with another device file.
[    17.330] (II) config/udev: Adding input device spice vdagent tablet (/dev/input/event4)
[    17.330] (**) spice vdagent tablet: Applying InputClass "evdev pointer catchall"
[    17.330] (**) spice vdagent tablet: Applying InputClass "libinput pointer catchall"
[    17.330] (II) Using input driver 'libinput' for 'spice vdagent tablet'
[    17.330] (**) spice vdagent tablet: always reports core events
[    17.330] (**) Option "Device" "/dev/input/event4"
[    17.330] (**) Option "_source" "server/udev"
[    17.331] (II) event4  - (II) spice vdagent tablet: (II) is tagged by udev as: Mouse
[    17.331] (II) event4  - (II) spice vdagent tablet: (II) device is a pointer
[    17.331] (II) event4  - (II) spice vdagent tablet: (II) device removed
[    17.346] (**) Option "config_info" "udev:/sys/devices/virtual/input/input6/event4"
[    17.346] (II) XINPUT: Adding extended input device "spice vdagent tablet" (type: MOUSE, id 9)
[    17.346] (**) Option "AccelerationScheme" "none"
[    17.346] (**) spice vdagent tablet: (accel) selected scheme none/0
[    17.346] (**) spice vdagent tablet: (accel) acceleration factor: 2.000
[    17.346] (**) spice vdagent tablet: (accel) acceleration threshold: 4
[    17.347] (II) event4  - (II) spice vdagent tablet: (II) is tagged by udev as: Mouse
[    17.347] (II) event4  - (II) spice vdagent tablet: (II) device is a pointer
[    26.209] (II) event1  - (II) Power Button: (II) device removed
[    26.218] (II) event0  - (II) AT Translated Set 2 keyboard: (II) device removed
[    26.228] (II) event3  - (II) ImExPS/2 Generic Explorer Mouse: (II) device removed
[    26.248] (II) event4  - (II) spice vdagent tablet: (II) device removed
[    26.291] (II) config/udev: removing device spice vdagent tablet
[    26.292] (II) UnloadModule: "libinput"

This

[    26.248] (II) event4  - (II) spice vdagent tablet: (II) device removed
[    26.291] (II) config/udev: removing device spice vdagent tablet

does not happen on my working VMs.

@ Lone_Wolf: Thx for your support .

Last edited by ArgelErx (2017-08-15 19:09:02)

Lone_Wolf

Forum Moderator

From: Netherlands, Europe

Registered: 2005-10-04

Posts: 11,925

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Aug 15 20:11:32 test lightdm[405]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files

    8.262] (II) systemd-logind: logind integration requires -keeptty and -keeptty was not provided, disabling logind integration

Combining those with the spice-vdagent messages suggests that lightdm may have to do with the problem.

Try booting to multi-user target, logging in as user. running startxfce4 and / or startx directly.

---

Disliking systemd intensely, but not satisfied with alternatives so focusing on taming systemd.

(A works at time B)  && (time C > time B ) ≠  (A works at time C)

ArgelErx

Member

Registered: 2013-12-30

Posts: 13

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Restored a backup of my test VM. Did a

yaourt -Sayu --ignore linux

extra/qemu-guest-agent             2.9.0-2                    2 -> 3
extra/xorg-server                  1.19.3-2                   2 -> 3
community/xf86-video-qxl           0.1.5-3                    3 -> 4
core/systemd                       233.75-3                   -> 234.11-8
extra/spice                        0.12.8-2                   -> 0.12.8+8+ga957a90b-1

yaourt -Qs linux

core/linux 4.11.9-1 (base)
community/spice-vdagent 0.17.0-3

Rebooted VM.
Verified that "Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files" is present.
Verified that "systemd-logind: logind integration requires -keeptty and -keeptty was not provided, disabling logind integration" is present.

VM works as expected.

Did a

yaourt -S accountsservice

accountsservice-0.6.45-1

Rebooted VM.

dbus[383]: [system] Activating via systemd: service name='org.freedesktop.Accounts' unit='accounts-daemon.service'
accounts-daemon[421]: started daemon version 0.6.45
systemd[1]: Started Accounts Service.
dbus[383]: [system] Successfully activated service 'org.freedesktop.Accounts'

"Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files" is gone.
"systemd-logind: logind integration requires -keeptty and -keeptty was not provided, disabling logind integration" still present.

Did a

systemctl stop lightdm
startxfce4

[  1400.779] (++) using VT number 2                                                                                                                                            
[  1400.779] (--) controlling tty is VT number 2, auto-enabling KeepTty                                                                                                        
[  1400.781] (II) systemd-logind: took control of session /org/freedesktop/login1/session/c4 

"systemd-logind: logind integration requires -keeptty and -keeptty was not provided, disabling logind integration" is gone.

VM works as expected.

Did a

systemctl disable lightdm
yaourt -Sayu

core/linux 4.11.9-1 -> 4.12.8-1

Rebooted VM.

Did a

startxfce4

No luck.

"spice-vdagentd[397]: Error getting active session: No data available" still present.

In addition:
- Xfce4 hangs on logout
- light-locker dumps core

Rebooted VM.

Did a

yaourt -Rc light-locker

light-locker-1.8.0-1

Did a

startxfce4

No luck. In addition Xfce4 still hangs on logout.

spice-vdagentd[397]: Error getting active session: No data available
spice-vdagentd[397]: Error getting active session: No data available
spice-vdagentd[397]: closed vdagent virtio channel

still present.

"Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files" and "systemd-logind: logind integration requires -keeptty and -keeptty was not provided, disabling logind integration" are both gone.

New in Xorg.log

[   200.747] (II) config/udev: removing device spice vdagent tablet
[   200.748] (II) UnloadModule: "libinput"
[   200.748] (II) systemd-logind: releasing fd for 13:68
[   200.748] (EE) systemd-logind: failed to release device: Device not taken
[   206.551] (II) systemd-logind: got pause for 13:64
[   206.551] (II) systemd-logind: got pause for 13:67
[   206.551] (II) systemd-logind: got pause for 13:68
[   206.551] (WW) systemd-logind: could not find dev 13:68
[   206.551] (II) systemd-logind: got pause for 226:0
[   206.551] (II) systemd-logind: got pause for 13:65

which is probaly the reason why Xfce4 logout hangs.

So any ideas how to resolve "spice-vdagentd[397]: Error getting active session: No data available"?

Edit says:

Prior to all off the abvoe i updated the host system:

extra/qemu                         2.9.0-2                    2 -> 3
core/linux-lts                     4.9.41-1                   -> 4.9.44-1
community/virt-manager             1.4.1-2                    -> 1.4.2-1

Last edited by ArgelErx (2017-08-19 07:12:23)

ArgelErx

Member

Registered: 2013-12-30

Posts: 13

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Update

tldr; Guest screen resolution sync between guest vd-agent, host qemu/libvirt, client spice viewer/server is unreliable.

So after doing further experiments, code reading and extensive head scratching this is the latest state of my problem:

This

Aug 15 20:11:50 test spice-vdagentd[397]: Error getting active session: No data available
Aug 15 20:11:50 test spice-vdagentd[397]: closed vdagent virtio channel

does not seem to be a problem but "normal" operation.
Simple put: vdagentd/vdagent run in an event loop, reacting to spice server/X11/xrandr messages. On each event loop session information is requested, communication channels are reestablished and information is exchanged.

This "works" (confirmed by extensive logging [including new log statemnets in the vdagentd/vdagent code] and testing) but not reliable (at least not as reliable as with kernel 4.11.x).

When it does not work guest, host and client spice viewer/server disagree on guest resolution, hence viewer screen corruption.

The only way (for me) out if this is repeatedly forcing a guest screen resolution sync by resizing the client spice viewer window and using xrandr in the guest. One out of three tries this fixes the viewer screen corruption.

Not really useable.

At this point i am stuck (or more precisely i am loathing to investigate further). Since the breaking component in this seems to be the kernel (modules) in the guest the next step (imho) would be building a custom 4.12 kernel using the 4.11 kernel configuration (probaly something in char device handling changed and broke the communication?). Something i am not fond of doing.

I am letting this go for now and stick to kernel 4.11.9, testing the state of this when relevant updates come along.

ArgelErx

Member

Registered: 2013-12-30

Posts: 13

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

Resolved since kernel 4.14.

How do I mark this as Solved?

Slithery

Administrator

From: Norfolk, UK

Registered: 2013-12-01

Posts: 5,776

Re: [SOLVED] Problem with qxl/Xfce4 and kernel >= 4.12

CoC - How to Post

---

No, it didn't "fix" anything. It just shifted the brokeness one space to the right. - jasonwryan
Closing -- for deletion; Banning -- for muppetry. - jasonwryan

aur - dotfiles