Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600 seconds run the VM test script additionally exposed symbols: server, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh server: starting vm server # Disk image does not exist, creating the virtualisation disk image... server # Formatting '/build/vm-state-server/tmp.b31hZOvh8A', fmt=raw size=1073741824 server: QEMU running (pid 12) server # mke2fs 1.47.4 (6-Mar-2025) server # Discarding device blocks: 0/262144 done server # Creating filesystem with 262144 4k blocks and 65536 inodes server # Filesystem UUID: 0fdf371b-4821-45df-a2d2-ea36feac81d9 server # Superblock backups stored on blocks: server # 32768, 98304, 163840, 229376 server # server # Allocating group tables: 0/8 done server # Writing inode tables: 0/8 done server # Creating journal (8192 blocks): done server # Writing superblocks and filesystem accounting information: 0/8 done server # server # Virtualisation disk image created. server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org) server # server # server # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1850+3EF31850 CA00 server # Press Ctrl-B to configure iPXE (PCI 00:03.0)... server # server # server # server # server # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1850 3EF31850 CB00 server # Press Ctrl-B to configure iPXE (PCI 00:09.0)... server # server # server # Booting from ROM... server # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Jul 4 11:44:22 UTC 2026 server # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/blggjpr5bzlyj6ys4s1icm9s95hzz3zp-nixos-system-server-test/init regInfo=/nix/store/a3faqpg9m9fr095dwkbfn8spzr65gr5l-closure-info/registration console=ttyS0,115200n8 console=tty0 server # [ 0.000000] x86/split lock detection: #DB: warning on user-space bus_locks server # [ 0.000000] BIOS-provided physical RAM map: server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable server # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved server # [ 0.000000] NX (Execute Disable) protection: active server # [ 0.000000] APIC: Static calls initialized server # [ 0.000000] SMBIOS 2.8 present. server # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014 server # [ 0.000000] DMI: Memory slots populated: 1/1 server # [ 0.000000] Hypervisor detected: KVM server # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x10000000000 server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 server # [ 0.000001] kvm-clock: using sched offset of 465619095 cycles server # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns server # [ 0.000008] tsc: Detected 2100.000 MHz processor server # [ 0.001414] last_pfn = 0x3ffdb max_arch_pfn = 0x10000000000 server # [ 0.001466] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs server # [ 0.001470] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT server # [ 0.013411] found SMP MP-table at [mem 0x000f5470-0x000f547f] server # [ 0.013434] Using GB pages for direct mapping server # [ 0.013603] RAMDISK: [mem 0x3e4ba000-0x3ffcffff] server # [ 0.013613] ACPI: Early table checksum verification disabled server # [ 0.013620] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS ) server # [ 0.013628] ACPI: RSDT 0x000000003FFE23CC 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.013640] ACPI: FACP 0x000000003FFE2280 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.013654] ACPI: DSDT 0x000000003FFE0040 002240 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.013659] ACPI: FACS 0x000000003FFE0000 000040 server # [ 0.013664] ACPI: APIC 0x000000003FFE22F4 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.013668] ACPI: HPET 0x000000003FFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.013673] ACPI: WAET 0x000000003FFE23A4 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) server # [ 0.013676] ACPI: Reserving FACP table memory at [mem 0x3ffe2280-0x3ffe22f3] server # [ 0.013678] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe227f] server # [ 0.013679] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f] server # [ 0.013680] ACPI: Reserving APIC table memory at [mem 0x3ffe22f4-0x3ffe236b] server # [ 0.013681] ACPI: Reserving HPET table memory at [mem 0x3ffe236c-0x3ffe23a3] server # [ 0.013682] ACPI: Reserving WAET table memory at [mem 0x3ffe23a4-0x3ffe23cb] server # [ 0.014076] No NUMA configuration found server # [ 0.014077] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff] server # [ 0.014081] NODE_DATA(0) allocated [mem 0x3ffd5780-0x3ffdacff] server # [ 0.016267] Zone ranges: server # [ 0.016268] DMA [mem 0x0000000000001000-0x0000000000ffffff] server # [ 0.016271] DMA32 [mem 0x0000000001000000-0x000000003ffdafff] server # [ 0.016273] Normal empty server # [ 0.016274] Device empty server # [ 0.016275] Movable zone start for each node server # [ 0.016276] Early memory node ranges server # [ 0.016277] node 0: [mem 0x0000000000001000-0x000000000009efff] server # [ 0.016278] node 0: [mem 0x0000000000100000-0x000000003ffdafff] server # [ 0.016280] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff] server # [ 0.016329] On node 0, zone DMA: 1 pages in unavailable ranges server # [ 0.016572] On node 0, zone DMA: 97 pages in unavailable ranges server # [ 0.033007] On node 0, zone DMA32: 37 pages in unavailable ranges server # [ 0.033758] ACPI: PM-Timer IO Port: 0x608 server # [ 0.033784] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) server # [ 0.033848] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 server # [ 0.033853] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) server # [ 0.033856] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) server # [ 0.033857] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) server # [ 0.033858] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) server # [ 0.033860] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) server # [ 0.033866] ACPI: Using ACPI (MADT) for SMP configuration information server # [ 0.033868] ACPI: HPET id: 0x8086a201 base: 0xfed00000 server # [ 0.033874] TSC deadline timer available server # [ 0.033879] CPU topo: Max. logical packages: 1 server # [ 0.033880] CPU topo: Max. logical dies: 1 server # [ 0.033881] CPU topo: Max. dies per package: 1 server # [ 0.033886] CPU topo: Max. threads per core: 1 server # [ 0.033887] CPU topo: Num. cores per package: 1 server # [ 0.033887] CPU topo: Num. threads per package: 1 server # [ 0.033888] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs server # [ 0.033919] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() server # [ 0.033959] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] server # [ 0.033961] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] server # [ 0.033963] [mem 0x40000000-0xfeffbfff] available for PCI devices server # [ 0.033965] Booting paravirtualized kernel on KVM server # [ 0.033969] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns server # [ 0.042230] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 server # [ 0.044291] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152 server # [ 0.044352] kvm-guest: PV spinlocks disabled, single CPU server # [ 0.044355] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/blggjpr5bzlyj6ys4s1icm9s95hzz3zp-nixos-system-server-test/init regInfo=/nix/store/a3faqpg9m9fr095dwkbfn8spzr65gr5l-closure-info/registration console=ttyS0,115200n8 console=tty0 server # [ 0.044516] Unknown kernel command line parameters "regInfo=/nix/store/a3faqpg9m9fr095dwkbfn8spzr65gr5l-closure-info/registration", will be passed to user space. server # [ 0.044530] random: crng init done server # [ 0.044531] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes server # [ 0.044585] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) server # [ 0.045109] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) server # [ 0.045179] Fallback order for Node 0: 0 server # [ 0.045183] Built 1 zonelists, mobility grouping on. Total pages: 262009 server # [ 0.045184] Policy zone: DMA32 server # [ 0.047237] mem auto-init: stack:all(zero), heap alloc:on, heap free:off server # [ 0.051274] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 server # [ 0.053544] allocated 2097152 bytes of page_ext server # [ 0.065455] ftrace: allocating 48598 entries in 192 pages server # [ 0.065457] ftrace: allocated 192 pages with 2 groups server # [ 0.066372] Dynamic Preempt: lazy server # [ 0.066538] rcu: Preemptible hierarchical RCU implementation. server # [ 0.066538] rcu: RCU event tracing is enabled. server # [ 0.066539] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. server # [ 0.066541] Trampoline variant of Tasks RCU enabled. server # [ 0.066542] Rude variant of Tasks RCU enabled. server # [ 0.066542] Tracing variant of Tasks RCU enabled. server # [ 0.066543] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. server # [ 0.066544] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 server # [ 0.066607] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.066609] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.066611] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. server # [ 0.073591] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16 server # [ 0.073935] rcu: srcu_init: Setting srcu_struct sizes based on contention. server # [ 0.074115] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) server # [ 0.080465] Console: colour VGA+ 80x25 server # [ 0.080469] printk: legacy console [tty0] enabled server # [ 0.123646] printk: legacy console [ttyS0] enabled server # [ 0.247131] ACPI: Core revision 20250807 server # [ 0.248293] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns server # [ 0.250582] APIC: Switch to symmetric I/O mode setup server # [ 0.252023] x2apic enabled server # [ 0.253098] APIC: Switched APIC routing to: physical x2apic server # [ 0.255690] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 server # [ 0.257001] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1e4530a99b6, max_idle_ns: 440795257976 ns server # [ 0.259251] Calibrating delay loop (skipped) preset value.. 4200.00 BogoMIPS (lpj=2100000) server # [ 0.261459] x86/cpu: User Mode Instruction Prevention (UMIP) activated server # [ 0.263423] CET detected: Indirect Branch Tracking enabled server # [ 0.264251] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 server # [ 0.265249] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 server # [ 0.267262] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto server # [ 0.268249] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl server # [ 0.269249] Spectre V2 : Mitigation: Enhanced / Automatic IBRS server # [ 0.270249] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization server # [ 0.272251] Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT server # [ 0.273249] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier server # [ 0.274299] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' server # [ 0.275249] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' server # [ 0.277184] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' server # [ 0.278249] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' server # [ 0.280249] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' server # [ 0.281249] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' server # [ 0.282249] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' server # [ 0.284249] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' server # [ 0.285249] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)' server # [ 0.287249] x86/fpu: Supporting XSAVE feature 0x20000: 'AMX Tile config' server # [ 0.289249] x86/fpu: Supporting XSAVE feature 0x40000: 'AMX Tile data' server # [ 0.290249] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 server # [ 0.292119] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 server # [ 0.293249] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 server # [ 0.294249] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 server # [ 0.295249] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 server # [ 0.296249] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16 server # [ 0.298249] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24 server # [ 0.299249] x86/fpu: xstate_offset[17]: 2496, xstate_sizes[17]: 64 server # [ 0.300249] x86/fpu: xstate_offset[18]: 2560, xstate_sizes[18]: 8192 server # [ 0.301249] x86/fpu: Enabled xstate features 0x61ae7, context size is 10752 bytes, using 'compacted' format. server # [ 0.336019] Freeing SMP alternatives memory: 44K server # [ 0.336900] pid_max: default: 32768 minimum: 301 server # [ 0.337993] LSM: initializing lsm=capability,landlock,yama,bpf,ima server # [ 0.339316] landlock: Up and running. server # [ 0.340076] Yama: becoming mindful. server # [ 0.340941] LSM support for eBPF active server # [ 0.341882] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.343264] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) server # [ 0.345945] smpboot: CPU0: Intel(R) Xeon(R) Gold 5412U (family: 0x6, model: 0x8f, stepping: 0x8) server # [ 0.346713] Performance Events: PEBS fmt0-, Sapphire Rapids events, full-width counters, Intel PMU driver. server # [ 0.348253] ... version: 2 server # [ 0.349125] ... bit width: 48 server # [ 0.349910] ... generic counters: 8 server # [ 0.350250] ... generic bitmap: 00000000000000ff server # [ 0.351250] ... fixed-purpose counters: 3 server # [ 0.352150] ... fixed-purpose bitmap: 0000000000000007 server # [ 0.353079] ... value mask: 0000ffffffffffff server # [ 0.354052] ... max period: 00007fffffffffff server # [ 0.355079] ... global_ctrl mask: 00000007000000ff server # [ 0.356282] signal: max sigframe size: 11952 server # [ 0.357245] rcu: Hierarchical SRCU implementation. server # [ 0.358245] rcu: Max phase no-delay instances is 400. server # [ 0.360032] smp: Bringing up secondary CPUs ... server # [ 0.360948] smp: Brought up 1 node, 1 CPU server # [ 0.361251] smpboot: Total of 1 processors activated (4200.00 BogoMIPS) server # [ 0.362396] Memory: 942408K/1048036K available (17159K kernel code, 2721K rwdata, 13560K rodata, 3636K init, 3020K bss, 98260K reserved, 0K cma-reserved) server # [ 0.363412] devtmpfs: initialized server # [ 0.364303] x86/mm: Memory block size: 128MB server # [ 0.365919] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns server # [ 0.367271] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 0.368269] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). server # [ 0.369332] pinctrl core: initialized pinctrl subsystem server # [ 0.370506] PM: RTC time: 21:16:17, date: 2026-10-07 server # [ 0.373742] NET: Registered PF_NETLINK/PF_ROUTE protocol family server # [ 0.374560] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations server # [ 0.375270] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations server # [ 0.376379] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations server # [ 0.377259] audit: initializing netlink subsys (disabled) server # [ 0.378493] thermal_sys: Registered thermal governor 'fair_share' server # [ 0.378495] thermal_sys: Registered thermal governor 'bang_bang' server # [ 0.379253] audit: type=2000 audit(1791407778.431:1): state=initialized audit_enabled=0 res=1 server # [ 0.381251] thermal_sys: Registered thermal governor 'step_wise' server # [ 0.381252] thermal_sys: Registered thermal governor 'user_space' server # [ 0.382250] thermal_sys: Registered thermal governor 'power_allocator' server # [ 0.383261] cpuidle: using governor menu server # [ 0.385189] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 server # [ 0.386449] PCI: Using configuration type 1 for base access server # [ 0.387420] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. server # [ 0.394328] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages server # [ 0.395251] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page server # [ 0.400251] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages server # [ 0.401251] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page server # [ 0.409017] ACPI: Added _OSI(Module Device) server # [ 0.414250] ACPI: Added _OSI(Processor Device) server # [ 0.415140] ACPI: Added _OSI(Processor Aggregator Device) server # [ 0.421206] ACPI: 1 ACPI AML tables successfully acquired and loaded server # [ 0.426714] ACPI: Interpreter enabled server # [ 0.427263] ACPI: PM: (supports S0 S3 S4 S5) server # [ 0.428164] ACPI: Using IOAPIC for interrupt routing server # [ 0.429013] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug server # [ 0.435250] PCI: Using E820 reservations for host bridge windows server # [ 0.436352] ACPI: Enabled 2 GPEs in block 00 to 0F server # [ 0.444000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) server # [ 0.445164] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] server # [ 0.448258] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] server # [ 0.449261] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge server # [ 0.452474] acpiphp: Slot [3] registered server # [ 0.453275] acpiphp: Slot [4] registered server # [ 0.454128] acpiphp: Slot [5] registered server # [ 0.457301] acpiphp: Slot [6] registered server # [ 0.458166] acpiphp: Slot [7] registered server # [ 0.458876] acpiphp: Slot [8] registered server # [ 0.461270] acpiphp: Slot [9] registered server # [ 0.462137] acpiphp: Slot [10] registered server # [ 0.462846] acpiphp: Slot [11] registered server # [ 0.465284] acpiphp: Slot [12] registered server # [ 0.466134] acpiphp: Slot [13] registered server # [ 0.466876] acpiphp: Slot [14] registered server # [ 0.469270] acpiphp: Slot [15] registered server # [ 0.470147] acpiphp: Slot [16] registered server # [ 0.470870] acpiphp: Slot [17] registered server # [ 0.473269] acpiphp: Slot [18] registered server # [ 0.474119] acpiphp: Slot [19] registered server # [ 0.474841] acpiphp: Slot [20] registered server # [ 0.477027] acpiphp: Slot [21] registered server # [ 0.477873] acpiphp: Slot [22] registered server # [ 0.478274] acpiphp: Slot [23] registered server # [ 0.479147] acpiphp: Slot [24] registered server # [ 0.479846] acpiphp: Slot [25] registered server # [ 0.480266] acpiphp: Slot [26] registered server # [ 0.481147] acpiphp: Slot [27] registered server # [ 0.481875] acpiphp: Slot [28] registered server # [ 0.482267] acpiphp: Slot [29] registered server # [ 0.483133] acpiphp: Slot [30] registered server # [ 0.483877] acpiphp: Slot [31] registered server # [ 0.484290] PCI host bridge to bus 0000:00 server # [ 0.485134] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] server # [ 0.486239] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] server # [ 0.487240] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] server # [ 0.488250] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window] server # [ 0.489250] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] server # [ 0.490251] pci_bus 0000:00: root bus resource [bus 00-ff] server # [ 0.491374] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint server # [ 0.492834] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint server # [ 0.494807] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint server # [ 0.496293] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef] server # [ 0.497325] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk server # [ 0.498250] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk server # [ 0.499250] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk server # [ 0.500250] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk server # [ 0.501478] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint server # [ 0.503293] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f] server # [ 0.504993] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint server # [ 0.506653] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI server # [ 0.507259] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB server # [ 0.508507] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint server # [ 0.511352] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] server # [ 0.512273] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] server # [ 0.513284] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref] server # [ 0.514509] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] server # [ 0.516407] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 0.519263] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f] server # [ 0.520260] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] server # [ 0.521284] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] server # [ 0.522259] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref] server # [ 0.524796] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 0.527263] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f] server # [ 0.528260] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] server # [ 0.529284] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] server # [ 0.532584] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint server # [ 0.535262] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf] server # [ 0.536267] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] server # [ 0.537574] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] server # [ 0.539566] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint server # [ 0.542264] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f] server # [ 0.543260] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff] server # [ 0.544284] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref] server # [ 0.546559] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint server # [ 0.549264] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f] server # [ 0.550259] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff] server # [ 0.551292] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref] server # [ 0.553876] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint server # [ 0.557281] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f] server # [ 0.558386] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff] server # [ 0.559284] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref] server # [ 0.561873] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint server # [ 0.565190] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf] server # [ 0.566059] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff] server # [ 0.567176] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref] server # [ 0.568259] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref] server # [ 0.570514] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint server # [ 0.572312] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff] server # [ 0.573284] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref] server # [ 0.575563] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint server # [ 0.578265] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff] server # [ 0.579260] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff] server # [ 0.580284] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref] server # [ 0.582615] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint server # [ 0.585129] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df] server # [ 0.586030] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff] server # [ 0.587284] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref] server # [ 0.593095] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 server # [ 0.594259] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 server # [ 0.595348] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 server # [ 0.596353] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 server # [ 0.597303] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 server # [ 0.599459] iommu: Default domain type: Translated server # [ 0.600257] iommu: DMA domain TLB invalidation policy: lazy mode server # [ 0.601595] ACPI: bus type USB registered server # [ 0.602311] usbcore: registered new interface driver usbfs server # [ 0.603267] usbcore: registered new interface driver hub server # [ 0.604263] usbcore: registered new device driver usb server # [ 0.606284] NetLabel: Initializing server # [ 0.607059] NetLabel: domain hash size = 128 server # [ 0.607921] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO server # [ 0.608313] NetLabel: unlabeled traffic allowed by default server # [ 0.609261] PCI: Using ACPI for IRQ routing server # [ 0.610701] pci 0000:00:02.0: vgaarb: setting as boot VGA device server # [ 0.611247] pci 0000:00:02.0: vgaarb: bridge control possible server # [ 0.611247] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none server # [ 0.611251] vgaarb: loaded server # [ 0.612137] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 server # [ 0.612992] hpet0: 3 comparators, 64-bit 100.000000 MHz counter server # [ 0.616329] clocksource: Switched to clocksource kvm-clock server # [ 0.618080] VFS: Disk quotas dquot_6.6.0 server # [ 0.620739] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) server # [ 0.622454] pnp: PnP ACPI init server # [ 0.623645] pnp: PnP ACPI: found 6 devices server # [ 0.630948] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns server # [ 0.632750] clocksource: Switched to clocksource acpi_pm server # [ 0.634005] NET: Registered PF_INET protocol family server # [ 0.635252] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) server # [ 0.657013] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) server # [ 0.658943] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) server # [ 0.660624] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) server # [ 0.662265] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) server # [ 0.663844] TCP: Hash tables configured (established 8192 bind 8192) server # [ 0.665295] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) server # [ 0.666918] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 0.668337] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) server # [ 0.669869] NET: Registered PF_UNIX/PF_LOCAL protocol family server # [ 0.671094] NET: Registered PF_XDP protocol family server # [ 0.672134] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] server # [ 0.673446] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] server # [ 0.674711] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] server # [ 0.676095] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window] server # [ 0.677579] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] server # [ 0.679081] pci 0000:00:01.0: PIIX3: Enabling Passive Release server # [ 0.680296] pci 0000:00:00.0: Limiting direct PCI/PCI transfers server # [ 0.682614] ACPI: \_SB_.LNKD: Enabled at IRQ 11 server # [ 0.685203] PCI: CLS 0 bytes, default 64 server # [ 0.686273] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e4530a99b6, max_idle_ns: 440795257976 ns server # [ 0.688451] Trying to unpack rootfs image as initramfs... server # [ 0.746827] Initialise system trusted keyrings server # [ 0.751406] workingset: timestamp_bits=40 max_order=18 bucket_order=0 server # [ 0.774392] Key type asymmetric registered server # [ 0.777355] Asymmetric key parser 'x509' registered server # [ 0.778511] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) server # [ 0.783497] io scheduler mq-deadline registered server # [ 0.787337] io scheduler kyber registered server # [ 0.789016] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled server # [ 0.792676] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A server # [ 0.798260] Linux agpgart interface v0.103 server # [ 0.799229] ACPI: bus type drm_connector registered server # [ 0.803821] usbcore: registered new interface driver usbserial_generic server # [ 0.805191] usbserial: USB Serial support registered for generic server # [ 0.808368] intel_pstate: CPU model not supported server # [ 0.809591] drop_monitor: Initializing network drop monitor service server # [ 0.813513] NET: Registered PF_INET6 protocol family server # [ 0.817777] Segment Routing with IPv6 server # [ 0.818638] In-situ OAM (IOAM) with IPv6 server # [ 0.821744] IPI shorthand broadcast: enabled server # [ 0.829693] sched_clock: Marking stable (649025241, 180304483)->(917480248, -88150524) server # [ 0.833434] registered taskstats version 1 server # [ 0.834519] Loading compiled-in X.509 certificates server # [ 0.848713] Demotion targets for Node 0: null server # [ 0.852402] Key type .fscrypt registered server # [ 0.853261] Key type fscrypt-provisioning registered server # [ 0.854445] ima: No TPM chip found, activating TPM-bypass! server # [ 0.855616] ima: Allocated hash algorithm: sha1 server # [ 0.860413] ima: No architecture policies found server # [ 0.861601] PM: Magic number: 14:498:297 server # [ 0.866052] RAS: Correctable Errors collector initialized. server # [ 0.881937] clk: Disabling unused clocks server # [ 0.884347] PM: genpd: Disabling unused power domains server # [ 1.096541] Freeing initrd memory: 27736K server # [ 1.118707] Freeing unused decrypted memory: 2028K server # [ 1.122148] Freeing unused kernel image (initmem) memory: 3636K server # [ 1.123535] Write protecting the kernel read-only data: 32768k server # [ 1.125979] Freeing unused kernel image (text/rodata gap) memory: 1272K server # [ 1.128057] Freeing unused kernel image (rodata/data gap) memory: 776K server # [ 1.141746] x86/mm: Checked W+X mappings: passed, no W+X pages found. server # [ 1.143089] Run /init as init process server # [ 1.156089] systemd[1]: Inserted module 'autofs4' server # [ 1.179163] fuse: init (API version 7.45) server # [ 1.188109] ACPI: \_SB_.LNKC: Enabled at IRQ 10 server # [ 1.194728] ACPI: \_SB_.LNKA: Enabled at IRQ 10 server # [ 1.198061] ACPI: \_SB_.LNKB: Enabled at IRQ 11 server # [ 1.229389] systemd[1]: Successfully made /usr/ read-only. server # [ 1.566563] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 1.573240] systemd[1]: Detected virtualization kvm. server # [ 1.574333] systemd[1]: Detected architecture x86-64. server # [ 1.575388] systemd[1]: Running in initrd. server # [ 1.576727] systemd[1]: Initializing machine ID from random generator. server # [ 1.578199] systemd[1]: Hostname set to . server # [ 1.636217] systemd[1]: Queued start job for default target Initrd Default Target. server # [ 1.640870] systemd[1]: Created slice Slice /system/modprobe. server # [ 1.642401] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 1.644282] systemd[1]: Expecting device /dev/disk/by-label/nixos... server # [ 1.645696] systemd[1]: Reached target Path Units. server # [ 1.646886] systemd[1]: Reached target Slice Units. server # [ 1.648015] systemd[1]: Reached target Swaps. server # [ 1.649034] systemd[1]: Reached target Timer Units. server # [ 1.650610] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 1.652144] systemd[1]: Listening on Journal Socket (/dev/log). server # [ 1.653579] systemd[1]: Listening on Journal Sockets. server # [ 1.654827] systemd[1]: Listening on udev Control Socket. server # [ 1.656076] systemd[1]: Listening on udev Kernel Socket. server # [ 1.657245] systemd[1]: Reached target Socket Units. server # [ 1.659536] systemd[1]: Starting Create List of Static Device Nodes... server # [ 1.666463] systemd[1]: Starting Load Kernel Module 9pnet_virtio... server # [ 1.677145] systemd[1]: Starting Load Kernel Module configfs... server # [ 1.694030] systemd[1]: Starting Journal Service... server # [ 1.727429] netfs: FS-Cache loaded server # [ 1.735677] systemd[1]: Starting Load Kernel Modules... server # [ 1.745453] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 1.749276] 9pnet: Installing 9P2000 support server # [ 1.761448] systemd[1]: Starting Coldplug All udev Devices... server # [ 1.773955] systemd-journald[67]: Collecting audit messages is disabled. server # [ 1.784429] systemd[1]: Finished Create List of Static Device Nodes. server # [ 1.792611] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. server # [ 1.800855] systemd[1]: Finished Load Kernel Module 9pnet_virtio. server # [ 1.807883] systemd[1]: modprobe@configfs.service: Deactivated successfully. server # [ 1.815845] systemd[1]: Finished Load Kernel Module configfs. server # [ 1.822977] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config server # [ 1.832965] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. server # [ 1.838581] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 1.845549] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev server # [ 1.888962] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 1.898482] systemd[1]: Finished Load Kernel Modules. server # [ 1.904602] systemd[1]: Starting Apply Kernel Variables... server # [ 1.917572] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 1.752535] systemd-modules-load[68]: Inserted module 'dm_mod' server # [ 1.934593] systemd[1]: Started Journal Service. server # [ 1.755114] systemd-modules-load[68]: Inserted module 'virtio_balloon' server # [ 1.756403] systemd-modules-load[68]: Inserted module 'virtio_gpu' server # [ 1.786777] systemd[1]: Finished Apply Kernel Variables. server # [ 1.796185] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 1.797337] systemd[1]: Reached target Preparation for Local File Systems. server # [ 1.798434] systemd[1]: Reached target Local File Systems. server # [ 1.800762] systemd[1]: Starting Create System Files and Directories... server # [ 1.808220] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 1.840587] systemd[1]: Finished Create System Files and Directories. server # [ 1.869365] systemd-udevd[83]: Using default interface naming scheme 'v260'. server # [ 1.876954] systemd[1]: Finished Coldplug All udev Devices. server # [ 1.892093] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 1.893329] systemd[1]: Reached target System Initialization. server # [ 1.894279] systemd[1]: Reached target Basic System. server # [ 2.306286] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 server # [ 2.336015] virtio_blk virtio5: 1/0/0 default/read/poll queues server # [ 2.346033] serio: i8042 KBD port at 0x60,0x64 irq 1 server # [ 2.346929] serio: i8042 AUX port at 0x60,0x64 irq 12 server # [ 2.361163] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) server # [ 2.402187] uhci_hcd 0000:00:01.2: UHCI Host Controller server # [ 2.403084] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 server # [ 2.419401] uhci_hcd 0000:00:01.2: detected 2 ports server # [ 2.427183] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100 server # [ 2.437028] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18 server # [ 2.438364] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 server # [ 2.260866] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line. server # [ 2.267479] (udev-worker)[88]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 2.269915] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line. server # [ 2.459734] SCSI subsystem initialized server # [ 2.280653] systemd[1]: Starting Virtual Console Setup... server # [ 2.477090] usb usb1: Product: UHCI Host Controller server # [ 2.481371] usb usb1: Manufacturer: Linux 6.18.38 uhci_hcd server # [ 2.494859] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 server # [ 2.500338] usb usb1: SerialNumber: 0000:00:01.2 server # [ 2.322407] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 2.327121] systemd[1]: Finished Virtual Console Setup. server # [ 2.514484] hub 1-0:1.0: USB hub found server # [ 2.521102] hub 1-0:1.0: 2 ports detected server # [ 2.348090] systemd[1]: Found device /dev/disk/by-label/nixos. server # [ 2.349108] systemd[1]: Reached target Initrd Root Device. server # [ 2.351541] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... server # [ 2.389554] systemd-fsck[106]: nixos: clean, 12/65536 files, 13019/262144 blocks server # [ 2.400068] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. server # [ 2.603075] scsi host0: ata_piix server # [ 2.604106] scsi host1: ata_piix server # [ 2.605497] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0 server # [ 2.608009] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0 server # [ 2.758568] usb 1-1: new full-speed USB device number 2 using uhci_hcd server # [ 2.766539] ata2: found unknown device (class 0) server # [ 2.767930] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 server # [ 2.771106] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 server # [ 2.597990] systemd[1]: Mounting /sysroot... server # [ 2.819035] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray server # [ 2.832698] cdrom: Uniform CD-ROM driver Revision: 3.20 server # [ 2.926912] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 server # [ 2.928330] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 server # [ 2.929869] usb 1-1: Product: QEMU USB Tablet server # [ 2.931600] usb 1-1: Manufacturer: QEMU server # [ 2.933161] usb 1-1: SerialNumber: 28754-0000:00:01.2-1 server # [ 2.942235] EXT4-fs (vda): mounted filesystem 0fdf371b-4821-45df-a2d2-ea36feac81d9 r/w with ordered data mode. Quota mode: none. server # [ 2.768214] systemd[1]: Mounted /sysroot. server # [ 2.769702] systemd[1]: Reached target Initrd Root File System. server # [ 2.778111] systemd[1]: Mounting /sysroot/nix/.ro-store... server # [ 2.785115] systemd[1]: Mounting /sysroot/nix/.rw-store... server # [ 2.794115] systemd[1]: Mounting /sysroot/run... server # [ 2.984824] hid: raw HID events driver (C) Jiri Kosina server # [ 2.806604] systemd[1]: Mounting /sysroot/tmp/shared... server # [ 2.819202] systemd[1]: Mounting /sysroot/tmp/xchg... server # [ 3.012980] usbcore: registered new interface driver usbhid server # [ 2.833939] systemd[1]: Starting Mountpoints Configured in the Real Root... server # [ 3.018107] usbhid: USB HID core driver server # [ 3.027945] 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 server # [ 3.032616] 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 server # [ 2.882438] systemd[1]: Mounted /sysroot/nix/.rw-store. server # [ 3.066008] 9p: Installing v9fs 9p2000 file system support server # [ 2.907637] systemd-sysroot-fstab-check[130]: /sysroot should be mounted in the initrd, will request daemon-reload. server # [ 2.911985] systemd[1]: Mounted /sysroot/nix/.ro-store. server # [ 2.915228] systemd[1]: Mounted /sysroot/run. server # [ 2.916802] systemd[1]: Mounted /sysroot/tmp/shared. server # [ 2.917930] systemd[1]: Mounted /sysroot/tmp/xchg. server # [ 2.923412] systemd[1]: Reload requested from client PID 130 ('systemd-sysroot') (unit initrd-parse-etc.service)... server # [ 2.926207] systemd[1]: Reloading... server # [ 3.010442] systemd[1]: Reloading finished in 85 ms. server # [ 3.017408] systemd-sysroot-fstab-check[130]: Requesting initrd-fs.target/start/replace... server # [ 3.021157] systemd-sysroot-fstab-check[130]: Requesting swap.target/start/replace... server # [ 3.023133] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 3.027614] systemd[1]: initrd-parse-etc.service: Deactivated successfully. server # [ 3.029337] systemd[1]: Finished Mountpoints Configured in the Real Root. server # [ 3.030508] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. server # [ 3.041777] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 3.043902] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 3.600067] systemd[1]: Mounting /sysroot/nix/store... server # [ 3.627495] systemd[1]: Mounted /sysroot/nix/store. server # [ 3.630061] systemd[1]: Reached target Initrd File Systems. server # [ 3.632117] systemd[1]: Starting Find NixOS closure... server # [ 3.634404] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... server # [ 3.658297] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. server # [ 3.663153] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. server # [ 3.671131] systemd[1]: Finished Find NixOS closure. server # [ 3.672479] systemd[1]: Reached target Initrd Default Target. server # [ 3.674549] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... server # [ 3.693069] systemd[1]: initrd-cleanup.service: Deactivated successfully. server # [ 3.694622] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. server # [ 3.697636] systemd[1]: Stopped target Initrd Default Target. server # [ 3.699213] systemd[1]: Stopped target Basic System. server # [ 3.700088] systemd[1]: Stopped target Initrd Root Device. server # [ 3.702183] systemd[1]: Stopped target Path Units. server # [ 3.703063] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. server # [ 3.704260] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. server # [ 3.705701] systemd[1]: Stopped target Slice Units. server # [ 3.706720] systemd[1]: Stopped target Socket Units. server # [ 3.708178] systemd[1]: Stopped target System Initialization. server # [ 3.709089] systemd[1]: Stopped target Swaps. server # [ 3.709781] systemd[1]: Stopped target Timer Units. server # [ 3.710587] systemd[1]: dbus.socket: Deactivated successfully. server # [ 3.712203] systemd[1]: Closed D-Bus System Message Bus Socket. server # [ 3.713199] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. server # [ 3.715183] systemd[1]: Stopped Find NixOS closure. server # [ 3.715982] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio server # [ 3.717604] systemd[1]: Starting rw-sysroot-nix-store.service... server # [ 3.718566] systemd[1]: systemd-sysctl.service: Deactivated successfully. server # [ 3.720140] systemd[1]: Stopped Apply Kernel Variables. server # [ 3.720939] systemd[1]: systemd-modules-load.service: Deactivated successfully. server # [ 3.722075] systemd[1]: Stopped Load Kernel Modules. server # [ 3.723146] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. server # [ 3.724960] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. server # [ 3.727236] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. server # [ 3.728762] systemd[1]: Stopped Create System Files and Directories. server # [ 3.729775] systemd[1]: Stopped target Local File Systems. server # [ 3.730935] systemd[1]: Stopped target Preparation for Local File Systems. server # [ 3.732747] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. server # [ 3.735238] systemd[1]: Stopped Coldplug All udev Devices. server # [ 3.736169] systemd[1]: Stopping Rule-based Manager for Device Events and Files... server # [ 3.737299] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 3.739160] systemd[1]: Stopped Virtual Console Setup. server # [ 3.747945] systemd[1]: systemd-udevd.service: Deactivated successfully. server # [ 3.750233] systemd[1]: Stopped Rule-based Manager for Device Events and Files. server # [ 3.751484] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. server # [ 3.752584] systemd[1]: Closed udev Control Socket. server # [ 3.756128] systemd[1]: Starting Cleanup udev Database... server # [ 3.758094] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. server # [ 3.760137] systemd[1]: Stopped Create Static Device Nodes in /dev. server # [ 3.761250] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. server # [ 3.762479] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. server # [ 3.763802] systemd[1]: kmod-static-nodes.service: Deactivated successfully. server # [ 3.765174] systemd[1]: Stopped Create List of Static Device Nodes. server # [ 3.766903] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. server # [ 3.769441] systemd[1]: Finished rw-sysroot-nix-store.service. server # [ 3.782441] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. server # [ 3.785086] systemd[1]: Finished Cleanup udev Database. server # [ 3.788107] systemd[1]: Reached target Switch Root. server # [ 3.789997] systemd[1]: Starting NixOS Activation... server # [ 3.954671] initrd-nixos-activation-start[175]: booting system configuration /nix/store/blggjpr5bzlyj6ys4s1icm9s95hzz3zp-nixos-system-server-test server # [ 4.035199] initrd-nixos-activation-start[175]: running activation script... server # [ 4.442953] initrd-nixos-activation-start[198]: setting up /etc... server # [ 4.676081] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. server # [ 4.678091] systemd[1]: Finished NixOS Activation. server # [ 4.680358] systemd[1]: Starting Switch Root... server # [ 4.697259] systemd[1]: Switching root. server # [ 4.892032] systemd-journald[67]: Received SIGTERM from PID 1 (systemd). server # [ 5.016671] NET: Registered PF_VSOCK protocol family server # [ 5.411442] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) server # [ 5.416882] systemd[1]: Detected virtualization kvm. server # [ 5.417974] systemd[1]: Detected architecture x86-64. server # [ 5.419157] systemd[1]: Detected first boot. server # [ 5.426526] systemd[1]: Initializing machine ID from random generator. server # [ 5.674212] systemd[1]: bpf-restrict-fs: LSM BPF program attached server # [ 5.805156] systemd[1]: Applying preset policy. server # [ 6.184214] systemd[1]: Populated /etc with preset unit settings. server # [ 6.545019] systemd[1]: initrd-switch-root.service: Deactivated successfully. server # [ 6.546413] systemd[1]: Stopped initrd-switch-root.service. server # [ 6.548729] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. server # [ 6.550456] systemd[1]: Created slice Slice /system/getty. server # [ 6.551529] systemd[1]: Created slice User and Session Slice. server # [ 6.552288] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. server # [ 6.553448] systemd[1]: Started Forward Password Requests to Wall Directory Watch. server # [ 6.554475] systemd[1]: Expecting device /dev/hvc0... server # [ 6.555084] systemd[1]: Expecting device /dev/ttyS0... server # [ 6.555764] systemd[1]: Reached target Local Encrypted Volumes. server # [ 6.556512] systemd[1]: Stopped target initrd-fs.target. server # [ 6.557160] systemd[1]: Stopped target initrd-root-fs.target. server # [ 6.557823] systemd[1]: Stopped target initrd-switch-root.target. server # [ 6.558561] systemd[1]: Reached target Virtual Machines and Containers. server # [ 6.559359] systemd[1]: Reached target Path Units. server # [ 6.559952] systemd[1]: Reached target Remote File Systems. server # [ 6.560618] systemd[1]: Reached target Slice Units. server # [ 6.561181] systemd[1]: Reached target Swaps. server # [ 6.564030] systemd[1]: Listening on Query the User Interactively for a Password. server # [ 6.566733] systemd[1]: Listening on Process Core Dump Socket. server # [ 6.568734] systemd[1]: Listening on Credential Encryption/Decryption. server # [ 6.570889] systemd[1]: Listening on Factory Reset Management. server # [ 6.573773] systemd[1]: Starting Journal Log Access Socket... server # [ 6.575164] systemd[1]: Listening on Journal Audit Socket. server # [ 6.577984] systemd[1]: Listening on Console Output Muting Service Socket. server # [ 6.578974] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. server # [ 6.579933] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki server # [ 6.581043] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki server # [ 6.586510] systemd[1]: Listening on Disk Repartitioning Service Socket. server # [ 6.587268] systemd[1]: Listening on udev Control Socket. server # [ 6.587929] systemd[1]: Listening on udev Varlink Socket. server # [ 6.591087] systemd[1]: Mounting Huge Pages File System... server # [ 6.593816] systemd[1]: Mounting POSIX Message Queue File System... server # [ 6.598736] systemd[1]: Mounting Kernel Debug File System... server # [ 6.610025] systemd[1]: Mounting Kernel Trace File System... server # [ 6.617375] systemd[1]: Starting Create List of Static Device Nodes... server # [ 6.618208] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio server # [ 6.627495] systemd[1]: Starting Load Kernel Module configfs... server # [ 6.628300] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm server # [ 6.631569] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 6.636533] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse server # [ 6.662568] systemd[1]: Mounting FUSE Control File System... server # [ 6.670517] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 6.693214] systemd[1]: Starting Journal Service... server # [ 6.697724] systemd[1]: Starting Load Kernel Modules... server # [ 6.707856] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... server # [ 6.720634] systemd[1]: Starting Remount Root and Kernel File Systems... server # [ 6.726921] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 6.748860] systemd[1]: Starting Coldplug All udev Devices... server # [ 6.784286] loop: module loaded server # [ 6.785131] systemd[1]: Listening on Journal Log Access Socket. server # [ 6.794487] systemd[1]: Mounted Huge Pages File System. server # [ 6.798027] systemd[1]: Mounted POSIX Message Queue File System. server # [ 6.803915] systemd[1]: Mounted Kernel Debug File System. server # [ 6.806483] systemd-journald[267]: Collecting audit messages is enabled. server # [ 6.808433] systemd[1]: Mounted Kernel Trace File System. server # [ 6.817445] systemd[1]: Finished Create List of Static Device Nodes. server # [ 6.818676] EXT4-fs (vda): re-mounted 0fdf371b-4821-45df-a2d2-ea36feac81d9. server # [ 6.822528] systemd[1]: modprobe@configfs.service: Deactivated successfully. server # [ 6.830492] systemd[1]: Finished Load Kernel Module configfs. server # [ 6.835949] systemd[1]: Mounted FUSE Control File System. server # [ 6.841455] systemd[1]: Finished Load Kernel Modules. server # [ 6.663579] systemd[1]: Queued start job for default target Multi-User System. server # [ 6.665162] systemd[1]: systemd-journald.service: Deactivated successfully. server # [ 6.667600] systemd-modules-load[268]: Inserted module 'loop' server # [ 6.849605] systemd[1]: Started Journal Service. server # [ 6.676215] systemd[1]: Finished Remount Root and Kernel File Systems. server # [ 6.697093] systemd[1]: Listening on Disk Image Download Service Socket. server # [ 6.704118] systemd[1]: Mounting Kernel Configuration File System... server # [ 6.709642] systemd[1]: Starting Firewall... server # [ 6.721090] systemd[1]: Starting Flush Journal to Persistent Storage... server # [ 6.722181] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore server # [ 6.726711] systemd-oomd[269]: No swap; memory pressure usage will be degraded server # [ 6.744570] systemd[1]: Starting Load/Save OS Random Seed... server # [ 6.759397] systemd[1]: Starting Apply Kernel Variables... server # [ 6.778093] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... server # [ 6.780836] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 6.782755] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. server # [ 7.010379] systemd-journald[267]: Received client request to flush runtime journal. server # [ 7.021588] systemd[1]: Mounted Kernel Configuration File System. server # [ 7.022763] systemd[1]: Finished Load/Save OS Random Seed. server # [ 7.026734] systemd[1]: Reached target First Boot Complete. server # [ 7.031141] systemd[1]: Finished Apply Kernel Variables. server # [ 7.032178] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. server # [ 7.033314] systemd[1]: Starting Create Static Device Nodes in /dev... server # [ 7.041355] systemd[1]: Finished Flush Journal to Persistent Storage. server # [ 7.076730] systemd[1]: Finished Coldplug All udev Devices. server # [ 7.100502] systemd[1]: Finished Create Static Device Nodes in /dev. server # [ 7.102882] systemd[1]: Reached target Preparation for Local File Systems. server # [ 7.107604] systemd[1]: Starting Rule-based Manager for Device Events and Files... server # [ 7.179720] systemd-udevd[311]: Using default interface naming scheme 'v260'. server # [ 7.284408] systemd[1]: Started Rule-based Manager for Device Events and Files. server # [ 7.370723] systemd[1]: Mounting /run/wrappers... server # [ 7.421808] systemd[1]: Mounted /run/wrappers. server # [ 7.422742] systemd[1]: Reached target Local File Systems. server # [ 7.427100] systemd[1]: Listening on Boot Loader Control Service Socket. server # [ 7.434687] systemd[1]: Starting register-nix-paths.service... server # [ 7.443449] systemd[1]: Starting Create SUID/SGID Wrappers... server # [ 7.444847] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 7.453937] systemd[1]: Starting Save Transient machine-id to Disk... server # [ 7.474686] systemd[1]: Starting Create System Files and Directories... server # [ 7.536383] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse server # [ 7.594761] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. server # [ 7.607348] systemd[1]: Finished Save Transient machine-id to Disk. server # [ 7.684641] systemd[1]: Finished Create System Files and Directories. server # [ 7.698503] systemd[1]: Starting Rebuild Journal Catalog... server # [ 7.708063] systemd[1]: Starting Record System Boot/Shutdown in UTMP... server # [ 7.830582] systemd[1]: Finished Record System Boot/Shutdown in UTMP. server # [ 7.877253] systemd[1]: Finished Rebuild Journal Catalog. server # [ 7.888408] systemd[1]: Starting Update is Completed... server # [ 7.943341] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. server # [ 7.966205] systemd[1]: Finished Update is Completed. server # [ 7.991495] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped. server # [ 8.111352] (udev-worker)[346]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. server # [ 8.116620] (udev-worker)[346]: Network interface NamePolicy= disabled on kernel command line. server # [ 8.141364] (udev-worker)[343]: Network interface NamePolicy= disabled on kernel command line. server # [ 8.205643] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. server # [ 8.208097] systemd[1]: Finished Create SUID/SGID Wrappers. server # [ 8.266051] systemd[1]: Finished Firewall. server # [ 8.290951] systemd[1]: Condition check resulted in Virtio network device being skipped. server # [ 8.293077] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore server # [ 8.297121] systemd[1]: Starting Create SUID/SGID Wrappers... server # [ 8.298416] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. server # [ 8.299815] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 server # [ 8.301634] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore server # [ 8.303696] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 8.305134] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki server # [ 8.639473] mousedev: PS/2 mouse device common for all mice server # [ 8.472105] systemd[1]: Finished register-nix-paths.service. server # [ 8.661206] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 server # [ 8.675794] ACPI: button: Power Button [PWRF] server # [ 8.706301] rtc_cmos 00:05: RTC can wake from S4 server # [ 8.719523] rtc_cmos 00:05: registered as rtc0 server # [ 8.731567] parport_pc 00:03: reported by Plug and Play ACPI server # [ 8.732299] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)] server # [ 8.747199] Floppy drive(s): fd0 is 2.88M AMI BIOS server # [ 8.759975] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console server # [ 8.769890] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 server # [ 8.769952] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD server # [ 8.667367] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. server # [ 8.668763] systemd[1]: Finished Create SUID/SGID Wrappers. server # [ 8.670767] systemd[1]: Reached target System Initialization. server # [ 8.674090] systemd[1]: Started Discard unused filesystem blocks once a week. server # [ 8.674864] systemd[1]: Started postgresqlBackup-horae.timer. server # [ 8.675511] systemd[1]: Started Daily Cleanup of Temporary Directories. server # [ 8.676248] systemd[1]: Reached target Timer Units. server # [ 8.676807] systemd[1]: Listening on D-Bus System Message Bus Socket. server # [ 8.677534] systemd[1]: Listening on Nix Daemon Socket. server # [ 8.678102] systemd[1]: Listening on Hostname Service Socket. server # [ 8.678679] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. server # [ 8.679824] systemd[1]: Reached target Socket Units. server # [ 8.680707] systemd[1]: Reached target Basic System. server # [ 8.684042] systemd[1]: Started backdoor.service. server # [ 8.687330] systemd[1]: Starting Import lastlog data into lastlog2 database... server # [ 8.692756] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 8.774731] FDC 0 is a S82078B server # [ 8.783930] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4 server # [ 8.790667] rtc_cmos 00:05: setting system clock to 2026-10-07T21:16:26 UTC (1791407786) server # [ 8.790772] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs server # [ 8.699223] systemd[1]: Starting Post-Boot Actions... server # [ 8.791522] systemd-journald[267]: Time jumped backwards, rotating. server # [ 8.827414] Console: switching to colour dummy device 80x25 server # [ 8.714938] systemd[1]: Started Reset console on configuration changes. server # [ 8.733463] systemd[1]: Starting resolvconf update... server # [ 8.734046] systemd[1]: Starting D-Bus System Message Bus... server # [ 8.935161] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6 server # [ 8.936078] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5 server # [ 8.967731] [drm] Found bochs VGA, ID 0xb0c5. server # [ 8.968104] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. server # [ 8.790798] systemd[1]: Finished Post-Boot Actions. server # [ 8.813275] nsncd[553]: Oct 07 21:16:26.699 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 8.815193] systemd[1]: Started Name Service Cache Daemon (nsncd). server # connecting to host... server # [ 8.822919] systemd[1]: Reached target Host and Network Name Lookups. server # [ 8.823742] systemd[1]: Reached target User and Group Name Lookups. server # [ 9.012806] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic server # [ 8.835609] systemd[1]: Starting User Login Management... server # [ 9.026743] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0 server # [ 8.866521] systemd[1]: Finished Import lastlog data into lastlog2 database. server # [ 8.886630] systemd[1]: Starting Virtual Console Setup... server # [ 8.889648] dbus-broker-launch[557]: Looking up NSS user entry for 'systemd-timesync'... server # [ 8.909080] dbus-broker-launch[557]: NSS returned no entry for 'systemd-timesync' server # [ 8.909892] dbus-broker-launch[557]: Invalid user-name in /nix/store/l9k22xmfgghd484x05c1lkjp0za9zq2i-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" server # [ 8.934706] systemd[1]: Started D-Bus System Message Bus. server # [ 8.975137] dbus-broker-launch[557]: Ready server # [ 9.158557] RAPL PMU: API unit is 2^-32 Joules, 1 fixed counters, 10737418240 ms ovfl timer server # [ 9.159187] RAPL PMU: hw unit of domain psys 2^-0 Joules server # [ 8.989695] systemd-logind[578]: New seat seat0. server # [ 8.992844] systemd[1]: Stopped target Host and Network Name Lookups. server # [ 8.994697] systemd[1]: Stopping Host and Network Name Lookups... server # [ 8.997138] systemd[1]: Stopped target User and Group Name Lookups. server # [ 8.997808] systemd[1]: Stopping User and Group Name Lookups... server # [ 8.999938] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... server # [ 9.000735] systemd[1]: Started User Login Management. server # [ 9.002656] systemd[1]: nscd.service: Deactivated successfully. server # [ 9.004365] systemd[1]: Stopped Name Service Cache Daemon (nsncd). server # [ 9.008964] systemd-logind[578]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) server # [ 9.023044] systemd[1]: Starting linger-users.service... server # [ 9.025562] systemd[1]: Starting Name Service Cache Daemon (nsncd)... server # [ 9.192234] fbcon: bochs-drmdrmfb (fb0) is primary device server # [ 9.272593] ppdev: user-space parallel port driver server # [ 9.383713] Console: switching to colour frame buffer device 160x50 server # [ 9.405170] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device server # [ 9.070325] systemd[1]: Finished resolvconf update. server # [ 9.228540] dhcpcd[643]: dhcpcd-10.3.2 starting server # [ 9.229620] systemd[1]: Reached target Preparation for Network. server # [ 9.232137] systemd[1]: Starting DHCP Client... server # [ 9.232856] dhcpcd[648]: dev: loaded udev server # [ 9.236309] systemd[1]: Starting Address configuration of eth1... server # [ 9.236988] systemd-logind[578]: Watching system buttons on /dev/input/event2 (Power Button) server # [ 9.238799] systemd[1]: Starting Extra networking commands.... server # [ 9.241696] systemd-logind[578]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) server # [ 9.243335] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 9.245373] systemd[1]: Stopped Virtual Console Setup. server # [ 9.443451] 8021q: 802.1Q VLAN Support v1.8 server: waiting for unit postgresql.service server: waiting for the VM to finish booting server # [ 9.281223] systemd[1]: Starting Virtual Console Setup... server: Guest shell says: b'Spawning backdoor root shell...\n' server: connected to guest root shell server: (connecting took 0.00 seconds) server: (finished: waiting for the VM to finish booting, in 0.00 seconds) server # [ 9.293811] systemd[1]: linger-users.service: Deactivated successfully. server # [ 9.311364] systemd[1]: Finished linger-users.service. server # [ 9.317577] nsncd[622]: Oct 07 21:16:27.203 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" server # [ 9.325056] systemd[1]: Started Name Service Cache Daemon (nsncd). server # [ 9.348913] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. server # [ 9.533952] 8021q: adding VLAN 0 to HW filter on device eth1 server # [ 9.357993] systemd[1]: Stopped Virtual Console Setup. server # [ 9.388333] systemd[1]: Reached target Host and Network Name Lookups. server # [ 9.389383] systemd[1]: Reached target User and Group Name Lookups. server # [ 9.390268] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. server # [ 9.398235] network-addresses-eth1-start[639]: adding address 192.168.1.1/24... done server # [ 9.404111] systemd[1]: Starting Virtual Console Setup... server # [ 9.424091] network-addresses-eth1-start[639]: adding address 2001:db8:1::1/64... done server # [ 9.522771] systemd[1]: Finished Address configuration of eth1. server # [ 9.632432] systemd[1]: Finished Extra networking commands.. server # [ 9.638081] systemd[1]: Reached target Network. server # [ 9.654703] systemd[1]: Starting dex identity provider... server # [ 9.662181] systemd[1]: Starting PostgreSQL Server... server # [ 9.851800] cfg80211: Loading compiled-in X.509 certificates for regulatory database server # [ 9.674231] systemd[1]: Starting Permit User Sessions... server # [ 9.876105] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' server # [ 9.878524] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' server # [ 9.880265] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 server # [ 9.881169] cfg80211: failed to load regulatory.db server # [ 9.770803] systemd[1]: Finished Permit User Sessions. server # [ 9.792388] systemd[1]: Started Getty on tty1. server # [ 9.793190] systemd[1]: Reached target Login Prompts. server # [ 10.016471] 8021q: adding VLAN 0 to HW filter on device eth0 server # [ 9.841056] dhcpcd[648]: eth0: waiting for carrier server # [ 9.843261] dhcpcd[648]: eth0: carrier acquired server # [ 9.849469] systemd-vconsole-setup[680]: Configuration of first virtual console was skipped, ignoring remaining ones. server # [ 9.858156] systemd[1]: Finished Virtual Console Setup. server # [ 9.871386] dhcpcd[648]: DUID 00:01:00:01:32:59:73:2b:52:54:00:12:34:56 server # [ 9.872702] dhcpcd[648]: eth0: IAID 00:12:34:56 server # [ 9.874318] dhcpcd[648]: eth0: adding address fe80::5054:ff:fe12:3456 server # [ 10.097251] Key type encrypted registered server # [ 10.152054] EDAC MC: Ver: 3.0.0 server # [ 9.982538] systemd[1]: Started dex identity provider. server # [ 10.019754] postgresql-pre-start[750]: The files belonging to this database system will be owned by user "postgres". server # [ 10.021377] postgresql-pre-start[750]: This user must also own the server process. server # [ 10.028386] postgresql-pre-start[750]: The database cluster will be initialized with locale "en_US.UTF-8". server # [ 10.029661] postgresql-pre-start[750]: The default database encoding has accordingly been set to "UTF8". server # [ 10.030963] postgresql-pre-start[750]: The default text search configuration will be set to "english". server # [ 10.032186] postgresql-pre-start[750]: Data page checksums are disabled. server # [ 10.033172] postgresql-pre-start[750]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok server # [ 10.034588] postgresql-pre-start[750]: creating subdirectories ... ok server # [ 10.036065] postgresql-pre-start[750]: selecting dynamic shared memory implementation ... posix server # [ 10.102789] postgresql-pre-start[750]: selecting default "max_connections" ... 100 server # [ 10.165586] postgresql-pre-start[750]: selecting default "shared_buffers" ... 128MB server # [ 10.245596] dex[757]: time=2026-10-07T21:16:28.133Z level=INFO msg="Version info" dex_version=v2.45.1 go.version=go1.26.4 go.os=linux go.arch=amd64 server # [ 10.248127] dex[757]: time=2026-10-07T21:16:28.134Z level=INFO msg="config issuer" issuer=http://127.0.0.1:5556/dex server # [ 10.249968] dex[757]: time=2026-10-07T21:16:28.137Z level=INFO msg="config storage" storage_type=memory server # [ 10.251416] dex[757]: time=2026-10-07T21:16:28.137Z level=INFO msg="config static client" client_name=Horae server # [ 10.252691] dex[757]: time=2026-10-07T21:16:28.137Z level=INFO msg="config connector" connector_id=mock server # [ 10.254156] dex[757]: time=2026-10-07T21:16:28.139Z level=INFO msg="config skipping approval screen" server # [ 10.255692] dex[757]: time=2026-10-07T21:16:28.139Z level=INFO msg="continue on connector failure feature flag enabled" server # [ 10.257147] dex[757]: time=2026-10-07T21:16:28.139Z level=INFO msg="signer configured" type=local keys_rotation_period=6h server # [ 10.259073] dex[757]: time=2026-10-07T21:16:28.140Z level=INFO msg="config refresh tokens rotation" enabled=true server # [ 10.261594] dex[757]: time=2026-10-07T21:16:28.151Z level=ERROR msg="failed to get signing algorithm" err="no signing key found" server # [ 10.264698] dex[757]: time=2026-10-07T21:16:28.154Z level=INFO msg="keys expired, rotating" server # [ 10.318146] dex[757]: time=2026-10-07T21:16:28.207Z level=INFO msg="keys rotated" next_rotation=2026-10-08T03:16:28.207Z server # [ 10.320562] dex[757]: time=2026-10-07T21:16:28.210Z level=INFO msg="listening on" server=http address=127.0.0.1:5556 server # [ 11.096178] dhcpcd[648]: eth0: soliciting a DHCP lease server # [ 11.293079] NET: Registered PF_PACKET protocol family server # [ 11.116607] dhcpcd[648]: eth0: offered 10.0.2.15 from 10.0.2.2 server # [ 11.119074] dhcpcd[648]: eth0: probing address 10.0.2.15/24 server # [ 11.945117] postgresql-pre-start[750]: selecting default time zone ... UTC server # [ 11.948402] postgresql-pre-start[750]: creating configuration files ... ok server # [ 12.161109] postgresql-pre-start[750]: running bootstrap script ... ok server # [ 12.291746] dhcpcd[648]: eth0: soliciting an IPv6 router server # [ 12.292664] dhcpcd[648]: eth0: Router Advertisement from fe80::2 server # [ 12.293622] dhcpcd[648]: eth0: adding address fec0::5054:ff:fe12:3456/64 server # [ 12.295149] dhcpcd[648]: eth0: adding route to fec0::/64 server # [ 12.295950] dhcpcd[648]: eth0: adding default route via fe80::2 server # [ 12.774829] postgresql-pre-start[750]: performing post-bootstrap initialization ... ok server # [ 12.899194] postgresql-pre-start[750]: syncing data to disk ... ok server # [ 12.900338] postgresql-pre-start[750]: initdb: warning: enabling "trust" authentication for local connections server # [ 12.901469] postgresql-pre-start[750]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb. server # [ 12.903422] postgresql-pre-start[750]: Success. You can now start the database server using: server # [ 12.904486] postgresql-pre-start[750]: pg_ctl -D /var/lib/postgresql/17 -l logfile start server # [ 13.016689] postgres[791]: [791] LOG: starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit server # [ 13.023201] postgres[791]: [791] LOG: listening on IPv6 address "::1", port 5432 server # [ 13.024099] postgres[791]: [791] LOG: listening on IPv4 address "127.0.0.1", port 5432 server # [ 13.025783] postgres[791]: [791] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" server # [ 13.034326] postgres[797]: [797] LOG: database system was shut down at 2026-10-07 21:16:30 GMT server # [ 13.045344] postgres[791]: [791] LOG: database system is ready to accept connections server # [ 13.057506] systemd[1]: Started PostgreSQL Server. server # [ 13.071252] systemd[1]: Starting PostgreSQL Setup Scripts... server # [ 13.292913] postgresql-setup-start[808]: CREATE DATABASE server # [ 13.326281] postgresql-setup-start[813]: CREATE ROLE server # [ 13.341423] postgresql-setup-start[815]: ALTER ROLE server # [ 13.356392] postgresql-setup-start[817]: ALTER DATABASE server # [ 13.361142] systemd[1]: Finished PostgreSQL Setup Scripts. server # [ 13.363397] systemd[1]: Reached target PostgreSQL. server # [ 13.366482] systemd[1]: Starting Horae time tracking server... server # [ 13.515779] horae[820]: 2026-10-07T21:16:31.403814Z INFO horae: Running migrations... server # [ 13.721856] horae[820]: 2026-10-07T21:16:31.611268Z INFO horae: Migrations complete. server # [ 13.730175] systemd[1]: Started Horae time tracking server. server # [ 13.759483] horae[827]: 2026-10-07T21:16:31.648810Z INFO horae: Starting Horae on 127.0.0.1:3000 server # [ 13.767387] horae[827]: 2026-10-07T21:16:31.656990Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping server # [ 13.778866] horae[827]: 2026-10-07T21:16:31.668069Z INFO horae::plugin::registry: plugins directory does not exist: plugins server # [ 13.799210] horae[827]: 2026-10-07T21:16:31.688701Z INFO dioxus_server::server: Registering: POST /api/approve_submissions13590059188800848654 server # [ 13.801056] horae[827]: 2026-10-07T21:16:31.690506Z INFO dioxus_server::server: Registering: POST /api/approve_submission13590059188800848654 server # [ 13.802904] horae[827]: 2026-10-07T21:16:31.690525Z INFO dioxus_server::server: Registering: POST /api/reject_submission13590059188800848654 server # [ 13.804288] horae[827]: 2026-10-07T21:16:31.690537Z INFO dioxus_server::server: Registering: POST /api/list_approvals13590059188800848654 server # [ 13.805797] horae[827]: 2026-10-07T21:16:31.690553Z INFO dioxus_server::server: Registering: POST /api/submit_week13590059188800848654 server # [ 13.807282] horae[827]: 2026-10-07T21:16:31.690627Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection1604479113674082919 server # [ 13.808963] horae[827]: 2026-10-07T21:16:31.690638Z INFO dioxus_server::server: Registering: POST /api/project_creation_options1604479113674082919 server # [ 13.810342] horae[827]: 2026-10-07T21:16:31.692055Z INFO dioxus_server::server: Registering: POST /api/project_creation_client1604479113674082919 server # [ 13.812073] horae[827]: 2026-10-07T21:16:31.692098Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft1604479113674082919 server # [ 13.813848] horae[827]: 2026-10-07T21:16:31.692114Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft1604479113674082919 server # [ 13.815588] horae[827]: 2026-10-07T21:16:31.692124Z INFO dioxus_server::server: Registering: POST /api/save_project_editor1604479113674082919 server # [ 13.817476] horae[827]: 2026-10-07T21:16:31.692134Z INFO dioxus_server::server: Registering: POST /api/load_project_editor1604479113674082919 server # [ 13.819344] horae[827]: 2026-10-07T21:16:31.692143Z INFO dioxus_server::server: Registering: POST /api/save_project_draft1604479113674082919 server # [ 13.821054] horae[827]: 2026-10-07T21:16:31.692154Z INFO dioxus_server::server: Registering: POST /api/load_project_draft1604479113674082919 server # [ 13.823118] horae[827]: 2026-10-07T21:16:31.695219Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection server # [ 13.824614] horae[827]: 2026-10-07T21:16:31.695317Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel server # [ 13.826058] horae[827]: 2026-10-07T21:16:31.695330Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode} server # [ 13.827424] horae[827]: 2026-10-07T21:16:31.695345Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start server # [ 13.828685] horae[827]: 2026-10-07T21:16:31.695355Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry server # [ 13.830128] horae[827]: 2026-10-07T21:16:31.695364Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history server # [ 13.831489] horae[827]: 2026-10-07T21:16:31.695374Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account1479223498380199393 server # [ 13.833034] horae[827]: 2026-10-07T21:16:31.695388Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status server # [ 13.834320] horae[827]: 2026-10-07T21:16:31.695401Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start1479223498380199393 server # [ 13.835840] horae[827]: 2026-10-07T21:16:31.695411Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect1479223498380199393 server # [ 13.837328] horae[827]: 2026-10-07T21:16:31.698367Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status2064041990223864818 server # [ 13.838681] horae[827]: 2026-10-07T21:16:31.698382Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit2064041990223864818 server # [ 13.840116] horae[827]: 2026-10-07T21:16:31.698392Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft2064041990223864818 server # [ 13.842196] horae[827]: 2026-10-07T21:16:31.698402Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor2064041990223864818 server # [ 13.843485] horae[827]: 2026-10-07T21:16:31.698413Z INFO dioxus_server::server: Registering: POST /api/generate_invoice2064041990223864818 server # [ 13.844716] horae[827]: 2026-10-07T21:16:31.698485Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice2064041990223864818 server # [ 13.845948] horae[827]: 2026-10-07T21:16:31.698498Z INFO dioxus_server::server: Registering: POST /api/list_invoices2064041990223864818 server # [ 13.847291] horae[827]: 2026-10-07T21:16:31.698507Z INFO dioxus_server::server: Registering: POST /api/get_invoice2064041990223864818 server # [ 13.848809] horae[827]: 2026-10-07T21:16:31.698518Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets7690553569863186185 server # [ 13.850263] horae[827]: 2026-10-07T21:16:31.698528Z INFO dioxus_server::server: Registering: POST /api/report_detailed7690553569863186185 server # [ 13.851750] horae[827]: 2026-10-07T21:16:31.698540Z INFO dioxus_server::server: Registering: POST /api/report_time7690553569863186185 server # [ 13.853667] horae[827]: 2026-10-07T21:16:31.701494Z INFO dioxus_server::server: Registering: POST /api/update_org_branding13047172052397277026 server # [ 13.855970] horae[827]: 2026-10-07T21:16:31.701507Z INFO dioxus_server::server: Registering: POST /api/get_org_branding13047172052397277026 server # [ 13.858071] horae[827]: 2026-10-07T21:16:31.701516Z INFO dioxus_server::server: Registering: POST /api/get_week_start13047172052397277026 server # [ 13.859477] horae[827]: 2026-10-07T21:16:31.701525Z INFO dioxus_server::server: Registering: POST /api/get_org_name13047172052397277026 server # [ 13.860815] horae[827]: 2026-10-07T21:16:31.701534Z INFO dioxus_server::server: Registering: POST /api/logout16144359270796124307 server # [ 13.862264] horae[827]: 2026-10-07T21:16:31.701682Z INFO dioxus_server::server: Registering: POST /api/get_me16144359270796124307 server # [ 13.863605] horae[827]: 2026-10-07T21:16:31.701692Z INFO dioxus_server::server: Registering: POST /api/list_plugins15265546378964521800 server # [ 13.865065] horae[827]: 2026-10-07T21:16:31.703038Z INFO dioxus_server::server: Registering: POST /api/set_user_active13739675588719463496 server # [ 13.866631] horae[827]: 2026-10-07T21:16:31.703048Z INFO dioxus_server::server: Registering: POST /api/set_user_role13739675588719463496 server # [ 13.868143] horae[827]: 2026-10-07T21:16:31.703059Z INFO dioxus_server::server: Registering: POST /api/create_user13739675588719463496 server # [ 13.869985] horae[827]: 2026-10-07T21:16:31.703068Z INFO dioxus_server::server: Registering: POST /api/list_users13739675588719463496 server # [ 13.871657] horae[827]: 2026-10-07T21:16:31.703341Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress13963200375258473206 server # [ 13.873103] horae[827]: 2026-10-07T21:16:31.705051Z INFO dioxus_server::server: Registering: POST /api/get_project_fee_balances13963200375258473206 server # [ 13.874448] horae[827]: 2026-10-07T21:16:31.705086Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects13963200375258473206 server # [ 13.875723] horae[827]: 2026-10-07T21:16:31.705097Z INFO dioxus_server::server: Registering: POST /api/set_projects_active13963200375258473206 server # [ 13.876974] horae[827]: 2026-10-07T21:16:31.705115Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks13963200375258473206 server # [ 13.878284] horae[827]: 2026-10-07T21:16:31.705126Z INFO dioxus_server::server: Registering: POST /api/get_project_details13963200375258473206 server # [ 13.879574] horae[827]: 2026-10-07T21:16:31.705136Z INFO dioxus_server::server: Registering: POST /api/set_project_active13963200375258473206 server # [ 13.882039] horae[827]: 2026-10-07T21:16:31.706800Z INFO dioxus_server::server: Registering: POST /api/list_project_tasks13963200375258473206 server # [ 13.883612] horae[827]: 2026-10-07T21:16:31.706825Z INFO dioxus_server::server: Registering: POST /api/list_project_spend13963200375258473206 server # [ 13.885024] horae[827]: 2026-10-07T21:16:31.706835Z INFO dioxus_server::server: Registering: POST /api/list_project_tags13963200375258473206 server # [ 13.886440] horae[827]: 2026-10-07T21:16:31.706861Z INFO dioxus_server::server: Registering: POST /api/link_project_task13963200375258473206 server # [ 13.887862] horae[827]: 2026-10-07T21:16:31.706927Z INFO dioxus_server::server: Registering: POST /api/delete_assignment13963200375258473206 server # [ 13.889644] horae[827]: 2026-10-07T21:16:31.706938Z INFO dioxus_server::server: Registering: POST /api/create_assignment13963200375258473206 server # [ 13.892117] horae[827]: 2026-10-07T21:16:31.706948Z INFO dioxus_server::server: Registering: POST /api/list_assignments13963200375258473206 server # [ 13.893629] horae[827]: 2026-10-07T21:16:31.706958Z INFO dioxus_server::server: Registering: POST /api/set_task_active13963200375258473206 server # [ 13.895179] horae[827]: 2026-10-07T21:16:31.706968Z INFO dioxus_server::server: Registering: POST /api/list_projects13963200375258473206 server # [ 13.896832] horae[827]: 2026-10-07T21:16:31.706981Z INFO dioxus_server::server: Registering: POST /api/update_task13963200375258473206 server # [ 13.898375] horae[827]: 2026-10-07T21:16:31.706990Z INFO dioxus_server::server: Registering: POST /api/create_task13963200375258473206 server # [ 13.900231] horae[827]: 2026-10-07T21:16:31.707003Z INFO dioxus_server::server: Registering: POST /api/list_tasks13963200375258473206 server # [ 13.901799] horae[827]: 2026-10-07T21:16:31.708709Z INFO dioxus_server::server: Registering: POST /api/update_client_profile10485135825777491571 server # [ 13.903286] horae[827]: 2026-10-07T21:16:31.708735Z INFO dioxus_server::server: Registering: POST /api/list_client_summaries10485135825777491571 server # [ 13.904809] horae[827]: 2026-10-07T21:16:31.708748Z INFO dioxus_server::server: Registering: POST /api/create_project_client10485135825777491571 server # [ 13.906252] horae[827]: 2026-10-07T21:16:31.708760Z INFO dioxus_server::server: Registering: POST /api/create_client_profile10485135825777491571 server # [ 13.907809] horae[827]: 2026-10-07T21:16:31.708774Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices10485135825777491571 server # [ 13.909587] horae[827]: 2026-10-07T21:16:31.708800Z INFO dioxus_server::server: Registering: POST /api/get_client_details10485135825777491571 server # [ 13.911152] horae[827]: 2026-10-07T21:16:31.708813Z INFO dioxus_server::server: Registering: POST /api/set_client_active10485135825777491571 server # [ 13.912778] horae[827]: 2026-10-07T21:16:31.708825Z INFO dioxus_server::server: Registering: POST /api/update_client10485135825777491571 server # [ 13.914352] horae[827]: 2026-10-07T21:16:31.708838Z INFO dioxus_server::server: Registering: POST /api/create_client10485135825777491571 server # [ 13.916846] horae[827]: 2026-10-07T21:16:31.708851Z INFO dioxus_server::server: Registering: POST /api/list_clients10485135825777491571 server # [ 13.919125] horae[827]: 2026-10-07T21:16:31.710583Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts16386063561583833955 server # [ 13.920718] horae[827]: 2026-10-07T21:16:31.710610Z INFO dioxus_server::server: Registering: POST /api/reorder_untimed_entries16386063561583833955 server # [ 13.922430] horae[827]: 2026-10-07T21:16:31.710625Z INFO dioxus_server::server: Registering: POST /api/reschedule_time_entry16386063561583833955 server # [ 13.924452] horae[827]: 2026-10-07T21:16:31.710643Z INFO dioxus_server::server: Registering: POST /api/update_time_entry16386063561583833955 server # [ 13.926573] horae[827]: 2026-10-07T21:16:31.712301Z INFO dioxus_server::server: Registering: POST /api/list_time_entries16386063561583833955 server # [ 13.929314] horae[827]: 2026-10-07T21:16:31.712317Z INFO dioxus_server::server: Registering: POST /api/get_current_timer16386063561583833955 server # [ 13.930692] horae[827]: 2026-10-07T21:16:31.712331Z INFO dioxus_server::server: Registering: POST /api/delete_time_entry16386063561583833955 server # [ 13.932032] horae[827]: 2026-10-07T21:16:31.712345Z INFO dioxus_server::server: Registering: POST /api/create_time_entry16386063561583833955 server # [ 13.933373] horae[827]: 2026-10-07T21:16:31.714134Z INFO dioxus_server::server: Registering: POST /api/start_timer16386063561583833955 server # [ 13.934650] horae[827]: 2026-10-07T21:16:31.714156Z INFO dioxus_server::server: Registering: POST /api/stop_timer16386063561583833955 server # [ 13.935916] horae[827]: 2026-10-07T21:16:31.798925Z INFO horae: Listening on 127.0.0.1:3000 server: (finished: waiting for unit postgresql.service, in 4.68 seconds) server: waiting for unit dex.service server: (finished: waiting for unit dex.service, in 0.04 seconds) server: waiting for unit horae.service server: (finished: waiting for unit horae.service, in 0.03 seconds) server: waiting for TCP port 5556 on localhost server # Connection to localhost (127.0.0.1) 5556 port [tcp/freeciv] succeeded! server: (finished: waiting for TCP port 5556 on localhost, in 0.04 seconds) server: waiting for TCP port 3000 on localhost server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded! server: (finished: waiting for TCP port 3000 on localhost, in 0.02 seconds) server: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok server: (finished: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok, in 0.05 seconds) server: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null server: (finished: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null, in 0.04 seconds) server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed server # [ 14.219796] sudo[865]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; ENV=DATABASE_URL=postgres:///horae ; COMMAND=/run/current-system/sw/bin/horae seed server # [ 14.245966] sudo[865]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0) server # [ 14.323412] postgres[874]: [874] LOG: could not receive data from client: Connection reset by peer server # [ 14.326359] sudo[865]: pam_unix(sudo:session): session closed for user horae server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed, in 0.15 seconds) server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member server # [ 14.357465] sudo[878]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; ENV=DATABASE_URL=postgres:///horae ; COMMAND=/run/current-system/sw/bin/horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member server # [ 14.382771] sudo[878]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0) server # [ 14.413701] postgres[886]: [886] LOG: could not send data to client: Broken pipe server # [ 14.416239] postgres[886]: [886] FATAL: connection to client lost server # [ 14.419524] sudo[878]: pam_unix(sudo:session): session closed for user horae server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member, in 0.09 seconds) server: must succeed: curl -s -c /tmp/jar.txt -b /tmp/jar.txt -L http://127.0.0.1:3000/auth/oidc/start -o /dev/null server # [ 14.488945] dex[757]: time=2026-10-07T21:16:32.377Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=478805bc-a31b-47c8-83b5-20f46f51c7b6 server: (finished: must succeed: curl -s -c /tmp/jar.txt -b /tmp/jar.txt -L http://127.0.0.1:3000/auth/oidc/start -o /dev/null, in 0.17 seconds) server: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries server: (finished: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.08 seconds) server: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'" server # [ 14.719308] sudo[893]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -c 'UPDATE users SET active = false WHERE email = \'kilgore@kilgore.trout\'' server # [ 14.745846] sudo[893]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0) server # [ 14.780606] sudo[893]: pam_unix(sudo:session): session closed for user horae server: (finished: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'", in 0.11 seconds) server # [ 14.845574] dex[757]: time=2026-10-07T21:16:32.735Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=951d31e6-60ef-417a-80a8-ec5e85a7a25f server # [ 14.866050] horae[827]: 2026-10-07T21:16:32.753988Z WARN call: horae::auth::oidc: OIDC login denied: account is deactivated server: must succeed: curl -s -o /dev/null -w '%{http_code}' -b /tmp/jar2.txt http://127.0.0.1:3000/harvest/v2/time_entries server: (finished: must succeed: curl -s -o /dev/null -w '%{http_code}' -b /tmp/jar2.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.05 seconds) (finished: run the VM test script, in 15.89 seconds) test script finished in 15.93s cleanup kill QemuMachine (pid 12) server # qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/l9k0anq0z7zz81zcwy035jfwap9ga6rl-python3-3.13.13/bin/python3.13) (finished: cleanup, in 0.09 seconds)