1this derivation will be built:2 /nix/store/a261j6q02zv3naxx7xg8p9dy94a2s3yn-vm-test-run-horae-e2e-oidc.drv3building '/nix/store/a261j6q02zv3naxx7xg8p9dy94a2s3yn-vm-test-run-horae-e2e-oidc.drv' on 'grpc://farm.numtide.com:50051?client-cert=/run/secrets/vars/per-machine/bld1/nix-farm-ci/crt&client-key=/run/secrets/vars/per-machine/bld1/nix-farm-ci/key&connect-timeout=20&system=aarch64-linux'4building '/nix/store/a261j6q02zv3naxx7xg8p9dy94a2s3yn-vm-test-run-horae-e2e-oidc.drv' on farm.numtide.com:500515vm-test-run-horae-e2e-oidc> elastic-arm-ea992526: building a261j6q02zv3naxx7xg8p9dy94a2s3yn-vm-test-run-horae-e2e-oidc.drv6vm-test-run-horae-e2e-oidc> Machine state will be reset. To keep it, pass --keep-machine-state7vm-test-run-horae-e2e-oidc> start all VLans8vm-test-run-horae-e2e-oidc> (finished: start all VLans, in 0.01 seconds)9vm-test-run-horae-e2e-oidc> Test will time out and terminate in 3600 seconds10vm-test-run-horae-e2e-oidc> run the VM test script11vm-test-run-horae-e2e-oidc> additionally exposed symbols:12vm-test-run-horae-e2e-oidc> server,13vm-test-run-horae-e2e-oidc> vlan1,14vm-test-run-horae-e2e-oidc> 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_ssh15vm-test-run-horae-e2e-oidc> server: starting vm16vm-test-run-horae-e2e-oidc> server: QEMU running (pid 12)17vm-test-run-horae-e2e-oidc> server # Disk image does not exist, creating the virtualisation disk image...18vm-test-run-horae-e2e-oidc> server # Formatting '/build/vm-state-server/tmp.KcxyYJVkrR', fmt=raw size=107374182419vm-test-run-horae-e2e-oidc> server # mke2fs 1.47.4 (6-Mar-2025)20vm-test-run-horae-e2e-oidc> server: waiting for unit postgresql.service21vm-test-run-horae-e2e-oidc> server # Discarding device blocks: 0/262144 done22vm-test-run-horae-e2e-oidc> server: waiting for the VM to finish booting23vm-test-run-horae-e2e-oidc> server # Creating filesystem with 262144 4k blocks and 65536 inodes24vm-test-run-horae-e2e-oidc> server # Filesystem UUID: 672a12cf-5c1d-4c5e-9e0f-89bd54a7d83725vm-test-run-horae-e2e-oidc> server # Superblock backups stored on blocks:26vm-test-run-horae-e2e-oidc> server # 32768, 98304, 163840, 22937627vm-test-run-horae-e2e-oidc> server # 28vm-test-run-horae-e2e-oidc> server # Allocating group tables: 0/8 done29vm-test-run-horae-e2e-oidc> server # Writing inode tables: 0/8 done30vm-test-run-horae-e2e-oidc> server # Creating journal (8192 blocks): done31vm-test-run-horae-e2e-oidc> server # Writing superblocks and filesystem accounting information: 0/8 done32vm-test-run-horae-e2e-oidc> server # 33vm-test-run-horae-e2e-oidc> server # Virtualisation disk image created.34vm-test-run-horae-e2e-oidc> server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory35vm-test-run-horae-e2e-oidc> server # qemu-system-aarch64: failed to initialize kvm: No such file or directory36vm-test-run-horae-e2e-oidc> server # qemu-system-aarch64: falling back to tcg37vm-test-run-horae-e2e-oidc> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]38vm-test-run-horae-e2e-oidc> server # [ 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 202639vm-test-run-horae-e2e-oidc> server # [ 0.000000] KASLR enabled40vm-test-run-horae-e2e-oidc> server # [ 0.000000] random: crng init done41vm-test-run-horae-e2e-oidc> server # [ 0.000000] Machine model: linux,dummy-virt42vm-test-run-horae-e2e-oidc> server # [ 0.000000] efi: UEFI not found.43vm-test-run-horae-e2e-oidc> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT44vm-test-run-horae-e2e-oidc> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]45vm-test-run-horae-e2e-oidc> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf]46vm-test-run-horae-e2e-oidc> server # [ 0.000000] Zone ranges:47vm-test-run-horae-e2e-oidc> server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]48vm-test-run-horae-e2e-oidc> server # [ 0.000000] DMA32 empty49vm-test-run-horae-e2e-oidc> server # [ 0.000000] Normal empty50vm-test-run-horae-e2e-oidc> server # [ 0.000000] Device empty51vm-test-run-horae-e2e-oidc> server # [ 0.000000] Movable zone start for each node52vm-test-run-horae-e2e-oidc> server # [ 0.000000] Early memory node ranges53vm-test-run-horae-e2e-oidc> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]54vm-test-run-horae-e2e-oidc> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]55vm-test-run-horae-e2e-oidc> server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000056vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: probing for conduit method from DT.57vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: PSCIv1.1 detected in firmware.58vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs59vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: Trusted OS migration not required60vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: SMC Calling Convention v1.061vm-test-run-horae-e2e-oidc> server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129662vm-test-run-horae-e2e-oidc> server # [ 0.000000] Detected PIPT I-cache on CPU063vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)64vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: GICv3 CPU interface65vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: HCRX_EL2 register66vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE)67vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)68vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: Spectre-v469vm-test-run-horae-e2e-oidc> server # [ 0.000000] alternatives: applying boot alternatives70vm-test-run-horae-e2e-oidc> server # [ 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/9mqs26gy19d4rday0l3dg6qspcp9h4ym-nixos-system-server-test/init regInfo=/nix/store/8p4y024id2d9niss1p7k4m407dpzpim2-closure-info/registration console=ttyAMA0,115200n8 console=tty071vm-test-run-horae-e2e-oidc> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/8p4y024id2d9niss1p7k4m407dpzpim2-closure-info/registration", will be passed to user space.72vm-test-run-horae-e2e-oidc> server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes73vm-test-run-horae-e2e-oidc> server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)74vm-test-run-horae-e2e-oidc> server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)75vm-test-run-horae-e2e-oidc> server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB76vm-test-run-horae-e2e-oidc> server # [ 0.000000] software IO TLB: area num 1.77vm-test-run-horae-e2e-oidc> server # [ 0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB)78vm-test-run-horae-e2e-oidc> server # [ 0.000000] Fallback order for Node 0: 079vm-test-run-horae-e2e-oidc> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214480vm-test-run-horae-e2e-oidc> server # [ 0.000000] Policy zone: DMA81vm-test-run-horae-e2e-oidc> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off82vm-test-run-horae-e2e-oidc> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=183vm-test-run-horae-e2e-oidc> server # [ 0.000000] allocated 2097152 bytes of page_ext84vm-test-run-horae-e2e-oidc> server # [ 0.000000] ftrace: allocating 74713 entries in 292 pages85vm-test-run-horae-e2e-oidc> server # [ 0.000000] ftrace: allocated 292 pages with 3 groups86vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: Hierarchical RCU implementation.87vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: RCU event tracing is enabled.88vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.89vm-test-run-horae-e2e-oidc> server # [ 0.000000] Trampoline variant of Tasks RCU enabled.90vm-test-run-horae-e2e-oidc> server # [ 0.000000] Rude variant of Tasks RCU enabled.91vm-test-run-horae-e2e-oidc> server # [ 0.000000] Tracing variant of Tasks RCU enabled.92vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.93vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=194vm-test-run-horae-e2e-oidc> server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.95vm-test-run-horae-e2e-oidc> server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.96vm-test-run-horae-e2e-oidc> server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.97vm-test-run-horae-e2e-oidc> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 098vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: 256 SPIs implemented99vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: 0 Extended SPIs implemented100vm-test-run-horae-e2e-oidc> server # [ 0.000000] Root IRQ handler: gic_handle_irq101vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs102vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0103vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000104vm-test-run-horae-e2e-oidc> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]105vm-test-run-horae-e2e-oidc> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)106vm-test-run-horae-e2e-oidc> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)107vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000108vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000109vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.110vm-test-run-horae-e2e-oidc> server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).111vm-test-run-horae-e2e-oidc> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns112vm-test-run-horae-e2e-oidc> server # [ 0.000083] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns113vm-test-run-horae-e2e-oidc> server # [ 0.005914] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)114vm-test-run-horae-e2e-oidc> server # [ 0.032874] Console: colour dummy device 80x25115vm-test-run-horae-e2e-oidc> server # [ 0.036655] printk: legacy console [tty0] enabled116vm-test-run-horae-e2e-oidc> server # [ 0.051177] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)117vm-test-run-horae-e2e-oidc> server # [ 0.052155] pid_max: default: 32768 minimum: 301118vm-test-run-horae-e2e-oidc> server # [ 0.060254] LSM: initializing lsm=capability,landlock,yama,bpf,ima119vm-test-run-horae-e2e-oidc> server # [ 0.063136] landlock: Up and running.120vm-test-run-horae-e2e-oidc> server # [ 0.063297] Yama: becoming mindful.121vm-test-run-horae-e2e-oidc> server # [ 0.065910] LSM support for eBPF active122vm-test-run-horae-e2e-oidc> server # [ 0.072011] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)123vm-test-run-horae-e2e-oidc> server # [ 0.072299] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)124vm-test-run-horae-e2e-oidc> server # [ 0.144275] cacheinfo: Unable to detect cache hierarchy for CPU 0125vm-test-run-horae-e2e-oidc> server # [ 0.175932] rcu: Hierarchical SRCU implementation.126vm-test-run-horae-e2e-oidc> server # [ 0.176143] rcu: Max phase no-delay instances is 1000.127vm-test-run-horae-e2e-oidc> server # [ 0.195029] fsl-mc MSI: its@8080000 domain created128vm-test-run-horae-e2e-oidc> server # [ 0.199415] EFI services will not be available.129vm-test-run-horae-e2e-oidc> server # [ 0.202803] smp: Bringing up secondary CPUs ...130vm-test-run-horae-e2e-oidc> server # [ 0.204317] smp: Brought up 1 node, 1 CPU131vm-test-run-horae-e2e-oidc> server # [ 0.204454] SMP: Total of 1 processors activated.132vm-test-run-horae-e2e-oidc> server # [ 0.204558] CPU: All CPU(s) started at EL1133vm-test-run-horae-e2e-oidc> server # [ 0.205209] CPU features: detected: Branch Target Identification134vm-test-run-horae-e2e-oidc> server # [ 0.205344] CPU features: detected: 32-bit EL0 Support135vm-test-run-horae-e2e-oidc> server # [ 0.205422] CPU features: detected: 32-bit EL1 Support136vm-test-run-horae-e2e-oidc> server # [ 0.205500] CPU features: detected: ARMv8.4 Translation Table Level137vm-test-run-horae-e2e-oidc> server # [ 0.205584] CPU features: detected: Instruction cache invalidation not required for I/D coherence138vm-test-run-horae-e2e-oidc> server # [ 0.205679] CPU features: detected: Data cache clean to the PoU not required for I/D coherence139vm-test-run-horae-e2e-oidc> server # [ 0.205811] CPU features: detected: Common not Private translations140vm-test-run-horae-e2e-oidc> server # [ 0.207920] CPU features: detected: CRC32 instructions141vm-test-run-horae-e2e-oidc> server # [ 0.208017] CPU features: detected: Data cache clean to Point of Deep Persistence142vm-test-run-horae-e2e-oidc> server # [ 0.208095] CPU features: detected: Data cache clean to Point of Persistence143vm-test-run-horae-e2e-oidc> server # [ 0.208163] CPU features: detected: Data independent timing control (DIT)144vm-test-run-horae-e2e-oidc> server # [ 0.208230] CPU features: detected: E0PD145vm-test-run-horae-e2e-oidc> server # [ 0.208283] CPU features: detected: Enhanced Counter Virtualization146vm-test-run-horae-e2e-oidc> server # [ 0.208346] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)147vm-test-run-horae-e2e-oidc> server # [ 0.208413] CPU features: detected: Enhanced Privileged Access Never148vm-test-run-horae-e2e-oidc> server # [ 0.208493] CPU features: detected: Enhanced Virtualization Traps149vm-test-run-horae-e2e-oidc> server # [ 0.208574] CPU features: detected: Fine Grained Traps150vm-test-run-horae-e2e-oidc> server # [ 0.208660] CPU features: detected: Guarded Control Stack (GCS)151vm-test-run-horae-e2e-oidc> server # [ 0.208804] CPU features: detected: Generic authentication (IMP DEF algorithm)152vm-test-run-horae-e2e-oidc> server # [ 0.209014] CPU features: detected: RCpc load-acquire (LDAPR)153vm-test-run-horae-e2e-oidc> server # [ 0.209165] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)154vm-test-run-horae-e2e-oidc> server # [ 0.209243] CPU features: detected: LSE atomic instructions155vm-test-run-horae-e2e-oidc> server # [ 0.209304] CPU features: detected: Memory Copy and Memory Set instructions156vm-test-run-horae-e2e-oidc> server # [ 0.209371] CPU features: detected: Privileged Access Never157vm-test-run-horae-e2e-oidc> server # [ 0.209520] CPU features: detected: PMUv3158vm-test-run-horae-e2e-oidc> server # [ 0.209586] CPU features: detected: SCTLR2159vm-test-run-horae-e2e-oidc> server # [ 0.209641] CPU features: detected: RAS Extension Support160vm-test-run-horae-e2e-oidc> server # [ 0.209773] CPU features: detected: RASv1p1 Extension Support161vm-test-run-horae-e2e-oidc> server # [ 0.209836] CPU features: detected: Random Number Generator162vm-test-run-horae-e2e-oidc> server # [ 0.209896] CPU features: detected: Speculation barrier (SB)163vm-test-run-horae-e2e-oidc> server # [ 0.209957] CPU features: detected: Stage-2 Force Write-Back164vm-test-run-horae-e2e-oidc> server # [ 0.210018] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality165vm-test-run-horae-e2e-oidc> server # [ 0.210151] CPU features: detected: TLB range maintenance instructions166vm-test-run-horae-e2e-oidc> server # [ 0.210820] CPU features: detected: WFx with timeout167vm-test-run-horae-e2e-oidc> server # [ 0.211016] CPU features: detected: Scalable Matrix Extension168vm-test-run-horae-e2e-oidc> server # [ 0.211082] CPU features: detected: FA64169vm-test-run-horae-e2e-oidc> server # [ 0.211135] CPU features: detected: SME2170vm-test-run-horae-e2e-oidc> server # [ 0.211188] CPU features: detected: Speculative Store Bypassing Safe (SSBS)171vm-test-run-horae-e2e-oidc> server # [ 0.211255] CPU features: detected: Scalable Vector Extension172vm-test-run-horae-e2e-oidc> server # [ 0.228752] alternatives: applying system-wide alternatives173vm-test-run-horae-e2e-oidc> server # [ 0.255660] CPU features: detected: Hardware dirty bit management on CPU0174vm-test-run-horae-e2e-oidc> server # [ 0.257238] SVE: maximum available vector length 256 bytes per vector175vm-test-run-horae-e2e-oidc> server # [ 0.257452] SVE: default vector length 64 bytes per vector176vm-test-run-horae-e2e-oidc> server # [ 0.260123] SME: minimum available vector length 16 bytes per vector177vm-test-run-horae-e2e-oidc> server # [ 0.260259] SME: maximum available vector length 256 bytes per vector178vm-test-run-horae-e2e-oidc> server # [ 0.260341] SME: default vector length 32 bytes per vector179vm-test-run-horae-e2e-oidc> server # [ 0.291514] Memory: 891192K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 116112K reserved, 32768K cma-reserved)180vm-test-run-horae-e2e-oidc> server # [ 0.339337] devtmpfs: initialized181vm-test-run-horae-e2e-oidc> server # [ 0.400575] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns182vm-test-run-horae-e2e-oidc> server # [ 0.401955] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)183vm-test-run-horae-e2e-oidc> server # [ 0.402562] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).184vm-test-run-horae-e2e-oidc> server # [ 0.410191] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL185vm-test-run-horae-e2e-oidc> server # [ 0.410386] 0 pages in range for non-PLT usage186vm-test-run-horae-e2e-oidc> server # [ 0.410496] 508320 pages in range for PLT usage187vm-test-run-horae-e2e-oidc> server # [ 0.416866] pinctrl core: initialized pinctrl subsystem188vm-test-run-horae-e2e-oidc> server # [ 0.444890] DMI not present or invalid.189vm-test-run-horae-e2e-oidc> server # [ 0.478789] NET: Registered PF_NETLINK/PF_ROUTE protocol family190vm-test-run-horae-e2e-oidc> server # [ 0.504190] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations191vm-test-run-horae-e2e-oidc> server # [ 0.507632] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations192vm-test-run-horae-e2e-oidc> server # [ 0.511062] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations193vm-test-run-horae-e2e-oidc> server # [ 0.511809] audit: initializing netlink subsys (disabled)194vm-test-run-horae-e2e-oidc> server # [ 0.516655] audit: type=2000 audit(0.428:1): state=initialized audit_enabled=0 res=1195vm-test-run-horae-e2e-oidc> server # [ 0.531788] thermal_sys: Registered thermal governor 'fair_share'196vm-test-run-horae-e2e-oidc> server # [ 0.531912] thermal_sys: Registered thermal governor 'bang_bang'197vm-test-run-horae-e2e-oidc> server # [ 0.532034] thermal_sys: Registered thermal governor 'step_wise'198vm-test-run-horae-e2e-oidc> server # [ 0.532100] thermal_sys: Registered thermal governor 'user_space'199vm-test-run-horae-e2e-oidc> server # [ 0.532170] thermal_sys: Registered thermal governor 'power_allocator'200vm-test-run-horae-e2e-oidc> server # [ 0.533762] cpuidle: using governor ladder201vm-test-run-horae-e2e-oidc> server # [ 0.534224] cpuidle: using governor menu202vm-test-run-horae-e2e-oidc> server # [ 0.540815] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.203vm-test-run-horae-e2e-oidc> server # [ 0.541991] ASID allocator initialised with 65536 entries204vm-test-run-horae-e2e-oidc> server # [ 0.564182] Serial: AMBA PL011 UART driver205vm-test-run-horae-e2e-oidc> server # [ 0.703141] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1206vm-test-run-horae-e2e-oidc> server # [ 0.707687] printk: console [ttyAMA0] enabled207vm-test-run-horae-e2e-oidc> server # [ 0.861307] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages208vm-test-run-horae-e2e-oidc> server # [ 0.861609] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page209vm-test-run-horae-e2e-oidc> server # [ 0.861731] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages210vm-test-run-horae-e2e-oidc> server # [ 0.861823] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page211vm-test-run-horae-e2e-oidc> server # [ 0.861914] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages212vm-test-run-horae-e2e-oidc> server # [ 0.862002] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page213vm-test-run-horae-e2e-oidc> server # [ 0.862094] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages214vm-test-run-horae-e2e-oidc> server # [ 0.862182] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page215vm-test-run-horae-e2e-oidc> server # [ 0.916574] fbcon: Taking over console216vm-test-run-horae-e2e-oidc> server # [ 0.917273] ACPI: Interpreter disabled.217vm-test-run-horae-e2e-oidc> server # [ 0.953568] iommu: Default domain type: Translated218vm-test-run-horae-e2e-oidc> server # [ 0.953826] iommu: DMA domain TLB invalidation policy: strict mode219vm-test-run-horae-e2e-oidc> server # [ 0.966982] SCSI subsystem initialized220vm-test-run-horae-e2e-oidc> server # [ 0.978868] usbcore: registered new interface driver usbfs221vm-test-run-horae-e2e-oidc> server # [ 0.979619] usbcore: registered new interface driver hub222vm-test-run-horae-e2e-oidc> server # [ 0.980121] usbcore: registered new device driver usb223vm-test-run-horae-e2e-oidc> server # [ 0.994917] pps_core: LinuxPPS API ver. 1 registered224vm-test-run-horae-e2e-oidc> server # [ 0.995960] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>225vm-test-run-horae-e2e-oidc> server # [ 0.996393] PTP clock support registered226vm-test-run-horae-e2e-oidc> server # [ 0.997324] EDAC MC: Ver: 3.0.0227vm-test-run-horae-e2e-oidc> server # [ 1.009275] scmi_core: SCMI protocol bus registered228vm-test-run-horae-e2e-oidc> server # [ 1.026318] FPGA manager framework229vm-test-run-horae-e2e-oidc> server # [ 1.073299] vgaarb: loaded230vm-test-run-horae-e2e-oidc> server # [ 1.089267] clocksource: Switched to clocksource arch_sys_counter231vm-test-run-horae-e2e-oidc> server # [ 1.107371] VFS: Disk quotas dquot_6.6.0232vm-test-run-horae-e2e-oidc> server # [ 1.107997] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)233vm-test-run-horae-e2e-oidc> server # [ 1.119751] netfs: FS-Cache loaded234vm-test-run-horae-e2e-oidc> server # [ 1.130894] pnp: PnP ACPI: disabled235vm-test-run-horae-e2e-oidc> server # [ 1.263890] NET: Registered PF_INET protocol family236vm-test-run-horae-e2e-oidc> server # [ 1.266928] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)237vm-test-run-horae-e2e-oidc> server # [ 1.676129] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)238vm-test-run-horae-e2e-oidc> server # [ 1.676636] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)239vm-test-run-horae-e2e-oidc> server # [ 1.678173] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)240vm-test-run-horae-e2e-oidc> server # [ 1.678957] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)241vm-test-run-horae-e2e-oidc> server # [ 1.679401] TCP: Hash tables configured (established 8192 bind 8192)242vm-test-run-horae-e2e-oidc> server # [ 1.683716] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)243vm-test-run-horae-e2e-oidc> server # [ 1.684555] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)244vm-test-run-horae-e2e-oidc> server # [ 1.686299] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)245vm-test-run-horae-e2e-oidc> server # [ 1.690165] NET: Registered PF_UNIX/PF_LOCAL protocol family246vm-test-run-horae-e2e-oidc> server # [ 1.691094] NET: Registered PF_XDP protocol family247vm-test-run-horae-e2e-oidc> server # [ 1.691550] PCI: CLS 0 bytes, default 64248vm-test-run-horae-e2e-oidc> server # [ 1.703244] Trying to unpack rootfs image as initramfs...249vm-test-run-horae-e2e-oidc> server # [ 1.713729] kvm [1]: HYP mode not available250vm-test-run-horae-e2e-oidc> server # [ 2.371933] Initialise system trusted keyrings251vm-test-run-horae-e2e-oidc> server # [ 2.383472] workingset: timestamp_bits=42 max_order=18 bucket_order=0252vm-test-run-horae-e2e-oidc> server # [ 2.405567] squashfs: version 4.0 (2009/01/31) Phillip Lougher253vm-test-run-horae-e2e-oidc> server # [ 2.414459] 9p: Installing v9fs 9p2000 file system support254vm-test-run-horae-e2e-oidc> server # [ 2.683265] Key type asymmetric registered255vm-test-run-horae-e2e-oidc> server # [ 2.683695] Asymmetric key parser 'x509' registered256vm-test-run-horae-e2e-oidc> server # [ 2.684637] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)257vm-test-run-horae-e2e-oidc> server # [ 2.695218] io scheduler mq-deadline registered258vm-test-run-horae-e2e-oidc> server # [ 2.695574] io scheduler kyber registered259vm-test-run-horae-e2e-oidc> server # [ 2.847717] pl061_gpio 9030000.pl061: PL061 GPIO chip registered260vm-test-run-horae-e2e-oidc> server # [ 2.872589] ledtrig-cpu: registered to indicate activity on CPUs261vm-test-run-horae-e2e-oidc> server # [ 2.891245] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:262vm-test-run-horae-e2e-oidc> server # [ 2.892529] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000263vm-test-run-horae-e2e-oidc> server # [ 2.898066] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000264vm-test-run-horae-e2e-oidc> server # [ 2.898483] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000265vm-test-run-horae-e2e-oidc> server # [ 2.899684] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits266vm-test-run-horae-e2e-oidc> server # [ 2.900846] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]267vm-test-run-horae-e2e-oidc> server # [ 2.912330] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00268vm-test-run-horae-e2e-oidc> server # [ 2.921608] pci_bus 0000:00: root bus resource [bus 00-ff]269vm-test-run-horae-e2e-oidc> server # [ 2.922015] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]270vm-test-run-horae-e2e-oidc> server # [ 2.922207] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]271vm-test-run-horae-e2e-oidc> server # [ 2.922319] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]272vm-test-run-horae-e2e-oidc> server # [ 2.924765] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint273vm-test-run-horae-e2e-oidc> server # [ 2.944512] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint274vm-test-run-horae-e2e-oidc> server # [ 2.953785] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]275vm-test-run-horae-e2e-oidc> server # [ 2.954177] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]276vm-test-run-horae-e2e-oidc> server # [ 2.954372] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]277vm-test-run-horae-e2e-oidc> server # [ 2.954639] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]278vm-test-run-horae-e2e-oidc> server # [ 2.956507] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint279vm-test-run-horae-e2e-oidc> server # [ 2.956807] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]280vm-test-run-horae-e2e-oidc> server # [ 2.959573] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]281vm-test-run-horae-e2e-oidc> server # [ 2.959890] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282vm-test-run-horae-e2e-oidc> server # [ 2.962671] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint283vm-test-run-horae-e2e-oidc> server # [ 2.963019] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]284vm-test-run-horae-e2e-oidc> server # [ 2.963153] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]285vm-test-run-horae-e2e-oidc> server # [ 2.963271] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]286vm-test-run-horae-e2e-oidc> server # [ 2.964726] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287vm-test-run-horae-e2e-oidc> server # [ 2.969405] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]288vm-test-run-horae-e2e-oidc> server # [ 2.969660] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]289vm-test-run-horae-e2e-oidc> server # [ 2.969783] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290vm-test-run-horae-e2e-oidc> server # [ 2.971227] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291vm-test-run-horae-e2e-oidc> server # [ 2.971525] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]292vm-test-run-horae-e2e-oidc> server # [ 2.971652] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]293vm-test-run-horae-e2e-oidc> server # [ 2.971770] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]294vm-test-run-horae-e2e-oidc> server # [ 2.981841] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint295vm-test-run-horae-e2e-oidc> server # [ 2.982197] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]296vm-test-run-horae-e2e-oidc> server # [ 2.982324] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]297vm-test-run-horae-e2e-oidc> server # [ 2.982460] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]298vm-test-run-horae-e2e-oidc> server # [ 2.983844] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint299vm-test-run-horae-e2e-oidc> server # [ 2.984116] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]300vm-test-run-horae-e2e-oidc> server # [ 2.984232] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]301vm-test-run-horae-e2e-oidc> server # [ 2.984347] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302vm-test-run-horae-e2e-oidc> server # [ 2.984513] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]303vm-test-run-horae-e2e-oidc> server # [ 2.994594] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint304vm-test-run-horae-e2e-oidc> server # [ 2.994972] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]305vm-test-run-horae-e2e-oidc> server # [ 2.995108] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]306vm-test-run-horae-e2e-oidc> server # [ 2.996544] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint307vm-test-run-horae-e2e-oidc> server # [ 2.996823] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]308vm-test-run-horae-e2e-oidc> server # [ 3.005638] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]309vm-test-run-horae-e2e-oidc> server # [ 3.007319] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint310vm-test-run-horae-e2e-oidc> server # [ 3.007621] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]311vm-test-run-horae-e2e-oidc> server # [ 3.010695] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint312vm-test-run-horae-e2e-oidc> server # [ 3.011108] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]313vm-test-run-horae-e2e-oidc> server # [ 3.011255] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]314vm-test-run-horae-e2e-oidc> server # [ 3.011370] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]315vm-test-run-horae-e2e-oidc> server # [ 3.012617] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint316vm-test-run-horae-e2e-oidc> server # [ 3.012914] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]317vm-test-run-horae-e2e-oidc> server # [ 3.025504] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]318vm-test-run-horae-e2e-oidc> server # [ 3.025666] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]319vm-test-run-horae-e2e-oidc> server # [ 3.033888] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned320vm-test-run-horae-e2e-oidc> server # [ 3.034426] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned321vm-test-run-horae-e2e-oidc> server # [ 3.034767] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned322vm-test-run-horae-e2e-oidc> server # [ 3.035163] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned323vm-test-run-horae-e2e-oidc> server # [ 3.035402] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned324vm-test-run-horae-e2e-oidc> server # [ 3.035629] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned325vm-test-run-horae-e2e-oidc> server # [ 3.035795] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned326vm-test-run-horae-e2e-oidc> server # [ 3.035954] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned327vm-test-run-horae-e2e-oidc> server # [ 3.036115] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned328vm-test-run-horae-e2e-oidc> server # [ 3.036315] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned329vm-test-run-horae-e2e-oidc> server # [ 3.036502] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned330vm-test-run-horae-e2e-oidc> server # [ 3.036670] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned331vm-test-run-horae-e2e-oidc> server # [ 3.049401] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned332vm-test-run-horae-e2e-oidc> server # [ 3.049815] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned333vm-test-run-horae-e2e-oidc> server # [ 3.049992] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned334vm-test-run-horae-e2e-oidc> server # [ 3.050133] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned335vm-test-run-horae-e2e-oidc> server # [ 3.050263] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned336vm-test-run-horae-e2e-oidc> server # [ 3.050398] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned337vm-test-run-horae-e2e-oidc> server # [ 3.050594] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned338vm-test-run-horae-e2e-oidc> server # [ 3.050736] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned339vm-test-run-horae-e2e-oidc> server # [ 3.050868] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned340vm-test-run-horae-e2e-oidc> server # [ 3.051004] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned341vm-test-run-horae-e2e-oidc> server # [ 3.051151] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned342vm-test-run-horae-e2e-oidc> server # [ 3.051285] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned343vm-test-run-horae-e2e-oidc> server # [ 3.051599] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned344vm-test-run-horae-e2e-oidc> server # [ 3.051779] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned345vm-test-run-horae-e2e-oidc> server # [ 3.051957] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned346vm-test-run-horae-e2e-oidc> server # [ 3.052084] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned347vm-test-run-horae-e2e-oidc> server # [ 3.052203] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned348vm-test-run-horae-e2e-oidc> server # [ 3.052328] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned349vm-test-run-horae-e2e-oidc> server # [ 3.052477] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned350vm-test-run-horae-e2e-oidc> server # [ 3.052611] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned351vm-test-run-horae-e2e-oidc> server # [ 3.052738] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned352vm-test-run-horae-e2e-oidc> server # [ 3.052864] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned353vm-test-run-horae-e2e-oidc> server # [ 3.070230] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]354vm-test-run-horae-e2e-oidc> server # [ 3.070459] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]355vm-test-run-horae-e2e-oidc> server # [ 3.070585] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]356vm-test-run-horae-e2e-oidc> server # [ 3.082027] pci 0000:00:0a.0: enabling device (0000 -> 0002)357vm-test-run-horae-e2e-oidc> server # [ 3.416777] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)358vm-test-run-horae-e2e-oidc> server # [ 3.437227] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)359vm-test-run-horae-e2e-oidc> server # [ 3.453941] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)360vm-test-run-horae-e2e-oidc> server # [ 3.458900] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)361vm-test-run-horae-e2e-oidc> server # [ 3.470995] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)362vm-test-run-horae-e2e-oidc> server # [ 3.475473] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)363vm-test-run-horae-e2e-oidc> server # [ 3.484059] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)364vm-test-run-horae-e2e-oidc> server # [ 3.495939] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)365vm-test-run-horae-e2e-oidc> server # [ 3.510589] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)366vm-test-run-horae-e2e-oidc> server # [ 3.526903] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)367vm-test-run-horae-e2e-oidc> server # [ 3.532197] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)368vm-test-run-horae-e2e-oidc> server # [ 3.639053] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled369vm-test-run-horae-e2e-oidc> server # [ 3.688508] msm_serial: driver initialized370vm-test-run-horae-e2e-oidc> server # [ 3.700268] SuperH (H)SCI(F) driver initialized371vm-test-run-horae-e2e-oidc> server # [ 3.702358] STM32 USART driver initialized372vm-test-run-horae-e2e-oidc> server # [ 3.935861] loop: module loaded373vm-test-run-horae-e2e-oidc> server # [ 3.945449] virtio_blk virtio5: 1/0/0 default/read/poll queues374vm-test-run-horae-e2e-oidc> server # [ 3.952313] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)375vm-test-run-horae-e2e-oidc> server # [ 4.015219] megasas: 07.734.00.00-rc1376vm-test-run-horae-e2e-oidc> server # [ 4.048176] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]377vm-test-run-horae-e2e-oidc> server # [ 4.060567] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000378vm-test-run-horae-e2e-oidc> server # [ 4.062699] Intel/Sharp Extended Query Table at 0x0031379vm-test-run-horae-e2e-oidc> server # [ 4.073411] Using buffer write method380vm-test-run-horae-e2e-oidc> server # [ 4.074895] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]381vm-test-run-horae-e2e-oidc> server # [ 4.077844] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000382vm-test-run-horae-e2e-oidc> server # [ 4.078147] Intel/Sharp Extended Query Table at 0x0031383vm-test-run-horae-e2e-oidc> server # [ 4.079773] Using buffer write method384vm-test-run-horae-e2e-oidc> server # [ 4.080147] Concatenating MTD devices:385vm-test-run-horae-e2e-oidc> server # [ 4.080258] (0): "0.flash"386vm-test-run-horae-e2e-oidc> server # [ 4.080346] (1): "0.flash"387vm-test-run-horae-e2e-oidc> server # [ 4.080425] into device "0.flash"388vm-test-run-horae-e2e-oidc> server # [ 5.366638] Freeing initrd memory: 25516K389vm-test-run-horae-e2e-oidc> server # [ 5.613742] tun: Universal TUN/TAP device driver, 1.6390vm-test-run-horae-e2e-oidc> server # [ 5.648010] thunder_xcv, ver 1.0391vm-test-run-horae-e2e-oidc> server # [ 5.648701] thunder_bgx, ver 1.0392vm-test-run-horae-e2e-oidc> server # [ 5.650024] nicpf, ver 1.0393vm-test-run-horae-e2e-oidc> server # [ 5.659856] e1000: Intel(R) PRO/1000 Network Driver394vm-test-run-horae-e2e-oidc> server # [ 5.660124] e1000: Copyright (c) 1999-2006 Intel Corporation.395vm-test-run-horae-e2e-oidc> server # [ 5.660602] e1000e: Intel(R) PRO/1000 Network Driver396vm-test-run-horae-e2e-oidc> server # [ 5.660753] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.397vm-test-run-horae-e2e-oidc> server # [ 5.662926] igb: Intel(R) Gigabit Ethernet Network Driver398vm-test-run-horae-e2e-oidc> server # [ 5.663132] igb: Copyright (c) 2007-2014 Intel Corporation.399vm-test-run-horae-e2e-oidc> server # [ 5.663668] igbvf: Intel(R) Gigabit Virtual Function Network Driver400vm-test-run-horae-e2e-oidc> server # [ 5.663821] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.401vm-test-run-horae-e2e-oidc> server # [ 5.667919] sky2: driver version 1.30402vm-test-run-horae-e2e-oidc> server # [ 5.694241] ehci-pci 0000:00:0a.0: EHCI Host Controller403vm-test-run-horae-e2e-oidc> server # [ 5.695501] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1404vm-test-run-horae-e2e-oidc> server # [ 5.704395] usbcore: registered new interface driver usb-storage405vm-test-run-horae-e2e-oidc> server # [ 5.708625] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000406vm-test-run-horae-e2e-oidc> server # [ 5.710885] usbcore: registered new interface driver usbserial_generic407vm-test-run-horae-e2e-oidc> server # [ 5.711548] usbserial: USB Serial support registered for generic408vm-test-run-horae-e2e-oidc> server # [ 5.721578] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00409vm-test-run-horae-e2e-oidc> server # [ 5.732038] hv_vmbus: registering driver hyperv_keyboard410vm-test-run-horae-e2e-oidc> server # [ 5.748001] hub 1-0:1.0: USB hub found411vm-test-run-horae-e2e-oidc> server # [ 5.760093] hub 1-0:1.0: 6 ports detected412vm-test-run-horae-e2e-oidc> server # [ 5.778701] rtc-pl031 9010000.pl031: registered as rtc0413vm-test-run-horae-e2e-oidc> server # [ 5.779849] rtc-pl031 9010000.pl031: setting system clock to 2026-10-06T19:51:27 UTC (1791316287)414vm-test-run-horae-e2e-oidc> server # [ 5.786292] i2c_dev: i2c /dev entries driver415vm-test-run-horae-e2e-oidc> server # [ 5.827217] sdhci: Secure Digital Host Controller Interface driver416vm-test-run-horae-e2e-oidc> server # [ 5.827517] sdhci: Copyright(c) Pierre Ossman417vm-test-run-horae-e2e-oidc> server # [ 5.832979] Synopsys Designware Multimedia Card Interface Driver418vm-test-run-horae-e2e-oidc> server # [ 5.839198] sdhci-pltfm: SDHCI platform and OF driver helper419vm-test-run-horae-e2e-oidc> server # [ 5.861667] hid: raw HID events driver (C) Jiri Kosina420vm-test-run-horae-e2e-oidc> server # [ 5.867700] usbcore: registered new interface driver usbhid421vm-test-run-horae-e2e-oidc> server # [ 5.867948] usbhid: USB HID core driver422vm-test-run-horae-e2e-oidc> server # [ 5.888350] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available423vm-test-run-horae-e2e-oidc> server # [ 5.917578] drop_monitor: Initializing network drop monitor service424vm-test-run-horae-e2e-oidc> server # [ 5.920156] NET: Registered PF_INET6 protocol family425vm-test-run-horae-e2e-oidc> server # [ 5.943707] Segment Routing with IPv6426vm-test-run-horae-e2e-oidc> server # [ 5.944490] In-situ OAM (IOAM) with IPv6427vm-test-run-horae-e2e-oidc> server # [ 5.946648] NET: Registered PF_PACKET protocol family428vm-test-run-horae-e2e-oidc> server # [ 5.949571] 9pnet: Installing 9P2000 support429vm-test-run-horae-e2e-oidc> server # [ 5.959360] Key type dns_resolver registered430vm-test-run-horae-e2e-oidc> server # [ 6.025598] usb 1-1: new high-speed USB device number 2 using ehci-pci431vm-test-run-horae-e2e-oidc> server # [ 6.071588] registered taskstats version 1432vm-test-run-horae-e2e-oidc> server # [ 6.078122] Loading compiled-in X.509 certificates433vm-test-run-horae-e2e-oidc> server # [ 6.261250] 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/input0434vm-test-run-horae-e2e-oidc> server # [ 6.265337] Demotion targets for Node 0: null435vm-test-run-horae-e2e-oidc> server # [ 6.267803] Key type .fscrypt registered436vm-test-run-horae-e2e-oidc> server # [ 6.267991] Key type fscrypt-provisioning registered437vm-test-run-horae-e2e-oidc> server # [ 6.275363] ima: No TPM chip found, activating TPM-bypass!438vm-test-run-horae-e2e-oidc> server # [ 6.278581] ima: Allocated hash algorithm: sha1439vm-test-run-horae-e2e-oidc> server # [ 6.282890] ima: No architecture policies found440vm-test-run-horae-e2e-oidc> server # [ 6.300117] input: gpio-keys as /devices/platform/gpio-keys/input/input1441vm-test-run-horae-e2e-oidc> server # [ 6.331065] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0442vm-test-run-horae-e2e-oidc> server # [ 6.441937] clk: Disabling unused clocks443vm-test-run-horae-e2e-oidc> server # [ 6.442531] PM: genpd: Disabling unused power domains444vm-test-run-horae-e2e-oidc> server # [ 6.479603] usb 1-2: new high-speed USB device number 3 using ehci-pci445vm-test-run-horae-e2e-oidc> server # [ 6.766610] 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/input2446vm-test-run-horae-e2e-oidc> server # [ 6.770152] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0447vm-test-run-horae-e2e-oidc> server # [ 7.088737] Freeing unused kernel memory: 4736K448vm-test-run-horae-e2e-oidc> server # [ 7.091398] Run /init as init process449vm-test-run-horae-e2e-oidc> server # [ 7.382905] systemd[1]: Successfully made /usr/ read-only.450vm-test-run-horae-e2e-oidc> server # [ 7.756512] 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)451vm-test-run-horae-e2e-oidc> server # [ 7.759790] systemd[1]: Detected virtualization qemu.452vm-test-run-horae-e2e-oidc> server # [ 7.761831] systemd[1]: Detected architecture arm64.453vm-test-run-horae-e2e-oidc> server # [ 7.762842] systemd[1]: Running in initrd.454vm-test-run-horae-e2e-oidc> server # [ 7.780107] systemd[1]: Initializing machine ID from random generator.455vm-test-run-horae-e2e-oidc> server # [ 7.793622] systemd[1]: Hostname set to <server>.456vm-test-run-horae-e2e-oidc> server # [ 9.735079] systemd[1]: Queued start job for default target Initrd Default Target.457vm-test-run-horae-e2e-oidc> server # [ 9.829893] systemd[1]: Created slice Slice /system/modprobe.458vm-test-run-horae-e2e-oidc> server # [ 9.840956] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459vm-test-run-horae-e2e-oidc> server # [ 9.844782] systemd[1]: Expecting device /dev/disk/by-label/nixos...460vm-test-run-horae-e2e-oidc> server # [ 9.847561] systemd[1]: Reached target Path Units.461vm-test-run-horae-e2e-oidc> server # [ 9.849691] systemd[1]: Reached target Slice Units.462vm-test-run-horae-e2e-oidc> server # [ 9.851283] systemd[1]: Reached target Swaps.463vm-test-run-horae-e2e-oidc> server # [ 9.852874] systemd[1]: Reached target Timer Units.464vm-test-run-horae-e2e-oidc> server # [ 9.862652] systemd[1]: Listening on D-Bus System Message Bus Socket.465vm-test-run-horae-e2e-oidc> server # [ 9.870615] systemd[1]: Listening on Journal Socket (/dev/log).466vm-test-run-horae-e2e-oidc> server # [ 9.876010] systemd[1]: Listening on Journal Sockets.467vm-test-run-horae-e2e-oidc> server # [ 9.880904] systemd[1]: Listening on udev Control Socket.468vm-test-run-horae-e2e-oidc> server # [ 9.885367] systemd[1]: Listening on udev Kernel Socket.469vm-test-run-horae-e2e-oidc> server # [ 9.887140] systemd[1]: Reached target Socket Units.470vm-test-run-horae-e2e-oidc> server # [ 9.948220] systemd[1]: Starting Create List of Static Device Nodes...471vm-test-run-horae-e2e-oidc> server # [ 10.007264] systemd[1]: Starting Load Kernel Module 9pnet_virtio...472vm-test-run-horae-e2e-oidc> server # [ 10.015388] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs473vm-test-run-horae-e2e-oidc> server # [ 10.115117] systemd[1]: Mounting Kernel Configuration File System...474vm-test-run-horae-e2e-oidc> server # [ 10.535539] systemd[1]: Starting Journal Service...475vm-test-run-horae-e2e-oidc> server # [ 10.659162] systemd[1]: Starting Load Kernel Modules...476vm-test-run-horae-e2e-oidc> server # [ 10.662926] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki477vm-test-run-horae-e2e-oidc> server # [ 10.763164] systemd[1]: Starting Coldplug All udev Devices...478vm-test-run-horae-e2e-oidc> server # [ 11.011337] systemd[1]: Finished Create List of Static Device Nodes.479vm-test-run-horae-e2e-oidc> server # [ 11.224641] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480vm-test-run-horae-e2e-oidc> server # [ 11.279068] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481vm-test-run-horae-e2e-oidc> server # [ 11.334655] systemd[1]: Mounted Kernel Configuration File System.482vm-test-run-horae-e2e-oidc> server # [ 11.483061] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...483vm-test-run-horae-e2e-oidc> server # [ 11.947495] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.484vm-test-run-horae-e2e-oidc> server # [ 11.986805] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev485vm-test-run-horae-e2e-oidc> server # [ 12.240815] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0486vm-test-run-horae-e2e-oidc> server # [ 12.267087] [drm] features: -virgl +edid -resource_blob -host_visible487vm-test-run-horae-e2e-oidc> server # [ 12.267319] [drm] features: -context_init488vm-test-run-horae-e2e-oidc> server # [ 12.280242] [drm] number of scanouts: 1489vm-test-run-horae-e2e-oidc> server # [ 12.280581] [drm] number of cap sets: 0490vm-test-run-horae-e2e-oidc> server # [ 12.364048] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic491vm-test-run-horae-e2e-oidc> server # [ 12.364509] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0492vm-test-run-horae-e2e-oidc> server # [ 12.492152] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.493vm-test-run-horae-e2e-oidc> server # [ 12.494871] systemd-journald[74]: Collecting audit messages is disabled.494vm-test-run-horae-e2e-oidc> server # [ 12.635529] systemd[1]: Starting Create Static Device Nodes in /dev...495vm-test-run-horae-e2e-oidc> server # [ 12.773913] Console: switching to colour frame buffer device 160x50496vm-test-run-horae-e2e-oidc> server # [ 12.835963] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device497vm-test-run-horae-e2e-oidc> server # [ 12.970568] systemd[1]: Finished Load Kernel Modules.498vm-test-run-horae-e2e-oidc> server # [ 13.115200] systemd[1]: Starting Apply Kernel Variables...499vm-test-run-horae-e2e-oidc> server # [ 13.652261] systemd[1]: Finished Create Static Device Nodes in /dev.500vm-test-run-horae-e2e-oidc> server # [ 13.698521] systemd[1]: Reached target Preparation for Local File Systems.501vm-test-run-horae-e2e-oidc> server # [ 13.699744] systemd[1]: Reached target Local File Systems.502vm-test-run-horae-e2e-oidc> server # [ 13.839287] systemd[1]: Starting Rule-based Manager for Device Events and Files...503vm-test-run-horae-e2e-oidc> server # [ 14.092209] systemd[1]: Finished Apply Kernel Variables.504vm-test-run-horae-e2e-oidc> server # [ 17.371637] systemd[1]: Started Rule-based Manager for Device Events and Files.505vm-test-run-horae-e2e-oidc> server # [ 17.599128] systemd[1]: Started Journal Service.506vm-test-run-horae-e2e-oidc> server # [ 17.524907] systemd-modules-load[75]: Inserted module 'dm_mod'507vm-test-run-horae-e2e-oidc> server # [ 17.579184] systemd-modules-load[75]: Module 'virtio_balloon' is built in508vm-test-run-horae-e2e-oidc> server # [ 17.622902] systemd-modules-load[75]: Module 'virtio_console' is built in509vm-test-run-horae-e2e-oidc> server # [ 17.658333] systemd-modules-load[75]: Inserted module 'virtio_gpu'510vm-test-run-horae-e2e-oidc> server # [ 17.693251] systemd-modules-load[75]: Module 'virtio_rng' is built in511vm-test-run-horae-e2e-oidc> server # [ 17.759350] systemd-udevd[81]: Using default interface naming scheme 'v260'.512vm-test-run-horae-e2e-oidc> server # [ 17.830230] systemd[1]: Starting Create System Files and Directories...513vm-test-run-horae-e2e-oidc> server # [ 18.851362] systemd[1]: Finished Create System Files and Directories.514vm-test-run-horae-e2e-oidc> server # [ 19.641170] systemd[1]: Starting Virtual Console Setup...515vm-test-run-horae-e2e-oidc> server # [ 21.406180] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.516vm-test-run-horae-e2e-oidc> server # [ 21.479931] systemd[1]: Finished Virtual Console Setup.517vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...518vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.519vm-test-run-horae-e2e-oidc> server # [ 42.031357] systemd[1]: Finished Coldplug All udev Devices.520vm-test-run-horae-e2e-oidc> server # [ 42.053162] systemd[1]: Reached target System Initialization.521vm-test-run-horae-e2e-oidc> server # [ 42.069334] systemd[1]: Reached target Basic System.522vm-test-run-horae-e2e-oidc> server # [ 51.848862] (udev-worker)[131]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.523vm-test-run-horae-e2e-oidc> server # [ 51.855988] (udev-worker)[131]: Network interface NamePolicy= disabled on kernel command line.524vm-test-run-horae-e2e-oidc> server # [ 51.882375] (udev-worker)[130]: Network interface NamePolicy= disabled on kernel command line.525vm-test-run-horae-e2e-oidc> server # [ 53.975155] systemd[1]: Found device /dev/disk/by-label/nixos.526vm-test-run-horae-e2e-oidc> server # [ 53.990594] systemd[1]: Reached target Initrd Root Device.527vm-test-run-horae-e2e-oidc> server # [ 54.035324] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...528vm-test-run-horae-e2e-oidc> server # [ 54.991545] systemd-fsck[153]: nixos: clean, 12/65536 files, 13019/262144 blocks529vm-test-run-horae-e2e-oidc> server # [ 55.115449] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.530vm-test-run-horae-e2e-oidc> server # [ 55.205537] systemd[1]: Mounting /sysroot...531vm-test-run-horae-e2e-oidc> server # [ 55.866666] EXT4-fs (vda): mounted filesystem 672a12cf-5c1d-4c5e-9e0f-89bd54a7d837 r/w with ordered data mode. Quota mode: none.532vm-test-run-horae-e2e-oidc> server # [ 55.893229] systemd[1]: Mounted /sysroot.533vm-test-run-horae-e2e-oidc> server # [ 55.927664] systemd[1]: Reached target Initrd Root File System.534vm-test-run-horae-e2e-oidc> server # [ 56.075156] systemd[1]: Mounting /sysroot/nix/.ro-store...535vm-test-run-horae-e2e-oidc> server # [ 56.254587] systemd[1]: Mounting /sysroot/nix/.rw-store...536vm-test-run-horae-e2e-oidc> server # [ 56.400011] systemd[1]: Mounting /sysroot/run...537vm-test-run-horae-e2e-oidc> server # [ 56.689401] systemd[1]: Mounting /sysroot/tmp/shared...538vm-test-run-horae-e2e-oidc> server # [ 56.931236] systemd[1]: Mounting /sysroot/tmp/xchg...539vm-test-run-horae-e2e-oidc> server # [ 57.098097] systemd[1]: Starting Mountpoints Configured in the Real Root...540vm-test-run-horae-e2e-oidc> server # [ 57.945928] systemd-sysroot-fstab-check[166]: /sysroot should be mounted in the initrd, will request daemon-reload.541vm-test-run-horae-e2e-oidc> server # [ 58.055839] systemd[1]: Mounted /sysroot/nix/.ro-store.542vm-test-run-horae-e2e-oidc> server # [ 58.062018] systemd[1]: Mounted /sysroot/nix/.rw-store.543vm-test-run-horae-e2e-oidc> server # [ 58.069141] systemd[1]: Mounted /sysroot/run.544vm-test-run-horae-e2e-oidc> server # [ 58.079401] systemd[1]: Mounted /sysroot/tmp/shared.545vm-test-run-horae-e2e-oidc> server # [ 58.094809] systemd[1]: Mounted /sysroot/tmp/xchg.546vm-test-run-horae-e2e-oidc> server # [ 58.238263] systemd[1]: Reload requested from client PID 166 ('systemd-sysroot') (unit initrd-parse-etc.service)...547vm-test-run-horae-e2e-oidc> server # [ 58.245974] systemd[1]: Reloading...548vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...549vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.550vm-test-run-horae-e2e-oidc> server # [ 61.190482] systemd[1]: Reloading finished in 2921 ms.551vm-test-run-horae-e2e-oidc> server # [ 61.378513] systemd-sysroot-fstab-check[166]: Requesting initrd-fs.target/start/replace...552vm-test-run-horae-e2e-oidc> server # [ 61.433639] systemd-sysroot-fstab-check[166]: Requesting swap.target/start/replace...553vm-test-run-horae-e2e-oidc> server # [ 61.498596] systemd[1]: initrd-parse-etc.service: Deactivated successfully.554vm-test-run-horae-e2e-oidc> server # [ 61.505589] systemd[1]: Finished Mountpoints Configured in the Real Root.555vm-test-run-horae-e2e-oidc> server # [ 61.513271] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.556vm-test-run-horae-e2e-oidc> server # [ 61.589280] systemd[1]: Starting rw-sysroot-nix-store.service...557vm-test-run-horae-e2e-oidc> server # [ 62.033312] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.558vm-test-run-horae-e2e-oidc> server # [ 62.055853] systemd[1]: Finished rw-sysroot-nix-store.service.559vm-test-run-horae-e2e-oidc> server # [ 62.149156] systemd[1]: Mounting /sysroot/nix/store...560vm-test-run-horae-e2e-oidc> server # [ 62.867857] systemd[1]: Mounted /sysroot/nix/store.561vm-test-run-horae-e2e-oidc> server # [ 62.899514] systemd[1]: Reached target Initrd File Systems.562vm-test-run-horae-e2e-oidc> server # [ 62.939075] systemd[1]: Starting Find NixOS closure...563vm-test-run-horae-e2e-oidc> server # [ 63.005162] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...564vm-test-run-horae-e2e-oidc> server # [ 63.903153] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.565vm-test-run-horae-e2e-oidc> server # [ 63.965161] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.566vm-test-run-horae-e2e-oidc> server # [ 64.417594] systemd[1]: Finished Find NixOS closure.567vm-test-run-horae-e2e-oidc> server # [ 64.444061] systemd[1]: Reached target Initrd Default Target.568vm-test-run-horae-e2e-oidc> server # [ 64.503138] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...569vm-test-run-horae-e2e-oidc> server # [ 65.280803] systemd[1]: Stopped target Initrd Default Target.570vm-test-run-horae-e2e-oidc> server # [ 65.289915] systemd[1]: Stopped target Basic System.571vm-test-run-horae-e2e-oidc> server # [ 65.301802] systemd[1]: Stopped target Initrd Root Device.572vm-test-run-horae-e2e-oidc> server # [ 65.323233] systemd[1]: Stopped target Path Units.573vm-test-run-horae-e2e-oidc> server # [ 65.335888] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.574vm-test-run-horae-e2e-oidc> server # [ 65.353145] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.575vm-test-run-horae-e2e-oidc> server # [ 65.382627] systemd[1]: Stopped target Slice Units.576vm-test-run-horae-e2e-oidc> server # [ 65.401183] systemd[1]: Stopped target Socket Units.577vm-test-run-horae-e2e-oidc> server # [ 65.421195] systemd[1]: Stopped target System Initialization.578vm-test-run-horae-e2e-oidc> server # [ 65.441825] systemd[1]: Stopped target Swaps.579vm-test-run-horae-e2e-oidc> server # [ 65.459141] systemd[1]: Stopped target Timer Units.580vm-test-run-horae-e2e-oidc> server # [ 65.473482] systemd[1]: dbus.socket: Deactivated successfully.581vm-test-run-horae-e2e-oidc> server # [ 65.493350] systemd[1]: Closed D-Bus System Message Bus Socket.582vm-test-run-horae-e2e-oidc> server # [ 65.513516] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.583vm-test-run-horae-e2e-oidc> server # [ 65.529300] systemd[1]: Stopped Find NixOS closure.584vm-test-run-horae-e2e-oidc> server # [ 65.633355] systemd[1]: Starting Load Kernel Module 9pnet_virtio...585vm-test-run-horae-e2e-oidc> server # [ 65.773646] systemd[1]: Starting rw-sysroot-nix-store.service...586vm-test-run-horae-e2e-oidc> server # [ 65.786030] systemd[1]: systemd-sysctl.service: Deactivated successfully.587vm-test-run-horae-e2e-oidc> server # [ 65.814288] systemd[1]: Stopped Apply Kernel Variables.588vm-test-run-horae-e2e-oidc> server # [ 65.863392] systemd[1]: systemd-modules-load.service: Deactivated successfully.589vm-test-run-horae-e2e-oidc> server # [ 65.909940] systemd[1]: Stopped Load Kernel Modules.590vm-test-run-horae-e2e-oidc> server # [ 65.950223] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.591vm-test-run-horae-e2e-oidc> server # [ 65.998527] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.592vm-test-run-horae-e2e-oidc> server # [ 66.037838] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.593vm-test-run-horae-e2e-oidc> server # [ 66.098568] systemd[1]: Stopped Create System Files and Directories.594vm-test-run-horae-e2e-oidc> server # [ 66.179789] systemd[1]: Stopped target Local File Systems.595vm-test-run-horae-e2e-oidc> server # [ 66.194041] systemd[1]: Stopped target Preparation for Local File Systems.596vm-test-run-horae-e2e-oidc> server # [ 66.234685] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.597vm-test-run-horae-e2e-oidc> server # [ 66.327708] systemd[1]: Stopped Coldplug All udev Devices.598vm-test-run-horae-e2e-oidc> server # [ 66.342316] systemd[1]: systemd-udev-trigger.service: Consumed 16.284s CPU time over 55.642s wall clock time, 9.3M memory peak.599vm-test-run-horae-e2e-oidc> server # [ 66.381359] systemd[1]: Stopping Rule-based Manager for Device Events and Files...600vm-test-run-horae-e2e-oidc> server # [ 66.394112] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.601vm-test-run-horae-e2e-oidc> server # [ 66.414073] systemd[1]: Stopped Virtual Console Setup.602vm-test-run-horae-e2e-oidc> server # [ 66.674696] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.603vm-test-run-horae-e2e-oidc> server # [ 66.687697] systemd[1]: Finished Load Kernel Module 9pnet_virtio.604vm-test-run-horae-e2e-oidc> server # [ 66.715941] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.605vm-test-run-horae-e2e-oidc> server # [ 66.731096] systemd[1]: Finished rw-sysroot-nix-store.service.606vm-test-run-horae-e2e-oidc> server # [ 66.861168] systemd[1]: initrd-cleanup.service: Deactivated successfully.607vm-test-run-horae-e2e-oidc> server # [ 66.870409] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.608vm-test-run-horae-e2e-oidc> server # [ 67.741892] systemd[1]: systemd-udevd.service: Deactivated successfully.609vm-test-run-horae-e2e-oidc> server # [ 67.753469] systemd[1]: Stopped Rule-based Manager for Device Events and Files.610vm-test-run-horae-e2e-oidc> server # [ 67.758753] systemd[1]: systemd-udevd.service: Consumed 19.847s CPU time over 54.016s wall clock time, 19.7M memory peak.611vm-test-run-horae-e2e-oidc> server # [ 67.771730] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.612vm-test-run-horae-e2e-oidc> server # [ 67.781983] systemd[1]: Closed udev Control Socket.613vm-test-run-horae-e2e-oidc> server # [ 67.815143] systemd[1]: Starting Cleanup udev Database...614vm-test-run-horae-e2e-oidc> server # [ 67.822832] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.615vm-test-run-horae-e2e-oidc> server # [ 67.829967] systemd[1]: Stopped Create Static Device Nodes in /dev.616vm-test-run-horae-e2e-oidc> server # [ 67.839336] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.617vm-test-run-horae-e2e-oidc> server # [ 67.845926] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.618vm-test-run-horae-e2e-oidc> server # [ 67.852883] systemd[1]: kmod-static-nodes.service: Deactivated successfully.619vm-test-run-horae-e2e-oidc> server # [ 67.859867] systemd[1]: Stopped Create List of Static Device Nodes.620vm-test-run-horae-e2e-oidc> server # [ 68.217038] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.621vm-test-run-horae-e2e-oidc> server # [ 68.226096] systemd[1]: Finished Cleanup udev Database.622vm-test-run-horae-e2e-oidc> server # [ 68.242072] systemd[1]: Reached target Switch Root.623vm-test-run-horae-e2e-oidc> server # [ 68.278194] systemd[1]: Starting NixOS Activation...624vm-test-run-horae-e2e-oidc> server # [ 70.927908] initrd-nixos-activation-start[221]: booting system configuration /nix/store/9mqs26gy19d4rday0l3dg6qspcp9h4ym-nixos-system-server-test625vm-test-run-horae-e2e-oidc> server # [ 71.887765] initrd-nixos-activation-start[221]: running activation script...626vm-test-run-horae-e2e-oidc> server # [ 79.418990] initrd-nixos-activation-start[244]: setting up /etc...627vm-test-run-horae-e2e-oidc> server # [ 83.495752] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.628vm-test-run-horae-e2e-oidc> server # [ 83.507157] systemd[1]: Finished NixOS Activation.629vm-test-run-horae-e2e-oidc> server # [ 83.514889] systemd[1]: initrd-nixos-activation.service: Consumed 8.679s CPU time over 15.208s wall clock time, 22.4M memory peak.630vm-test-run-horae-e2e-oidc> server # [ 83.551323] systemd[1]: Starting Switch Root...631vm-test-run-horae-e2e-oidc> server # [ 83.853216] systemd[1]: Switching root.632vm-test-run-horae-e2e-oidc> server # [ 84.284035] systemd-journald[74]: Received SIGTERM from PID 1 (systemd).633vm-test-run-horae-e2e-oidc> server # [ 87.580150] 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)634vm-test-run-horae-e2e-oidc> server # [ 87.585796] systemd[1]: Detected virtualization qemu.635vm-test-run-horae-e2e-oidc> server # [ 87.589614] systemd[1]: Detected architecture arm64.636vm-test-run-horae-e2e-oidc> server # [ 87.595046] systemd[1]: Detected first boot.637vm-test-run-horae-e2e-oidc> server # [ 87.646998] systemd[1]: Initializing machine ID from random generator.638vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...639vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.640vm-test-run-horae-e2e-oidc> server # [ 90.683856] systemd[1]: bpf-restrict-fs: LSM BPF program attached641vm-test-run-horae-e2e-oidc> server # [ 93.494041] systemd[1]: Applying preset policy.642vm-test-run-horae-e2e-oidc> server # [ 101.694296] systemd[1]: Populated /etc with preset unit settings.643vm-test-run-horae-e2e-oidc> server # [ 109.938110] systemd[1]: initrd-switch-root.service: Deactivated successfully.644vm-test-run-horae-e2e-oidc> server # [ 109.951223] systemd[1]: Stopped initrd-switch-root.service.645vm-test-run-horae-e2e-oidc> server # [ 110.003640] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.646vm-test-run-horae-e2e-oidc> server # [ 110.044021] systemd[1]: Created slice Slice /system/getty.647vm-test-run-horae-e2e-oidc> server # [ 110.071684] systemd[1]: Created slice User and Session Slice.648vm-test-run-horae-e2e-oidc> server # [ 110.084666] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.649vm-test-run-horae-e2e-oidc> server # [ 110.097425] systemd[1]: Started Forward Password Requests to Wall Directory Watch.650vm-test-run-horae-e2e-oidc> server # [ 110.106232] systemd[1]: Expecting device /dev/hvc0...651vm-test-run-horae-e2e-oidc> server # [ 110.114088] systemd[1]: Expecting device /dev/ttyAMA0...652vm-test-run-horae-e2e-oidc> server # [ 110.122207] systemd[1]: Reached target Local Encrypted Volumes.653vm-test-run-horae-e2e-oidc> server # [ 110.130327] systemd[1]: Stopped target initrd-fs.target.654vm-test-run-horae-e2e-oidc> server # [ 110.138161] systemd[1]: Stopped target initrd-root-fs.target.655vm-test-run-horae-e2e-oidc> server # [ 110.146226] systemd[1]: Stopped target initrd-switch-root.target.656vm-test-run-horae-e2e-oidc> server # [ 110.154925] systemd[1]: Reached target Virtual Machines and Containers.657vm-test-run-horae-e2e-oidc> server # [ 110.163955] systemd[1]: Reached target Path Units.658vm-test-run-horae-e2e-oidc> server # [ 110.171972] systemd[1]: Reached target Remote File Systems.659vm-test-run-horae-e2e-oidc> server # [ 110.179922] systemd[1]: Reached target Slice Units.660vm-test-run-horae-e2e-oidc> server # [ 110.188072] systemd[1]: Reached target Swaps.661vm-test-run-horae-e2e-oidc> server # [ 110.240279] systemd[1]: Listening on Query the User Interactively for a Password.662vm-test-run-horae-e2e-oidc> server # [ 110.302213] systemd[1]: Listening on Process Core Dump Socket.663vm-test-run-horae-e2e-oidc> server # [ 110.348514] systemd[1]: Listening on Credential Encryption/Decryption.664vm-test-run-horae-e2e-oidc> server # [ 110.400424] systemd[1]: Listening on Factory Reset Management.665vm-test-run-horae-e2e-oidc> server # [ 110.472588] systemd[1]: Starting Journal Log Access Socket...666vm-test-run-horae-e2e-oidc> server # [ 110.517973] systemd[1]: Listening on Journal Audit Socket.667vm-test-run-horae-e2e-oidc> server # [ 110.590889] systemd[1]: Listening on Console Output Muting Service Socket.668vm-test-run-horae-e2e-oidc> server # [ 110.603078] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.669vm-test-run-horae-e2e-oidc> server # [ 110.611870] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki670vm-test-run-horae-e2e-oidc> server # [ 110.619987] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki671vm-test-run-horae-e2e-oidc> server # [ 110.739234] systemd[1]: Listening on Disk Repartitioning Service Socket.672vm-test-run-horae-e2e-oidc> server # [ 110.749880] systemd[1]: Listening on udev Control Socket.673vm-test-run-horae-e2e-oidc> server # [ 110.760821] systemd[1]: Listening on udev Varlink Socket.674vm-test-run-horae-e2e-oidc> server # [ 110.834176] systemd[1]: Mounting Huge Pages File System...675vm-test-run-horae-e2e-oidc> server # [ 110.935193] systemd[1]: Mounting POSIX Message Queue File System...676vm-test-run-horae-e2e-oidc> server # [ 111.099462] systemd[1]: Mounting Kernel Debug File System...677vm-test-run-horae-e2e-oidc> server # [ 111.302702] systemd[1]: Mounting Kernel Trace File System...678vm-test-run-horae-e2e-oidc> server # [ 111.587609] systemd[1]: Starting Create List of Static Device Nodes...679vm-test-run-horae-e2e-oidc> server # [ 111.898949] systemd[1]: Starting Load Kernel Module 9pnet_virtio...680vm-test-run-horae-e2e-oidc> server # [ 111.944155] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs681vm-test-run-horae-e2e-oidc> server # [ 112.292715] systemd[1]: Mounting Kernel Configuration File System...682vm-test-run-horae-e2e-oidc> server # [ 112.371927] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm683vm-test-run-horae-e2e-oidc> server # [ 112.456060] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore684vm-test-run-horae-e2e-oidc> server # [ 113.047205] systemd[1]: Starting Load Kernel Module fuse...685vm-test-run-horae-e2e-oidc> server # [ 113.146600] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67686vm-test-run-horae-e2e-oidc> server # [ 113.679033] systemd[1]: Starting Journal Service...687vm-test-run-horae-e2e-oidc> server # [ 114.062759] systemd[1]: Starting Load Kernel Modules...688vm-test-run-horae-e2e-oidc> server # [ 114.508866] fuse: init (API version 7.45)689vm-test-run-horae-e2e-oidc> server # [ 114.607209] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...690vm-test-run-horae-e2e-oidc> server # [ 114.887855] systemd[1]: Starting Remount Root and Kernel File Systems...691vm-test-run-horae-e2e-oidc> server # [ 114.923900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki692vm-test-run-horae-e2e-oidc> server # [ 115.467219] systemd[1]: Starting Coldplug All udev Devices...693vm-test-run-horae-e2e-oidc> server # [ 116.164662] systemd-journald[315]: Collecting audit messages is enabled.694vm-test-run-horae-e2e-oidc> server # [ 116.354233] systemd[1]: Listening on Journal Log Access Socket.695vm-test-run-horae-e2e-oidc> server # [ 116.440487] systemd[1]: Mounted Huge Pages File System.696vm-test-run-horae-e2e-oidc> server # [ 116.500618] systemd[1]: Mounted POSIX Message Queue File System.697vm-test-run-horae-e2e-oidc> server # [ 116.567386] systemd[1]: Mounted Kernel Debug File System.698vm-test-run-horae-e2e-oidc> server # [ 116.626934] systemd[1]: Mounted Kernel Trace File System.699vm-test-run-horae-e2e-oidc> server # [ 116.738676] systemd[1]: Finished Create List of Static Device Nodes.700vm-test-run-horae-e2e-oidc> server # [ 116.828031] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.701vm-test-run-horae-e2e-oidc> server # [ 116.918515] systemd[1]: Finished Load Kernel Module 9pnet_virtio.702vm-test-run-horae-e2e-oidc> server # [ 116.972635] systemd[1]: Mounted Kernel Configuration File System.703vm-test-run-horae-e2e-oidc> server # [ 117.091502] systemd[1]: modprobe@fuse.service: Deactivated successfully.704vm-test-run-horae-e2e-oidc> server # [ 117.170598] EXT4-fs (vda): re-mounted 672a12cf-5c1d-4c5e-9e0f-89bd54a7d837.705vm-test-run-horae-e2e-oidc> server # [ 117.183694] systemd[1]: Finished Load Kernel Module fuse.706vm-test-run-horae-e2e-oidc> server # [ 117.118262] systemd[1]: Queued start job for default target Multi-User System.707vm-test-run-horae-e2e-oidc> server # [ 117.206833] systemd[1]: systemd-journald.service: Deactivated successfully.708vm-test-run-horae-e2e-oidc> server # [ 117.301386] systemd[1]: systemd-journald.service: Consumed 2.505s CPU time over 1min 39.263s wall clock time, 4M memory peak.709vm-test-run-horae-e2e-oidc> server # [ 117.456235] systemd[1]: Started Journal Service.710vm-test-run-horae-e2e-oidc> server # [ 117.378596] systemd-modules-load[316]: Module 'atkbd' is built in711vm-test-run-horae-e2e-oidc> server # [ 117.407381] systemd-modules-load[316]: Module 'loop' is built in712vm-test-run-horae-e2e-oidc> server # [ 117.511027] systemd[1]: Finished Load Kernel Modules.713vm-test-run-horae-e2e-oidc> server # [ 117.595171] systemd[1]: Finished Remount Root and Kernel File Systems.714vm-test-run-horae-e2e-oidc> server # [ 118.151210] systemd[1]: Listening on Disk Image Download Service Socket.715vm-test-run-horae-e2e-oidc> server # [ 118.315457] systemd[1]: Mounting FUSE Control File System...716vm-test-run-horae-e2e-oidc> server # [ 118.585947] systemd[1]: Starting Firewall...717vm-test-run-horae-e2e-oidc> server # [ 118.951350] systemd[1]: Starting Flush Journal to Persistent Storage...718vm-test-run-horae-e2e-oidc> server # [ 118.985269] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore719vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...720vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.721vm-test-run-horae-e2e-oidc> server # [ 119.688961] systemd-oomd[317]: No swap; memory pressure usage will be degraded722vm-test-run-horae-e2e-oidc> server # [ 119.743215] systemd[1]: Starting Load/Save OS Random Seed...723vm-test-run-horae-e2e-oidc> server # [ 120.449385] systemd[1]: Starting Apply Kernel Variables...724vm-test-run-horae-e2e-oidc> server # [ 121.199839] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...725vm-test-run-horae-e2e-oidc> server # [ 121.230550] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki726vm-test-run-horae-e2e-oidc> server # [ 121.521370] systemd-journald[315]: Received client request to flush runtime journal.727vm-test-run-horae-e2e-oidc> server # [ 123.694544] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.728vm-test-run-horae-e2e-oidc> server # [ 123.757020] systemd[1]: Mounted FUSE Control File System.729vm-test-run-horae-e2e-oidc> server # [ 123.825094] systemd[1]: Finished Load/Save OS Random Seed.730vm-test-run-horae-e2e-oidc> server # [ 123.883496] systemd[1]: Reached target First Boot Complete.731vm-test-run-horae-e2e-oidc> server # [ 123.918848] systemd[1]: Finished Flush Journal to Persistent Storage.732vm-test-run-horae-e2e-oidc> server # [ 124.955453] systemd[1]: Finished Apply Kernel Variables.733vm-test-run-horae-e2e-oidc> server # [ 126.045494] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.734vm-test-run-horae-e2e-oidc> server # [ 126.250587] systemd[1]: Starting Create Static Device Nodes in /dev...735vm-test-run-horae-e2e-oidc> server # [ 128.293965] systemd[1]: Finished Create Static Device Nodes in /dev.736vm-test-run-horae-e2e-oidc> server # [ 128.365097] systemd[1]: Reached target Preparation for Local File Systems.737vm-test-run-horae-e2e-oidc> server # [ 128.501935] systemd[1]: Mounting /run/wrappers...738vm-test-run-horae-e2e-oidc> server # [ 128.796729] systemd[1]: Starting Rule-based Manager for Device Events and Files...739vm-test-run-horae-e2e-oidc> server # [ 130.311569] systemd[1]: Mounted /run/wrappers.740vm-test-run-horae-e2e-oidc> server # [ 130.382276] systemd[1]: Reached target Local File Systems.741vm-test-run-horae-e2e-oidc> server # [ 130.514256] systemd[1]: Listening on Boot Loader Control Service Socket.742vm-test-run-horae-e2e-oidc> server # [ 130.697367] systemd[1]: Starting register-nix-paths.service...743vm-test-run-horae-e2e-oidc> server # [ 131.077384] systemd[1]: Starting Create SUID/SGID Wrappers...744vm-test-run-horae-e2e-oidc> server # [ 131.149126] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.745vm-test-run-horae-e2e-oidc> server # [ 131.807207] systemd[1]: Starting Save Transient machine-id to Disk...746vm-test-run-horae-e2e-oidc> server # [ 132.422690] systemd[1]: Starting Create System Files and Directories...747vm-test-run-horae-e2e-oidc> server # [ 135.334705] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.748vm-test-run-horae-e2e-oidc> server # [ 135.431744] systemd[1]: Finished Save Transient machine-id to Disk.749vm-test-run-horae-e2e-oidc> server # [ 140.279016] systemd[1]: Finished Create System Files and Directories.750vm-test-run-horae-e2e-oidc> server # [ 140.791568] systemd[1]: Starting Rebuild Journal Catalog...751vm-test-run-horae-e2e-oidc> server # [ 141.441968] systemd[1]: Starting Record System Boot/Shutdown in UTMP...752vm-test-run-horae-e2e-oidc> server # [ 145.099527] systemd[1]: Finished Record System Boot/Shutdown in UTMP.753vm-test-run-horae-e2e-oidc> server # [ 148.399659] systemd[1]: Finished Rebuild Journal Catalog.754vm-test-run-horae-e2e-oidc> server # [ 148.697866] systemd[1]: Starting Update is Completed...755vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...756vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.757vm-test-run-horae-e2e-oidc> server # [ 151.136060] systemd[1]: Finished Update is Completed.758vm-test-run-horae-e2e-oidc> server # [ 156.766424] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.759vm-test-run-horae-e2e-oidc> server # [ 156.803555] systemd[1]: Finished Create SUID/SGID Wrappers.760vm-test-run-horae-e2e-oidc> server # [ 156.822688] systemd[1]: suid-sgid-wrappers.service: Consumed 3.117s CPU time over 25.666s wall clock time, 4.8M memory peak.761vm-test-run-horae-e2e-oidc> server # [ 163.561690] systemd[1]: Finished Firewall.762vm-test-run-horae-e2e-oidc> server # [ 166.746774] systemd[1]: Finished register-nix-paths.service.763vm-test-run-horae-e2e-oidc> server # [ 167.013754] systemd-udevd[371]: Using default interface naming scheme 'v260'.764vm-test-run-horae-e2e-oidc> server # [ 169.553359] systemd[1]: Started Rule-based Manager for Device Events and Files.765vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...766vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.767vm-test-run-horae-e2e-oidc> server # [ 188.535249] systemd[1]: Finished Coldplug All udev Devices.768vm-test-run-horae-e2e-oidc> server # [ 188.551357] systemd[1]: Reached target System Initialization.769vm-test-run-horae-e2e-oidc> server # [ 188.582930] systemd[1]: Started Discard unused filesystem blocks once a week.770vm-test-run-horae-e2e-oidc> server # [ 188.594174] systemd[1]: Started postgresqlBackup-horae.timer.771vm-test-run-horae-e2e-oidc> server # [ 188.610375] systemd[1]: Started Daily Cleanup of Temporary Directories.772vm-test-run-horae-e2e-oidc> server # [ 188.622544] systemd[1]: Reached target Timer Units.773vm-test-run-horae-e2e-oidc> server # [ 188.645670] systemd[1]: Listening on D-Bus System Message Bus Socket.774vm-test-run-horae-e2e-oidc> server # [ 188.650435] systemd[1]: Listening on Nix Daemon Socket.775vm-test-run-horae-e2e-oidc> server # [ 188.662545] systemd[1]: Listening on Hostname Service Socket.776vm-test-run-horae-e2e-oidc> server # [ 188.674542] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.777vm-test-run-horae-e2e-oidc> server # [ 188.687429] systemd[1]: Reached target Socket Units.778vm-test-run-horae-e2e-oidc> server # [ 188.698502] systemd[1]: Reached target Basic System.779vm-test-run-horae-e2e-oidc> server # [ 188.759039] systemd[1]: Starting Import lastlog data into lastlog2 database...780vm-test-run-horae-e2e-oidc> server # [ 188.958542] systemd[1]: Starting Name Service Cache Daemon (nsncd)...781vm-test-run-horae-e2e-oidc> server # [ 189.191047] systemd[1]: Starting Post-Boot Actions...782vm-test-run-horae-e2e-oidc> server # [ 189.595682] systemd[1]: Started Reset console on configuration changes.783vm-test-run-horae-e2e-oidc> server # [ 190.085323] systemd[1]: Starting resolvconf update...784vm-test-run-horae-e2e-oidc> server # [ 191.875222] nsncd[562]: Oct 06 19:54:33.650 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"785vm-test-run-horae-e2e-oidc> server # [ 191.956710] systemd[1]: Starting D-Bus System Message Bus...786vm-test-run-horae-e2e-oidc> server # [ 191.994748] systemd[1]: Started Name Service Cache Daemon (nsncd).787vm-test-run-horae-e2e-oidc> server # [ 192.211186] systemd[1]: Finished Post-Boot Actions.788vm-test-run-horae-e2e-oidc> server # [ 192.741150] systemd[1]: Finished Import lastlog data into lastlog2 database.789vm-test-run-horae-e2e-oidc> server # [ 192.860037] systemd[1]: Reached target Host and Network Name Lookups.790vm-test-run-horae-e2e-oidc> server # [ 192.923035] systemd[1]: Reached target User and Group Name Lookups.791vm-test-run-horae-e2e-oidc> server # [ 193.347155] systemd[1]: Starting User Login Management...792vm-test-run-horae-e2e-oidc> server # [ 195.851789] dbus-broker-launch[570]: Looking up NSS user entry for 'systemd-timesync'...793vm-test-run-horae-e2e-oidc> server # [ 196.268841] dbus-broker-launch[570]: NSS returned no entry for 'systemd-timesync'794vm-test-run-horae-e2e-oidc> server # [ 196.288977] dbus-broker-launch[570]: Invalid user-name in /nix/store/1q82yp8x968fv5ysy6kdaxgsnslsd5z3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"795vm-test-run-horae-e2e-oidc> server # [ 196.965184] systemd[1]: Started D-Bus System Message Bus.796vm-test-run-horae-e2e-oidc> server # [ 198.355742] systemd-logind[584]: New seat seat0.797vm-test-run-horae-e2e-oidc> server # [ 198.423280] dbus-broker-launch[570]: Ready798vm-test-run-horae-e2e-oidc> server # [ 198.559237] systemd[1]: Started User Login Management.799vm-test-run-horae-e2e-oidc> server # [ 198.905013] systemd[1]: Stopped target Host and Network Name Lookups.800vm-test-run-horae-e2e-oidc> server # [ 198.923151] systemd[1]: Stopping Host and Network Name Lookups...801vm-test-run-horae-e2e-oidc> server # [ 198.941764] systemd[1]: Stopped target User and Group Name Lookups.802vm-test-run-horae-e2e-oidc> server # [ 198.958822] systemd[1]: Stopping User and Group Name Lookups...803vm-test-run-horae-e2e-oidc> server # [ 199.089991] systemd[1]: Starting linger-users.service...804vm-test-run-horae-e2e-oidc> server # [ 199.205646] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...805vm-test-run-horae-e2e-oidc> server # [ 199.405879] systemd[1]: nscd.service: Deactivated successfully.806vm-test-run-horae-e2e-oidc> server # [ 199.490166] systemd[1]: Stopped Name Service Cache Daemon (nsncd).807vm-test-run-horae-e2e-oidc> server # [ 200.335834] systemd[1]: Starting Name Service Cache Daemon (nsncd)...808vm-test-run-horae-e2e-oidc> server # [ 201.030822] systemd[1]: linger-users.service: Deactivated successfully.809vm-test-run-horae-e2e-oidc> server # [ 201.094448] systemd[1]: Finished linger-users.service.810vm-test-run-horae-e2e-oidc> server # [ 201.299333] systemd[1]: Finished resolvconf update.811vm-test-run-horae-e2e-oidc> server # [ 201.398262] systemd[1]: Reached target Preparation for Network.812vm-test-run-horae-e2e-oidc> server # [ 201.611013] systemd[1]: Starting DHCP Client...813vm-test-run-horae-e2e-oidc> server # [ 201.979935] systemd[1]: Starting Extra networking commands....814vm-test-run-horae-e2e-oidc> server # [ 202.113666] nsncd[635]: Oct 06 19:54:43.910 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"815vm-test-run-horae-e2e-oidc> server # [ 202.255310] systemd[1]: Started Name Service Cache Daemon (nsncd).816vm-test-run-horae-e2e-oidc> server # [ 202.361495] systemd[1]: Reached target Host and Network Name Lookups.817vm-test-run-horae-e2e-oidc> server # [ 202.397314] systemd[1]: Reached target User and Group Name Lookups.818vm-test-run-horae-e2e-oidc> server # [ 207.483493] systemd-logind[584]: Watching system buttons on /dev/input/event1 (gpio-keys)819vm-test-run-horae-e2e-oidc> server # [ 208.066655] systemd[1]: Finished Extra networking commands..820vm-test-run-horae-e2e-oidc> server # [ 208.141126] systemd[1]: Reached target Network.821vm-test-run-horae-e2e-oidc> server # [ 208.339306] dhcpcd[659]: dhcpcd-10.3.2 starting822vm-test-run-horae-e2e-oidc> server # [ 208.476937] systemd[1]: Starting dex identity provider...823vm-test-run-horae-e2e-oidc> server # [ 208.679589] dhcpcd[710]: dev: loaded udev824vm-test-run-horae-e2e-oidc> server # [ 208.833384] dhcpcd[710]: no valid interfaces found825vm-test-run-horae-e2e-oidc> server # [ 208.838341] dhcpcd[710]: no valid interfaces found826vm-test-run-horae-e2e-oidc> server # [ 208.870011] systemd[1]: Starting PostgreSQL Server...827vm-test-run-horae-e2e-oidc> server # [ 209.018060] systemd[1]: Starting Permit User Sessions...828vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...829vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.830vm-test-run-horae-e2e-oidc> server # [ 210.545754] systemd[1]: Finished Permit User Sessions.831vm-test-run-horae-e2e-oidc> server # [ 210.838880] systemd[1]: Started Getty on tty1.832vm-test-run-horae-e2e-oidc> server # [ 210.955337] systemd[1]: Reached target Login Prompts.833vm-test-run-horae-e2e-oidc> server # [ 216.222270] systemd[1]: Started dex identity provider.834vm-test-run-horae-e2e-oidc> server # [ 216.367137] postgresql-pre-start[722]: The files belonging to this database system will be owned by user "postgres".835vm-test-run-horae-e2e-oidc> server # [ 216.391979] postgresql-pre-start[722]: This user must also own the server process.836vm-test-run-horae-e2e-oidc> server # [ 216.545135] postgresql-pre-start[722]: The database cluster will be initialized with locale "en_US.UTF-8".837vm-test-run-horae-e2e-oidc> server # [ 216.547101] postgresql-pre-start[722]: The default database encoding has accordingly been set to "UTF8".838vm-test-run-horae-e2e-oidc> server # [ 216.563690] postgresql-pre-start[722]: The default text search configuration will be set to "english".839vm-test-run-horae-e2e-oidc> server # [ 216.567403] postgresql-pre-start[722]: Data page checksums are disabled.840vm-test-run-horae-e2e-oidc> server # [ 216.594632] postgresql-pre-start[722]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok841vm-test-run-horae-e2e-oidc> server # [ 216.704941] postgresql-pre-start[722]: creating subdirectories ... ok842vm-test-run-horae-e2e-oidc> server # [ 216.749533] postgresql-pre-start[722]: selecting dynamic shared memory implementation ... posix843vm-test-run-horae-e2e-oidc> server # [ 220.495606] postgresql-pre-start[722]: selecting default "max_connections" ... 100844vm-test-run-horae-e2e-oidc> server # [ 223.676817] postgresql-pre-start[722]: selecting default "shared_buffers" ... 128MB845vm-test-run-horae-e2e-oidc> server # [ 232.178003] mousedev: PS/2 mouse device common for all mice846vm-test-run-horae-e2e-oidc> server # [ 233.799595] dex[734]: time=2026-10-06T19:55:15.558Z level=INFO msg="Version info" dex_version=v2.45.1 go.version=go1.26.4 go.os=linux go.arch=arm64847vm-test-run-horae-e2e-oidc> server # [ 233.826874] dex[734]: time=2026-10-06T19:55:15.617Z level=INFO msg="config issuer" issuer=http://127.0.0.1:5556/dex848vm-test-run-horae-e2e-oidc> server # [ 233.849193] dex[734]: time=2026-10-06T19:55:15.661Z level=INFO msg="config storage" storage_type=memory849vm-test-run-horae-e2e-oidc> server # [ 233.866954] dex[734]: time=2026-10-06T19:55:15.681Z level=INFO msg="config static client" client_name=Horae850vm-test-run-horae-e2e-oidc> server # [ 233.884920] dex[734]: time=2026-10-06T19:55:15.682Z level=INFO msg="config connector" connector_id=mock851vm-test-run-horae-e2e-oidc> server # [ 233.890962] dex[734]: time=2026-10-06T19:55:15.684Z level=INFO msg="config skipping approval screen"852vm-test-run-horae-e2e-oidc> server # [ 233.910023] dex[734]: time=2026-10-06T19:55:15.684Z level=INFO msg="continue on connector failure feature flag enabled"853vm-test-run-horae-e2e-oidc> server # [ 233.925661] dex[734]: time=2026-10-06T19:55:15.723Z level=INFO msg="signer configured" type=local keys_rotation_period=6h854vm-test-run-horae-e2e-oidc> server # [ 233.939333] dex[734]: time=2026-10-06T19:55:15.778Z level=INFO msg="config refresh tokens rotation" enabled=true855vm-test-run-horae-e2e-oidc> server # [ 234.113603] dex[734]: time=2026-10-06T19:55:15.951Z level=ERROR msg="failed to get signing algorithm" err="no signing key found"856vm-test-run-horae-e2e-oidc> server # [ 234.281012] dex[734]: time=2026-10-06T19:55:16.119Z level=INFO msg="keys expired, rotating"857vm-test-run-horae-e2e-oidc> server # [ 238.929476] dhcpcd[710]: timed out858vm-test-run-horae-e2e-oidc> server # [ 238.951038] dhcpcd[710]: timed out859vm-test-run-horae-e2e-oidc> server # [ 239.011352] systemd[1]: Started DHCP Client.860vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet...861vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'.862vm-test-run-horae-e2e-oidc> server # [ 244.720055] dex[734]: time=2026-10-06T19:55:26.519Z level=INFO msg="keys rotated" next_rotation=2026-10-07T01:55:26.509Z863vm-test-run-horae-e2e-oidc> server # [ 244.791545] dex[734]: time=2026-10-06T19:55:26.635Z level=INFO msg="listening on" server=http address=127.0.0.1:5556