vm-test-run-nixos-test-niks3
aarch64-linux.nixos-test-niks3
· build #80
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600 seconds6run the VM test script7start all VMs8builder: starting vm9server: starting vm10server: QEMU running (pid 15)11server # Disk image does not exist, creating the virtualisation disk image...12server # Formatting '/build/vm-state-server/tmp.eCsZlF9hpv', fmt=raw size=107374182413builder # Disk image does not exist, creating the virtualisation disk image...14builder: QEMU running (pid 14)15builder # Formatting '/build/vm-state-builder/tmp.W6lbfEAtHl', fmt=raw size=107374182416server # mke2fs 1.47.4 (6-Mar-2025)17builder # mke2fs 1.47.4 (6-Mar-2025)18server # Discarding device blocks: 0/262144 done19(finished: start all VMs, in 0.48 seconds)20server # Creating filesystem with 262144 4k blocks and 65536 inodes21server: waiting for unit postgresql.service22server # Filesystem UUID: 47c35091-0e2c-4ddb-a6f4-2b8efff440d423server: waiting for the VM to finish booting24server # Superblock backups stored on blocks:25builder # Discarding device blocks: 0/262144 done26server # 32768, 98304, 163840, 22937627builder # Creating filesystem with 262144 4k blocks and 65536 inodes28server # 29builder # Filesystem UUID: c50e52c7-988d-4e9c-a97e-239fd5e48fe130server # Allocating group tables: 0/8 done31builder # Superblock backups stored on blocks:32server # Writing inode tables: 0/8 done33builder # 32768, 98304, 163840, 22937634server # Creating journal (8192 blocks): done35builder # 36server # Writing superblocks and filesystem accounting information: 0/8 done37builder # Allocating group tables: 0/8 done38server # 39builder # Writing inode tables: 0/8 done40server # Virtualisation disk image created.41builder # Creating journal (8192 blocks): done42builder # Writing superblocks and filesystem accounting information: 0/8 done43builder # 44builder # Virtualisation disk image created.45server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]46server # [ 0.000000] Linux version 6.18.37 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jun 27 10:06:50 UTC 202647server # [ 0.000000] KASLR enabled48server # [ 0.000000] random: crng init done49server # [ 0.000000] Machine model: linux,dummy-virt50server # [ 0.000000] efi: UEFI not found.51server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT52server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]53server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]54server # [ 0.000000] Zone ranges:55server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]56server # [ 0.000000] DMA32 empty57server # [ 0.000000] Normal empty58server # [ 0.000000] Device empty59server # [ 0.000000] Movable zone start for each node60server # [ 0.000000] Early memory node ranges61server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]62server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]63server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000064server # [ 0.000000] psci: probing for conduit method from DT.65server # [ 0.000000] psci: PSCIv1.3 detected in firmware.66server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs67server # [ 0.000000] psci: Trusted OS migration not required68server # [ 0.000000] psci: SMC Calling Convention v1.169server # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)70server # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129671server # [ 0.000000] Detected PIPT I-cache on CPU072server # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)73server # [ 0.000000] CPU features: detected: GICv3 CPU interface74server # [ 0.000000] CPU features: detected: Spectre-v475server # [ 0.000000] CPU features: detected: Spectre-BHB76server # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3877server # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2378server # [ 0.000000] alternatives: applying boot alternatives79server # [ 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/rgbhlyrymfpybmdrxvl338nnzfczpvqd-nixos-system-server-test/init regInfo=/nix/store/1sb2lvhg7aci4qhvj8w7xifv49ds1g2r-closure-info/registration console=ttyAMA0,115200n8 console=tty080server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/1sb2lvhg7aci4qhvj8w7xifv49ds1g2r-closure-info/registration", will be passed to user space.81server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes82server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)83server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)84server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB85server # [ 0.000000] software IO TLB: area num 1.86server # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)87server # [ 0.000000] Fallback order for Node 0: 088server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214489server # [ 0.000000] Policy zone: DMA90server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off91server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=192server # [ 0.000000] allocated 2097152 bytes of page_ext93server # [ 0.000000] ftrace: allocating 74702 entries in 292 pages94server # [ 0.000000] ftrace: allocated 292 pages with 3 groups95server # [ 0.000000] rcu: Hierarchical RCU implementation.96server # [ 0.000000] rcu: RCU event tracing is enabled.97server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.98server # [ 0.000000] Trampoline variant of Tasks RCU enabled.99server # [ 0.000000] Rude variant of Tasks RCU enabled.100server # [ 0.000000] Tracing variant of Tasks RCU enabled.101server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.102server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1103server # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.104server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.105server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.106server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0107server # [ 0.000000] GICv3: 256 SPIs implemented108server # [ 0.000000] GICv3: 0 Extended SPIs implemented109server # [ 0.000000] Root IRQ handler: gic_handle_irq110server # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI111builder # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]112server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0113server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000114builder # [ 0.000000] Linux version 6.18.37 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sat Jun 27 10:06:50 UTC 2026115server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]116builder # [ 0.000000] KASLR enabled117builder # [ 0.000000] random: crng init done118builder # [ 0.000000] Machine model: linux,dummy-virt119server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)120builder # [ 0.000000] efi: UEFI not found.121builder # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT122server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)123builder # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]124server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000125builder # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded740-0x7fdf0ebf]126builder # [ 0.000000] Zone ranges:127server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000128builder # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]129server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.130builder # [ 0.000000] DMA32 empty131builder # [ 0.000000] Normal empty132builder # [ 0.000000] Device empty133server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).134builder # [ 0.000000] Movable zone start for each node135builder # [ 0.000000] Early memory node ranges136server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns137builder # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]138server # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns139builder # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]140server # [ 0.000034] arm-pv: using stolen time PV141builder # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc00000142builder # [ 0.000000] psci: probing for conduit method from DT.143server # [ 0.000665] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)144builder # [ 0.000000] psci: PSCIv1.3 detected in firmware.145server # [ 0.000856] Console: colour dummy device 80x25146builder # [ 0.000000] psci: Using standard PSCI v0.2 function IDs147server # [ 0.000863] printk: legacy console [tty0] enabled148builder # [ 0.000000] psci: Trusted OS migration not required149builder # [ 0.000000] psci: SMC Calling Convention v1.1150server # [ 0.001042] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)151server # [ 0.001049] pid_max: default: 32768 minimum: 301152builder # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)153server # [ 0.001133] LSM: initializing lsm=capability,landlock,yama,bpf,ima154builder # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296155server # [ 0.001321] landlock: Up and running.156builder # [ 0.000000] Detected PIPT I-cache on CPU0157server # [ 0.001324] Yama: becoming mindful.158server # [ 0.001983] LSM support for eBPF active159builder # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)160builder # [ 0.000000] CPU features: detected: GICv3 CPU interface161server # [ 0.002109] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)162builder # [ 0.000000] CPU features: detected: Spectre-v4163server # [ 0.002129] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)164builder # [ 0.000000] CPU features: detected: Spectre-BHB165server # [ 0.003227] cacheinfo: Unable to detect cache hierarchy for CPU 0166builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38167server # [ 0.003958] rcu: Hierarchical SRCU implementation.168builder # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23169server # [ 0.003962] rcu: Max phase no-delay instances is 1000.170builder # [ 0.000000] alternatives: applying boot alternatives171server # [ 0.005192] fsl-mc MSI: its@8080000 domain created172server # [ 0.005282] EFI services will not be available.173server # [ 0.005356] smp: Bringing up secondary CPUs ...174server # [ 0.005364] smp: Brought up 1 node, 1 CPU175server # [ 0.005367] SMP: Total of 1 processors activated.176server # [ 0.005369] CPU: All CPU(s) started at EL1177server # [ 0.005379] CPU features: detected: Branch Target Identification178builder # [ 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/iifh79al739z0m3ksihz95rbiqxz7qg4-nixos-system-builder-test/init regInfo=/nix/store/l7z6bc3qm1l0gg1bzynd89piv0p35jbk-closure-info/registration console=ttyAMA0,115200n8 console=tty0179server # [ 0.005383] CPU features: detected: ARMv8.4 Translation Table Level180server # [ 0.005388] CPU features: detected: Instruction cache invalidation not required for I/D coherence181builder # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/l7z6bc3qm1l0gg1bzynd89piv0p35jbk-closure-info/registration", will be passed to user space.182server # [ 0.005392] CPU features: detected: Data cache clean to the PoU not required for I/D coherence183builder # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes184server # [ 0.005396] CPU features: detected: Common not Private translations185builder # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)186server # [ 0.005398] CPU features: detected: CRC32 instructions187builder # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)188server # [ 0.005401] CPU features: detected: Data cache clean to Point of Deep Persistence189builder # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB190server # [ 0.005404] CPU features: detected: Data cache clean to Point of Persistence191builder # [ 0.000000] software IO TLB: area num 1.192server # [ 0.005408] CPU features: detected: Data independent timing control (DIT)193builder # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)194builder # [ 0.000000] Fallback order for Node 0: 0195server # [ 0.005410] CPU features: detected: E0PD196builder # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144197server # [ 0.005413] CPU features: detected: Enhanced Counter Virtualization198builder # [ 0.000000] Policy zone: DMA199builder # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off200server # [ 0.005416] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)201builder # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1202server # [ 0.005419] CPU features: detected: Enhanced Virtualization Traps203builder # [ 0.000000] allocated 2097152 bytes of page_ext204server # [ 0.005422] CPU features: detected: Fine Grained Traps205builder # [ 0.000000] ftrace: allocating 74702 entries in 292 pages206server # [ 0.005425] CPU features: detected: Generic authentication (architected QARMA5 algorithm)207builder # [ 0.000000] ftrace: allocated 292 pages with 3 groups208server # [ 0.005430] CPU features: detected: RCpc load-acquire (LDAPR)209builder # [ 0.000000] rcu: Hierarchical RCU implementation.210builder # [ 0.000000] rcu: RCU event tracing is enabled.211server # [ 0.005432] CPU features: detected: LSE atomic instructions212server # [ 0.005435] CPU features: detected: Privileged Access Never213builder # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.214server # [ 0.005438] CPU features: detected: PMUv3215builder # [ 0.000000] Trampoline variant of Tasks RCU enabled.216builder # [ 0.000000] Rude variant of Tasks RCU enabled.217server # [ 0.005440] CPU features: detected: RAS Extension Support218builder # [ 0.000000] Tracing variant of Tasks RCU enabled.219server # [ 0.005443] CPU features: detected: RASv1p1 Extension Support220server # [ 0.005445] CPU features: detected: Random Number Generator221builder # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.222server # [ 0.005448] CPU features: detected: Speculation barrier (SB)223builder # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1224server # [ 0.005450] CPU features: detected: Stage-2 Force Write-Back225builder # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.226server # [ 0.005453] CPU features: detected: TLB range maintenance instructions227builder # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.228server # [ 0.005457] CPU features: detected: Speculative Store Bypassing Safe (SSBS)229server # [ 0.005497] alternatives: applying system-wide alternatives230builder # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.231server # [ 0.008369] CPU features: detected: BBM Level 2 without TLB conflict abort232builder # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0233builder # [ 0.000000] GICv3: 256 SPIs implemented234builder # [ 0.000000] GICv3: 0 Extended SPIs implemented235builder # [ 0.000000] Root IRQ handler: gic_handle_irq236server # [ 0.008574] Memory: 895052K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 112248K reserved, 32768K cma-reserved)237server # [ 0.008940] devtmpfs: initialized238builder # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI239builder # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0240server # [ 0.010656] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns241builder # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000242builder # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]243server # [ 0.010678] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)244builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)245server # [ 0.010699] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).246server # [ 0.010906] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL247builder # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)248server # [ 0.010910] 0 pages in range for non-PLT usage249server # [ 0.010911] 508320 pages in range for PLT usage250builder # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000251server # [ 0.011038] pinctrl core: initialized pinctrl subsystem252builder # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000253server # [ 0.011829] DMI not present or invalid.254builder # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.255server # [ 0.015395] NET: Registered PF_NETLINK/PF_ROUTE protocol family256builder # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).257server # [ 0.017880] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations258server # [ 0.018023] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations259builder # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns260server # [ 0.018182] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations261builder # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns262builder # [ 0.000031] arm-pv: using stolen time PV263server # [ 0.018204] audit: initializing netlink subsys (disabled)264server # [ 0.018745] thermal_sys: Registered thermal governor 'fair_share'265builder # [ 0.000527] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)266server # [ 0.018747] thermal_sys: Registered thermal governor 'bang_bang'267builder # [ 0.000694] Console: colour dummy device 80x25268server # [ 0.018751] thermal_sys: Registered thermal governor 'step_wise'269builder # [ 0.000702] printk: legacy console [tty0] enabled270server # [ 0.018754] thermal_sys: Registered thermal governor 'user_space'271builder # [ 0.000887] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)272server # [ 0.018756] thermal_sys: Registered thermal governor 'power_allocator'273builder # [ 0.000894] pid_max: default: 32768 minimum: 301274server # [ 0.018783] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1275builder # [ 0.001007] LSM: initializing lsm=capability,landlock,yama,bpf,ima276builder # [ 0.001200] landlock: Up and running.277server # [ 0.018792] cpuidle: using governor ladder278builder # [ 0.001203] Yama: becoming mindful.279server # [ 0.018798] cpuidle: using governor menu280builder # [ 0.001735] LSM support for eBPF active281server # [ 0.018998] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.282builder # [ 0.001847] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)283server # [ 0.019014] ASID allocator initialised with 65536 entries284server # [ 0.020161] Serial: AMBA PL011 UART driver285builder # [ 0.001867] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)286builder # [ 0.002868] cacheinfo: Unable to detect cache hierarchy for CPU 0287server # [ 0.025539] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1288builder # [ 0.003612] rcu: Hierarchical SRCU implementation.289server # [ 0.025694] printk: console [ttyAMA0] enabled290builder # [ 0.003617] rcu: Max phase no-delay instances is 1000.291builder # [ 0.004822] fsl-mc MSI: its@8080000 domain created292builder # [ 0.004912] EFI services will not be available.293builder # [ 0.004991] smp: Bringing up secondary CPUs ...294builder # [ 0.004998] smp: Brought up 1 node, 1 CPU295builder # [ 0.005001] SMP: Total of 1 processors activated.296builder # [ 0.005004] CPU: All CPU(s) started at EL1297builder # [ 0.005014] CPU features: detected: Branch Target Identification298builder # [ 0.005018] CPU features: detected: ARMv8.4 Translation Table Level299builder # [ 0.005024] CPU features: detected: Instruction cache invalidation not required for I/D coherence300builder # [ 0.005028] CPU features: detected: Data cache clean to the PoU not required for I/D coherence301server # [ 0.153546] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages302builder # [ 0.005032] CPU features: detected: Common not Private translations303server # [ 0.153567] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page304builder # [ 0.005035] CPU features: detected: CRC32 instructions305server # [ 0.153572] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages306builder # [ 0.005038] CPU features: detected: Data cache clean to Point of Deep Persistence307server # [ 0.153577] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page308builder # [ 0.005041] CPU features: detected: Data cache clean to Point of Persistence309server # [ 0.153581] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages310builder # [ 0.005044] CPU features: detected: Data independent timing control (DIT)311server # [ 0.153585] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page312builder # [ 0.005048] CPU features: detected: E0PD313server # [ 0.153590] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages314builder # [ 0.005051] CPU features: detected: Enhanced Counter Virtualization315server # [ 0.153594] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page316builder # [ 0.005054] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)317builder # [ 0.005057] CPU features: detected: Enhanced Virtualization Traps318builder # [ 0.005061] CPU features: detected: Fine Grained Traps319server # [ 0.161342] fbcon: Taking over console320server # [ 0.161358] ACPI: Interpreter disabled.321builder # [ 0.005065] CPU features: detected: Generic authentication (architected QARMA5 algorithm)322builder # [ 0.005069] CPU features: detected: RCpc load-acquire (LDAPR)323builder # [ 0.005072] CPU features: detected: LSE atomic instructions324builder # [ 0.005075] CPU features: detected: Privileged Access Never325builder # [ 0.005078] CPU features: detected: PMUv3326builder # [ 0.005080] CPU features: detected: RAS Extension Support327builder # [ 0.005083] CPU features: detected: RASv1p1 Extension Support328builder # [ 0.005086] CPU features: detected: Random Number Generator329builder # [ 0.005088] CPU features: detected: Speculation barrier (SB)330builder # [ 0.005091] CPU features: detected: Stage-2 Force Write-Back331builder # [ 0.005094] CPU features: detected: TLB range maintenance instructions332builder # [ 0.005099] CPU features: detected: Speculative Store Bypassing Safe (SSBS)333server # [ 0.169945] iommu: Default domain type: Translated334builder # [ 0.005137] alternatives: applying system-wide alternatives335server # [ 0.169955] iommu: DMA domain TLB invalidation policy: strict mode336server # [ 0.170308] SCSI subsystem initialized337builder # [ 0.008055] CPU features: detected: BBM Level 2 without TLB conflict abort338server # [ 0.172380] usbcore: registered new interface driver usbfs339builder # [ 0.008209] Memory: 895072K/1048576K available (24384K kernel code, 7086K rwdata, 26324K rodata, 4736K init, 1102K bss, 112248K reserved, 32768K cma-reserved)340server # [ 0.172413] usbcore: registered new interface driver hub341server # [ 0.172429] usbcore: registered new device driver usb342server # [ 0.172729] pps_core: LinuxPPS API ver. 1 registered343server # [ 0.172735] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>344server # [ 0.172745] PTP clock support registered345server # [ 0.172802] EDAC MC: Ver: 3.0.0346server # [ 0.177545] scmi_core: SCMI protocol bus registered347server # [ 0.178554] FPGA manager framework348server # [ 0.179562] vgaarb: loaded349server # [ 0.180224] clocksource: Switched to clocksource arch_sys_counter350server # [ 0.180710] VFS: Disk quotas dquot_6.6.0351builder # [ 0.008533] devtmpfs: initialized352server # [ 0.180742] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)353server # [ 0.183240] netfs: FS-Cache loaded354builder # [ 0.010220] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns355server # [ 0.183347] pnp: PnP ACPI: disabled356builder # [ 0.010243] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)357builder # [ 0.010263] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).358builder # [ 0.010439] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL359builder # [ 0.010444] 0 pages in range for non-PLT usage360builder # [ 0.010445] 508320 pages in range for PLT usage361builder # [ 0.010552] pinctrl core: initialized pinctrl subsystem362builder # [ 0.011328] DMI not present or invalid.363builder # [ 0.014837] NET: Registered PF_NETLINK/PF_ROUTE protocol family364builder # [ 0.017180] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations365builder # [ 0.017335] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations366builder # [ 0.017504] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations367server # [ 0.191083] NET: Registered PF_INET protocol family368builder # [ 0.017526] audit: initializing netlink subsys (disabled)369builder # [ 0.018097] thermal_sys: Registered thermal governor 'fair_share'370server # [ 0.191243] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)371builder # [ 0.018099] thermal_sys: Registered thermal governor 'bang_bang'372builder # [ 0.018103] thermal_sys: Registered thermal governor 'step_wise'373builder # [ 0.018106] thermal_sys: Registered thermal governor 'user_space'374builder # [ 0.018109] thermal_sys: Registered thermal governor 'power_allocator'375builder # [ 0.018135] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1376builder # [ 0.018143] cpuidle: using governor ladder377builder # [ 0.018149] cpuidle: using governor menu378builder # [ 0.018338] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.379builder # [ 0.018354] ASID allocator initialised with 65536 entries380builder # [ 0.019523] Serial: AMBA PL011 UART driver381builder # [ 0.024817] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1382builder # [ 0.024966] printk: console [ttyAMA0] enabled383builder # [ 0.151429] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages384builder # [ 0.151448] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page385builder # [ 0.151454] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages386builder # [ 0.151458] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page387builder # [ 0.151462] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages388builder # [ 0.151466] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page389builder # [ 0.151471] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages390builder # [ 0.151475] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page391builder # [ 0.159021] fbcon: Taking over console392builder # [ 0.159034] ACPI: Interpreter disabled.393builder # [ 0.160902] iommu: Default domain type: Translated394builder # [ 0.160912] iommu: DMA domain TLB invalidation policy: strict mode395builder # [ 0.162659] SCSI subsystem initialized396builder # [ 0.169652] usbcore: registered new interface driver usbfs397builder # [ 0.169686] usbcore: registered new interface driver hub398builder # [ 0.169703] usbcore: registered new device driver usb399builder # [ 0.169965] pps_core: LinuxPPS API ver. 1 registered400builder # [ 0.169971] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401builder # [ 0.169981] PTP clock support registered402builder # [ 0.170029] EDAC MC: Ver: 3.0.0403builder # [ 0.174868] scmi_core: SCMI protocol bus registered404builder # [ 0.175842] FPGA manager framework405builder # [ 0.176807] vgaarb: loaded406server # [ 0.219646] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)407builder # [ 0.177454] clocksource: Switched to clocksource arch_sys_counter408builder # [ 0.177901] VFS: Disk quotas dquot_6.6.0409server # [ 0.219686] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)410builder # [ 0.177927] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)411server # [ 0.219710] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)412builder # [ 0.180354] netfs: FS-Cache loaded413server # [ 0.219756] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)414builder # [ 0.180450] pnp: PnP ACPI: disabled415server # [ 0.219831] TCP: Hash tables configured (established 8192 bind 8192)416server # [ 0.219905] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)417server # [ 0.219974] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)418server # [ 0.220022] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)419server # [ 0.220132] NET: Registered PF_UNIX/PF_LOCAL protocol family420server # [ 0.220157] NET: Registered PF_XDP protocol family421server # [ 0.220196] PCI: CLS 0 bytes, default 64422server # [ 0.220312] Trying to unpack rootfs image as initramfs...423builder # [ 0.186550] NET: Registered PF_INET protocol family424builder # [ 0.186708] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)425server # [ 0.238570] kvm [1]: HYP mode not available426builder # [ 0.215419] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)427builder # [ 0.215457] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)428builder # [ 0.215479] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)429builder # [ 0.215520] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)430builder # [ 0.215596] TCP: Hash tables configured (established 8192 bind 8192)431builder # [ 0.215668] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)432builder # [ 0.215702] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)433builder # [ 0.215750] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)434builder # [ 0.215859] NET: Registered PF_UNIX/PF_LOCAL protocol family435builder # [ 0.215879] NET: Registered PF_XDP protocol family436builder # [ 0.215897] PCI: CLS 0 bytes, default 64437builder # [ 0.216134] Trying to unpack rootfs image as initramfs...438builder # [ 0.231470] kvm [1]: HYP mode not available439server # [ 0.322251] Initialise system trusted keyrings440server # [ 0.323008] workingset: timestamp_bits=42 max_order=18 bucket_order=0441server # [ 0.324356] squashfs: version 4.0 (2009/01/31) Phillip Lougher442server # [ 0.325157] 9p: Installing v9fs 9p2000 file system support443server # [ 0.353121] Key type asymmetric registered444server # [ 0.353140] Asymmetric key parser 'x509' registered445server # [ 0.353223] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)446server # [ 0.355326] io scheduler mq-deadline registered447server # [ 0.355338] io scheduler kyber registered448server # [ 0.364384] pl061_gpio 9030000.pl061: PL061 GPIO chip registered449builder # [ 0.315950] Initialise system trusted keyrings450server # [ 0.365814] ledtrig-cpu: registered to indicate activity on CPUs451builder # [ 0.321513] workingset: timestamp_bits=42 max_order=18 bucket_order=0452server # [ 0.366177] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:453builder # [ 0.322858] squashfs: version 4.0 (2009/01/31) Phillip Lougher454server # [ 0.366194] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000455server # [ 0.366209] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000456builder # [ 0.323644] 9p: Installing v9fs 9p2000 file system support457server # [ 0.366217] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000458server # [ 0.366237] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits459server # [ 0.366262] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]460server # [ 0.366335] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00461server # [ 0.366344] pci_bus 0000:00: root bus resource [bus 00-ff]462server # [ 0.366351] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]463server # [ 0.366357] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]464server # [ 0.366362] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]465server # [ 0.366423] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint466server # [ 0.366865] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint467server # [ 0.367054] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]468server # [ 0.367071] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]469server # [ 0.367102] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]470server # [ 0.367119] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]471server # [ 0.367584] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint472server # [ 0.367770] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]473server # [ 0.367787] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]474server # [ 0.367817] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]475server # [ 0.368301] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint476server # [ 0.368485] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]477server # [ 0.368502] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]478server # [ 0.368531] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]479builder # [ 0.344258] Key type asymmetric registered480server # [ 0.368980] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint481builder # [ 0.344271] Asymmetric key parser 'x509' registered482server # [ 0.369165] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]483builder # [ 0.344328] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)484server # [ 0.369182] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]485server # [ 0.369212] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]486server # [ 0.369673] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint487server # [ 0.369860] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]488server # [ 0.369877] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]489builder # [ 0.349500] io scheduler mq-deadline registered490server # [ 0.369906] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]491builder # [ 0.349514] io scheduler kyber registered492server # [ 0.370353] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint493server # [ 0.370536] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]494server # [ 0.370552] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]495server # [ 0.370581] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]496server # [ 0.371042] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint497server # [ 0.371224] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]498server # [ 0.371240] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]499server # [ 0.371270] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]500server # [ 0.371286] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]501server # [ 0.371740] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint502server # [ 0.371927] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]503server # [ 0.371957] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]504builder # [ 0.361609] pl061_gpio 9030000.pl061: PL061 GPIO chip registered505builder # [ 0.362983] ledtrig-cpu: registered to indicate activity on CPUs506builder # [ 0.363366] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:507builder # [ 0.363384] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000508builder # [ 0.363398] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000509server # [ 0.416510] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint510builder # [ 0.363407] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000511server # [ 0.416720] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]512builder # [ 0.363427] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits513server # [ 0.416752] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]514builder # [ 0.363450] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]515server # [ 0.417154] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint516builder # [ 0.363528] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00517server # [ 0.417341] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]518builder # [ 0.363538] pci_bus 0000:00: root bus resource [bus 00-ff]519server # [ 0.417590] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint520builder # [ 0.363544] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]521server # [ 0.417870] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]522builder # [ 0.363549] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]523server # [ 0.417888] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]524builder # [ 0.363554] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]525server # [ 0.417919] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]526builder # [ 0.363609] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint527server # [ 0.418386] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint528server # [ 0.418574] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]529builder # [ 0.364046] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint530builder # [ 0.364235] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]531server # [ 0.418590] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]532builder # [ 0.364251] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]533server # [ 0.418620] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534builder # [ 0.364281] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535server # [ 0.419215] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned536builder # [ 0.364298] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]537server # [ 0.419227] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned538builder # [ 0.364757] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint539server # [ 0.419234] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned540builder # [ 0.364942] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]541server # [ 0.419280] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned542builder # [ 0.364958] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]543server # [ 0.419330] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned544builder # [ 0.364988] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]545server # [ 0.419379] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned546builder # [ 0.385121] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint547server # [ 0.419427] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned548builder # [ 0.385306] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]549builder # [ 0.385323] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]550server # [ 0.419476] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned551builder # [ 0.385353] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552server # [ 0.419526] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned553server # [ 0.419576] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned554builder # [ 0.389053] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint555builder # [ 0.389239] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]556server # [ 0.419625] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned557builder # [ 0.389255] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]558server # [ 0.419673] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned559builder # [ 0.389285] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560server # [ 0.419734] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned561server # [ 0.419781] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned562server # [ 0.419804] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned563server # [ 0.419826] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned564server # [ 0.419849] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned565server # [ 0.419871] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned566server # [ 0.419894] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned567server # [ 0.419920] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned568server # [ 0.419943] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned569server # [ 0.419965] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned570server # [ 0.419988] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned571server # [ 0.420011] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned572builder # [ 0.397854] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint573builder # [ 0.398059] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]574server # [ 0.420034] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned575builder # [ 0.398076] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]576server # [ 0.420057] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned577server # [ 0.420079] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned578builder # [ 0.398106] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]579server # [ 0.420101] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned580builder # [ 0.398606] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint581server # [ 0.420124] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned582builder # [ 0.398790] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]583server # [ 0.420146] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned584builder # [ 0.398806] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]585server # [ 0.420176] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned586builder # [ 0.398836] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]587server # [ 0.420199] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned588builder # [ 0.399291] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint589server # [ 0.420222] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned590builder # [ 0.399475] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]591builder # [ 0.399491] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]592builder # [ 0.399521] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]593builder # [ 0.399538] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]594builder # [ 0.399994] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint595builder # [ 0.400181] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]596builder # [ 0.400211] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]597builder # [ 0.400667] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint598builder # [ 0.400854] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]599builder # [ 0.400884] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]600server # [ 0.464297] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned601server # [ 0.464344] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]602builder # [ 0.401274] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint603server # [ 0.464355] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]604server # [ 0.464361] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]605server # [ 0.465549] pci 0000:00:0a.0: enabling device (0000 -> 0002)606builder # [ 0.418219] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]607builder # [ 0.418564] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint608builder # [ 0.418826] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]609builder # [ 0.418844] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]610builder # [ 0.418874] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]611builder # [ 0.419342] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint612builder # [ 0.419528] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]613builder # [ 0.419544] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]614builder # [ 0.419574] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]615builder # [ 0.420162] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned616builder # [ 0.420174] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned617builder # [ 0.420180] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned618builder # [ 0.420227] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned619builder # [ 0.420274] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned620builder # [ 0.420321] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned621builder # [ 0.420368] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned622builder # [ 0.420414] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned623builder # [ 0.420462] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned624builder # [ 0.420509] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned625builder # [ 0.420557] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned626builder # [ 0.420603] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned627builder # [ 0.420664] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned628builder # [ 0.420709] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned629builder # [ 0.420731] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned630builder # [ 0.420752] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned631builder # [ 0.420774] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned632builder # [ 0.420795] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned633server # [ 0.494020] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)634builder # [ 0.420817] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned635builder # [ 0.420841] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned636builder # [ 0.420863] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned637builder # [ 0.420885] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned638builder # [ 0.420907] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned639builder # [ 0.420930] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned640builder # [ 0.420952] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned641builder # [ 0.420974] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned642builder # [ 0.420995] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned643builder # [ 0.421017] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned644builder # [ 0.421038] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned645builder # [ 0.421059] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned646builder # [ 0.421080] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned647builder # [ 0.421102] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned648builder # [ 0.421123] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned649builder # [ 0.421146] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned650server # [ 0.500438] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)651builder # [ 0.421172] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]652server # [ 0.502427] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)653builder # [ 0.421182] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]654server # [ 0.504531] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)655builder # [ 0.421186] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]656builder # [ 0.466473] pci 0000:00:0a.0: enabling device (0000 -> 0002)657server # [ 0.516417] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)658server # [ 0.532423] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)659builder # [ 0.487083] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)660server # [ 0.534479] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)661builder # [ 0.489282] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)662server # [ 0.536527] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)663server # [ 0.538540] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)664server # [ 0.548396] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)665builder # [ 0.500361] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)666server # [ 0.550855] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)667server # [ 0.565100] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled668builder # [ 0.514704] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)669builder # [ 0.516650] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)670builder # [ 0.520504] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)671server # [ 0.573463] msm_serial: driver initialized672server # [ 0.573606] SuperH (H)SCI(F) driver initialized673server # [ 0.573658] STM32 USART driver initialized674builder # [ 0.523395] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)675builder # [ 0.533669] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)676builder # [ 0.535731] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)677builder # [ 0.545633] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)678builder # [ 0.548188] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)679server # [ 0.602991] loop: module loaded680server # [ 0.603154] virtio_blk virtio5: 1/0/0 default/read/poll queues681server # [ 0.603914] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)682builder # [ 0.558874] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled683server # [ 0.608769] megasas: 07.734.00.00-rc1684server # [ 0.609461] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685builder # [ 0.561473] msm_serial: driver initialized686builder # [ 0.561623] SuperH (H)SCI(F) driver initialized687builder # [ 0.561675] STM32 USART driver initialized688server # [ 0.621226] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000689server # [ 0.621281] Intel/Sharp Extended Query Table at 0x0031690server # [ 0.622835] Using buffer write method691server # [ 0.622894] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]692server # [ 0.638095] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000693server # [ 0.638124] Intel/Sharp Extended Query Table at 0x0031694server # [ 0.639800] Using buffer write method695server # [ 0.639828] Concatenating MTD devices:696server # [ 0.639832] (0): "0.flash"697server # [ 0.639836] (1): "0.flash"698server # [ 0.639839] into device "0.flash"699builder # [ 0.595762] loop: module loaded700builder # [ 0.595951] virtio_blk virtio5: 1/0/0 default/read/poll queues701builder # [ 0.596636] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)702builder # [ 0.602101] megasas: 07.734.00.00-rc1703builder # [ 0.602776] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]704builder # [ 0.604990] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000705builder # [ 0.605014] Intel/Sharp Extended Query Table at 0x0031706builder # [ 0.614431] Using buffer write method707builder # [ 0.614500] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]708builder # [ 0.617016] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000709builder # [ 0.617040] Intel/Sharp Extended Query Table at 0x0031710builder # [ 0.630462] Using buffer write method711builder # [ 0.630496] Concatenating MTD devices:712builder # [ 0.630500] (0): "0.flash"713builder # [ 0.630504] (1): "0.flash"714builder # [ 0.630508] into device "0.flash"715server # [ 0.852515] Freeing initrd memory: 25520K716server # [ 0.858385] tun: Universal TUN/TAP device driver, 1.6717server # [ 0.861931] thunder_xcv, ver 1.0718server # [ 0.861972] thunder_bgx, ver 1.0719server # [ 0.861996] nicpf, ver 1.0720server # [ 0.862529] e1000: Intel(R) PRO/1000 Network Driver721server # [ 0.862536] e1000: Copyright (c) 1999-2006 Intel Corporation.722server # [ 0.862565] e1000e: Intel(R) PRO/1000 Network Driver723server # [ 0.862573] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.724server # [ 0.862604] igb: Intel(R) Gigabit Ethernet Network Driver725server # [ 0.862610] igb: Copyright (c) 2007-2014 Intel Corporation.726server # [ 0.862634] igbvf: Intel(R) Gigabit Virtual Function Network Driver727server # [ 0.862640] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.728server # [ 0.862768] sky2: driver version 1.30729server # [ 0.864862] ehci-pci 0000:00:0a.0: EHCI Host Controller730server # [ 0.864887] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1731server # [ 0.865085] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000732server # [ 0.874619] usbcore: registered new interface driver usb-storage733server # [ 0.874667] usbcore: registered new interface driver usbserial_generic734server # [ 0.874681] usbserial: USB Serial support registered for generic735server # [ 0.875249] hv_vmbus: registering driver hyperv_keyboard736server # [ 0.878857] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00737server # [ 0.879155] hub 1-0:1.0: USB hub found738server # [ 0.879175] hub 1-0:1.0: 6 ports detected739server # [ 0.881462] rtc-pl031 9010000.pl031: registered as rtc0740server # [ 0.881489] rtc-pl031 9010000.pl031: setting system clock to 2026-07-08T22:01:17 UTC (1783548077)741server # [ 0.881787] i2c_dev: i2c /dev entries driver742server # [ 0.886606] sdhci: Secure Digital Host Controller Interface driver743server # [ 0.886616] sdhci: Copyright(c) Pierre Ossman744server # [ 0.886871] Synopsys Designware Multimedia Card Interface Driver745server # [ 0.887222] sdhci-pltfm: SDHCI platform and OF driver helper746server # [ 0.891605] hid: raw HID events driver (C) Jiri Kosina747server # [ 0.891842] usbcore: registered new interface driver usbhid748server # [ 0.891849] usbhid: USB HID core driver749builder # [ 0.843730] Freeing initrd memory: 25520K750builder # [ 0.849532] tun: Universal TUN/TAP device driver, 1.6751builder # [ 0.853059] thunder_xcv, ver 1.0752builder # [ 0.853103] thunder_bgx, ver 1.0753builder # [ 0.853126] nicpf, ver 1.0754builder # [ 0.854892] e1000: Intel(R) PRO/1000 Network Driver755builder # [ 0.854904] e1000: Copyright (c) 1999-2006 Intel Corporation.756builder # [ 0.854934] e1000e: Intel(R) PRO/1000 Network Driver757builder # [ 0.854942] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.758builder # [ 0.854973] igb: Intel(R) Gigabit Ethernet Network Driver759builder # [ 0.854979] igb: Copyright (c) 2007-2014 Intel Corporation.760builder # [ 0.855004] igbvf: Intel(R) Gigabit Virtual Function Network Driver761builder # [ 0.855010] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.762builder # [ 0.855143] sky2: driver version 1.30763server # [ 0.912531] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available764builder # [ 0.856689] usbcore: registered new interface driver usb-storage765server # [ 0.914009] drop_monitor: Initializing network drop monitor service766server # [ 0.914148] NET: Registered PF_INET6 protocol family767builder # [ 0.856736] usbcore: registered new interface driver usbserial_generic768builder # [ 0.856750] usbserial: USB Serial support registered for generic769server # [ 0.917280] Segment Routing with IPv6770server # [ 0.917309] In-situ OAM (IOAM) with IPv6771builder # [ 0.857308] hv_vmbus: registering driver hyperv_keyboard772server # [ 0.917337] NET: Registered PF_PACKET protocol family773builder # [ 0.858345] ehci-pci 0000:00:0a.0: EHCI Host Controller774builder # [ 0.858369] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1775builder # [ 0.858559] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000776server # [ 0.919005] 9pnet: Installing 9P2000 support777builder # [ 0.870003] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00778builder # [ 0.870306] hub 1-0:1.0: USB hub found779builder # [ 0.870325] hub 1-0:1.0: 6 ports detected780server # [ 0.921679] Key type dns_resolver registered781builder # [ 0.873118] rtc-pl031 9010000.pl031: registered as rtc0782builder # [ 0.873145] rtc-pl031 9010000.pl031: setting system clock to 2026-07-08T22:01:17 UTC (1783548077)783builder # [ 0.873448] i2c_dev: i2c /dev entries driver784server # [ 0.927886] registered taskstats version 1785server # [ 0.928024] Loading compiled-in X.509 certificates786builder # [ 0.878261] sdhci: Secure Digital Host Controller Interface driver787builder # [ 0.878270] sdhci: Copyright(c) Pierre Ossman788builder # [ 0.878521] Synopsys Designware Multimedia Card Interface Driver789builder # [ 0.878872] sdhci-pltfm: SDHCI platform and OF driver helper790builder # [ 0.880445] hid: raw HID events driver (C) Jiri Kosina791builder # [ 0.880671] usbcore: registered new interface driver usbhid792builder # [ 0.880678] usbhid: USB HID core driver793server # [ 0.936109] Demotion targets for Node 0: null794server # [ 0.936994] Key type .fscrypt registered795server # [ 0.937004] Key type fscrypt-provisioning registered796server # [ 0.937099] ima: No TPM chip found, activating TPM-bypass!797server # [ 0.937118] ima: Allocated hash algorithm: sha1798builder # [ 0.886174] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available799server # [ 0.937139] ima: No architecture policies found800builder # [ 0.887658] drop_monitor: Initializing network drop monitor service801builder # [ 0.887819] NET: Registered PF_INET6 protocol family802server # [ 0.940678] input: gpio-keys as /devices/platform/gpio-keys/input/input0803builder # [ 0.890912] Segment Routing with IPv6804builder # [ 0.890931] In-situ OAM (IOAM) with IPv6805builder # [ 0.890963] NET: Registered PF_PACKET protocol family806builder # [ 0.892610] 9pnet: Installing 9P2000 support807builder # [ 0.895452] Key type dns_resolver registered808builder # [ 0.901677] registered taskstats version 1809builder # [ 0.901834] Loading compiled-in X.509 certificates810server # [ 0.958150] clk: Disabling unused clocks811server # [ 0.958176] PM: genpd: Disabling unused power domains812builder # [ 0.909889] Demotion targets for Node 0: null813builder # [ 0.909983] Key type .fscrypt registered814builder # [ 0.909990] Key type fscrypt-provisioning registered815builder # [ 0.910077] ima: No TPM chip found, activating TPM-bypass!816server # [ 0.962555] Freeing unused kernel memory: 4736K817builder # [ 0.910096] ima: Allocated hash algorithm: sha1818server # [ 0.962774] Run /init as init process819builder # [ 0.910115] ima: No architecture policies found820builder # [ 0.914131] input: gpio-keys as /devices/platform/gpio-keys/input/input0821server # [ 0.978679] systemd[1]: Successfully made /usr/ read-only.822builder # [ 0.931285] clk: Disabling unused clocks823builder # [ 0.931308] PM: genpd: Disabling unused power domains824builder # [ 0.935637] Freeing unused kernel memory: 4736K825builder # [ 0.935852] Run /init as init process826builder # [ 0.951581] systemd[1]: Successfully made /usr/ read-only.827server # [ 1.136303] usb 1-1: new high-speed USB device number 2 using ehci-pci828builder # [ 1.117525] usb 1-1: new high-speed USB device number 2 using ehci-pci829server # [ 1.288468] 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/input1830server # [ 1.313655] 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)831builder # [ 1.269733] 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/input1832server # [ 1.326336] systemd[1]: Detected virtualization qemu.833server # [ 1.328612] systemd[1]: Detected architecture arm64.834server # [ 1.330576] systemd[1]: Running in initrd.835server # [ 1.333450] systemd[1]: Initializing machine ID from random generator.836server # [ 1.336589] systemd[1]: Hostname set to <server>.837builder # [ 1.286560] 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)838builder # [ 1.298955] systemd[1]: Detected virtualization qemu.839builder # [ 1.301041] systemd[1]: Detected architecture arm64.840builder # [ 1.303078] systemd[1]: Running in initrd.841builder # [ 1.305869] systemd[1]: Initializing machine ID from random generator.842builder # [ 1.308667] systemd[1]: Hostname set to <builder>.843server # [ 1.380635] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0844builder # [ 1.357777] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0845server # [ 1.452692] systemd[1]: Queued start job for default target Initrd Default Target.846server # [ 1.462252] systemd[1]: Created slice Slice /system/modprobe.847server # [ 1.463405] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.848server # [ 1.464755] systemd[1]: Expecting device /dev/disk/by-label/nixos...849server # [ 1.465778] systemd[1]: Reached target Path Units.850server # [ 1.466569] systemd[1]: Reached target Slice Units.851server # [ 1.467379] systemd[1]: Reached target Swaps.852server # [ 1.468116] systemd[1]: Reached target Timer Units.853server # [ 1.469122] systemd[1]: Listening on D-Bus System Message Bus Socket.854server # [ 1.470321] systemd[1]: Listening on Journal Socket (/dev/log).855server # [ 1.471448] systemd[1]: Listening on Journal Sockets.856server # [ 1.472433] systemd[1]: Listening on udev Control Socket.857server # [ 1.472569] systemd[1]: Listening on udev Kernel Socket.858server # [ 1.472591] systemd[1]: Reached target Socket Units.859server # [ 1.476357] systemd[1]: Starting Create List of Static Device Nodes...860builder # [ 1.425869] systemd[1]: Queued start job for default target Initrd Default Target.861server # [ 1.484411] systemd[1]: Starting Load Kernel Module 9pnet_virtio...862server # [ 1.485569] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs863builder # [ 1.437723] systemd[1]: Created slice Slice /system/modprobe.864builder # [ 1.438895] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.865builder # [ 1.440211] systemd[1]: Expecting device /dev/disk/by-label/nixos...866server # [ 1.491437] systemd[1]: Mounting Kernel Configuration File System...867builder # [ 1.441280] systemd[1]: Reached target Path Units.868builder # [ 1.442174] systemd[1]: Reached target Slice Units.869builder # [ 1.442996] systemd[1]: Reached target Swaps.870builder # [ 1.443743] systemd[1]: Reached target Timer Units.871builder # [ 1.444706] systemd[1]: Listening on D-Bus System Message Bus Socket.872builder # [ 1.445987] systemd[1]: Listening on Journal Socket (/dev/log).873builder # [ 1.447132] systemd[1]: Listening on Journal Sockets.874builder # [ 1.448114] systemd[1]: Listening on udev Control Socket.875builder # [ 1.449119] systemd[1]: Listening on udev Kernel Socket.876builder # [ 1.450085] systemd[1]: Reached target Socket Units.877builder # [ 1.452432] systemd[1]: Starting Create List of Static Device Nodes...878server # [ 1.504287] usb 1-2: new high-speed USB device number 3 using ehci-pci879server # [ 1.508752] systemd[1]: Starting Journal Service...880builder # [ 1.461667] systemd[1]: Starting Load Kernel Module 9pnet_virtio...881builder # [ 1.461770] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs882builder # [ 1.469768] systemd[1]: Mounting Kernel Configuration File System...883server # [ 1.521200] systemd[1]: Starting Load Kernel Modules...884server # [ 1.522199] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki885builder # [ 1.481530] usb 1-2: new high-speed USB device number 3 using ehci-pci886server # [ 1.536618] systemd[1]: Starting Coldplug All udev Devices...887builder # [ 1.489873] systemd[1]: Starting Journal Service...888server # [ 1.548433] systemd[1]: Finished Create List of Static Device Nodes.889server # [ 1.549263] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.890server # [ 1.549557] systemd[1]: Finished Load Kernel Module 9pnet_virtio.891builder # [ 1.505670] systemd[1]: Starting Load Kernel Modules...892builder # [ 1.506563] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki893builder # [ 1.514494] systemd[1]: Starting Coldplug All udev Devices...894server # [ 1.570092] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.895server # [ 1.573789] systemd-journald[73]: Collecting audit messages is disabled.896server # [ 1.576447] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...897server # [ 1.584310] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev898server # [ 1.588695] systemd[1]: Mounted Kernel Configuration File System.899builder # [ 1.537574] systemd[1]: Finished Create List of Static Device Nodes.900builder # [ 1.538701] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.901builder # [ 1.539004] systemd[1]: Finished Load Kernel Module 9pnet_virtio.902builder # [ 1.539233] systemd[1]: Mounted Kernel Configuration File System.903server # [ 1.592996] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0904server # [ 1.593233] [drm] features: -virgl +edid -resource_blob -host_visible905builder # [ 1.544062] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...906server # [ 1.593243] [drm] features: -context_init907server # [ 1.593985] [drm] number of scanouts: 1908server # [ 1.594004] [drm] number of cap sets: 0909server # [ 1.604866] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic910server # [ 1.604885] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0911builder # [ 1.569336] systemd-journald[73]: Collecting audit messages is disabled.912server # [ 1.620877] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.913server # [ 1.624724] systemd[1]: Starting Create Static Device Nodes in /dev...914builder # [ 1.573898] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.915builder # [ 1.585544] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev916builder # [ 1.593174] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0917builder # [ 1.593418] [drm] features: -virgl +edid -resource_blob -host_visible918builder # [ 1.593428] [drm] features: -context_init919server # [ 1.641568] Console: switching to colour frame buffer device 160x50920builder # [ 1.606190] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.921builder # [ 1.610205] [drm] number of scanouts: 1922builder # [ 1.610232] [drm] number of cap sets: 0923builder # [ 1.611789] systemd[1]: Starting Create Static Device Nodes in /dev...924builder # [ 1.613852] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic925builder # [ 1.613868] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0926server # [ 1.670536] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device927server # [ 1.674620] 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/input2928server # [ 1.677383] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0929server # [ 1.679911] systemd[1]: Finished Create Static Device Nodes in /dev.930server # [ 1.680111] systemd[1]: Reached target Preparation for Local File Systems.931server # [ 1.680146] systemd[1]: Reached target Local File Systems.932server # [ 1.685452] systemd[1]: Starting Rule-based Manager for Device Events and Files...933server # [ 1.696345] systemd[1]: Finished Load Kernel Modules.934builder # [ 1.641854] Console: switching to colour frame buffer device 160x50935server # [ 1.710843] systemd[1]: Starting Apply Kernel Variables...936builder # [ 1.664799] 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/input2937builder # [ 1.664926] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0938builder # [ 1.670002] systemd[1]: Finished Create Static Device Nodes in /dev.939builder # [ 1.670156] systemd[1]: Reached target Preparation for Local File Systems.940builder # [ 1.670178] systemd[1]: Reached target Local File Systems.941builder # [ 1.677775] systemd[1]: Starting Rule-based Manager for Device Events and Files...942builder # [ 1.686082] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device943builder # [ 1.697957] systemd[1]: Finished Load Kernel Modules.944server # [ 1.756550] systemd[1]: Finished Apply Kernel Variables.945builder # [ 1.713621] systemd[1]: Starting Apply Kernel Variables...946server # [ 1.776432] systemd[1]: Started Journal Service.947server # [ 1.760303] systemd-modules-load[74]: Inserted module 'dm_mod'948server # [ 1.764425] systemd-modules-load[74]: Module 'virtio_balloon' is built in949server # [ 1.765522] systemd-modules-load[74]: Module 'virtio_console' is built in950server # [ 1.766554] systemd-modules-load[74]: Inserted module 'virtio_gpu'951server # [ 1.767555] systemd-modules-load[74]: Module 'virtio_rng' is built in952builder # [ 1.746003] systemd[1]: Finished Apply Kernel Variables.953server # [ 1.780352] systemd[1]: Starting Create System Files and Directories...954server # [ 1.781665] systemd-udevd[81]: Using default interface naming scheme 'v260'.955builder # [ 1.770527] systemd[1]: Started Journal Service.956builder # [ 1.763954] systemd-modules-load[74]: Inserted module 'dm_mod'957server # [ 1.812750] systemd[1]: Finished Create System Files and Directories.958builder # [ 1.768350] systemd-modules-load[74]: Module 'virtio_balloon' is built in959builder # [ 1.769423] systemd-modules-load[74]: Module 'virtio_console' is built in960builder # [ 1.770458] systemd-modules-load[74]: Inserted module 'virtio_gpu'961builder # [ 1.771455] systemd-modules-load[74]: Module 'virtio_rng' is built in962server # [ 1.823598] systemd[1]: Started Rule-based Manager for Device Events and Files.963builder # [ 1.781624] systemd-udevd[80]: Using default interface naming scheme 'v260'.964builder # [ 1.782742] systemd[1]: Starting Create System Files and Directories...965builder # [ 1.805377] systemd[1]: Finished Create System Files and Directories.966builder # [ 1.822781] systemd[1]: Started Rule-based Manager for Device Events and Files.967server # [ 1.888118] systemd[1]: Starting Virtual Console Setup...968builder # [ 1.889609] systemd[1]: Starting Virtual Console Setup...969server # [ 1.960508] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.970server # [ 1.968171] systemd[1]: Finished Virtual Console Setup.971builder # [ 1.960482] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.972builder # [ 1.972272] systemd[1]: Finished Virtual Console Setup.973server # [ 2.659636] systemd[1]: Finished Coldplug All udev Devices.974server # [ 2.660643] systemd[1]: Reached target System Initialization.975server # [ 2.661456] systemd[1]: Reached target Basic System.976builder # [ 2.660125] systemd[1]: Finished Coldplug All udev Devices.977builder # [ 2.661050] systemd[1]: Reached target System Initialization.978builder # [ 2.661849] systemd[1]: Reached target Basic System.979server # [ 2.804526] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.980server # [ 2.846343] (udev-worker)[103]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.981server # [ 2.852243] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.982builder # [ 2.811594] (udev-worker)[94]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.983server # [ 2.883422] systemd[1]: Found device /dev/disk/by-label/nixos.984server # [ 2.885214] systemd[1]: Reached target Initrd Root Device.985builder # [ 2.839435] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.986server # [ 2.888658] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...987builder # [ 2.848454] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.988server # [ 2.930339] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks989server # [ 2.936120] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.990builder # [ 2.888986] systemd[1]: Found device /dev/disk/by-label/nixos.991server # [ 2.939464] systemd[1]: Mounting /sysroot...992builder # [ 2.893254] systemd[1]: Reached target Initrd Root Device.993builder # [ 2.896753] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...994builder # [ 2.938480] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks995server # [ 3.007641] EXT4-fs (vda): mounted filesystem 47c35091-0e2c-4ddb-a6f4-2b8efff440d4 r/w with ordered data mode. Quota mode: none.996builder # [ 2.944149] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.997server # [ 2.993274] systemd[1]: Mounted /sysroot.998builder # [ 2.946486] systemd[1]: Mounting /sysroot...999server # [ 2.995093] systemd[1]: Reached target Initrd Root File System.1000server # [ 3.000427] systemd[1]: Mounting /sysroot/nix/.ro-store...1001server # [ 3.016157] systemd[1]: Mounting /sysroot/nix/.rw-store...1002server # [ 3.020931] systemd[1]: Mounting /sysroot/run...1003builder # [ 3.007345] EXT4-fs (vda): mounted filesystem c50e52c7-988d-4e9c-a97e-239fd5e48fe1 r/w with ordered data mode. Quota mode: none.1004server # [ 3.040095] systemd[1]: Mounting /sysroot/tmp/shared...1005builder # [ 2.994917] systemd[1]: Mounted /sysroot.1006builder # [ 2.998945] systemd[1]: Reached target Initrd Root File System.1007server # [ 3.055390] systemd[1]: Mounting /sysroot/tmp/xchg...1008builder # [ 3.008870] systemd[1]: Starting Mountpoints Configured in the Real Root...1009builder # [ 3.025162] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.1010builder # [ 3.031613] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...1011server # [ 3.081162] systemd[1]: Starting Mountpoints Configured in the Real Root...1012server # [ 3.083652] systemd[1]: Mounted /sysroot/nix/.rw-store.1013builder # [ 3.036647] systemd[1]: Reloading...1014server # [ 3.095438] systemd[1]: Mounted /sysroot/nix/.ro-store.1015server # [ 3.106100] systemd[1]: Mounted /sysroot/run.1016server # [ 3.123358] systemd[1]: Mounted /sysroot/tmp/shared.1017server # [ 3.126501] systemd-sysroot-fstab-check[126]: /sysroot should be mounted in the initrd, will request daemon-reload.1018server # [ 3.132874] systemd[1]: Starting rw-sysroot-nix-store.service...1019server # [ 3.144471] systemd[1]: Reload requested from client PID 126 ('systemd-sysroot') (unit initrd-parse-etc.service)...1020server # [ 3.151096] systemd[1]: Reloading...1021builder # [ 3.254367] systemd[1]: Reloading finished in 217 ms.1022builder # [ 3.262567] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...1023builder # [ 3.266249] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1024builder # [ 3.272683] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1025builder # [ 3.276265] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1026builder # [ 3.281378] systemd[1]: Finished Mountpoints Configured in the Real Root.1027builder # [ 3.282653] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1028builder # [ 3.302547] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1029builder # [ 3.304795] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1030server # [ 3.374178] systemd[1]: Reloading finished in 227 ms.1031server # [ 3.395385] systemd-sysroot-fstab-check[126]: Requesting initrd-fs.target/start/replace...1032server # [ 3.399145] systemd[1]: Mounted /sysroot/tmp/xchg.1033server # [ 3.401080] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1034server # [ 3.403130] systemd[1]: Finished rw-sysroot-nix-store.service.1035server # [ 3.407829] systemd-sysroot-fstab-check[126]: Requesting swap.target/start/replace...1036server # [ 3.412788] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1037server # [ 3.414791] systemd[1]: Finished Mountpoints Configured in the Real Root.1038server # [ 3.419328] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1039server # [ 3.425802] systemd[1]: Starting rw-sysroot-nix-store.service...1040server # [ 3.449692] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1041server # [ 3.450758] systemd[1]: Finished rw-sysroot-nix-store.service.1042server # [ 3.518445] systemd[1]: Mounting /sysroot/nix/store...1043server # [ 3.555576] systemd[1]: Mounted /sysroot/nix/store.1044server # [ 3.557284] systemd[1]: Reached target Initrd File Systems.1045builder # [ 3.511982] systemd[1]: Mounting /sysroot/nix/.ro-store...1046server # [ 3.561082] systemd[1]: Starting Find NixOS closure...1047builder # [ 3.524131] systemd[1]: Mounting /sysroot/nix/.rw-store...1048server # [ 3.571777] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1049builder # [ 3.534893] systemd[1]: Mounting /sysroot/run...1050builder # [ 3.552977] systemd[1]: Mounting /sysroot/tmp/shared...1051server # [ 3.606433] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1052server # [ 3.607645] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1053server # [ 3.621576] systemd[1]: Finished Find NixOS closure.1054server # [ 3.624720] systemd[1]: Reached target Initrd Default Target.1055server # [ 3.628820] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1056builder # [ 3.582994] systemd[1]: Mounting /sysroot/tmp/xchg...1057builder # [ 3.597810] systemd[1]: Mounted /sysroot/nix/.rw-store.1058builder # [ 3.599642] systemd[1]: Mounted /sysroot/run.1059builder # [ 3.603086] systemd[1]: Mounted /sysroot/nix/.ro-store.1060server # [ 3.651870] systemd[1]: Stopped target Initrd Default Target.1061server # [ 3.658793] systemd[1]: Stopped target Basic System.1062builder # [ 3.611529] systemd[1]: Mounted /sysroot/tmp/shared.1063server # [ 3.660458] systemd[1]: Stopped target Initrd Root Device.1064server # [ 3.663830] systemd[1]: Stopped target Path Units.1065server # [ 3.667470] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1066server # [ 3.672162] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1067builder # [ 3.625178] systemd[1]: Starting rw-sysroot-nix-store.service...1068server # [ 3.674451] systemd[1]: Stopped target Slice Units.1069server # [ 3.676284] systemd[1]: Stopped target Socket Units.1070server # [ 3.680634] systemd[1]: Stopped target System Initialization.1071builder # [ 3.633572] systemd[1]: Mounted /sysroot/tmp/xchg.1072server # [ 3.682897] systemd[1]: Stopped target Swaps.1073server # [ 3.684111] systemd[1]: Stopped target Timer Units.1074server # [ 3.689123] systemd[1]: dbus.socket: Deactivated successfully.1075server # [ 3.690944] systemd[1]: Closed D-Bus System Message Bus Socket.1076server # [ 3.692997] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1077server # [ 3.696791] systemd[1]: Stopped Find NixOS closure.1078server # [ 3.697553] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1079builder # [ 3.651478] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1080server # [ 3.700603] systemd[1]: Starting rw-sysroot-nix-store.service...1081builder # [ 3.652710] systemd[1]: Finished rw-sysroot-nix-store.service.1082server # [ 3.704858] systemd[1]: systemd-sysctl.service: Deactivated successfully.1083server # [ 3.708249] systemd[1]: Stopped Apply Kernel Variables.1084server # [ 3.715992] systemd[1]: systemd-modules-load.service: Deactivated successfully.1085server # [ 3.722599] systemd[1]: Stopped Load Kernel Modules.1086server # [ 3.723457] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1087server # [ 3.728930] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1088server # [ 3.730065] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1089server # [ 3.731076] systemd[1]: Stopped Create System Files and Directories.1090server # [ 3.731973] systemd[1]: Stopped target Local File Systems.1091server # [ 3.739002] systemd[1]: Stopped target Preparation for Local File Systems.1092server # [ 3.740007] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1093server # [ 3.741091] systemd[1]: Stopped Coldplug All udev Devices.1094server # [ 3.741873] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1095server # [ 3.742913] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1096server # [ 3.743958] systemd[1]: Stopped Virtual Console Setup.1097server # [ 3.749376] systemd[1]: initrd-cleanup.service: Deactivated successfully.1098server # [ 3.752214] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1099server # [ 3.754808] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1100server # [ 3.755859] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1101server # [ 3.757681] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1102server # [ 3.758707] systemd[1]: Finished rw-sysroot-nix-store.service.1103server # [ 3.759524] systemd[1]: systemd-udevd.service: Deactivated successfully.1104server # [ 3.760579] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1105server # [ 3.761558] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1106server # [ 3.762533] systemd[1]: Closed udev Control Socket.1107server # [ 3.763221] systemd[1]: Starting Cleanup udev Database...1108server # [ 3.763978] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1109server # [ 3.772208] systemd[1]: Stopped Create Static Device Nodes in /dev.1110server # [ 3.773120] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1111server # [ 3.774434] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1112server # [ 3.775416] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1113server # [ 3.780288] systemd[1]: Stopped Create List of Static Device Nodes.1114server # [ 3.792312] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1115server # [ 3.796495] systemd[1]: Finished Cleanup udev Database.1116server # [ 3.797317] systemd[1]: Reached target Switch Root.1117server # [ 3.798022] systemd[1]: Starting NixOS Activation...1118server # [ 3.952583] initrd-nixos-activation-start[174]: booting system configuration /nix/store/rgbhlyrymfpybmdrxvl338nnzfczpvqd-nixos-system-server-test1119server # [ 4.011894] initrd-nixos-activation-start[174]: running activation script...1120builder # [ 4.249319] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/k2s978i7i26km4cr56fvm64p2q6mpl02-systemd-260.2/lib/udev/mtd_probe": No such file or directory1121builder # [ 4.256360] (udev-worker)[99]: mtd0ro: /nix/store/k2s978i7i26km4cr56fvm64p2q6mpl02-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1122builder # [ 4.287841] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1123builder # [ 4.291348] systemd[1]: Stopped Virtual Console Setup.1124builder # [ 4.295674] systemd[1]: Stopping Virtual Console Setup...1125builder # [ 4.296612] systemd[1]: Starting Virtual Console Setup...1126builder # [ 4.319424] systemd-vconsole-setup[154]: Configuration of first virtual console was skipped, ignoring remaining ones.1127builder # [ 4.322868] systemd[1]: Finished Virtual Console Setup.1128server # [ 4.404451] initrd-nixos-activation-start[197]: setting up /etc...1129builder # [ 4.514656] systemd[1]: Mounting /sysroot/nix/store...1130builder # [ 4.576891] systemd[1]: Mounted /sysroot/nix/store.1131builder # [ 4.580812] systemd[1]: Reached target Initrd File Systems.1132builder # [ 4.585948] systemd[1]: Starting Find NixOS closure...1133builder # [ 4.594925] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1134server # [ 4.650221] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1135server # [ 4.652984] systemd[1]: Finished NixOS Activation.1136server # [ 4.654111] systemd[1]: Starting Switch Root...1137server # [ 4.674719] systemd[1]: Switching root.1138builder # [ 4.645823] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1139builder # [ 4.652342] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1140builder # [ 4.665194] systemd[1]: Finished Find NixOS closure.1141builder # [ 4.667893] systemd[1]: Reached target Initrd Default Target.1142builder # [ 4.672385] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1143server # [ 4.745874] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1144builder # [ 4.702933] systemd[1]: Stopped target Initrd Default Target.1145builder # [ 4.705122] systemd[1]: Stopped target Basic System.1146builder # [ 4.708386] systemd[1]: Stopped target Initrd Root Device.1147builder # [ 4.710049] systemd[1]: Stopped target Path Units.1148builder # [ 4.713715] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1149builder # [ 4.715843] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1150builder # [ 4.720283] systemd[1]: Stopped target Slice Units.1151builder # [ 4.724196] systemd[1]: Stopped target Socket Units.1152builder # [ 4.725224] systemd[1]: Stopped target System Initialization.1153builder # [ 4.726270] systemd[1]: Stopped target Swaps.1154builder # [ 4.728151] systemd[1]: Stopped target Timer Units.1155builder # [ 4.729722] systemd[1]: dbus.socket: Deactivated successfully.1156builder # [ 4.732351] systemd[1]: Closed D-Bus System Message Bus Socket.1157builder # [ 4.735755] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1158builder # [ 4.737489] systemd[1]: Stopped Find NixOS closure.1159builder # [ 4.741287] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1160builder # [ 4.744161] systemd[1]: Starting rw-sysroot-nix-store.service...1161builder # [ 4.748549] systemd[1]: systemd-sysctl.service: Deactivated successfully.1162builder # [ 4.750956] systemd[1]: Stopped Apply Kernel Variables.1163builder # [ 4.755984] systemd[1]: systemd-modules-load.service: Deactivated successfully.1164builder # [ 4.764477] systemd[1]: Stopped Load Kernel Modules.1165builder # [ 4.766840] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1166builder # [ 4.771103] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1167builder # [ 4.772877] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1168builder # [ 4.774652] systemd[1]: Stopped Create System Files and Directories.1169builder # [ 4.775647] systemd[1]: Stopped target Local File Systems.1170builder # [ 4.783216] systemd[1]: Stopped target Preparation for Local File Systems.1171builder # [ 4.786445] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1172builder # [ 4.791686] systemd[1]: Stopped Coldplug All udev Devices.1173builder # [ 4.792829] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1174builder # [ 4.793856] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1175builder # [ 4.794858] systemd[1]: Stopped Virtual Console Setup.1176builder # [ 4.795584] systemd[1]: initrd-cleanup.service: Deactivated successfully.1177builder # [ 4.799029] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1178builder # [ 4.799980] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1179builder # [ 4.801083] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1180builder # [ 4.801931] systemd[1]: systemd-udevd.service: Deactivated successfully.1181builder # [ 4.802854] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1182builder # [ 4.803845] systemd[1]: systemd-udevd.service: Consumed 1.545s CPU time over 3.124s wall clock time, 22.4M memory peak.1183builder # [ 4.812199] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1184builder # [ 4.813246] systemd[1]: Closed udev Control Socket.1185builder # [ 4.814187] systemd[1]: Starting Cleanup udev Database...1186builder # [ 4.814990] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1187builder # [ 4.820157] systemd[1]: Stopped Create Static Device Nodes in /dev.1188builder # [ 4.821083] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1189builder # [ 4.822219] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1190builder # [ 4.824098] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1191builder # [ 4.828406] systemd[1]: Stopped Create List of Static Device Nodes.1192builder # [ 4.829301] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1193builder # [ 4.830294] systemd[1]: Finished rw-sysroot-nix-store.service.1194builder # [ 4.854418] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1195builder # [ 4.859477] systemd[1]: Finished Cleanup udev Database.1196builder # [ 4.860864] systemd[1]: Reached target Switch Root.1197builder # [ 4.861558] systemd[1]: Starting NixOS Activation...1198builder # [ 5.018024] initrd-nixos-activation-start[179]: booting system configuration /nix/store/iifh79al739z0m3ksihz95rbiqxz7qg4-nixos-system-builder-test1199builder # [ 5.078938] initrd-nixos-activation-start[179]: running activation script...1200server # [ 5.299931] 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)1201server # [ 5.312771] systemd[1]: Detected virtualization qemu.1202server # [ 5.315752] systemd[1]: Detected architecture arm64.1203server # [ 5.318873] systemd[1]: Detected first boot.1204server # [ 5.326490] systemd[1]: Initializing machine ID from random generator.1205builder # [ 5.475729] initrd-nixos-activation-start[202]: setting up /etc...1206server # [ 5.645208] systemd[1]: bpf-restrict-fs: LSM BPF program attached1207builder # [ 5.728171] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1208builder # [ 5.730824] systemd[1]: Finished NixOS Activation.1209builder # [ 5.732001] systemd[1]: Starting Switch Root...1210builder # [ 5.754158] systemd[1]: Switching root.1211server # [ 5.834104] systemd[1]: Applying preset policy.1212builder # [ 5.814997] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1213server # [ 6.345861] systemd[1]: Populated /etc with preset unit settings.1214builder # [ 6.368343] 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)1215builder # [ 6.380732] systemd[1]: Detected virtualization qemu.1216builder # [ 6.384244] systemd[1]: Detected architecture arm64.1217builder # [ 6.386371] systemd[1]: Detected first boot.1218builder # [ 6.392619] systemd[1]: Initializing machine ID from random generator.1219builder # [ 6.699268] systemd[1]: bpf-restrict-fs: LSM BPF program attached1220server # [ 6.850217] systemd[1]: initrd-switch-root.service: Deactivated successfully.1221server # [ 6.851495] systemd[1]: Stopped initrd-switch-root.service.1222server # [ 6.854644] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1223server # [ 6.857653] systemd[1]: Created slice Slice /system/getty.1224server # [ 6.860385] systemd[1]: Created slice User and Session Slice.1225server # [ 6.861627] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1226server # [ 6.863976] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1227server # [ 6.865407] systemd[1]: Expecting device /dev/hvc0...1228server # [ 6.866722] systemd[1]: Expecting device /dev/ttyAMA0...1229server # [ 6.868112] systemd[1]: Reached target Local Encrypted Volumes.1230server # [ 6.869698] systemd[1]: Stopped target initrd-fs.target.1231server # [ 6.870942] systemd[1]: Stopped target initrd-root-fs.target.1232server # [ 6.872432] systemd[1]: Stopped target initrd-switch-root.target.1233server # [ 6.874532] systemd[1]: Reached target Virtual Machines and Containers.1234server # [ 6.875721] systemd[1]: Reached target Path Units.1235server # [ 6.877119] systemd[1]: Reached target Remote File Systems.1236server # [ 6.879171] systemd[1]: Reached target Slice Units.1237server # [ 6.880153] systemd[1]: Reached target Swaps.1238server # [ 6.884057] systemd[1]: Listening on Query the User Interactively for a Password.1239server # [ 6.888638] systemd[1]: Listening on Process Core Dump Socket.1240server # [ 6.892308] systemd[1]: Listening on Credential Encryption/Decryption.1241server # [ 6.895956] systemd[1]: Listening on Factory Reset Management.1242server # [ 6.900904] systemd[1]: Starting Journal Log Access Socket...1243server # [ 6.902831] systemd[1]: Listening on Journal Audit Socket.1244server # [ 6.906838] systemd[1]: Listening on Console Output Muting Service Socket.1245server # [ 6.909309] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1246server # [ 6.911529] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki1247server # [ 6.914013] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1248server # [ 6.924446] systemd[1]: Listening on Disk Repartitioning Service Socket.1249server # [ 6.926498] systemd[1]: Listening on udev Control Socket.1250server # [ 6.928423] systemd[1]: Listening on udev Varlink Socket.1251server # [ 6.932702] systemd[1]: Mounting Huge Pages File System...1252server # [ 6.937623] systemd[1]: Mounting POSIX Message Queue File System...1253server # [ 6.946728] systemd[1]: Mounting Kernel Debug File System...1254server # [ 6.951275] systemd[1]: Mounting Kernel Trace File System...1255builder # [ 6.901239] systemd[1]: Applying preset policy.1256server # [ 6.967949] systemd[1]: Starting Create List of Static Device Nodes...1257server # [ 6.974488] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1258server # [ 6.976366] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1259server # [ 6.983338] systemd[1]: Mounting Kernel Configuration File System...1260server # [ 6.985770] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1261server # [ 6.988616] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1262server # [ 7.006273] systemd[1]: Starting Load Kernel Module fuse...1263server # [ 7.008402] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671264server # [ 7.065967] systemd[1]: Starting Journal Service...1265server # [ 7.100988] systemd[1]: Starting Load Kernel Modules...1266server # [ 7.110101] fuse: init (API version 7.45)1267server # [ 7.128481] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1268server # [ 7.146315] systemd[1]: Starting Remount Root and Kernel File Systems...1269server # [ 7.148882] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1270server # [ 7.169781] systemd[1]: Starting Coldplug All udev Devices...1271server # [ 7.183357] systemd[1]: Listening on Journal Log Access Socket.1272server # [ 7.187285] systemd[1]: Mounted Huge Pages File System.1273server # [ 7.210509] systemd[1]: Mounted POSIX Message Queue File System.1274server # [ 7.213594] systemd[1]: Mounted Kernel Debug File System.1275server # [ 7.214085] systemd[1]: Mounted Kernel Trace File System.1276server # [ 7.221756] systemd[1]: Finished Create List of Static Device Nodes.1277server # [ 7.227292] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1278server # [ 7.231557] systemd-journald[268]: Collecting audit messages is enabled.1279server # [ 7.234595] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1280server # [ 7.240880] systemd[1]: Mounted Kernel Configuration File System.1281server # [ 7.241506] systemd[1]: modprobe@fuse.service: Deactivated successfully.1282server # [ 7.248355] EXT4-fs (vda): re-mounted 47c35091-0e2c-4ddb-a6f4-2b8efff440d4.1283server # [ 7.251377] systemd[1]: Finished Load Kernel Module fuse.1284server # [ 7.237621] systemd[1]: Queued start job for default target Multi-User System.1285server # [ 7.245257] systemd[1]: systemd-journald.service: Deactivated successfully.1286server # [ 7.268640] systemd[1]: Mounting FUSE Control File System...1287server # [ 7.250884] systemd-modules-load[269]: Module 'atkbd' is built in1288server # [ 7.280543] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1289server # [ 7.259929] systemd-modules-load[269]: Module 'loop' is built in1290server # [ 7.284442] systemd[1]: Started Journal Service.1291server # [ 7.272535] systemd-modules-load[269]: Inserted module 'tls'1292server # [ 7.285530] systemd[1]: Finished Load Kernel Modules.1293server # [ 7.288277] systemd[1]: Finished Remount Root and Kernel File Systems.1294server # [ 7.312939] systemd-oomd[270]: No swap; memory pressure usage will be degraded1295server # [ 7.327820] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1296server # [ 7.337489] systemd[1]: Listening on Disk Image Download Service Socket.1297server # [ 7.345790] systemd[1]: Starting Firewall...1298server # [ 7.362128] systemd[1]: Starting Flush Journal to Persistent Storage...1299server # [ 7.366341] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1300server # [ 7.380915] systemd[1]: Starting Load/Save OS Random Seed...1301server # [ 7.415170] systemd[1]: Starting Apply Kernel Variables...1302server # [ 7.416867] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1303server # [ 7.424276] systemd[1]: Mounted FUSE Control File System.1304builder # [ 7.395343] systemd[1]: Populated /etc with preset unit settings.1305server # [ 7.454609] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1306server # [ 7.467858] systemd[1]: Starting Create Static Device Nodes in /dev...1307server # [ 7.516271] systemd-journald[268]: Received client request to flush runtime journal.1308server # [ 7.576701] systemd[1]: Finished Load/Save OS Random Seed.1309server # [ 7.580153] systemd[1]: Reached target First Boot Complete.1310server # [ 7.585831] systemd[1]: Finished Apply Kernel Variables.1311server # [ 7.586854] systemd[1]: Finished Flush Journal to Persistent Storage.1312server # [ 7.628132] systemd[1]: Finished Create Static Device Nodes in /dev.1313server # [ 7.630260] systemd[1]: Reached target Preparation for Local File Systems.1314server # [ 7.634538] systemd[1]: Starting Rule-based Manager for Device Events and Files...1315server # [ 7.841986] systemd[1]: Mounting /run/wrappers...1316server # [ 7.905415] systemd[1]: Mounted /run/wrappers.1317server # [ 7.906630] systemd[1]: Reached target Local File Systems.1318server # [ 7.916256] systemd[1]: Listening on Boot Loader Control Service Socket.1319builder # [ 7.886909] systemd[1]: initrd-switch-root.service: Deactivated successfully.1320builder # [ 7.888188] systemd[1]: Stopped initrd-switch-root.service.1321builder # [ 7.891322] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1322server # [ 7.923149] systemd[1]: Starting register-nix-paths.service...1323builder # [ 7.894040] systemd[1]: Created slice Slice /system/getty.1324builder # [ 7.895904] systemd[1]: Created slice User and Session Slice.1325server # [ 7.927085] systemd[1]: Starting Create SUID/SGID Wrappers...1326builder # [ 7.897144] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1327server # [ 7.928723] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1328builder # [ 7.899561] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1329builder # [ 7.901690] systemd[1]: Expecting device /dev/hvc0...1330builder # [ 7.902690] systemd[1]: Expecting device /dev/ttyAMA0...1331builder # [ 7.904716] systemd[1]: Reached target Local Encrypted Volumes.1332builder # [ 7.905853] systemd[1]: Stopped target initrd-fs.target.1333builder # [ 7.907243] systemd[1]: Stopped target initrd-root-fs.target.1334builder # [ 7.909355] systemd[1]: Stopped target initrd-switch-root.target.1335builder # [ 7.910542] systemd[1]: Reached target Virtual Machines and Containers.1336server # [ 7.941805] systemd[1]: Starting Save Transient machine-id to Disk...1337builder # [ 7.912085] systemd[1]: Reached target Path Units.1338builder # [ 7.913413] systemd[1]: Reached target Remote File Systems.1339server # [ 7.945353] systemd[1]: Starting Create System Files and Directories...1340builder # [ 7.914900] systemd[1]: Reached target Slice Units.1341builder # [ 7.916194] systemd[1]: Reached target Swaps.1342builder # [ 7.920197] systemd[1]: Listening on Query the User Interactively for a Password.1343builder # [ 7.924694] systemd[1]: Listening on Process Core Dump Socket.1344builder # [ 7.928432] systemd[1]: Listening on Credential Encryption/Decryption.1345builder # [ 7.932168] systemd[1]: Listening on Factory Reset Management.1346builder # [ 7.937043] systemd[1]: Starting Journal Log Access Socket...1347builder # [ 7.939095] systemd[1]: Listening on Journal Audit Socket.1348builder # [ 7.943122] systemd[1]: Listening on Console Output Muting Service Socket.1349builder # [ 7.945335] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1350builder # [ 7.947649] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki1351builder # [ 7.950067] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1352builder # [ 7.960606] systemd[1]: Listening on Disk Repartitioning Service Socket.1353builder # [ 7.962786] systemd[1]: Listening on udev Control Socket.1354builder # [ 7.964665] systemd[1]: Listening on udev Varlink Socket.1355builder # [ 7.969063] systemd[1]: Mounting Huge Pages File System...1356builder # [ 7.973995] systemd[1]: Mounting POSIX Message Queue File System...1357builder # [ 7.982562] systemd[1]: Mounting Kernel Debug File System...1358builder # [ 7.991690] systemd[1]: Mounting Kernel Trace File System...1359builder # [ 8.004243] systemd[1]: Starting Create List of Static Device Nodes...1360builder # [ 8.012549] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1361builder # [ 8.017625] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1362builder # [ 8.025711] systemd[1]: Mounting Kernel Configuration File System...1363builder # [ 8.027995] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1364builder # [ 8.034573] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1365server # [ 8.094792] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1366builder # [ 8.078452] systemd[1]: Starting Load Kernel Module fuse...1367server # [ 8.107072] systemd[1]: Finished Save Transient machine-id to Disk.1368builder # [ 8.081280] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671369server # [ 8.130029] systemd-udevd[305]: Using default interface naming scheme 'v260'.1370builder # [ 8.113556] systemd[1]: Starting Journal Service...1371builder # [ 8.137584] systemd[1]: Starting Load Kernel Modules...1372builder # [ 8.145221] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1373builder # [ 8.160605] systemd[1]: Starting Remount Root and Kernel File Systems...1374builder # [ 8.164784] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1375builder # [ 8.196742] fuse: init (API version 7.45)1376builder # [ 8.199379] systemd[1]: Starting Coldplug All udev Devices...1377server # [ 8.231930] systemd[1]: Finished Create System Files and Directories.1378server # [ 8.242934] systemd[1]: Starting Rebuild Journal Catalog...1379server # [ 8.254672] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1380builder # [ 8.222884] systemd-journald[272]: Collecting audit messages is enabled.1381builder # [ 8.237863] systemd[1]: Listening on Journal Log Access Socket.1382builder # [ 8.240081] systemd[1]: Mounted Huge Pages File System.1383builder # [ 8.225265] systemd[1]: Queued start job for default target Multi-User System.1384builder # [ 8.245593] systemd[1]: Started Journal Service.1385builder # [ 8.226723] systemd[1]: systemd-journald.service: Deactivated successfully.1386builder # [ 8.245778] systemd[1]: Mounted POSIX Message Queue File System.1387builder # [ 8.247206] systemd[1]: Mounted Kernel Debug File System.1388builder # [ 8.253181] systemd[1]: Mounted Kernel Trace File System.1389builder # [ 8.257540] systemd[1]: Finished Create List of Static Device Nodes.1390builder # [ 8.264277] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1391builder # [ 8.271071] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1392builder # [ 8.280270] systemd[1]: Mounted Kernel Configuration File System.1393builder # [ 8.286177] systemd[1]: modprobe@fuse.service: Deactivated successfully.1394builder # [ 8.289357] systemd[1]: Finished Load Kernel Module fuse.1395builder # [ 8.294570] systemd-modules-load[273]: Module 'atkbd' is built in1396builder # [ 8.300539] systemd-modules-load[273]: Module 'loop' is built in1397builder # [ 8.305035] systemd[1]: Mounting FUSE Control File System...1398builder # [ 8.307757] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1399builder # [ 8.329694] EXT4-fs (vda): re-mounted c50e52c7-988d-4e9c-a97e-239fd5e48fe1.1400builder # [ 8.322817] systemd[1]: Finished Load Kernel Modules.1401builder # [ 8.325622] systemd-oomd[274]: No swap; memory pressure usage will be degraded1402builder # [ 8.334547] systemd[1]: Starting Firewall...1403builder # [ 8.349735] systemd[1]: Starting Apply Kernel Variables...1404server # [ 8.395248] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1405builder # [ 8.357211] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1406builder # [ 8.358996] systemd[1]: Finished Remount Root and Kernel File Systems.1407builder # [ 8.368904] systemd[1]: Listening on Disk Image Download Service Socket.1408builder # [ 8.395232] systemd[1]: Starting Flush Journal to Persistent Storage...1409server # [ 8.455245] systemd[1]: Started Rule-based Manager for Device Events and Files.1410builder # [ 8.398561] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1411server # [ 8.468515] systemd[1]: Finished Rebuild Journal Catalog.1412builder # [ 8.424133] systemd[1]: Starting Load/Save OS Random Seed...1413builder # [ 8.425158] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1414server # [ 8.481863] systemd[1]: Starting Update is Completed...1415builder # [ 8.482300] systemd[1]: Mounted FUSE Control File System.1416builder # [ 8.542132] systemd-journald[272]: Received client request to flush runtime journal.1417server # [ 8.590692] systemd[1]: Finished Update is Completed.1418builder # [ 8.597624] systemd[1]: Finished Load/Save OS Random Seed.1419builder # [ 8.605875] systemd[1]: Reached target First Boot Complete.1420builder # [ 8.610353] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1421builder # [ 8.616198] systemd[1]: Starting Create Static Device Nodes in /dev...1422builder # [ 8.620432] systemd[1]: Finished Apply Kernel Variables.1423builder # [ 8.621242] systemd[1]: Finished Flush Journal to Persistent Storage.1424builder # [ 8.692098] systemd[1]: Finished Create Static Device Nodes in /dev.1425builder # [ 8.697492] systemd[1]: Reached target Preparation for Local File Systems.1426builder # [ 8.700371] systemd[1]: Starting Rule-based Manager for Device Events and Files...1427builder # [ 8.812396] systemd-udevd[315]: Using default interface naming scheme 'v260'.1428builder # [ 8.874993] systemd[1]: Mounting /run/wrappers...1429builder # [ 8.928410] systemd[1]: Mounted /run/wrappers.1430builder # [ 8.930294] systemd[1]: Reached target Local File Systems.1431builder # [ 8.933780] systemd[1]: Listening on Boot Loader Control Service Socket.1432builder # [ 8.941574] systemd[1]: Starting register-nix-paths.service...1433builder # [ 8.950693] systemd[1]: Starting Create SUID/SGID Wrappers...1434builder # [ 8.952852] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1435builder # [ 8.977600] systemd[1]: Starting Save Transient machine-id to Disk...1436builder # [ 8.981320] systemd[1]: Starting Create System Files and Directories...1437server # [ 9.055008] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1438server # [ 9.058669] systemd[1]: Finished Create SUID/SGID Wrappers.1439builder # [ 9.098071] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1440builder # [ 9.113589] systemd[1]: Finished Save Transient machine-id to Disk.1441builder # [ 9.163358] systemd[1]: Started Rule-based Manager for Device Events and Files.1442builder # [ 9.263514] systemd[1]: Finished Create System Files and Directories.1443builder # [ 9.277686] systemd[1]: Starting Rebuild Journal Catalog...1444builder # [ 9.281991] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1445server # [ 9.389378] systemd[1]: Finished register-nix-paths.service.1446builder # [ 9.413181] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1447builder # [ 9.477605] systemd[1]: Finished Rebuild Journal Catalog.1448builder # [ 9.488846] systemd[1]: Starting Update is Completed...1449builder # [ 9.569139] systemd[1]: Finished Update is Completed.1450server # [ 9.748769] systemd[1]: Finished Firewall.1451server # [ 9.785527] systemd[1]: Finished Coldplug All udev Devices.1452server # [ 9.786699] systemd[1]: Reached target System Initialization.1453server # [ 9.789305] systemd[1]: Started Discard unused filesystem blocks once a week.1454server # [ 9.790389] systemd[1]: Started niks3 garbage collection timer.1455server # [ 9.791653] systemd[1]: Started Daily Cleanup of Temporary Directories.1456server # [ 9.792720] systemd[1]: Reached target Timer Units.1457server # [ 9.798698] systemd[1]: Listening on D-Bus System Message Bus Socket.1458server # [ 9.799713] systemd[1]: Listening on niks3 server socket.1459server # [ 9.800728] systemd[1]: Listening on Nix Daemon Socket.1460server # [ 9.804390] systemd[1]: Listening on Hostname Service Socket.1461server # [ 9.806935] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1462server # [ 9.810089] systemd[1]: Reached target Socket Units.1463server # [ 9.814669] systemd[1]: Reached target Basic System.1464server # [ 9.816349] systemd[1]: Starting Import lastlog data into lastlog2 database...1465server # [ 9.820757] systemd[1]: Starting Generate test mTLS certs...1466server # [ 9.824164] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1467server # [ 9.836127] systemd[1]: Starting Post-Boot Actions...1468server # [ 9.861974] systemd[1]: Started Reset console on configuration changes.1469server # [ 9.883855] systemd[1]: Starting resolvconf update...1470server # [ 9.928529] systemd[1]: Starting D-Bus System Message Bus...1471builder # [ 9.883601] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1472builder # [ 9.889465] systemd[1]: Finished Create SUID/SGID Wrappers.1473server # [ 9.972452] systemd[1]: Finished Post-Boot Actions.1474server # [ 9.985578] systemd[1]: Started Name Service Cache Daemon (nsncd).1475server # [ 9.989999] nsncd[497]: Jul 08 22:01:26.624 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1476server # [ 10.002946] niks3-test-certs-start[504]: -----1477server # [ 10.025836] systemd[1]: Reached target Host and Network Name Lookups.1478server # [ 10.032965] systemd[1]: Reached target User and Group Name Lookups.1479server # [ 10.038151] niks3-test-certs-start[517]: -----1480server # [ 10.057121] systemd[1]: Starting User Login Management...1481server # [ 10.092611] systemd[1]: Finished Import lastlog data into lastlog2 database.1482server # [ 10.182480] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...1483server # [ 10.200551] niks3-test-certs-start[522]: Certificate request self-signature ok1484server # [ 10.207361] niks3-test-certs-start[522]: subject=CN=server1485server # [ 10.212766] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'1486server # [ 10.223407] dbus-broker-launch[502]: Invalid user-name in /nix/store/8sks95prqm311i9v71vd4hrck9pfivf3-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1487builder # [ 10.182719] systemd[1]: Finished register-nix-paths.service.1488server # [ 10.260608] systemd[1]: Started D-Bus System Message Bus.1489server # [ 10.267945] niks3-test-certs-start[555]: -----1490server # [ 10.284426] systemd-logind[521]: New seat seat0.1491server # [ 10.316312] dbus-broker-launch[502]: Ready1492server # [ 10.323582] systemd[1]: Started User Login Management.1493server # [ 10.338668] systemd[1]: Stopped target Host and Network Name Lookups.1494server # [ 10.343737] systemd[1]: Stopping Host and Network Name Lookups...1495server # [ 10.345896] systemd[1]: Stopped target User and Group Name Lookups.1496server # [ 10.352557] systemd[1]: Stopping User and Group Name Lookups...1497builder # [ 10.309180] systemd[1]: Finished Firewall.1498server # [ 10.358935] systemd[1]: Starting linger-users.service...1499server # [ 10.365550] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1500server # [ 10.369482] niks3-test-certs-start[560]: Certificate request self-signature ok1501server # [ 10.374118] niks3-test-certs-start[560]: subject=CN=niks3 test client1502server # [ 10.382093] systemd[1]: nscd.service: Deactivated successfully.1503server # [ 10.382991] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1504server # [ 10.404399] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1505server # [ 10.416251] systemd[1]: Finished Generate test mTLS certs.1506server # [ 10.456943] systemd[1]: linger-users.service: Deactivated successfully.1507server # [ 10.460649] systemd[1]: Finished linger-users.service.1508server # [ 10.486786] systemd[1]: Started Name Service Cache Daemon (nsncd).1509server # [ 10.490322] nsncd[570]: Jul 08 22:01:27.128 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1510server # [ 10.497969] systemd[1]: Finished resolvconf update.1511server # [ 10.500283] systemd[1]: Reached target Preparation for Network.1512server # [ 10.502573] systemd[1]: Reached target Host and Network Name Lookups.1513server # [ 10.506759] systemd[1]: Reached target User and Group Name Lookups.1514server # [ 10.509095] systemd[1]: Starting DHCP Client...1515server # [ 10.512268] systemd[1]: Starting Extra networking commands....1516builder # [ 10.469517] systemd[1]: Finished Coldplug All udev Devices.1517builder # [ 10.470503] systemd[1]: Reached target System Initialization.1518builder # [ 10.472263] systemd[1]: Started Discard unused filesystem blocks once a week.1519builder # [ 10.476169] systemd[1]: Started Daily Cleanup of Temporary Directories.1520builder # [ 10.480443] systemd[1]: Reached target Timer Units.1521builder # [ 10.482098] systemd[1]: Listening on D-Bus System Message Bus Socket.1522builder # [ 10.483077] systemd[1]: Starting niks3 auto-upload socket...1523builder # [ 10.488290] systemd[1]: Listening on Nix Daemon Socket.1524builder # [ 10.491126] systemd[1]: Listening on Hostname Service Socket.1525builder # [ 10.492075] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1526builder # [ 10.495644] systemd[1]: Starting D-Bus System Message Bus...1527builder # [ 10.500719] systemd[1]: Listening on niks3 auto-upload socket.1528builder # [ 10.501757] systemd[1]: Reached target Socket Units.1529builder # [ 10.525209] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1530builder # [ 10.608303] dbus-broker-launch[490]: Looking up NSS user entry for 'systemd-timesync'...1531builder # [ 10.620892] dbus-broker-launch[490]: NSS returned no entry for 'systemd-timesync'1532builder # [ 10.623126] dbus-broker-launch[490]: Invalid user-name in /nix/store/s1vb1xr0657f3bdwv3b8r1y8i0n7zy53-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1533builder # [ 10.654751] systemd[1]: Started D-Bus System Message Bus.1534server # [ 10.717186] dhcpcd[601]: dhcpcd-10.3.2 starting1535builder # [ 10.683252] dbus-broker-launch[490]: Ready1536server # [ 10.734308] dhcpcd[631]: dev: loaded udev1537builder # [ 10.686717] systemd[1]: Reached target Basic System.1538builder # [ 10.692241] systemd[1]: Starting Import lastlog data into lastlog2 database...1539server # [ 10.742064] dhcpcd[631]: no valid interfaces found1540server # [ 10.744516] dhcpcd[631]: libudev: received NULL device1541builder # [ 10.697499] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1542server # [ 10.746692] dhcpcd[631]: no valid interfaces found1543server # [ 10.749435] dhcpcd[631]: libudev: received NULL device1544builder # [ 10.709624] systemd[1]: Starting Post-Boot Actions...1545server # [ 10.780890] systemd[1]: Finished Extra networking commands..1546builder # [ 10.733422] systemd[1]: Started Reset console on configuration changes.1547server # [ 10.784756] systemd[1]: Reached target Network.1548server # [ 10.790521] systemd[1]: Started Mock OIDC server for testing.1549server # [ 10.796362] systemd[1]: Starting Nginx Web Server...1550builder # [ 10.751234] systemd[1]: Starting resolvconf update...1551server # [ 10.804789] systemd[1]: Starting PostgreSQL Server...1552server # [ 10.817969] systemd[1]: Started RustFS S3-compatible object storage.1553server # [ 10.844765] systemd[1]: Starting Setup RustFS bucket...1554builder # [ 10.816933] systemd[1]: Finished Post-Boot Actions.1555server # [ 10.874327] systemd[1]: Starting Permit User Sessions...1556builder # [ 10.848787] nsncd[493]: Jul 08 22:01:27.492 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1557builder # [ 10.858493] systemd[1]: Started Name Service Cache Daemon (nsncd).1558builder # [ 10.865815] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1559builder # [ 10.881218] systemd[1]: Finished Import lastlog data into lastlog2 database.1560builder # [ 10.885366] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1561builder # [ 10.887990] systemd[1]: Reached target Host and Network Name Lookups.1562builder # [ 10.893369] systemd[1]: Reached target User and Group Name Lookups.1563builder # [ 10.897770] systemd[1]: Started backdoor.service.1564builder # [ 10.922103] systemd[1]: Starting User Login Management...1565server # [ 10.988772] systemd[1]: Finished Permit User Sessions.1566server # [ 11.008301] systemd[1]: Started Getty on tty1.1567server # [ 11.010740] systemd[1]: Reached target Login Prompts.1568builder # connecting to host...1569builder # [ 11.086998] systemd[1]: Stopped target Host and Network Name Lookups.1570builder # [ 11.089269] systemd[1]: Stopping Host and Network Name Lookups...1571server # [ 11.138908] mock-oidc-server[644]: Mock OIDC Server running1572builder # [ 11.090746] systemd[1]: Stopped target User and Group Name Lookups.1573server # [ 11.144553] mock-oidc-server[644]: OIDC Address: 127.0.0.1:80801574builder # [ 11.101187] systemd[1]: Stopping User and Group Name Lookups...1575builder # [ 11.102041] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1576builder # [ 11.103200] systemd-logind[516]: New seat seat0.1577server # [ 11.151426] mock-oidc-server[644]: Issue Address: 127.0.0.1:80811578builder # [ 11.103893] systemd[1]: nscd.service: Deactivated successfully.1579server # [ 11.153161] mock-oidc-server[644]: Issuer: http://127.0.0.1:8080/oidc1580server # [ 11.157472] mock-oidc-server[644]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1581builder # [ 11.114678] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1582builder # [ 11.118952] systemd[1]: Started User Login Management.1583server # [ 11.163190] mock-oidc-server[644]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1584server # [ 11.170153] mock-oidc-server[644]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1585builder # [ 11.124165] systemd[1]: Starting linger-users.service...1586builder # [ 11.132934] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1587builder # [ 11.215528] systemd[1]: linger-users.service: Deactivated successfully.1588builder # [ 11.218354] systemd[1]: Finished linger-users.service.1589builder # [ 11.223133] nsncd[563]: Jul 08 22:01:27.861 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1590builder # [ 11.230694] systemd[1]: Started Name Service Cache Daemon (nsncd).1591builder # [ 11.233424] systemd[1]: Reached target Host and Network Name Lookups.1592builder # [ 11.234352] systemd[1]: Reached target User and Group Name Lookups.1593builder # [ 11.255718] systemd[1]: Finished resolvconf update.1594builder # [ 11.260689] systemd[1]: Reached target Preparation for Network.1595builder # [ 11.266952] systemd[1]: Starting DHCP Client...1596builder # [ 11.271590] systemd[1]: Starting Extra networking commands....1597server # [ 11.360792] postgresql-pre-start[670]: The files belonging to this database system will be owned by user "postgres".1598server # [ 11.367321] postgresql-pre-start[670]: This user must also own the server process.1599builder # [ 11.325860] (udev-worker)[365]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1600server # [ 11.377840] postgresql-pre-start[670]: The database cluster will be initialized with locale "en_US.UTF-8".1601server # [ 11.381723] postgresql-pre-start[670]: The default database encoding has accordingly been set to "UTF8".1602builder # [ 11.335650] (udev-worker)[372]: Network interface NamePolicy= disabled on kernel command line.1603builder # [ 11.340494] (udev-worker)[365]: Network interface NamePolicy= disabled on kernel command line.1604server # [ 11.387593] postgresql-pre-start[670]: The default text search configuration will be set to "english".1605server # [ 11.394751] postgresql-pre-start[670]: Data page checksums are disabled.1606server # [ 11.398467] postgresql-pre-start[670]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok1607server # [ 11.405750] postgresql-pre-start[670]: creating subdirectories ... ok1608server # [ 11.410819] postgresql-pre-start[670]: selecting dynamic shared memory implementation ... posix1609server # [ 11.421783] nginx-pre-start[663]: nginx: the configuration file /nix/store/q2qwgbfznnzrr87lhic3cd6hyk8rk12a-nginx.conf syntax is ok1610server # [ 11.428894] nginx-pre-start[663]: nginx: configuration file /nix/store/q2qwgbfznnzrr87lhic3cd6hyk8rk12a-nginx.conf test is successful1611server # [ 11.437013] systemd[1]: Started Nginx Web Server.1612builder # [ 11.511059] dhcpcd[595]: dhcpcd-10.3.2 starting1613builder # [ 11.526170] dhcpcd[632]: dev: loaded udev1614builder # [ 11.545886] systemd-logind[516]: Watching system buttons on /dev/input/event0 (gpio-keys)1615server # [ 11.610671] postgresql-pre-start[670]: selecting default "max_connections" ... 1001616builder # [ 11.564365] systemd[1]: Finished Extra networking commands..1617builder # [ 11.568914] systemd[1]: Reached target Network.1618builder # [ 11.590179] 8021q: 802.1Q VLAN Support v1.81619builder # [ 11.576751] systemd[1]: Starting Permit User Sessions...1620server # [ 11.694930] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31621builder # [ 11.639259] systemd[1]: Finished Permit User Sessions.1622builder # [ 11.648507] systemd[1]: Started Getty on tty1.1623builder # [ 11.652649] systemd[1]: Reached target Login Prompts.1624builder # [ 11.707250] cfg80211: Loading compiled-in X.509 certificates for regulatory database1625builder # [ 11.697779] systemd[1]: Condition check resulted in Virtio network device being skipped.1626builder # [ 11.706793] systemd[1]: Starting Address configuration of eth1...1627server # [ 11.779450] postgresql-pre-start[670]: selecting default "shared_buffers" ... 128MB1628builder # [ 11.748747] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1629builder # [ 11.749215] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1630builder # [ 11.754235] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21631builder # [ 11.754542] cfg80211: failed to load regulatory.db1632builder # [ 11.832575] 8021q: adding VLAN 0 to HW filter on device eth11633builder # [ 11.836575] mousedev: PS/2 mouse device common for all mice1634builder # [ 11.851357] 8021q: adding VLAN 0 to HW filter on device eth01635builder # [ 11.839578] dhcpcd[632]: eth0: waiting for carrier1636builder # [ 11.844226] dhcpcd[632]: eth0: waiting for carrier1637builder # [ 11.847419] dhcpcd[632]: eth0: carrier acquired1638builder # [ 11.850265] network-addresses-eth1-start[646]: adding address 192.168.1.1/24... done1639builder # [ 11.867630] dhcpcd[632]: DUID 00:01:00:01:31:e1:85:38:52:54:00:12:34:561640builder # [ 11.870460] dhcpcd[632]: eth0: IAID 00:12:34:561641builder # [ 11.871655] network-addresses-eth1-start[646]: adding address 2001:db8:1::1/64... done1642builder # [ 11.873497] dhcpcd[632]: eth0: adding address fe80::5054:ff:fe12:34561643builder # [ 11.891020] systemd[1]: Finished Address configuration of eth1.1644server # [ 12.014469] rustfs[647]: {"timestamp":"2026-07-08T22:01:28.6510589Z","level":"ERROR","fields":{"message":"list_path_raw: revjob err VolumeNotFound"},"target":"rustfs_ecstore::cache_value::metacache_set","filename":"crates/ecstore/src/cache_value/metacache_set.rs","line_number":486,"threadName":"rustfs-worker","threadId":"ThreadId(3)"}1645builder # [ 12.066139] systemd-logind[516]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1646builder # [ 12.120893] dhcpcd[632]: eth0: soliciting a DHCP lease1647builder # [ 12.128505] dhcpcd[632]: eth0: offered 10.0.2.15 from 10.0.2.21648builder # [ 12.136202] dhcpcd[632]: eth0: probing address 10.0.2.15/241649server # [ 12.368901] systemd[1]: Starting Virtual Console Setup...1650server # [ 12.400170] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1651server # [ 12.414784] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1652server # [ 12.418695] systemd[1]: Stopped Virtual Console Setup.1653server # [ 12.426695] systemd[1]: Starting Virtual Console Setup...1654server # [ 12.670568] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1655server # [ 12.724956] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1656server # [ 12.731152] systemd[1]: Started backdoor.service.1657builder # [ 12.720148] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31658server # [ 12.850975] 8021q: 802.1Q VLAN Support v1.81659server # connecting to host...1660server: Guest shell says: b'Spawning backdoor root shell...\n'1661server: connected to guest root shell1662server # [ 12.918009] (udev-worker)[681]: Network interface NamePolicy= disabled on kernel command line.1663server: (connecting took 13.21 seconds)1664server: (finished: waiting for the VM to finish booting, in 13.21 seconds)1665server # [ 12.948573] (udev-worker)[680]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1666server # [ 12.950712] (udev-worker)[680]: Network interface NamePolicy= disabled on kernel command line.1667server # [ 12.991080] systemd-vconsole-setup[715]: Configuration of first virtual console was skipped, ignoring remaining ones.[ 13.018323] cfg80211: Loading compiled-in X.509 certificates for regulatory database1668server # 1669server # [ 13.005701] systemd[1]: Finished Virtual Console Setup.1670server # [ 13.060588] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1671server # [ 13.061072] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1672server # [ 13.067033] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21673server # [ 13.067357] cfg80211: failed to load regulatory.db1674builder # [ 13.025171] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1675builder # [ 13.056351] systemd[1]: Starting Virtual Console Setup...1676builder # [ 13.087365] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1677builder # [ 13.089195] systemd[1]: Stopped Virtual Console Setup.1678builder # [ 13.096357] systemd[1]: Starting Virtual Console Setup...1679builder # [ 13.115134] systemd-logind[516]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1680server # [ 13.193513] systemd[1]: Condition check resulted in Virtio network device being skipped.1681server # [ 13.200993] systemd[1]: Starting Address configuration of eth1...1682server # [ 13.319952] 8021q: adding VLAN 0 to HW filter on device eth01683server # [ 13.305092] dhcpcd[631]: eth0: waiting for carrier1684server # [ 13.308133] dhcpcd[631]: eth0: carrier acquired1685server # [ 13.333593] 8021q: adding VLAN 0 to HW filter on device eth11686server # [ 13.324251] dhcpcd[631]: DUID 00:01:00:01:31:e1:85:39:52:54:00:12:34:561687server # [ 13.327506] dhcpcd[631]: eth0: IAID 00:12:34:561688server # [ 13.330166] dhcpcd[631]: eth0: adding address fe80::5054:ff:fe12:34561689server # [ 13.338069] network-addresses-eth1-start[738]: adding address 192.168.1.2/24... done1690server # [ 13.353093] network-addresses-eth1-start[738]: adding address 2001:db8:1::2/64... done1691server # [ 13.373340] systemd[1]: Finished Address configuration of eth1.1692server # [ 13.416904] systemd-logind[521]: Watching system buttons on /dev/input/event0 (gpio-keys)1693server # [ 13.543156] mousedev: PS/2 mouse device common for all mice1694builder # [ 13.485345] systemd-vconsole-setup[683]: Configuration of first virtual console was skipped, ignoring remaining ones.1695builder # [ 13.489635] systemd[1]: Finished Virtual Console Setup.1696server # [ 13.692585] systemd-logind[521]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1697builder # [ 13.794846] dhcpcd[632]: eth0: soliciting an IPv6 router1698builder # [ 13.798390] dhcpcd[632]: eth0: Router Advertisement from fe80::21699builder # [ 13.801035] dhcpcd[632]: eth0: adding address fec0::5054:ff:fe12:3456/641700builder # [ 13.803812] dhcpcd[632]: eth0: adding route to fec0::/641701builder # [ 13.806210] dhcpcd[632]: eth0: adding default route via fe80::21702server # [ 14.633540] postgresql-pre-start[670]: selecting default time zone ... UTC1703server # [ 14.639972] postgresql-pre-start[670]: creating configuration files ... ok1704server # [ 15.028127] dhcpcd[631]: eth0: soliciting a DHCP lease1705server # [ 15.033769] dhcpcd[631]: eth0: offered 10.0.2.15 from 10.0.2.21706server # [ 15.040306] dhcpcd[631]: eth0: probing address 10.0.2.15/241707server # [ 15.088504] dhcpcd[631]: eth0: soliciting an IPv6 router1708server # [ 15.089874] dhcpcd[631]: eth0: Router Advertisement from fe80::21709server # [ 15.090733] dhcpcd[631]: eth0: adding address fec0::5054:ff:fe12:3456/641710server # [ 15.091641] dhcpcd[631]: eth0: adding route to fec0::/641711server # [ 15.100176] dhcpcd[631]: eth0: adding default route via fe80::21712server # [ 15.144765] postgresql-pre-start[670]: running bootstrap script ... ok1713server # [ 15.734067] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1714server # [ 15.762397] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1715server # [ 15.763507] systemd[1]: Stopped Virtual Console Setup.1716server # [ 15.770109] systemd[1]: Stopping Virtual Console Setup...1717server # [ 15.773430] systemd[1]: Starting Virtual Console Setup...1718server # [ 15.824323] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1719server # [ 15.825382] systemd[1]: Stopped Virtual Console Setup.1720server # [ 15.828774] systemd[1]: Starting Virtual Console Setup...1721server # [ 15.863554] systemd-logind[521]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1722server # [ 16.157900] postgresql-pre-start[670]: performing post-bootstrap initialization ... ok1723server # [ 16.245853] systemd-vconsole-setup[784]: Configuration of first virtual console was skipped, ignoring remaining ones.1724server # [ 16.249516] systemd[1]: Finished Virtual Console Setup.1725server # [ 16.304895] postgresql-pre-start[670]: syncing data to disk ... ok1726server # [ 16.305844] postgresql-pre-start[670]: initdb: warning: enabling "trust" authentication for local connections1727server # [ 16.307117] postgresql-pre-start[670]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.1728server # [ 16.309579] postgresql-pre-start[670]: Success. You can now start the database server using:1729server # [ 16.312286] postgresql-pre-start[670]: pg_ctl -D /var/lib/postgresql/17 -l logfile start1730server # [ 16.448500] postgres[794]: [794] LOG: starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit1731server # [ 16.452062] postgres[794]: [794] LOG: listening on IPv6 address "::1", port 54321732server # [ 16.453173] postgres[794]: [794] LOG: listening on IPv4 address "127.0.0.1", port 54321733server # [ 16.456301] postgres[794]: [794] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1734server # [ 16.467157] postgres[800]: [800] LOG: database system was shut down at 2026-07-08 22:01:32 GMT1735server # [ 16.475352] postgres[794]: [794] LOG: database system is ready to accept connections1736server # [ 16.481323] systemd[1]: Started PostgreSQL Server.1737server # [ 16.486367] systemd[1]: Starting PostgreSQL Setup Scripts...1738server # [ 16.695430] postgresql-setup-start[816]: CREATE DATABASE1739server # [ 16.740694] postgresql-setup-start[821]: CREATE ROLE1740server # [ 16.762942] postgresql-setup-start[823]: ALTER DATABASE1741server # [ 16.769859] systemd[1]: Finished PostgreSQL Setup Scripts.1742server # [ 16.771632] systemd[1]: Reached target PostgreSQL.1743server: (finished: waiting for unit postgresql.service, in 17.74 seconds)1744server: waiting for unit rustfs.service1745server: (finished: waiting for unit rustfs.service, in 0.08 seconds)1746server: waiting for unit rustfs-setup.service1747builder # [ 17.936397] dhcpcd[632]: eth0: leased 10.0.2.15 for 86400 seconds1748builder # [ 17.939562] dhcpcd[632]: eth0: adding route to 10.0.2.0/241749builder # [ 17.942521] dhcpcd[632]: eth0: adding default route via 10.0.2.21750builder # [ 18.092268] systemd[1]: Started DHCP Client.1751builder # [ 18.094261] systemd[1]: Reached target Multi-User System.1752builder # [ 18.095500] systemd[1]: Startup finished in 923ms (kernel) + 4.970s (initrd) + 12.201s (userspace) = 18.095s.1753server # [ 20.127867] dhcpcd[631]: eth0: leased 10.0.2.15 for 86400 seconds1754server # [ 20.131368] dhcpcd[631]: eth0: adding route to 10.0.2.0/241755server # [ 20.134564] dhcpcd[631]: eth0: adding default route via 10.0.2.21756server # [ 20.289178] systemd[1]: Started DHCP Client.1757server # [ 25.647697] rustfs-setup-start[915]: mb s3://niks3-test1758server # [ 25.656389] systemd[1]: Finished Setup RustFS bucket.1759server # [ 25.665270] systemd[1]: Starting niks3 server...1760server # [ 25.861236] postgres[927]: [927] ERROR: relation "goose_db_version" does not exist at character 361761server # [ 25.862822] postgres[927]: [927] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1762server # [ 25.891055] niks3-server[922]: 2026/07/08 22:01:42 OK 20241026095416_initial_model.sql (17.32ms)1763server # [ 25.905372] niks3-server[922]: 2026/07/08 22:01:42 OK 20251210153512_drop_unused_gin_index.sql (3.36ms)1764server # [ 25.908320] niks3-server[922]: 2026/07/08 22:01:42 OK 20251218171726_add_pins.sql (4.24ms)1765server # [ 25.909472] niks3-server[922]: 2026/07/08 22:01:42 OK 20260628120000_add_object_size_and_stats.sql (4.32ms)1766server # [ 25.910739] niks3-server[922]: 2026/07/08 22:01:42 goose: successfully migrated database to version: 202606281200001767server # [ 25.912733] niks3-server[922]: 2026/07/08 22:01:42 OK 1_commit_pending_closure.sql (5.05ms)1768server # [ 25.914324] niks3-server[922]: 2026/07/08 22:01:42 OK 2_object_stats_trigger.sql (6.12ms)1769server # [ 25.915636] niks3-server[922]: 2026/07/08 22:01:42 goose: up to current file version: 21770server # [ 25.932232] niks3-server[922]: 2026/07/08 22:01:42 INFO OIDC provider initialized name=test1771server # [ 25.933592] niks3-server[922]: 2026/07/08 22:01:42 INFO OIDC authentication enabled config=/nix/store/2za6kyy80mq2xh1w4ydfs7gg8psbv5g3-niks3-oidc.json1772server # [ 25.936056] niks3-server[922]: 2026/07/08 22:01:42 INFO Loaded signing key name=niks3-test-1 path=/nix/store/1cz670ydszbfkf0naknmhg579xvlcyr7-niks3-signing-key1773server # [ 25.962605] rustfs[647]: {"timestamp":"2026-07-08T22:01:42.60090284Z","level":"ERROR","fields":{"message":"system path read failed","path_kind":"data_usage","operation":"read_primary","reason":"config_not_found","object":"buckets/.usage.json","error":"Config not found"},"target":"rustfs_ecstore::data_usage","filename":"crates/ecstore/src/data_usage.rs","line_number":126,"threadName":"rustfs-worker","threadId":"ThreadId(3)"}1774server # [ 25.969173] niks3-server[922]: 2026/07/08 22:01:42 INFO Created nix-cache-info in bucket bucket=niks3-test1775server # [ 25.971157] niks3-server[922]: 2026/07/08 22:01:42 INFO Using socket-activated listener address=0.0.0.0:57511776server # [ 25.974073] niks3-server[922]: 2026/07/08 22:01:42 INFO systemd watchdog enabled interval=15s1777server # [ 25.975248] niks3-server[922]: 2026/07/08 22:01:42 INFO Starting HTTP server address=0.0.0.0:57511778server # [ 25.977470] systemd[1]: Started niks3 server.1779server # [ 25.978161] systemd[1]: Reached target Multi-User System.1780server # [ 25.978916] systemd[1]: Startup finished in 947ms (kernel) + 3.871s (initrd) + 21.157s (userspace) = 25.977s.1781server: (finished: waiting for unit rustfs-setup.service, in 8.74 seconds)1782server: waiting for unit mock-oidc.service1783server: (finished: waiting for unit mock-oidc.service, in 0.08 seconds)1784server: waiting for unit niks3.service1785server: (finished: waiting for unit niks3.service, in 0.08 seconds)1786server: waiting for TCP port 5751 on localhost1787server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!1788server: (finished: waiting for TCP port 5751 on localhost, in 0.06 seconds)1789server: waiting for TCP port 8080 on localhost1790server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1791server: (finished: waiting for TCP port 8080 on localhost, in 0.04 seconds)1792server: waiting for TCP port 9000 on localhost1793server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!1794server: (finished: waiting for TCP port 9000 on localhost, in 0.03 seconds)1795server: must succeed: mkdir -p /tmp/test-config1796server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.03 seconds)1797server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token1798server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.02 seconds)1799server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31800server # [ 26.835842] niks3-server[922]: 2026/07/08 22:01:43 INFO Received uploads request method=POST path=/api/pending_closures1801server # time=2026-07-08T22:01:43.491Z level=INFO msg="Uploading 5 paths to server (0 already cached)"1802server # time=2026-07-08T22:01:43.493Z level=INFO msg="Uploading 020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2 (2.0MB)"1803server # time=2026-07-08T22:01:43.495Z level=INFO msg="Uploading jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67 (44.4MB)"1804server # time=2026-07-08T22:01:43.497Z level=INFO msg="Uploading qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3 (287.5KB)"1805server # time=2026-07-08T22:01:43.500Z level=INFO msg="Uploading wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc (150.1KB)"1806server # time=2026-07-08T22:01:43.502Z level=INFO msg="Uploading fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8 (366.1KB)"1807server # [ 28.562438] niks3-server[922]: 2026/07/08 22:01:45 INFO Received complete multipart upload request method=POST path=/api/multipart/complete1808server # [ 28.575559] niks3-server[922]: 2026/07/08 22:01:45 INFO Completed multipart upload object_key=nar/0n9jhh8wlbk6hqhzsklzl8vbv6xab1d7yi91hr9z948fzw5s80jd.nar.zst upload_id=YWViZTYzZjAtOTE1NC00MmI2LTk2NzItNDg3MDk4MjgyMDBjLmI2NmU5MGFjLWFiYjctNGFiNy04Njc0LTljNzI0MDZmYzlkOXgxNzgzNTQ4MTAzNDg3NTYwNTQw parts=11809server # time=2026-07-08T22:01:45.225Z level=INFO msg="Uploading 5 narinfos"1810server # [ 28.587996] niks3-server[922]: 2026/07/08 22:01:45 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign1811server # [ 28.589594] niks3-server[922]: 2026/07/08 22:01:45 INFO Signed narinfos id=1 count=51812server # [ 28.601350] niks3-server[922]: 2026/07/08 22:01:45 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete1813server # time=2026-07-08T22:01:45.247Z level=INFO msg="Upload complete. (1.856s)"1814server # [ 28.609226] niks3-server[922]: 2026/07/08 22:01:45 INFO Completed upload id=11815server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 2.02 seconds)1816server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token1817server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.02 seconds)1818server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31819server # [ 28.809938] niks3-server[922]: 2026/07/08 22:01:45 WARN Authentication failed token_preview=invalid-token token_length=13 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]1820server # time=2026-07-08T22:01:45.451Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1821server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.18 seconds)1822server: waiting for unit nginx.service1823server: (finished: waiting for unit nginx.service, in 0.06 seconds)1824server: waiting for TCP port 443 on localhost1825server # Connection to localhost (::1) 443 port [tcp/https] succeeded!1826server: (finished: waiting for TCP port 443 on localhost, in 0.03 seconds)1827server: must succeed: /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31828server # time=2026-07-08T22:01:45.639Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/client.pem key=/etc/niks3-test-certs/client.key ca=/etc/niks3-test-certs/ca.pem1829server # [ 29.092955] niks3-server[922]: 2026/07/08 22:01:45 INFO Received uploads request method=POST path=/api/pending_closures1830server # time=2026-07-08T22:01:45.736Z level=INFO msg="Uploading 0 paths to server (5 already cached)"1831server # [ 29.099156] niks3-server[922]: 2026/07/08 22:01:45 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete1832server # [ 29.102401] niks3-server[922]: 2026/07/08 22:01:45 INFO Completed upload id=21833server # time=2026-07-08T22:01:45.742Z level=INFO msg="Upload complete. (98ms)"1834server: (finished: must succeed: /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.20 seconds)1835server: must fail: /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31836server # time=2026-07-08T22:01:45.765Z level=ERROR msg="Fatal error" error="auth token is required (use --auth-token-path, --auth-token-script, NIKS3_AUTH_TOKEN_FILE, or $XDG_CONFIG_HOME/niks3/auth-token)"1837server: (finished: must fail: /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.02 seconds)1838server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31839server # time=2026-07-08T22:01:45.862Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem1840server # [ 29.303966] niks3-server[922]: 2026/07/08 22:01:45 INFO Received uploads request method=POST path=/api/pending_closures1841server # time=2026-07-08T22:01:45.946Z level=INFO msg="Uploading 0 paths to server (5 already cached)"1842server # [ 29.310517] niks3-server[922]: 2026/07/08 22:01:45 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete1843server # time=2026-07-08T22:01:45.951Z level=INFO msg="Upload complete. (87ms)"1844server # [ 29.314045] niks3-server[922]: 2026/07/08 22:01:45 INFO Completed upload id=31845server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.19 seconds)1846server: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'1847server # -----1848server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.03 seconds)1849server: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem1850server # Certificate request self-signature ok1851server # subject=CN=other client1852server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/y2wn0af8r6l6r44r407091nbzgy7aw8z-openssl-3.6.2-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.05 seconds)1853server: must fail: /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31854server # time=2026-07-08T22:01:46.122Z level=INFO msg="Configuring client TLS" cert=/etc/niks3-test-certs/other.pem key=/etc/niks3-test-certs/other.key ca=/etc/niks3-test-certs/ca.pem1855server # time=2026-07-08T22:01:46.202Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"1856server # [ 29.566558] niks3-server[922]: 2026/07/08 22:01:46 WARN mTLS auth: subject not in bound subjects subject="CN=other client"1857server: (finished: must fail: /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3, in 0.18 seconds)1858server: must succeed: mkdir -p /tmp/test-store1859server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.02 seconds)1860server: must succeed: 1861 export AWS_ACCESS_KEY_ID=rustfsadmin1862export AWS_SECRET_ACCESS_KEY=rustfsadmin1863 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.318641865server # copying 5 paths...1866server # copying path '/nix/store/020cvdgp5va6j1mdz75s2nzfz25k37v2-libunistring-1.4.2' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1867server # copying path '/nix/store/fqhxni8jcvyf5id4rvg9x2y00qx1963d-libidn2-2.3.8' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1868server # copying path '/nix/store/wp59zwnmv6h2glrrb9rd7siprz1mxn6r-xgcc-15.2.0-libgcc' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1869server # copying path '/nix/store/jp8avbmpfcdnm0axwrzyk072nmq9cr0d-glibc-2.42-67' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1870server # copying path '/nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.3' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1871server: (finished: must succeed: 1872 export AWS_ACCESS_KEY_ID=rustfsadmin1873export AWS_SECRET_ACCESS_KEY=rustfsadmin1874 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.31875, in 0.52 seconds)1876server: must succeed: 1877cat > /tmp/test-drv.nix << 'EOF'1878derivation {1879 name = "test-build-log";1880 system = builtins.currentSystem;1881 builder = "/bin/sh";1882 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1883}1884EOF18851886server: (finished: must succeed: 1887cat > /tmp/test-drv.nix << 'EOF'1888derivation {1889 name = "test-build-log";1890 system = builtins.currentSystem;1891 builder = "/bin/sh";1892 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];1893}1894EOF1895, in 0.03 seconds)1896server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix1897server # this derivation will be built:1898server # /nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv1899server # building '/nix/store/04bylz4wv9mr41hs78kv5ygm2d5xg9k9-test-build-log.drv'...1900server # test-build-log> test build log output1901server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.23 seconds)1902server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1903server # [ 30.556288] niks3-server[922]: 2026/07/08 22:01:47 INFO Received uploads request method=POST path=/api/pending_closures1904server # time=2026-07-08T22:01:47.208Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1905server # time=2026-07-08T22:01:47.209Z level=INFO msg="Uploading z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log (128B)"1906server # [ 30.582732] niks3-server[922]: 2026/07/08 22:01:47 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign1907server # time=2026-07-08T22:01:47.223Z level=INFO msg="Uploading 1 narinfos"1908server # [ 30.587002] niks3-server[922]: 2026/07/08 22:01:47 INFO Signed narinfos id=4 count=11909server # [ 30.590103] niks3-server[922]: 2026/07/08 22:01:47 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete1910server # time=2026-07-08T22:01:47.231Z level=INFO msg="Upload complete. (112ms)"1911server # [ 30.594075] niks3-server[922]: 2026/07/08 22:01:47 INFO Completed upload id=41912server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log, in 0.22 seconds)1913server: must succeed: 1914 export AWS_ACCESS_KEY_ID=rustfsadmin1915export AWS_SECRET_ACCESS_KEY=rustfsadmin1916 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log19171918server # got build log for '/nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'1919server: (finished: must succeed: 1920 export AWS_ACCESS_KEY_ID=rustfsadmin1921export AWS_SECRET_ACCESS_KEY=rustfsadmin1922 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log1923, in 0.15 seconds)1924server: must succeed: 1925cat > /tmp/ca-test.nix << 'EOF'1926derivation {1927 name = "ca-test";1928 system = builtins.currentSystem;1929 builder = "/bin/sh";1930 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];1931 __contentAddressed = true;1932 outputHashMode = "recursive";1933 outputHashAlgo = "sha256";1934}1935EOF19361937server: (finished: must succeed: 1938cat > /tmp/ca-test.nix << 'EOF'1939derivation {1940 name = "ca-test";1941 system = builtins.currentSystem;1942 builder = "/bin/sh";1943 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];1944 __contentAddressed = true;1945 outputHashMode = "recursive";1946 outputHashAlgo = "sha256";1947}1948EOF1949, in 0.03 seconds)1950server: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link1951server # this derivation will be built:1952server # /nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv1953server # building '/nix/store/8j226mdc8yhan5fv0s31bbzx0iiny3pa-ca-test.drv'...1954server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link, in 0.19 seconds)1955server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test1956server # [ 31.214713] niks3-server[922]: 2026/07/08 22:01:47 INFO Received uploads request method=POST path=/api/pending_closures1957server # time=2026-07-08T22:01:47.857Z level=INFO msg="Uploading 1 paths to server (0 already cached)"1958server # time=2026-07-08T22:01:47.858Z level=INFO msg="Uploading 5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test (144B)"1959server # [ 31.230352] niks3-server[922]: 2026/07/08 22:01:47 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign1960server # time=2026-07-08T22:01:47.870Z level=INFO msg="Uploading 1 narinfos"1961server # [ 31.233578] niks3-server[922]: 2026/07/08 22:01:47 INFO Signed narinfos id=5 count=11962server # [ 31.237353] niks3-server[922]: 2026/07/08 22:01:47 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete1963server # time=2026-07-08T22:01:47.878Z level=INFO msg="Upload complete. (171ms)"1964server # [ 31.240457] niks3-server[922]: 2026/07/08 22:01:47 INFO Completed upload id=51965server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/qlvr9s99yzzmygirzyfzr0ds4jdc0r6w-niks3-1.7.0/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.27 seconds)1966server: must succeed: mkdir -p /tmp/chroot-store1967server: (finished: must succeed: mkdir -p /tmp/chroot-store, in 0.02 seconds)1968server: must succeed: 1969 export AWS_ACCESS_KEY_ID=rustfsadmin1970export AWS_SECRET_ACCESS_KEY=rustfsadmin1971 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test19721973server # copying 1 paths...1974server # copying path '/nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...1975server: (finished: must succeed: 1976 export AWS_ACCESS_KEY_ID=rustfsadmin1977export AWS_SECRET_ACCESS_KEY=rustfsadmin1978 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test1979, in 0.17 seconds)1980server: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test1981server: (finished: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.09 seconds)1982server: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test1983server: (finished: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.09 seconds)1984server: must succeed: readlink /etc/niks3-test/symlink-wrapper1985server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.03 seconds)1986server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper1987server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.02 seconds)1988server: must succeed: test -L /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper1989server: output: 1990!!! Traceback (most recent call last):1991!!! File "<string>", line 148, in <module>1992!!! server.succeed(f"test -L {symlink_wrapper}")1993!!! 1994!!! RequestedAssertionFailed: command `test -L /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper` failed (exit code 1)1995cleanup1996kill QemuMachine (pid 14)1997builder # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)1998kill QemuMachine (pid 15)1999server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/smwd56pl54f0pcjdspgh5vnf20c8xwsc-python3-3.13.13/bin/python3.13)2000(finished: cleanup, in 0.42 seconds)2001additionally exposed symbols:2002 builder, server,2003 vlan1,2004 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_ssh2005Hello store path: /nix/store/qpp9968dpkv1c755nk13mrkrzpsvah18-hello-2.12.32006Test output path: /nix/store/z57vvdhnnry9vypazxb33pyi7hsaz71i-test-build-log2007CA derivation output path: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2008Realisation info in chroot store: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test20092010Symlink wrapper store path: /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper2011/etc/niks3-test/:2012total 82013drwxr-xr-x 2 root root 4096 Jul 8 22:01 .2014drwxr-xr-x 23 root root 4096 Jul 8 22:01 ..2015lrwxrwxrwx 1 root root 38 Jul 8 22:01 symlink-wrapper -> /etc/static/niks3-test/symlink-wrapper20162017/etc/static/niks3-test/:2018total 102019dr-xr-xr-x 2 root root 3 Jan 1 1970 .2020dr-xr-xr-x 19 root root 59 Jan 1 1970 ..2021lrwxrwxrwx 1 root root 59 Jan 1 1970 symlink-wrapper -> /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper20222023lrwxrwxrwx 1 root root 59 Jan 1 1970 /etc/static/niks3-test/symlink-wrapper -> /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper2024-r-xr-xr-x 1 root root 41 Jan 1 1970 /nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper20252026/nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper20272028/nix/store/pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper20292030unknown-deriver20312032pvdx502yvdpvia8734qkyzlwik5fyglv-symlink-wrapper2033