vm-test-run-horae-e2e-oidc
checks.aarch64-linux.e2e-oidc
· build #524
· raw
1elastic-arm-3c88ad8c: building xzpx080m6x4ijl1gy6jpfanc09xxzl9x-vm-test-run-horae-e2e-oidc.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 seconds)5Test will time out and terminate in 3600 seconds6run the VM test script7additionally exposed symbols:8 server,9 vlan1,10 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_ssh11server: starting vm12server # Disk image does not exist, creating the virtualisation disk image...13server: QEMU running (pid 12)14server # Formatting '/build/vm-state-server/tmp.VlcIB5ZJhX', fmt=raw size=107374182415server # mke2fs 1.47.4 (6-Mar-2025)16server # Discarding device blocks: 0/262144 done17server # Creating filesystem with 262144 4k blocks and 65536 inodes18server # Filesystem UUID: b4730de7-7260-4382-b53f-90bb14e2932b19server # Superblock backups stored on blocks:20server # 32768, 98304, 163840, 22937621server # 22server # Allocating group tables: 0/8 done23server # Writing inode tables: 0/8 done24server # Creating journal (8192 blocks): done25server # Writing superblocks and filesystem accounting information: 0/8 done26server # 27server # Virtualisation disk image created.28server # Creating Nix store image...29server # Created Nix store image.30server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory31server # qemu-system-aarch64: failed to initialize kvm: No such file or directory32server # qemu-system-aarch64: falling back to tcg33server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]34server # [ 0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jul 4 11:44:22 UTC 202635server # [ 0.000000] KASLR enabled36server # [ 0.000000] random: crng init done37server # [ 0.000000] Machine model: linux,dummy-virt38server # [ 0.000000] efi: UEFI not found.39server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT40server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]41server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf]42server # [ 0.000000] Zone ranges:43server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]44server # [ 0.000000] DMA32 empty45server # [ 0.000000] Normal empty46server # [ 0.000000] Device empty47server # [ 0.000000] Movable zone start for each node48server # [ 0.000000] Early memory node ranges49server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]50server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]51server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000052server # [ 0.000000] psci: probing for conduit method from DT.53server # [ 0.000000] psci: PSCIv1.1 detected in firmware.54server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs55server # [ 0.000000] psci: Trusted OS migration not required56server # [ 0.000000] psci: SMC Calling Convention v1.057server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129658server # [ 0.000000] Detected PIPT I-cache on CPU059server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)60server # [ 0.000000] CPU features: detected: GICv3 CPU interface61server # [ 0.000000] CPU features: detected: HCRX_EL2 register62server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE)63server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)64server # [ 0.000000] CPU features: detected: Spectre-v465server # [ 0.000000] alternatives: applying boot alternatives66server # [ 0.000000] Kernel command line: console=ttyAMA0 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/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test/init regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration console=ttyAMA0,115200n8 console=tty067server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration", will be passed to user space.68server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes69server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)70server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)71server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB72server # [ 0.000000] software IO TLB: area num 1.73server # [ 0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB)74server # [ 0.000000] Fallback order for Node 0: 075server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214476server # [ 0.000000] Policy zone: DMA77server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off78server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=179server # [ 0.000000] allocated 2097152 bytes of page_ext80server # [ 0.000000] ftrace: allocating 74713 entries in 292 pages81server # [ 0.000000] ftrace: allocated 292 pages with 3 groups82server # [ 0.000000] rcu: Hierarchical RCU implementation.83server # [ 0.000000] rcu: RCU event tracing is enabled.84server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.85server # [ 0.000000] Trampoline variant of Tasks RCU enabled.86server # [ 0.000000] Rude variant of Tasks RCU enabled.87server # [ 0.000000] Tracing variant of Tasks RCU enabled.88server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.89server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=190server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.91server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.92server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.93server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 094server # [ 0.000000] GICv3: 256 SPIs implemented95server # [ 0.000000] GICv3: 0 Extended SPIs implemented96server # [ 0.000000] Root IRQ handler: gic_handle_irq97server # [ 0.000000] GICv3: GICv3 features: 16 PPIs98server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=099server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000100server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]101server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)102server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)103server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000104server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000105server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.106server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).107server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns108server # [ 0.000082] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns109server # [ 0.007712] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)110server # [ 0.039566] Console: colour dummy device 80x25111server # [ 0.043963] printk: legacy console [tty0] enabled112server # [ 0.060473] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)113server # [ 0.061480] pid_max: default: 32768 minimum: 301114server # [ 0.070407] LSM: initializing lsm=capability,landlock,yama,bpf,ima115server # [ 0.074448] landlock: Up and running.116server # [ 0.074723] Yama: becoming mindful.117server # [ 0.077569] LSM support for eBPF active118server # [ 0.084397] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)119server # [ 0.084723] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)120server # [ 0.159501] cacheinfo: Unable to detect cache hierarchy for CPU 0121server # [ 0.192611] rcu: Hierarchical SRCU implementation.122server # [ 0.192849] rcu: Max phase no-delay instances is 1000.123server # [ 0.213038] fsl-mc MSI: its@8080000 domain created124server # [ 0.217561] EFI services will not be available.125server # [ 0.220965] smp: Bringing up secondary CPUs ...126server # [ 0.222743] smp: Brought up 1 node, 1 CPU127server # [ 0.222902] SMP: Total of 1 processors activated.128server # [ 0.223009] CPU: All CPU(s) started at EL1129server # [ 0.223661] CPU features: detected: Branch Target Identification130server # [ 0.223806] CPU features: detected: 32-bit EL0 Support131server # [ 0.223882] CPU features: detected: 32-bit EL1 Support132server # [ 0.223947] CPU features: detected: ARMv8.4 Translation Table Level133server # [ 0.224030] CPU features: detected: Instruction cache invalidation not required for I/D coherence134server # [ 0.224128] CPU features: detected: Data cache clean to the PoU not required for I/D coherence135server # [ 0.224280] CPU features: detected: Common not Private translations136server # [ 0.224352] CPU features: detected: CRC32 instructions137server # [ 0.224411] CPU features: detected: Data cache clean to Point of Deep Persistence138server # [ 0.224485] CPU features: detected: Data cache clean to Point of Persistence139server # [ 0.224553] CPU features: detected: Data independent timing control (DIT)140server # [ 0.224620] CPU features: detected: E0PD141server # [ 0.224672] CPU features: detected: Enhanced Counter Virtualization142server # [ 0.224759] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)143server # [ 0.224828] CPU features: detected: Enhanced Privileged Access Never144server # [ 0.224892] CPU features: detected: Enhanced Virtualization Traps145server # [ 0.224969] CPU features: detected: Fine Grained Traps146server # [ 0.225053] CPU features: detected: Guarded Control Stack (GCS)147server # [ 0.225237] CPU features: detected: Generic authentication (IMP DEF algorithm)148server # [ 0.226386] CPU features: detected: RCpc load-acquire (LDAPR)149server # [ 0.226557] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)150server # [ 0.226630] CPU features: detected: LSE atomic instructions151server # [ 0.226692] CPU features: detected: Memory Copy and Memory Set instructions152server # [ 0.226761] CPU features: detected: Privileged Access Never153server # [ 0.226870] CPU features: detected: PMUv3154server # [ 0.226926] CPU features: detected: SCTLR2155server # [ 0.226980] CPU features: detected: RAS Extension Support156server # [ 0.227115] CPU features: detected: RASv1p1 Extension Support157server # [ 0.227179] CPU features: detected: Random Number Generator158server # [ 0.227256] CPU features: detected: Speculation barrier (SB)159server # [ 0.227320] CPU features: detected: Stage-2 Force Write-Back160server # [ 0.227383] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality161server # [ 0.227458] CPU features: detected: TLB range maintenance instructions162server # [ 0.227523] CPU features: detected: WFx with timeout163server # [ 0.227734] CPU features: detected: Scalable Matrix Extension164server # [ 0.227807] CPU features: detected: FA64165server # [ 0.227860] CPU features: detected: SME2166server # [ 0.227914] CPU features: detected: Speculative Store Bypassing Safe (SSBS)167server # [ 0.227981] CPU features: detected: Scalable Vector Extension168server # [ 0.246108] alternatives: applying system-wide alternatives169server # [ 0.275985] CPU features: detected: Hardware dirty bit management on CPU0170server # [ 0.277513] SVE: maximum available vector length 256 bytes per vector171server # [ 0.277703] SVE: default vector length 64 bytes per vector172server # [ 0.280334] SME: minimum available vector length 16 bytes per vector173server # [ 0.280496] SME: maximum available vector length 256 bytes per vector174server # [ 0.280581] SME: default vector length 32 bytes per vector175server # [ 0.311674] Memory: 891112K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 116168K reserved, 32768K cma-reserved)176server # [ 0.396260] devtmpfs: initialized177server # [ 0.493141] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns178server # [ 0.494590] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)179server # [ 0.495182] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).180server # [ 0.503497] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL181server # [ 0.503715] 0 pages in range for non-PLT usage182server # [ 0.503821] 508320 pages in range for PLT usage183server # [ 0.510610] pinctrl core: initialized pinctrl subsystem184server # [ 0.537357] DMI not present or invalid.185server # [ 0.575286] NET: Registered PF_NETLINK/PF_ROUTE protocol family186server # [ 0.601181] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations187server # [ 0.604712] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations188server # [ 0.608536] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations189server # [ 0.609207] audit: initializing netlink subsys (disabled)190server # [ 0.613507] audit: type=2000 audit(0.452:1): state=initialized audit_enabled=0 res=1191server # [ 0.630075] thermal_sys: Registered thermal governor 'fair_share'192server # [ 0.630230] thermal_sys: Registered thermal governor 'bang_bang'193server # [ 0.630358] thermal_sys: Registered thermal governor 'step_wise'194server # [ 0.630424] thermal_sys: Registered thermal governor 'user_space'195server # [ 0.630488] thermal_sys: Registered thermal governor 'power_allocator'196server # [ 0.631619] cpuidle: using governor ladder197server # [ 0.632071] cpuidle: using governor menu198server # [ 0.636887] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.199server # [ 0.638173] ASID allocator initialised with 65536 entries200server # [ 0.662647] Serial: AMBA PL011 UART driver201server # [ 0.818609] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1202server # [ 0.824079] printk: console [ttyAMA0] enabled203server # [ 1.028618] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages204server # [ 1.028961] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page205server # [ 1.029098] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages206server # [ 1.029222] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page207server # [ 1.029329] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages208server # [ 1.029427] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page209server # [ 1.029519] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages210server # [ 1.029606] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page211server # [ 1.108056] fbcon: Taking over console212server # [ 1.108839] ACPI: Interpreter disabled.213server # [ 1.160414] iommu: Default domain type: Translated214server # [ 1.160722] iommu: DMA domain TLB invalidation policy: strict mode215server # [ 1.168973] SCSI subsystem initialized216server # [ 1.193994] usbcore: registered new interface driver usbfs217server # [ 1.196494] usbcore: registered new interface driver hub218server # [ 1.197184] usbcore: registered new device driver usb219server # [ 1.212992] pps_core: LinuxPPS API ver. 1 registered220server # [ 1.213289] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>221server # [ 1.213559] PTP clock support registered222server # [ 1.214449] EDAC MC: Ver: 3.0.0223server # [ 1.232139] scmi_core: SCMI protocol bus registered224server # [ 1.250115] FPGA manager framework225server # [ 1.305868] vgaarb: loaded226server # [ 1.324018] clocksource: Switched to clocksource arch_sys_counter227server # [ 1.364206] VFS: Disk quotas dquot_6.6.0228server # [ 1.364873] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)229server # [ 1.378470] netfs: FS-Cache loaded230server # [ 1.382872] pnp: PnP ACPI: disabled231server # [ 1.534972] NET: Registered PF_INET protocol family232server # [ 1.545760] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)233server # [ 2.059110] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)234server # [ 2.069440] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)235server # [ 2.069911] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)236server # [ 2.070580] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)237server # [ 2.070999] TCP: Hash tables configured (established 8192 bind 8192)238server # [ 2.086775] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)239server # [ 2.088827] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)240server # [ 2.089537] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)241server # [ 2.105164] NET: Registered PF_UNIX/PF_LOCAL protocol family242server # [ 2.106524] NET: Registered PF_XDP protocol family243server # [ 2.107188] PCI: CLS 0 bytes, default 64244server # [ 2.132265] kvm [1]: HYP mode not available245server # [ 2.158592] Trying to unpack rootfs image as initramfs...246server # [ 3.028869] Initialise system trusted keyrings247server # [ 3.038544] workingset: timestamp_bits=42 max_order=18 bucket_order=0248server # [ 3.113657] squashfs: version 4.0 (2009/01/31) Phillip Lougher249server # [ 3.117960] 9p: Installing v9fs 9p2000 file system support250server # [ 3.750154] Key type asymmetric registered251server # [ 3.750843] Asymmetric key parser 'x509' registered252server # [ 3.769539] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)253server # [ 3.772562] io scheduler mq-deadline registered254server # [ 3.772914] io scheduler kyber registered255server # [ 4.070438] pl061_gpio 9030000.pl061: PL061 GPIO chip registered256server # [ 4.093011] ledtrig-cpu: registered to indicate activity on CPUs257server # [ 4.110884] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:258server # [ 4.125006] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000259server # [ 4.126600] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000260server # [ 4.126933] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000261server # [ 4.134660] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits262server # [ 4.138569] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]263server # [ 4.144869] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00264server # [ 4.145703] pci_bus 0000:00: root bus resource [bus 00-ff]265server # [ 4.145981] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]266server # [ 4.146155] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]267server # [ 4.146313] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]268server # [ 4.161412] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint269server # [ 4.188395] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint270server # [ 4.189114] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]271server # [ 4.189413] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]272server # [ 4.189587] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]273server # [ 4.189839] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]274server # [ 4.191413] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint275server # [ 4.200364] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]276server # [ 4.200622] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]277server # [ 4.200771] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]278server # [ 4.202277] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint279server # [ 4.202584] pci 0000:00:03.0: BAR 0 [io 0x0000-0x001f]280server # [ 4.202701] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]281server # [ 4.202816] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282server # [ 4.212851] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint283server # [ 4.213260] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]284server # [ 4.213402] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]285server # [ 4.213518] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]286server # [ 4.214878] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint287server # [ 4.215161] pci 0000:00:05.0: BAR 0 [io 0x0000-0x007f]288server # [ 4.215339] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]289server # [ 4.215462] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290server # [ 4.233631] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint291server # [ 4.234069] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]292server # [ 4.234223] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]293server # [ 4.234356] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]294server # [ 4.238324] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint295server # [ 4.238719] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]296server # [ 4.238847] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]297server # [ 4.238963] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]298server # [ 4.239078] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]299server # [ 4.257958] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint300server # [ 4.258417] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]301server # [ 4.258572] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302server # [ 4.262712] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint303server # [ 4.263103] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]304server # [ 4.263267] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]305server # [ 4.271043] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint306server # [ 4.271447] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]307server # [ 4.283263] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint308server # [ 4.285918] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]309server # [ 4.286187] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]310server # [ 4.286373] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]311server # [ 4.292054] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint312server # [ 4.292454] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]313server # [ 4.292594] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]314server # [ 4.292737] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]315server # [ 4.313521] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned316server # [ 4.314092] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned317server # [ 4.314446] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned318server # [ 4.314860] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned319server # [ 4.315098] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned320server # [ 4.315291] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned321server # [ 4.315460] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned322server # [ 4.328348] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned323server # [ 4.328704] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned324server # [ 4.328890] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned325server # [ 4.329053] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned326server # [ 4.329231] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned327server # [ 4.329514] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned328server # [ 4.329749] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned329server # [ 4.329919] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned330server # [ 4.330059] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned331server # [ 4.330192] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned332server # [ 4.330352] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned333server # [ 4.330484] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned334server # [ 4.330612] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned335server # [ 4.330956] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned336server # [ 4.331106] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned337server # [ 4.331297] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned338server # [ 4.331444] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned339server # [ 4.356281] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned340server # [ 4.356672] pci 0000:00:05.0: BAR 0 [io 0x1000-0x107f]: assigned341server # [ 4.356940] pci 0000:00:06.0: BAR 0 [io 0x1080-0x10ff]: assigned342server # [ 4.357086] pci 0000:00:0b.0: BAR 0 [io 0x1100-0x113f]: assigned343server # [ 4.357230] pci 0000:00:01.0: BAR 0 [io 0x1140-0x115f]: assigned344server # [ 4.357367] pci 0000:00:02.0: BAR 0 [io 0x1160-0x117f]: assigned345server # [ 4.357501] pci 0000:00:03.0: BAR 0 [io 0x1180-0x119f]: assigned346server # [ 4.357630] pci 0000:00:04.0: BAR 0 [io 0x11a0-0x11bf]: assigned347server # [ 4.357754] pci 0000:00:07.0: BAR 0 [io 0x11c0-0x11df]: assigned348server # [ 4.357878] pci 0000:00:0c.0: BAR 0 [io 0x11e0-0x11ff]: assigned349server # [ 4.358569] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]350server # [ 4.358740] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]351server # [ 4.358852] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]352server # [ 4.362606] pci 0000:00:0a.0: enabling device (0000 -> 0002)353server # [ 4.381614] pci 0000:00:0a.0: quirk_usb_early_handoff+0x0/0xa60 took 19221 usecs354server # [ 4.746711] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)355server # [ 4.763447] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)356server # [ 4.776459] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)357server # [ 4.792959] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)358server # [ 4.806985] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)359server # [ 4.813373] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)360server # [ 4.825344] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)361server # [ 4.838982] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)362server # [ 4.845189] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)363server # [ 4.857470] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)364server # [ 4.870875] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)365server # [ 4.981222] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled366server # [ 5.038990] msm_serial: driver initialized367server # [ 5.050886] SuperH (H)SCI(F) driver initialized368server # [ 5.053825] STM32 USART driver initialized369server # [ 5.474053] loop: module loaded370server # [ 5.493833] virtio_blk virtio4: 1/0/0 default/read/poll queues371server # [ 5.507323] virtio_blk virtio4: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)372server # [ 5.604905] virtio_blk virtio5: 1/0/0 default/read/poll queues373server # [ 5.610057] virtio_blk virtio5: [vdb] 2919560 512-byte logical blocks (1.49 GB/1.39 GiB)374server # [ 5.655018] megasas: 07.734.00.00-rc1375server # [ 5.701082] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]376server # [ 5.714307] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000377server # [ 5.715446] Intel/Sharp Extended Query Table at 0x0031378server # [ 5.719226] Using buffer write method379server # [ 5.729710] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]380server # [ 5.732946] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000381server # [ 5.733304] Intel/Sharp Extended Query Table at 0x0031382server # [ 5.735031] Using buffer write method383server # [ 5.735494] Concatenating MTD devices:384server # [ 5.743963] (0): "0.flash"385server # [ 5.744364] (1): "0.flash"386server # [ 5.744488] into device "0.flash"387server # [ 7.649436] Freeing initrd memory: 25572K388server # [ 7.917171] tun: Universal TUN/TAP device driver, 1.6389server # [ 7.990230] thunder_xcv, ver 1.0390server # [ 7.990991] thunder_bgx, ver 1.0391server # [ 7.991517] nicpf, ver 1.0392server # [ 8.018568] e1000: Intel(R) PRO/1000 Network Driver393server # [ 8.018848] e1000: Copyright (c) 1999-2006 Intel Corporation.394server # [ 8.019372] e1000e: Intel(R) PRO/1000 Network Driver395server # [ 8.019517] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.396server # [ 8.027209] igb: Intel(R) Gigabit Ethernet Network Driver397server # [ 8.027508] igb: Copyright (c) 2007-2014 Intel Corporation.398server # [ 8.028961] igbvf: Intel(R) Gigabit Virtual Function Network Driver399server # [ 8.029182] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.400server # [ 8.031328] sky2: driver version 1.30401server # [ 8.082802] ehci-pci 0000:00:0a.0: EHCI Host Controller402server # [ 8.084365] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1403server # [ 8.088761] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000404server # [ 8.097190] usbcore: registered new interface driver usb-storage405server # [ 8.098438] usbcore: registered new interface driver usbserial_generic406server # [ 8.099019] usbserial: USB Serial support registered for generic407server # [ 8.101113] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00408server # [ 8.124992] hub 1-0:1.0: USB hub found409server # [ 8.129839] hv_vmbus: registering driver hyperv_keyboard410server # [ 8.132797] hub 1-0:1.0: 6 ports detected411server # [ 8.164909] rtc-pl031 9010000.pl031: registered as rtc0412server # [ 8.166072] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T22:26:27 UTC (1791498387)413server # [ 8.173611] i2c_dev: i2c /dev entries driver414server # [ 8.217421] sdhci: Secure Digital Host Controller Interface driver415server # [ 8.217704] sdhci: Copyright(c) Pierre Ossman416server # [ 8.222975] Synopsys Designware Multimedia Card Interface Driver417server # [ 8.229858] sdhci-pltfm: SDHCI platform and OF driver helper418server # [ 8.253580] hid: raw HID events driver (C) Jiri Kosina419server # [ 8.259558] usbcore: registered new interface driver usbhid420server # [ 8.260288] usbhid: USB HID core driver421server # [ 8.290737] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available422server # [ 8.316302] drop_monitor: Initializing network drop monitor service423server # [ 8.319045] NET: Registered PF_INET6 protocol family424server # [ 8.338202] Segment Routing with IPv6425server # [ 8.338915] In-situ OAM (IOAM) with IPv6426server # [ 8.341654] NET: Registered PF_PACKET protocol family427server # [ 8.344919] 9pnet: Installing 9P2000 support428server # [ 8.355382] Key type dns_resolver registered429server # [ 8.408787] usb 1-1: new high-speed USB device number 2 using ehci-pci430server # [ 8.472726] registered taskstats version 1431server # [ 8.480600] Loading compiled-in X.509 certificates432server # [ 8.644477] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0433server # [ 8.685402] Demotion targets for Node 0: null434server # [ 8.689384] Key type .fscrypt registered435server # [ 8.689671] Key type fscrypt-provisioning registered436server # [ 8.708087] ima: No TPM chip found, activating TPM-bypass!437server # [ 8.708531] ima: Allocated hash algorithm: sha1438server # [ 8.725364] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0439server # [ 8.729804] ima: No architecture policies found440server # [ 8.749934] input: gpio-keys as /devices/platform/gpio-keys/input/input1441server # [ 8.865069] usb 1-2: new high-speed USB device number 3 using ehci-pci442server # [ 8.926177] clk: Disabling unused clocks443server # [ 8.926824] PM: genpd: Disabling unused power domains444server # [ 9.309961] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2445server # [ 9.312706] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0446server # [ 9.936313] Freeing unused kernel memory: 4736K447server # [ 9.938499] Run /init as init process448server # [ 10.298081] systemd[1]: Successfully made /usr/ read-only.449server # [ 10.712548] 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)450server # [ 10.720487] systemd[1]: Detected virtualization qemu.451server # [ 10.724259] systemd[1]: Detected architecture arm64.452server # [ 10.726658] systemd[1]: Running in initrd.453server # [ 10.765918] systemd[1]: Initializing machine ID from random generator.454server # [ 10.789862] systemd[1]: Hostname set to <server>.455server # [ 13.147093] systemd[1]: Queued start job for default target Initrd Default Target.456server # [ 13.354721] systemd[1]: Created slice Slice /system/modprobe.457server # [ 13.378855] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.458server # [ 13.384366] systemd[1]: Expecting device /dev/disk/by-label/nix-store...459server # [ 13.388708] systemd[1]: Expecting device /dev/disk/by-label/nixos...460server # [ 13.392705] systemd[1]: Reached target Path Units.461server # [ 13.395970] systemd[1]: Reached target Slice Units.462server # [ 13.398795] systemd[1]: Reached target Swaps.463server # [ 13.401978] systemd[1]: Reached target Timer Units.464server # [ 13.413012] systemd[1]: Listening on D-Bus System Message Bus Socket.465server # [ 13.421992] systemd[1]: Listening on Journal Socket (/dev/log).466server # [ 13.429073] systemd[1]: Listening on Journal Sockets.467server # [ 13.434822] systemd[1]: Listening on udev Control Socket.468server # [ 13.441102] systemd[1]: Listening on udev Kernel Socket.469server # [ 13.444549] systemd[1]: Reached target Socket Units.470server # [ 13.517396] systemd[1]: Starting Create List of Static Device Nodes...471server # [ 13.581812] systemd[1]: Starting Load Kernel Module 9pnet_virtio...472server # [ 13.590166] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs473server # [ 13.702562] systemd[1]: Mounting Kernel Configuration File System...474server # [ 14.206502] systemd[1]: Starting Journal Service...475server # [ 14.342024] systemd[1]: Starting Load Kernel Modules...476server # [ 14.349685] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki477server # [ 14.468039] systemd[1]: Starting Coldplug All udev Devices...478server # [ 14.838793] systemd[1]: Finished Create List of Static Device Nodes.479server # [ 15.061514] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480server # [ 15.121399] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481server # [ 15.182367] systemd[1]: Mounted Kernel Configuration File System.482server # [ 15.370178] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...483server # [ 16.344847] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.484server # [ 16.374024] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev485server # [ 16.692495] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0486server # [ 16.737877] [drm] features: -virgl +edid -resource_blob -host_visible487server # [ 16.738145] [drm] features: -context_init488server # [ 16.743396] systemd-journald[74]: Collecting audit messages is disabled.489server # [ 16.766942] [drm] number of scanouts: 1490server # [ 16.767337] [drm] number of cap sets: 0491server # [ 16.791474] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.492server # [ 16.868911] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic493server # [ 16.869377] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0494server # [ 16.961827] systemd[1]: Starting Create Static Device Nodes in /dev...495server # [ 17.287498] Console: switching to colour frame buffer device 160x50496server # [ 17.350476] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device497server # [ 17.485701] systemd[1]: Finished Load Kernel Modules.498server # [ 17.765911] systemd[1]: Starting Apply Kernel Variables...499server # [ 18.739471] systemd[1]: Finished Create Static Device Nodes in /dev.500server # [ 18.805410] systemd[1]: Reached target Preparation for Local File Systems.501server # [ 18.810991] systemd[1]: Reached target Local File Systems.502server # [ 18.990156] systemd[1]: Starting Rule-based Manager for Device Events and Files...503server # [ 19.278538] systemd[1]: Finished Apply Kernel Variables.504server # [ 23.289767] systemd[1]: Started Rule-based Manager for Device Events and Files.505server # [ 23.638093] systemd[1]: Started Journal Service.506server # [ 23.449238] systemd-modules-load[75]: Inserted module 'dm_mod'507server # [ 23.538398] systemd-modules-load[75]: Module 'virtio_balloon' is built in508server # [ 23.593511] systemd-modules-load[75]: Module 'virtio_console' is built in509server # [ 23.626891] systemd-modules-load[75]: Inserted module 'virtio_gpu'510server # [ 23.674780] systemd-modules-load[75]: Module 'virtio_rng' is built in511server # [ 23.799051] systemd-udevd[81]: Using default interface naming scheme 'v260'.512server # [ 23.866228] systemd[1]: Starting Create System Files and Directories...513server # [ 25.001869] systemd[1]: Finished Create System Files and Directories.514server # [ 26.195979] systemd[1]: Starting Virtual Console Setup...515server # [ 28.171765] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.516server # [ 28.296619] systemd[1]: Finished Virtual Console Setup.517server # [ 59.482071] systemd[1]: Finished Coldplug All udev Devices.518server # [ 59.505205] systemd[1]: Reached target System Initialization.519server # [ 59.510528] systemd[1]: Reached target Basic System.520server # [ 73.610944] (udev-worker)[141]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.521server # [ 73.636990] (udev-worker)[141]: Network interface NamePolicy= disabled on kernel command line.522server # [ 73.663666] (udev-worker)[140]: Network interface NamePolicy= disabled on kernel command line.523server # [ 76.641948] systemd[1]: Found device /dev/disk/by-label/nix-store.524server # [ 76.933347] systemd[1]: Found device /dev/disk/by-label/nixos.525server # [ 76.946101] systemd[1]: Reached target Initrd Root Device.526server # [ 77.017339] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...527server # [ 78.294565] systemd-fsck[162]: nixos: clean, 12/65536 files, 13019/262144 blocks528server # [ 78.483581] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.529server # [ 78.585374] systemd[1]: Mounting /sysroot...530server # [ 79.615075] EXT4-fs (vda): mounted filesystem b4730de7-7260-4382-b53f-90bb14e2932b r/w with ordered data mode. Quota mode: none.531server # [ 79.569905] systemd[1]: Mounted /sysroot.532server # [ 79.581721] systemd[1]: Reached target Initrd Root File System.533server # [ 79.775746] systemd[1]: Mounting /sysroot/nix/.ro-store...534server # [ 79.911792] systemd[1]: Mounting /sysroot/nix/.rw-store...535server # [ 80.129643] systemd[1]: Mounting /sysroot/run...536server # [ 80.421180] systemd[1]: Mounting /sysroot/tmp/shared...537server # [ 80.833885] systemd[1]: Mounting /sysroot/tmp/xchg...538server # [ 81.287587] systemd[1]: Starting Mountpoints Configured in the Real Root...539server # [ 82.278362] erofs (device vdb): mounted with root inode @ nid 36.540server # [ 82.831482] systemd-sysroot-fstab-check[185]: /sysroot should be mounted in the initrd, will request daemon-reload.541server # [ 82.981977] systemd[1]: Mounted /sysroot/nix/.ro-store.542server # [ 82.990425] systemd[1]: Mounted /sysroot/nix/.rw-store.543server # [ 83.005334] systemd[1]: Mounted /sysroot/run.544server # [ 83.014384] systemd[1]: Mounted /sysroot/tmp/shared.545server # [ 83.030317] systemd[1]: Mounted /sysroot/tmp/xchg.546server # [ 83.190107] systemd[1]: Reload requested from client PID 185 ('systemd-sysroot') (unit initrd-parse-etc.service)...547server # [ 83.202867] systemd[1]: Reloading...548server # [ 87.406805] systemd[1]: Reloading finished in 4172 ms.549server # [ 87.668952] systemd-sysroot-fstab-check[185]: Requesting initrd-fs.target/start/replace...550server # [ 87.723932] systemd-sysroot-fstab-check[185]: Requesting swap.target/start/replace...551server # [ 87.803723] systemd[1]: Starting rw-sysroot-nix-store.service...552server # [ 87.832818] systemd[1]: initrd-parse-etc.service: Deactivated successfully.553server # [ 87.858445] systemd[1]: Finished Mountpoints Configured in the Real Root.554server # [ 87.873245] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.555server # [ 88.479313] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.556server # [ 88.491492] systemd[1]: Finished rw-sysroot-nix-store.service.557server # [ 88.567488] systemd[1]: Mounting /sysroot/nix/store...558server # [ 89.711163] systemd[1]: Mounted /sysroot/nix/store.559server # [ 89.745300] systemd[1]: Reached target Initrd File Systems.560server # [ 89.811246] systemd[1]: Starting Find NixOS closure...561server # [ 89.902113] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...562server # [ 91.110342] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.563server # [ 91.213052] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.564server # [ 91.703292] systemd[1]: Finished Find NixOS closure.565server # [ 91.747361] systemd[1]: Reached target Initrd Default Target.566server # [ 91.837096] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...567server # [ 92.794433] systemd[1]: Stopped target Initrd Default Target.568server # [ 92.807044] systemd[1]: Stopped target Basic System.569server # [ 92.826130] systemd[1]: Stopped target Initrd Root Device.570server # [ 92.849267] systemd[1]: Stopped target Path Units.571server # [ 92.859127] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.572server # [ 92.875063] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.573server # [ 92.914402] systemd[1]: Stopped target Slice Units.574server # [ 92.937326] systemd[1]: Stopped target Socket Units.575server # [ 92.955072] systemd[1]: Stopped target System Initialization.576server # [ 92.983619] systemd[1]: Stopped target Swaps.577server # [ 92.998120] systemd[1]: Stopped target Timer Units.578server # [ 93.014032] systemd[1]: dbus.socket: Deactivated successfully.579server # [ 93.037570] systemd[1]: Closed D-Bus System Message Bus Socket.580server # [ 93.057496] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.581server # [ 93.082076] systemd[1]: Stopped Find NixOS closure.582server # [ 93.193381] systemd[1]: Starting Load Kernel Module 9pnet_virtio...583server # [ 93.369895] systemd[1]: Starting rw-sysroot-nix-store.service...584server # [ 93.387376] systemd[1]: systemd-sysctl.service: Deactivated successfully.585server # [ 93.441390] systemd[1]: Stopped Apply Kernel Variables.586server # [ 93.474678] systemd[1]: systemd-modules-load.service: Deactivated successfully.587server # [ 93.554980] systemd[1]: Stopped Load Kernel Modules.588server # [ 93.641302] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.589server # [ 93.697609] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.590server # [ 93.749035] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.591server # [ 93.837981] systemd[1]: Stopped Create System Files and Directories.592server # [ 93.878888] systemd[1]: Stopped target Local File Systems.593server # [ 93.933341] systemd[1]: Stopped target Preparation for Local File Systems.594server # [ 93.958133] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.595server # [ 94.003881] systemd[1]: Stopped Coldplug All udev Devices.596server # [ 94.047320] systemd[1]: systemd-udev-trigger.service: Consumed 22.915s CPU time over 1min 19.759s wall clock time, 9.3M memory peak.597server # [ 94.065515] systemd[1]: Stopping Rule-based Manager for Device Events and Files...598server # [ 94.086697] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.599server # [ 94.106929] systemd[1]: Stopped Virtual Console Setup.600server # [ 94.555474] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.601server # [ 94.582025] systemd[1]: Finished Load Kernel Module 9pnet_virtio.602server # [ 94.638912] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.603server # [ 94.659127] systemd[1]: Finished rw-sysroot-nix-store.service.604server # [ 94.777581] systemd[1]: initrd-cleanup.service: Deactivated successfully.605server # [ 94.793489] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.606server # [ 95.786900] systemd[1]: systemd-udevd.service: Deactivated successfully.607server # [ 95.799837] systemd[1]: Stopped Rule-based Manager for Device Events and Files.608server # [ 95.827049] systemd[1]: systemd-udevd.service: Consumed 27.718s CPU time over 1min 17.021s wall clock time, 21.2M memory peak.609server # [ 95.871418] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.610server # [ 95.883993] systemd[1]: Closed udev Control Socket.611server # [ 95.915264] systemd[1]: Starting Cleanup udev Database...612server # [ 95.923766] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.613server # [ 95.938177] systemd[1]: Stopped Create Static Device Nodes in /dev.614server # [ 95.946658] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.615server # [ 95.958883] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.616server # [ 95.967175] systemd[1]: kmod-static-nodes.service: Deactivated successfully.617server # [ 95.978980] systemd[1]: Stopped Create List of Static Device Nodes.618server # [ 96.369068] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.619server # [ 96.385837] systemd[1]: Finished Cleanup udev Database.620server # [ 96.423323] systemd[1]: Reached target Switch Root.621server # [ 96.470847] systemd[1]: Starting NixOS Activation...622server # [ 98.342919] initrd-nixos-activation-start[241]: booting system configuration /nix/store/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test623server # [ 99.137323] initrd-nixos-activation-start[241]: running activation script...624server # [ 105.489506] initrd-nixos-activation-start[264]: setting up /etc...625server # [ 108.301725] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.626server # [ 108.311539] systemd[1]: Finished NixOS Activation.627server # [ 108.321896] systemd[1]: initrd-nixos-activation.service: Consumed 9.517s CPU time over 11.824s wall clock time, 36.6M memory peak.628server # [ 108.362638] systemd[1]: Starting Switch Root...629server # [ 108.673040] systemd[1]: Switching root.630server # [ 109.210766] systemd-journald[74]: Received SIGTERM from PID 1 (systemd).631server # [ 111.358802] 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)632server # [ 111.366645] systemd[1]: Detected virtualization qemu.633server # [ 111.373634] systemd[1]: Detected architecture arm64.634server # [ 111.378918] systemd[1]: Detected first boot.635server # [ 111.405316] systemd[1]: Initializing machine ID from random generator.636server # [ 114.468293] systemd[1]: bpf-restrict-fs: LSM BPF program attached637server # [ 116.651494] systemd[1]: Applying preset policy.638server # [ 122.003401] systemd[1]: Populated /etc with preset unit settings.639server # [ 126.436828] systemd[1]: initrd-switch-root.service: Deactivated successfully.640server # [ 126.451142] systemd[1]: Stopped initrd-switch-root.service.641server # [ 126.535435] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.642server # [ 126.575149] systemd[1]: Created slice Slice /system/getty.643server # [ 126.603459] systemd[1]: Created slice User and Session Slice.644server # [ 126.617922] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.645server # [ 126.629102] systemd[1]: Started Forward Password Requests to Wall Directory Watch.646server # [ 126.638381] systemd[1]: Expecting device /dev/hvc0...647server # [ 126.646427] systemd[1]: Expecting device /dev/ttyAMA0...648server # [ 126.654795] systemd[1]: Reached target Local Encrypted Volumes.649server # [ 126.663147] systemd[1]: Stopped target initrd-fs.target.650server # [ 126.671387] systemd[1]: Stopped target initrd-root-fs.target.651server # [ 126.679043] systemd[1]: Stopped target initrd-switch-root.target.652server # [ 126.687599] systemd[1]: Reached target Virtual Machines and Containers.653server # [ 126.696881] systemd[1]: Reached target Path Units.654server # [ 126.705365] systemd[1]: Reached target Remote File Systems.655server # [ 126.714015] systemd[1]: Reached target Slice Units.656server # [ 126.721853] systemd[1]: Reached target Swaps.657server # [ 126.763586] systemd[1]: Listening on Query the User Interactively for a Password.658server # [ 126.807109] systemd[1]: Listening on Process Core Dump Socket.659server # [ 126.839109] systemd[1]: Listening on Credential Encryption/Decryption.660server # [ 126.876815] systemd[1]: Listening on Factory Reset Management.661server # [ 126.941886] systemd[1]: Starting Journal Log Access Socket...662server # [ 126.972737] systemd[1]: Listening on Journal Audit Socket.663server # [ 127.032224] systemd[1]: Listening on Console Output Muting Service Socket.664server # [ 127.045289] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.665server # [ 127.054516] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki666server # [ 127.065103] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki667server # [ 127.136415] systemd[1]: Listening on Disk Repartitioning Service Socket.668server # [ 127.148008] systemd[1]: Listening on udev Control Socket.669server # [ 127.161421] systemd[1]: Listening on udev Varlink Socket.670server # [ 127.235283] systemd[1]: Mounting Huge Pages File System...671server # [ 127.325972] systemd[1]: Mounting POSIX Message Queue File System...672server # [ 127.474077] systemd[1]: Mounting Kernel Debug File System...673server # [ 127.658348] systemd[1]: Mounting Kernel Trace File System...674server # [ 127.914412] systemd[1]: Starting Create List of Static Device Nodes...675server # [ 128.254068] systemd[1]: Starting Load Kernel Module 9pnet_virtio...676server # [ 128.302468] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs677server # [ 128.650214] systemd[1]: Mounting Kernel Configuration File System...678server # [ 128.726002] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm679server # [ 128.790213] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore680server # [ 128.997994] systemd[1]: Starting Load Kernel Module fuse...681server # [ 129.032014] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67682server # [ 129.342265] systemd[1]: Starting Journal Service...683server # [ 129.625977] systemd[1]: Starting Load Kernel Modules...684server # [ 129.888497] fuse: init (API version 7.45)685server # [ 129.977950] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...686server # [ 130.146174] systemd[1]: Starting Remount Root and Kernel File Systems...687server # [ 130.215283] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki688server # [ 130.589933] systemd[1]: Starting Coldplug All udev Devices...689server # [ 131.073723] systemd-journald[335]: Collecting audit messages is enabled.690server # [ 131.443729] systemd[1]: Listening on Journal Log Access Socket.691server # [ 131.547314] systemd[1]: Mounted Huge Pages File System.692server # [ 131.606453] systemd[1]: Mounted POSIX Message Queue File System.693server # [ 131.666036] systemd[1]: Mounted Kernel Debug File System.694server # [ 131.715207] systemd[1]: Mounted Kernel Trace File System.695server # [ 131.834818] systemd[1]: Finished Create List of Static Device Nodes.696server # [ 131.933009] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.697server # [ 131.997531] EXT4-fs (vda): re-mounted b4730de7-7260-4382-b53f-90bb14e2932b.698server # [ 132.026094] systemd[1]: Finished Load Kernel Module 9pnet_virtio.699server # [ 131.895504] systemd[1]: Queued start job for default target Multi-User System.700server # [ 131.955382] systemd[1]: Unnecessary job was removed for /dev/vdb.701server # [ 132.031850] systemd[1]: systemd-journald.service: Deactivated successfully.702server # [ 132.081298] systemd[1]: systemd-journald.service: Consumed 3.296s CPU time over 1min 52.109s wall clock time, 4M memory peak.703server # [ 132.362838] systemd[1]: Started Journal Service.704server # [ 132.162360] systemd-modules-load[336]: Module 'atkbd' is built in705server # [ 132.201898] systemd-modules-load[336]: Module 'loop' is built in706server # [ 132.235060] systemd[1]: Mounted Kernel Configuration File System.707server # [ 132.317314] systemd[1]: modprobe@fuse.service: Deactivated successfully.708server # [ 132.386894] systemd[1]: Finished Load Kernel Module fuse.709server # [ 132.466054] systemd[1]: Finished Load Kernel Modules.710server # [ 132.527529] systemd[1]: Finished Remount Root and Kernel File Systems.711server # [ 133.157784] systemd[1]: Listening on Disk Image Download Service Socket.712server # [ 133.269090] systemd[1]: Mounting FUSE Control File System...713server # [ 133.523458] systemd[1]: Starting Firewall...714server # [ 133.853452] systemd[1]: Starting Flush Journal to Persistent Storage...715server # [ 133.897303] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore716server # [ 134.257993] systemd-oomd[337]: No swap; memory pressure usage will be degraded717server # [ 134.435531] systemd[1]: Starting Load/Save OS Random Seed...718server # [ 134.835828] systemd[1]: Starting Apply Kernel Variables...719server # [ 135.339496] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...720server # [ 135.478169] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki721server # [ 136.040814] systemd-journald[335]: Received client request to flush runtime journal.722server # [ 138.210695] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.723server # [ 138.262002] systemd[1]: Mounted FUSE Control File System.724server # [ 138.301692] systemd[1]: Finished Load/Save OS Random Seed.725server # [ 138.337350] systemd[1]: Reached target First Boot Complete.726server # [ 138.380021] systemd[1]: Finished Flush Journal to Persistent Storage.727server # [ 138.555322] systemd[1]: Finished Apply Kernel Variables.728server # [ 139.331810] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.729server # [ 139.577836] systemd[1]: Starting Create Static Device Nodes in /dev...730server # [ 141.071900] systemd[1]: Finished Create Static Device Nodes in /dev.731server # [ 141.197388] systemd[1]: Reached target Preparation for Local File Systems.732server # [ 141.353641] systemd[1]: Mounting /run/wrappers...733server # [ 141.596981] systemd[1]: Starting Rule-based Manager for Device Events and Files...734server # [ 142.834270] systemd[1]: Mounted /run/wrappers.735server # [ 142.869030] systemd[1]: Reached target Local File Systems.736server # [ 143.001024] systemd[1]: Listening on Boot Loader Control Service Socket.737server # [ 143.181910] systemd[1]: Starting register-nix-paths.service...738server # [ 143.486728] systemd[1]: Starting Create SUID/SGID Wrappers...739server # [ 143.525213] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.740server # [ 143.991306] systemd[1]: Starting Save Transient machine-id to Disk...741server # [ 144.597235] systemd[1]: Starting Create System Files and Directories...742server # [ 147.262998] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.743server # [ 147.366380] systemd[1]: Finished Save Transient machine-id to Disk.744server # [ 150.934450] systemd[1]: Finished Create System Files and Directories.745server # [ 151.449114] systemd[1]: Starting Rebuild Journal Catalog...746server # [ 151.952047] systemd[1]: Starting Record System Boot/Shutdown in UTMP...747server # [ 155.042732] systemd[1]: Finished Record System Boot/Shutdown in UTMP.748server # [ 157.761506] systemd[1]: Finished Rebuild Journal Catalog.749server # [ 158.037002] systemd[1]: Starting Update is Completed...750server # [ 159.782160] systemd[1]: Finished Update is Completed.751server # [ 162.393422] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.752server # [ 162.460264] systemd[1]: Finished Create SUID/SGID Wrappers.753server # [ 162.494408] systemd[1]: suid-sgid-wrappers.service: Consumed 2.547s CPU time over 18.899s wall clock time, 5.1M memory peak, 1.9M read from disk.754server # [ 165.171018] systemd[1]: Finished Firewall.755server # [ 168.254672] systemd[1]: Finished register-nix-paths.service.756server # [ 171.922174] systemd-udevd[394]: Using default interface naming scheme 'v260'.757server # [ 173.879100] systemd[1]: Started Rule-based Manager for Device Events and Files.758server # [ 194.539034] systemd[1]: Finished Coldplug All udev Devices.759server # [ 194.562233] systemd[1]: Reached target System Initialization.760server # [ 194.580952] systemd[1]: Started Discard unused filesystem blocks once a week.761server # [ 194.587882] systemd[1]: Started postgresqlBackup-horae.timer.762server # [ 194.598456] systemd[1]: Started Daily Cleanup of Temporary Directories.763server # [ 194.618195] systemd[1]: Reached target Timer Units.764server # [ 194.637389] systemd[1]: Listening on D-Bus System Message Bus Socket.765server # [ 194.650159] systemd[1]: Listening on Nix Daemon Socket.766server # [ 194.658275] systemd[1]: Listening on Hostname Service Socket.767server # [ 194.666564] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.768server # [ 194.678551] systemd[1]: Reached target Socket Units.769server # [ 194.690376] systemd[1]: Reached target Basic System.770server # [ 194.755242] systemd[1]: Starting Import lastlog data into lastlog2 database...771server # [ 194.941652] systemd[1]: Starting Name Service Cache Daemon (nsncd)...772server # [ 195.143158] systemd[1]: Starting Post-Boot Actions...773server # [ 195.469080] systemd[1]: Started Reset console on configuration changes.774server # [ 195.875069] systemd[1]: Starting resolvconf update...775server # [ 196.276953] systemd[1]: Started test-console-diagnostics.service.776server # [ 197.040918] systemd[1]: Finished Post-Boot Actions.777server # [ 197.179438] nsncd[582]: Oct 08 22:29:36.675 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"778server # [ 197.298275] systemd[1]: Started Name Service Cache Daemon (nsncd).779server # [ 197.825480] systemd[1]: Reached target Host and Network Name Lookups.780server # [ 197.846094] systemd[1]: Reached target User and Group Name Lookups.781server # [ 198.185562] systemd[1]: Starting D-Bus System Message Bus...782server # [ 198.897282] systemd[1]: Starting User Login Management...783server # [ 199.101295] systemd[1]: Finished Import lastlog data into lastlog2 database.784server # [ 200.422273] test-console-diagnostics-start[616]: JOB UNIT TYPE STATE785server # [ 200.450664] test-console-diagnostics-start[616]: 294 dev-ttyAMA0.device start running786server # [ 200.473843] test-console-diagnostics-start[616]: 285 postgresql-setup.service start waiting787server # [ 200.493728] test-console-diagnostics-start[616]: 295 dev-hvc0.device start running788server # [ 200.516852] test-console-diagnostics-start[616]: 296 network.target start waiting789server # [ 200.553791] test-console-diagnostics-start[616]: 315 dbus-broker.service start running790server # [ 200.577767] test-console-diagnostics-start[616]: 297 network-local-commands.service start waiting791server # [ 200.598993] test-console-diagnostics-start[616]: 278 systemd-logind.service start running792server # [ 200.605833] test-console-diagnostics-start[616]: 175 multi-user.target start waiting793server # [ 200.607684] test-console-diagnostics-start[616]: 283 postgresql.target start waiting794server # [ 200.649224] test-console-diagnostics-start[616]: 269 getty.target start waiting795server # [ 200.662238] test-console-diagnostics-start[616]: 270 getty@tty1.service start waiting796server # [ 200.691039] test-console-diagnostics-start[616]: 299 dhcpcd.service start waiting797server # [ 200.707121] test-console-diagnostics-start[616]: 293 backdoor.service start waiting798server # [ 200.740559] test-console-diagnostics-start[616]: 274 dex.service start waiting799server # [ 200.759435] test-console-diagnostics-start[616]: 282 horae.service start waiting800server # [ 200.785783] test-console-diagnostics-start[616]: 284 postgresql.service start waiting801server # [ 200.787821] test-console-diagnostics-start[616]: 231 network-pre.target start waiting802server # [ 200.821357] test-console-diagnostics-start[616]: 276 systemd-user-sessions.service start waiting803server # [ 200.846221] test-console-diagnostics-start[616]: 277 linger-users.service start waiting804server # [ 200.861402] test-console-diagnostics-start[616]: 275 resolvconf.service start running805server # [ 200.881241] test-console-diagnostics-start[616]: 20 jobs listed.806server # [ 201.435181] dbus-broker-launch[603]: Looking up NSS user entry for 'systemd-timesync'...807server # [ 201.885810] dbus-broker-launch[603]: NSS returned no entry for 'systemd-timesync'808server # [ 201.906414] dbus-broker-launch[603]: Invalid user-name in /nix/store/s8i6b1w4ph66lzniglnwcmzrxxzbqy2b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"809server # [ 202.570820] systemd[1]: Started D-Bus System Message Bus.810server # [ 203.505467] dbus-broker-launch[603]: Ready811server # [ 203.982025] systemd-logind[607]: New seat seat0.812server # [ 204.103949] test-console-diagnostics-start[627]: Id=backdoor.service813server # [ 204.126058] test-console-diagnostics-start[627]: Requires=sysinit.target dev-ttyAMA0.device dev-hvc0.device system.slice814server # [ 204.145845] test-console-diagnostics-start[627]: After=sysinit.target dev-ttyAMA0.device systemd-journald.socket basic.target dev-hvc0.device system.slice815server # [ 204.167006] test-console-diagnostics-start[627]: ActiveState=inactive816server # [ 204.173727] test-console-diagnostics-start[627]: SubState=dead817server # [ 204.175428] test-console-diagnostics-start[627]: Result=success818server # [ 204.190038] test-console-diagnostics-start[627]: Id=dev-hvc0.device819server # [ 204.201778] test-console-diagnostics-start[627]: Requires=820server # [ 204.217309] test-console-diagnostics-start[627]: After=821server # [ 204.221191] test-console-diagnostics-start[627]: ActiveState=activating822server # [ 204.233536] test-console-diagnostics-start[627]: SubState=tentative823server # [ 204.249449] test-console-diagnostics-start[627]: Id=dev-ttyAMA0.device824server # [ 204.253379] test-console-diagnostics-start[627]: Requires=825server # [ 204.270892] test-console-diagnostics-start[627]: After=826server # [ 204.286602] test-console-diagnostics-start[627]: ActiveState=activating827server # [ 204.301238] test-console-diagnostics-start[627]: SubState=tentative828server # [ 204.309324] test-console-diagnostics-start[627]: Id=dev-ttyS0.device829server # [ 204.321678] test-console-diagnostics-start[627]: Requires=830server # [ 204.333293] test-console-diagnostics-start[627]: After=831server # [ 204.341464] test-console-diagnostics-start[627]: ActiveState=inactive832server # [ 204.353268] test-console-diagnostics-start[627]: SubState=dead833server # [ 204.398099] systemd[1]: Started User Login Management.834server # [ 204.561675] test-console-diagnostics-start[644]: DEVPATH=/devices/virtual/tty/hvc0835server # [ 204.573612] test-console-diagnostics-start[644]: DEVNAME=/dev/hvc0836server # [ 204.587926] test-console-diagnostics-start[644]: MAJOR=229837server # [ 204.602482] test-console-diagnostics-start[644]: MINOR=0838server # [ 204.621027] test-console-diagnostics-start[644]: SUBSYSTEM=tty839server # [ 204.631032] systemd[1]: Stopped target Host and Network Name Lookups.840server # [ 204.654320] systemd[1]: Stopping Host and Network Name Lookups...841server # [ 204.670478] systemd[1]: Stopped target User and Group Name Lookups.842server # [ 204.690841] systemd[1]: Stopping User and Group Name Lookups...843server # [ 204.838328] systemd[1]: Starting linger-users.service...844server # [ 204.933295] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...845server # [ 205.058452] test-console-diagnostics-start[646]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA0846server # [ 205.073541] test-console-diagnostics-start[646]: DEVNAME=/dev/ttyAMA0847server # [ 205.089457] test-console-diagnostics-start[646]: MAJOR=204848server # [ 205.105760] test-console-diagnostics-start[646]: MINOR=64849server # [ 205.117582] test-console-diagnostics-start[646]: SUBSYSTEM=tty850server # [ 205.165192] systemd[1]: nscd.service: Deactivated successfully.851server # [ 205.276992] systemd[1]: Stopped Name Service Cache Daemon (nsncd).852server # [ 205.281017] systemd[1]: nscd.service: Consumed 541ms CPU time over 10.256s wall clock time, 7.8M memory peak, 5.9M read from disk.853server # [ 205.425863] test-console-diagnostics-start[652]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS0854server # [ 205.442495] test-console-diagnostics-start[652]: DEVNAME=/dev/ttyS0855server # [ 205.457388] test-console-diagnostics-start[652]: MAJOR=4856server # [ 205.466172] test-console-diagnostics-start[652]: MINOR=64857server # [ 205.478432] test-console-diagnostics-start[652]: SUBSYSTEM=tty858server # [ 205.991569] systemd[1]: Starting Name Service Cache Daemon (nsncd)...859server # [ 206.321178] systemd[1]: Finished resolvconf update.860server # [ 206.378605] systemd[1]: linger-users.service: Deactivated successfully.861server # [ 206.406613] systemd[1]: Finished linger-users.service.862server # [ 206.589939] systemd[1]: Reached target Preparation for Network.863server # [ 206.768951] systemd[1]: Starting DHCP Client...864server # [ 206.997432] systemd[1]: Starting Extra networking commands....865server # [ 207.087582] nsncd[664]: Oct 08 22:29:46.619 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"866server # [ 207.130153] systemd[1]: Started Name Service Cache Daemon (nsncd).867server # [ 207.238870] systemd[1]: Reached target Host and Network Name Lookups.868server # [ 207.270546] systemd[1]: Reached target User and Group Name Lookups.869server # [ 211.874667] systemd[1]: Finished Extra networking commands..870server # [ 211.937728] systemd[1]: Reached target Network.871server # [ 212.119046] systemd[1]: Starting dex identity provider...872server # [ 212.266550] dhcpcd[693]: dhcpcd-10.3.2 starting873server # [ 212.382166] dhcpcd[739]: dev: loaded udev874server # [ 212.487137] systemd[1]: Starting PostgreSQL Server...875server # [ 212.521174] dhcpcd[739]: no valid interfaces found876server # [ 212.529789] dhcpcd[739]: no valid interfaces found877server # [ 212.630186] systemd[1]: Starting Permit User Sessions...878server # [ 213.921643] systemd[1]: Finished Permit User Sessions.879server # [ 214.239253] systemd[1]: Started Getty on tty1.880server # [ 214.316944] systemd[1]: Reached target Login Prompts.881server # [ 218.371194] postgresql-pre-start[750]: The files belonging to this database system will be owned by user "postgres".882server # [ 218.387242] postgresql-pre-start[750]: This user must also own the server process.883server # [ 218.456944] postgresql-pre-start[750]: The database cluster will be initialized with locale "en_US.UTF-8".884server # [ 218.461529] postgresql-pre-start[750]: The default database encoding has accordingly been set to "UTF8".885server # [ 218.478334] postgresql-pre-start[750]: The default text search configuration will be set to "english".886server # [ 218.489446] postgresql-pre-start[750]: Data page checksums are disabled.887server # [ 218.493935] postgresql-pre-start[750]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok888server # [ 218.600734] postgresql-pre-start[750]: creating subdirectories ... ok889server # [ 218.610222] postgresql-pre-start[750]: selecting dynamic shared memory implementation ... posix890server # [ 218.764054] systemd[1]: Started dex identity provider.891server # [ 220.919346] postgresql-pre-start[750]: selecting default "max_connections" ... 100892server # [ 221.346207] systemd-logind[607]: Watching system buttons on /dev/input/event1 (gpio-keys)893server # [ 223.257954] postgresql-pre-start[750]: selecting default "shared_buffers" ... 128MB894server # [ 228.391196] dex[765]: time=2026-10-08T22:30:07.899Z level=INFO msg="Version info" dex_version=v2.45.1 go.version=go1.26.4 go.os=linux go.arch=arm64895server # [ 228.419601] dex[765]: time=2026-10-08T22:30:07.943Z level=INFO msg="config issuer" issuer=http://127.0.0.1:5556/dex896server # [ 228.441126] dex[765]: time=2026-10-08T22:30:07.982Z level=INFO msg="config storage" storage_type=memory897server # [ 228.457125] dex[765]: time=2026-10-08T22:30:07.983Z level=INFO msg="config static client" client_name=Horae898server # [ 228.469851] dex[765]: time=2026-10-08T22:30:07.984Z level=INFO msg="config connector" connector_id=mock899server # [ 228.477680] dex[765]: time=2026-10-08T22:30:07.985Z level=INFO msg="config skipping approval screen"900server # [ 228.493871] dex[765]: time=2026-10-08T22:30:07.986Z level=INFO msg="continue on connector failure feature flag enabled"901server # [ 228.511917] dex[765]: time=2026-10-08T22:30:08.000Z level=INFO msg="signer configured" type=local keys_rotation_period=6h902server # [ 228.517859] dex[765]: time=2026-10-08T22:30:08.002Z level=INFO msg="config refresh tokens rotation" enabled=true903server # [ 228.569155] dex[765]: time=2026-10-08T22:30:08.132Z level=ERROR msg="failed to get signing algorithm" err="no signing key found"904server # [ 228.665920] dex[765]: time=2026-10-08T22:30:08.214Z level=INFO msg="keys expired, rotating"905server # [ 233.643848] dex[765]: time=2026-10-08T22:30:13.180Z level=INFO msg="keys rotated" next_rotation=2026-10-09T04:30:13.180Z906server # [ 233.729517] dex[765]: time=2026-10-08T22:30:13.293Z level=INFO msg="listening on" server=http address=127.0.0.1:5556907server # [ 241.956730] mousedev: PS/2 mouse device common for all mice908server # [ 242.592017] dhcpcd[739]: timed out909server # [ 242.606911] dhcpcd[739]: timed out910server # [ 242.667639] systemd[1]: Started DHCP Client.911server # [ 249.427858] systemd-logind[607]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)912server # [ 250.805931] postgresql-pre-start[750]: selecting default time zone ... UTC913server # [ 250.858045] postgresql-pre-start[750]: creating configuration files ... ok914elastic-arm-0f89b4f7: building xzpx080m6x4ijl1gy6jpfanc09xxzl9x-vm-test-run-horae-e2e-oidc.drv915Machine state will be reset. To keep it, pass --keep-machine-state916start all VLans917(finished: start all VLans, in 0.00 seconds)918Test will time out and terminate in 3600 seconds919run the VM test script920additionally exposed symbols:921 server,922 vlan1,923 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_ssh924server: starting vm925server: QEMU running (pid 12)926server # Disk image does not exist, creating the virtualisation disk image...927server # Formatting '/build/vm-state-server/tmp.j97Ac7hmHD', fmt=raw size=1073741824928server # mke2fs 1.47.4 (6-Mar-2025)929server # Discarding device blocks: 0/262144 done930server # Creating filesystem with 262144 4k blocks and 65536 inodes931server # Filesystem UUID: dbc17c46-8b04-4a5b-a960-c9035675691b932server # Superblock backups stored on blocks:933server # 32768, 98304, 163840, 229376934server # 935server # Allocating group tables: 0/8 done936server # Writing inode tables: 0/8 done937server # Creating journal (8192 blocks): done938server # Writing superblocks and filesystem accounting information: 0/8 done939server # 940server # Virtualisation disk image created.941server # Creating Nix store image...942server # Created Nix store image.943server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory944server # qemu-system-aarch64: failed to initialize kvm: No such file or directory945server # qemu-system-aarch64: falling back to tcg946server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]947server # [ 0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jul 4 11:44:22 UTC 2026948server # [ 0.000000] KASLR enabled949server # [ 0.000000] random: crng init done950server # [ 0.000000] Machine model: linux,dummy-virt951server # [ 0.000000] efi: UEFI not found.952server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT953server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]954server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf]955server # [ 0.000000] Zone ranges:956server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]957server # [ 0.000000] DMA32 empty958server # [ 0.000000] Normal empty959server # [ 0.000000] Device empty960server # [ 0.000000] Movable zone start for each node961server # [ 0.000000] Early memory node ranges962server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]963server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]964server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000965server # [ 0.000000] psci: probing for conduit method from DT.966server # [ 0.000000] psci: PSCIv1.1 detected in firmware.967server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs968server # [ 0.000000] psci: Trusted OS migration not required969server # [ 0.000000] psci: SMC Calling Convention v1.0970server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296971server # [ 0.000000] Detected PIPT I-cache on CPU0972server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)973server # [ 0.000000] CPU features: detected: GICv3 CPU interface974server # [ 0.000000] CPU features: detected: HCRX_EL2 register975server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE)976server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)977server # [ 0.000000] CPU features: detected: Spectre-v4978server # [ 0.000000] alternatives: applying boot alternatives979server # [ 0.000000] Kernel command line: console=ttyAMA0 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/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test/init regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration console=ttyAMA0,115200n8 console=tty0980server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/h0kn3rqnggrcr4b76il4684km12lp4kr-closure-info/registration", will be passed to user space.981server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes982server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)983server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)984server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB985server # [ 0.000000] software IO TLB: area num 1.986server # [ 0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB)987server # [ 0.000000] Fallback order for Node 0: 0988server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144989server # [ 0.000000] Policy zone: DMA990server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off991server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1992server # [ 0.000000] allocated 2097152 bytes of page_ext993server # [ 0.000000] ftrace: allocating 74713 entries in 292 pages994server # [ 0.000000] ftrace: allocated 292 pages with 3 groups995server # [ 0.000000] rcu: Hierarchical RCU implementation.996server # [ 0.000000] rcu: RCU event tracing is enabled.997server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.998server # [ 0.000000] Trampoline variant of Tasks RCU enabled.999server # [ 0.000000] Rude variant of Tasks RCU enabled.1000server # [ 0.000000] Tracing variant of Tasks RCU enabled.1001server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.1002server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=11003server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.1004server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.1005server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.1006server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 01007server # [ 0.000000] GICv3: 256 SPIs implemented1008server # [ 0.000000] GICv3: 0 Extended SPIs implemented1009server # [ 0.000000] Root IRQ handler: gic_handle_irq1010server # [ 0.000000] GICv3: GICv3 features: 16 PPIs1011server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=01012server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a00001013server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]1014server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)1015server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)1016server # [ 0.000000] GICv3: using LPI property table @0x0000000044d100001017server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d200001018server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.1019server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).1020server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns1021server # [ 0.000084] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns1022server # [ 0.006102] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)1023server # [ 0.034322] Console: colour dummy device 80x251024server # [ 0.038193] printk: legacy console [tty0] enabled1025server # [ 0.053571] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)1026server # [ 0.055223] pid_max: default: 32768 minimum: 3011027server # [ 0.062947] LSM: initializing lsm=capability,landlock,yama,bpf,ima1028server # [ 0.066850] landlock: Up and running.1029server # [ 0.067052] Yama: becoming mindful.1030server # [ 0.070276] LSM support for eBPF active1031server # [ 0.076953] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)1032server # [ 0.077570] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)1033server # [ 0.157256] cacheinfo: Unable to detect cache hierarchy for CPU 01034server # [ 0.193616] rcu: Hierarchical SRCU implementation.1035server # [ 0.193855] rcu: Max phase no-delay instances is 1000.1036server # [ 0.216391] fsl-mc MSI: its@8080000 domain created1037server # [ 0.221400] EFI services will not be available.1038server # [ 0.225270] smp: Bringing up secondary CPUs ...1039server # [ 0.226778] smp: Brought up 1 node, 1 CPU1040server # [ 0.226956] SMP: Total of 1 processors activated.1041server # [ 0.229572] CPU: All CPU(s) started at EL11042server # [ 0.230397] CPU features: detected: Branch Target Identification1043server # [ 0.230565] CPU features: detected: 32-bit EL0 Support1044server # [ 0.230640] CPU features: detected: 32-bit EL1 Support1045server # [ 0.230706] CPU features: detected: ARMv8.4 Translation Table Level1046server # [ 0.230788] CPU features: detected: Instruction cache invalidation not required for I/D coherence1047server # [ 0.230886] CPU features: detected: Data cache clean to the PoU not required for I/D coherence1048server # [ 0.231019] CPU features: detected: Common not Private translations1049server # [ 0.231085] CPU features: detected: CRC32 instructions1050server # [ 0.231155] CPU features: detected: Data cache clean to Point of Deep Persistence1051server # [ 0.231229] CPU features: detected: Data cache clean to Point of Persistence1052server # [ 0.231295] CPU features: detected: Data independent timing control (DIT)1053server # [ 0.231361] CPU features: detected: E0PD1054server # [ 0.231413] CPU features: detected: Enhanced Counter Virtualization1055server # [ 0.231519] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)1056server # [ 0.232392] CPU features: detected: Enhanced Privileged Access Never1057server # [ 0.232478] CPU features: detected: Enhanced Virtualization Traps1058server # [ 0.232572] CPU features: detected: Fine Grained Traps1059server # [ 0.232666] CPU features: detected: Guarded Control Stack (GCS)1060server # [ 0.232935] CPU features: detected: Generic authentication (IMP DEF algorithm)1061server # [ 0.233199] CPU features: detected: RCpc load-acquire (LDAPR)1062server # [ 0.233356] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)1063server # [ 0.233453] CPU features: detected: LSE atomic instructions1064server # [ 0.233524] CPU features: detected: Memory Copy and Memory Set instructions1065server # [ 0.233592] CPU features: detected: Privileged Access Never1066server # [ 0.233710] CPU features: detected: PMUv31067server # [ 0.233766] CPU features: detected: SCTLR21068server # [ 0.233818] CPU features: detected: RAS Extension Support1069server # [ 0.234001] CPU features: detected: RASv1p1 Extension Support1070server # [ 0.234081] CPU features: detected: Random Number Generator1071server # [ 0.234155] CPU features: detected: Speculation barrier (SB)1072server # [ 0.234222] CPU features: detected: Stage-2 Force Write-Back1073server # [ 0.234286] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality1074server # [ 0.234359] CPU features: detected: TLB range maintenance instructions1075server # [ 0.234424] CPU features: detected: WFx with timeout1076server # [ 0.234628] CPU features: detected: Scalable Matrix Extension1077server # [ 0.234696] CPU features: detected: FA641078server # [ 0.234748] CPU features: detected: SME21079server # [ 0.234801] CPU features: detected: Speculative Store Bypassing Safe (SSBS)1080server # [ 0.234867] CPU features: detected: Scalable Vector Extension1081server # [ 0.254960] alternatives: applying system-wide alternatives1082server # [ 0.281307] CPU features: detected: Hardware dirty bit management on CPU01083server # [ 0.282958] SVE: maximum available vector length 256 bytes per vector1084server # [ 0.283153] SVE: default vector length 64 bytes per vector1085server # [ 0.285842] SME: minimum available vector length 16 bytes per vector1086server # [ 0.286031] SME: maximum available vector length 256 bytes per vector1087server # [ 0.286111] SME: default vector length 32 bytes per vector1088server # [ 0.319483] Memory: 891156K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 116168K reserved, 32768K cma-reserved)1089server # [ 0.370082] devtmpfs: initialized1090server # [ 0.436388] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns1091server # [ 0.438869] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)1092server # [ 0.439541] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).1093server # [ 0.446970] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL1094server # [ 0.447208] 0 pages in range for non-PLT usage1095server # [ 0.447303] 508320 pages in range for PLT usage1096server # [ 0.454264] pinctrl core: initialized pinctrl subsystem1097server # [ 0.483842] DMI not present or invalid.1098server # [ 0.521372] NET: Registered PF_NETLINK/PF_ROUTE protocol family1099server # [ 0.547567] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations1100server # [ 0.551223] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations1101server # [ 0.555088] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations1102server # [ 0.555756] audit: initializing netlink subsys (disabled)1103server # [ 0.562660] audit: type=2000 audit(0.452:1): state=initialized audit_enabled=0 res=11104server # [ 0.578427] thermal_sys: Registered thermal governor 'fair_share'1105server # [ 0.578562] thermal_sys: Registered thermal governor 'bang_bang'1106server # [ 0.578692] thermal_sys: Registered thermal governor 'step_wise'1107server # [ 0.578808] thermal_sys: Registered thermal governor 'user_space'1108server # [ 0.579082] thermal_sys: Registered thermal governor 'power_allocator'1109server # [ 0.580355] cpuidle: using governor ladder1110server # [ 0.580855] cpuidle: using governor menu1111server # [ 0.588189] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.1112server # [ 0.589219] ASID allocator initialised with 65536 entries1113server # [ 0.611628] Serial: AMBA PL011 UART driver1114server # [ 0.769342] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev11115server # [ 0.774237] printk: console [ttyAMA0] enabled1116server # [ 0.895229] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages1117server # [ 0.895574] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page1118server # [ 0.895699] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages1119server # [ 0.895795] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page1120server # [ 0.895888] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages1121server # [ 0.895976] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page1122server # [ 0.896067] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages1123server # [ 0.896202] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page1124server # [ 0.960770] fbcon: Taking over console1125server # [ 0.961550] ACPI: Interpreter disabled.1126server # [ 1.008768] iommu: Default domain type: Translated1127server # [ 1.009091] iommu: DMA domain TLB invalidation policy: strict mode1128server # [ 1.017227] SCSI subsystem initialized1129server # [ 1.042471] usbcore: registered new interface driver usbfs1130server # [ 1.043394] usbcore: registered new interface driver hub1131server # [ 1.044071] usbcore: registered new device driver usb1132server # [ 1.052225] pps_core: LinuxPPS API ver. 1 registered1133server # [ 1.052540] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>1134server # [ 1.052816] PTP clock support registered1135server # [ 1.062599] EDAC MC: Ver: 3.0.01136server # [ 1.067513] scmi_core: SCMI protocol bus registered1137server # [ 1.085207] FPGA manager framework1138server # [ 1.144285] vgaarb: loaded1139server # [ 1.161340] clocksource: Switched to clocksource arch_sys_counter1140server # [ 1.194507] VFS: Disk quotas dquot_6.6.01141server # [ 1.195245] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)1142server # [ 1.207916] netfs: FS-Cache loaded1143server # [ 1.211827] pnp: PnP ACPI: disabled1144server # [ 1.358398] NET: Registered PF_INET protocol family1145server # [ 1.360658] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)1146server # [ 1.760598] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)1147server # [ 1.762164] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)1148server # [ 1.762648] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)1149server # [ 1.763305] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)1150server # [ 1.763786] TCP: Hash tables configured (established 8192 bind 8192)1151server # [ 1.768623] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)1152server # [ 1.770480] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)1153server # [ 1.771191] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)1154server # [ 1.775425] NET: Registered PF_UNIX/PF_LOCAL protocol family1155server # [ 1.779018] NET: Registered PF_XDP protocol family1156server # [ 1.779650] PCI: CLS 0 bytes, default 641157server # [ 1.790682] Trying to unpack rootfs image as initramfs...1158server # [ 1.795966] kvm [1]: HYP mode not available1159server # [ 2.543807] Initialise system trusted keyrings1160server # [ 2.563074] workingset: timestamp_bits=42 max_order=18 bucket_order=01161server # [ 2.619054] squashfs: version 4.0 (2009/01/31) Phillip Lougher1162server # [ 2.629908] 9p: Installing v9fs 9p2000 file system support1163server # [ 3.111801] Key type asymmetric registered1164server # [ 3.125700] Asymmetric key parser 'x509' registered1165server # [ 3.128702] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)1166server # [ 3.141601] io scheduler mq-deadline registered1167server # [ 3.142034] io scheduler kyber registered1168server # [ 3.395615] pl061_gpio 9030000.pl061: PL061 GPIO chip registered1169server # [ 3.438296] ledtrig-cpu: registered to indicate activity on CPUs1170server # [ 3.472264] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:1171server # [ 3.486943] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x00000000001172server # [ 3.488561] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x00100000001173server # [ 3.490643] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x80000000001174server # [ 3.492217] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits1175server # [ 3.503177] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]1176server # [ 3.510579] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:001177server # [ 3.512348] pci_bus 0000:00: root bus resource [bus 00-ff]1178server # [ 3.523283] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]1179server # [ 3.523703] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]1180server # [ 3.523832] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]1181server # [ 3.528608] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint1182server # [ 3.556812] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint1183server # [ 3.570256] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]1184server # [ 3.572696] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]1185server # [ 3.575351] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1186server # [ 3.576090] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]1187server # [ 3.589626] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint1188server # [ 3.590501] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]1189server # [ 3.590976] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]1190server # [ 3.591265] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1191server # [ 3.606992] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint1192server # [ 3.607475] pci 0000:00:03.0: BAR 0 [io 0x0000-0x001f]1193server # [ 3.607782] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]1194server # [ 3.607923] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1195server # [ 3.612487] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint1196server # [ 3.615018] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]1197server # [ 3.615463] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]1198server # [ 3.615753] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1199server # [ 3.630153] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint1200server # [ 3.630979] pci 0000:00:05.0: BAR 0 [io 0x0000-0x007f]1201server # [ 3.631333] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]1202server # [ 3.631931] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1203server # [ 3.638349] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint1204server # [ 3.639019] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]1205server # [ 3.639476] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]1206server # [ 3.639632] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1207server # [ 3.654952] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint1208server # [ 3.656177] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]1209server # [ 3.656512] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]1210server # [ 3.660195] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1211server # [ 3.660734] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]1212server # [ 3.664797] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint1213server # [ 3.666039] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]1214server # [ 3.666279] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1215server # [ 3.668016] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint1216server # [ 3.668354] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]1217server # [ 3.668494] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1218server # [ 3.678822] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint1219server # [ 3.679568] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]1220server # [ 3.683191] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint1221server # [ 3.683698] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]1222server # [ 3.683857] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]1223server # [ 3.683975] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1224server # [ 3.695195] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint1225server # [ 3.695908] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]1226server # [ 3.696086] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]1227server # [ 3.696402] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]1228server # [ 3.710665] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned1229server # [ 3.711346] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned1230server # [ 3.711673] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned1231server # [ 3.712072] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned1232server # [ 3.712336] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned1233server # [ 3.712514] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned1234server # [ 3.712679] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned1235server # [ 3.712850] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned1236server # [ 3.721643] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned1237server # [ 3.721968] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned1238server # [ 3.722159] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned1239server # [ 3.722327] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned1240server # [ 3.722580] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned1241server # [ 3.722828] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned1242server # [ 3.722990] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned1243server # [ 3.723173] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned1244server # [ 3.723341] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned1245server # [ 3.723478] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned1246server # [ 3.723608] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned1247server # [ 3.723736] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned1248server # [ 3.723868] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned1249server # [ 3.724007] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned1250server # [ 3.724164] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned1251server # [ 3.724306] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned1252server # [ 3.724442] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned1253server # [ 3.724616] pci 0000:00:05.0: BAR 0 [io 0x1000-0x107f]: assigned1254server # [ 3.724844] pci 0000:00:06.0: BAR 0 [io 0x1080-0x10ff]: assigned1255server # [ 3.741925] pci 0000:00:0b.0: BAR 0 [io 0x1100-0x113f]: assigned1256server # [ 3.742229] pci 0000:00:01.0: BAR 0 [io 0x1140-0x115f]: assigned1257server # [ 3.742375] pci 0000:00:02.0: BAR 0 [io 0x1160-0x117f]: assigned1258server # [ 3.742508] pci 0000:00:03.0: BAR 0 [io 0x1180-0x119f]: assigned1259server # [ 3.742639] pci 0000:00:04.0: BAR 0 [io 0x11a0-0x11bf]: assigned1260server # [ 3.742768] pci 0000:00:07.0: BAR 0 [io 0x11c0-0x11df]: assigned1261server # [ 3.742896] pci 0000:00:0c.0: BAR 0 [io 0x11e0-0x11ff]: assigned1262server # [ 3.743673] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]1263server # [ 3.743878] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]1264server # [ 3.743991] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]1265server # [ 3.747633] pci 0000:00:0a.0: enabling device (0000 -> 0002)1266server # [ 3.762261] pci 0000:00:0a.0: quirk_usb_early_handoff+0x0/0xa60 took 14915 usecs1267server # [ 4.218738] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)1268server # [ 4.236606] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)1269server # [ 4.248472] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)1270server # [ 4.263263] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)1271server # [ 4.276162] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)1272server # [ 4.288580] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)1273server # [ 4.294333] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)1274server # [ 4.309912] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)1275server # [ 4.323505] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)1276server # [ 4.332910] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)1277server # [ 4.344832] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)1278server # [ 4.488216] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled1279server # [ 4.566013] msm_serial: driver initialized1280server # [ 4.568851] SuperH (H)SCI(F) driver initialized1281server # [ 4.579385] STM32 USART driver initialized1282server # [ 4.865858] loop: module loaded1283server # [ 4.867438] virtio_blk virtio4: 1/0/0 default/read/poll queues1284server # [ 4.884010] virtio_blk virtio4: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1285server # [ 4.934001] virtio_blk virtio5: 1/0/0 default/read/poll queues1286server # [ 4.954414] virtio_blk virtio5: [vdb] 2919560 512-byte logical blocks (1.49 GB/1.39 GiB)1287server # [ 4.986330] megasas: 07.734.00.00-rc11288server # [ 5.018771] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]1289server # [ 5.030919] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001290server # [ 5.032314] Intel/Sharp Extended Query Table at 0x00311291server # [ 5.035961] Using buffer write method1292server # [ 5.045989] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]1293server # [ 5.048466] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x0000001294server # [ 5.048841] Intel/Sharp Extended Query Table at 0x00311295server # [ 5.051146] Using buffer write method1296server # [ 5.051632] Concatenating MTD devices:1297server # [ 5.051801] (0): "0.flash"1298server # [ 5.051893] (1): "0.flash"1299server # [ 5.051973] into device "0.flash"1300server # [ 6.697300] Freeing initrd memory: 25572K1301server # [ 6.977971] tun: Universal TUN/TAP device driver, 1.61302server # [ 7.016058] thunder_xcv, ver 1.01303server # [ 7.016838] thunder_bgx, ver 1.01304server # [ 7.018909] nicpf, ver 1.01305server # [ 7.030517] e1000: Intel(R) PRO/1000 Network Driver1306server # [ 7.030858] e1000: Copyright (c) 1999-2006 Intel Corporation.1307server # [ 7.031526] e1000e: Intel(R) PRO/1000 Network Driver1308server # [ 7.031735] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.1309server # [ 7.032209] igb: Intel(R) Gigabit Ethernet Network Driver1310server # [ 7.032367] igb: Copyright (c) 2007-2014 Intel Corporation.1311server # [ 7.032872] igbvf: Intel(R) Gigabit Virtual Function Network Driver1312server # [ 7.035517] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.1313server # [ 7.039452] sky2: driver version 1.301314server # [ 7.065663] ehci-pci 0000:00:0a.0: EHCI Host Controller1315server # [ 7.066921] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 11316server # [ 7.080108] ehci-pci 0000:00:0a.0: irq 15, io mem 0x100890001317server # [ 7.083786] usbcore: registered new interface driver usb-storage1318server # [ 7.086415] usbcore: registered new interface driver usbserial_generic1319server # [ 7.087225] usbserial: USB Serial support registered for generic1320server # [ 7.090680] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.001321server # [ 7.120720] hv_vmbus: registering driver hyperv_keyboard1322server # [ 7.129366] hub 1-0:1.0: USB hub found1323server # [ 7.137735] hub 1-0:1.0: 6 ports detected1324server # [ 7.159625] rtc-pl031 9010000.pl031: registered as rtc01325server # [ 7.162012] rtc-pl031 9010000.pl031: setting system clock to 2026-10-08T22:32:27 UTC (1791498747)1326server # [ 7.170249] i2c_dev: i2c /dev entries driver1327server # [ 7.220232] sdhci: Secure Digital Host Controller Interface driver1328server # [ 7.220576] sdhci: Copyright(c) Pierre Ossman1329server # [ 7.232313] Synopsys Designware Multimedia Card Interface Driver1330server # [ 7.248570] sdhci-pltfm: SDHCI platform and OF driver helper1331server # [ 7.303540] hid: raw HID events driver (C) Jiri Kosina1332server # [ 7.310639] usbcore: registered new interface driver usbhid1333server # [ 7.310977] usbhid: USB HID core driver1334server # [ 7.333493] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available1335server # [ 7.359940] drop_monitor: Initializing network drop monitor service1336server # [ 7.366417] NET: Registered PF_INET6 protocol family1337server # [ 7.388270] Segment Routing with IPv61338server # [ 7.390436] In-situ OAM (IOAM) with IPv61339server # [ 7.391916] NET: Registered PF_PACKET protocol family1340server # [ 7.395485] 9pnet: Installing 9P2000 support1341server # [ 7.405725] Key type dns_resolver registered1342server # [ 7.418143] usb 1-1: new high-speed USB device number 2 using ehci-pci1343server # [ 7.527740] registered taskstats version 11344server # [ 7.534593] Loading compiled-in X.509 certificates1345server # [ 7.664781] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input01346server # [ 7.759301] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input01347server # [ 7.775593] Demotion targets for Node 0: null1348server # [ 7.779182] Key type .fscrypt registered1349server # [ 7.779537] Key type fscrypt-provisioning registered1350server # [ 7.786677] ima: No TPM chip found, activating TPM-bypass!1351server # [ 7.787159] ima: Allocated hash algorithm: sha11352server # [ 7.791576] ima: No architecture policies found1353server # [ 7.810885] input: gpio-keys as /devices/platform/gpio-keys/input/input11354server # [ 7.893713] usb 1-2: new high-speed USB device number 3 using ehci-pci1355server # [ 7.934875] clk: Disabling unused clocks1356server # [ 7.935548] PM: genpd: Disabling unused power domains1357server # [ 8.180573] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input21358server # [ 8.185374] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input01359server # [ 8.619650] Freeing unused kernel memory: 4736K1360server # [ 8.623017] Run /init as init process1361server # [ 8.971162] systemd[1]: Successfully made /usr/ read-only.1362server # [ 9.361280] 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)1363server # [ 9.370303] systemd[1]: Detected virtualization qemu.1364server # [ 9.372827] systemd[1]: Detected architecture arm64.1365server # [ 9.374720] systemd[1]: Running in initrd.1366server # [ 9.398724] systemd[1]: Initializing machine ID from random generator.1367server # [ 9.410482] systemd[1]: Hostname set to <server>.1368server # [ 11.825811] systemd[1]: Queued start job for default target Initrd Default Target.1369server # [ 11.950490] systemd[1]: Created slice Slice /system/modprobe.1370server # [ 11.964640] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1371server # [ 11.968857] systemd[1]: Expecting device /dev/disk/by-label/nix-store...1372server # [ 11.971460] systemd[1]: Expecting device /dev/disk/by-label/nixos...1373server # [ 11.974175] systemd[1]: Reached target Path Units.1374server # [ 11.976319] systemd[1]: Reached target Slice Units.1375server # [ 11.978697] systemd[1]: Reached target Swaps.1376server # [ 11.980716] systemd[1]: Reached target Timer Units.1377server # [ 11.991925] systemd[1]: Listening on D-Bus System Message Bus Socket.1378server # [ 12.000479] systemd[1]: Listening on Journal Socket (/dev/log).1379server # [ 12.006777] systemd[1]: Listening on Journal Sockets.1380server # [ 12.012742] systemd[1]: Listening on udev Control Socket.1381server # [ 12.018454] systemd[1]: Listening on udev Kernel Socket.1382server # [ 12.020476] systemd[1]: Reached target Socket Units.1383server # [ 12.093259] systemd[1]: Starting Create List of Static Device Nodes...1384server # [ 12.179462] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1385server # [ 12.183598] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1386server # [ 12.307517] systemd[1]: Mounting Kernel Configuration File System...1387server # [ 12.844408] systemd[1]: Starting Journal Service...1388server # [ 13.100195] systemd[1]: Starting Load Kernel Modules...1389server # [ 13.107495] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1390server # [ 13.274723] systemd[1]: Starting Coldplug All udev Devices...1391server # [ 13.615901] systemd[1]: Finished Create List of Static Device Nodes.1392server # [ 13.928573] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1393server # [ 13.995663] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1394server # [ 14.108666] systemd[1]: Mounted Kernel Configuration File System.1395server # [ 14.304138] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1396server # [ 14.878765] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1397server # [ 14.903207] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1398server # [ 15.250947] [drm] pci: virtio-gpu-pci detected at 0000:00:08.01399server # [ 15.277947] [drm] features: -virgl +edid -resource_blob -host_visible1400server # [ 15.278281] [drm] features: -context_init1401server # [ 15.300461] [drm] number of scanouts: 11402server # [ 15.309759] [drm] number of cap sets: 01403server # [ 15.418971] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic1404server # [ 15.419508] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 01405server # [ 15.459586] systemd-journald[74]: Collecting audit messages is disabled.1406server # [ 15.695785] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1407server # [ 15.883541] systemd[1]: Starting Create Static Device Nodes in /dev...1408server # [ 15.896305] Console: switching to colour frame buffer device 160x501409server # [ 15.973221] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device1410server # [ 16.167336] systemd[1]: Finished Load Kernel Modules.1411server # [ 16.432143] systemd[1]: Starting Apply Kernel Variables...1412server # [ 17.432119] systemd[1]: Finished Create Static Device Nodes in /dev.1413server # [ 17.502658] systemd[1]: Reached target Preparation for Local File Systems.1414server # [ 17.508415] systemd[1]: Reached target Local File Systems.1415server # [ 17.720602] systemd[1]: Starting Rule-based Manager for Device Events and Files...1416server # [ 17.907592] systemd[1]: Finished Apply Kernel Variables.1417server # [ 22.545799] systemd[1]: Started Rule-based Manager for Device Events and Files.1418server # [ 22.783985] systemd[1]: Started Journal Service.1419server # [ 22.713270] systemd-modules-load[75]: Inserted module 'dm_mod'1420server # [ 22.782812] systemd-modules-load[75]: Module 'virtio_balloon' is built in1421server # [ 22.849997] systemd-modules-load[75]: Module 'virtio_console' is built in1422server # [ 22.905804] systemd-modules-load[75]: Inserted module 'virtio_gpu'1423server # [ 22.945968] systemd-modules-load[75]: Module 'virtio_rng' is built in1424server # [ 23.035248] systemd-udevd[81]: Using default interface naming scheme 'v260'.1425server # [ 23.131799] systemd[1]: Starting Create System Files and Directories...1426server # [ 24.619989] systemd[1]: Finished Create System Files and Directories.1427server # [ 25.887874] systemd[1]: Starting Virtual Console Setup...1428server # [ 28.415750] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.1429server # [ 28.566883] systemd[1]: Finished Virtual Console Setup.1430server # [ 60.999126] systemd[1]: Finished Coldplug All udev Devices.1431server # [ 61.041853] systemd[1]: Reached target System Initialization.1432server # [ 61.050546] systemd[1]: Reached target Basic System.1433server # [ 76.591365] (udev-worker)[140]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1434server # [ 76.606138] (udev-worker)[140]: Network interface NamePolicy= disabled on kernel command line.1435server # [ 76.639630] (udev-worker)[143]: Network interface NamePolicy= disabled on kernel command line.1436server # [ 78.299841] systemd[1]: Found device /dev/disk/by-label/nix-store.1437server # [ 78.627218] systemd[1]: Found device /dev/disk/by-label/nixos.1438server # [ 78.639503] systemd[1]: Reached target Initrd Root Device.1439server # [ 78.717877] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1440server # [ 80.110671] systemd-fsck[161]: nixos: clean, 12/65536 files, 13019/262144 blocks1441server # [ 80.243388] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1442server # [ 80.327892] systemd[1]: Mounting /sysroot...1443server # [ 81.307502] EXT4-fs (vda): mounted filesystem dbc17c46-8b04-4a5b-a960-c9035675691b r/w with ordered data mode. Quota mode: none.1444server # [ 81.359174] systemd[1]: Mounted /sysroot.1445server # [ 81.395845] systemd[1]: Reached target Initrd Root File System.1446server # [ 81.575472] systemd[1]: Mounting /sysroot/nix/.ro-store...1447server # [ 81.744763] systemd[1]: Mounting /sysroot/nix/.rw-store...1448server # [ 81.991505] systemd[1]: Mounting /sysroot/run...1449server # [ 82.330716] systemd[1]: Mounting /sysroot/tmp/shared...1450server # [ 82.794213] systemd[1]: Mounting /sysroot/tmp/xchg...1451server # [ 83.217802] systemd[1]: Starting Mountpoints Configured in the Real Root...1452server # [ 84.184098] erofs (device vdb): mounted with root inode @ nid 36.1453server # [ 84.798826] systemd-sysroot-fstab-check[184]: /sysroot should be mounted in the initrd, will request daemon-reload.1454server # [ 84.963832] systemd[1]: Mounted /sysroot/nix/.ro-store.1455server # [ 84.972001] systemd[1]: Mounted /sysroot/nix/.rw-store.1456server # [ 84.983110] systemd[1]: Mounted /sysroot/run.1457server # [ 85.001775] systemd[1]: Mounted /sysroot/tmp/shared.1458server # [ 85.017510] systemd[1]: Mounted /sysroot/tmp/xchg.1459server # [ 85.177187] systemd[1]: Reload requested from client PID 184 ('systemd-sysroot') (unit initrd-parse-etc.service)...1460server # [ 85.183533] systemd[1]: Reloading...1461server # [ 88.843521] systemd[1]: Reloading finished in 3625 ms.1462server # [ 89.087200] systemd-sysroot-fstab-check[184]: Requesting initrd-fs.target/start/replace...1463server # [ 89.149255] systemd-sysroot-fstab-check[184]: Requesting swap.target/start/replace...1464server # [ 89.231622] systemd[1]: Starting rw-sysroot-nix-store.service...1465server # [ 89.260929] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1466server # [ 89.290763] systemd[1]: Finished Mountpoints Configured in the Real Root.1467server # [ 89.298438] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1468server # [ 89.783586] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1469server # [ 89.799998] systemd[1]: Finished rw-sysroot-nix-store.service.1470server # [ 89.898704] systemd[1]: Mounting /sysroot/nix/store...1471server # [ 90.815093] systemd[1]: Mounted /sysroot/nix/store.1472server # [ 90.861426] systemd[1]: Reached target Initrd File Systems.1473server # [ 90.951012] systemd[1]: Starting Find NixOS closure...1474server # [ 91.046951] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1475server # [ 92.169731] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1476server # [ 92.279691] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1477server # [ 92.757715] systemd[1]: Finished Find NixOS closure.1478server # [ 92.787618] systemd[1]: Reached target Initrd Default Target.1479server # [ 92.877239] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1480server # [ 93.754908] systemd[1]: Stopped target Initrd Default Target.1481server # [ 93.767528] systemd[1]: Stopped target Basic System.1482server # [ 93.782025] systemd[1]: Stopped target Initrd Root Device.1483server # [ 93.799348] systemd[1]: Stopped target Path Units.1484server # [ 93.814811] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1485server # [ 93.847164] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1486server # [ 93.887949] systemd[1]: Stopped target Slice Units.1487server # [ 93.902158] systemd[1]: Stopped target Socket Units.1488server # [ 93.930635] systemd[1]: Stopped target System Initialization.1489server # [ 93.951060] systemd[1]: Stopped target Swaps.1490server # [ 93.975828] systemd[1]: Stopped target Timer Units.1491server # [ 93.990498] systemd[1]: dbus.socket: Deactivated successfully.1492server # [ 94.007460] systemd[1]: Closed D-Bus System Message Bus Socket.1493server # [ 94.033397] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1494server # [ 94.054903] systemd[1]: Stopped Find NixOS closure.1495server # [ 94.177888] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1496server # [ 94.354937] systemd[1]: Starting rw-sysroot-nix-store.service...1497server # [ 94.370921] systemd[1]: systemd-sysctl.service: Deactivated successfully.1498server # [ 94.410519] systemd[1]: Stopped Apply Kernel Variables.1499server # [ 94.465596] systemd[1]: systemd-modules-load.service: Deactivated successfully.1500server # [ 94.515509] systemd[1]: Stopped Load Kernel Modules.1501server # [ 94.561452] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1502server # [ 94.621608] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1503server # [ 94.668022] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1504server # [ 94.725079] systemd[1]: Stopped Create System Files and Directories.1505server # [ 94.783600] systemd[1]: Stopped target Local File Systems.1506server # [ 94.811138] systemd[1]: Stopped target Preparation for Local File Systems.1507server # [ 94.841666] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1508server # [ 94.869150] systemd[1]: Stopped Coldplug All udev Devices.1509server # [ 94.902502] systemd[1]: systemd-udev-trigger.service: Consumed 23.915s CPU time over 1min 21.739s wall clock time, 9.2M memory peak.1510server # [ 94.934528] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1511server # [ 94.950503] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1512server # [ 94.990899] systemd[1]: Stopped Virtual Console Setup.1513server # [ 95.261449] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1514server # [ 95.278039] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1515server # [ 95.313580] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1516server # [ 95.323634] systemd[1]: Finished rw-sysroot-nix-store.service.1517server # [ 95.460007] systemd[1]: initrd-cleanup.service: Deactivated successfully.1518server # [ 95.478540] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1519server # [ 96.500931] systemd[1]: systemd-udevd.service: Deactivated successfully.1520server # [ 96.518017] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1521server # [ 96.528890] systemd[1]: systemd-udevd.service: Consumed 27.859s CPU time over 1min 18.927s wall clock time, 19.3M memory peak.1522server # [ 96.548912] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1523server # [ 96.557547] systemd[1]: Closed udev Control Socket.1524server # [ 96.599804] systemd[1]: Starting Cleanup udev Database...1525server # [ 96.607705] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1526server # [ 96.619390] systemd[1]: Stopped Create Static Device Nodes in /dev.1527server # [ 96.627963] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1528server # [ 96.638471] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1529server # [ 96.649858] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1530server # [ 96.659895] systemd[1]: Stopped Create List of Static Device Nodes.1531server # [ 97.091490] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1532server # [ 97.107932] systemd[1]: Finished Cleanup udev Database.1533server # [ 97.127775] systemd[1]: Reached target Switch Root.1534server # [ 97.175168] systemd[1]: Starting NixOS Activation...1535server # [ 99.036207] initrd-nixos-activation-start[240]: booting system configuration /nix/store/3nn5davn91miamyv68xi4g704dnx1nsx-nixos-system-server-test1536server # [ 99.801526] initrd-nixos-activation-start[240]: running activation script...1537server # [ 106.131800] initrd-nixos-activation-start[263]: setting up /etc...1538server # [ 108.817903] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1539server # [ 108.827729] systemd[1]: Finished NixOS Activation.1540server # [ 108.839968] systemd[1]: initrd-nixos-activation.service: Consumed 9.211s CPU time over 11.632s wall clock time, 36.6M memory peak.1541server # [ 108.883278] systemd[1]: Starting Switch Root...1542server # [ 109.220609] systemd[1]: Switching root.1543server # [ 109.682260] systemd-journald[74]: Received SIGTERM from PID 1 (systemd).1544server # [ 111.826257] 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)1545server # [ 111.831661] systemd[1]: Detected virtualization qemu.1546server # [ 111.835717] systemd[1]: Detected architecture arm64.1547server # [ 111.842230] systemd[1]: Detected first boot.1548server # [ 111.866835] systemd[1]: Initializing machine ID from random generator.1549server # [ 115.041988] systemd[1]: bpf-restrict-fs: LSM BPF program attached1550server # [ 117.297147] systemd[1]: Applying preset policy.1551server # [ 123.243207] systemd[1]: Populated /etc with preset unit settings.1552server # [ 127.946376] systemd[1]: initrd-switch-root.service: Deactivated successfully.1553server # [ 127.966433] systemd[1]: Stopped initrd-switch-root.service.1554server # [ 128.050897] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1555server # [ 128.091673] systemd[1]: Created slice Slice /system/getty.1556server # [ 128.121943] systemd[1]: Created slice User and Session Slice.1557server # [ 128.136622] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1558server # [ 128.149619] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1559server # [ 128.160218] systemd[1]: Expecting device /dev/hvc0...1560server # [ 128.168872] systemd[1]: Expecting device /dev/ttyAMA0...1561server # [ 128.178245] systemd[1]: Reached target Local Encrypted Volumes.1562server # [ 128.187486] systemd[1]: Stopped target initrd-fs.target.1563server # [ 128.197727] systemd[1]: Stopped target initrd-root-fs.target.1564server # [ 128.207053] systemd[1]: Stopped target initrd-switch-root.target.1565server # [ 128.219191] systemd[1]: Reached target Virtual Machines and Containers.1566server # [ 128.228625] systemd[1]: Reached target Path Units.1567server # [ 128.238126] systemd[1]: Reached target Remote File Systems.1568server # [ 128.246987] systemd[1]: Reached target Slice Units.1569server # [ 128.256185] systemd[1]: Reached target Swaps.1570server # [ 128.300039] systemd[1]: Listening on Query the User Interactively for a Password.1571server # [ 128.347171] systemd[1]: Listening on Process Core Dump Socket.1572server # [ 128.383512] systemd[1]: Listening on Credential Encryption/Decryption.1573server # [ 128.431284] systemd[1]: Listening on Factory Reset Management.1574server # [ 128.533775] systemd[1]: Starting Journal Log Access Socket...1575server # [ 128.579206] systemd[1]: Listening on Journal Audit Socket.1576server # [ 128.639334] systemd[1]: Listening on Console Output Muting Service Socket.1577server # [ 128.655806] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1578server # [ 128.666236] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki1579server # [ 128.676411] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1580server # [ 128.755931] systemd[1]: Listening on Disk Repartitioning Service Socket.1581server # [ 128.769345] systemd[1]: Listening on udev Control Socket.1582server # [ 128.782622] systemd[1]: Listening on udev Varlink Socket.1583server # [ 128.864639] systemd[1]: Mounting Huge Pages File System...1584server # [ 128.963259] systemd[1]: Mounting POSIX Message Queue File System...1585server # [ 129.119205] systemd[1]: Mounting Kernel Debug File System...1586server # [ 129.371469] systemd[1]: Mounting Kernel Trace File System...1587server # [ 129.699522] systemd[1]: Starting Create List of Static Device Nodes...1588server # [ 130.020025] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1589server # [ 130.083760] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1590server # [ 130.483438] systemd[1]: Mounting Kernel Configuration File System...1591server # [ 130.590883] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1592server # [ 130.655344] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1593server # [ 130.927619] systemd[1]: Starting Load Kernel Module fuse...1594server # [ 130.967763] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671595server # [ 131.311641] systemd[1]: Starting Journal Service...1596server # [ 131.693716] systemd[1]: Starting Load Kernel Modules...1597server # [ 132.034016] fuse: init (API version 7.45)1598server # [ 132.123342] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1599server # [ 132.319549] systemd[1]: Starting Remount Root and Kernel File Systems...1600server # [ 132.383122] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1601server # [ 132.867221] systemd[1]: Starting Coldplug All udev Devices...1602server # [ 133.436163] systemd-journald[334]: Collecting audit messages is enabled.1603server # [ 133.845146] systemd[1]: Listening on Journal Log Access Socket.1604server # [ 133.926950] systemd[1]: Mounted Huge Pages File System.1605server # [ 134.002031] systemd[1]: Mounted POSIX Message Queue File System.1606server # [ 134.048423] systemd[1]: Mounted Kernel Debug File System.1607server # [ 134.138367] systemd[1]: Mounted Kernel Trace File System.1608server # [ 134.245393] systemd[1]: Finished Create List of Static Device Nodes.1609server # [ 134.343914] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1610server # [ 134.451266] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1611server # [ 134.514364] EXT4-fs (vda): re-mounted dbc17c46-8b04-4a5b-a960-c9035675691b.1612server # [ 134.527797] systemd[1]: Mounted Kernel Configuration File System.1613server # [ 134.540027] systemd[1]: Queued start job for default target Multi-User System.1614server # [ 134.602922] systemd[1]: Unnecessary job was removed for /dev/vdb.1615server # [ 134.651729] systemd[1]: systemd-journald.service: Deactivated successfully.1616server # [ 134.687984] systemd[1]: systemd-journald.service: Consumed 3.334s CPU time over 1min 54.988s wall clock time, 3.9M memory peak.1617server # [ 134.882972] systemd[1]: Started Journal Service.1618server # [ 134.790044] systemd-modules-load[335]: Module 'atkbd' is built in1619server # [ 134.838947] systemd-modules-load[335]: Module 'loop' is built in1620server # [ 134.911215] systemd[1]: modprobe@fuse.service: Deactivated successfully.1621server # [ 134.999400] systemd[1]: Finished Load Kernel Module fuse.1622server # [ 135.101581] systemd[1]: Finished Load Kernel Modules.1623server # [ 135.197845] systemd[1]: Finished Remount Root and Kernel File Systems.1624server # [ 135.907940] systemd[1]: Listening on Disk Image Download Service Socket.1625server # [ 136.053216] systemd[1]: Mounting FUSE Control File System...1626server # [ 136.323699] systemd[1]: Starting Firewall...1627server # [ 136.709773] systemd[1]: Starting Flush Journal to Persistent Storage...1628server # [ 136.745613] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1629server # [ 137.186552] systemd-oomd[336]: No swap; memory pressure usage will be degraded1630server # [ 137.356045] systemd[1]: Starting Load/Save OS Random Seed...1631server # [ 137.821196] systemd[1]: Starting Apply Kernel Variables...1632server # [ 138.559497] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1633server # [ 138.606484] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1634server # [ 139.143886] systemd-journald[334]: Received client request to flush runtime journal.1635server # [ 141.862550] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1636server # [ 141.939280] systemd[1]: Mounted FUSE Control File System.1637server # [ 142.057597] systemd[1]: Finished Load/Save OS Random Seed.1638server # [ 142.106627] systemd[1]: Reached target First Boot Complete.1639server # [ 142.123078] systemd[1]: Finished Flush Journal to Persistent Storage.1640server # [ 142.394033] systemd[1]: Finished Apply Kernel Variables.1641server # [ 143.122565] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1642server # [ 143.370780] systemd[1]: Starting Create Static Device Nodes in /dev...1643server # [ 145.223635] systemd[1]: Finished Create Static Device Nodes in /dev.1644server # [ 145.373450] systemd[1]: Reached target Preparation for Local File Systems.1645server # [ 145.553533] systemd[1]: Mounting /run/wrappers...1646server # [ 145.833944] systemd[1]: Starting Rule-based Manager for Device Events and Files...1647server # [ 147.255378] systemd[1]: Mounted /run/wrappers.1648server # [ 147.341934] systemd[1]: Reached target Local File Systems.1649server # [ 147.483492] systemd[1]: Listening on Boot Loader Control Service Socket.1650server # [ 147.697655] systemd[1]: Starting register-nix-paths.service...1651server # [ 148.093393] systemd[1]: Starting Create SUID/SGID Wrappers...1652server # [ 148.103159] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1653server # [ 148.729934] systemd[1]: Starting Save Transient machine-id to Disk...1654server # [ 149.401361] systemd[1]: Starting Create System Files and Directories...1655server # [ 152.535958] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1656server # [ 152.674197] systemd[1]: Finished Save Transient machine-id to Disk.1657server # [ 156.955501] systemd[1]: Finished Create System Files and Directories.1658server # [ 157.589361] systemd[1]: Starting Rebuild Journal Catalog...1659server # [ 158.253612] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1660server # [ 161.849553] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1661server # [ 164.687671] systemd[1]: Finished Rebuild Journal Catalog.1662server # [ 165.057460] systemd[1]: Starting Update is Completed...1663server # [ 167.253850] systemd[1]: Finished Update is Completed.1664server # [ 169.883744] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1665server # [ 169.969211] systemd[1]: Finished Create SUID/SGID Wrappers.1666server # [ 169.983893] systemd[1]: suid-sgid-wrappers.service: Consumed 2.880s CPU time over 21.796s wall clock time, 5M memory peak, 1.9M read from disk.1667server # [ 174.676021] systemd[1]: Finished Firewall.1668server # [ 176.515788] systemd[1]: Finished register-nix-paths.service.1669server # [ 182.282084] systemd-udevd[391]: Using default interface naming scheme 'v260'.1670server # [ 184.434505] systemd[1]: Started Rule-based Manager for Device Events and Files.1671server # [ 210.653648] systemd[1]: Finished Coldplug All udev Devices.1672server # [ 210.693729] systemd[1]: Reached target System Initialization.1673server # [ 210.733683] systemd[1]: Started Discard unused filesystem blocks once a week.1674server # [ 210.746854] systemd[1]: Started postgresqlBackup-horae.timer.1675server # [ 210.767811] systemd[1]: Started Daily Cleanup of Temporary Directories.1676server # [ 210.779622] systemd[1]: Reached target Timer Units.1677server # [ 210.800063] systemd[1]: Listening on D-Bus System Message Bus Socket.1678server # [ 210.814155] systemd[1]: Listening on Nix Daemon Socket.1679server # [ 210.839762] systemd[1]: Listening on Hostname Service Socket.1680server # [ 210.869919] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1681server # [ 210.883987] systemd[1]: Reached target Socket Units.1682server # [ 210.906631] systemd[1]: Reached target Basic System.1683server # [ 210.988945] systemd[1]: Starting Import lastlog data into lastlog2 database...1684server # [ 211.259360] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1685server # [ 211.500771] systemd[1]: Starting Post-Boot Actions...1686server # [ 211.947113] systemd[1]: Started Reset console on configuration changes.1687server # [ 212.375982] systemd[1]: Starting resolvconf update...1688server # [ 213.070854] systemd[1]: Started test-console-diagnostics.service.1689server # [ 214.163231] nsncd[592]: Oct 08 22:35:54.542 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1690server # [ 214.262566] systemd[1]: Started Name Service Cache Daemon (nsncd).1691server # [ 214.442800] systemd[1]: Finished Post-Boot Actions.1692server # [ 215.289782] systemd[1]: Reached target Host and Network Name Lookups.1693server # [ 215.307419] systemd[1]: Reached target User and Group Name Lookups.1694server # [ 215.629166] systemd[1]: Starting D-Bus System Message Bus...1695server # [ 216.571701] systemd[1]: Starting User Login Management...1696server # [ 216.829060] systemd[1]: Finished Import lastlog data into lastlog2 database.1697server # [ 218.459591] test-console-diagnostics-start[627]: JOB UNIT TYPE STATE1698server # [ 218.492393] test-console-diagnostics-start[627]: 280 postgresql-setup.service start waiting1699server # [ 218.543047] test-console-diagnostics-start[627]: 316 dbus-broker.service start running1700server # [ 218.559161] test-console-diagnostics-start[627]: 298 linger-users.service start waiting1701server # [ 218.583195] test-console-diagnostics-start[627]: 243 network-pre.target start waiting1702server # [ 218.626389] test-console-diagnostics-start[627]: 266 network.target start waiting1703server # [ 218.650789] test-console-diagnostics-start[627]: 175 multi-user.target start waiting1704server # [ 218.678852] test-console-diagnostics-start[627]: 297 dev-ttyAMA0.device start running1705server # [ 218.718365] test-console-diagnostics-start[627]: 279 postgresql.target start waiting1706server # [ 218.750110] test-console-diagnostics-start[627]: 294 dhcpcd.service start waiting1707server # [ 218.771240] test-console-diagnostics-start[627]: 291 horae.service start waiting1708server # [ 218.790288] test-console-diagnostics-start[627]: 273 getty.target start waiting1709server # [ 218.838366] test-console-diagnostics-start[627]: 283 systemd-logind.service start running1710server # [ 218.855511] test-console-diagnostics-start[627]: 292 systemd-user-sessions.service start waiting1711server # [ 218.898266] test-console-diagnostics-start[627]: 278 resolvconf.service start running1712server # [ 218.919604] test-console-diagnostics-start[627]: 274 getty@tty1.service start waiting1713server # [ 218.953860] test-console-diagnostics-start[627]: 267 network-local-commands.service start waiting1714server # [ 218.974546] test-console-diagnostics-start[627]: 281 postgresql.service start waiting1715server # [ 219.014107] test-console-diagnostics-start[627]: 296 dev-hvc0.device start running1716server # [ 219.034787] test-console-diagnostics-start[627]: 289 dex.service start waiting1717server # [ 219.073644] test-console-diagnostics-start[627]: 295 backdoor.service start waiting1718server # [ 219.115075] test-console-diagnostics-start[627]: 20 jobs listed.1719server # [ 220.053782] dbus-broker-launch[614]: Looking up NSS user entry for 'systemd-timesync'...1720server # [ 220.437961] dbus-broker-launch[614]: NSS returned no entry for 'systemd-timesync'1721server # [ 220.454319] dbus-broker-launch[614]: Invalid user-name in /nix/store/s8i6b1w4ph66lzniglnwcmzrxxzbqy2b-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1722server # [ 221.202075] systemd[1]: Started D-Bus System Message Bus.1723server # [ 222.394459] dbus-broker-launch[614]: Ready1724server # [ 222.790982] systemd-logind[618]: New seat seat0.1725server # [ 222.899592] test-console-diagnostics-start[639]: Id=backdoor.service1726server # [ 222.915528] test-console-diagnostics-start[639]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device1727server # [ 222.946428] test-console-diagnostics-start[639]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket1728server # [ 222.962213] test-console-diagnostics-start[639]: ActiveState=inactive1729server # [ 222.979583] test-console-diagnostics-start[639]: SubState=dead1730server # [ 223.001860] test-console-diagnostics-start[639]: Result=success1731server # [ 223.018034] test-console-diagnostics-start[639]: Id=dev-hvc0.device1732server # [ 223.041787] test-console-diagnostics-start[639]: Requires=1733server # [ 223.053937] test-console-diagnostics-start[639]: After=1734server # [ 223.063771] test-console-diagnostics-start[639]: ActiveState=activating1735server # [ 223.098409] test-console-diagnostics-start[639]: SubState=tentative1736server # [ 223.114126] test-console-diagnostics-start[639]: Id=dev-ttyAMA0.device1737server # [ 223.129725] test-console-diagnostics-start[639]: Requires=1738server # [ 223.141485] test-console-diagnostics-start[639]: After=1739server # [ 223.157999] test-console-diagnostics-start[639]: ActiveState=activating1740server # [ 223.169971] test-console-diagnostics-start[639]: SubState=tentative1741server # [ 223.181596] test-console-diagnostics-start[639]: Id=dev-ttyS0.device1742server # [ 223.193864] test-console-diagnostics-start[639]: Requires=1743server # [ 223.205795] test-console-diagnostics-start[639]: After=1744server # [ 223.217433] test-console-diagnostics-start[639]: ActiveState=inactive1745server # [ 223.229661] test-console-diagnostics-start[639]: SubState=dead1746server # [ 223.275510] systemd[1]: Started User Login Management.1747server # [ 223.522516] test-console-diagnostics-start[654]: DEVPATH=/devices/virtual/tty/hvc01748server # [ 223.547755] test-console-diagnostics-start[654]: DEVNAME=/dev/hvc01749server # [ 223.569861] test-console-diagnostics-start[654]: MAJOR=2291750server # [ 223.582401] test-console-diagnostics-start[654]: MINOR=01751server # [ 223.601945] test-console-diagnostics-start[654]: SUBSYSTEM=tty1752server # [ 223.615865] systemd[1]: Stopped target Host and Network Name Lookups.1753server # [ 223.651763] systemd[1]: Stopping Host and Network Name Lookups...1754server # [ 223.675521] systemd[1]: Stopped target User and Group Name Lookups.1755server # [ 223.699425] systemd[1]: Stopping User and Group Name Lookups...1756server # [ 223.801780] systemd[1]: Starting linger-users.service...1757server # [ 223.902240] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1758server # [ 224.082985] test-console-diagnostics-start[657]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA01759server # [ 224.121107] test-console-diagnostics-start[657]: DEVNAME=/dev/ttyAMA01760server # [ 224.129888] test-console-diagnostics-start[657]: MAJOR=2041761server # [ 224.143443] test-console-diagnostics-start[657]: MINOR=641762server # [ 224.157732] test-console-diagnostics-start[657]: SUBSYSTEM=tty1763server # [ 224.239917] systemd[1]: nscd.service: Deactivated successfully.1764server # [ 224.465713] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1765server # [ 224.482846] systemd[1]: nscd.service: Consumed 665ms CPU time over 13.015s wall clock time, 7.8M memory peak, 5.9M read from disk.1766server # [ 224.642245] test-console-diagnostics-start[662]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS01767server # [ 224.662247] test-console-diagnostics-start[662]: DEVNAME=/dev/ttyS01768server # [ 224.682525] test-console-diagnostics-start[662]: MAJOR=41769server # [ 224.697797] test-console-diagnostics-start[662]: MINOR=641770server # [ 224.713632] test-console-diagnostics-start[662]: SUBSYSTEM=tty1771server # [ 225.482237] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1772server # [ 225.883194] systemd[1]: Finished resolvconf update.1773server # [ 226.001273] systemd[1]: linger-users.service: Deactivated successfully.1774server # [ 226.059159] systemd[1]: Finished linger-users.service.1775server # [ 226.314508] systemd[1]: Reached target Preparation for Network.1776server # [ 226.659064] systemd[1]: Starting DHCP Client...1777server # [ 226.950082] systemd[1]: Starting Extra networking commands....1778server # [ 227.095962] nsncd[675]: Oct 08 22:36:07.527 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1779server # [ 227.225832] systemd[1]: Started Name Service Cache Daemon (nsncd).1780server # [ 227.401311] systemd[1]: Reached target Host and Network Name Lookups.1781server # [ 227.439390] systemd[1]: Reached target User and Group Name Lookups.1782server # [ 233.155515] systemd[1]: Finished Extra networking commands..1783server # [ 233.238573] systemd[1]: Reached target Network.1784server # [ 233.438009] systemd[1]: Starting dex identity provider...1785server # [ 233.526986] dhcpcd[698]: dhcpcd-10.3.2 starting1786server # [ 233.663999] dhcpcd[749]: dev: loaded udev1787server # [ 233.830828] dhcpcd[749]: no valid interfaces found1788server # [ 233.844981] dhcpcd[749]: no valid interfaces found1789server # [ 233.862517] systemd[1]: Starting PostgreSQL Server...1790server # [ 234.034334] systemd[1]: Starting Permit User Sessions...1791server # [ 235.575146] systemd[1]: Finished Permit User Sessions.1792server # [ 235.850139] systemd[1]: Started Getty on tty1.1793server # [ 235.947236] systemd[1]: Reached target Login Prompts.1794server # [ 240.619632] postgresql-pre-start[760]: The files belonging to this database system will be owned by user "postgres".1795server # [ 240.651442] postgresql-pre-start[760]: This user must also own the server process.1796server # [ 240.723692] postgresql-pre-start[760]: The database cluster will be initialized with locale "en_US.UTF-8".1797server # [ 240.746324] postgresql-pre-start[760]: The default database encoding has accordingly been set to "UTF8".1798server # [ 240.759971] postgresql-pre-start[760]: The default text search configuration will be set to "english".1799server # [ 240.782655] postgresql-pre-start[760]: Data page checksums are disabled.1800server # [ 240.813351] postgresql-pre-start[760]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok1801server # [ 240.917591] postgresql-pre-start[760]: creating subdirectories ... ok1802server # [ 240.961073] postgresql-pre-start[760]: selecting dynamic shared memory implementation ... posix1803server # [ 241.043029] systemd[1]: Started dex identity provider.1804server # [ 243.723082] postgresql-pre-start[760]: selecting default "max_connections" ... 1001805server # [ 246.182072] postgresql-pre-start[760]: selecting default "shared_buffers" ... 128MB1806server # [ 252.934880] dex[766]: time=2026-10-08T22:36:33.319Z level=INFO msg="Version info" dex_version=v2.45.1 go.version=go1.26.4 go.os=linux go.arch=arm641807server # [ 252.966972] dex[766]: time=2026-10-08T22:36:33.375Z level=INFO msg="config issuer" issuer=http://127.0.0.1:5556/dex1808server # [ 253.073422] dex[766]: time=2026-10-08T22:36:33.554Z level=INFO msg="config storage" storage_type=memory1809server # [ 253.097644] dex[766]: time=2026-10-08T22:36:33.578Z level=INFO msg="config static client" client_name=Horae1810server # [ 253.110894] dex[766]: time=2026-10-08T22:36:33.591Z level=INFO msg="config connector" connector_id=mock1811server # [ 253.142844] dex[766]: time=2026-10-08T22:36:33.607Z level=INFO msg="config skipping approval screen"1812server # [ 253.162337] dex[766]: time=2026-10-08T22:36:33.636Z level=INFO msg="continue on connector failure feature flag enabled"1813server # [ 253.195962] dex[766]: time=2026-10-08T22:36:33.665Z level=INFO msg="signer configured" type=local keys_rotation_period=6h1814server # [ 253.221562] dex[766]: time=2026-10-08T22:36:33.667Z level=INFO msg="config refresh tokens rotation" enabled=true1815server # [ 253.473952] dex[766]: time=2026-10-08T22:36:33.954Z level=ERROR msg="failed to get signing algorithm" err="no signing key found"1816server # [ 253.673289] dex[766]: time=2026-10-08T22:36:34.158Z level=INFO msg="keys expired, rotating"1817server # [ 258.845326] dex[766]: time=2026-10-08T22:36:39.248Z level=INFO msg="keys rotated" next_rotation=2026-10-09T04:36:39.247Z1818server # [ 258.862623] dex[766]: time=2026-10-08T22:36:39.340Z level=INFO msg="listening on" server=http address=127.0.0.1:55561819server # [ 263.950665] dhcpcd[749]: timed out1820server # [ 263.981782] dhcpcd[749]: timed out1821server # [ 264.074590] systemd[1]: Started DHCP Client.1822server # [ 273.995465] systemd-logind[618]: Watching system buttons on /dev/input/event1 (gpio-keys)1823server # [ 280.127620] postgresql-pre-start[760]: selecting default time zone ... UTC1824server # [ 280.196506] postgresql-pre-start[760]: creating configuration files ... ok1825server # [ 285.934269] test-console-diagnostics-start[832]: JOB UNIT TYPE STATE1826server # [ 285.959889] test-console-diagnostics-start[832]: 280 postgresql-setup.service start waiting1827server # [ 285.994422] test-console-diagnostics-start[832]: 175 multi-user.target start waiting1828server # [ 286.006070] test-console-diagnostics-start[832]: 297 dev-ttyAMA0.device start running1829server # [ 286.021921] test-console-diagnostics-start[832]: 279 postgresql.target start waiting1830server # [ 286.034134] test-console-diagnostics-start[832]: 291 horae.service start waiting1831server # [ 286.050048] test-console-diagnostics-start[832]: 281 postgresql.service start running1832server # [ 286.058240] test-console-diagnostics-start[832]: 296 dev-hvc0.device start running1833server # [ 286.074031] test-console-diagnostics-start[832]: 295 backdoor.service start waiting1834server # [ 286.090958] test-console-diagnostics-start[832]: 8 jobs listed.1835server # [ 287.201536] test-console-diagnostics-start[833]: Id=backdoor.service1836server # [ 287.216774] test-console-diagnostics-start[833]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device1837server # [ 287.222035] test-console-diagnostics-start[833]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket1838server # [ 287.238514] test-console-diagnostics-start[833]: ActiveState=inactive1839server # [ 287.253957] test-console-diagnostics-start[833]: SubState=dead1840server # [ 287.271829] test-console-diagnostics-start[833]: Result=success1841server # [ 287.289262] test-console-diagnostics-start[833]: Id=dev-hvc0.device1842server # [ 287.301687] test-console-diagnostics-start[833]: Requires=1843server # [ 287.318036] test-console-diagnostics-start[833]: After=1844server # [ 287.327542] test-console-diagnostics-start[833]: ActiveState=activating1845server # [ 287.343625] test-console-diagnostics-start[833]: SubState=tentative1846server # [ 287.369513] test-console-diagnostics-start[833]: Id=dev-ttyAMA0.device1847server # [ 287.385897] test-console-diagnostics-start[833]: Requires=1848server # [ 287.405225] test-console-diagnostics-start[833]: After=1849server # [ 287.415499] test-console-diagnostics-start[833]: ActiveState=activating1850server # [ 287.441695] test-console-diagnostics-start[833]: SubState=tentative1851server # [ 287.466239] test-console-diagnostics-start[833]: Id=dev-ttyS0.device1852server # [ 287.478582] test-console-diagnostics-start[833]: Requires=1853server # [ 287.489672] test-console-diagnostics-start[833]: After=1854server # [ 287.505460] test-console-diagnostics-start[833]: ActiveState=inactive1855server # [ 287.518850] test-console-diagnostics-start[833]: SubState=dead1856server # [ 287.747135] test-console-diagnostics-start[834]: DEVPATH=/devices/virtual/tty/hvc01857server # [ 287.774863] test-console-diagnostics-start[834]: DEVNAME=/dev/hvc01858server # [ 287.798352] test-console-diagnostics-start[834]: MAJOR=2291859server # [ 287.807078] test-console-diagnostics-start[834]: MINOR=01860server # [ 287.823618] test-console-diagnostics-start[834]: SUBSYSTEM=tty1861server # [ 288.230554] test-console-diagnostics-start[835]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA01862server # [ 288.251892] test-console-diagnostics-start[835]: DEVNAME=/dev/ttyAMA01863server # [ 288.279859] test-console-diagnostics-start[835]: MAJOR=2041864server # [ 288.293735] test-console-diagnostics-start[835]: MINOR=641865server # [ 288.314258] test-console-diagnostics-start[835]: SUBSYSTEM=tty1866server # [ 288.733984] test-console-diagnostics-start[836]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS01867server # [ 288.750640] test-console-diagnostics-start[836]: DEVNAME=/dev/ttyS01868server # [ 288.781458] test-console-diagnostics-start[836]: MAJOR=41869server # [ 288.791785] test-console-diagnostics-start[836]: MINOR=641870server # [ 288.809899] test-console-diagnostics-start[836]: SUBSYSTEM=tty1871server # [ 297.443004] postgresql-pre-start[760]: running bootstrap script ... ok1872server # [ 299.790040] mousedev: PS/2 mouse device common for all mice1873server # [ 308.878696] systemd-logind[618]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)1874server # [ 340.114373] postgresql-pre-start[760]: performing post-bootstrap initialization ... ok1875server # [ 349.113924] postgresql-pre-start[760]: syncing data to disk ... ok1876server # [ 349.130307] postgresql-pre-start[760]: initdb: warning: enabling "trust" authentication for local connections1877server # [ 349.155011] postgresql-pre-start[760]: 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.1878server # [ 349.186815] postgresql-pre-start[760]: Success. You can now start the database server using:1879server # [ 349.214193] postgresql-pre-start[760]: pg_ctl -D /var/lib/postgresql/17 -l logfile start1880server # [ 350.079936] test-console-diagnostics-start[926]: JOB UNIT TYPE STATE1881server # [ 350.099616] test-console-diagnostics-start[926]: 280 postgresql-setup.service start waiting1882server # [ 350.109599] test-console-diagnostics-start[926]: 175 multi-user.target start waiting1883server # [ 350.122192] test-console-diagnostics-start[926]: 297 dev-ttyAMA0.device start running1884server # [ 350.130488] test-console-diagnostics-start[926]: 279 postgresql.target start waiting1885server # [ 350.142928] test-console-diagnostics-start[926]: 291 horae.service start waiting1886server # [ 350.154595] test-console-diagnostics-start[926]: 281 postgresql.service start running1887server # [ 350.165770] test-console-diagnostics-start[926]: 296 dev-hvc0.device start running1888server # [ 350.181970] test-console-diagnostics-start[926]: 295 backdoor.service start waiting1889server # [ 350.193981] test-console-diagnostics-start[926]: 8 jobs listed.1890server # [ 351.314914] test-console-diagnostics-start[927]: Id=backdoor.service1891server # [ 351.330400] test-console-diagnostics-start[927]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device1892server # [ 351.353867] test-console-diagnostics-start[927]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket1893server # [ 351.365835] test-console-diagnostics-start[927]: ActiveState=inactive1894server # [ 351.382083] test-console-diagnostics-start[927]: SubState=dead1895server # [ 351.390568] test-console-diagnostics-start[927]: Result=success1896server # [ 351.409765] test-console-diagnostics-start[927]: Id=dev-hvc0.device1897server # [ 351.421922] test-console-diagnostics-start[927]: Requires=1898server # [ 351.441917] test-console-diagnostics-start[927]: After=1899server # [ 351.457942] test-console-diagnostics-start[927]: ActiveState=activating1900server # [ 351.470107] test-console-diagnostics-start[927]: SubState=tentative1901server # [ 351.487590] test-console-diagnostics-start[927]: Id=dev-ttyAMA0.device1902server # [ 351.503124] test-console-diagnostics-start[927]: Requires=1903server # [ 351.515975] test-console-diagnostics-start[927]: After=1904server # [ 351.545816] test-console-diagnostics-start[927]: ActiveState=activating1905server # [ 351.554658] test-console-diagnostics-start[927]: SubState=tentative1906server # [ 351.570527] test-console-diagnostics-start[927]: Id=dev-ttyS0.device1907server # [ 351.589831] test-console-diagnostics-start[927]: Requires=1908server # [ 351.602050] test-console-diagnostics-start[927]: After=1909server # [ 351.615860] test-console-diagnostics-start[927]: ActiveState=inactive1910server # [ 351.629611] test-console-diagnostics-start[927]: SubState=dead1911server # [ 351.834827] test-console-diagnostics-start[931]: DEVPATH=/devices/virtual/tty/hvc01912server # [ 351.865388] test-console-diagnostics-start[931]: DEVNAME=/dev/hvc01913server # [ 351.869719] test-console-diagnostics-start[931]: MAJOR=2291914server # [ 351.885773] test-console-diagnostics-start[931]: MINOR=01915server # [ 351.902055] test-console-diagnostics-start[931]: SUBSYSTEM=tty1916server # [ 352.206889] test-console-diagnostics-start[932]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA01917server # [ 352.222848] test-console-diagnostics-start[932]: DEVNAME=/dev/ttyAMA01918server # [ 352.245994] test-console-diagnostics-start[932]: MAJOR=2041919server # [ 352.262556] test-console-diagnostics-start[932]: MINOR=641920server # [ 352.273551] test-console-diagnostics-start[932]: SUBSYSTEM=tty1921server # [ 352.665829] test-console-diagnostics-start[933]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS01922server # [ 352.670379] test-console-diagnostics-start[933]: DEVNAME=/dev/ttyS01923server # [ 352.693551] test-console-diagnostics-start[933]: MAJOR=41924server # [ 352.705693] test-console-diagnostics-start[933]: MINOR=641925server # [ 352.717509] test-console-diagnostics-start[933]: SUBSYSTEM=tty1926server # [ 354.742310] postgres[924]: [924] LOG: starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1927server # [ 354.809295] postgres[924]: [924] LOG: listening on IPv6 address "::1", port 54321928server # [ 354.819094] postgres[924]: [924] LOG: listening on IPv4 address "127.0.0.1", port 54321929server # [ 354.850688] postgres[924]: [924] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1930server # [ 355.164512] postgres[937]: [937] LOG: database system was shut down at 2026-10-08 22:38:00 GMT1931server # [ 355.401720] postgres[924]: [924] LOG: database system is ready to accept connections1932server # [ 355.530242] systemd[1]: Started PostgreSQL Server.1933server # [ 355.654313] systemd[1]: Starting PostgreSQL Setup Scripts...1934server # [ 363.375650] postgresql-setup-start[958]: CREATE DATABASE1935server # [ 364.713447] postgresql-setup-start[973]: CREATE ROLE1936server # [ 365.191631] postgresql-setup-start[975]: ALTER ROLE1937server # [ 365.869229] postgresql-setup-start[977]: ALTER DATABASE1938server # [ 365.994950] systemd[1]: Finished PostgreSQL Setup Scripts.1939server # [ 366.046617] systemd[1]: Reached target PostgreSQL.1940server # [ 366.121300] systemd[1]: Starting Horae time tracking server...1941server # [ 369.950702] horae[981]: 2026-10-08T22:38:30.379066Z INFO horae: Running migrations...1942server # [ 372.411482] horae[981]: 2026-10-08T22:38:32.832248Z WARN sqlx::query: slow statement: execution time exceeded alert threshold summary="-- Horae Phase 1 …" db.statement="\n\n-- Horae Phase 1 schema\n-- All IDs are UUID v7 (time-ordered).\n-- Time stored as integer minutes; money as integer cents + ISO 4217 currency.\n\n-- ── Enum types ───────────────────────────────────────────────────────────────\n\nCREATE TYPE org_role AS ENUM ('admin', 'manager', 'member');\nCREATE TYPE project_role AS ENUM ('lead', 'freelancer', 'admin');\nCREATE TYPE project_type AS ENUM ('time_and_materials', 'fixed_fee', 'non_billable', 'retainer');\nCREATE TYPE entry_state AS ENUM ('open', 'submitted', 'approved', 'invoiced');\nCREATE TYPE budget_kind AS ENUM ('none', 'amount', 'hours');\nCREATE TYPE round_dir AS ENUM ('nearest', 'up', 'down');\n\n-- ── Tables ───────────────────────────────────────────────────────────────────\n\nCREATE TABLE organizations (\n id uuid PRIMARY KEY,\n name text NOT NULL,\n default_currency char(3) NOT NULL DEFAULT 'EUR',\n week_start smallint NOT NULL DEFAULT 1, -- 1 = Monday\n round_minutes smallint NOT NULL DEFAULT 0, -- 0 = no rounding\n round_dir round_dir NOT NULL DEFAULT 'nearest',\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE users (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n email text NOT NULL UNIQUE,\n name text NOT NULL,\n oidc_subject text UNIQUE, -- null until first OIDC login\n org_role org_role NOT NULL DEFAULT 'member',\n cost_rate_cents bigint,\n billable_rate_cents bigint,\n active boolean NOT NULL DEFAULT true,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE clients (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n name text NOT NULL,\n currency char(3) NOT NULL,\n address text,\n tax_id text,\n active boolean NOT NULL DEFAULT true,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE projects (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n client_id uuid NOT NULL REFERENCES clients(id),\n code text,\n name text NOT NULL,\n project_type project_type NOT NULL DEFAULT 'time_and_materials',\n currency char(3) NOT NULL,\n starts_on date,\n ends_on date,\n budget_kind budget_kind NOT NULL DEFAULT 'none',\n budget_amount_cents bigint,\n budget_minutes bigint,\n active boolean NOT NULL DEFAULT true,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE TABLE tasks ( -- org-level task catalog\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n name text NOT NULL,\n billable_default boolean NOT NULL DEFAULT true,\n default_rate_cents bigint,\n active boolean NOT NULL DEFAULT true\n);\n\nCREATE TABLE project_tasks ( -- task enabled per project + overrides\n project_id uuid NOT NULL REFERENCES projects(id),\n task_id uuid NOT NULL REFERENCES tasks(id),\n billable boolean NOT NULL,\n rate_cents bigint,\n PRIMARY KEY (project_id, task_id)\n);\n\nCREATE TABLE assignments (\n id uuid PRIMARY KEY,\n project_id uuid NOT NULL REFERENCES projects(id),\n user_id uuid NOT NULL REFERENCES users(id),\n role project_role NOT NULL DEFAULT 'freelancer',\n rate_cents bigint,\n created_at timestamptz NOT NULL DEFAULT now(),\n UNIQUE (project_id, user_id)\n);\n\nCREATE TABLE time_entries (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n user_id uuid NOT NULL REFERENCES users(id),\n project_id uuid NOT NULL REFERENCES projects(id),\n task_id uuid NOT NULL REFERENCES tasks(id),\n spent_date date NOT NULL,\n minutes integer NOT NULL DEFAULT 0,\n rounded_minutes integer, -- persisted at lock (submit)\n notes text,\n billable boolean NOT NULL,\n is_running boolean NOT NULL DEFAULT false,\n started_at timestamptz, -- non-null only while running\n state entry_state NOT NULL DEFAULT 'open',\n invoice_id uuid, -- Phase 4\n created_at timestamptz NOT NULL DEFAULT now(),\n updated_at timestamptz NOT NULL DEFAULT now()\n);\n\nCREATE INDEX ON time_entries (user_id, spent_date);\nCREATE INDEX ON time_entries (project_id, spent_date);\n\n-- Enforces the one-running-timer-per-user invariant at the database level.\nCREATE UNIQUE INDEX one_running_timer_per_user ON time_entries (user_id) WHERE is_running;\n\nCREATE TABLE approvals (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n user_id uuid NOT NULL REFERENCES users(id),\n period_start date NOT NULL,\n period_end date NOT NULL,\n state entry_state NOT NULL DEFAULT 'submitted', -- reject deletes the row\n submitted_at timestamptz NOT NULL DEFAULT now(),\n approved_by uuid REFERENCES users(id),\n approved_at timestamptz,\n UNIQUE (user_id, period_start)\n);\n\nCREATE TABLE audit_log (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL,\n actor_user_id uuid,\n action text NOT NULL, -- e.g. 'entry.submit'\n entity_type text NOT NULL,\n entity_id uuid,\n data jsonb,\n created_at timestamptz NOT NULL DEFAULT now()\n);\n\n" rows_affected=0 rows_returned=0 elapsed=1.870090147s elapsed_secs=1.870090147 slow_threshold=1s1943server # [ 381.515942] horae[981]: 2026-10-08T22:38:41.948000Z WARN sqlx::query: slow statement: execution time exceeded alert threshold summary="-- New creation settings …" db.statement="\n\n-- New creation settings are opt-in. Existing and imported projects retain the\n-- legacy billing cascade when no project_settings row exists.\nALTER TABLE users ADD CONSTRAINT users_id_org_unique UNIQUE (id, org_id);\nALTER TABLE projects ADD CONSTRAINT projects_id_org_unique UNIQUE (id, org_id);\nALTER TABLE tasks ADD CONSTRAINT tasks_id_org_unique UNIQUE (id, org_id);\n\nALTER TABLE clients ADD COLUMN default_rate_cents bigint\n CHECK (default_rate_cents IS NULL OR default_rate_cents >= 0);\n\nCREATE TABLE project_drafts (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n creator_id uuid NOT NULL,\n revision bigint NOT NULL DEFAULT 1 CHECK (revision > 0),\n payload_version smallint NOT NULL DEFAULT 1 CHECK (payload_version = 1),\n payload jsonb NOT NULL CHECK (\n jsonb_typeof(payload) = 'object'\n AND octet_length(payload::text) <= 262144),\n completed_project_id uuid,\n discarded_at timestamptz,\n created_at timestamptz NOT NULL DEFAULT now(),\n updated_at timestamptz NOT NULL DEFAULT now(),\n FOREIGN KEY (creator_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (completed_project_id, org_id) REFERENCES projects(id, org_id),\n CHECK (completed_project_id IS NULL OR discarded_at IS NULL)\n);\n\nCREATE UNIQUE INDEX project_drafts_current_creator_idx\n ON project_drafts (org_id, creator_id)\n WHERE completed_project_id IS NULL AND discarded_at IS NULL;\n\nCREATE TABLE project_settings (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL UNIQUE,\n creator_id uuid NOT NULL,\n rate_mode text NOT NULL CHECK (rate_mode IN ('person', 'task', 'project')),\n budget_scope text NOT NULL DEFAULT 'project'\n CHECK (budget_scope IN ('project', 'task', 'person')),\n monthly_reset boolean NOT NULL DEFAULT false,\n include_nonbillable boolean NOT NULL DEFAULT false,\n alert_enabled boolean NOT NULL DEFAULT false,\n alert_threshold smallint NOT NULL DEFAULT 80 CHECK (alert_threshold BETWEEN 0 AND 100),\n report_visibility text NOT NULL DEFAULT 'managers'\n CHECK (report_visibility IN ('managers', 'project_members')),\n fee_mode text CHECK (fee_mode IN ('single', 'milestones', 'monthly')),\n fee_amount_cents bigint CHECK (fee_amount_cents >= 0),\n monthly_day text CHECK (monthly_day IN ('first', 'fifteenth', 'last')),\n terms_days smallint NOT NULL DEFAULT 30 CHECK (terms_days BETWEEN 0 AND 365),\n po_number text NOT NULL DEFAULT '' CHECK (char_length(po_number) <= 200),\n discount_bps smallint NOT NULL DEFAULT 0 CHECK (discount_bps BETWEEN 0 AND 10000),\n tax1_bps smallint NOT NULL DEFAULT 0 CHECK (tax1_bps BETWEEN 0 AND 10000),\n tax2_name text CHECK (char_length(btrim(tax2_name)) BETWEEN 1 AND 100),\n tax2_bps smallint CHECK (tax2_bps BETWEEN 0 AND 10000),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (creator_id, org_id) REFERENCES users(id, org_id),\n CHECK ((tax2_name IS NULL) = (tax2_bps IS NULL)),\n CHECK (CASE fee_mode\n WHEN 'single' THEN fee_amount_cents IS NOT NULL AND monthly_day IS NULL\n WHEN 'milestones' THEN fee_amount_cents IS NULL AND monthly_day IS NULL\n WHEN 'monthly' THEN fee_amount_cents IS NOT NULL AND monthly_day IS NOT NULL\n ELSE fee_amount_cents IS NULL AND monthly_day IS NULL\n END)\n);\n\n-- Private fields must not inherit table-level SELECT grants on projects or\n-- assignments held by the plugin database role.\nCREATE TABLE project_private_settings (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL UNIQUE,\n admin_notes text NOT NULL DEFAULT '' CHECK (char_length(admin_notes) <= 10000),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_member_costs (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n user_id uuid NOT NULL,\n cost_rate_cents bigint NOT NULL CHECK (cost_rate_cents >= 0),\n UNIQUE (project_id, user_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (user_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (project_id, user_id) REFERENCES assignments(project_id, user_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_tags (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n name text NOT NULL CHECK (char_length(btrim(name)) BETWEEN 1 AND 50),\n UNIQUE (id, org_id)\n);\n\nCREATE UNIQUE INDEX project_tags_name_idx ON project_tags (org_id, lower(btrim(name)));\n\nCREATE TABLE project_tag_links (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n tag_id uuid NOT NULL,\n UNIQUE (project_id, tag_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (tag_id, org_id) REFERENCES project_tags(id, org_id)\n);\n\nCREATE TABLE project_task_settings (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n task_id uuid NOT NULL,\n restricted boolean NOT NULL DEFAULT false,\n budget_minutes bigint CHECK (budget_minutes >= 0),\n budget_cents bigint CHECK (budget_cents >= 0),\n UNIQUE (project_id, task_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (task_id, org_id) REFERENCES tasks(id, org_id),\n FOREIGN KEY (project_id, task_id) REFERENCES project_tasks(project_id, task_id) ON DELETE CASCADE,\n CHECK (budget_minutes IS NULL OR budget_cents IS NULL)\n);\n\nCREATE TABLE project_task_members (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n task_id uuid NOT NULL,\n user_id uuid NOT NULL,\n UNIQUE (project_id, task_id, user_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (task_id, org_id) REFERENCES tasks(id, org_id),\n FOREIGN KEY (user_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (project_id, task_id) REFERENCES project_task_settings(project_id, task_id) ON DELETE CASCADE,\n FOREIGN KEY (project_id, user_id) REFERENCES assignments(project_id, user_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_member_budgets (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n user_id uuid NOT NULL,\n budget_minutes bigint NOT NULL CHECK (budget_minutes >= 0),\n UNIQUE (project_id, user_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE,\n FOREIGN KEY (user_id, org_id) REFERENCES users(id, org_id),\n FOREIGN KEY (project_id, user_id) REFERENCES assignments(project_id, user_id) ON DELETE CASCADE\n);\n\nCREATE TABLE project_fee_milestones (\n id uuid PRIMARY KEY,\n org_id uuid NOT NULL REFERENCES organizations(id),\n project_id uuid NOT NULL,\n name text NOT NULL CHECK (char_length(btrim(name)) BETWEEN 1 AND 200),\n due_on date NOT NULL,\n amount_cents bigint NOT NULL CHECK (amount_cents >= 0),\n position smallint NOT NULL CHECK (position BETWEEN 0 AND 99),\n UNIQUE (project_id, position),\n UNIQUE (id, org_id),\n FOREIGN KEY (project_id, org_id) REFERENCES projects(id, org_id) ON DELETE CASCADE\n);\n\nREVOKE ALL ON project_drafts, project_settings, project_private_settings,\n project_member_costs, project_tags, project_tag_links, project_task_settings,\n project_task_members, project_member_budgets, project_fee_milestones FROM PUBLIC;\n\n" rows_affected=0 rows_returned=0 elapsed=2.90775578s elapsed_secs=2.90775578 slow_threshold=1s1944server # [ 388.590982] horae[981]: 2026-10-08T22:38:49.010607Z INFO horae: Migrations complete.1945server # [ 388.843635] systemd[1]: Started Horae time tracking server.1946server # [ 390.417565] horae[1018]: 2026-10-08T22:38:50.870951Z INFO horae: Starting Horae on 127.0.0.1:30001947server # [ 390.805829] horae[1018]: 2026-10-08T22:38:51.289762Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1948server # [ 391.553831] horae[1018]: 2026-10-08T22:38:51.983347Z INFO horae::plugin::registry: plugins directory does not exist: plugins1949server # [ 392.102800] horae[1018]: 2026-10-08T22:38:52.588954Z INFO dioxus_server::server: Registering: POST /api/logout161443592707961243071950server # [ 392.137388] horae[1018]: 2026-10-08T22:38:52.622776Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071951server # [ 392.155026] horae[1018]: 2026-10-08T22:38:52.630923Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634961952server # [ 392.175161] horae[1018]: 2026-10-08T22:38:52.632237Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634961953server # [ 392.198704] horae[1018]: 2026-10-08T22:38:52.647484Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634961954server # [ 392.223019] horae[1018]: 2026-10-08T22:38:52.648070Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634961955server # [ 392.249920] horae[1018]: 2026-10-08T22:38:52.648545Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486541956server # [ 392.258420] horae[1018]: 2026-10-08T22:38:52.670295Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486541957server # [ 392.278518] horae[1018]: 2026-10-08T22:38:52.671701Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486541958server # [ 392.299189] horae[1018]: 2026-10-08T22:38:52.672194Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486541959server # [ 392.325344] horae[1018]: 2026-10-08T22:38:52.672537Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486541960server # [ 392.347129] horae[1018]: 2026-10-08T22:38:52.689802Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181961server # [ 392.362022] horae[1018]: 2026-10-08T22:38:52.690497Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181962server # [ 392.374228] horae[1018]: 2026-10-08T22:38:52.690784Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181963server # [ 392.387474] horae[1018]: 2026-10-08T22:38:52.691130Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181964server # [ 392.409186] horae[1018]: 2026-10-08T22:38:52.691865Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181965server # [ 392.426081] horae[1018]: 2026-10-08T22:38:52.692130Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181966server # [ 392.436036] horae[1018]: 2026-10-08T22:38:52.692482Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181967server # [ 392.450647] horae[1018]: 2026-10-08T22:38:52.714158Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181968server # [ 392.467351] horae[1018]: 2026-10-08T22:38:52.714771Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851969server # [ 392.485496] horae[1018]: 2026-10-08T22:38:52.715034Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851970server # [ 392.554850] horae[1018]: 2026-10-08T22:38:52.715299Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851971server # [ 392.570380] horae[1018]: 2026-10-08T22:38:52.715577Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218001972server # [ 392.589344] horae[1018]: 2026-10-08T22:38:52.715810Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770261973server # [ 392.613626] horae[1018]: 2026-10-08T22:38:52.716032Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770261974server # [ 392.627244] horae[1018]: 2026-10-08T22:38:52.716371Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770261975server # [ 392.643679] horae[1018]: 2026-10-08T22:38:52.724155Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770261976server # [ 392.664914] horae[1018]: 2026-10-08T22:38:52.730372Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829191977server # [ 392.681676] horae[1018]: 2026-10-08T22:38:52.731144Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829191978server # [ 392.690732] horae[1018]: 2026-10-08T22:38:52.731913Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829191979server # [ 392.707254] horae[1018]: 2026-10-08T22:38:52.732200Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829191980server # [ 392.723004] horae[1018]: 2026-10-08T22:38:52.732422Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829191981server # [ 392.733853] horae[1018]: 2026-10-08T22:38:52.751671Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829191982server # [ 392.754509] horae[1018]: 2026-10-08T22:38:52.752287Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829191983server # [ 392.769892] horae[1018]: 2026-10-08T22:38:52.752572Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829191984server # [ 392.781676] horae[1018]: 2026-10-08T22:38:52.762024Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829191985server # [ 392.789471] horae[1018]: 2026-10-08T22:38:52.762613Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551986server # [ 392.812891] horae[1018]: 2026-10-08T22:38:52.762866Z INFO dioxus_server::server: Registering: POST /api/reorder_untimed_entries163860635615838339551987server # [ 392.817993] horae[1018]: 2026-10-08T22:38:52.763062Z INFO dioxus_server::server: Registering: POST /api/reschedule_time_entry163860635615838339551988server # [ 392.834173] horae[1018]: 2026-10-08T22:38:52.763331Z INFO dioxus_server::server: Registering: POST /api/update_time_entry163860635615838339551989server # [ 392.854075] horae[1018]: 2026-10-08T22:38:52.763559Z INFO dioxus_server::server: Registering: POST /api/list_time_entries163860635615838339551990server # [ 392.874351] horae[1018]: 2026-10-08T22:38:52.763931Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551991server # [ 392.895311] horae[1018]: 2026-10-08T22:38:52.764153Z INFO dioxus_server::server: Registering: POST /api/delete_time_entry163860635615838339551992server # [ 392.910257] horae[1018]: 2026-10-08T22:38:52.764431Z INFO dioxus_server::server: Registering: POST /api/create_time_entry163860635615838339551993server # [ 392.938128] horae[1018]: 2026-10-08T22:38:52.774056Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551994server # [ 392.954298] horae[1018]: 2026-10-08T22:38:52.774837Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551995server # [ 392.997714] horae[1018]: 2026-10-08T22:38:52.798939Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1996server # [ 393.010222] horae[1018]: 2026-10-08T22:38:52.799847Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1997server # [ 393.034125] horae[1018]: 2026-10-08T22:38:52.800231Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1998server # [ 393.046224] horae[1018]: 2026-10-08T22:38:52.811158Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1999server # [ 393.070278] horae[1018]: 2026-10-08T22:38:52.811754Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry2000server # [ 393.114806] horae[1018]: 2026-10-08T22:38:52.811989Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history2001server # [ 393.126756] horae[1018]: 2026-10-08T22:38:52.812220Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993932002server # [ 393.146473] horae[1018]: 2026-10-08T22:38:52.812436Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status2003server # [ 393.182813] horae[1018]: 2026-10-08T22:38:52.830903Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993932004server # [ 393.198761] horae[1018]: 2026-10-08T22:38:52.831572Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993932005server # [ 393.222344] horae[1018]: 2026-10-08T22:38:52.832198Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732062006server # [ 393.241990] horae[1018]: 2026-10-08T22:38:52.848001Z INFO dioxus_server::server: Registering: POST /api/get_project_fee_balances139632003752584732062007server # [ 393.253934] horae[1018]: 2026-10-08T22:38:52.858629Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732062008server # [ 393.273573] horae[1018]: 2026-10-08T22:38:52.859280Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732062009server # [ 393.281959] horae[1018]: 2026-10-08T22:38:52.859566Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732062010server # [ 393.301918] horae[1018]: 2026-10-08T22:38:52.859797Z INFO dioxus_server::server: Registering: POST /api/get_project_details139632003752584732062011server # [ 393.313806] horae[1018]: 2026-10-08T22:38:52.860035Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732062012server # [ 393.333754] horae[1018]: 2026-10-08T22:38:52.860274Z INFO dioxus_server::server: Registering: POST /api/list_project_tasks139632003752584732062013server # [ 393.349467] horae[1018]: 2026-10-08T22:38:52.860515Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732062014server # [ 393.388582] horae[1018]: 2026-10-08T22:38:52.868547Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732062015server # [ 393.407162] horae[1018]: 2026-10-08T22:38:52.877820Z INFO dioxus_server::server: Registering: POST /api/link_project_task139632003752584732062016server # [ 393.423482] horae[1018]: 2026-10-08T22:38:52.878530Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732062017server # [ 393.446171] horae[1018]: 2026-10-08T22:38:52.878854Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732062018server # [ 393.469204] horae[1018]: 2026-10-08T22:38:52.879081Z INFO dioxus_server::server: Registering: POST /api/list_assignments139632003752584732062019server # [ 393.481587] horae[1018]: 2026-10-08T22:38:52.879316Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732062020server # [ 393.486327] horae[1018]: 2026-10-08T22:38:52.879522Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732062021server # [ 393.555869] horae[1018]: 2026-10-08T22:38:52.879796Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732062022server # [ 393.571628] horae[1018]: 2026-10-08T22:38:52.880027Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732062023server # [ 393.598514] horae[1018]: 2026-10-08T22:38:52.880258Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732062024server # [ 393.610834] horae[1018]: 2026-10-08T22:38:52.900487Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915712025server # [ 393.622971] horae[1018]: 2026-10-08T22:38:52.904586Z INFO dioxus_server::server: Registering: POST /api/list_client_summaries104851358257774915712026server # [ 393.640194] horae[1018]: 2026-10-08T22:38:52.909664Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915712027server # [ 393.658098] horae[1018]: 2026-10-08T22:38:52.909942Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915712028server # [ 393.670183] horae[1018]: 2026-10-08T22:38:52.910158Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915712029server # [ 393.685318] horae[1018]: 2026-10-08T22:38:52.910398Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915712030server # [ 393.698073] horae[1018]: 2026-10-08T22:38:52.910609Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915712031server # [ 393.709675] horae[1018]: 2026-10-08T22:38:52.910825Z INFO dioxus_server::server: Registering: POST /api/update_client104851358257774915712032server # [ 393.722136] horae[1018]: 2026-10-08T22:38:52.911029Z INFO dioxus_server::server: Registering: POST /api/create_client104851358257774915712033server # [ 393.736832] horae[1018]: 2026-10-08T22:38:52.911252Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915712034server # [ 393.741804] horae[1018]: 2026-10-08T22:38:53.299889Z INFO horae: Listening on 127.0.0.1:30002035server # [ 413.582728] test-console-diagnostics-start[1099]: JOB UNIT TYPE STATE2036server # [ 413.626859] test-console-diagnostics-start[1099]: 175 multi-user.target start waiting2037server # [ 413.637655] test-console-diagnostics-start[1099]: 297 dev-ttyAMA0.device start running2038server # [ 413.654485] test-console-diagnostics-start[1099]: 296 dev-hvc0.device start running2039server # [ 413.670321] test-console-diagnostics-start[1099]: 295 backdoor.service start waiting2040server # [ 413.691988] test-console-diagnostics-start[1099]: 4 jobs listed.2041server # [ 414.737004] test-console-diagnostics-start[1100]: Id=backdoor.service2042server # [ 414.743056] test-console-diagnostics-start[1100]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device2043server # [ 414.758623] test-console-diagnostics-start[1100]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket2044server # [ 414.771160] test-console-diagnostics-start[1100]: ActiveState=inactive2045server # [ 414.785580] test-console-diagnostics-start[1100]: SubState=dead2046server # [ 414.797842] test-console-diagnostics-start[1100]: Result=success2047server # [ 414.809849] test-console-diagnostics-start[1100]: Id=dev-hvc0.device2048server # [ 414.817661] test-console-diagnostics-start[1100]: Requires=2049server # [ 414.827970] test-console-diagnostics-start[1100]: After=2050server # [ 414.841804] test-console-diagnostics-start[1100]: ActiveState=activating2051server # [ 414.853604] test-console-diagnostics-start[1100]: SubState=tentative2052server # [ 414.863474] test-console-diagnostics-start[1100]: Id=dev-ttyAMA0.device2053server # [ 414.877515] test-console-diagnostics-start[1100]: Requires=2054server # [ 414.890822] test-console-diagnostics-start[1100]: After=2055server # [ 414.905480] test-console-diagnostics-start[1100]: ActiveState=activating2056server # [ 414.917501] test-console-diagnostics-start[1100]: SubState=tentative2057server # [ 414.929895] test-console-diagnostics-start[1100]: Id=dev-ttyS0.device2058server # [ 414.937720] test-console-diagnostics-start[1100]: Requires=2059server # [ 414.951693] test-console-diagnostics-start[1100]: After=2060server # [ 414.961568] test-console-diagnostics-start[1100]: ActiveState=inactive2061server # [ 414.973832] test-console-diagnostics-start[1100]: SubState=dead2062server # [ 415.106506] test-console-diagnostics-start[1101]: DEVPATH=/devices/virtual/tty/hvc02063server # [ 415.122601] test-console-diagnostics-start[1101]: DEVNAME=/dev/hvc02064server # [ 415.141611] test-console-diagnostics-start[1101]: MAJOR=2292065server # [ 415.165048] test-console-diagnostics-start[1101]: MINOR=02066server # [ 415.174236] test-console-diagnostics-start[1101]: SUBSYSTEM=tty2067server # [ 415.514790] test-console-diagnostics-start[1102]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA02068server # [ 415.531860] test-console-diagnostics-start[1102]: DEVNAME=/dev/ttyAMA02069server # [ 415.574634] test-console-diagnostics-start[1102]: MAJOR=2042070server # [ 415.621676] test-console-diagnostics-start[1102]: MINOR=642071server # [ 415.646161] test-console-diagnostics-start[1102]: SUBSYSTEM=tty2072server # [ 416.075796] test-console-diagnostics-start[1103]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS02073server # [ 416.090874] test-console-diagnostics-start[1103]: DEVNAME=/dev/ttyS02074server # [ 416.117981] test-console-diagnostics-start[1103]: MAJOR=42075server # [ 416.138604] test-console-diagnostics-start[1103]: MINOR=642076server # [ 416.158322] test-console-diagnostics-start[1103]: SUBSYSTEM=tty2077server # [ 477.270378] test-console-diagnostics-start[1301]: JOB UNIT TYPE STATE2078server # [ 477.339284] test-console-diagnostics-start[1301]: 175 multi-user.target start waiting2079server # [ 477.354844] test-console-diagnostics-start[1301]: 297 dev-ttyAMA0.device start running2080server # [ 477.373545] test-console-diagnostics-start[1301]: 296 dev-hvc0.device start running2081server # [ 477.387123] test-console-diagnostics-start[1301]: 295 backdoor.service start waiting2082server # [ 477.411621] test-console-diagnostics-start[1301]: 4 jobs listed.2083server # [ 478.662519] test-console-diagnostics-start[1302]: Id=backdoor.service2084server # [ 478.682676] test-console-diagnostics-start[1302]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device2085server # [ 478.702956] test-console-diagnostics-start[1302]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket2086server # [ 478.714341] test-console-diagnostics-start[1302]: ActiveState=inactive2087server # [ 478.733877] test-console-diagnostics-start[1302]: SubState=dead2088server # [ 478.747243] test-console-diagnostics-start[1302]: Result=success2089server # [ 478.762019] test-console-diagnostics-start[1302]: Id=dev-hvc0.device2090server # [ 478.772738] test-console-diagnostics-start[1302]: Requires=2091server # [ 478.785018] test-console-diagnostics-start[1302]: After=2092server # [ 478.803023] test-console-diagnostics-start[1302]: ActiveState=activating2093server # [ 478.818001] test-console-diagnostics-start[1302]: SubState=tentative2094server # [ 478.829741] test-console-diagnostics-start[1302]: Id=dev-ttyAMA0.device2095server # [ 478.849616] test-console-diagnostics-start[1302]: Requires=2096server # [ 478.851963] test-console-diagnostics-start[1302]: After=2097server # [ 478.878324] test-console-diagnostics-start[1302]: ActiveState=activating2098server # [ 478.889490] test-console-diagnostics-start[1302]: SubState=tentative2099server # [ 478.901833] test-console-diagnostics-start[1302]: Id=dev-ttyS0.device2100server # [ 478.909546] test-console-diagnostics-start[1302]: Requires=2101server # [ 478.925452] test-console-diagnostics-start[1302]: After=2102server # [ 478.933585] test-console-diagnostics-start[1302]: ActiveState=inactive2103server # [ 478.953661] test-console-diagnostics-start[1302]: SubState=dead2104server # [ 479.085716] test-console-diagnostics-start[1311]: DEVPATH=/devices/virtual/tty/hvc02105server # [ 479.090225] test-console-diagnostics-start[1311]: DEVNAME=/dev/hvc02106server # [ 479.105893] test-console-diagnostics-start[1311]: MAJOR=2292107server # [ 479.113479] test-console-diagnostics-start[1311]: MINOR=02108server # [ 479.125558] test-console-diagnostics-start[1311]: SUBSYSTEM=tty2109server # [ 479.319533] test-console-diagnostics-start[1313]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA02110server # [ 479.346859] test-console-diagnostics-start[1313]: DEVNAME=/dev/ttyAMA02111server # [ 479.354506] test-console-diagnostics-start[1313]: MAJOR=2042112server # [ 479.361351] test-console-diagnostics-start[1313]: MINOR=642113server # [ 479.369800] test-console-diagnostics-start[1313]: SUBSYSTEM=tty2114server # [ 479.582427] test-console-diagnostics-start[1315]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS02115server # [ 479.598496] test-console-diagnostics-start[1315]: DEVNAME=/dev/ttyS02116server # [ 479.606090] test-console-diagnostics-start[1315]: MAJOR=42117server # [ 479.613590] test-console-diagnostics-start[1315]: MINOR=642118server # [ 479.621599] test-console-diagnostics-start[1315]: SUBSYSTEM=tty2119server # [ 525.352385] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input32120server # [ 540.454877] test-console-diagnostics-start[1513]: JOB UNIT TYPE STATE2121server # [ 540.479464] test-console-diagnostics-start[1513]: 175 multi-user.target start waiting2122server # [ 540.497948] test-console-diagnostics-start[1513]: 297 dev-ttyAMA0.device start running2123server # [ 540.505727] test-console-diagnostics-start[1513]: 296 dev-hvc0.device start running2124server # [ 540.517793] test-console-diagnostics-start[1513]: 295 backdoor.service start waiting2125server # [ 540.522296] test-console-diagnostics-start[1513]: 4 jobs listed.2126server # [ 541.475475] test-console-diagnostics-start[1514]: Id=backdoor.service2127server # [ 541.507634] test-console-diagnostics-start[1514]: Requires=sysinit.target system.slice dev-hvc0.device dev-ttyAMA0.device2128server # [ 541.530061] test-console-diagnostics-start[1514]: After=dev-ttyAMA0.device sysinit.target basic.target system.slice dev-hvc0.device systemd-journald.socket2129server # [ 541.545827] test-console-diagnostics-start[1514]: ActiveState=inactive2130server # [ 541.557485] test-console-diagnostics-start[1514]: SubState=dead2131server # [ 541.565806] test-console-diagnostics-start[1514]: Result=success2132server # [ 541.573577] test-console-diagnostics-start[1514]: Id=dev-hvc0.device2133server # [ 541.586160] test-console-diagnostics-start[1514]: Requires=2134server # [ 541.594073] test-console-diagnostics-start[1514]: After=2135server # [ 541.614021] test-console-diagnostics-start[1514]: ActiveState=activating2136server # [ 541.625873] test-console-diagnostics-start[1514]: SubState=tentative2137server # [ 541.633762] test-console-diagnostics-start[1514]: Id=dev-ttyAMA0.device2138server # [ 541.642061] test-console-diagnostics-start[1514]: Requires=2139server # [ 541.653932] test-console-diagnostics-start[1514]: After=2140server # [ 541.665753] test-console-diagnostics-start[1514]: ActiveState=activating2141server # [ 541.677694] test-console-diagnostics-start[1514]: SubState=tentative2142server # [ 541.685453] test-console-diagnostics-start[1514]: Id=dev-ttyS0.device2143server # [ 541.694036] test-console-diagnostics-start[1514]: Requires=2144server # [ 541.705448] test-console-diagnostics-start[1514]: After=2145server # [ 541.717897] test-console-diagnostics-start[1514]: ActiveState=inactive2146server # [ 541.725952] test-console-diagnostics-start[1514]: SubState=dead2147server # [ 541.831313] test-console-diagnostics-start[1515]: DEVPATH=/devices/virtual/tty/hvc02148server # [ 541.846891] test-console-diagnostics-start[1515]: DEVNAME=/dev/hvc02149server # [ 541.871125] test-console-diagnostics-start[1515]: MAJOR=2292150server # [ 541.882630] test-console-diagnostics-start[1515]: MINOR=02151server # [ 541.897588] test-console-diagnostics-start[1515]: SUBSYSTEM=tty2152server # [ 542.189188] test-console-diagnostics-start[1516]: DEVPATH=/devices/platform/9000000.pl011/9000000.pl011:0/9000000.pl011:0.0/tty/ttyAMA02153server # [ 542.206459] test-console-diagnostics-start[1516]: DEVNAME=/dev/ttyAMA02154server # [ 542.227032] test-console-diagnostics-start[1516]: MAJOR=2042155server # [ 542.238520] test-console-diagnostics-start[1516]: MINOR=642156server # [ 542.249689] test-console-diagnostics-start[1516]: SUBSYSTEM=tty2157server # [ 542.574505] test-console-diagnostics-start[1517]: DEVPATH=/devices/platform/serial8250/serial8250:0/serial8250:0.0/tty/ttyS02158server # [ 542.590439] test-console-diagnostics-start[1517]: DEVNAME=/dev/ttyS02159server # [ 542.599842] test-console-diagnostics-start[1517]: MAJOR=42160server # [ 542.617178] test-console-diagnostics-start[1517]: MINOR=642161server # [ 542.626330] test-console-diagnostics-start[1517]: SUBSYSTEM=tty2162server # [ 557.834844] systemd[1]: Starting Virtual Console Setup...2163server # [ 561.031803] systemd-vconsole-setup[1573]: Configuration of first virtual console was skipped, ignoring remaining ones.2164server # [ 561.147401] systemd[1]: Finished Virtual Console Setup.2165server # [ 570.159703] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2166server # [ 591.909823] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2167server # [ 592.895624] 8021q: 802.1Q VLAN Support v1.82168server # [ 594.420290] cfg80211: Loading compiled-in X.509 certificates for regulatory database2169server # [ 594.800163] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2170server # [ 594.823197] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2171server # [ 594.869024] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22172server # [ 594.870837] cfg80211: failed to load regulatory.db2173server # [ 599.161885] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2174server # [ 599.295764] systemd[1]: Started backdoor.service.2175server # [ 599.382066] systemd[1]: Reached target Multi-User System.2176server # [ 599.417617] systemd[1]: Startup finished in 8.551s (kernel) + 1min 42.212s (initrd) + 8min 8.649s (userspace) = 9min 59.413s.2177server # [ 599.571993] (udev-worker)[1688]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.2178server # [ 599.603744] (udev-worker)[1688]: Network interface NamePolicy= disabled on kernel command line.2179server # [ 599.666280] (udev-worker)[1687]: Network interface NamePolicy= disabled on kernel command line.2180server # connecting to host...2181server: waiting for unit register-nix-paths.service2182server: waiting for the VM to finish booting2183server: Guest shell says: b'Spawning backdoor root shell...\n'2184server: connected to guest root shell2185server: (connecting took 0.00 seconds)2186server: (finished: waiting for the VM to finish booting, in 0.00 seconds)2187server # [ 602.019169] systemd[1]: Condition check resulted in Virtio network device being skipped.2188server # [ 602.261419] systemd[1]: Starting Address configuration of eth1...2189server: (finished: waiting for unit register-nix-paths.service, in 2.59 seconds)2190server: waiting for unit postgresql.service2191server # [ 604.082592] systemd[1]: test-console-diagnostics.service: Deactivated successfully.2192server # [ 604.125924] systemd[1]: test-console-diagnostics.service: Consumed 4.796s CPU time over 6min 31.082s wall clock time, 2.3M memory peak, 396K read from disk.2193server # [ 604.850786] 8021q: adding VLAN 0 to HW filter on device eth12194server # [ 605.023048] 8021q: adding VLAN 0 to HW filter on device eth02195server # [ 604.946952] dhcpcd[749]: eth0: waiting for carrier2196server # [ 605.014169] dhcpcd[749]: eth0: carrier acquired2197server # [ 605.458738] network-addresses-eth1-start[1709]: adding address 192.168.1.1/24... done2198server # [ 605.672670] dhcpcd[749]: DUID 00:01:00:01:32:5a:d8:d2:52:54:00:12:34:562199server # [ 605.675834] dhcpcd[749]: eth0: IAID 00:12:34:562200server # [ 605.700036] dhcpcd[749]: eth0: adding address fe80::5054:ff:fe12:34562201server # [ 605.885395] network-addresses-eth1-start[1709]: adding address 2001:db8:1::1/64... done2202server # [ 606.326853] systemd[1]: Finished Address configuration of eth1.2203server: (finished: waiting for unit postgresql.service, in 2.62 seconds)2204server: waiting for unit dex.service2205server # [ 607.303117] dhcpcd[749]: eth0: soliciting a DHCP lease2206server # [ 607.331020] dhcpcd[749]: eth0: soliciting an IPv6 router2207server # [ 607.404005] dhcpcd[749]: eth0: offered 10.0.2.15 from 10.0.2.22208server # [ 607.434978] dhcpcd[749]: eth0: Router Advertisement from fe80::22209server # [ 607.474488] dhcpcd[749]: eth0: adding address fec0::5054:ff:fe12:3456/642210server # [ 607.505742] dhcpcd[749]: eth0: adding route to fec0::/642211server # [ 607.521885] dhcpcd[749]: eth0: adding default route via fe80::22212server # [ 607.545850] dhcpcd[749]: eth0: probing address 10.0.2.15/242213server: (finished: waiting for unit dex.service, in 1.60 seconds)2214server: waiting for unit horae.service2215server: (finished: waiting for unit horae.service, in 1.58 seconds)2216server: waiting for TCP port 5556 on localhost2217server # Connection to localhost (127.0.0.1) 5556 port [tcp/freeciv] succeeded!2218server: (finished: waiting for TCP port 5556 on localhost, in 1.47 seconds)2219server: waiting for TCP port 3000 on localhost2220server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!2221server: (finished: waiting for TCP port 3000 on localhost, in 0.92 seconds)2222server: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok2223server: (finished: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok, in 1.44 seconds)2224server: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null2225server # [ 613.582702] dhcpcd[749]: eth0: leased 10.0.2.15 for 86400 seconds2226server # [ 613.609443] dhcpcd[749]: eth0: adding route to 10.0.2.0/242227server # [ 613.612050] dhcpcd[749]: eth0: adding default route via 10.0.2.22228server: (finished: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null, in 1.79 seconds)2229server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed2230server # [ 617.793558] sudo[1786]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; ENV=DATABASE_URL=postgres:///horae ; COMMAND=/run/current-system/sw/bin/horae seed2231server # [ 619.739005] sudo[1786]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)2232server # [ 623.503518] postgres[1827]: [1827] LOG: could not receive data from client: Connection reset by peer2233server # [ 623.673534] sudo[1786]: pam_unix(sudo:session): session closed for user horae2234server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed, in 8.52 seconds)2235server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member2236server # [ 624.847297] sudo[1838]: 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 member2237server # [ 626.069421] sudo[1838]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)2238server # [ 627.622472] postgres[1846]: [1846] LOG: could not receive data from client: Connection reset by peer2239server # [ 627.747664] sudo[1838]: pam_unix(sudo:session): session closed for user horae2240server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member, in 4.09 seconds)2241server: must succeed: curl -s -c /tmp/jar.txt -b /tmp/jar.txt -L http://127.0.0.1:3000/auth/oidc/start -o /dev/null2242server # [ 630.066376] dex[766]: time=2026-10-08T22:42:50.484Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=bf7af4bd-9289-409d-8030-f0e9b29e34542243server: (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 4.73 seconds)2244server: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries2245server # [ 635.515150] horae[1018]: 2026-10-08T22:42:55.939365Z WARN call: sqlx::query: slow statement: execution time exceeded alert threshold summary="SELECT te.id, te.spent_date as …" db.statement="\n\nSELECT te.id,\n te.spent_date as \"spent_date: chrono::NaiveDate\",\n te.minutes, te.start_minute, te.rounded_minutes, te.notes,\n (te.billable AND (te.invoice_id IS NOT NULL OR (p.project_type <> 'non_billable' AND COALESCE(pt.billable, t.billable_default)))) as \"billable!\", te.is_running,\n te.started_at as \"started_at: chrono::DateTime<chrono::Utc>\",\n te.state::text AS \"state!: String\", te.invoice_id,\n te.created_at as \"created_at: chrono::DateTime<chrono::Utc>\",\n te.updated_at as \"updated_at: chrono::DateTime<chrono::Utc>\",\n te.user_id, u.name AS user_name,\n te.project_id, p.name AS project_name, p.code AS project_code,\n te.task_id, t.name AS task_name,\n p.client_id, c.name AS client_name,\n CASE WHEN viewer.org_role IN ('admin', 'manager') THEN COALESCE(line.rate_cents,\n CASE WHEN ps.project_id IS NULL OR p.project_type = 'time_and_materials'\n THEN resolve_project_rate(ps.rate_mode, pt.rate_cents, a.rate_cents, p.rate_cents,\n CASE WHEN ps.project_id IS NULL OR p.currency = o.default_currency THEN u.billable_rate_cents END,\n CASE WHEN p.currency = c.currency THEN c.default_rate_cents END\n ) END) END AS \"billable_rate_cents?\",\n CASE WHEN viewer.org_role = 'admin' OR (viewer.org_role = 'manager' AND mc.id IS NULL)\n THEN COALESCE(mc.cost_rate_cents, u.cost_rate_cents) END AS user_cost_rate_cents,\n CASE WHEN access.can_view_progress THEN p.budget_kind::text END AS \"budget_kind?: String\"\n FROM time_entries te\n JOIN users viewer ON viewer.id = $10 AND viewer.org_id = te.org_id AND viewer.active\n JOIN users u ON u.id = te.user_id\n JOIN projects p ON p.id = te.project_id\n LEFT JOIN project_read_access access ON access.project_id = p.id AND access.org_id = p.org_id AND access.user_id = viewer.id\n JOIN tasks t ON t.id = te.task_id\n LEFT JOIN project_tasks pt ON pt.project_id = te.project_id AND pt.task_id = te.task_id\n LEFT JOIN assignments a ON a.project_id = te.project_id AND a.user_id = te.user_id\n LEFT JOIN invoice_line_items line ON line.invoice_id = te.invoice_id AND line.time_entry_id = te.id\n JOIN clients c ON c.id = p.client_id\n JOIN organizations o ON o.id = te.org_id\n LEFT JOIN project_settings ps ON ps.project_id = p.id\n LEFT JOIN project_member_costs mc ON mc.project_id = te.project_id AND mc.user_id = te.user_id\n WHERE te.org_id = $1 AND (viewer.org_role IN ('admin', 'manager') OR te.user_id = viewer.id)\n AND ($2::uuid IS NULL OR te.user_id = $2)\n AND ($3::uuid IS NULL OR te.project_id = $3)\n AND ($4::date IS NULL OR te.spent_date >= $4::date)\n AND ($5::date IS NULL OR te.spent_date <= $5::date)\n AND ($6::bool IS NULL OR te.is_running = $6)\n AND ($7::timestamptz IS NULL OR te.updated_at >= $7::timestamptz)\n ORDER BY te.spent_date DESC, te.created_at DESC, te.id DESC\n LIMIT $8 OFFSET $9\n" rows_affected=0 rows_returned=0 elapsed=1.720413485s elapsed_secs=1.720413485 slow_threshold=1s2246server: (finished: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 3.04 seconds)2247server: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'"2248server # [ 636.818856] sudo[1864]: 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\''2249server # [ 637.883737] sudo[1864]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)2250server # [ 638.667834] sudo[1864]: pam_unix(sudo:session): session closed for user horae2251server: (finished: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'", in 3.17 seconds)2252server # [ 640.247259] dex[766]: time=2026-10-08T22:43:00.676Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=7676858c-d099-499f-8a00-88e7689aafd12253server # [ 640.759719] horae[1018]: 2026-10-08T22:43:01.199350Z WARN call: horae::auth::oidc: OIDC login denied: account is deactivated2254server: must succeed: curl -s -o /dev/null -w '%{http_code}' -b /tmp/jar2.txt http://127.0.0.1:3000/harvest/v2/time_entries2255server: (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.93 seconds)2256(finished: run the VM test script, in 646.16 seconds)2257test script finished in 646.98s2258cleanup2259kill QemuMachine (pid 12)2260server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)2261(finished: cleanup, in 0.12 seconds)