this derivation will be built: /nix/store/a261j6q02zv3naxx7xg8p9dy94a2s3yn-vm-test-run-horae-e2e-oidc.drv building '/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' building '/nix/store/a261j6q02zv3naxx7xg8p9dy94a2s3yn-vm-test-run-horae-e2e-oidc.drv' on farm.numtide.com:50051 vm-test-run-horae-e2e-oidc> elastic-arm-ea992526: building a261j6q02zv3naxx7xg8p9dy94a2s3yn-vm-test-run-horae-e2e-oidc.drv vm-test-run-horae-e2e-oidc> Machine state will be reset. To keep it, pass --keep-machine-state vm-test-run-horae-e2e-oidc> start all VLans vm-test-run-horae-e2e-oidc> (finished: start all VLans, in 0.01 seconds) vm-test-run-horae-e2e-oidc> Test will time out and terminate in 3600 seconds vm-test-run-horae-e2e-oidc> run the VM test script vm-test-run-horae-e2e-oidc> additionally exposed symbols: vm-test-run-horae-e2e-oidc> server, vm-test-run-horae-e2e-oidc> vlan1, vm-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_ssh vm-test-run-horae-e2e-oidc> server: starting vm vm-test-run-horae-e2e-oidc> server: QEMU running (pid 12) vm-test-run-horae-e2e-oidc> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-horae-e2e-oidc> server # Formatting '/build/vm-state-server/tmp.KcxyYJVkrR', fmt=raw size=1073741824 vm-test-run-horae-e2e-oidc> server # mke2fs 1.47.4 (6-Mar-2025) vm-test-run-horae-e2e-oidc> server: waiting for unit postgresql.service vm-test-run-horae-e2e-oidc> server # Discarding device blocks: 0/262144 done vm-test-run-horae-e2e-oidc> server: waiting for the VM to finish booting vm-test-run-horae-e2e-oidc> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-horae-e2e-oidc> server # Filesystem UUID: 672a12cf-5c1d-4c5e-9e0f-89bd54a7d837 vm-test-run-horae-e2e-oidc> server # Superblock backups stored on blocks: vm-test-run-horae-e2e-oidc> server # 32768, 98304, 163840, 229376 vm-test-run-horae-e2e-oidc> server # vm-test-run-horae-e2e-oidc> server # Allocating group tables: 0/8 done vm-test-run-horae-e2e-oidc> server # Writing inode tables: 0/8 done vm-test-run-horae-e2e-oidc> server # Creating journal (8192 blocks): done vm-test-run-horae-e2e-oidc> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-horae-e2e-oidc> server # vm-test-run-horae-e2e-oidc> server # Virtualisation disk image created. vm-test-run-horae-e2e-oidc> server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-horae-e2e-oidc> server # qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-horae-e2e-oidc> server # qemu-system-aarch64: falling back to tcg vm-test-run-horae-e2e-oidc> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-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 2026 vm-test-run-horae-e2e-oidc> server # [ 0.000000] KASLR enabled vm-test-run-horae-e2e-oidc> server # [ 0.000000] random: crng init done vm-test-run-horae-e2e-oidc> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-horae-e2e-oidc> server # [ 0.000000] efi: UEFI not found. vm-test-run-horae-e2e-oidc> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-horae-e2e-oidc> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] vm-test-run-horae-e2e-oidc> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf] vm-test-run-horae-e2e-oidc> server # [ 0.000000] Zone ranges: vm-test-run-horae-e2e-oidc> server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] vm-test-run-horae-e2e-oidc> server # [ 0.000000] DMA32 empty vm-test-run-horae-e2e-oidc> server # [ 0.000000] Normal empty vm-test-run-horae-e2e-oidc> server # [ 0.000000] Device empty vm-test-run-horae-e2e-oidc> server # [ 0.000000] Movable zone start for each node vm-test-run-horae-e2e-oidc> server # [ 0.000000] Early memory node ranges vm-test-run-horae-e2e-oidc> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] vm-test-run-horae-e2e-oidc> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] vm-test-run-horae-e2e-oidc> server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-horae-e2e-oidc> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-horae-e2e-oidc> server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296 vm-test-run-horae-e2e-oidc> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: GICv3 CPU interface vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE) vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-horae-e2e-oidc> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-horae-e2e-oidc> server # [ 0.000000] alternatives: applying boot alternatives vm-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=tty0 vm-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. vm-test-run-horae-e2e-oidc> server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes vm-test-run-horae-e2e-oidc> server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB vm-test-run-horae-e2e-oidc> server # [ 0.000000] software IO TLB: area num 1. vm-test-run-horae-e2e-oidc> server # [ 0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB) vm-test-run-horae-e2e-oidc> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-horae-e2e-oidc> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 vm-test-run-horae-e2e-oidc> server # [ 0.000000] Policy zone: DMA vm-test-run-horae-e2e-oidc> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-horae-e2e-oidc> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 vm-test-run-horae-e2e-oidc> server # [ 0.000000] allocated 2097152 bytes of page_ext vm-test-run-horae-e2e-oidc> server # [ 0.000000] ftrace: allocating 74713 entries in 292 pages vm-test-run-horae-e2e-oidc> server # [ 0.000000] ftrace: allocated 292 pages with 3 groups vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. vm-test-run-horae-e2e-oidc> server # [ 0.000000] Trampoline variant of Tasks RCU enabled. vm-test-run-horae-e2e-oidc> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-horae-e2e-oidc> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 vm-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. vm-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. vm-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. vm-test-run-horae-e2e-oidc> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-horae-e2e-oidc> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-horae-e2e-oidc> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-horae-e2e-oidc> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-horae-e2e-oidc> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 vm-test-run-horae-e2e-oidc> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 vm-test-run-horae-e2e-oidc> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-horae-e2e-oidc> server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt). vm-test-run-horae-e2e-oidc> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-horae-e2e-oidc> server # [ 0.000083] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-horae-e2e-oidc> server # [ 0.005914] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-horae-e2e-oidc> server # [ 0.032874] Console: colour dummy device 80x25 vm-test-run-horae-e2e-oidc> server # [ 0.036655] printk: legacy console [tty0] enabled vm-test-run-horae-e2e-oidc> server # [ 0.051177] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-horae-e2e-oidc> server # [ 0.052155] pid_max: default: 32768 minimum: 301 vm-test-run-horae-e2e-oidc> server # [ 0.060254] LSM: initializing lsm=capability,landlock,yama,bpf,ima vm-test-run-horae-e2e-oidc> server # [ 0.063136] landlock: Up and running. vm-test-run-horae-e2e-oidc> server # [ 0.063297] Yama: becoming mindful. vm-test-run-horae-e2e-oidc> server # [ 0.065910] LSM support for eBPF active vm-test-run-horae-e2e-oidc> server # [ 0.072011] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 0.072299] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 0.144275] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-horae-e2e-oidc> server # [ 0.175932] rcu: Hierarchical SRCU implementation. vm-test-run-horae-e2e-oidc> server # [ 0.176143] rcu: Max phase no-delay instances is 1000. vm-test-run-horae-e2e-oidc> server # [ 0.195029] fsl-mc MSI: its@8080000 domain created vm-test-run-horae-e2e-oidc> server # [ 0.199415] EFI services will not be available. vm-test-run-horae-e2e-oidc> server # [ 0.202803] smp: Bringing up secondary CPUs ... vm-test-run-horae-e2e-oidc> server # [ 0.204317] smp: Brought up 1 node, 1 CPU vm-test-run-horae-e2e-oidc> server # [ 0.204454] SMP: Total of 1 processors activated. vm-test-run-horae-e2e-oidc> server # [ 0.204558] CPU: All CPU(s) started at EL1 vm-test-run-horae-e2e-oidc> server # [ 0.205209] CPU features: detected: Branch Target Identification vm-test-run-horae-e2e-oidc> server # [ 0.205344] CPU features: detected: 32-bit EL0 Support vm-test-run-horae-e2e-oidc> server # [ 0.205422] CPU features: detected: 32-bit EL1 Support vm-test-run-horae-e2e-oidc> server # [ 0.205500] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-horae-e2e-oidc> server # [ 0.205584] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-horae-e2e-oidc> server # [ 0.205679] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-horae-e2e-oidc> server # [ 0.205811] CPU features: detected: Common not Private translations vm-test-run-horae-e2e-oidc> server # [ 0.207920] CPU features: detected: CRC32 instructions vm-test-run-horae-e2e-oidc> server # [ 0.208017] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-horae-e2e-oidc> server # [ 0.208095] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-horae-e2e-oidc> server # [ 0.208163] CPU features: detected: Data independent timing control (DIT) vm-test-run-horae-e2e-oidc> server # [ 0.208230] CPU features: detected: E0PD vm-test-run-horae-e2e-oidc> server # [ 0.208283] CPU features: detected: Enhanced Counter Virtualization vm-test-run-horae-e2e-oidc> server # [ 0.208346] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-horae-e2e-oidc> server # [ 0.208413] CPU features: detected: Enhanced Privileged Access Never vm-test-run-horae-e2e-oidc> server # [ 0.208493] CPU features: detected: Enhanced Virtualization Traps vm-test-run-horae-e2e-oidc> server # [ 0.208574] CPU features: detected: Fine Grained Traps vm-test-run-horae-e2e-oidc> server # [ 0.208660] CPU features: detected: Guarded Control Stack (GCS) vm-test-run-horae-e2e-oidc> server # [ 0.208804] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-horae-e2e-oidc> server # [ 0.209014] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-horae-e2e-oidc> server # [ 0.209165] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-horae-e2e-oidc> server # [ 0.209243] CPU features: detected: LSE atomic instructions vm-test-run-horae-e2e-oidc> server # [ 0.209304] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-horae-e2e-oidc> server # [ 0.209371] CPU features: detected: Privileged Access Never vm-test-run-horae-e2e-oidc> server # [ 0.209520] CPU features: detected: PMUv3 vm-test-run-horae-e2e-oidc> server # [ 0.209586] CPU features: detected: SCTLR2 vm-test-run-horae-e2e-oidc> server # [ 0.209641] CPU features: detected: RAS Extension Support vm-test-run-horae-e2e-oidc> server # [ 0.209773] CPU features: detected: RASv1p1 Extension Support vm-test-run-horae-e2e-oidc> server # [ 0.209836] CPU features: detected: Random Number Generator vm-test-run-horae-e2e-oidc> server # [ 0.209896] CPU features: detected: Speculation barrier (SB) vm-test-run-horae-e2e-oidc> server # [ 0.209957] CPU features: detected: Stage-2 Force Write-Back vm-test-run-horae-e2e-oidc> server # [ 0.210018] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-horae-e2e-oidc> server # [ 0.210151] CPU features: detected: TLB range maintenance instructions vm-test-run-horae-e2e-oidc> server # [ 0.210820] CPU features: detected: WFx with timeout vm-test-run-horae-e2e-oidc> server # [ 0.211016] CPU features: detected: Scalable Matrix Extension vm-test-run-horae-e2e-oidc> server # [ 0.211082] CPU features: detected: FA64 vm-test-run-horae-e2e-oidc> server # [ 0.211135] CPU features: detected: SME2 vm-test-run-horae-e2e-oidc> server # [ 0.211188] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-horae-e2e-oidc> server # [ 0.211255] CPU features: detected: Scalable Vector Extension vm-test-run-horae-e2e-oidc> server # [ 0.228752] alternatives: applying system-wide alternatives vm-test-run-horae-e2e-oidc> server # [ 0.255660] CPU features: detected: Hardware dirty bit management on CPU0 vm-test-run-horae-e2e-oidc> server # [ 0.257238] SVE: maximum available vector length 256 bytes per vector vm-test-run-horae-e2e-oidc> server # [ 0.257452] SVE: default vector length 64 bytes per vector vm-test-run-horae-e2e-oidc> server # [ 0.260123] SME: minimum available vector length 16 bytes per vector vm-test-run-horae-e2e-oidc> server # [ 0.260259] SME: maximum available vector length 256 bytes per vector vm-test-run-horae-e2e-oidc> server # [ 0.260341] SME: default vector length 32 bytes per vector vm-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) vm-test-run-horae-e2e-oidc> server # [ 0.339337] devtmpfs: initialized vm-test-run-horae-e2e-oidc> server # [ 0.400575] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-horae-e2e-oidc> server # [ 0.401955] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 0.402562] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). vm-test-run-horae-e2e-oidc> server # [ 0.410191] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-horae-e2e-oidc> server # [ 0.410386] 0 pages in range for non-PLT usage vm-test-run-horae-e2e-oidc> server # [ 0.410496] 508320 pages in range for PLT usage vm-test-run-horae-e2e-oidc> server # [ 0.416866] pinctrl core: initialized pinctrl subsystem vm-test-run-horae-e2e-oidc> server # [ 0.444890] DMI not present or invalid. vm-test-run-horae-e2e-oidc> server # [ 0.478789] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-horae-e2e-oidc> server # [ 0.504190] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations vm-test-run-horae-e2e-oidc> server # [ 0.507632] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-horae-e2e-oidc> server # [ 0.511062] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-horae-e2e-oidc> server # [ 0.511809] audit: initializing netlink subsys (disabled) vm-test-run-horae-e2e-oidc> server # [ 0.516655] audit: type=2000 audit(0.428:1): state=initialized audit_enabled=0 res=1 vm-test-run-horae-e2e-oidc> server # [ 0.531788] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-horae-e2e-oidc> server # [ 0.531912] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-horae-e2e-oidc> server # [ 0.532034] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-horae-e2e-oidc> server # [ 0.532100] thermal_sys: Registered thermal governor 'user_space' vm-test-run-horae-e2e-oidc> server # [ 0.532170] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-horae-e2e-oidc> server # [ 0.533762] cpuidle: using governor ladder vm-test-run-horae-e2e-oidc> server # [ 0.534224] cpuidle: using governor menu vm-test-run-horae-e2e-oidc> server # [ 0.540815] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-horae-e2e-oidc> server # [ 0.541991] ASID allocator initialised with 65536 entries vm-test-run-horae-e2e-oidc> server # [ 0.564182] Serial: AMBA PL011 UART driver vm-test-run-horae-e2e-oidc> server # [ 0.703141] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-horae-e2e-oidc> server # [ 0.707687] printk: console [ttyAMA0] enabled vm-test-run-horae-e2e-oidc> server # [ 0.861307] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-horae-e2e-oidc> server # [ 0.861609] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-horae-e2e-oidc> server # [ 0.861731] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-horae-e2e-oidc> server # [ 0.861823] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-horae-e2e-oidc> server # [ 0.861914] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-horae-e2e-oidc> server # [ 0.862002] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-horae-e2e-oidc> server # [ 0.862094] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-horae-e2e-oidc> server # [ 0.862182] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-horae-e2e-oidc> server # [ 0.916574] fbcon: Taking over console vm-test-run-horae-e2e-oidc> server # [ 0.917273] ACPI: Interpreter disabled. vm-test-run-horae-e2e-oidc> server # [ 0.953568] iommu: Default domain type: Translated vm-test-run-horae-e2e-oidc> server # [ 0.953826] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-horae-e2e-oidc> server # [ 0.966982] SCSI subsystem initialized vm-test-run-horae-e2e-oidc> server # [ 0.978868] usbcore: registered new interface driver usbfs vm-test-run-horae-e2e-oidc> server # [ 0.979619] usbcore: registered new interface driver hub vm-test-run-horae-e2e-oidc> server # [ 0.980121] usbcore: registered new device driver usb vm-test-run-horae-e2e-oidc> server # [ 0.994917] pps_core: LinuxPPS API ver. 1 registered vm-test-run-horae-e2e-oidc> server # [ 0.995960] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-horae-e2e-oidc> server # [ 0.996393] PTP clock support registered vm-test-run-horae-e2e-oidc> server # [ 0.997324] EDAC MC: Ver: 3.0.0 vm-test-run-horae-e2e-oidc> server # [ 1.009275] scmi_core: SCMI protocol bus registered vm-test-run-horae-e2e-oidc> server # [ 1.026318] FPGA manager framework vm-test-run-horae-e2e-oidc> server # [ 1.073299] vgaarb: loaded vm-test-run-horae-e2e-oidc> server # [ 1.089267] clocksource: Switched to clocksource arch_sys_counter vm-test-run-horae-e2e-oidc> server # [ 1.107371] VFS: Disk quotas dquot_6.6.0 vm-test-run-horae-e2e-oidc> server # [ 1.107997] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-horae-e2e-oidc> server # [ 1.119751] netfs: FS-Cache loaded vm-test-run-horae-e2e-oidc> server # [ 1.130894] pnp: PnP ACPI: disabled vm-test-run-horae-e2e-oidc> server # [ 1.263890] NET: Registered PF_INET protocol family vm-test-run-horae-e2e-oidc> server # [ 1.266928] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.676129] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.676636] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.678173] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.678957] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.679401] TCP: Hash tables configured (established 8192 bind 8192) vm-test-run-horae-e2e-oidc> server # [ 1.683716] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.684555] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.686299] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-horae-e2e-oidc> server # [ 1.690165] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-horae-e2e-oidc> server # [ 1.691094] NET: Registered PF_XDP protocol family vm-test-run-horae-e2e-oidc> server # [ 1.691550] PCI: CLS 0 bytes, default 64 vm-test-run-horae-e2e-oidc> server # [ 1.703244] Trying to unpack rootfs image as initramfs... vm-test-run-horae-e2e-oidc> server # [ 1.713729] kvm [1]: HYP mode not available vm-test-run-horae-e2e-oidc> server # [ 2.371933] Initialise system trusted keyrings vm-test-run-horae-e2e-oidc> server # [ 2.383472] workingset: timestamp_bits=42 max_order=18 bucket_order=0 vm-test-run-horae-e2e-oidc> server # [ 2.405567] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-horae-e2e-oidc> server # [ 2.414459] 9p: Installing v9fs 9p2000 file system support vm-test-run-horae-e2e-oidc> server # [ 2.683265] Key type asymmetric registered vm-test-run-horae-e2e-oidc> server # [ 2.683695] Asymmetric key parser 'x509' registered vm-test-run-horae-e2e-oidc> server # [ 2.684637] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) vm-test-run-horae-e2e-oidc> server # [ 2.695218] io scheduler mq-deadline registered vm-test-run-horae-e2e-oidc> server # [ 2.695574] io scheduler kyber registered vm-test-run-horae-e2e-oidc> server # [ 2.847717] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-horae-e2e-oidc> server # [ 2.872589] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-horae-e2e-oidc> server # [ 2.891245] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-horae-e2e-oidc> server # [ 2.892529] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-horae-e2e-oidc> server # [ 2.898066] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-horae-e2e-oidc> server # [ 2.898483] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-horae-e2e-oidc> server # [ 2.899684] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-horae-e2e-oidc> server # [ 2.900846] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-horae-e2e-oidc> server # [ 2.912330] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-horae-e2e-oidc> server # [ 2.921608] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-horae-e2e-oidc> server # [ 2.922015] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-horae-e2e-oidc> server # [ 2.922207] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-horae-e2e-oidc> server # [ 2.922319] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-horae-e2e-oidc> server # [ 2.924765] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.944512] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.953785] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-horae-e2e-oidc> server # [ 2.954177] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.954372] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.954639] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-horae-e2e-oidc> server # [ 2.956507] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.956807] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-horae-e2e-oidc> server # [ 2.959573] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.959890] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.962671] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.963019] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-horae-e2e-oidc> server # [ 2.963153] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.963271] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.964726] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.969405] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-horae-e2e-oidc> server # [ 2.969660] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.969783] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.971227] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.971525] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-horae-e2e-oidc> server # [ 2.971652] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.971770] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.981841] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.982197] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-horae-e2e-oidc> server # [ 2.982324] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.982460] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.983844] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.984116] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-horae-e2e-oidc> server # [ 2.984232] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.984347] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.984513] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-horae-e2e-oidc> server # [ 2.994594] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.994972] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 2.995108] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 2.996544] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 2.996823] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 3.005638] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 3.007319] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 3.007621] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 3.010695] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 3.011108] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-horae-e2e-oidc> server # [ 3.011255] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 3.011370] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 3.012617] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-horae-e2e-oidc> server # [ 3.012914] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-horae-e2e-oidc> server # [ 3.025504] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-horae-e2e-oidc> server # [ 3.025666] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-horae-e2e-oidc> server # [ 3.033888] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.034426] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.034767] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.035163] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.035402] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.035629] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.035795] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.035954] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.036115] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.036315] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.036502] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.036670] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.049401] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.049815] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.049992] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.050133] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.050263] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.050398] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.050594] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.050736] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.050868] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.051004] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.051151] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.051285] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.051599] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.051779] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.051957] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.052084] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.052203] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.052328] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.052477] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.052611] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.052738] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.052864] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-horae-e2e-oidc> server # [ 3.070230] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-horae-e2e-oidc> server # [ 3.070459] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-horae-e2e-oidc> server # [ 3.070585] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-horae-e2e-oidc> server # [ 3.082027] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-horae-e2e-oidc> server # [ 3.416777] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.437227] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.453941] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.458900] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.470995] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.475473] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.484059] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.495939] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-horae-e2e-oidc> server # [ 3.510589] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-horae-e2e-oidc> server # [ 3.526903] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.532197] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-horae-e2e-oidc> server # [ 3.639053] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-horae-e2e-oidc> server # [ 3.688508] msm_serial: driver initialized vm-test-run-horae-e2e-oidc> server # [ 3.700268] SuperH (H)SCI(F) driver initialized vm-test-run-horae-e2e-oidc> server # [ 3.702358] STM32 USART driver initialized vm-test-run-horae-e2e-oidc> server # [ 3.935861] loop: module loaded vm-test-run-horae-e2e-oidc> server # [ 3.945449] virtio_blk virtio5: 1/0/0 default/read/poll queues vm-test-run-horae-e2e-oidc> server # [ 3.952313] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-horae-e2e-oidc> server # [ 4.015219] megasas: 07.734.00.00-rc1 vm-test-run-horae-e2e-oidc> server # [ 4.048176] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-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 0x000000 vm-test-run-horae-e2e-oidc> server # [ 4.062699] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-horae-e2e-oidc> server # [ 4.073411] Using buffer write method vm-test-run-horae-e2e-oidc> server # [ 4.074895] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-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 0x000000 vm-test-run-horae-e2e-oidc> server # [ 4.078147] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-horae-e2e-oidc> server # [ 4.079773] Using buffer write method vm-test-run-horae-e2e-oidc> server # [ 4.080147] Concatenating MTD devices: vm-test-run-horae-e2e-oidc> server # [ 4.080258] (0): "0.flash" vm-test-run-horae-e2e-oidc> server # [ 4.080346] (1): "0.flash" vm-test-run-horae-e2e-oidc> server # [ 4.080425] into device "0.flash" vm-test-run-horae-e2e-oidc> server # [ 5.366638] Freeing initrd memory: 25516K vm-test-run-horae-e2e-oidc> server # [ 5.613742] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-horae-e2e-oidc> server # [ 5.648010] thunder_xcv, ver 1.0 vm-test-run-horae-e2e-oidc> server # [ 5.648701] thunder_bgx, ver 1.0 vm-test-run-horae-e2e-oidc> server # [ 5.650024] nicpf, ver 1.0 vm-test-run-horae-e2e-oidc> server # [ 5.659856] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-horae-e2e-oidc> server # [ 5.660124] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-horae-e2e-oidc> server # [ 5.660602] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-horae-e2e-oidc> server # [ 5.660753] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-horae-e2e-oidc> server # [ 5.662926] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-horae-e2e-oidc> server # [ 5.663132] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-horae-e2e-oidc> server # [ 5.663668] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-horae-e2e-oidc> server # [ 5.663821] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-horae-e2e-oidc> server # [ 5.667919] sky2: driver version 1.30 vm-test-run-horae-e2e-oidc> server # [ 5.694241] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-horae-e2e-oidc> server # [ 5.695501] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-horae-e2e-oidc> server # [ 5.704395] usbcore: registered new interface driver usb-storage vm-test-run-horae-e2e-oidc> server # [ 5.708625] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-horae-e2e-oidc> server # [ 5.710885] usbcore: registered new interface driver usbserial_generic vm-test-run-horae-e2e-oidc> server # [ 5.711548] usbserial: USB Serial support registered for generic vm-test-run-horae-e2e-oidc> server # [ 5.721578] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-horae-e2e-oidc> server # [ 5.732038] hv_vmbus: registering driver hyperv_keyboard vm-test-run-horae-e2e-oidc> server # [ 5.748001] hub 1-0:1.0: USB hub found vm-test-run-horae-e2e-oidc> server # [ 5.760093] hub 1-0:1.0: 6 ports detected vm-test-run-horae-e2e-oidc> server # [ 5.778701] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-horae-e2e-oidc> server # [ 5.779849] rtc-pl031 9010000.pl031: setting system clock to 2026-10-06T19:51:27 UTC (1791316287) vm-test-run-horae-e2e-oidc> server # [ 5.786292] i2c_dev: i2c /dev entries driver vm-test-run-horae-e2e-oidc> server # [ 5.827217] sdhci: Secure Digital Host Controller Interface driver vm-test-run-horae-e2e-oidc> server # [ 5.827517] sdhci: Copyright(c) Pierre Ossman vm-test-run-horae-e2e-oidc> server # [ 5.832979] Synopsys Designware Multimedia Card Interface Driver vm-test-run-horae-e2e-oidc> server # [ 5.839198] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-horae-e2e-oidc> server # [ 5.861667] hid: raw HID events driver (C) Jiri Kosina vm-test-run-horae-e2e-oidc> server # [ 5.867700] usbcore: registered new interface driver usbhid vm-test-run-horae-e2e-oidc> server # [ 5.867948] usbhid: USB HID core driver vm-test-run-horae-e2e-oidc> server # [ 5.888350] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-horae-e2e-oidc> server # [ 5.917578] drop_monitor: Initializing network drop monitor service vm-test-run-horae-e2e-oidc> server # [ 5.920156] NET: Registered PF_INET6 protocol family vm-test-run-horae-e2e-oidc> server # [ 5.943707] Segment Routing with IPv6 vm-test-run-horae-e2e-oidc> server # [ 5.944490] In-situ OAM (IOAM) with IPv6 vm-test-run-horae-e2e-oidc> server # [ 5.946648] NET: Registered PF_PACKET protocol family vm-test-run-horae-e2e-oidc> server # [ 5.949571] 9pnet: Installing 9P2000 support vm-test-run-horae-e2e-oidc> server # [ 5.959360] Key type dns_resolver registered vm-test-run-horae-e2e-oidc> server # [ 6.025598] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-horae-e2e-oidc> server # [ 6.071588] registered taskstats version 1 vm-test-run-horae-e2e-oidc> server # [ 6.078122] Loading compiled-in X.509 certificates vm-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/input0 vm-test-run-horae-e2e-oidc> server # [ 6.265337] Demotion targets for Node 0: null vm-test-run-horae-e2e-oidc> server # [ 6.267803] Key type .fscrypt registered vm-test-run-horae-e2e-oidc> server # [ 6.267991] Key type fscrypt-provisioning registered vm-test-run-horae-e2e-oidc> server # [ 6.275363] ima: No TPM chip found, activating TPM-bypass! vm-test-run-horae-e2e-oidc> server # [ 6.278581] ima: Allocated hash algorithm: sha1 vm-test-run-horae-e2e-oidc> server # [ 6.282890] ima: No architecture policies found vm-test-run-horae-e2e-oidc> server # [ 6.300117] input: gpio-keys as /devices/platform/gpio-keys/input/input1 vm-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/input0 vm-test-run-horae-e2e-oidc> server # [ 6.441937] clk: Disabling unused clocks vm-test-run-horae-e2e-oidc> server # [ 6.442531] PM: genpd: Disabling unused power domains vm-test-run-horae-e2e-oidc> server # [ 6.479603] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-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/input2 vm-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/input0 vm-test-run-horae-e2e-oidc> server # [ 7.088737] Freeing unused kernel memory: 4736K vm-test-run-horae-e2e-oidc> server # [ 7.091398] Run /init as init process vm-test-run-horae-e2e-oidc> server # [ 7.382905] systemd[1]: Successfully made /usr/ read-only. vm-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) vm-test-run-horae-e2e-oidc> server # [ 7.759790] systemd[1]: Detected virtualization qemu. vm-test-run-horae-e2e-oidc> server # [ 7.761831] systemd[1]: Detected architecture arm64. vm-test-run-horae-e2e-oidc> server # [ 7.762842] systemd[1]: Running in initrd. vm-test-run-horae-e2e-oidc> server # [ 7.780107] systemd[1]: Initializing machine ID from random generator. vm-test-run-horae-e2e-oidc> server # [ 7.793622] systemd[1]: Hostname set to . vm-test-run-horae-e2e-oidc> server # [ 9.735079] systemd[1]: Queued start job for default target Initrd Default Target. vm-test-run-horae-e2e-oidc> server # [ 9.829893] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-horae-e2e-oidc> server # [ 9.840956] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-horae-e2e-oidc> server # [ 9.844782] systemd[1]: Expecting device /dev/disk/by-label/nixos... vm-test-run-horae-e2e-oidc> server # [ 9.847561] systemd[1]: Reached target Path Units. vm-test-run-horae-e2e-oidc> server # [ 9.849691] systemd[1]: Reached target Slice Units. vm-test-run-horae-e2e-oidc> server # [ 9.851283] systemd[1]: Reached target Swaps. vm-test-run-horae-e2e-oidc> server # [ 9.852874] systemd[1]: Reached target Timer Units. vm-test-run-horae-e2e-oidc> server # [ 9.862652] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-horae-e2e-oidc> server # [ 9.870615] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-horae-e2e-oidc> server # [ 9.876010] systemd[1]: Listening on Journal Sockets. vm-test-run-horae-e2e-oidc> server # [ 9.880904] systemd[1]: Listening on udev Control Socket. vm-test-run-horae-e2e-oidc> server # [ 9.885367] systemd[1]: Listening on udev Kernel Socket. vm-test-run-horae-e2e-oidc> server # [ 9.887140] systemd[1]: Reached target Socket Units. vm-test-run-horae-e2e-oidc> server # [ 9.948220] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-horae-e2e-oidc> server # [ 10.007264] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-horae-e2e-oidc> server # [ 10.015388] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-horae-e2e-oidc> server # [ 10.115117] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-horae-e2e-oidc> server # [ 10.535539] systemd[1]: Starting Journal Service... vm-test-run-horae-e2e-oidc> server # [ 10.659162] systemd[1]: Starting Load Kernel Modules... vm-test-run-horae-e2e-oidc> server # [ 10.662926] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-horae-e2e-oidc> server # [ 10.763164] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-horae-e2e-oidc> server # [ 11.011337] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-horae-e2e-oidc> server # [ 11.224641] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 11.279068] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-horae-e2e-oidc> server # [ 11.334655] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-horae-e2e-oidc> server # [ 11.483061] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-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. vm-test-run-horae-e2e-oidc> server # [ 11.986805] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev vm-test-run-horae-e2e-oidc> server # [ 12.240815] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0 vm-test-run-horae-e2e-oidc> server # [ 12.267087] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-horae-e2e-oidc> server # [ 12.267319] [drm] features: -context_init vm-test-run-horae-e2e-oidc> server # [ 12.280242] [drm] number of scanouts: 1 vm-test-run-horae-e2e-oidc> server # [ 12.280581] [drm] number of cap sets: 0 vm-test-run-horae-e2e-oidc> server # [ 12.364048] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic vm-test-run-horae-e2e-oidc> server # [ 12.364509] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0 vm-test-run-horae-e2e-oidc> server # [ 12.492152] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-horae-e2e-oidc> server # [ 12.494871] systemd-journald[74]: Collecting audit messages is disabled. vm-test-run-horae-e2e-oidc> server # [ 12.635529] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-horae-e2e-oidc> server # [ 12.773913] Console: switching to colour frame buffer device 160x50 vm-test-run-horae-e2e-oidc> server # [ 12.835963] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-horae-e2e-oidc> server # [ 12.970568] systemd[1]: Finished Load Kernel Modules. vm-test-run-horae-e2e-oidc> server # [ 13.115200] systemd[1]: Starting Apply Kernel Variables... vm-test-run-horae-e2e-oidc> server # [ 13.652261] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-horae-e2e-oidc> server # [ 13.698521] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-horae-e2e-oidc> server # [ 13.699744] systemd[1]: Reached target Local File Systems. vm-test-run-horae-e2e-oidc> server # [ 13.839287] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-horae-e2e-oidc> server # [ 14.092209] systemd[1]: Finished Apply Kernel Variables. vm-test-run-horae-e2e-oidc> server # [ 17.371637] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-horae-e2e-oidc> server # [ 17.599128] systemd[1]: Started Journal Service. vm-test-run-horae-e2e-oidc> server # [ 17.524907] systemd-modules-load[75]: Inserted module 'dm_mod' vm-test-run-horae-e2e-oidc> server # [ 17.579184] systemd-modules-load[75]: Module 'virtio_balloon' is built in vm-test-run-horae-e2e-oidc> server # [ 17.622902] systemd-modules-load[75]: Module 'virtio_console' is built in vm-test-run-horae-e2e-oidc> server # [ 17.658333] systemd-modules-load[75]: Inserted module 'virtio_gpu' vm-test-run-horae-e2e-oidc> server # [ 17.693251] systemd-modules-load[75]: Module 'virtio_rng' is built in vm-test-run-horae-e2e-oidc> server # [ 17.759350] systemd-udevd[81]: Using default interface naming scheme 'v260'. vm-test-run-horae-e2e-oidc> server # [ 17.830230] systemd[1]: Starting Create System Files and Directories... vm-test-run-horae-e2e-oidc> server # [ 18.851362] systemd[1]: Finished Create System Files and Directories. vm-test-run-horae-e2e-oidc> server # [ 19.641170] systemd[1]: Starting Virtual Console Setup... vm-test-run-horae-e2e-oidc> server # [ 21.406180] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-horae-e2e-oidc> server # [ 21.479931] systemd[1]: Finished Virtual Console Setup. vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-horae-e2e-oidc> server # [ 42.031357] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-horae-e2e-oidc> server # [ 42.053162] systemd[1]: Reached target System Initialization. vm-test-run-horae-e2e-oidc> server # [ 42.069334] systemd[1]: Reached target Basic System. vm-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. vm-test-run-horae-e2e-oidc> server # [ 51.855988] (udev-worker)[131]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-horae-e2e-oidc> server # [ 51.882375] (udev-worker)[130]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-horae-e2e-oidc> server # [ 53.975155] systemd[1]: Found device /dev/disk/by-label/nixos. vm-test-run-horae-e2e-oidc> server # [ 53.990594] systemd[1]: Reached target Initrd Root Device. vm-test-run-horae-e2e-oidc> server # [ 54.035324] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... vm-test-run-horae-e2e-oidc> server # [ 54.991545] systemd-fsck[153]: nixos: clean, 12/65536 files, 13019/262144 blocks vm-test-run-horae-e2e-oidc> server # [ 55.115449] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. vm-test-run-horae-e2e-oidc> server # [ 55.205537] systemd[1]: Mounting /sysroot... vm-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. vm-test-run-horae-e2e-oidc> server # [ 55.893229] systemd[1]: Mounted /sysroot. vm-test-run-horae-e2e-oidc> server # [ 55.927664] systemd[1]: Reached target Initrd Root File System. vm-test-run-horae-e2e-oidc> server # [ 56.075156] systemd[1]: Mounting /sysroot/nix/.ro-store... vm-test-run-horae-e2e-oidc> server # [ 56.254587] systemd[1]: Mounting /sysroot/nix/.rw-store... vm-test-run-horae-e2e-oidc> server # [ 56.400011] systemd[1]: Mounting /sysroot/run... vm-test-run-horae-e2e-oidc> server # [ 56.689401] systemd[1]: Mounting /sysroot/tmp/shared... vm-test-run-horae-e2e-oidc> server # [ 56.931236] systemd[1]: Mounting /sysroot/tmp/xchg... vm-test-run-horae-e2e-oidc> server # [ 57.098097] systemd[1]: Starting Mountpoints Configured in the Real Root... vm-test-run-horae-e2e-oidc> server # [ 57.945928] systemd-sysroot-fstab-check[166]: /sysroot should be mounted in the initrd, will request daemon-reload. vm-test-run-horae-e2e-oidc> server # [ 58.055839] systemd[1]: Mounted /sysroot/nix/.ro-store. vm-test-run-horae-e2e-oidc> server # [ 58.062018] systemd[1]: Mounted /sysroot/nix/.rw-store. vm-test-run-horae-e2e-oidc> server # [ 58.069141] systemd[1]: Mounted /sysroot/run. vm-test-run-horae-e2e-oidc> server # [ 58.079401] systemd[1]: Mounted /sysroot/tmp/shared. vm-test-run-horae-e2e-oidc> server # [ 58.094809] systemd[1]: Mounted /sysroot/tmp/xchg. vm-test-run-horae-e2e-oidc> server # [ 58.238263] systemd[1]: Reload requested from client PID 166 ('systemd-sysroot') (unit initrd-parse-etc.service)... vm-test-run-horae-e2e-oidc> server # [ 58.245974] systemd[1]: Reloading... vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-horae-e2e-oidc> server # [ 61.190482] systemd[1]: Reloading finished in 2921 ms. vm-test-run-horae-e2e-oidc> server # [ 61.378513] systemd-sysroot-fstab-check[166]: Requesting initrd-fs.target/start/replace... vm-test-run-horae-e2e-oidc> server # [ 61.433639] systemd-sysroot-fstab-check[166]: Requesting swap.target/start/replace... vm-test-run-horae-e2e-oidc> server # [ 61.498596] systemd[1]: initrd-parse-etc.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 61.505589] systemd[1]: Finished Mountpoints Configured in the Real Root. vm-test-run-horae-e2e-oidc> server # [ 61.513271] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. vm-test-run-horae-e2e-oidc> server # [ 61.589280] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-horae-e2e-oidc> server # [ 62.033312] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 62.055853] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-horae-e2e-oidc> server # [ 62.149156] systemd[1]: Mounting /sysroot/nix/store... vm-test-run-horae-e2e-oidc> server # [ 62.867857] systemd[1]: Mounted /sysroot/nix/store. vm-test-run-horae-e2e-oidc> server # [ 62.899514] systemd[1]: Reached target Initrd File Systems. vm-test-run-horae-e2e-oidc> server # [ 62.939075] systemd[1]: Starting Find NixOS closure... vm-test-run-horae-e2e-oidc> server # [ 63.005162] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... vm-test-run-horae-e2e-oidc> server # [ 63.903153] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. vm-test-run-horae-e2e-oidc> server # [ 63.965161] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 64.417594] systemd[1]: Finished Find NixOS closure. vm-test-run-horae-e2e-oidc> server # [ 64.444061] systemd[1]: Reached target Initrd Default Target. vm-test-run-horae-e2e-oidc> server # [ 64.503138] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... vm-test-run-horae-e2e-oidc> server # [ 65.280803] systemd[1]: Stopped target Initrd Default Target. vm-test-run-horae-e2e-oidc> server # [ 65.289915] systemd[1]: Stopped target Basic System. vm-test-run-horae-e2e-oidc> server # [ 65.301802] systemd[1]: Stopped target Initrd Root Device. vm-test-run-horae-e2e-oidc> server # [ 65.323233] systemd[1]: Stopped target Path Units. vm-test-run-horae-e2e-oidc> server # [ 65.335888] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 65.353145] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. vm-test-run-horae-e2e-oidc> server # [ 65.382627] systemd[1]: Stopped target Slice Units. vm-test-run-horae-e2e-oidc> server # [ 65.401183] systemd[1]: Stopped target Socket Units. vm-test-run-horae-e2e-oidc> server # [ 65.421195] systemd[1]: Stopped target System Initialization. vm-test-run-horae-e2e-oidc> server # [ 65.441825] systemd[1]: Stopped target Swaps. vm-test-run-horae-e2e-oidc> server # [ 65.459141] systemd[1]: Stopped target Timer Units. vm-test-run-horae-e2e-oidc> server # [ 65.473482] systemd[1]: dbus.socket: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 65.493350] systemd[1]: Closed D-Bus System Message Bus Socket. vm-test-run-horae-e2e-oidc> server # [ 65.513516] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 65.529300] systemd[1]: Stopped Find NixOS closure. vm-test-run-horae-e2e-oidc> server # [ 65.633355] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-horae-e2e-oidc> server # [ 65.773646] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-horae-e2e-oidc> server # [ 65.786030] systemd[1]: systemd-sysctl.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 65.814288] systemd[1]: Stopped Apply Kernel Variables. vm-test-run-horae-e2e-oidc> server # [ 65.863392] systemd[1]: systemd-modules-load.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 65.909940] systemd[1]: Stopped Load Kernel Modules. vm-test-run-horae-e2e-oidc> server # [ 65.950223] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 65.998527] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. vm-test-run-horae-e2e-oidc> server # [ 66.037838] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 66.098568] systemd[1]: Stopped Create System Files and Directories. vm-test-run-horae-e2e-oidc> server # [ 66.179789] systemd[1]: Stopped target Local File Systems. vm-test-run-horae-e2e-oidc> server # [ 66.194041] systemd[1]: Stopped target Preparation for Local File Systems. vm-test-run-horae-e2e-oidc> server # [ 66.234685] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 66.327708] systemd[1]: Stopped Coldplug All udev Devices. vm-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. vm-test-run-horae-e2e-oidc> server # [ 66.381359] systemd[1]: Stopping Rule-based Manager for Device Events and Files... vm-test-run-horae-e2e-oidc> server # [ 66.394112] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 66.414073] systemd[1]: Stopped Virtual Console Setup. vm-test-run-horae-e2e-oidc> server # [ 66.674696] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 66.687697] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-horae-e2e-oidc> server # [ 66.715941] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 66.731096] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-horae-e2e-oidc> server # [ 66.861168] systemd[1]: initrd-cleanup.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 66.870409] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. vm-test-run-horae-e2e-oidc> server # [ 67.741892] systemd[1]: systemd-udevd.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 67.753469] systemd[1]: Stopped Rule-based Manager for Device Events and Files. vm-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. vm-test-run-horae-e2e-oidc> server # [ 67.771730] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 67.781983] systemd[1]: Closed udev Control Socket. vm-test-run-horae-e2e-oidc> server # [ 67.815143] systemd[1]: Starting Cleanup udev Database... vm-test-run-horae-e2e-oidc> server # [ 67.822832] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 67.829967] systemd[1]: Stopped Create Static Device Nodes in /dev. vm-test-run-horae-e2e-oidc> server # [ 67.839336] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 67.845926] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. vm-test-run-horae-e2e-oidc> server # [ 67.852883] systemd[1]: kmod-static-nodes.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 67.859867] systemd[1]: Stopped Create List of Static Device Nodes. vm-test-run-horae-e2e-oidc> server # [ 68.217038] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 68.226096] systemd[1]: Finished Cleanup udev Database. vm-test-run-horae-e2e-oidc> server # [ 68.242072] systemd[1]: Reached target Switch Root. vm-test-run-horae-e2e-oidc> server # [ 68.278194] systemd[1]: Starting NixOS Activation... vm-test-run-horae-e2e-oidc> server # [ 70.927908] initrd-nixos-activation-start[221]: booting system configuration /nix/store/9mqs26gy19d4rday0l3dg6qspcp9h4ym-nixos-system-server-test vm-test-run-horae-e2e-oidc> server # [ 71.887765] initrd-nixos-activation-start[221]: running activation script... vm-test-run-horae-e2e-oidc> server # [ 79.418990] initrd-nixos-activation-start[244]: setting up /etc... vm-test-run-horae-e2e-oidc> server # [ 83.495752] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 83.507157] systemd[1]: Finished NixOS Activation. vm-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. vm-test-run-horae-e2e-oidc> server # [ 83.551323] systemd[1]: Starting Switch Root... vm-test-run-horae-e2e-oidc> server # [ 83.853216] systemd[1]: Switching root. vm-test-run-horae-e2e-oidc> server # [ 84.284035] systemd-journald[74]: Received SIGTERM from PID 1 (systemd). vm-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) vm-test-run-horae-e2e-oidc> server # [ 87.585796] systemd[1]: Detected virtualization qemu. vm-test-run-horae-e2e-oidc> server # [ 87.589614] systemd[1]: Detected architecture arm64. vm-test-run-horae-e2e-oidc> server # [ 87.595046] systemd[1]: Detected first boot. vm-test-run-horae-e2e-oidc> server # [ 87.646998] systemd[1]: Initializing machine ID from random generator. vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-horae-e2e-oidc> server # [ 90.683856] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-horae-e2e-oidc> server # [ 93.494041] systemd[1]: Applying preset policy. vm-test-run-horae-e2e-oidc> server # [ 101.694296] systemd[1]: Populated /etc with preset unit settings. vm-test-run-horae-e2e-oidc> server # [ 109.938110] systemd[1]: initrd-switch-root.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 109.951223] systemd[1]: Stopped initrd-switch-root.service. vm-test-run-horae-e2e-oidc> server # [ 110.003640] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. vm-test-run-horae-e2e-oidc> server # [ 110.044021] systemd[1]: Created slice Slice /system/getty. vm-test-run-horae-e2e-oidc> server # [ 110.071684] systemd[1]: Created slice User and Session Slice. vm-test-run-horae-e2e-oidc> server # [ 110.084666] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-horae-e2e-oidc> server # [ 110.097425] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-horae-e2e-oidc> server # [ 110.106232] systemd[1]: Expecting device /dev/hvc0... vm-test-run-horae-e2e-oidc> server # [ 110.114088] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-horae-e2e-oidc> server # [ 110.122207] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-horae-e2e-oidc> server # [ 110.130327] systemd[1]: Stopped target initrd-fs.target. vm-test-run-horae-e2e-oidc> server # [ 110.138161] systemd[1]: Stopped target initrd-root-fs.target. vm-test-run-horae-e2e-oidc> server # [ 110.146226] systemd[1]: Stopped target initrd-switch-root.target. vm-test-run-horae-e2e-oidc> server # [ 110.154925] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-horae-e2e-oidc> server # [ 110.163955] systemd[1]: Reached target Path Units. vm-test-run-horae-e2e-oidc> server # [ 110.171972] systemd[1]: Reached target Remote File Systems. vm-test-run-horae-e2e-oidc> server # [ 110.179922] systemd[1]: Reached target Slice Units. vm-test-run-horae-e2e-oidc> server # [ 110.188072] systemd[1]: Reached target Swaps. vm-test-run-horae-e2e-oidc> server # [ 110.240279] systemd[1]: Listening on Query the User Interactively for a Password. vm-test-run-horae-e2e-oidc> server # [ 110.302213] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-horae-e2e-oidc> server # [ 110.348514] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-horae-e2e-oidc> server # [ 110.400424] systemd[1]: Listening on Factory Reset Management. vm-test-run-horae-e2e-oidc> server # [ 110.472588] systemd[1]: Starting Journal Log Access Socket... vm-test-run-horae-e2e-oidc> server # [ 110.517973] systemd[1]: Listening on Journal Audit Socket. vm-test-run-horae-e2e-oidc> server # [ 110.590889] systemd[1]: Listening on Console Output Muting Service Socket. vm-test-run-horae-e2e-oidc> server # [ 110.603078] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-horae-e2e-oidc> server # [ 110.611870] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-horae-e2e-oidc> server # [ 110.619987] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-horae-e2e-oidc> server # [ 110.739234] systemd[1]: Listening on Disk Repartitioning Service Socket. vm-test-run-horae-e2e-oidc> server # [ 110.749880] systemd[1]: Listening on udev Control Socket. vm-test-run-horae-e2e-oidc> server # [ 110.760821] systemd[1]: Listening on udev Varlink Socket. vm-test-run-horae-e2e-oidc> server # [ 110.834176] systemd[1]: Mounting Huge Pages File System... vm-test-run-horae-e2e-oidc> server # [ 110.935193] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-horae-e2e-oidc> server # [ 111.099462] systemd[1]: Mounting Kernel Debug File System... vm-test-run-horae-e2e-oidc> server # [ 111.302702] systemd[1]: Mounting Kernel Trace File System... vm-test-run-horae-e2e-oidc> server # [ 111.587609] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-horae-e2e-oidc> server # [ 111.898949] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-horae-e2e-oidc> server # [ 111.944155] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-horae-e2e-oidc> server # [ 112.292715] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-horae-e2e-oidc> server # [ 112.371927] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm vm-test-run-horae-e2e-oidc> server # [ 112.456060] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-horae-e2e-oidc> server # [ 113.047205] systemd[1]: Starting Load Kernel Module fuse... vm-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-6d876050dc67 vm-test-run-horae-e2e-oidc> server # [ 113.679033] systemd[1]: Starting Journal Service... vm-test-run-horae-e2e-oidc> server # [ 114.062759] systemd[1]: Starting Load Kernel Modules... vm-test-run-horae-e2e-oidc> server # [ 114.508866] fuse: init (API version 7.45) vm-test-run-horae-e2e-oidc> server # [ 114.607209] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-horae-e2e-oidc> server # [ 114.887855] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-horae-e2e-oidc> server # [ 114.923900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-horae-e2e-oidc> server # [ 115.467219] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-horae-e2e-oidc> server # [ 116.164662] systemd-journald[315]: Collecting audit messages is enabled. vm-test-run-horae-e2e-oidc> server # [ 116.354233] systemd[1]: Listening on Journal Log Access Socket. vm-test-run-horae-e2e-oidc> server # [ 116.440487] systemd[1]: Mounted Huge Pages File System. vm-test-run-horae-e2e-oidc> server # [ 116.500618] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-horae-e2e-oidc> server # [ 116.567386] systemd[1]: Mounted Kernel Debug File System. vm-test-run-horae-e2e-oidc> server # [ 116.626934] systemd[1]: Mounted Kernel Trace File System. vm-test-run-horae-e2e-oidc> server # [ 116.738676] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-horae-e2e-oidc> server # [ 116.828031] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 116.918515] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-horae-e2e-oidc> server # [ 116.972635] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-horae-e2e-oidc> server # [ 117.091502] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 117.170598] EXT4-fs (vda): re-mounted 672a12cf-5c1d-4c5e-9e0f-89bd54a7d837. vm-test-run-horae-e2e-oidc> server # [ 117.183694] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-horae-e2e-oidc> server # [ 117.118262] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-horae-e2e-oidc> server # [ 117.206833] systemd[1]: systemd-journald.service: Deactivated successfully. vm-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. vm-test-run-horae-e2e-oidc> server # [ 117.456235] systemd[1]: Started Journal Service. vm-test-run-horae-e2e-oidc> server # [ 117.378596] systemd-modules-load[316]: Module 'atkbd' is built in vm-test-run-horae-e2e-oidc> server # [ 117.407381] systemd-modules-load[316]: Module 'loop' is built in vm-test-run-horae-e2e-oidc> server # [ 117.511027] systemd[1]: Finished Load Kernel Modules. vm-test-run-horae-e2e-oidc> server # [ 117.595171] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-horae-e2e-oidc> server # [ 118.151210] systemd[1]: Listening on Disk Image Download Service Socket. vm-test-run-horae-e2e-oidc> server # [ 118.315457] systemd[1]: Mounting FUSE Control File System... vm-test-run-horae-e2e-oidc> server # [ 118.585947] systemd[1]: Starting Firewall... vm-test-run-horae-e2e-oidc> server # [ 118.951350] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-horae-e2e-oidc> server # [ 118.985269] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-horae-e2e-oidc> server # [ 119.688961] systemd-oomd[317]: No swap; memory pressure usage will be degraded vm-test-run-horae-e2e-oidc> server # [ 119.743215] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-horae-e2e-oidc> server # [ 120.449385] systemd[1]: Starting Apply Kernel Variables... vm-test-run-horae-e2e-oidc> server # [ 121.199839] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-horae-e2e-oidc> server # [ 121.230550] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-horae-e2e-oidc> server # [ 121.521370] systemd-journald[315]: Received client request to flush runtime journal. vm-test-run-horae-e2e-oidc> server # [ 123.694544] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-horae-e2e-oidc> server # [ 123.757020] systemd[1]: Mounted FUSE Control File System. vm-test-run-horae-e2e-oidc> server # [ 123.825094] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-horae-e2e-oidc> server # [ 123.883496] systemd[1]: Reached target First Boot Complete. vm-test-run-horae-e2e-oidc> server # [ 123.918848] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-horae-e2e-oidc> server # [ 124.955453] systemd[1]: Finished Apply Kernel Variables. vm-test-run-horae-e2e-oidc> server # [ 126.045494] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-horae-e2e-oidc> server # [ 126.250587] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-horae-e2e-oidc> server # [ 128.293965] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-horae-e2e-oidc> server # [ 128.365097] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-horae-e2e-oidc> server # [ 128.501935] systemd[1]: Mounting /run/wrappers... vm-test-run-horae-e2e-oidc> server # [ 128.796729] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-horae-e2e-oidc> server # [ 130.311569] systemd[1]: Mounted /run/wrappers. vm-test-run-horae-e2e-oidc> server # [ 130.382276] systemd[1]: Reached target Local File Systems. vm-test-run-horae-e2e-oidc> server # [ 130.514256] systemd[1]: Listening on Boot Loader Control Service Socket. vm-test-run-horae-e2e-oidc> server # [ 130.697367] systemd[1]: Starting register-nix-paths.service... vm-test-run-horae-e2e-oidc> server # [ 131.077384] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-horae-e2e-oidc> server # [ 131.149126] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-horae-e2e-oidc> server # [ 131.807207] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-horae-e2e-oidc> server # [ 132.422690] systemd[1]: Starting Create System Files and Directories... vm-test-run-horae-e2e-oidc> server # [ 135.334705] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 135.431744] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-horae-e2e-oidc> server # [ 140.279016] systemd[1]: Finished Create System Files and Directories. vm-test-run-horae-e2e-oidc> server # [ 140.791568] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-horae-e2e-oidc> server # [ 141.441968] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-horae-e2e-oidc> server # [ 145.099527] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-horae-e2e-oidc> server # [ 148.399659] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-horae-e2e-oidc> server # [ 148.697866] systemd[1]: Starting Update is Completed... vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-horae-e2e-oidc> server # [ 151.136060] systemd[1]: Finished Update is Completed. vm-test-run-horae-e2e-oidc> server # [ 156.766424] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 156.803555] systemd[1]: Finished Create SUID/SGID Wrappers. vm-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. vm-test-run-horae-e2e-oidc> server # [ 163.561690] systemd[1]: Finished Firewall. vm-test-run-horae-e2e-oidc> server # [ 166.746774] systemd[1]: Finished register-nix-paths.service. vm-test-run-horae-e2e-oidc> server # [ 167.013754] systemd-udevd[371]: Using default interface naming scheme 'v260'. vm-test-run-horae-e2e-oidc> server # [ 169.553359] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-horae-e2e-oidc> server # [ 188.535249] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-horae-e2e-oidc> server # [ 188.551357] systemd[1]: Reached target System Initialization. vm-test-run-horae-e2e-oidc> server # [ 188.582930] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-horae-e2e-oidc> server # [ 188.594174] systemd[1]: Started postgresqlBackup-horae.timer. vm-test-run-horae-e2e-oidc> server # [ 188.610375] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-horae-e2e-oidc> server # [ 188.622544] systemd[1]: Reached target Timer Units. vm-test-run-horae-e2e-oidc> server # [ 188.645670] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-horae-e2e-oidc> server # [ 188.650435] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-horae-e2e-oidc> server # [ 188.662545] systemd[1]: Listening on Hostname Service Socket. vm-test-run-horae-e2e-oidc> server # [ 188.674542] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. vm-test-run-horae-e2e-oidc> server # [ 188.687429] systemd[1]: Reached target Socket Units. vm-test-run-horae-e2e-oidc> server # [ 188.698502] systemd[1]: Reached target Basic System. vm-test-run-horae-e2e-oidc> server # [ 188.759039] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-horae-e2e-oidc> server # [ 188.958542] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-horae-e2e-oidc> server # [ 189.191047] systemd[1]: Starting Post-Boot Actions... vm-test-run-horae-e2e-oidc> server # [ 189.595682] systemd[1]: Started Reset console on configuration changes. vm-test-run-horae-e2e-oidc> server # [ 190.085323] systemd[1]: Starting resolvconf update... vm-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" vm-test-run-horae-e2e-oidc> server # [ 191.956710] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-horae-e2e-oidc> server # [ 191.994748] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-horae-e2e-oidc> server # [ 192.211186] systemd[1]: Finished Post-Boot Actions. vm-test-run-horae-e2e-oidc> server # [ 192.741150] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-horae-e2e-oidc> server # [ 192.860037] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-horae-e2e-oidc> server # [ 192.923035] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-horae-e2e-oidc> server # [ 193.347155] systemd[1]: Starting User Login Management... vm-test-run-horae-e2e-oidc> server # [ 195.851789] dbus-broker-launch[570]: Looking up NSS user entry for 'systemd-timesync'... vm-test-run-horae-e2e-oidc> server # [ 196.268841] dbus-broker-launch[570]: NSS returned no entry for 'systemd-timesync' vm-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" vm-test-run-horae-e2e-oidc> server # [ 196.965184] systemd[1]: Started D-Bus System Message Bus. vm-test-run-horae-e2e-oidc> server # [ 198.355742] systemd-logind[584]: New seat seat0. vm-test-run-horae-e2e-oidc> server # [ 198.423280] dbus-broker-launch[570]: Ready vm-test-run-horae-e2e-oidc> server # [ 198.559237] systemd[1]: Started User Login Management. vm-test-run-horae-e2e-oidc> server # [ 198.905013] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-horae-e2e-oidc> server # [ 198.923151] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-horae-e2e-oidc> server # [ 198.941764] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-horae-e2e-oidc> server # [ 198.958822] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-horae-e2e-oidc> server # [ 199.089991] systemd[1]: Starting linger-users.service... vm-test-run-horae-e2e-oidc> server # [ 199.205646] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-horae-e2e-oidc> server # [ 199.405879] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 199.490166] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-horae-e2e-oidc> server # [ 200.335834] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-horae-e2e-oidc> server # [ 201.030822] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-horae-e2e-oidc> server # [ 201.094448] systemd[1]: Finished linger-users.service. vm-test-run-horae-e2e-oidc> server # [ 201.299333] systemd[1]: Finished resolvconf update. vm-test-run-horae-e2e-oidc> server # [ 201.398262] systemd[1]: Reached target Preparation for Network. vm-test-run-horae-e2e-oidc> server # [ 201.611013] systemd[1]: Starting DHCP Client... vm-test-run-horae-e2e-oidc> server # [ 201.979935] systemd[1]: Starting Extra networking commands.... vm-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" vm-test-run-horae-e2e-oidc> server # [ 202.255310] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-horae-e2e-oidc> server # [ 202.361495] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-horae-e2e-oidc> server # [ 202.397314] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-horae-e2e-oidc> server # [ 207.483493] systemd-logind[584]: Watching system buttons on /dev/input/event1 (gpio-keys) vm-test-run-horae-e2e-oidc> server # [ 208.066655] systemd[1]: Finished Extra networking commands.. vm-test-run-horae-e2e-oidc> server # [ 208.141126] systemd[1]: Reached target Network. vm-test-run-horae-e2e-oidc> server # [ 208.339306] dhcpcd[659]: dhcpcd-10.3.2 starting vm-test-run-horae-e2e-oidc> server # [ 208.476937] systemd[1]: Starting dex identity provider... vm-test-run-horae-e2e-oidc> server # [ 208.679589] dhcpcd[710]: dev: loaded udev vm-test-run-horae-e2e-oidc> server # [ 208.833384] dhcpcd[710]: no valid interfaces found vm-test-run-horae-e2e-oidc> server # [ 208.838341] dhcpcd[710]: no valid interfaces found vm-test-run-horae-e2e-oidc> server # [ 208.870011] systemd[1]: Starting PostgreSQL Server... vm-test-run-horae-e2e-oidc> server # [ 209.018060] systemd[1]: Starting Permit User Sessions... vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-horae-e2e-oidc> server # [ 210.545754] systemd[1]: Finished Permit User Sessions. vm-test-run-horae-e2e-oidc> server # [ 210.838880] systemd[1]: Started Getty on tty1. vm-test-run-horae-e2e-oidc> server # [ 210.955337] systemd[1]: Reached target Login Prompts. vm-test-run-horae-e2e-oidc> server # [ 216.222270] systemd[1]: Started dex identity provider. vm-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". vm-test-run-horae-e2e-oidc> server # [ 216.391979] postgresql-pre-start[722]: This user must also own the server process. vm-test-run-horae-e2e-oidc> server # [ 216.545135] postgresql-pre-start[722]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-horae-e2e-oidc> server # [ 216.547101] postgresql-pre-start[722]: The default database encoding has accordingly been set to "UTF8". vm-test-run-horae-e2e-oidc> server # [ 216.563690] postgresql-pre-start[722]: The default text search configuration will be set to "english". vm-test-run-horae-e2e-oidc> server # [ 216.567403] postgresql-pre-start[722]: Data page checksums are disabled. vm-test-run-horae-e2e-oidc> server # [ 216.594632] postgresql-pre-start[722]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok vm-test-run-horae-e2e-oidc> server # [ 216.704941] postgresql-pre-start[722]: creating subdirectories ... ok vm-test-run-horae-e2e-oidc> server # [ 216.749533] postgresql-pre-start[722]: selecting dynamic shared memory implementation ... posix vm-test-run-horae-e2e-oidc> server # [ 220.495606] postgresql-pre-start[722]: selecting default "max_connections" ... 100 vm-test-run-horae-e2e-oidc> server # [ 223.676817] postgresql-pre-start[722]: selecting default "shared_buffers" ... 128MB vm-test-run-horae-e2e-oidc> server # [ 232.178003] mousedev: PS/2 mouse device common for all mice vm-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=arm64 vm-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/dex vm-test-run-horae-e2e-oidc> server # [ 233.849193] dex[734]: time=2026-10-06T19:55:15.661Z level=INFO msg="config storage" storage_type=memory vm-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=Horae vm-test-run-horae-e2e-oidc> server # [ 233.884920] dex[734]: time=2026-10-06T19:55:15.682Z level=INFO msg="config connector" connector_id=mock vm-test-run-horae-e2e-oidc> server # [ 233.890962] dex[734]: time=2026-10-06T19:55:15.684Z level=INFO msg="config skipping approval screen" vm-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" vm-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=6h vm-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=true vm-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" vm-test-run-horae-e2e-oidc> server # [ 234.281012] dex[734]: time=2026-10-06T19:55:16.119Z level=INFO msg="keys expired, rotating" vm-test-run-horae-e2e-oidc> server # [ 238.929476] dhcpcd[710]: timed out vm-test-run-horae-e2e-oidc> server # [ 238.951038] dhcpcd[710]: timed out vm-test-run-horae-e2e-oidc> server # [ 239.011352] systemd[1]: Started DHCP Client. vm-test-run-horae-e2e-oidc> server: Guest root shell did not produce any data yet... vm-test-run-horae-e2e-oidc> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-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.509Z vm-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