Skip to content

Instantly share code, notes, and snippets.

@srhb
Created April 1, 2019 16:47
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save srhb/cdb7a11ba3dc189be719574e89291420 to your computer and use it in GitHub Desktop.
Save srhb/cdb7a11ba3dc189be719574e89291420 to your computer and use it in GitHub Desktop.
import ./make-test.nix {
machine =
{ pkgs, ... }:
{
imports = [ ./common/x11.nix ];
environment.systemPackages =
let
python = pkgs.python3.withPackages (ps: [ ps.selenium ]);
pythonTest = pkgs.writeScriptBin "test-selenium-firefox" ''
#!${python}/bin/python3
from selenium import webdriver
webdriver.Firefox()
'';
in with pkgs; [
pythonTest
geckodriver
firefox
];
};
testScript = ''
$machine->waitForX;
subtest "selenium can launch Firefox", sub {
$machine->succeed("test-selenium-firefox");
$machine->screenshot("Firefox");
};
'';
}
@das-g
Copy link

das-g commented Apr 1, 2019

This fails for me with

subtest: selenium can launch Firefox
machine: must succeed: test-selenium-firefox
machine# [  160.775168] hrtimer: interrupt took 3909714 ns
machine# Traceback (most recent call last):
machine#   File "/run/current-system/sw/bin/test-selenium-firefox", line 3, in <module>
machine#     webdriver.Firefox()
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/firefox/webdriver.py", line 174, in __init__
machine#     keep_alive=True)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/webdriver.py", line 157, in __init__
machine#     self.start_session(capabilities, browser_profile)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/webdriver.py", line 252, in start_session
machine#     response = self.execute(Command.NEW_SESSION, parameters)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/webdriver.py", line 321, in execute
machine#     self.error_handler.check_response(response)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/errorhandler.py", line 242, in check_response
machine#     raise exception_class(message, screen, stacktrace)
machine# selenium.common.exceptions.WebDriverException: Message: connection refused
machine#
machine: exit status 1
(click for full terminal output)
$ nix-build nixos/tests/geckodriver.nix 
these derivations will be built:
  /nix/store/35a10mqlr3g6w0x681ldgrgryc1shas3-vm-test-run-unnamed.drv
building '/nix/store/35a10mqlr3g6w0x681ldgrgryc1shas3-vm-test-run-unnamed.drv'...
starting VDE switch for network 1
running the VM test script
machine: waiting for the X11 server
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: waiting for the VM to finish booting
machine: starting vm
machine# Formatting '/build/vm-state-machine/machine.qcow2', fmt=qcow2 size=536870912 cluster_size=65536 lazy_refcounts=off refcount_bits=16
machine: QEMU running (pid 594)
machine# cSeaBIOS (version rel-1.12.0-0-ga698c8995f-prebuilt.qemu.org)
machine#
machine#
machine# iPXE (http://ipxe.org) 00:03.0 C980 PCI2.10 PnP PMM+17F90F80+17EF0F80 C980

machine#
machine#
machine#
machine#
machine# iPXE (http://ipxe.org) 00:08.0 CA80 PCI2.10 PnP PMM 17F90F80 17EF0F80 CA80

