starting VDE switch for network 1 running the VM test script starting all VMs machine: starting vm machine# Formatting '/tmp/nix-build-vm-test-run-plasma5.drv-0/vm-state-machine/machine.qcow2', fmt=qcow2 size=536870912 encryption=off cluster_size=65536 lazy_refcounts=off refcount_bits=16 machine: QEMU running (pid 78) machine: waiting for (?^:Alice Foobar) to appear on the screen machine: performing optical character recognition machine: sending monitor command: screendump /tmp/nix-build-vm-test-run-plasma5.drv-0/ocrin.ppm machine# [ 0.000000] Linux version 4.9.20 (nixbld@localhost) (gcc version 5.4.0 (GCC) ) #1-NixOS SMP Fri Mar 31 08:32:02 UTC 2017 machine# [ 0.000000] Command line: loglevel=7 console=ttyS0 panic=1 boot.panic_on_fail init=/nix/store/kai1rnwk1mrcnli6k9bm3dh8khaa9p41-nixos-system-machine-17.09pre-git/init regInfo=/nix/store/h7b8v6cw6gyb5j3a5yycrxsfvz053i4c-reginfo machine# [ 0.000000] x86/fpu: Legacy x87 FPU detected. machine# [ 0.000000] x86/fpu: Using 'eager' FPU context switches. machine# [ 0.000000] e820: BIOS-provided physical RAM map: machine# [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable machine# [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved machine# [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved machine# [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdbfff] usable machine# [ 0.000000] BIOS-e820: [mem 0x000000003ffdc000-0x000000003fffffff] reserved machine# [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved machine# [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved machine# [ 0.000000] NX (Execute Disable) protection: active machine# [ 0.000000] SMBIOS 2.8 present. machine# [ 0.000000] Hypervisor detected: KVM machine# [ 0.000000] e820: last_pfn = 0x3ffdc max_arch_pfn = 0x400000000 machine# [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- UC machine# [ 0.000000] found SMP MP-table at [mem 0x000f6a70-0x000f6a7f] mapped at [ffff9e7e000f6a70] machine# [ 0.000000] Scanning 1 areas for low memory corruption machine# [ 0.000000] RAMDISK: [mem 0x3f9ce000-0x3ffcffff] machine# [ 0.000000] ACPI: Early table checksum verification disabled machine# [ 0.000000] ACPI: RSDP 0x00000000000F6880 000014 (v00 BOCHS ) machine# [ 0.000000] ACPI: RSDT 0x000000003FFE18DC 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) machine# [ 0.000000] ACPI: FACP 0x000000003FFE17B8 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) machine# [ 0.000000] ACPI: DSDT 0x000000003FFE0040 001778 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) machine# [ 0.000000] ACPI: FACS 0x000000003FFE0000 000040 machine# [ 0.000000] ACPI: APIC 0x000000003FFE182C 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) machine# [ 0.000000] ACPI: HPET 0x000000003FFE18A4 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) machine# [ 0.000000] No NUMA configuration found machine# [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdbfff] machine# [ 0.000000] NODE_DATA(0) allocated [mem 0x3ffd8000-0x3ffdbfff] machine# [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 machine# [ 0.000000] kvm-clock: cpu 0, msr 0:3ffd2001, primary cpu clock machine# [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns machine# [ 0.000000] Zone ranges: machine# [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] machine# [ 0.000000] DMA32 [mem 0x0000000001000000-0x000000003ffdbfff] machine# [ 0.000000] Normal empty machine# [ 0.000000] Movable zone start for each node machine# [ 0.000000] Early memory node ranges machine# [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] machine# [ 0.000000] node 0: [mem 0x0000000000100000-0x000000003ffdbfff] machine# [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdbfff] machine# [ 0.000000] ACPI: PM-Timer IO Port: 0x608 machine# [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) machine# [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 machine# [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) machine# [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) machine# [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) machine# [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) machine# [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) machine# [ 0.000000] Using ACPI (MADT) for SMP configuration information machine# [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 machine# [ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs machine# [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] machine# [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] machine# [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] machine# [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] machine# [ 0.000000] e820: [mem 0x40000000-0xfeffbfff] available for PCI devices machine# [ 0.000000] Booting paravirtualized kernel on KVM machine# [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns machine# [ 0.000000] setup_percpu: NR_CPUS:384 nr_cpumask_bits:384 nr_cpu_ids:1 nr_node_ids:1 machine# [ 0.000000] percpu: Embedded 34 pages/cpu @ffff9e7e3f600000 s102168 r8192 d28904 u2097152 machine# [ 0.000000] KVM setup async PF for cpu 0 machine# [ 0.000000] kvm-stealtime: cpu 0, msr 3f60d940 machine# [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 257893 machine# [ 0.000000] Policy zone: DMA32 machine# [ 0.000000] Kernel command line: loglevel=7 console=ttyS0 panic=1 boot.panic_on_fail init=/nix/store/kai1rnwk1mrcnli6k9bm3dh8khaa9p41-nixos-system-machine-17.09pre-git/init regInfo=/nix/store/h7b8v6cw6gyb5j3a5yycrxsfvz053i4c-reginfo machine# [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) machine# [ 0.000000] Memory: 1013860K/1048040K available (5520K kernel code, 983K rwdata, 1856K rodata, 1292K init, 780K bss, 34180K reserved, 0K cma-reserved) machine# [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 machine# [ 0.000000] Hierarchical RCU implementation. machine# [ 0.000000] Build-time adjustment of leaf fanout to 64. machine# [ 0.000000] RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. machine# [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1 machine# [ 0.000000] NR_IRQS:24832 nr_irqs:256 16 machine# [ 0.000000] Console: colour VGA+ 80x25 machine# [ 0.000000] console [ttyS0] enabled machine# [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns machine# [ 0.000000] tsc: Detected 2194.916 MHz processor machine# [ 0.002000] Calibrating delay loop (skipped) preset value.. 4389.83 BogoMIPS (lpj=2194916) machine# [ 0.002348] pid_max: default: 32768 minimum: 301 machine# [ 0.003018] ACPI: Core revision 20160831 machine# [ 0.004628] ACPI: 1 ACPI AML tables successfully acquired and loaded machine# [ 0.005030] Security Framework initialized machine# [ 0.005460] Yama: becoming mindful. machine# [ 0.006017] AppArmor: AppArmor initialized machine# [ 0.006512] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) machine# [ 0.008661] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) machine# [ 0.010190] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes) machine# [ 0.011006] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes) machine# [ 0.012449] CPU: Physical Processor ID: 0 machine# [ 0.013017] mce: CPU supports 10 MCE banks machine# [ 0.013492] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 machine# [ 0.014003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 machine# [ 0.028758] Freeing SMP alternatives memory: 24K (ffffffff95a3a000 - ffffffff95a40000) machine# [ 0.033931] ftrace: allocating 22853 entries in 90 pages machine# [ 0.040251] smpboot: Max logical packages: 1 machine# [ 0.040899] x2apic enabled machine# [ 0.041005] Switched APIC routing to physical x2apic. machine# [ 0.042804] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 machine# [ 0.043000] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1) machine# [ 0.043006] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. machine# [ 0.044604] x86: Booted up 1 node, 1 CPUs machine# [ 0.045003] smpboot: Total of 1 processors activated (4389.83 BogoMIPS) machine# [ 0.046330] devtmpfs: initialized machine# [ 0.047119] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns machine# [ 0.048006] futex hash table entries: 256 (order: 2, 16384 bytes) machine# [ 0.049043] pinctrl core: initialized pinctrl subsystem machine# [ 0.050014] NET: Registered protocol family 16 machine# [ 0.050809] cpuidle: using governor menu machine# [ 0.051044] ACPI: bus type PCI registered machine# [ 0.052033] PCI: Using configuration type 1 for base access machine# [ 0.053440] HugeTLB registered 2 MB page size, pre-allocated 0 pages machine# [ 0.054303] ACPI: Added _OSI(Module Device) machine# [ 0.055003] ACPI: Added _OSI(Processor Device) machine# [ 0.055577] ACPI: Added _OSI(3.0 _SCP Extensions) machine# [ 0.056002] ACPI: Added _OSI(Processor Aggregator Device) machine# [ 0.059007] ACPI: Interpreter enabled machine# [ 0.059517] ACPI: (supports S0 S3 S4 S5) machine# [ 0.059917] ACPI: Using IOAPIC for interrupt routing machine# [ 0.060018] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug machine# [ 0.065108] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) machine# [ 0.065882] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] machine# [ 0.066006] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM machine# [ 0.067009] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. machine# [ 0.068048] PCI host bridge to bus 0000:00 machine# [ 0.069003] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] machine# [ 0.070003] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] machine# [ 0.071002] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] machine# [ 0.072002] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window] machine# [ 0.073006] pci_bus 0000:00: root bus resource [bus 00-ff] machine# [ 0.086865] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] machine# [ 0.087003] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] machine# [ 0.088002] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] machine# [ 0.089002] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] machine# [ 0.101146] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI machine# [ 0.102010] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB machine# [ 0.240861] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) machine# [ 0.241384] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) machine# [ 0.243139] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) machine# [ 0.244438] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) machine# [ 0.245385] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) machine# [ 0.246362] ACPI: Enabled 3 GPEs in block 00 to 0F machine# [ 0.248159] vgaarb: setting as boot device: PCI:0000:00:02.0 machine# [ 0.249000] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none machine# [ 0.249004] vgaarb: loaded machine# [ 0.250002] vgaarb: bridge control possible 0000:00:02.0 machine# [ 0.251321] PCI: Using ACPI for IRQ routing machine# [ 0.252507] NetLabel: Initializing machine# [ 0.253009] NetLabel: domain hash size = 128 machine# [ 0.254001] NetLabel: protocols = UNLABELED CIPSOv4 machine# [ 0.255062] NetLabel: unlabeled traffic allowed by default machine# [ 0.256339] HPET: 3 timers in total, 0 timers will be used for per-cpu timer machine# [ 0.257026] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 machine# [ 0.258228] hpet0: 3 comparators, 64-bit 100.000000 MHz counter machine# [ 0.263125] clocksource: Switched to clocksource kvm-clock machine# [ 0.303608] VFS: Disk quotas dquot_6.6.0 machine# [ 0.304639] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) machine# [ 0.306328] AppArmor: AppArmor Filesystem Enabled machine# [ 0.307476] pnp: PnP ACPI init machine# [ 0.308879] pnp: PnP ACPI: found 6 devices machine# [ 0.317028] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns machine# [ 0.319127] NET: Registered protocol family 2 machine# [ 0.320570] TCP established hash table entries: 8192 (order: 4, 65536 bytes) machine# [ 0.322104] TCP bind hash table entries: 8192 (order: 5, 131072 bytes) machine# [ 0.323750] TCP: Hash tables configured (established 8192 bind 8192) machine# [ 0.325435] UDP hash table entries: 512 (order: 2, 16384 bytes) machine# [ 0.326763] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) machine# [ 0.328307] NET: Registered protocol family 1 machine# [ 0.329309] pci 0000:00:00.0: Limiting direct PCI/PCI transfers machine# [ 0.330557] pci 0000:00:01.0: PIIX3: Enabling Passive Release machine# [ 0.331785] pci 0000:00:01.0: Activating ISA DMA hang workarounds machine# [ 0.367519] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11 machine# [ 0.403613] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] machine# [ 0.405964] Unpacking initramfs... machine# [ 0.621379] Freeing initrd memory: 6152K (ffff9e7e3f9ce000 - ffff9e7e3ffd0000) machine# [ 0.623483] Scanning for low memory corruption every 60 seconds machine# [ 0.625455] audit: initializing netlink subsys (disabled) machine# [ 0.626931] audit: type=2000 audit(1491181266.040:1): initialized machine# [ 0.629204] workingset: timestamp_bits=40 max_order=18 bucket_order=0 machine# [ 0.645367] zbud: loaded machine# [ 0.646842] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) machine# [ 0.648882] io scheduler noop registered machine# [ 0.650054] io scheduler cfq registered (default) machine# [ 0.651501] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 machine# [ 0.653047] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 machine# [ 0.655080] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled machine# [ 0.684235] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A machine# [ 0.687077] microcode: sig=0xf61, pf=0x1, revision=0x1 machine# [ 0.688402] microcode: Microcode Update Driver: v2.01 , Peter Oruba machine# [ 0.690614] registered taskstats version 1 machine# [ 0.691600] zswap: loaded using pool lzo/zbud machine# [ 0.692752] AppArmor: AppArmor sha1 policy hashing enabled machine# [ 0.697945] Freeing unused kernel memory: 1292K (ffffffff958f7000 - ffffffff95a3a000) machine# [ 0.699665] Write protecting the kernel read-only data: 8192k machine# [ 0.701767] Freeing unused kernel memory: 608K (ffff9e7e34d68000 - ffff9e7e34e00000) machine# [ 0.704559] Freeing unused kernel memory: 192K (ffff9e7e34fd0000 - ffff9e7e35000000) machine# machine# <<< NixOS Stage 1 >>> machine# machine# loading module virtio_balloon... machine# loading module virtio_console... machine# loading module virtio_rng... machine# loading module dm_mod... machine# [ 0.785479] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com machine# running udev... machine# [ 0.794581] systemd-udevd[84]: starting version 232 machine# [ 0.798716] random: systemd-udevd: uninitialized urandom read (16 bytes read) machine# [ 0.800607] random: systemd-udevd: uninitialized urandom read (16 bytes read) machine# [ 0.803062] random: systemd-udevd: uninitialized urandom read (16 bytes read) machine# [ 0.805060] random: udevadm: uninitialized urandom read (16 bytes read) machine# [ 0.807107] random: udevadm: uninitialized urandom read (16 bytes read) machine# [ 0.810886] random: udevadm: uninitialized urandom read (16 bytes read) machine# [ 0.812600] random: udevadm: uninitialized urandom read (16 bytes read) machine# [ 0.814414] random: udevadm: uninitialized urandom read (16 bytes read) machine# [ 0.816527] random: udevadm: uninitialized urandom read (16 bytes read) machine# [ 0.818778] random: udevadm: uninitialized urandom read (16 bytes read) machine# [ 0.912961] rtc_cmos 00:00: RTC can wake from S4 machine# [ 0.942054] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 machine# [ 0.951117] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs machine# [ 0.960078] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 machine# [ 0.962945] serio: i8042 KBD port at 0x60,0x64 irq 1 machine# [ 0.970022] serio: i8042 AUX port at 0x60,0x64 irq 12 machine# [ 1.059719] ACPI: bus type USB registered machine# [ 1.063079] usbcore: registered new interface driver usbfs machine# [ 1.068070] usbcore: registered new interface driver hub machine# [ 1.073103] usbcore: registered new device driver usb machine# [ 1.094114] SCSI subsystem initialized machine# [ 1.119066] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver machine# [ 1.132727] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10 machine# [ 1.159525] uhci_hcd: USB Universal Host Controller Interface driver machine# [ 1.176979] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 machine# [ 1.255264] uhci_hcd 0000:00:01.2: UHCI Host Controller machine# [ 1.256284] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 machine# [ 1.257664] uhci_hcd 0000:00:01.2: detected 2 ports machine# [ 1.258684] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c080 machine# [ 1.259878] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 machine# [ 1.261266] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 machine# [ 1.262642] usb usb1: Product: UHCI Host Controller machine# [ 1.263652] usb usb1: Manufacturer: Linux 4.9.20 uhci_hcd machine# [ 1.264919] usb usb1: SerialNumber: 0000:00:01.2 machine# [ 1.266281] hub 1-0:1.0: USB hub found machine# [ 1.267057] hub 1-0:1.0: 2 ports detected machine# [ 1.271043] scsi host0: ata_piix machine# [ 1.271970] scsi host1: ata_piix machine# [ 1.272871] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc140 irq 14 machine# [ 1.274211] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc148 irq 15 machine# [ 1.299864] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 machine# [ 1.327179] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11 machine# [ 1.436892] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 machine# [ 1.490766] ata2.00: configured for MWDMA2 machine# [ 1.493552] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 machine# [ 1.537057] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray machine# [ 1.538894] cdrom: Uniform CD-ROM driver Revision: 3.20 machine# [ 1.606044] usb 1-1: new full-speed USB device number 2 using uhci_hcd machine# [ 1.613446] 9pnet: Installing 9P2000 support machine# [ 1.632102] tsc: Refined TSC clocksource calibration: 2194.914 MHz machine# [ 1.634672] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1fa36e579bf, max_idle_ns: 440795269840 ns machine# [ 1.761421] usb 1-1: New USB device found, idVendor=0627, idProduct=0001 machine# [ 1.763507] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=5 machine# [ 1.765487] usb 1-1: Product: QEMU USB Tablet machine# [ 1.766820] usb 1-1: Manufacturer: QEMU machine# [ 1.767901] usb 1-1: SerialNumber: 42 machine# kbd_mode: KDSKBMODE: Inappropriate ioctl for device machine# %Gstarting device mapper and LVM... %machine# [ 1.797715] clocksource: Switched to clocksource acpi_pm machine# [ 1.804628] hidraw: raw HID events driver (C) Jiri Kosina machine# [ 1.811264] random: fast init done machine# mke2fs 1.43.4 (31-Jan-2017) machine# Creating filesystem with 131072 4k blocks and 32768 inodes machine# Filesystem UUID: b03b0fa1-7cbc-425a-8cd9-7fed98876a90[ 1.833920] usbcore: registered new interface driver usbhid machine# [ 1.835449] usbhid: USB HID core driver machine# machine# Superblock backups stored on blocks: machine# 32768, 98304 machine# machine# Allocating group tables: 0/4 done machine# Writing inode tables: 0/4 done machine# Creating journal (4096 blocks): [ 1.847712] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 machine# [ 1.868517] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Pointer [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0 machine# done machine# Writing superblocks and filesystem accounting information: 0/4 done machine# machine# checking /dev/vda... machine# fsck (busybox 1.26.2, ) machine# [fsck.ext4 (1) -- /mnt-root/] fsck.ext4 -a /dev/vda machine# /dev/vda: clean, 11/32768 files, 6353/131072 blocks machine# mounting /dev/vda on /... machine# [ 2.972761] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null) machine# mounting store on /nix/.ro-store... machine# [ 2.998689] FS-Cache: Loaded machine# [ 3.008687] 9p: Installing v9fs 9p2000 file system support machine# [ 3.012447] FS-Cache: Netfs '9p' registered for caching machine# mounting tmpfs on /nix/.rw-store... machine# mounting shared on /tmp/shared... machine# mounting xchg on /tmp/xchg... machine# mounting overlay filesystem on /nix/store... machine# machine# <<< NixOS Stage 2 >>> machine# machine# [ 3.535624] EXT4-fs (vda): re-mounted. Opts: (null) machine# [ 3.539396] booting system configuration /nix/store/kai1rnwk1mrcnli6k9bm3dh8khaa9p41-nixos-system-machine-17.09pre-git Tesseract Open Source OCR Engine v3.04.01 with Leptonica machine# running activation script... machine# setting up /etc... machine: performing optical character recognition machine: sending monitor command: screendump /tmp/nix-build-vm-test-run-plasma5.drv-0/ocrin.ppm machine# Initializing machine ID from random generator. machine# [ 8.502943] random: crng init done Tesseract Open Source OCR Engine v3.04.01 with Leptonica machine# starting systemd... machine# [ 10.806351] NET: Registered protocol family 10 machine# [ 10.824582] ip_tables: (C) 2000-2006 Netfilter Core Team machine# [ 10.843692] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT -SELINUX +IMA +APPARMOR -SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD -IDN) machine# [ 10.847689] systemd[1]: Detected virtualization qemu. machine# [ 10.848813] systemd[1]: Detected architecture x86-64. machine# [ 10.855356] systemd[1]: Set hostname to . machine# [ 10.978300] systemd-fstab-generator[447]: Checking was requested for "store", but it is not a device. machine# [ 10.980990] systemd-fstab-generator[447]: Checking was requested for "shared", but it is not a device. machine# [ 10.983777] systemd-fstab-generator[447]: Checking was requested for "xchg", but it is not a device. machine# [ 11.876772] systemd[1]: serial-getty@hvc0.service: Cannot add dependency job, ignoring: Unit serial-getty@hvc0.service is masked. machine# [ 11.879812] systemd[1]: serial-getty@ttyS0.service: Cannot add dependency job, ignoring: Unit serial-getty@ttyS0.service is masked. machine# [ 11.885526] systemd[1]: Started Forward Password Requests to Wall Directory Watch. machine# [ 11.888685] systemd[1]: Listening on udev Kernel Socket. machine# [ 11.890845] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. machine# [ 11.892934] systemd[1]: Listening on udev Control Socket. machine: performing optical character recognition machine: sending monitor command: screendump /tmp/nix-build-vm-test-run-plasma5.drv-0/ocrin.ppm machine# [ 12.087576] loop: module loaded machine# [ 12.186837] EXT4-fs (vda): re-mounted. Opts: (null) machine# [ 12.259987] audit: type=1130 audit(1491181277.461:2): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm="systemd" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' machine# [ 12.272438] audit: type=1130 audit(1491181277.474:3): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-remount-fs comm="systemd" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' machine# [ 12.302776] audit: type=1130 audit(1491181277.504:4): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-modules-load comm="systemd" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' machine# [ 12.458402] audit: type=1127 audit(1491181277.659:5): pid=481 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' machine# [ 12.468914] audit: type=1130 audit(1491181277.669:6): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' machine# [ 12.518505] audit: type=1130 audit(1491181277.720:7): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-udev-trigger comm="systemd" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' machine# [ 12.547894] audit: type=1130 audit(1491181277.749:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-random-seed comm="systemd" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' machine# [ 12.571422] audit: type=1130 audit(1491181277.773:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/nix/store/bjr4icgfz8a37d2z8r634fnclrnscjh3-systemd-232/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' machine# [ 12.632253] audit: type=1325 audit(1491181277.833:10): table=filter family=2 entries=0 machine# [ 12.635893] audit: type=1300 audit(1491181277.833:10): arch=c000003e syscall=175 success=yes exit=0 a0=2573ca0 a1=1a50 a2=419eec a3=0 items=0 ppid=105 pid=488 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="modprobe" exe="/nix/store/hwm3frz532d6c72yfr9a3zqswvjbnjjd-kmod-24/bin/kmod" key=(null) machine# [ 12.729852] ip6_tables: (C) 2000-2006 Netfilter Core Team machine# [ 13.303646] systemd-journald[472]: Received request to flush runtime journal from PID 1 machine# [ 13.543450] systemd-modules-load[453]: Inserted module 'cpufreq_ondemand' machine# [ 13.556733] systemd-modules-load[453]: Inserted module 'loop' machine# [ 13.578700] systemd-modules-load[453]: Inserted module 'snd_pcm_oss' machine# [ 13.591853] systemd-modules-load[453]: Failed to find module 'gcov-proc' machine# [ 13.613879] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 machine# [ 13.622546] systemd[1]: Starting Flush Journal to Persistent Storage... machine# [ 13.631254] systemd[1]: Started Flush Journal to Persistent Storage.[ 13.632502] ACPI: Power Button [PWRF] machine# machine# [ 13.639724] systemd[1]: Starting Create Volatile Files and Directories... machine# [ 13.665761] systemd[1]: Started Create Volatile Files and Directories. machine# [ 13.764905] parport_pc 00:04: reported by Plug and Play ACPI machine# [ 13.776062] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] machine# [ 13.823598] Floppy drive(s): fd0 is 2.88M AMI BIOS machine# [ 13.838551] FDC 0 is a S82078B machine# [ 13.902465] nf_conntrack version 0.5.0 (8192 buckets, 32768 max) machine# [ 13.955581] Linux agpgart interface v0.103 machine# [ 14.128971] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 machine# [ 14.151927] systemd[1]: Found device Virtio network device. machine# [ 14.186439] mtp-probe[587]: checking bus 1, device 2: "/sys/devices/pci0000:00/0000:00:01.2/usb1/1-1" machine# [ 14.194359] mtp-probe[587]: bus: 1, device: 2 was not an MTP device machine# [ 14.208844] systemd[1]: Started Firewall. machine# [ 14.231932] systemd[1]: Reached target Network (Pre). machine# [ 14.275224] mousedev: PS/2 mouse device common for all mice machine# [ 14.372420] systemd[1]: Found device /dev/ttyS0. Tesseract Open Source OCR Engine v3.04.01 with Leptonica machine# [ 14.411955] [drm] Initialized machine# [ 14.437311] systemd[1]: Found device /dev/hvc0. machine# [ 14.498565] [drm] Found bochs VGA, ID 0xb0c0. machine# [ 14.499527] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. machine# [ 14.509353] [TTM] Zone kernel: Available graphics memory: 511064 kiB machine# [ 14.510521] [TTM] Initializing pool allocator machine# [ 14.515080] [TTM] Initializing DMA pool allocator machine# [ 14.566440] fbcon: bochsdrmfb (fb0) is primary device machine# [ 14.585822] ppdev: user-space parallel port driver machine# [ 14.620798] Console: switching to colour frame buffer device 128x48 machine# [ 14.655887] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device machine# [ 14.659918] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0 machine# [ 15.033577] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 machine# [ 15.066238] hrtimer: interrupt took 4720991 ns machine# [ 15.184639] systemd[1]: Started udev Wait for Complete Device Initialization. machine# [ 15.188800] systemd[1]: Reached target System Initialization. machine# [ 15.243078] systemd[1]: Listening on Nix Daemon Socket. machine# [ 15.291532] systemd[1]: Started Daily Cleanup of Temporary Directories. machine# [ 15.348388] network-link-eth1-start[614]: Configuring link... machine# [ 15.430108] systemd[1]: Reached target Timers. machine# [ 15.452966] network-addresses-eth1-start[616]: bringing up interface... machine# [ 15.463804] network-addresses-eth1-start[616]: checking ip 192.168.1.1/24... machine# [ 15.486105] network-addresses-eth1-start[616]: added ip 192.168.1.1/24... machine# [ 15.532518] systemd[1]: Listening on D-Bus System Message Bus Socket. machine# connecting to host... machine# [ 15.579596] nscd[653]: 653 monitoring file `/etc/passwd` (1) machine# [ 15.622471] systemd[1]: Reached target Sockets. machine# [ 15.705527] nscd[653]: 653 monitoring directory `/etc` (2) machine# [ 15.732562] systemd[1]: Reached target Basic System. machine# sh: cannot set terminal process group (-1): Inappropriate ioctl for device machine# sh: no job control in this shell machine# [ 15.756453] nscd[653]: 653 monitoring file `/etc/group` (3) machine# [ 15.784115] systemd[1]: Started backdoor.service. machine# [ 15.793788] nscd[653]: 653 monitoring directory `/etc` (2) machine# [ 15.813803] 6jh2rcl9gknzawfz4yx7xmc4zr0nsf10-audit-disable[624]: No rules machine# [ 15.822553] systemd[1]: Starting Link configuration of eth1... machine# [ 15.831283] nscd[653]: 653 monitoring file `/etc/hosts` (4) machine# [ 15.847054] systemd[1]: Starting Address configuration of eth1... machine# [ 15.855824] nscd[653]: 653 monitoring directory `/etc` (2) machine# [ 15.874637] systemd[1]: Starting Store Sound Card State... machine# [ 15.882682] nscd[653]: 653 monitoring file `/etc/resolv.conf` (5) machine# [ 15.894677] systemd[1]: Starting Name Service Cache Daemon... machine# [ 15.907529] nscd[653]: 653 monitoring directory `/etc` (2) machine# [ 15.918415] systemd[1]: Starting Kernel Auditing... machine# [ 15.925625] dbus-daemon[675]: Unknown username "avahi" in message bus configuration file machine# [ 15.932504] dbus-daemon[675]: Unknown group "netdev" in message bus configuration file machine# [ 15.947661] systemd[1]: Starting Setup Virtual Console... machine# [ 15.957708] systemd[1]: Started Link configuration of eth1. machine# [ 15.964433] systemd[1]: Started Address configuration of eth1. machine# [ 15.972472] systemd[1]: Started Store Sound Card State. machine# [ 15.976892] systemd[1]: Started Kernel Auditing. machine# [ 15.985890] systemd[1]: Starting Networking Setup... machine# [ 15.993104] systemd[1]: Reached target All Network Interfaces (deprecated). machine# [ 16.001320] systemd[1]: Started Name Service Cache Daemon. machine# [ 16.005583] systemd[1]: Reached target User and Group Name Lookups. machine# [ 16.009525] systemd[1]: Starting Login Service... machine# [ 16.013408] systemd[1]: Reached target Host and Network Name Lookups. machine# [ 16.017743] systemd[1]: Started D-Bus System Message Bus. machine# [ 16.133112] systemd[1]: Started Setup Virtual Console. machine# [ 16.136269] systemd[1]: Started Networking Setup. machine# [ 16.142476] systemd-logind[671]: New seat seat0. machine# [ 16.150577] systemd-logind[671]: Watching system buttons on /dev/input/event2 (Power Button) machine# [ 16.167368] systemd[1]: Starting Extra networking commands.... machine# [ 16.172810] systemd[1]: Started Login Service. machine# [ 16.201852] systemd[1]: Starting X11 Server... machine# [ 16.229883] systemd[1]: Started Extra networking commands.. machine# [ 16.302259] systemd[1]: Reached target Network. machine# [ 16.312671] dhcpcd[717]: dev: loaded udev machine# [ 16.317976] systemd[1]: Starting DHCP Client... machine# [ 16.329697] systemd[1]: Starting Permit User Sessions... machine# [ 16.345985] systemd[1]: Started X11 Server. machine# [ 16.360590] systemd[1]: Started Permit User Sessions. machine# [ 16.390751] systemd[1]: Started Getty on tty1. machine# [ 16.403594] systemd[1]: Reached target Login Prompts. machine# [ 16.406920] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. machine# [ 16.657064] dhcpcd[717]: eth0: waiting for carrier machine# [ 16.660299] dhcpcd[717]: eth0: carrier acquired machine# [ 16.698712] dhcpcd[717]: DUID 00:01:00:01:20:74:5b:61:52:54:00:12:34:56 machine# [ 16.706366] dhcpcd[717]: eth0: IAID 00:12:34:56 machine# [ 16.712431] dhcpcd[717]: eth0: adding address fe80::5054:ff:fe12:3456 machine# [ 16.856394] dhcpcd[717]: eth0: soliciting an IPv6 router machine: performing optical character recognition machine: sending monitor command: screendump /tmp/nix-build-vm-test-run-plasma5.drv-0/ocrin.ppm machine# [ 17.323314] sddm[722]: Initializing... machine# [ 17.353920] sddm[722]: Starting... machine# [ 17.357806] sddm[722]: Adding new display on vt 7 ... machine# [ 17.389511] sddm[722]: Display server starting... machine# [ 17.391488] sddm[722]: Running: /nix/store/qi8jl5vcihijgy9vq252xlbwp25gh6s4-xserver-wrapper -nolisten tcp -auth /var/run/sddm/{b78486fe-a087-4b5e-bf58-50364c100dff} -background none -noreset -displayfd 18 vt7 machine# [ 17.492670] NET: Registered protocol family 17 machine# [ 17.497730] dhcpcd[717]: eth0: soliciting a DHCP lease machine# [ 17.500629] dhcpcd[717]: eth0: offered 10.0.2.15 from 10.0.2.2 machine# [ 17.505891] dhcpcd[717]: eth0: leased 10.0.2.15 for 86400 seconds machine# [ 17.516714] dhcpcd[717]: eth0: adding route to 10.0.2.0/24 machine# [ 17.527505] dhcpcd[717]: eth0: adding default route via 10.0.2.2 machine# [ 17.743323] nscd[653]: 653 monitored file `/etc/resolv.conf` was written to machine# [ 17.815690] systemd[1]: Stopping Name Service Cache Daemon... machine# [ 17.833854] systemd[1]: Stopped Name Service Cache Daemon. machine# [ 17.872500] systemd[1]: Starting Name Service Cache Daemon... machine# [ 17.936777] X[741]: (--) Log file renamed from "/dev/null" to "/dev/null" machine# [ 17.954809] X[741]: X.Org X Server 1.19.3 machine# [ 17.980662] X[741]: Release Date: 2017-03-15 machine# [ 17.999886] X[741]: X Protocol Version 11, Revision 0 machine# [ 18.119836] X[741]: Build Operating System: Linux 4.9.19 x86_64 machine# [ 18.148394] X[741]: Current Operating System: Linux machine 4.9.20 #1-NixOS SMP Fri Mar 31 08:32:02 UTC 2017 x86_64 machine# [ 18.164680] X[741]: Kernel command line: loglevel=7 console=ttyS0 panic=1 boot.panic_on_fail init=/nix/store/kai1rnwk1mrcnli6k9bm3dh8khaa9p41-nixos-system-machine-17.09pre-git/init regInfo=/nix/store/h7b8v6cw6gyb5j3a5yycrxsfvz053i4c-reginfo machine# [ 18.178794] X[741]: Build Date: 02 April 2017 03:10:18PM machine# [ 18.185650] X[741]: machine# [ 18.190438] X[741]: Current version of pixman: 0.34.0 machine# [ 18.194674] X[741]: Before reporting problems, check http://wiki.x.org machine# [ 18.200747] X[741]: to make sure that you have the latest version. machine# [ 18.205252] X[741]: Markers: (--) probed, (**) from config file, (==) default setting, machine# [ 18.210928] X[741]: (++) from command line, (!!) notice, (II) informational, machine# [ 18.215703] X[741]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. machine# [ 18.220426] X[741]: (++) Log file: "/dev/null", Time: Mon Apr 3 01:01:23 2017 machine# [ 18.226815] systemd[1]: Started DHCP Client. machine# [ 18.232113] dhcpcd[717]: Failed to try-restart ntpd.service: Unit ntpd.service not found. machine# [ 18.237686] nscd[793]: 793 monitoring file `/etc/passwd` (1) machine# [ 18.246326] X[741]: (++) Using config file: "/nix/store/yl246jl54sghw5831cjxg06260mjg6y1-xserver.conf" machine# [ 18.253913] X[741]: (==) Using config directory: "/etc/X11/xorg.conf.d" machine# [ 18.259452] X[741]: (==) Using system config directory "/nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/share/X11/xorg.conf.d" machine# [ 18.264349] X[741]: (==) ServerLayout "Layout[all]" machine# [ 18.267933] X[741]: (**) |-->Screen "Screen-modesetting[0]" (0) machine# [ 18.272819] X[741]: (**) | |-->Monitor "Monitor[0]" machine# [ 18.276952] X[741]: (**) | |-->Device "Device-modesetting[0]" machine# [ 18.281398] X[741]: (**) Option "DontZap" "on" machine# [ 18.286512] X[741]: (**) Option "AllowMouseOpenFail" "on" machine# [ 18.290957] X[741]: (==) Automatically adding devices machine# [ 18.295623] X[741]: (==) Automatically enabling devices machine# [ 18.299860] X[741]: (==) Automatically adding GPU devices machine# [ 18.304480] X[741]: (==) Max clients allowed: 256, resource mask: 0x1fffff machine# [ 18.309543] X[741]: (**) FontPath set to: machine# [ 18.313482] X[741]: /nix/store/gyaxiski6p2yl2x4qp2f6xvw84h882v2-font-bh-lucidatypewriter-100dpi-1.0.3/lib/X11/fonts/100dpi, machine# [ 18.318697] X[741]: /nix/store/pc4v8i63b6373lr2jv47b3cvi8wdvb6x-font-bh-lucidatypewriter-75dpi-1.0.3/lib/X11/fonts/75dpi, machine# [ 18.323526] X[741]: /nix/store/b0m03aa45pfin8im013a4kzslsgpvi37-font-bh-100dpi-1.0.3/lib/X11/fonts/100dpi, machine# [ 18.328486] X[741]: /nix/store/d8ag7w9yh5838dafaw5jpdfrd5vawbgz-font-misc-misc-1.1.2/lib/X11/fonts/misc, machine# [ 18.333509] X[741]: /nix/store/gs41v99vq5wg3ix1jchc4b95hvgjh8sy-font-cursor-misc-1.0.3/lib/X11/fonts/misc, machine# [ 18.340331] X[741]: /nix/store/wbx7kcg9q76h20233s095sxa2l9gdvlv-unifont-9.0.06/share/fonts, machine# [ 18.345436] X[741]: /nix/store/gb0s3gnk2aahj01v2mk4rmskar1wdmj7-font-adobe-100dpi-1.0.3/lib/X11/fonts/100dpi, machine# [ 18.349852] X[741]: /nix/store/ba9993qa9w1i029x43cdykqhygbfhkwq-font-adobe-75dpi-1.0.3/lib/X11/fonts/75dpi machine# [ 18.353841] X[741]: (**) ModulePath set to "/nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/lib/xorg/modules,/nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/lib/xorg/modules/extensions,/nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/lib/xorg/modules/drivers,/nix/store/3g51y2a734wz4ii6gh9l97a493rcia0i-xf86-input-evdev-2.10.5/lib/xorg/modules/input" machine# [ 18.363971] X[741]: (II) The server relies on udev to provide the list of input devices. machine# [ 18.368885] X[741]: If no devices become available, reconfigure udev or disable AutoAddDevices. machine# [ 18.376658] X[741]: (II) Loader magic: 0x81ad60 machine# [ 18.380796] X[741]: (II) Module ABI versions: machine# [ 18.387787] X[741]: X.Org ANSI C Emulation: 0.4 machine# [ 18.391897] X[741]: X.Org Video Driver: 23.0 machine# [ 18.396650] X[741]: X.Org XInput driver : 24.1 machine# [ 18.422838] systemd[1]: Reached target Network is Online. machine# [ 18.427579] dhcpcd[717]: Failed to try-restart openntpd.service: Unit openntpd.service not found. machine# [ 18.429524] nscd[793]: 793 monitoring directory `/etc` (2) machine# [ 18.431321] X[741]: X.Org Server Extension : 10.0 machine# [ 18.432914] X[741]: (++) using VT number 7 machine# [ 18.436797] X[741]: (II) systemd-logind: logind integration requires -keeptty and -keeptty was not provided, disabling logind integration machine# [ 18.441549] X[741]: (II) xfree86: Adding drm device (/dev/dri/card0) machine# [ 18.444749] X[741]: (--) PCI:*(0:0:2:0) 1234:1111:1af4:1100 rev 2, Mem @ 0xfd000000/16777216, 0xfebd0000/4096, BIOS @ 0x????????/131072 machine# [ 18.449909] X[741]: (WW) Open ACPI failed (/var/run/acpid.socket) (No such file or directory) machine# [ 18.454374] X[741]: (II) "glx" will be loaded by default. machine# [ 18.457548] X[741]: (II) LoadModule: "glx" machine# [ 18.461816] X[741]: (II) Loading /nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/lib/xorg/modules/extensions/libglx.so machine# [ 18.467888] X[741]: (II) Module glx: vendor="X.Org Foundation" machine# [ 18.472667] X[741]: compiled for 1.19.3, module version = 1.0.0 machine# [ 18.477619] X[741]: ABI class: X.Org Server Extension, version 10.0 machine# [ 18.481920] X[741]: (II) LoadModule: "modesetting" machine# [ 18.487311] X[741]: (II) Loading /nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/lib/xorg/modules/drivers/modesetting_drv.so machine# [ 18.494260] X[741]: (II) Module modesetting: vendor="X.Org Foundation" machine# [ 18.498393] X[741]: compiled for 1.19.3, module version = 1.19.3 machine# [ 18.504467] X[741]: Module class: X.Org Video Driver machine# [ 18.510606] X[741]: ABI class: X.Org Video Driver, version 23.0 machine# [ 18.516704] X[741]: (II) modesetting: Driver for Modesetting Kernel Drivers: kms machine# [ 18.522683] X[741]: (II) modeset(0): using drv /dev/dri/card0 machine# [ 18.529391] X[741]: (==) modeset(0): Depth 24, (==) framebuffer bpp 32 machine# [ 18.534406] X[741]: (==) modeset(0): RGB weight 888 machine# [ 18.538802] X[741]: (==) modeset(0): Default visual is TrueColor machine# [ 18.544262] X[741]: (II) Loading sub module "glamoregl" machine# [ 18.548884] X[741]: (II) LoadModule: "glamoregl" machine# [ 18.552778] X[741]: (II) Loading /nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/lib/xorg/modules/libglamoregl.so machine# [ 18.557855] systemd[1]: Reached target Multi-User System. machine# [ 18.559842] nscd[793]: 793 monitoring file `/etc/group` (3) machine# [ 18.566683] systemd[1]: Reached target Graphical Interface. machine# [ 18.572428] nscd[793]: 793 monitoring directory `/etc` (2) machine# [ 18.577837] systemd[1]: Started Name Service Cache Daemon. machine# [ 18.586438] nscd[793]: 793 monitoring file `/etc/hosts` (4) machine# [ 18.591599] systemd[1]: Startup finished in 10.635s (kernel) + 7.500s (userspace) = 18.135s. machine# [ 18.594740] nscd[793]: 793 monitoring directory `/etc` (2) machine# [ 18.597268] nscd[793]: 793 monitoring file `/etc/resolv.conf` (5) machine# [ 18.599396] nscd[793]: 793 monitoring directory `/etc` (2) machine# [ 18.601535] dhcpcd[717]: script_runreason: /nix/store/dy83r7f5dnih8ycwlwk475k9lrbxdqc3-dhcpcd-6.11.5/libexec/dhcpcd-run-hooks: WEXITSTATUS 5 machine# [ 18.621931] dhcpcd[803]: eth0: Router Advertisement from fe80::2 machine# [ 18.623584] dhcpcd[803]: eth0: adding address fec0::5054:ff:fe12:3456/64 machine# [ 18.625777] dhcpcd[717]: forked to background, child pid 803 machine# [ 18.675328] X[741]: (II) Module glamoregl: vendor="X.Org Foundation" machine# [ 18.679451] X[741]: compiled for 1.19.3, module version = 1.0.0 machine# [ 18.682949] X[741]: ABI class: X.Org ANSI C Emulation, version 0.4 machine# [ 18.688428] X[741]: (II) glamor: OpenGL accelerated X.org driver based. machine# [ 18.693676] X[741]: pci id for fd 24: 1234:1111, driver (null) machine# [ 19.646419] nscd[793]: 793 monitored file `/etc/resolv.conf` was written to machine# [ 19.669673] systemd[1]: Stopping Name Service Cache Daemon... machine# [ 19.674970] systemd[1]: Stopped Name Service Cache Daemon. machine# [ 19.691596] systemd[1]: Starting Name Service Cache Daemon... machine# [ 19.804668] nscd[856]: 856 monitoring file `/etc/passwd` (1) machine# [ 19.819414] nscd[856]: 856 monitoring directory `/etc` (2) machine# [ 19.857735] nscd[856]: 856 monitoring file `/etc/group` (3) machine# [ 19.865253] nscd[856]: 856 monitoring directory `/etc` (2) machine# [ 19.874330] nscd[856]: 856 monitoring file `/etc/hosts` (4) machine# [ 19.886807] nscd[856]: 856 monitoring directory `/etc` (2) machine# [ 19.890583] nscd[856]: 856 monitoring file `/etc/resolv.conf` (5) machine# [ 19.895825] nscd[856]: 856 monitoring directory `/etc` (2) machine# [ 19.908863] systemd[1]: Started Name Service Cache Daemon. machine# [ 21.173285] X[741]: (II) glamor: EGL version 1.4 (DRI2): machine# [ 21.174934] X[741]: EGL_MESA_drm_image required. machine# [ 21.188663] X[741]: (EE) modeset(0): glamor initialization failed machine# [ 21.190485] X[741]: (II) modeset(0): ShadowFB: preferred NO, enabled NO machine# [ 21.191871] X[741]: (II) modeset(0): Output Virtual-1 using monitor section Monitor[0] machine# [ 21.201186] X[741]: (II) modeset(0): EDID for output Virtual-1 machine# [ 21.206318] X[741]: (II) modeset(0): Printing probed modes for output Virtual-1 machine# [ 21.210758] X[741]: (II) modeset(0): Modeline "1024x768"x60.0 65.00 1024 1048 1184 1344 768 771 777 806 -hsync -vsync (48.4 kHz UeP) machine# [ 21.214374] X[741]: (II) modeset(0): Modeline "1920x1080"x60.0 148.50 1920 2008 2052 2200 1080 1084 1089 1125 -hsync -vsync (67.5 kHz e) machine# [ 21.217566] X[741]: (II) modeset(0): Modeline "1600x1200"x60.0 162.00 1600 1664 1856 2160 1200 1201 1204 1250 +hsync +vsync (75.0 kHz e) machine# [ 21.222380] X[741]: (II) modeset(0): Modeline "1680x1050"x60.0 146.25 1680 1784 1960 2240 1050 1053 1059 1089 -hsync +vsync (65.3 kHz e) machine# [ 21.227260] X[741]: (II) modeset(0): Modeline "1680x1050"x59.9 119.00 1680 1728 1760 1840 1050 1053 1059 1080 +hsync -vsync (64.7 kHz e) machine# [ 21.232337] X[741]: (II) modeset(0): Modeline "1400x1050"x60.0 121.75 1400 1488 1632 1864 1050 1053 1057 1089 -hsync +vsync (65.3 kHz e) machine# [ 21.238270] X[741]: (II) modeset(0): Modeline "1400x1050"x59.9 101.00 1400 1448 1480 1560 1050 1053 1057 1080 +hsync -vsync (64.7 kHz e) machine# [ 21.243342] X[741]: (II) modeset(0): Modeline "1600x900"x60.0 108.00 1600 1624 1704 1800 900 901 904 1000 +hsync +vsync (60.0 kHz e) machine# [ 21.248615] X[741]: (II) modeset(0): Modeline "1280x1024"x60.0 108.00 1280 1328 1440 1688 1024 1025 1028 1066 +hsync +vsync (64.0 kHz e) machine# [ 21.253746] X[741]: (II) modeset(0): Modeline "1440x900"x59.9 106.50 1440 1520 1672 1904 900 903 909 934 -hsync +vsync (55.9 kHz e) machine# [ 21.257707] X[741]: (II) modeset(0): Modeline "1440x900"x59.9 88.75 1440 1488 1520 1600 900 903 909 926 +hsync -vsync (55.5 kHz e) machine# [ 21.260903] X[741]: (II) modeset(0): Modeline "1280x960"x60.0 108.00 1280 1376 1488 1800 960 961 964 1000 +hsync +vsync (60.0 kHz e) machine# [ 21.264566] X[741]: (II) modeset(0): Modeline "1366x768"x59.8 85.50 1366 1436 1579 1792 768 771 774 798 +hsync +vsync (47.7 kHz e) machine# [ 21.268749] X[741]: (II) modeset(0): Modeline "1366x768"x60.0 72.00 1366 1380 1436 1500 768 769 772 800 +hsync +vsync (48.0 kHz e) machine# [ 21.273864] X[741]: (II) modeset(0): Modeline "1360x768"x60.0 85.50 1360 1424 1536 1792 768 771 777 795 +hsync +vsync (47.7 kHz e) machine# [ 21.277659] X[741]: (II) modeset(0): Modeline "1280x800"x59.8 83.50 1280 1352 1480 1680 800 803 809 831 -hsync +vsync (49.7 kHz e) machine# [ 21.284545] X[741]: (II) modeset(0): Modeline "1280x800"x59.9 71.00 1280 1328 1360 1440 800 803 809 823 +hsync -vsync (49.3 kHz e) machine# [ 21.287603] X[741]: (II) modeset(0): Modeline "1280x768"x59.9 79.50 1280 1344 1472 1664 768 771 778 798 -hsync +vsync (47.8 kHz e) machine# [ 21.290977] X[741]: (II) modeset(0): Modeline "1280x768"x60.0 68.25 1280 1328 1360 1440 768 771 778 790 +hsync -vsync (47.4 kHz e) machine# [ 21.294761] X[741]: (II) modeset(0): Modeline "1280x720"x60.0 74.25 1280 1390 1430 1650 720 725 730 750 +hsync +vsync (45.0 kHz e) machine# [ 21.297975] X[741]: (II) modeset(0): Modeline "800x600"x60.3 40.00 800 840 968 1056 600 601 605 628 +hsync +vsync (37.9 kHz e) machine# [ 21.301226] X[741]: (II) modeset(0): Modeline "800x600"x56.2 36.00 800 824 896 1024 600 601 603 625 +hsync +vsync (35.2 kHz e) machine# [ 21.304008] X[741]: (II) modeset(0): Modeline "848x480"x60.0 33.75 848 864 976 1088 480 486 494 517 +hsync +vsync (31.0 kHz e) machine# [ 21.313323] X[741]: (II) modeset(0): Modeline "640x480"x59.9 25.18 640 656 752 800 480 490 492 525 -hsync -vsync (31.5 kHz e) machine# [ 21.315568] X[741]: (II) modeset(0): Output Virtual-1 connected machine# [ 21.317911] X[741]: (II) modeset(0): Using user preference for initial modes machine# [ 21.321443] X[741]: (II) modeset(0): Output Virtual-1 using initial mode 1024x768 +0+0 machine# [ 21.323591] X[741]: (==) modeset(0): Using gamma correction (1.0, 1.0, 1.0) machine# [ 21.327749] X[741]: (==) modeset(0): DPI set to (96, 96) machine# [ 21.331442] X[741]: (II) Loading sub module "fb" machine# [ 21.334222] X[741]: (II) LoadModule: "fb" machine# [ 21.347060] X[741]: (II) Loading /nix/store/5z03lxr2y29qjf4bjs56w502h0cyhhwp-xorg-server-1.19.3/lib/xorg/modules/libfb.so machine# [ 21.348743] X[741]: (II) Module fb: vendor="X.Org Foundation" machine# [ 21.349762] X[741]: compiled for 1.19.3, module version = 1.0.0 machine# [ 21.350897] X[741]: ABI class: X.Org ANSI C Emulation, version 0.4 machine# [ 21.351985] X[741]: (==) Depth 24 pixmap format is 32 bpp machine# [ 21.352959] X[741]: (==) modeset(0): Backing store enabled machine# [ 21.354189] X[741]: (==) modeset(0): Silken mouse enabled machine# [ 21.356246] X[741]: (II) modeset(0): RandR 1.2 enabled, ignore the following RandR disabled message. machine# [ 21.360566] X[741]: (==) modeset(0): DPMS enabled machine# [ 21.361897] X[741]: (--) RandR disabled machine# [ 21.365715] X[741]: (II) AIGLX: Screen 0 is not DRI2 capable machine# [ 21.371576] X[741]: (EE) AIGLX: reverting to software rendering machine# [ 21.375398] X[741]: (II) IGLX: enabled GLX_MESA_copy_sub_buffer machine# [ 21.378588] X[741]: (II) IGLX: Loaded and initialized swrast machine# [ 21.381908] X[741]: (II) GLX: Initialized DRISWRAST GL provider for screen 0 machine# [ 21.384754] X[741]: (II) modeset(0): Damage tracking initialized machine# [ 21.387567] X[741]: (II) modeset(0): Setting screen physical size to 270 x 203 machine# [ 21.837569] X[741]: (II) config/udev: Adding input device Power Button (/dev/input/event2) machine# [ 21.840917] X[741]: (**) Power Button: Applying InputClass "evdev keyboard catchall" machine# [ 21.847506] X[741]: (**) Power Button: Applying InputClass "Keyboard catchall" machine# [ 21.851955] X[741]: (II) LoadModule: "evdev" machine# [ 21.906228] X[741]: (II) Loading /nix/store/3g51y2a734wz4ii6gh9l97a493rcia0i-xf86-input-evdev-2.10.5/lib/xorg/modules/input/evdev_drv.so machine# [ 21.953472] X[741]: (II) Module evdev: vendor="X.Org Foundation" machine# [ 21.956448] X[741]: compiled for 1.19.3, module version = 2.10.5 machine# [ 21.959573] X[741]: Module class: X.Org XInput Driver machine# [ 21.966547] X[741]: ABI class: X.Org XInput driver, version 24.1 machine# [ 21.970722] X[741]: (II) Using input driver 'evdev' for 'Power Button' machine# [ 21.977504] X[741]: (**) Power Button: always reports core events machine# [ 21.981643] X[741]: (**) evdev: Power Button: Device: "/dev/input/event2" machine# [ 21.985442] X[741]: (--) evdev: Power Button: Vendor 0 Product 0x1 machine# [ 21.990567] X[741]: (--) evdev: Power Button: Found keys machine# [ 22.009932] X[741]: (II) evdev: Power Button: Configuring as keyboard machine# [ 22.012088] X[741]: (**) Option "config_info" "udev:/sys/devices/LNXSYSTM:00/LNXPWRBN:00/input/input3/event2" machine# [ 22.014485] X[741]: (II) XINPUT: Adding extended input device "Power Button" (type: KEYBOARD, id 6) machine# [ 22.016538] X[741]: (**) Option "xkb_rules" "evdev" machine# [ 22.017769] X[741]: (**) Option "xkb_model" "pc104" machine# [ 22.019347] X[741]: (**) Option "xkb_layout" "us" machine# [ 22.020585] X[741]: (WW) Option "xkb_variant" requires a string value machine# [ 22.022115] X[741]: (**) Option "xkb_options" "terminate:ctrl_alt_bksp" machine# [ 22.161347] X[741]: (II) config/udev: Adding input device QEMU QEMU USB Tablet (/dev/input/event1) machine# [ 22.163637] X[741]: (**) QEMU QEMU USB Tablet: Applying InputClass "evdev pointer catchall" machine# [ 22.165642] X[741]: (II) Using input driver 'evdev' for 'QEMU QEMU USB Tablet' machine# [ 22.167671] X[741]: (**) QEMU QEMU USB Tablet: always reports core events machine# [ 22.169071] X[741]: (**) evdev: QEMU QEMU USB Tablet: Device: "/dev/input/event1" machine# [ 22.219376] X[741]: (--) evdev: QEMU QEMU USB Tablet: Vendor 0x627 Product 0x1 machine# [ 22.225919] X[741]: (--) evdev: QEMU QEMU USB Tablet: Found 3 mouse buttons machine# [ 22.232702] X[741]: (--) evdev: QEMU QEMU USB Tablet: Found scroll wheel(s) machine# [ 22.240816] X[741]: (--) evdev: QEMU QEMU USB Tablet: Found relative axes machine# [ 22.245414] X[741]: (--) evdev: QEMU QEMU USB Tablet: Found absolute axes machine# [ 22.255441] X[741]: (--) evdev: QEMU QEMU USB Tablet: Found x and y absolute axes machine# [ 22.268452] X[741]: (--) evdev: QEMU QEMU USB Tablet: Found absolute touchscreen machine# [ 22.273971] X[741]: (II) evdev: QEMU QEMU USB Tablet: Configuring as touchscreen machine# [ 22.279718] X[741]: (II) evdev: QEMU QEMU USB Tablet: Adding scrollwheel support machine# [ 22.288911] X[741]: (**) evdev: QEMU QEMU USB Tablet: YAxisMapping: buttons 4 and 5 machine# [ 22.294693] X[741]: (**) evdev: QEMU QEMU USB Tablet: EmulateWheelButton: 4, EmulateWheelInertia: 10, EmulateWheelTimeout: 200 machine# [ 22.304805] X[741]: (**) Option "config_info" "udev:/sys/devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2/event1" machine# [ 22.313664] X[741]: (II) XINPUT: Adding extended input device "QEMU QEMU USB Tablet" (type: TOUCHSCREEN, id 7) machine# [ 22.318592] X[741]: (WW) evdev: QEMU QEMU USB Tablet: touchpads, tablets and touchscreens ignore relative axes. machine# [ 22.323692] X[741]: (II) evdev: QEMU QEMU USB Tablet: initialized for absolute axes. machine# [ 22.330377] X[741]: (**) QEMU QEMU USB Tablet: (accel) keeping acceleration scheme 1 machine# [ 22.335374] X[741]: (**) QEMU QEMU USB Tablet: (accel) acceleration profile 0 machine# [ 22.341044] X[741]: (**) QEMU QEMU USB Tablet: (accel) acceleration factor: 2.000 machine# [ 22.347652] X[741]: (**) QEMU QEMU USB Tablet: (accel) acceleration threshold: 4 machine# [ 22.353850] X[741]: (II) config/udev: Adding input device QEMU QEMU USB Tablet (/dev/input/js0) machine# [ 22.361611] X[741]: (II) No input driver specified, ignoring this device. machine# [ 22.367425] X[741]: (II) This device may have been added with another device file. machine# [ 22.373686] X[741]: (II) config/udev: Adding input device QEMU QEMU USB Tablet (/dev/input/mouse0) machine# [ 22.379963] X[741]: (II) No input driver specified, ignoring this device. machine# [ 22.386815] X[741]: (II) This device may have been added with another device file. machine# [ 22.396611] X[741]: (II) config/udev: Adding input device AT Translated Set 2 keyboard (/dev/input/event0) machine# [ 22.400567] X[741]: (**) AT Translated Set 2 keyboard: Applying InputClass "evdev keyboard catchall" machine# [ 22.404263] X[741]: (**) AT Translated Set 2 keyboard: Applying InputClass "Keyboard catchall" machine# [ 22.407650] X[741]: (II) Using input driver 'evdev' for 'AT Translated Set 2 keyboard' machine# [ 22.411750] X[741]: (**) AT Translated Set 2 keyboard: always reports core events machine# [ 22.415854] X[741]: (**) evdev: AT Translated Set 2 keyboard: Device: "/dev/input/event0" machine# [ 22.420904] X[741]: (--) evdev: AT Translated Set 2 keyboard: Vendor 0x1 Product 0x1 machine# [ 22.425535] X[741]: (--) evdev: AT Translated Set 2 keyboard: Found keys machine# [ 22.430342] X[741]: (II) evdev: AT Translated Set 2 keyboard: Configuring as keyboard machine# [ 22.434697] X[741]: (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio0/input/input0/event0" machine# [ 22.438697] X[741]: (II) XINPUT: Adding extended input device "AT Translated Set 2 keyboard" (type: KEYBOARD, id 8) machine# [ 22.442794] X[741]: (**) Option "xkb_rules" "evdev" machine# [ 22.445841] X[741]: (**) Option "xkb_model" "pc104" machine# [ 22.448336] X[741]: (**) Option "xkb_layout" "us" machine# [ 22.453628] X[741]: (WW) Option "xkb_variant" requires a string value machine# [ 22.456374] X[741]: (**) Option "xkb_options" "terminate:ctrl_alt_bksp" machine# [ 22.457448] X[741]: (II) config/udev: Adding input device ImExPS/2 Generic Explorer Mouse (/dev/input/event3) machine# [ 22.459967] X[741]: (**) ImExPS/2 Generic Explorer Mouse: Applying InputClass "evdev pointer catchall" machine# [ 22.463242] X[741]: (II) Using input driver 'evdev' for 'ImExPS/2 Generic Explorer Mouse' machine# [ 22.465232] X[741]: (**) ImExPS/2 Generic Explorer Mouse: always reports core events machine# [ 22.468244] X[741]: (**) evdev: ImExPS/2 Generic Explorer Mouse: Device: "/dev/input/event3" machine# [ 22.470236] X[741]: (--) evdev: ImExPS/2 Generic Explorer Mouse: Vendor 0x2 Product 0x6 machine# [ 22.472258] X[741]: (--) evdev: ImExPS/2 Generic Explorer Mouse: Found 9 mouse buttons machine# [ 22.475339] X[741]: (--) evdev: ImExPS/2 Generic Explorer Mouse: Found scroll wheel(s) machine# [ 22.477235] X[741]: (--) evdev: ImExPS/2 Generic Explorer Mouse: Found relative axes machine# [ 22.480219] X[741]: (--) evdev: ImExPS/2 Generic Explorer Mouse: Found x and y relative axes machine# [ 22.482257] X[741]: (II) evdev: ImExPS/2 Generic Explorer Mouse: Configuring as mouse machine# [ 22.483771] X[741]: (II) evdev: ImExPS/2 Generic Explorer Mouse: Adding scrollwheel support machine# [ 22.486467] X[741]: (**) evdev: ImExPS/2 Generic Explorer Mouse: YAxisMapping: buttons 4 and 5 machine# [ 22.489712] sddm[722]: Running display setup script "/nix/store/k5d8sqb3rl7gxf809a0svidjhqn6xbbj-Xsetup" machine# [ 22.492627] X[741]: (**) evdev: ImExPS/2 Generic Explorer Mouse: EmulateWheelButton: 4, EmulateWheelInertia: 10, EmulateWheelTimeout: 200 machine# [ 22.494989] X[741]: (**) Option "config_info" "udev:/sys/devices/platform/i8042/serio1/input/input4/event3" machine# [ 22.497343] X[741]: (II) XINPUT: Adding extended input device "ImExPS/2 Generic Explorer Mouse" (type: MOUSE, id 9) machine# [ 22.499568] X[741]: (II) evdev: ImExPS/2 Generic Explorer Mouse: initialized for relative axes. machine# [ 22.502246] X[741]: (**) ImExPS/2 Generic Explorer Mouse: (accel) keeping acceleration scheme 1 machine# [ 22.503969] X[741]: (**) ImExPS/2 Generic Explorer Mouse: (accel) acceleration profile 0 machine# [ 22.505952] X[741]: (**) ImExPS/2 Generic Explorer Mouse: (accel) acceleration factor: 2.000 machine# [ 22.507879] X[741]: (**) ImExPS/2 Generic Explorer Mouse: (accel) acceleration threshold: 4 machine# [ 22.509770] X[741]: (II) config/udev: Adding input device ImExPS/2 Generic Explorer Mouse (/dev/input/mouse1) machine# [ 22.511695] X[741]: (II) No input driver specified, ignoring this device. machine# [ 22.512652] X[741]: (II) This device may have been added with another device file. machine# [ 22.513873] sddm[722]: Display server started. machine# [ 22.514953] sddm[722]: Socket server starting... machine# [ 22.515978] sddm[722]: Socket server started. machine# [ 22.516925] sddm[722]: Greeter starting... machine# [ 22.517924] sddm[722]: Adding cookie to "/var/run/sddm/{b78486fe-a087-4b5e-bf58-50364c100dff}" machine# [ 23.023291] sddm-helper[872]: [PAM] Starting... machine# [ 23.024831] sddm-helper[872]: [PAM] Authenticating... machine# [ 23.026878] sddm-helper[872]: [PAM] returning. machine# [ 23.055766] systemd[1]: Created slice User Slice of sddm. machine# [ 23.086270] systemd-logind[671]: New session c1 of user sddm. machine# [ 23.089704] systemd[1]: Started Session c1 of user sddm. machine# [ 23.092498] systemd[1]: Starting User Manager for UID 175... machine# [ 23.124100] systemd[875]: pam_unix(systemd-user:session): session opened for user sddm by (uid=0) machine# [ 23.493639] systemd[875]: Reached target Sockets. machine# [ 23.497116] systemd[875]: Reached target Timers. machine# [ 23.498671] systemd[875]: Reached target Paths. machine# [ 23.502641] systemd[875]: Reached target Basic System. machine# [ 23.515476] systemd[875]: Starting SSH Agent... machine# [ 23.635286] systemd[875]: Started SSH Agent. machine# [ 23.636831] systemd[875]: Reached target Default. machine# [ 23.647627] systemd[875]: Startup finished in 428ms. machine# [ 23.653298] systemd[1]: Started User Manager for UID 175. machine# [ 23.659497] sddm[722]: Greeter session started successfully Tesseract Open Source OCR Engine v3.04.01 with Leptonica machine# [ 25.554295] sddm-greeter[883]: Reading from "/nix/store/r72d4y8x7ladgyzq3sh3694ibbm07ywi-desktops/plasma5.desktop" machine# [ 25.561875] sddm-greeter[883]: Reading from "/nix/store/r72d4y8x7ladgyzq3sh3694ibbm07ywi-desktops/xterm.desktop" machine# [ 25.573514] sddm-greeter[883]: inotify_add_watch("/usr/share/wayland-sessions") failed: "No such file or directory" machine# [ 25.600745] sddm-greeter[883]: Connected to the daemon. machine# [ 25.609564] sddm[722]: Message received from greeter: Connect machine# [ 26.732310] sddm-greeter[883]: Loading file:///nix/store/36jjwsbpp0zwlxcgnsvnxnkmgd1wk0ps-sddm-0.14.0/share/sddm/themes/breeze/Main.qml... machine: performing optical character recognition machine: sending monitor command: screendump /tmp/nix-build-vm-test-run-plasma5.drv-0/ocrin.ppm machine# [ 30.404266] sddm-greeter[883]: QObject::installEventFilter(): Cannot filter events for objects in a different thread. Tesseract Open Source OCR Engine v3.04.01 with Leptonica machine: performing optical character recognition machine: sending monitor command: screendump /tmp/nix-build-vm-test-run-plasma5.drv-0/ocrin.ppm machine# [ 37.202111] sddm-greeter[883]: QObject::installEventFilter(): Cannot filter events for objects in a different thread. machine# [ 39.015479] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service' machine# [ 39.034274] systemd[1]: Starting Udisks2 service... machine# [ 39.427042] udisksd[898]: udisks daemon version 2.1.6 starting machine# [ 39.526690] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' machine# [ 39.557942] systemd[1]: Starting Authorization Manager... machine# [ 39.803506] polkitd[902]: Started polkitd version 0.113 machine# [ 40.019593] polkitd[902]: Loading rules from directory /etc/polkit-1/rules.d machine# [ 40.023460] polkitd[902]: Loading rules from directory /var/run/current-system/sw/share/polkit-1/rules.d machine# [ 40.041762] polkitd[902]: Finished loading, compiling and executing 1 rules machine# [ 40.046915] dbus[675]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' machine# [ 40.057961] systemd[1]: Started Authorization Manager. machine# [ 40.069482] polkitd[902]: Acquired the name org.freedesktop.PolicyKit1 on the system bus machine# [ 40.132904] dbus[675]: [system] Successfully activated service 'org.freedesktop.UDisks2' machine# [ 40.150708] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' machine# [ 40.166770] systemd[1]: Started Udisks2 service. machine# [ 40.173568] udisksd[898]: Acquired the name org.freedesktop.UDisks2 on the system message bus machine# [ 40.180550] systemd[1]: Starting Power Management Daemon... machine# [ 40.296572] dbus[675]: [system] Successfully activated service 'org.freedesktop.UPower' machine# [ 40.299951] systemd[1]: Started Power Management Daemon. machine# [ 41.045814] sddm-greeter[883]: Adding view for "Virtual-1" QRect(0,0 1024x768) machine# [ 41.129683] sddm-greeter[883]: QDBusConnection: name 'org.freedesktop.UDisks2' had owner '' but we thought it was ':1.6' machine# [ 41.226896] sddm-greeter[883]: Message received from daemon: Capabilities machine# [ 41.230327] sddm-greeter[883]: Message received from daemon: HostName machine# [ 43.646485] X[741]: (II) modeset(0): Disabling kernel dirty updates, not required. Tesseract Open Source OCR Engine v3.04.01 with Leptonica machine: performing optical character recognition machine: sending monitor command: screendump /tmp/nix-build-vm-test-run-plasma5.drv-0/ocrin.ppm Tesseract Open Source OCR Engine v3.04.01 with Leptonica machine: making screenshot ‘sddm.png’ machine: sending monitor command: screendump /nix/store/l243ccb7wd35yqb6pxghv0g2nd8wq2nz-vm-test-run-plasma5/sddm.png.ppm machine: sending keys ‘foobar ’ machine: sending monitor command: sendkey f machine: sending monitor command: sendkey o machine: sending monitor command: sendkey o machine: sending monitor command: sendkey b machine: sending monitor command: sendkey a machine: sending monitor command: sendkey r machine: sending monitor command: sendkey ret machine: waiting for file ‘/home/alice/.Xauthority’ machine: running command: test -e /home/alice/.Xauthority machine: waiting for the VM to finish booting machine: connected to guest root shell machine: exit status 1 machine# [ 56.079571] sddm-greeter[883]: Reading from "/nix/store/r72d4y8x7ladgyzq3sh3694ibbm07ywi-desktops/plasma5.desktop" machine# [ 56.092501] sddm[722]: Message received from greeter: Login machine# [ 56.100830] sddm[722]: Reading from "/nix/store/r72d4y8x7ladgyzq3sh3694ibbm07ywi-desktops/plasma5.desktop" machine# [ 56.113436] sddm[722]: Reading from "/nix/store/r72d4y8x7ladgyzq3sh3694ibbm07ywi-desktops/plasma5.desktop" machine# [ 56.121661] sddm[722]: Session "/nix/store/r72d4y8x7ladgyzq3sh3694ibbm07ywi-desktops/plasma5.desktop" selected, command: "/nix/store/i0x9mqayswj7jb69bg56jnv5506kaa6d-xsession 'plasma5'" machine# [ 56.308464] sddm-helper[923]: [PAM] Starting... machine# [ 56.314563] sddm-helper[923]: [PAM] Authenticating... machine# [ 56.323361] sddm-helper[923]: [PAM] Preparing to converse... machine# [ 56.326381] sddm-helper[923]: [PAM] Conversation with 1 messages machine# [ 56.354393] sddm-helper[923]: pam_kwallet5(sddm:auth): (null): pam_sm_authenticate machine# [ 56.541820] sddm-helper[923]: [PAM] returning. machine# [ 56.547807] sddm[722]: Authenticated successfully machine# [ 56.551542] sddm-greeter[883]: Message received from daemon: LoginSucceeded machine# [ 56.561428] sddm-helper[923]: pam_kwallet5(sddm:setcred): pam_kwallet5: pam_sm_setcred machine# [ 56.570660] sddm-helper[923]: pam_unix(sddm:session): session opened for user alice by (uid=0) machine# [ 56.619396] systemd[1]: Created slice User Slice of alice. machine# [ 56.658220] systemd[1]: Starting User Manager for UID 1000... machine# [ 56.735639] systemd-logind[671]: New session 2 of user alice. machine# [ 56.815737] systemd[924]: pam_unix(systemd-user:session): session opened for user alice by (uid=0) machine: running command: test -e /home/alice/.Xauthority machine: exit status 1 machine# [ 56.866888] systemd[1]: Started Session 2 of user alice. machine# [ 57.012577] systemd[924]: Reached target Sockets. machine# [ 57.233820] systemd[924]: Reached target Timers. machine# [ 57.277871] sddm-helper[923]: pam_kwallet5(sddm:session): pam_kwallet5: pam_sm_open_session machine# [ 57.289977] systemd[924]: Reached target Paths. machine# [ 57.313738] sddm-helper[923]: pam_kwallet5(sddm:session): pam_kwallet5: final socket path: /run/user/1000/kwallet5.socket machine# [ 57.333765] systemd[924]: Reached target Basic System. machine# [ 57.353626] systemd[924]: Starting SSH Agent... machine# [ 57.368784] sddm-helper[872]: [PAM] Closing session machine# [ 57.376870] systemd[924]: Started SSH Agent. machine# [ 57.405768] systemd[924]: Reached target Default. machine# [ 57.416530] systemd[924]: Startup finished in 323ms. machine# [ 57.425484] sddm-helper[872]: [PAM] Ended. machine# [ 57.436651] systemd[1]: Started User Manager for UID 1000. machine# [ 57.447943] sddm-helper[923]: Starting: "/nix/store/i0x9mqayswj7jb69bg56jnv5506kaa6d-xsession /nix/store/i0x9mqayswj7jb69bg56jnv5506kaa6d-xsession 'plasma5'" machine# [ 57.465764] sddm-helper[935]: Adding cookie to "/home/alice/.Xauthority" machine# [ 57.493538] sddm[722]: Auth: sddm-helper exited successfully machine# [ 57.511744] sddm[722]: Greeter stopped. machine# [ 57.518675] sddm[722]: Session started machine: running command: test -e /home/alice/.Xauthority machine: exit status 0 machine: must succeed: xauth merge ~alice/.Xauthority machine# xauth: file /root/.Xauthority does not exist machine: exit status 0 machine: waiting for success: pgrep plasmashell machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 59.478839] xsession[935]: /nix/store/i0x9mqayswj7jb69bg56jnv5506kaa6d-xsession: Window manager 'plasma5' not found. machine# [ 60.066959] xsession[935]: kbuildsycoca5 running... machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 90.284789] xsession[935]: /nix/store/vhm1z6jliymlh9b8phkxwkgndk1w83cp-startkde: line 35: /home/alice/.config/gtk-3.0/settings.ini: No such file or directory machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 97.111578] xsession[935]: Loading stage "initial" 4586 machine# [ 97.120804] xsession[935]: startkde: Starting up... machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 98.329326] xsession[935]: dbus-update-activation-environment: warning: error sending to systemd: org.freedesktop.DBus.Error.Spawn.ChildExited: Process org.freedesktop.systemd1 exited with status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 102.905629] xsession[935]: Connecting to deprecated signal QDBusConnectionInterface::serviceOwnerChanged(QString,QString,QString) machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 109.762933] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/soliduiserver.so" machine# [ 109.773744] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/lx0xjvy5h4pa21pyw2wby16clvqb23ly-khotkeys-5.9.4/lib/qt5/plugins/kf5/kded/khotkeys.so" machine# [ 109.795450] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/glv51a6bl6aq1cx9a9mxm0wgplcg79sx-kscreen-5.9.4/lib/qt5/plugins/kf5/kded/kscreen.so" machine# [ 109.810909] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/r6k4hyiv0jkd5sx00ls36c7jsfi12pxa-kio-extras-16.12.3/lib/qt5/plugins/kf5/kded/filenamesearchmodule.so" machine# [ 109.831195] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/desktopnotifier.so" machine# [ 109.841898] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/cjbc2nzq6snlbxpk085cnak3zi1qvd84-kio-5.32.0/lib/qt5/plugins/kf5/kded/proxyscout.so" machine: running command: pgrep plasmashell machine# [ 109.854823] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1n3jiidhp6171aa7nv5a0vg3w476jhh8-plasma-desktop-5.9.4/lib/qt5/plugins/kf5/kded/keyboard.so" machine: exit status 1 machine# [ 109.882322] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/phi4bivyriq4ava4z8b55l13hbywyaa5-kdelibs4support-5.32.0/lib/qt5/plugins/kf5/kded/networkstatus.so" machine# [ 109.936607] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/r6k4hyiv0jkd5sx00ls36c7jsfi12pxa-kio-extras-16.12.3/lib/qt5/plugins/kf5/kded/networkwatcher.so" machine# [ 109.941679] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/freespacenotifier.so" machine# [ 109.945966] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/ktimezoned.so" machine# [ 109.957948] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/remotedirnotify.so" machine# [ 109.978488] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/solidautoeject.so" machine# [ 109.983651] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/ksysguard.so" machine# [ 109.992955] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/appmenu.so" machine# [ 110.004248] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/cjbc2nzq6snlbxpk085cnak3zi1qvd84-kio-5.32.0/lib/qt5/plugins/kf5/kded/remotenotifier.so" machine# [ 110.013454] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1979cmn7jfqq8zx7qv1ipx2jz7g0g85c-plasma-workspace-5.9.4/lib/qt5/plugins/kf5/kded/statusnotifierwatcher.so" machine# [ 110.025542] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/r6k4hyiv0jkd5sx00ls36c7jsfi12pxa-kio-extras-16.12.3/lib/qt5/plugins/kf5/kded/recentdocumentsnotifier.so" machine# [ 110.040532] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/k9s6qy8h8lj4ccgy7dln2bwnhgc3a7zj-baloo-5.32.0/lib/qt5/plugins/kf5/kded/baloosearchmodule.so" machine# [ 110.048458] xsession[935]: kf5.kded: No X-KDE-DBus-ServiceName found in "/nix/store/1n3jiidhp6171aa7nv5a0vg3w476jhh8-plasma-desktop-5.9.4/lib/qt5/plugins/kf5/kded/device_automounter.so" machine# [ 110.502456] xsession[935]: Initializing "kded_touchpad" : "kcminit_touchpad" machine# [ 110.519954] xsession[935]: kcm_touchpad: Using X11 backend machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 111.660852] xsession[935]: Initializing "kcm_access" : "kcminit_access" machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine# [ 113.193337] xsession[935]: Initializing "kcm_style" : "kcminit_style" machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 114.706461] xsession[935]: Initializing "kcm_kgamma" : "kcminit_kgamma" machine# [ 114.799669] xsession[935]: Initializing "kcm_input" : "kcminit_mouse" machine: running command: pgrep plasmashell machine# [ 115.363378] xsession[935]: kf5.kded: found kded module "printmanager" by prepending 'kded_' to the library path, please fix your metadata. machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 120.284494] xsession[935]: kf5.kcoreaddons.kaboutdata: Could not initialize the equivalent properties of Q*Application: no instance (yet) existing. machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 123.421301] xsession[935]: 2017-04-03T01:03:08 Checking update-file '/run/current-system/sw/share/kconf_update/fonts_akregator.upd' for new updates machine# [ 123.430427] xsession[935]: 2017-04-03T01:03:08 fonts_akregator.upd: Found new update 'Plasma_Fonts_Akregator' machine# [ 123.445736] xsession[935]: 2017-04-03T01:03:08 fonts_akregator.upd: File 'akregatorrc' does not exist or empty, skipping machine# [ 123.604626] xsession[935]: 2017-04-03T01:03:08 Checking update-file '/run/current-system/sw/share/kconf_update/fonts_global.upd' for new updates machine# [ 123.616803] xsession[935]: 2017-04-03T01:03:08 fonts_global.upd: Found new update 'Fonts_Global' machine# [ 123.643382] xsession[935]: 2017-04-03T01:03:08 fonts_global.upd: Running script 'fonts_global.pl' machine# [ 123.794498] xsession[935]: Qt: Session management error: networkIdsList argument is NULL machine# [ 123.866405] xsession[935]: Configuring Lock Action machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 124.343404] xsession[935]: 2017-04-03T01:03:09 Checking update-file '/run/current-system/sw/share/kconf_update/fonts_kate.upd' for new updates machine# [ 124.373433] xsession[935]: 2017-04-03T01:03:09 fonts_kate.upd: Found new update 'Plasma_Fonts_Kate' machine# [ 124.392929] xsession[935]: 2017-04-03T01:03:09 fonts_kate.upd: File 'kateschemarc' does not exist or empty, skipping machine# [ 124.563464] xsession[935]: 2017-04-03T01:03:09 Checking update-file '/run/current-system/sw/share/kconf_update/gtkbreeze5.5.upd' for new updates machine# [ 124.581960] xsession[935]: 2017-04-03T01:03:09 gtkbreeze5.5.upd: Found new update 'GTKBreeze5.5' machine# [ 124.624651] xsession[935]: 2017-04-03T01:03:09 gtkbreeze5.5.upd:3:'Script=gtkbreeze5.5': Script 'gtkbreeze5.5' not found machine# [ 124.677104] xsession[935]: 2017-04-03T01:03:09 Checking update-file '/run/current-system/sw/share/kconf_update/kde4breeze.upd' for new updates machine# [ 124.702908] xsession[935]: 2017-04-03T01:03:09 kde4breeze.upd: Found new update '5.0KDE4Breeze_2' machine# [ 124.730774] xsession[935]: 2017-04-03T01:03:09 kde4breeze.upd:3:'Script=kde4breeze': Script 'kde4breeze' not found machine# [ 124.788035] xsession[935]: 2017-04-03T01:03:09 Checking update-file '/run/current-system/sw/share/kconf_update/krdb_libpathwipe.upd' for new updates machine# [ 124.808382] xsession[935]: "Missing \"Version=5\", file '/run/current-system/sw/share/kconf_update/krdb_libpathwipe.upd' will be skipped." machine# [ 124.876104] xsession[935]: 2017-04-03T01:03:10 Checking update-file '/run/current-system/sw/share/kconf_update/krunnerplugins.upd' for new updates machine# [ 124.917847] xsession[935]: 2017-04-03T01:03:10 krunnerplugins.upd: Found new update '5.9KRunnerPlugins' machine: running command: pgrep plasmashell machine# [ 124.946676] xsession[935]: 2017-04-03T01:03:10 krunnerplugins.upd:3:'Script=krunnerplugins': Script 'krunnerplugins' not found machine# [ 125.013992] xsession[935]: 2017-04-03T01:03:10 Checking update-file '/run/current-system/sw/share/kconf_update/kscreenlocker.upd' for new updates machine# [ 125.064946] xsession[935]: 2017-04-03T01:03:10 kscreenlocker.upd: Found new update '0.1-autolock' machine# [ 125.095625] xsession[935]: 2017-04-03T01:03:10 kscreenlocker.upd: File 'kscreenlockerrc' does not exist or empty, skipping machine: exit status 1 machine# [ 125.379918] xsession[935]: "Missing \"Version=5\", file '/run/current-system/sw/share/kconf_update/krdb_libpathwipe.upd' will be skipped." machine: running command: pgrep plasmashell machine# [ 126.139306] xsession[935]: pm.kded: unable to register service to dbus machine: exit status 1 machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 127.279877] xsession[935]: detected kglobalaccel restarting, re-registering all shortcut keys machine# [ 127.390927] xsession[935]: ksmserver: "/run/user/1000/KSMserver" machine# [ 127.398845] xsession[935]: ksmserver: KSMServer: SetAProc_loc: conn 0 , prot= local , file= @/tmp/.ICE-unix/1083 machine# [ 127.409398] xsession[935]: ksmserver: KSMServer: SetAProc_loc: conn 1 , prot= unix , file= /tmp/.ICE-unix/1083 machine# [ 127.435417] xsession[935]: ksmserver: KSMServer::restoreSession "saved at previous logout" machine# [ 128.045985] xsession[935]: detected kglobalaccel restarting, re-registering all shortcut keys machine: running command: pgrep plasmashell machine: exit status 1 machine# [ 128.575739] xsession[935]: QDBusConnection: name 'org.kde.kglobalaccel' had owner '' but we thought it was ':1.10' machine# [ 128.584346] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/baloo_file.desktop" machine# [ 128.600372] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/krunner.desktop" machine# [ 128.619642] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/pam_kwallet_init.desktop" machine# [ 128.675447] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/plasmashell.desktop" machine# [ 128.753916] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/polkit-kde-authentication-agent-1.desktop" machine# [ 128.811432] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/xembedsniproxy.desktop" machine# [ 128.843897] xsession[935]: ksmserver: Autostart 0 done machine# [ 128.886483] org.kde.KScreen[957]: kscreen.xrandr: Connected output 64 to CRTC 63 machine# [ 128.889689] org.kde.KScreen[957]: kscreen.xcb.helper: Detected XRandR 1.5 machine# [ 128.893678] org.kde.KScreen[957]: kscreen.xcb.helper: Event Base: 89 machine# [ 128.897782] org.kde.KScreen[957]: kscreen.xcb.helper: Event Error: 147 machine# [ 129.014843] xsession[935]: detected kglobalaccel restarting, re-registering all shortcut keys machine# [ 129.105346] xsession[935]: ksmserver: Kcminit phase 1 done machine# [ 129.142458] xsession[935]: kscreen.kded: Config KScreen::Config(0x1cdd3e0) is ready machine# [ 129.155993] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/kwrited-autostart.desktop" machine# [ 129.202321] xsession[935]: ksmserver: Autostart 1 done machine: running command: pgrep plasmashell machine: exit status 0 machine: waiting for a window to appear machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine# [ 129.640335] xsession[935]: kscreen.kded: PowerDevil SuspendSession action not available! machine: exit status 0 machine# [ 129.651746] xsession[935]: kscreen.kded: "The name org.kde.Solid.PowerManagement was not provided by any .service files" machine# [ 129.681251] xsession[935]: kscreen.kded: Applying config machine# [ 129.691748] xsession[935]: kscreen.kded: Applying ideal config machine# [ 129.694883] xsession[935]: kscreen.kded: Connected outputs: 1 machine# [ 129.706414] xsession[935]: kscreen.kded: doApplyConfig() machine# [ 129.797789] org.kde.KScreen[957]: kscreen.xrandr: XRandR::setConfig machine# [ 129.800835] org.kde.KScreen[957]: kscreen.xrandr: Requested screen size is QSize(1024, 768) machine# [ 129.811589] org.kde.KScreen[957]: kscreen.xrandr: Needed CRTCs: 1 machine# [ 129.816722] org.kde.KScreen[957]: kscreen.xrandr: Actions to perform: machine# [ 129.821848] org.kde.KScreen[957]: kscreen.xrandr: Primary Output: false machine# [ 129.829230] org.kde.KScreen[957]: kscreen.xrandr: Change Screen Size: false machine# [ 129.832752] org.kde.KScreen[957]: kscreen.xrandr: Disable outputs: false machine# [ 129.837769] org.kde.KScreen[957]: kscreen.xrandr: Change outputs: false machine# [ 129.847675] org.kde.KScreen[957]: kscreen.xrandr: Enable outputs: false machine# [ 129.857348] org.kde.KScreen[957]: kscreen.xrandr: XRandR::setConfig done! machine# [ 129.939764] xsession[935]: kscreen.kded: Config applied machine# [ 129.946418] xsession[935]: kscreen.kded: Monitor for changes: true machine# [ 130.002803] xsession[935]: kscreen: Requesting missing EDID for outputs (64) machine# [ 130.047941] xsession[935]: kscreen.kded: Change detected machine# [ 130.052318] xsession[935]: kscreen.kded: KScreen::Output( 64 "Virtual-1" connected enabled QPoint(0,0) QSize(1024, 768) "66" ) machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 132.758357] xsession[935]: Installing the delayed initialization callback. machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 133.501715] xsession[935]: Trying to convert empty KLocalizedString to QString. machine# [ 133.505459] xsession[935]: Trying to convert empty KLocalizedString to QString. machine# [ 133.509524] xsession[935]: Trying to convert empty KLocalizedString to QString. machine# [ 133.710277] xsession[935]: org.kde.kcm_keyboard: Failed to open layout memory xml file for reading "/home/alice/.local/share/kded5/keyboard/session/layout_memory.xml" error: 5 machine# [ 133.829533] xsession[935]: System timezone has been changed, new timezone is "UTC" machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 134.791665] xsession[935]: kf5.kded: found kded module "touchpad" by prepending 'kded_' to the library path, please fix your metadata. machine# [ 134.801880] xsession[935]: kcm_touchpad: Using X11 backend machine# [ 134.845868] xsession[935]: ksmserver: Starting notification thread machine# [ 134.855841] xsession[935]: Session path: "/org/freedesktop/login1/session/_32" machine# [ 134.869962] xsession[935]: ksmserver: Kcminit phase 2 done machine# [ 134.886703] xsession[935]: Delayed initialization. machine# [ 134.910589] xsession[935]: Reloading the khotkeys configuration machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine# [ 134.951826] xsession[935]: ksmserver: autostart service "/nix/store/gbryhjxm6b2311wrr7gyqcg48gdx0bwh-kwallet-pam-5.9.4/lib/libexec/pam_kwallet_init" finished with exit code 0 machine# [ 134.968092] xsession[935]: ksmserver: Starting autostart service "/run/current-system/sw/etc/xdg/autostart/powerdevil.desktop" machine: exit status 0 machine# [ 135.061248] xsession[935]: No version specified in file: machine# [ 135.081469] xsession[935]: Failed to load defaults from "/nix/store/phi4bivyriq4ava4z8b55l13hbywyaa5-kdelibs4support-5.32.0/share/khotkeys/defaults.khotkeys" machine# [ 135.102907] xsession[935]: true machine# [ 135.133987] xsession[935]: Version 2 File! machine# [ 135.192509] xsession[935]: ksmserver: Autostart 2 done machine# [ 135.353552] xsession[935]: Imported file "/nix/store/2drxgfdb2s6m0s8znp76hkas35c67170-system-path/share/khotkeys/defaults.khotkeys" machine# [ 135.445604] xsession[935]: Version 2 File! machine# [ 135.458822] xsession[935]: Imported file "/nix/store/2drxgfdb2s6m0s8znp76hkas35c67170-system-path/share/khotkeys/kde32b1.khotkeys" machine# [ 135.514719] xsession[935]: Version 2 File! machine# [ 135.550707] xsession[935]: Imported file "/nix/store/2drxgfdb2s6m0s8znp76hkas35c67170-system-path/share/khotkeys/konqueror_gestures_kde321.khotkeys" machine# [ 135.845258] xsession[935]: kscreen.kded: Saving current config to file machine# [ 135.848904] xsession[935]: kscreen.kded: Config saved on: "/home/alice/.local/share/kscreen/cca5b2c6a5fbfdc9ff2f84ca107a2982" machine# [ 135.859358] xsession[935]: kscreen.kded: KScreen::Output( 64 "Virtual-1" connected enabled QPoint(0,0) QSize(1024, 768) "66" ) machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 139.993891] polkitd[902]: Registered Authentication Agent for unix-session:2 (system bus name :1.14 [/nix/store/c9lk6y135fj9w5lqvnb0kz0rvwh8mbq4-polkit-kde-agent-1-5.9.4/lib/libexec/polkit-kde-authentication-agent-1], object path /org/kde/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 149.839272] dbus[675]: [system] Activating service name='org.kde.powerdevil.discretegpuhelper' (using servicehelper) machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 150.770093] org.kde.powerdevil.discretegpuhelper[1220]: QDBusArgument: read from a write-only object machine# [ 150.777681] org.kde.powerdevil.discretegpuhelper[1220]: QDBusArgument: read from a write-only object machine# [ 150.785069] org.kde.powerdevil.discretegpuhelper[1220]: QDBusArgument: read from a write-only object machine# [ 150.818812] dbus[675]: [system] Successfully activated service 'org.kde.powerdevil.discretegpuhelper' machine# [ 150.859346] dbus[675]: [system] Activating service name='org.kde.powerdevil.backlighthelper' (using servicehelper) machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 151.832507] org.kde.powerdevil.backlighthelper[675]: powerdevil: no kernel backlight interface found machine# [ 152.018529] org.kde.powerdevil.backlighthelper[1228]: QDBusArgument: read from a write-only object machine# [ 152.040839] org.kde.powerdevil.backlighthelper[1228]: QDBusArgument: read from a write-only object machine# [ 152.088570] org.kde.powerdevil.backlighthelper[1228]: QDBusArgument: read from a write-only object machine# [ 152.120639] dbus[675]: [system] Successfully activated service 'org.kde.powerdevil.backlighthelper' machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 156.234816] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service' machine# [ 156.245861] dbus[675]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.NetworkManager.service': Unit dbus-org.freedesktop.NetworkManager.service not found. machine# [ 156.270739] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service' machine# [ 156.278880] dbus[675]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.NetworkManager.service': Unit dbus-org.freedesktop.NetworkManager.service not found. machine# [ 156.319396] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service' machine# [ 156.325453] dbus[675]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.NetworkManager.service': Unit dbus-org.freedesktop.NetworkManager.service not found. machine# [ 156.612951] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service' machine# [ 156.627652] dbus[675]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.NetworkManager.service': Unit dbus-org.freedesktop.NetworkManager.service not found. machine# [ 156.637812] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service' machine# [ 156.648071] dbus[675]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.NetworkManager.service': Unit dbus-org.freedesktop.NetworkManager.service not found. machine# [ 156.657896] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service' machine# [ 156.667546] dbus[675]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.NetworkManager.service': Unit dbus-org.freedesktop.NetworkManager.service not found. machine# [ 156.678949] dbus[675]: [system] Activating via systemd: service name='org.freedesktop.NetworkManager' unit='dbus-org.freedesktop.NetworkManager.service' machine# [ 156.695710] dbus[675]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.NetworkManager.service': Unit dbus-org.freedesktop.NetworkManager.service not found. machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: getfacl /dev/snd/timer | grep -q alice machine# getfacl: Removing leading '/' from absolute path names machine: exit status 0 machine: running command: su - alice -c 'DISPLAY=:0.0 dolphin &' machine# [ 159.778424] su[1268]: Successful su for alice by root machine# [ 159.835733] su[1268]: pam_unix(su:session): session opened for user alice by (uid=0) machine: exit status 0 machine: waiting for a window to appear machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine# [ 160.517882] su[1268]: pam_unix(su:session): session closed for user alice machine# [ 160.568644] xsession[935]: ksmserver: completeShutdownOrCheckpoint called machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-alice' machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 172.937727] xsession[935]: OpenGL vendor string: VMware, Inc. machine# [ 172.951780] xsession[935]: OpenGL renderer string: Gallium 0.4 on llvmpipe (LLVM 3.9, 128 bits) machine# [ 172.961369] xsession[935]: OpenGL version string: 3.0 Mesa 17.0.2 machine# [ 172.969972] xsession[935]: OpenGL shading language version string: 1.30 machine# [ 172.981381] xsession[935]: Driver: LLVMpipe machine# [ 173.011455] xsession[935]: GPU class: Unknown machine# [ 173.018840] xsession[935]: OpenGL version: 3.0 machine# [ 173.028609] xsession[935]: GLSL version: 1.30 machine# [ 173.036063] xsession[935]: Mesa version: 17.0.2 machine# [ 173.044484] xsession[935]: X server version: 1.19.3 machine# [ 173.055801] xsession[935]: Linux kernel version: 4.9.20 machine# [ 173.069555] xsession[935]: Requires strict binding: yes machine# [ 173.088402] xsession[935]: GLSL shaders: yes machine# [ 173.097586] xsession[935]: Texture NPOT support: yes machine# [ 173.108586] xsession[935]: Virtual Machine: no machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 176.021938] xsession[935]: Freeze in OpenGL initialization detected machine# [ 176.049945] xsession[935]: Application::crashHandler() called with signal 6; recent crashes: 1 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 177.338977] xsession[935]: KCrash: Application 'kwin_x11' crashing... machine# [ 177.357974] xsession[935]: KCrash: Attempting to start /run/current-system/sw/lib/libexec/drkonqi from kdeinit machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 180.790580] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 180.978713] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 181.097302] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 181.173745] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 181.233625] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 181.298654] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 181.394802] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 181.495782] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 181.710630] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine# [ 181.765715] xsession[935]: kf5.kcoreaddons.desktopparser: Could not locate service type file kservicetypes5/kwin-effect.desktop, tried ("/home/alice/.local/share", "/run/opengl-driver/share", "/home/alice/.nix-profile/share", "/nix/var/nix/profiles/default/share", "/run/current-system/sw/share") machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# org.kde.dolphin: Saving view-properties to "/home/alice/.local/share/dolphin/view_properties/global" machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: running command: su - alice -c 'DISPLAY=:0.0 konsole &' machine# [ 187.729958] su[1364]: Successful su for alice by root machine# [ 187.793486] su[1364]: pam_unix(su:session): session opened for user alice by (uid=0) machine# org.kde.dolphin: Saving view-properties to "/home/alice/.local/share/dolphin/view_properties/remote" machine# org.kde.dolphin: Saving view-properties to "/home/alice/.local/share/dolphin/view_properties/search/-GcD96GqLrtyEKc21tsud9GdNkQ=" machine# org.kde.dolphin: Saving view-properties to "/home/alice/.local/share/dolphin/view_properties/search/3rYA5zveYrVrSoDKydYCGWpdZhg=" machine# org.kde.dolphin: Saving view-properties to "/home/alice/.local/share/dolphin/view_properties/search/ykFRhXdlPjV1Id7FvXOgDWgJtsA=" machine# org.kde.dolphin: Saving view-properties to "/home/alice/.local/share/dolphin/view_properties/search/NQ5+nxCAQJ6aFlcde71NgUHGDvY=" machine# [ 188.515377] su[1364]: pam_unix(su:session): session closed for user alice machine: exit status 0 machine: waiting for a window to appear machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# Cannot creat accessible child interface for object: PlacesView(0x257ca40) index: 14 machine# QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-alice' machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-alice' machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-alice' machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-alice' machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 195.747848] xsession[935]: kf5.kio.trash: listdir: QUrl("trash:/") machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: running command: su - alice -c 'DISPLAY=:0.0 systemsettings5 &' machine# [ 196.672073] su[1408]: Successful su for alice by root machine# [ 196.700093] su[1408]: pam_unix(su:session): session opened for user alice by (uid=0) machine: exit status 0 machine: waiting for a window to appear machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine# [ 197.449569] su[1408]: pam_unix(su:session): session closed for user alice machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 200.560363] xsession[935]: Using /proc to determine executable path machine# [ 200.580927] xsession[935]: Executable is: "/nix/store/i608qv4gh68asp3147hqb7vl5xdvrf3l-kwin-5.9.4/bin/kwin_x11" machine# [ 200.610309] xsession[935]: Executable exists: true machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine# [ 201.146909] xsession[935]: Unable to find an internal debugger that can work with the KCrash backend machine: exit status 0 machine# [ 201.363792] xsession[935]: Enabling drkonqi crash catching machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine# [ 208.697541] xsession[935]: kf5.kwidgetsaddons: Invalid pixmap specified. machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: must succeed: xwininfo -root -tree | sed 's/.*0x[0-9a-f]* \"\([^\"]*\)\".*/\1/; t; d' machine: exit status 0 machine: running command: /nix/store/h6g06il39ks6v5dkxk2dmmimf0hhb37b-xdotool-3.20160805.1/bin/xdotool key Alt+F1 sleep 10 machine# [ 218.935565] xsession[935]: Sending SIGSTOP to process machine# [ 224.051370] xsession[935]: OpenGL vendor string: VMware, Inc. machine# [ 224.057508] xsession[935]: OpenGL renderer string: Gallium 0.4 on llvmpipe (LLVM 3.9, 128 bits) machine# [ 224.065915] xsession[935]: OpenGL version string: 3.0 Mesa 17.0.2 machine# [ 224.072071] xsession[935]: OpenGL shading language version string: 1.30 machine# [ 224.081275] xsession[935]: Driver: LLVMpipe machine# [ 224.089772] xsession[935]: GPU class: Unknown machine# [ 224.092515] xsession[935]: OpenGL version: 3.0 machine# [ 224.098936] xsession[935]: GLSL version: 1.30 machine# [ 224.107916] xsession[935]: Mesa version: 17.0.2 machine# [ 224.115878] xsession[935]: X server version: 1.19.3 machine# [ 224.123742] xsession[935]: Linux kernel version: 4.9.20 machine# [ 224.139945] xsession[935]: Requires strict binding: yes machine# [ 224.142705] xsession[935]: GLSL shaders: yes machine# [ 224.144717] xsession[935]: Texture NPOT support: yes machine# [ 224.146796] xsession[935]: Virtual Machine: no machine: exit status 0 machine: making screenshot ‘screen.png’ machine: sending monitor command: screendump /nix/store/l243ccb7wd35yqb6pxghv0g2nd8wq2nz-vm-test-run-plasma5/screen.png.ppm collecting coverage data machine: running command: test -e /sys/kernel/debug/gcov machine: exit status 1 syncing machine: running command: sync machine: exit status 0 test script finished in 226.92s cleaning up killing machine (pid 78) vde_switch: EOF on stdin, cleaning up and exiting vde_switch: Could not remove ctl dir '/tmp/nix-build-vm-test-run-plasma5.drv-0/vde1.ctl': Directory not empty