machine#
machine#
machine# Booting from ROM...
[    0.000000] Linux version 4.19.32 (nixbld@localhost) (gcc version 7.4.0 (GCC)) #1-NixOS SMP Wed Mar 27 05:14:43 UTC 2019
machine# [    0.000000] Command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 init=/nix/store/jbla3fimcidcz1psfs3crngfssnjs1dn-nixos-system-machine-19.09.git.1da4029/init regInfo=/nix/store/ai0fmc2qpfc7nlf16zal68dk54fafj1i-closure-info/registration console=ttyS0
machine# [    0.000000] x86/fpu: x87 FPU will use FXSAVE
machine# [    0.000000] 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-0x0000000017fdbfff] usable
machine# [    0.000000] BIOS-e820: [mem 0x0000000017fdc000-0x0000000017ffffff] 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] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.12.0-0-ga698c8995f-prebuilt.qemu.org 04/01/2014
machine# [    0.000000] tsc: Fast TSC calibration using PIT
machine# [    0.000000] tsc: Detected 2112.019 MHz processor
machine# [    0.012341] last_pfn = 0x17fdc max_arch_pfn = 0x400000000
machine# [    0.013472] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- UC
machine# [    0.034032] found SMP MP-table at [mem 0x000f5870-0x000f587f] mapped at [(____ptrval____)]
machine# [    0.035399] Scanning 1 areas for low memory corruption
machine# [    0.040240] RAMDISK: [mem 0x1766d000-0x17fcffff]
machine# [    0.040722] ACPI: Early table checksum verification disabled
machine# [    0.041941] ACPI: RSDP 0x00000000000F5680 000014 (v00 BOCHS )
machine# [    0.042134] ACPI: RSDT 0x0000000017FE156F 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
machine# [    0.042643] ACPI: FACP 0x0000000017FE144B 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
machine# [    0.043125] ACPI: DSDT 0x0000000017FE0040 00140B (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
machine# [    0.043213] ACPI: FACS 0x0000000017FE0000 000040
machine# [    0.043285] ACPI: APIC 0x0000000017FE14BF 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
machine# [    0.043331] ACPI: HPET 0x0000000017FE1537 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
machine# [    0.045054] No NUMA configuration found
machine# [    0.045083] Faking a node at [mem 0x0000000000000000-0x0000000017fdbfff]
machine# [    0.045651] NODE_DATA(0) allocated [mem 0x17fd8000-0x17fdbfff]
machine# [    0.047732] Zone ranges:
machine# [    0.047785]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
machine# [    0.047833]   DMA32    [mem 0x0000000001000000-0x0000000017fdbfff]
machine# [    0.047854]   Normal   empty
machine# [    0.047885] Movable zone start for each node
machine# [    0.047921] Early memory node ranges
machine# [    0.047956]   node   0: [mem 0x0000000000001000-0x000000000009efff]
machine# [    0.047979]   node   0: [mem 0x0000000000100000-0x0000000017fdbfff]
machine# [    0.048335] Reserved but unavailable: 98 pages
machine# [    0.048477] Initmem setup node 0 [mem 0x0000000000001000-0x0000000017fdbfff]
machine# [    0.067915] ACPI: PM-Timer IO Port: 0x608
machine# [    0.068338] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
machine# [    0.068673] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
machine# [    0.068779] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
machine# [    0.069009] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
machine# [    0.069047] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
machine# [    0.069137] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
machine# [    0.069155] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
machine# [    0.069358] Using ACPI (MADT) for SMP configuration information
machine# [    0.069411] ACPI: HPET id: 0x8086a201 base: 0xfed00000
machine# [    0.069759] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
machine# [    0.070473] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
machine# [    0.070518] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
machine# [    0.070548] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
machine# [    0.070562] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
machine# [    0.070671] [mem 0x18000000-0xfffbffff] available for PCI devices
machine# [    0.070705] Booting paravirtualized kernel on bare hardware
machine# [    0.070997] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
machine# [    1.617455] random: get_random_bytes called from start_kernel+0x94/0x4ce with crng_init=0
machine# [    1.618143] setup_percpu: NR_CPUS:384 nr_cpumask_bits:384 nr_cpu_ids:1 nr_node_ids:1
machine# [    1.623517] percpu: Embedded 44 pages/cpu @(____ptrval____) s141272 r8192 d30760 u2097152
machine# [    1.625495] Built 1 zonelists, mobility grouping on.  Total pages: 96613
machine# [    1.625520] Policy zone: DMA32
machine# [    1.625676] Kernel command line: console=ttyS0 panic=1 boot.panic_on_fail loglevel=7 init=/nix/store/jbla3fimcidcz1psfs3crngfssnjs1dn-nixos-system-machine-19.09.git.1da4029/init regInfo=/nix/store/ai0fmc2qpfc7nlf16zal68dk54fafj1i-closure-info/registration console=ttyS0
machine# [    1.635800] Memory: 354456K/392680K available (10252K kernel code, 1135K rwdata, 1932K rodata, 1440K init, 812K bss, 38224K reserved, 0K cma-reserved)
machine# [    1.639669] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
machine# [    1.640178] Kernel/User page tables isolation: enabled
machine# [    1.641677] ftrace: allocating 28444 entries in 112 pages
machine# [    1.774135] rcu: Hierarchical RCU implementation.
machine# [    1.774231] rcu:    RCU event tracing is enabled.
machine# [    1.774262] rcu:    RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
machine# [    1.774354] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
machine# [    1.801670] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16
machine# [    1.809674] Console: colour VGA+ 80x25
machine# [    1.824237] console [ttyS0] enabled
machine# [    1.825325] ACPI: Core revision 20180810
machine# [    1.830091] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
machine# [    1.833793] APIC: Switch to symmetric I/O mode setup
machine# [    1.838971] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
machine# [    1.844328] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1e718a785d2, max_idle_ns: 440795253802 ns
machine# [    1.845715] Calibrating delay loop (skipped), value calculated using timer frequency.. 4224.03 BogoMIPS (lpj=2112019)
machine# [    1.846956] pid_max: default: 32768 minimum: 301
machine# [    1.847719] Security Framework initialized
machine# [    1.847962] Yama: becoming mindful.
machine# [    1.850019] AppArmor: AppArmor initialized
machine# [    1.851387] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
machine# [    1.852037] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
machine# [    1.852495] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
machine# [    1.852757] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
machine# [    1.864490] mce: CPU supports 10 MCE banks
machine# [    1.865717] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
machine# [    1.865859] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
machine# [    1.866121] Spectre V2 : Mitigation: Full generic retpoline
machine# [    1.866270] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
machine# [    1.866544] Speculative Store Bypass: Vulnerable
machine# [    2.052490] Freeing SMP alternatives memory: 28K
machine# [    2.088845] random: fast init done
machine# [    2.088845] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
machine# [    2.092702] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
machine# [    2.094216] rcu: Hierarchical SRCU implementation.
machine# [    2.098403] smp: Bringing up secondary CPUs ...
machine# [    2.099022] smp: Brought up 1 node, 1 CPU
machine# [    2.099166] smpboot: Max logical packages: 1
machine# [    2.099352] smpboot: Total of 1 processors activated (4224.03 BogoMIPS)
machine# [    2.108135] devtmpfs: initialized
machine# [    2.110821] x86/mm: Memory block size: 128MB
machine# [    2.116060] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
machine# [    2.116593] futex hash table entries: 256 (order: 2, 16384 bytes)
machine# [    2.120287] pinctrl core: initialized pinctrl subsystem
machine# [    2.128449] NET: Registered protocol family 16
machine# [    2.130745] audit: initializing netlink subsys (disabled)
machine# [    2.134374] cpuidle: using governor menu
machine# [    2.135188] audit: type=2000 audit(1554139989.297:1): state=initialized audit_enabled=0 res=1
machine# [    2.136209] ACPI: bus type PCI registered
machine# [    2.136370] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
machine# [    2.138097] PCI: Using configuration type 1 for base access
machine# [    2.153543] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
machine# [    2.157999] ACPI: Added _OSI(Module Device)
machine# [    2.158134] ACPI: Added _OSI(Processor Device)
machine# [    2.158259] ACPI: Added _OSI(3.0 _SCP Extensions)
machine# [    2.158385] ACPI: Added _OSI(Processor Aggregator Device)
machine# [    2.158637] ACPI: Added _OSI(Linux-Dell-Video)
machine# [    2.158757] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
machine# [    2.173160] ACPI: 1 ACPI AML tables successfully acquired and loaded
machine# [    2.190833] ACPI: Interpreter enabled
machine# [    2.191700] ACPI: (supports S0 S3 S4 S5)
machine# [    2.191892] ACPI: Using IOAPIC for interrupt routing
machine# [    2.192351] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
machine# [    2.194161] ACPI: Enabled 2 GPEs in block 00 to 0F
machine# [    2.230136] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
machine# [    2.230628] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
machine# [    2.231013] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
machine# [    2.231543] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
machine# [    2.234757] acpiphp: Slot [3] registered
machine# [    2.235091] acpiphp: Slot [4] registered
machine# [    2.235300] acpiphp: Slot [5] registered
machine# [    2.235516] acpiphp: Slot [6] registered
machine# [    2.235744] acpiphp: Slot [7] registered
machine# [    2.235985] acpiphp: Slot [8] registered
machine# [    2.236203] acpiphp: Slot [9] registered
machine# [    2.236438] acpiphp: Slot [10] registered
machine# [    2.236673] acpiphp: Slot [11] registered
machine# [    2.237003] acpiphp: Slot [12] registered
machine# [    2.237235] acpiphp: Slot [13] registered
machine# [    2.237456] acpiphp: Slot [14] registered
machine# [    2.237684] acpiphp: Slot [15] registered
machine# [    2.237993] acpiphp: Slot [16] registered
machine# [    2.238211] acpiphp: Slot [17] registered
machine# [    2.238423] acpiphp: Slot [18] registered
machine# [    2.238654] acpiphp: Slot [19] registered
machine# [    2.238951] acpiphp: Slot [20] registered
machine# [    2.239209] acpiphp: Slot [21] registered
machine# [    2.239483] acpiphp: Slot [22] registered
machine# [    2.239875] acpiphp: Slot [23] registered
machine# [    2.240123] acpiphp: Slot [24] registered
machine# [    2.240376] acpiphp: Slot [25] registered
machine# [    2.240716] acpiphp: Slot [26] registered
machine# [    2.241066] acpiphp: Slot [27] registered
machine# [    2.241311] acpiphp: Slot [28] registered
machine# [    2.241860] acpiphp: Slot [29] registered
machine# [    2.242247] acpiphp: Slot [30] registered
machine# [    2.242954] acpiphp: Slot [31] registered
machine# [    2.243309] PCI host bridge to bus 0000:00
machine# [    2.243633] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
machine# [    2.243880] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
machine# [    2.244071] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
machine# [    2.244276] pci_bus 0000:00: root bus resource [mem 0x18000000-0xfebfffff window]
machine# [    2.244496] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
machine# [    2.244959] pci_bus 0000:00: root bus resource [bus 00-ff]
machine# [    2.252886] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
machine# [    2.253103] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
machine# [    2.253868] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
machine# [    2.254067] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
machine# [    2.259868] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
machine# [    2.260100] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
machine# [    2.316344] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
machine# [    2.317295] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
machine# [    2.318067] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
machine# [    2.318771] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
machine# [    2.319116] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
machine# [    2.324410] pci 0000:00:02.0: vgaarb: setting as boot VGA device
machine# [    2.324690] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
machine# [    2.324917] pci 0000:00:02.0: vgaarb: bridge control possible
machine# [    2.325149] vgaarb: loaded
machine# [    2.327271] PCI: Using ACPI for IRQ routing
machine# [    2.334624] NetLabel: Initializing
machine# [    2.334793] NetLabel:  domain hash size = 128
machine# [    2.334905] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
machine# [    2.336681] NetLabel:  unlabeled traffic allowed by default
machine# [    2.339887] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
machine# [    2.340265] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
machine# [    2.340475] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
machine# [    2.344743] clocksource: Switched to clocksource tsc-early
machine# [    2.440097] VFS: Disk quotas dquot_6.6.0
machine# [    2.440405] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
machine# [    2.444002] AppArmor: AppArmor Filesystem Enabled
machine# [    2.444690] pnp: PnP ACPI init
machine# [    2.449551] pnp: PnP ACPI: found 6 devices
machine# [    2.470520] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
machine# [    2.472390] NET: Registered protocol family 2
machine# [    2.476632] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes)
machine# [    2.476907] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
machine# [    2.477323] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
machine# [    2.477594] TCP: Hash tables configured (established 4096 bind 4096)
machine# [    2.478660] UDP hash table entries: 256 (order: 1, 8192 bytes)
machine# [    2.479008] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
machine# [    2.480198] NET: Registered protocol family 1
machine# [    2.480594] pci 0000:00:01.0: PIIX3: Enabling Passive Release
machine# [    2.480826] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
machine# [    2.481096] pci 0000:00:01.0: Activating ISA DMA hang workarounds
machine# [    2.694666] PCI Interrupt Link [LNKD] enabled at IRQ 11
machine# [    2.912413] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x6b0 took 420932 usecs
machine# [    2.912922] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
machine# [    2.916653] Trying to unpack rootfs image as initramfs...
machine# [    3.343444] Freeing initrd memory: 9612K
machine# [    3.344937] Scanning for low memory corruption every 60 seconds
machine# [    3.351059] Initialise system trusted keyrings
machine# [    3.352290] workingset: timestamp_bits=40 max_order=17 bucket_order=0
machine# [    3.365273] zbud: loaded
machine# [    3.376452] Key type asymmetric registered
machine# [    3.376626] Asymmetric key parser 'x509' registered
machine# [    3.377048] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
machine# [    3.377735] io scheduler noop registered
machine# [    3.377970] io scheduler deadline registered
machine# [    3.378462] io scheduler cfq registered (default)
machine# [    3.378608] io scheduler mq-deadline registered
machine# [    3.378746] io scheduler kyber registered
machine# [    3.383025] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
machine# [    3.405753] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
machine# [    3.445447] brd: module loaded
machine# [    3.448274] sched_clock: Marking stable (3418900468, 28984841)->(3598326661, -150441352)
machine# [    3.451558] registered taskstats version 1
machine# [    3.451737] Loading compiled-in X.509 certificates
machine# [    3.452747] zswap: loaded using pool lzo/zbud
machine# [    3.454806] AppArmor: AppArmor sha1 policy hashing enabled
machine# [    3.554208] Freeing unused kernel image memory: 1440K
machine# [    3.556412] Write protecting the kernel read-only data: 14336k
machine# [    3.559830] Freeing unused kernel image memory: 2012K
machine# [    3.560544] Freeing unused kernel image memory: 116K
machine# [    3.561305] Run /init as init process
machine#
machine# <<< NixOS Stage 1 >>>
machine#
machine# [    4.363356] tsc: Refined TSC clocksource calibration: 2111.993 MHz
machine# [    4.363671] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e7171ce903, max_idle_ns: 440795229087 ns
machine# [    4.364145] clocksource: Switched to clocksource tsc
machine# loading module virtio_balloon...
machine# loading module virtio_console...
machine# loading module virtio_rng...
machine# loading module dm_mod...
machine# [    4.933086] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
machine# running udev...
machine# [    5.104305] systemd-udevd[90]: starting version 239
machine# [    6.653286] rtc_cmos 00:00: RTC can wake from S4
machine# [    6.768843] rtc_cmos 00:00: registered as rtc0
machine# [    6.804430] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
machine# [    6.839438] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
machine# [    6.889811] serio: i8042 KBD port at 0x60,0x64 irq 1
machine# [    6.890360] serio: i8042 AUX port at 0x60,0x64 irq 12
machine# [    7.252288] SCSI subsystem initialized
machine# [    7.357520] ACPI: bus type USB registered
machine# [    7.364475] usbcore: registered new interface driver usbfs
machine# [    7.365916] usbcore: registered new interface driver hub
machine# [    7.387448] usbcore: registered new device driver usb
machine# [    7.541730] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
machine# [    7.623883] uhci_hcd: USB Universal Host Controller Interface driver
machine# [    7.744222] scsi host0: ata_piix
machine# [    7.761136] scsi host1: ata_piix
machine# [    7.762116] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc180 irq 14
machine# [    7.762309] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc188 irq 15
machine# [    7.927378] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
machine# [    7.948029] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
machine# [    8.307831] PCI Interrupt Link [LNKC] enabled at IRQ 10
machine# [    8.309676] uhci_hcd 0000:00:01.2: UHCI Host Controller
machine# [    8.310192] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
machine# [    8.310697] uhci_hcd 0000:00:01.2: detected 2 ports
machine# [    8.311249] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c080
machine# [    8.326562] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.19
machine# [    8.326840] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
machine# [    8.327138] usb usb1: Product: UHCI Host Controller
machine# [    8.327287] usb usb1: Manufacturer: Linux 4.19.32 uhci_hcd
machine# [    8.327446] usb usb1: SerialNumber: 0000:00:01.2
machine# [    8.339728] hub 1-0:1.0: USB hub found
machine# [    8.340371] hub 1-0:1.0: 2 ports detected
machine# [    8.670370] usb 1-1: new full-speed USB device number 2 using uhci_hcd
machine# [    8.836924] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00
machine# [    8.837152] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=5
machine# [    8.837351] usb 1-1: Product: QEMU USB Tablet
machine# [    8.837469] usb 1-1: Manufacturer: QEMU
machine# [    8.837576] usb 1-1: SerialNumber: 42
machine# [    8.971462] random: crng init done
machine# [    9.651831] PCI Interrupt Link [LNKA] enabled at IRQ 10
machine# [    9.942264] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
machine# [   10.206013] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
machine# [   10.206348] cdrom: Uniform CD-ROM driver Revision: 3.20
machine# [   10.244832] PCI Interrupt Link [LNKB] enabled at IRQ 11
machine# [   10.403202] hidraw: raw HID events driver (C) Jiri Kosina
machine# [   10.463061] usbcore: registered new interface driver usbhid
machine# [   10.463231] usbhid: USB HID core driver
machine# [   11.469024] 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# [   11.471667] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0
machine# [   12.176626] virtio_blk virtio8: [vda] 1048576 512-byte logical blocks (537 MB/512 MiB)
machine# [   12.323640] 9pnet: Installing 9P2000 support
machine# kbd_mode: KDSKBMODE: Inappropriate ioctl for device
machine# %Gstarting device mapper and LVM...
machine# [   12.944330] clocksource: Switched to clocksource acpi_pm
machine# mke2fs 1.44.5 (15-Dec-2018)
machine# Creating filesystem with 131072 4k blocks and 32768 inodes
machine# Filesystem UUID: 99a0790b-e8c1-4eee-9573-bb50f2aac829
machine# Superblock backups stored on blocks:
machine#        32768, 98304
machine#
machine# Allocating group tables: done
machine# Writing inode tables: done
machine# Creating journal (4096 blocks): done
machine# Writing superblocks and filesystem accounting information: done
machine#
machine# checking /dev/vda...
machine# fsck (busybox 1.30.1)
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# [   14.450635] EXT4-fs (vda): mounted filesystem with ordered data mode. Opts: (null)
machine# mounting store on /nix/.ro-store...
machine# [   14.858589] FS-Cache: Loaded
machine# [   14.919263] 9p: Installing v9fs 9p2000 file system support
machine# [   14.920142] 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# [   18.446401] EXT4-fs (vda): re-mounted. Opts: (null)
machine# [   18.458990] booting system configuration /nix/store/jbla3fimcidcz1psfs3crngfssnjs1dn-nixos-system-machine-19.09.git.1da4029
machine# running activation script...
machine# setting up /etc...
machine# starting systemd...
machine# [   33.249060] NET: Registered protocol family 10
machine# [   33.261807] Segment Routing with IPv6
machine# [   33.400492] systemd[1]: systemd 239 running in system mode. (+PAM +AUDIT -SELINUX +IMA +APPARMOR +SMACK -SYSVINIT +UTMP -LIBCRYPTSETUP +GCRYPT -GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID -ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid)
machine# [   33.403506] systemd[1]: Detected virtualization qemu.
machine# [   33.404084] systemd[1]: Detected architecture x86-64.
machine# [   33.446895] systemd[1]: Set hostname to <machine>.
machine# [   33.448459] systemd[1]: Initializing machine ID from random generator.
machine# [   34.389680] systemd-fstab-generator[410]: Checking was requested for "store", but it is not a device.
machine# [   34.402855] systemd-fstab-generator[410]: Checking was requested for "shared", but it is not a device.
machine# [   34.406895] systemd-fstab-generator[410]: Checking was requested for "xchg", but it is not a device.
machine# [   35.908182] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
machine# [   35.919847] systemd[1]: Listening on Journal Audit Socket.
machine# [   35.924846] systemd[1]: Listening on Journal Socket.
machine# [   36.024679] systemd[1]: Starting Create list of required static device nodes for the current kernel...
machine# [   36.093542] systemd[1]: Listening on Journal Socket (/dev/log).
machine# [   37.828477] EXT4-fs (vda): re-mounted. Opts: (null)
machine# [   38.510259] loop: module loaded
machine# [   38.792628] systemd[1]: Started Create list of required static device nodes for the current kernel.
machine# [   38.808431] audit: type=1130 audit(1554140025.865:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=kmod-static-nodes comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   38.838883] systemd[1]: Mounted Kernel Debug File System.
machine# [   38.851404] systemd[1]: Mounted Huge Pages File System.
machine# [   38.901453] systemd[1]: Started Remount Root and Kernel File Systems.
machine# [   38.904677] audit: type=1130 audit(1554140025.963:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-remount-fs comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   38.954457] systemd[1]: Started Load Kernel Modules.
machine# [   38.957550] audit: type=1130 audit(1554140026.016:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-modules-load comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   38.967555] systemd[1]: Mounted POSIX Message Queue File System.
machine# [   39.071341] systemd[1]: Starting Apply Kernel Variables...
machine# [   39.209514] systemd[1]: Starting Firewall...
machine# [   39.386860] systemd[1]: Starting Update UTMP about System Boot/Shutdown...
machine# [   39.583531] systemd[1]: Starting Load/Save Random Seed...
machine# [   40.469807] audit: type=1130 audit(1554140027.528:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-sysctl comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   40.656465] audit: type=1127 audit(1554140027.715:6): pid=426 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=' comm="systemd-update-utmp" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
machine# [   40.936260] audit: type=1130 audit(1554140027.995:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-random-seed comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   41.197870] audit: type=1130 audit(1554140028.256:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-utmp comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   41.409382] audit: type=1130 audit(1554140028.468:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   41.415865] audit: type=1325 audit(1554140028.453:10): table=filter family=2 entries=0
machine# [   41.427357] audit: type=1300 audit(1554140028.453:10): arch=c000003e syscall=175 success=yes exit=0 a0=1b7cc00 a1=1f10 a2=41c346 a3=0 items=0 ppid=109 pid=431 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/9p1j0gh1z3gahl1nvg637f96yb7djyg2-kmod-25/bin/kmod" subj==unconfined key=(null)
machine# [   44.148644] systemd[1]: Started Journal Service.
machine# [   44.184650] kauditd_printk_skb: 5 callbacks suppressed
machine# [   44.184787] audit: type=1130 audit(1554140031.244:13): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journald comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   44.541108] audit: type=1130 audit(1554140031.600:14): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udevd comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   44.613231] systemd-modules-load[420]: Failed to find module 'gcov-proc'
machine# [   44.699783] systemd-modules-load[420]: Inserted module 'loop'
machine# [   44.888360] audit: type=1130 audit(1554140031.948:15): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-update-done comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   44.877578] systemd[1]: Starting Flush Journal to Persistent Storage...
machine# [   44.980704] systemd[1]: Started udev Kernel Device Manager.
machine# [   45.055133] systemd[1]: Started Update is Completed.
machine# [   45.567746] systemd-journald[416]: Received request to flush runtime journal from PID 1
machine# [   46.991935] audit: type=1130 audit(1554140034.050:16): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-journal-flush comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   47.028292] systemd[1]: Started Flush Journal to Persistent Storage.
machine# [   47.093532] systemd[1]: Starting Create Volatile Files and Directories...
machine# [   48.408324] audit: type=1130 audit(1554140035.467:17): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   48.381394] systemd[1]: Started Create Volatile Files and Directories.
machine# [   48.492853] audit: type=1325 audit(1554140035.549:18): table=filter family=2 entries=4
machine# [   48.503037] audit: type=1300 audit(1554140035.549:18): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=1108900 items=0 ppid=425 pid=463 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   48.508086] audit: type=1327 audit(1554140035.549:18): proctitle=69707461626C6573002D77002D4E006E69786F732D66772D616363657074
machine# [   48.716373] audit: type=1325 audit(1554140035.772:19): table=filter family=10 entries=4
machine# [   48.720753] audit: type=1300 audit(1554140035.772:19): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=29 a2=40 a3=15f4a20 items=0 ppid=425 pid=464 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="ip6tables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   48.840177] systemd[1]: Started udev Coldplug all Devices.
machine# [   48.928541] systemd[1]: Starting udev Wait for Complete Device Initialization...
machine# [   49.224063] kauditd_printk_skb: 5 callbacks suppressed
machine# [   49.224077] audit: type=1325 audit(1554140036.277:22): table=filter family=10 entries=6
machine# [   49.230420] audit: type=1300 audit(1554140036.277:22): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=29 a2=40 a3=16ae440 items=0 ppid=425 pid=467 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="ip6tables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   49.232266] audit: type=1327 audit(1554140036.277:22): proctitle=6970367461626C6573002D77002D41006E69786F732D66772D616363657074002D6A00414343455054
machine# [   49.441205] audit: type=1325 audit(1554140036.499:23): table=filter family=2 entries=7
machine# [   49.446033] audit: type=1300 audit(1554140036.499:23): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=2103c50 items=0 ppid=425 pid=468 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   49.449777] audit: type=1327 audit(1554140036.499:23): proctitle=69707461626C6573002D77002D4E006E69786F732D66772D726566757365
machine# [   49.608589] audit: type=1325 audit(1554140036.667:24): table=filter family=10 entries=7
machine# [   49.612361] audit: type=1300 audit(1554140036.667:24): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=29 a2=40 a3=c1de50 items=0 ppid=425 pid=469 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="ip6tables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   49.614364] audit: type=1327 audit(1554140036.667:24): proctitle=6970367461626C6573002D77002D4E006E69786F732D66772D726566757365
machine# [   49.789742] audit: type=1325 audit(1554140036.848:25): table=filter family=2 entries=9
machine# [   52.277636] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
machine# [   52.359211] ACPI: Power Button [PWRF]
machine# [   53.968920] parport_pc 00:04: reported by Plug and Play ACPI
machine# [   54.031608] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
machine# [   54.413578] Floppy drive(s): fd0 is 2.88M AMI BIOS
machine# [   54.445822] FDC 0 is a S82078B
machine# [   54.483216] kauditd_printk_skb: 50 callbacks suppressed
machine# [   54.483303] audit: type=1325 audit(1554140041.541:42): table=raw family=10 entries=5
machine# [   54.489573] audit: type=1300 audit(1554140041.541:42): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=29 a2=40 a3=22fb0a0 items=0 ppid=425 pid=503 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="ip6tables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   54.492400] audit: type=1327 audit(1554140041.541:42): proctitle=6970367461626C6573002D77002D7400726177002D41006E69786F732D66772D727066696C746572002D6D00727066696C746572002D2D76616C69646D61726B002D6A0052455455524E
machine# [   54.707302] audit: type=1325 audit(1554140041.765:43): table=raw family=2 entries=6
machine# [   54.714418] audit: type=1300 audit(1554140041.765:43): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=16a6710 items=0 ppid=425 pid=505 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   54.720205] audit: type=1327 audit(1554140041.765:43): proctitle=69707461626C6573002D7400726177002D41006E69786F732D66772D727066696C746572002D7000756470002D2D73706F7274003637002D2D64706F7274003638002D6A0052455455524E
machine# [   54.931402] audit: type=1325 audit(1554140041.990:44): table=raw family=2 entries=7
machine# [   54.937349] audit: type=1300 audit(1554140041.990:44): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=0 a2=40 a3=1a4d7e0 items=0 ppid=425 pid=506 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   54.944450] audit: type=1327 audit(1554140041.990:44): proctitle=69707461626C6573002D7400726177002D41006E69786F732D66772D727066696C746572002D7300302E302E302E30002D64003235352E3235352E3235352E323535002D7000756470002D2D73706F7274003638002D2D64706F7274003637002D6A0052455455524E
machine# [   55.062728] Linux agpgart interface v0.103
machine# [   55.187946] audit: type=1325 audit(1554140042.242:45): table=raw family=2 entries=8
machine# [   58.073486] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
machine# [   59.579918] kauditd_printk_skb: 50 callbacks suppressed
machine# [   59.581074] audit: type=1325 audit(1554140046.636:62): table=filter family=10 entries=24
machine# [   59.585455] audit: type=1300 audit(1554140046.636:62): arch=c000003e syscall=54 success=yes exit=0 a0=4 a1=29 a2=40 a3=18805a0 items=0 ppid=425 pid=534 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="ip6tables" exe="/nix/store/ndqs999n0zxyc3aivxg0kl73hpmzkvmn-iptables-1.8.2/bin/xtables-legacy-multi" subj==unconfined key=(null)
machine# [   59.587281] audit: type=1327 audit(1554140046.636:62): proctitle=6970367461626C6573002D77002D4100494E505554002D6A006E69786F732D6677
machine# [   59.692862] audit: type=1130 audit(1554140046.752:63): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=firewall comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   59.682733] systemd[1]: Started Firewall.
machine# [   59.718053] systemd[1]: Reached target Network (Pre).
machine# [   60.619312] mousedev: PS/2 mouse device common for all mice
machine# [   61.389319] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
machine# [   61.721518] systemd[1]: Found device /dev/ttyS0.
machine# [   63.493642] systemd[1]: Found device Virtio network device.
machine# [   66.807520] [drm] Found bochs VGA, ID 0xb0c0.
machine# [   66.808422] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
machine# [   66.873982] [TTM] Zone  kernel: Available graphics memory: 183832 kiB
machine# [   66.874439] [TTM] Initializing pool allocator
machine# [   66.885466] [TTM] Initializing DMA pool allocator
machine# [   67.094759] fbcon: bochsdrmfb (fb0) is primary device
machine# [   67.196693] Console: switching to colour frame buffer device 128x48
machine# [   67.377530] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
machine# [   67.417508] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
machine# [   67.696439] systemd[1]: Found device /dev/hvc0.
machine# [   68.399276] ppdev: user-space parallel port driver
machine# [   73.089063] audit: type=1130 audit(1554140060.146:64): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=systemd-udev-settle comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   73.067603] systemd[1]: Started udev Wait for Complete Device Initialization.
machine# [   73.129765] systemd[1]: Reached target System Initialization.
machine# [   73.142750] systemd[1]: Started Daily Cleanup of Temporary Directories.
machine# [   73.169040] systemd[1]: Reached target Timers.
machine# [   73.189673] systemd[1]: Listening on D-Bus System Message Bus Socket.
machine# [   73.197510] systemd[1]: Listening on Nix Daemon Socket.
machine# [   73.206126] systemd[1]: Reached target Sockets.
machine# [   73.216571] systemd[1]: Reached target Basic System.
machine# [   73.258622] systemd[1]: Starting Kernel Auditing...
machine# [   73.322451] systemd[1]: Starting Address configuration of eth1...
machine# [   73.424267] systemd[1]: Starting Name Service Cache Daemon...
machine# [   73.551239] systemd[1]: Started backdoor.service.
machine# [   73.603366] audit: type=1130 audit(1554140060.661:65): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg='unit=backdoor comm="systemd" exe="/nix/store/rl4ky8x58ixnfjssliygq7iwyd30l3gn-systemd-239.20190219/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
machine# [   73.711539] systemd[1]: Starting DHCP Client...
machine# [   73.932611] systemd[1]: Starting Link configuration of eth1...
machine# [   74.693839] jm099mrhvm5hb9xfylwmp4311bz7kfl8-audit-disable[550]: No rules
machine# [   74.964472] qpqff87f2bjbxdmj1fbgwgv0lnk7iv94-unit-script-network-link-eth1-start[   75.004722] audit: type=1305 audit(1554140062.061:66): audit_enabled=0 old=1 auid=4294967295 ses=4294967295 subj==unconfined res=1
machine# [555]: Configuring link...
machine# [   75.172890] dhcpcd[554]: dev: loaded udev
machine# [   75.331612] systemd[1]: Started Kernel Auditing.
machine# [   75.522455] qpqff87f2bjbxdmj1fbgwgv0lnk7iv94-unit-script-network-link-eth1-start[555]: bringing up interface... done
machine# [   75.657140] 1d2zyhv2hbx24422l5vandspd6npg9xa-unit-script-network-addresses-eth1-start[551]: adding address 192.168.1.1/24... done
machine# [   75.683731] systemd[1]: Started Link configuration of eth1.
machine# [   75.704520] systemd[1]: Reached target All Network Interfaces (deprecated).
machine# [   75.902832] 8021q: 802.1Q VLAN Support v1.8
machine# [   75.903890] 8021q: adding VLAN 0 to HW filter on device eth1
machine# [   76.309393] systemd[1]: Started Address configuration of eth1.
machine# [   76.392822] nscd[580]: 580 monitoring file `/etc/passwd` (1)
machine# [   76.419595] nscd[580]: 580 monitoring directory `/etc` (2)
machine# [   76.450722] nscd[580]: 580 monitoring file `/etc/group` (3)
machine# [   76.480527] nscd[580]: 580 monitoring directory `/etc` (2)
machine# [   76.517663] nscd[580]: 580 monitoring file `/etc/hosts` (4)
machine# [   76.542744] nscd[580]: 580 monitoring directory `/etc` (2)
machine# [   76.589576] nscd[580]: 580 monitoring file `/etc/resolv.conf` (5)
machine# [   76.628628] nscd[580]: 580 monitoring directory `/etc` (2)
machine# [   76.701341] systemd[1]: Starting Networking Setup...
machine# [   76.733566] nscd[580]: 580 monitoring file `/etc/services` (6)
machine# [   76.792394] nscd[580]: 580 monitoring directory `/etc` (2)
machine# [   76.815718] nscd[580]: 580 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
machine# [   76.857063] nscd[580]: 580 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
machine# [   77.095744] systemd[1]: Started D-Bus System Message Bus.
machine# [   78.437664] dbus-daemon[587]: dbus[587]: Unknown username "systemd-timesync" in message bus configuration file
machine# [   78.688869] cfg80211: Loading compiled-in X.509 certificates for regulatory database
machine# [   79.343307] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
machine# [   79.702499] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
machine# [   79.724510] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
machine# [   79.725092] cfg80211: failed to load regulatory.db
machine# connecting to host...
machine: connected to guest root shell
machine: (connecting took 83.63 seconds)
(83.71 seconds)
machine# [   81.087647] systemd[1]: Started Name Service Cache Daemon.
machine# [   81.117630] systemd[1]: Reached target Host and Network Name Lookups.
machine# [   81.142093] systemd[1]: Reached target User and Group Name Lookups.
machine# [   81.264440] systemd[1]: Starting Accounts Service...
machine# sh: cannot set terminal process group (-1): Inappropriate ioctl for device
machine# sh: no job control in this shell
machine# [   81.519615] systemd[1]: Starting Login Service...
machine# [   83.819477] systemd-logind[633]: New seat seat0.
machine# [   84.083109] systemd-logind[633]: Watching system buttons on /dev/input/event2 (Power Button)
machine# [   84.132541] systemd-logind[633]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
machine: exit status 1
(87.16 seconds)
machine# [   84.550082] systemd[1]: Started Login Service.
machine# [   84.941416] systemd[1]: Starting X11 Server...
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [   85.377567] systemd[1]: Started Networking Setup.
machine# [   85.617517] systemd[1]: Starting Extra networking commands....
machine# [   85.762393] dbus-daemon[587]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.4' (uid=0 pid=632 comm="/nix/store/n114pl4pfph620kijvbl4yi2fnmmvw31-accoun")
machine# [   86.137611] systemd[1]: Starting Authorization Manager...
machine# [   86.326417] systemd[1]: Started X11 Server.
machine# [   86.486186] systemd[1]: Started Extra networking commands..
machine# [   87.470806] 8021q: adding VLAN 0 to HW filter on device eth0
machine: exit status 1
(2.32 seconds)
machine# [   87.722840] dhcpcd[554]: eth0: waiting for carrier
machine# [   87.842834] dhcpcd[554]: eth0: carrier acquired
machine# [   88.484867] dhcpcd[554]: DUID 00:01:00:01:24:35:08:2b:52:54:00:12:34:56
machine# [   88.488650] dhcpcd[554]: eth0: IAID 00:12:34:56
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [   88.504330] dhcpcd[554]: eth0: adding address fe80::5054:ff:fe12:3456
machine# [   88.637755] polkitd[685]: Started polkitd version 0.115
machine# [   89.132230] dbus-daemon[587]: [system] Activating via systemd: service name='org.freedesktop.Accounts' unit='accounts-daemon.service' requested by ':1.6' (uid=0 pid=687 comm="/nix/store/im18yk9wjy7g1dzs75162pfk8h5dsxal-lightd")
machine# [   89.217994] NET: Registered protocol family 17
machine# [   89.248335] dhcpcd[554]: eth0: soliciting a DHCP lease
machine# [   89.269274] dhcpcd[554]: eth0: soliciting an IPv6 router
machine# [   89.288112] dhcpcd[554]: eth0: offered 10.0.2.15 from 10.0.2.2
machine# [   89.315517] dhcpcd[554]: eth0: leased 10.0.2.15 for 86400 seconds
machine# [   89.356287] dhcpcd[554]: eth0: adding route to 10.0.2.0/24
machine# [   89.368411] dhcpcd[554]: eth0: adding default route via 10.0.2.2
machine: exit status 1
(1.78 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [   92.672391] polkitd[685]: Loading rules from directory /etc/polkit-1/rules.d
machine# [   92.781687] polkitd[685]: Loading rules from directory /run/current-system/sw/share/polkit-1/rules.d
machine: exit status 1
(1.50 seconds)
machine# [   92.811587] polkitd[685]: Finished loading, compiling and executing 2 rules
machine# [   92.959177] nscd[580]: 580 monitored file `/etc/resolv.conf` was written to
machine# [   92.999200] dbus-daemon[587]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
machine# [   93.066535] systemd[1]: Started Authorization Manager.
machine# [   93.090551] polkitd[685]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
machine# [   93.244645] accounts-daemon[632]: started daemon version 0.6.54
machine# [   93.271646] dbus-daemon[587]: [system] Successfully activated service 'org.freedesktop.Accounts'
machine# [   93.286141] systemd[1]: Started Accounts Service.
machine# [   93.588669] systemd[1]: Stopping Name Service Cache Daemon...
machine# [   93.714118] systemd[1]: Stopped Name Service Cache Daemon.
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [   93.945822] systemd[1]: Starting Name Service Cache Daemon...
machine# [   95.424544] dhcpcd[554]: Failed to try-reload-or-restart ntpd.service: Unit ntpd.service not found.
machine# [   95.530506] dhcpcd[554]: Failed to try-reload-or-restart openntpd.service: Unit openntpd.service not found.
machine# [   95.559063] dhcpcd[554]: Failed to try-reload-or-restart chronyd.service: Unit chronyd.service not found.
machine# [   95.633998] dhcpcd[554]: forked to background, child pid 771
machine: exit status 1
(1.98 seconds)
machine# [   95.835694] dhcpcd[771]: eth0: Router Advertisement from fe80::2
machine# [   95.871536] dhcpcd[771]: eth0: adding address fec0::5054:ff:fe12:3456/64
machine# [   95.908379] dhcpcd[771]: eth0: adding route to fec0::/64
machine# [   95.990039] dhcpcd[771]: eth0: adding default route via fe80::2
machine# [   96.059301] systemd[1]: Started DHCP Client.
machine# [   96.094877] systemd[1]: Reached target Network.
machine# [   96.119327] systemd[1]: Reached target Network is Online.
machine# [   96.258258] systemd[1]: Starting Permit User Sessions...
machine# [   96.343508] nscd[776]: 776 monitoring file `/etc/passwd` (1)
machine# [   96.372525] nscd[776]: 776 monitoring directory `/etc` (2)
machine# [   96.394718] nscd[776]: 776 monitoring file `/etc/group` (3)
machine# [   96.407485] nscd[776]: 776 monitoring directory `/etc` (2)
machine# [   96.430655] nscd[776]: 776 monitoring file `/etc/hosts` (4)
machine# [   96.450046] nscd[776]: 776 monitoring directory `/etc` (2)
machine# [   96.472867] nscd[776]: 776 monitoring file `/etc/resolv.conf` (5)
machine# [   96.496270] nscd[776]: 776 monitoring directory `/etc` (2)
machine# [   96.528179] nscd[776]: 776 monitoring file `/etc/services` (6)
machine# [   96.553206] nscd[776]: 776 monitoring directory `/etc` (2)
machine# [   96.566077] nscd[776]: 776 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
machine# [   96.591369] nscd[776]: 776 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [   97.108152] systemd[1]: Started Permit User Sessions.
machine# [   97.275416] systemd[1]: Started Getty on tty1.
machine# [   97.298426] systemd[1]: Reached target Login Prompts.
machine# [   97.317718] systemd[1]: Reached target Multi-User System.
machine# [   97.355646] systemd[1]: Reached target Graphical Interface.
machine# [   97.928552] systemd[1]: Started Name Service Cache Daemon.
machine# [   97.969485] systemd[1]: Startup finished in 32.800s (kernel) + 1min 5.160s (userspace) = 1min 37.960s.
machine: exit status 1
(2.13 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 1
(1.04 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 1
(0.75 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 1
(1.30 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 1
(0.85 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 1
(0.73 seconds)
machine# [  109.388041] lightdm[833]: pam_succeed_if(lightdm-autologin:auth): no condition detected; module succeeded
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [  109.876642] lightdm[833]: pam_unix(lightdm-autologin:session): session opened for user root by (uid=0)
machine: exit status 1
(1.10 seconds)
machine# [  111.071119] systemd-logind[633]: New session 1 of user root.
machine# [  111.175745] systemd[1]: Created slice user-0.slice.
machine# [  111.213354] systemd[1]: Started Session 1 of user root.
machine# [  111.246427] systemd[1]: Created slice system-user\x2druntime\x2ddir.slice.
machine# [  111.307330] systemd[1]: Started /run/user/0 mount wrapper.
machine# [  111.380155] systemd[1]: Starting User Manager for UID 0...
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [  111.718328] systemd[841]: pam_unix(systemd-user:session): session opened for user root by (uid=0)
machine: exit status 1
(1.11 seconds)
machine# [  113.416739] systemd[841]: Reached target Paths.
machine# [  113.448391] systemd[841]: Reached target Sockets.
machine# [  113.473526] systemd[841]: Reached target Timers.
machine# [  113.489096] systemd[841]: Reached target Basic System.
machine# [  113.518611] systemd[1]: Started User Manager for UID 0.
machine# [  113.526327] systemd[841]: Reached target Default.
machine# [  113.534573] systemd[841]: Startup finished in 1.111s.
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 1
(1.00 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine# [  115.976727] nscd[776]: 776 checking for monitored file `/etc/netgroup': No such file or directory
machine: exit status 1
(0.88 seconds)
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 1
(0.88 seconds)
machine# [  119.051577] systemd[841]: Reached target Current graphical user session.
machine: running command: journalctl -b SYSLOG_IDENTIFIER=systemd | grep 'Reached target Current graphical'
machine: exit status 0
(0.87 seconds)
machine: running command: [ -e /tmp/.X11-unix/X0 ]
machine: exit status 0
(0.05 seconds)
(123.46 seconds)
subtest: selenium can launch Firefox
machine: must succeed: test-selenium-firefox
machine# [  160.775168] hrtimer: interrupt took 3909714 ns
machine# Traceback (most recent call last):
machine#   File "/run/current-system/sw/bin/test-selenium-firefox", line 3, in <module>
machine#     webdriver.Firefox()
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/firefox/webdriver.py", line 174, in __init__
machine#     keep_alive=True)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/webdriver.py", line 157, in __init__
machine#     self.start_session(capabilities, browser_profile)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/webdriver.py", line 252, in start_session
machine#     response = self.execute(Command.NEW_SESSION, parameters)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/webdriver.py", line 321, in execute
machine#     self.error_handler.check_response(response)
machine#   File "/nix/store/n8n269gcgsp2xsl6rdf5k466cvn5kpf7-python3-3.7.2-env/lib/python3.7/site-packages/selenium/webdriver/remote/errorhandler.py", line 242, in check_response
machine#     raise exception_class(message, screen, stacktrace)
machine# selenium.common.exceptions.WebDriverException: Message: connection refused
machine#
machine: exit status 1
machine: output:
(77.21 seconds)
error: command `test-selenium-firefox' did not succeed (exit code 1)
(77.21 seconds)
(200.66 seconds)
collecting coverage data
machine: running command: test -e /sys/kernel/debug/gcov
machine: exit status 1
(0.04 seconds)
(0.04 seconds)
syncing
machine: running command: sync
machine: exit status 0
(0.27 seconds)
(0.27 seconds)
0 out of 1 tests succeeded
test script finished in 200.97s
cleaning up
killing machine (pid 594)
(0.00 seconds)
vde_switch: EOF on stdin, cleaning up and exiting
vde_switch: Could not remove ctl dir '/build/vde1.ctl': Directory not empty
builder for '/nix/store/35a10mqlr3g6w0x681ldgrgryc1shas3-vm-test-run-unnamed.drv' failed with exit code 1
error: build of '/nix/store/35a10mqlr3g6w0x681ldgrgryc1shas3-vm-test-run-unnamed.drv' failed

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment