vm-test-run-nixos-test-niks3
checks.x86_64-linux.nixos-test-niks3-lix
· build #349
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7start all VMs8builder: starting vm9server: starting vm10server: QEMU running (pid 48)11server # Disk image does not exist, creating the virtualisation disk image...12server # Formatting '/build/vm-state-server/tmp.H0DasD60g1', fmt=raw size=107374182413server # mke2fs 1.47.4 (6-Mar-2025)14server # Discarding device blocks: 0/262144 done15server # Creating filesystem with 262144 4k blocks and 65536 inodes16server # Filesystem UUID: 37fd6ef0-b064-474c-83fc-4278552e393417server # Superblock backups stored on blocks:18server # 32768, 98304, 163840, 22937619server # 20server # Allocating group tables: 0/8 done21server # Writing inode tables: 0/8 done22server # Creating journal (8192 blocks): done23server # Writing superblocks and filesystem accounting information: 0/8 done24server # 25server # Virtualisation disk image created.26server # Starting virtiofs daemons...27server # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28server # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29server # [2026-10-04T19:30:33Z INFO virtiofsd] Waiting for vhost-user socket connection...30server # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31server # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32server # [2026-10-04T19:30:33Z INFO virtiofsd] Waiting for vhost-user socket connection...33server # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34server # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35server # [2026-10-04T19:30:33Z INFO virtiofsd] Waiting for vhost-user socket connection...36server # [2026-10-04T19:30:33Z INFO virtiofsd] Client connected, servicing requests37server # [2026-10-04T19:30:33Z INFO virtiofsd] Client connected, servicing requests38server # [2026-10-04T19:30:33Z INFO virtiofsd] Client connected, servicing requests39builder: QEMU running (pid 47)40builder # Disk image does not exist, creating the virtualisation disk image...41builder # Formatting '/build/vm-state-builder/tmp.FUkPSm7ko4', fmt=raw size=107374182442builder # mke2fs 1.47.4 (6-Mar-2025)43builder # Discarding device blocks: 0/262144 done44builder # Creating filesystem with 262144 4k blocks and 65536 inodes45builder # Filesystem UUID: e56bcf8b-8d78-45e1-a34d-aa587098b68946builder # Superblock backups stored on blocks:47builder # 32768, 98304, 163840, 22937648builder # 49builder # Allocating group tables: 0/8 done50builder # Writing inode tables: 0/8 done51builder # Creating journal (8192 blocks): done52builder # Writing superblocks and filesystem accounting information: 0/8 done53builder # 54builder # Virtualisation disk image created.55builder # Starting virtiofs daemons...56builder # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)57builder # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)58builder # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)59builder # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether60builder # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61builder # [2026-10-04T19:30:33Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62builder # [2026-10-04T19:30:33Z INFO virtiofsd] Waiting for vhost-user socket connection...63builder # [2026-10-04T19:30:33Z INFO virtiofsd] Waiting for vhost-user socket connection...64builder # [2026-10-04T19:30:33Z INFO virtiofsd] Waiting for vhost-user socket connection...65builder # [2026-10-04T19:30:33Z INFO virtiofsd] Client connected, servicing requests66builder # [2026-10-04T19:30:33Z INFO virtiofsd] Client connected, servicing requests67builder # [2026-10-04T19:30:33Z INFO virtiofsd] Client connected, servicing requests68(finished: start all VMs, in 0.30 seconds)69server: waiting for unit postgresql.service70server: waiting for the VM to finish booting71builder # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)72server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)73builder # 74builder # 75builder # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0076builder # Press Ctrl-B to configure iPXE (PCI 00:02.0)...77builder # 78builder # 79builder # 80builder # 81builder # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0082builder # Press Ctrl-B to configure iPXE (PCI 00:05.0)...83builder # 84builder # 85server # 86server # 87server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0088server # Press Ctrl-B to configure iPXE (PCI 00:02.0)...89server # 90server # 91server # 92server # 93server # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0094server # Press Ctrl-B to configure iPXE (PCI 00:05.0)...95server # 96server # 97builder # Booting from ROM...98server # Booting from ROM...99builder # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026100builder # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/380z13x9384wfk93j6facg9z2kkbjxp0-nixos-system-builder-test/init regInfo=/nix/.ro-store/gf777rx49vkf5nc4594sh7wlxv5mx51x-closure-info/registration console=ttyS0,115200n8 console=tty0101builder # [ 0.000000] BIOS-provided physical RAM map:102server # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026103builder # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable104builder # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved105builder # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved106builder # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable107builder # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved108server # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/39vygmhfn4f518m2ip151is6y5420s6r-nixos-system-server-test/init regInfo=/nix/.ro-store/zf2731rs8d1sbbk657h3w4rxayqdfvc1-closure-info/registration console=ttyS0,115200n8 console=tty0109server # [ 0.000000] BIOS-provided physical RAM map:110builder # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved111server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable112builder # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved113server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved114builder # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved115server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved116builder # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved117server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable118builder # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved119builder # [ 0.000000] NX (Execute Disable) protection: active120server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved121builder # [ 0.000000] APIC: Static calls initialized122server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved123builder # [ 0.000000] SMBIOS 2.8 present.124server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved125builder # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014126server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved127builder # [ 0.000000] DMI: Memory slots populated: 1/1128builder # [ 0.000000] Hypervisor detected: KVM129server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved130builder # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000131server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved132builder # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00133server # [ 0.000000] NX (Execute Disable) protection: active134builder # [ 0.000001] kvm-clock: using sched offset of 524097389 cycles135server # [ 0.000000] APIC: Static calls initialized136server # [ 0.000000] SMBIOS 2.8 present.137builder # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns138builder # [ 0.000005] tsc: Detected 2400.012 MHz processor139server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014140server # [ 0.000000] DMI: Memory slots populated: 1/1141builder # [ 0.000811] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000142server # [ 0.000000] Hypervisor detected: KVM143builder # [ 0.000838] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs144server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000145server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00146builder # [ 0.000841] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT147server # [ 0.000000] kvm-clock: using sched offset of 534788225 cycles148builder # [ 0.002738] found SMP MP-table at [mem 0x000f5450-0x000f545f]149builder # [ 0.002749] Using GB pages for direct mapping150server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns151builder # [ 0.002803] RAMDISK: [mem 0x3e369000-0x3ffcffff]152server # [ 0.000005] tsc: Detected 2400.012 MHz processor153builder # [ 0.002811] ACPI: Early table checksum verification disabled154server # [ 0.000843] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000155builder # [ 0.002814] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )156server # [ 0.000870] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs157builder # [ 0.002818] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)158server # [ 0.000873] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT159builder # [ 0.002822] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)160server # [ 0.002763] found SMP MP-table at [mem 0x000f5450-0x000f545f]161server # [ 0.002774] Using GB pages for direct mapping162builder # [ 0.002829] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)163server # [ 0.002876] RAMDISK: [mem 0x3e36b000-0x3ffcffff]164builder # [ 0.002832] ACPI: FACS 0x000000003FFE0000 000040165server # [ 0.002883] ACPI: Early table checksum verification disabled166builder # [ 0.002833] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)167server # [ 0.002886] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )168builder # [ 0.002835] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)169server # [ 0.002889] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)170builder # [ 0.002836] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)171server # [ 0.002893] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)172builder # [ 0.002838] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)173server # [ 0.002899] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)174server # [ 0.002901] ACPI: FACS 0x000000003FFE0000 000040175builder # [ 0.002839] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]176server # [ 0.002903] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)177builder # [ 0.002840] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]178builder # [ 0.002841] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]179server # [ 0.002904] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)180builder # [ 0.002841] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]181server # [ 0.002906] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)182builder # [ 0.002841] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]183server # [ 0.002907] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)184builder # [ 0.002842] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]185server # [ 0.002909] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]186builder # [ 0.002842] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]187server # [ 0.002910] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]188builder # [ 0.003075] No NUMA configuration found189server # [ 0.002910] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]190builder # [ 0.003076] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]191builder # [ 0.003078] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]192server # [ 0.002911] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]193builder # [ 0.005538] Zone ranges:194server # [ 0.002911] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]195builder # [ 0.005539] DMA [mem 0x0000000000001000-0x0000000000ffffff]196server # [ 0.002912] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]197builder # [ 0.005540] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]198builder # [ 0.005542] Normal empty199builder # [ 0.005542] Device empty200server # [ 0.002912] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]201builder # [ 0.005543] Movable zone start for each node202server # [ 0.003130] No NUMA configuration found203builder # [ 0.005544] Early memory node ranges204server # [ 0.003131] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]205builder # [ 0.005544] node 0: [mem 0x0000000000001000-0x000000000009efff]206server # [ 0.003133] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]207server # [ 0.005431] Zone ranges:208builder # [ 0.005545] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]209server # [ 0.005432] DMA [mem 0x0000000000001000-0x0000000000ffffff]210builder # [ 0.005546] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]211server # [ 0.005433] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]212builder # [ 0.005569] On node 0, zone DMA: 1 pages in unavailable ranges213server # [ 0.005434] Normal empty214server # [ 0.005435] Device empty215builder # [ 0.005852] On node 0, zone DMA: 97 pages in unavailable ranges216server # [ 0.005436] Movable zone start for each node217builder # [ 0.025173] On node 0, zone DMA32: 40 pages in unavailable ranges218server # [ 0.005436] Early memory node ranges219builder # [ 0.025635] ACPI: PM-Timer IO Port: 0x608220server # [ 0.005436] node 0: [mem 0x0000000000001000-0x000000000009efff]221builder # [ 0.025645] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])222server # [ 0.005437] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]223builder # [ 0.025673] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23224server # [ 0.005438] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]225builder # [ 0.025676] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)226server # [ 0.005458] On node 0, zone DMA: 1 pages in unavailable ranges227builder # [ 0.025678] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)228server # [ 0.005725] On node 0, zone DMA: 97 pages in unavailable ranges229builder # [ 0.025679] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)230server # [ 0.023740] On node 0, zone DMA32: 40 pages in unavailable ranges231server # [ 0.024192] ACPI: PM-Timer IO Port: 0x608232builder # [ 0.025680] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)233server # [ 0.024202] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])234builder # [ 0.025680] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)235server # [ 0.024226] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23236builder # [ 0.025683] ACPI: Using ACPI (MADT) for SMP configuration information237server # [ 0.024228] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)238builder # [ 0.025684] ACPI: HPET id: 0x8086a201 base: 0xfed00000239builder # [ 0.025687] TSC deadline timer available240server # [ 0.024230] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)241builder # [ 0.025691] CPU topo: Max. logical packages: 1242server # [ 0.024231] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)243builder # [ 0.025692] CPU topo: Max. logical dies: 1244builder # [ 0.025692] CPU topo: Max. dies per package: 1245server # [ 0.024232] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)246builder # [ 0.025696] CPU topo: Max. threads per core: 1247server # [ 0.024233] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)248builder # [ 0.025696] CPU topo: Num. cores per package: 1249builder # [ 0.025697] CPU topo: Num. threads per package: 1250server # [ 0.024235] ACPI: Using ACPI (MADT) for SMP configuration information251server # [ 0.024236] ACPI: HPET id: 0x8086a201 base: 0xfed00000252builder # [ 0.025697] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs253server # [ 0.024239] TSC deadline timer available254builder # [ 0.025716] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()255server # [ 0.024243] CPU topo: Max. logical packages: 1256server # [ 0.024244] CPU topo: Max. logical dies: 1257builder # [ 0.025746] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]258server # [ 0.024244] CPU topo: Max. dies per package: 1259server # [ 0.024247] CPU topo: Max. threads per core: 1260builder # [ 0.025747] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]261server # [ 0.024248] CPU topo: Num. cores per package: 1262builder # [ 0.025749] [mem 0x40000000-0xafffffff] available for PCI devices263server # [ 0.024248] CPU topo: Num. threads per package: 1264builder # [ 0.025750] Booting paravirtualized kernel on KVM265server # [ 0.024248] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs266builder # [ 0.025753] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns267server # [ 0.024265] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()268builder # [ 0.030201] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1269server # [ 0.024291] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]270builder # [ 0.032950] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152271server # [ 0.024293] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]272builder # [ 0.032996] kvm-guest: PV spinlocks disabled, single CPU273server # [ 0.024294] [mem 0x40000000-0xafffffff] available for PCI devices274server # [ 0.024295] Booting paravirtualized kernel on KVM275server # [ 0.024298] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns276server # [ 0.028734] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1277builder # [ 0.032997] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/380z13x9384wfk93j6facg9z2kkbjxp0-nixos-system-builder-test/init regInfo=/nix/.ro-store/gf777rx49vkf5nc4594sh7wlxv5mx51x-closure-info/registration console=ttyS0,115200n8 console=tty0278server # [ 0.031238] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152279server # [ 0.031279] kvm-guest: PV spinlocks disabled, single CPU280builder # [ 0.033090] Unknown kernel command line parameters "regInfo=/nix/.ro-store/gf777rx49vkf5nc4594sh7wlxv5mx51x-closure-info/registration", will be passed to user space.281builder # [ 0.033102] random: crng init done282builder # [ 0.033103] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes283builder # [ 0.034253] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)284server # [ 0.031281] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/39vygmhfn4f518m2ip151is6y5420s6r-nixos-system-server-test/init regInfo=/nix/.ro-store/zf2731rs8d1sbbk657h3w4rxayqdfvc1-closure-info/registration console=ttyS0,115200n8 console=tty0285builder # [ 0.034265] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)286builder # [ 0.034295] Fallback order for Node 0: 0287server # [ 0.031372] Unknown kernel command line parameters "regInfo=/nix/.ro-store/zf2731rs8d1sbbk657h3w4rxayqdfvc1-closure-info/registration", will be passed to user space.288builder # [ 0.034297] Built 1 zonelists, mobility grouping on. Total pages: 262006289builder # [ 0.034299] Policy zone: DMA32290server # [ 0.031385] random: crng init done291builder # [ 0.036914] mem auto-init: stack:all(zero), heap alloc:on, heap free:off292server # [ 0.031386] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes293builder # [ 0.039448] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1294server # [ 0.032459] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)295builder # [ 0.041810] allocated 2097152 bytes of page_ext296server # [ 0.032472] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)297builder # [ 0.051846] ftrace: allocating 48804 entries in 192 pages298server # [ 0.032500] Fallback order for Node 0: 0299builder # [ 0.051848] ftrace: allocated 192 pages with 2 groups300builder # [ 0.052731] Dynamic Preempt: lazy301server # [ 0.032502] Built 1 zonelists, mobility grouping on. Total pages: 262006302server # [ 0.032503] Policy zone: DMA32303builder # [ 0.053380] rcu: Preemptible hierarchical RCU implementation.304builder # [ 0.053380] rcu: RCU event tracing is enabled.305server # [ 0.034961] mem auto-init: stack:all(zero), heap alloc:on, heap free:off306builder # [ 0.053381] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.307server # [ 0.037399] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1308builder # [ 0.053382] Trampoline variant of Tasks RCU enabled.309server # [ 0.039640] allocated 2097152 bytes of page_ext310builder # [ 0.053383] Rude variant of Tasks RCU enabled.311server # [ 0.049273] ftrace: allocating 48804 entries in 192 pages312builder # [ 0.053383] Tracing variant of Tasks RCU enabled.313server # [ 0.049274] ftrace: allocated 192 pages with 2 groups314server # [ 0.050088] Dynamic Preempt: lazy315builder # [ 0.053384] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.316server # [ 0.050228] rcu: Preemptible hierarchical RCU implementation.317builder # [ 0.053385] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1318server # [ 0.050228] rcu: RCU event tracing is enabled.319server # [ 0.050228] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.320builder # [ 0.053408] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.321server # [ 0.050229] Trampoline variant of Tasks RCU enabled.322server # [ 0.050230] Rude variant of Tasks RCU enabled.323builder # [ 0.053409] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.324server # [ 0.050230] Tracing variant of Tasks RCU enabled.325builder # [ 0.053410] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.326server # [ 0.050231] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.327builder # [ 0.057832] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16328server # [ 0.050232] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1329builder # [ 0.058111] rcu: srcu_init: Setting srcu_struct sizes based on contention.330server # [ 0.050286] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.331builder # [ 0.058118] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns332server # [ 0.050288] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.333builder # [ 0.058226] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)334builder # [ 0.061730] Console: colour VGA+ 80x25335server # [ 0.050288] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.336builder # [ 0.061733] printk: legacy console [tty0] enabled337server # [ 0.054930] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16338builder # [ 0.091315] printk: legacy console [ttyS0] enabled339server # [ 0.055213] rcu: srcu_init: Setting srcu_struct sizes based on contention.340builder # [ 0.193749] ACPI: Core revision 20250807341server # [ 0.055219] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns342builder # [ 0.194676] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns343server # [ 0.055320] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)344server # [ 0.058820] Console: colour VGA+ 80x25345builder # [ 0.196308] APIC: Switch to symmetric I/O mode setup346server # [ 0.058823] printk: legacy console [tty0] enabled347builder # [ 0.197296] x2apic enabled348server # [ 0.088376] printk: legacy console [ttyS0] enabled349server # [ 0.190218] ACPI: Core revision 20250807350builder # [ 0.198080] APIC: Switched APIC routing to: physical x2apic351server # [ 0.191114] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns352builder # [ 0.200065] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1353server # [ 0.192721] APIC: Switch to symmetric I/O mode setup354server # [ 0.193698] x2apic enabled355builder # [ 0.201094] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns356server # [ 0.194476] APIC: Switched APIC routing to: physical x2apic357builder # [ 0.202849] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)358server # [ 0.196311] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1359builder # [ 0.204915] x86/cpu: User Mode Instruction Prevention (UMIP) activated360builder # [ 0.205985] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127361server # [ 0.197280] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns362builder # [ 0.206846] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0363server # [ 0.198978] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)364builder # [ 0.207850] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto365server # [ 0.200062] x86/cpu: User Mode Instruction Prevention (UMIP) activated366server # [ 0.201095] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127367builder # [ 0.208846] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl368server # [ 0.202691] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0369builder # [ 0.209846] Transient Scheduler Attacks: Vulnerable: No microcode370builder # [ 0.210846] Spectre V2 : Mitigation: Enhanced / Automatic IBRS371server # [ 0.203774] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto372builder # [ 0.211846] Speculative Return Stack Overflow: Mitigation: Safe RET373server # [ 0.204976] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl374server # [ 0.205976] Transient Scheduler Attacks: Vulnerable: No microcode375server # [ 0.206975] Spectre V2 : Mitigation: Enhanced / Automatic IBRS376server # [ 0.207976] Speculative Return Stack Overflow: Mitigation: Safe RET377builder # [ 0.212846] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization378builder # [ 0.214854] Spectre V2 : Enabling IBPB for BPF379builder # [ 0.215846] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier380builder # [ 0.216847] active return thunk: srso_alias_return_thunk381server # [ 0.208975] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization382builder # [ 0.218528] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'383server # [ 0.210983] Spectre V2 : Enabling IBPB for BPF384builder # [ 0.219846] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'385server # [ 0.212522] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier386builder # [ 0.220846] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'387server # [ 0.213976] active return thunk: srso_alias_return_thunk388builder # [ 0.221846] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'389server # [ 0.214988] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'390builder # [ 0.222846] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'391server # [ 0.215975] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'392builder # [ 0.223845] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'393server # [ 0.216975] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'394builder # [ 0.224846] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'395server # [ 0.217975] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'396builder # [ 0.225846] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256397server # [ 0.218975] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'398builder # [ 0.226846] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64399server # [ 0.219975] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'400builder # [ 0.227846] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512401server # [ 0.220975] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'402builder # [ 0.228846] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024403server # [ 0.221976] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256404builder # [ 0.229846] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8405server # [ 0.222975] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64406server # [ 0.223975] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512407builder # [ 0.230846] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.408server # [ 0.224975] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024409server # [ 0.225975] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8410server # [ 0.226975] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.411builder # [ 0.266714] Freeing SMP alternatives memory: 44K412builder # [ 0.267431] pid_max: default: 32768 minimum: 301413builder # [ 0.267945] LSM: initializing lsm=capability,landlock,yama,bpf,ima414server # [ 0.260436] Freeing SMP alternatives memory: 44K415builder # [ 0.268953] landlock: Up and running.416server # [ 0.260979] pid_max: default: 32768 minimum: 301417builder # [ 0.269847] Yama: becoming mindful.418server # [ 0.262065] LSM: initializing lsm=capability,landlock,yama,bpf,ima419builder # [ 0.271032] LSM support for eBPF active420server # [ 0.263071] landlock: Up and running.421server # [ 0.263714] Yama: becoming mindful.422builder # [ 0.271799] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)423server # [ 0.264626] LSM support for eBPF active424builder # [ 0.272871] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)425server # [ 0.265102] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)426server # [ 0.266003] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)427builder # [ 0.275485] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)428builder # [ 0.276470] Performance Events: Fam17h+ core perfctr, AMD PMU driver.429server # [ 0.269027] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)430builder # [ 0.276851] ... version: 2431builder # [ 0.277587] ... bit width: 48432server # [ 0.270539] Performance Events: Fam17h+ core perfctr, AMD PMU driver.433builder # [ 0.277849] ... generic counters: 6434server # [ 0.270984] ... version: 2435builder # [ 0.278570] ... generic bitmap: 000000000000003f436server # [ 0.271696] ... bit width: 48437builder # [ 0.278859] ... fixed-purpose counters: 0438server # [ 0.271977] ... generic counters: 6439builder # [ 0.279572] ... fixed-purpose bitmap: 0000000000000000440server # [ 0.272705] ... generic bitmap: 000000000000003f441server # [ 0.272977] ... fixed-purpose counters: 0442builder # [ 0.279848] ... value mask: 0000ffffffffffff443server # [ 0.273681] ... fixed-purpose bitmap: 0000000000000000444builder # [ 0.280756] ... max period: 00007fffffffffff445builder # [ 0.281540] ... global_ctrl mask: 000000000000003f446server # [ 0.273977] ... value mask: 0000ffffffffffff447builder # [ 0.281962] signal: max sigframe size: 3376448server # [ 0.274868] ... max period: 00007fffffffffff449builder # [ 0.282770] rcu: Hierarchical SRCU implementation.450server # [ 0.275663] ... global_ctrl mask: 000000000000003f451builder # [ 0.283432] rcu: Max phase no-delay instances is 400.452server # [ 0.276087] signal: max sigframe size: 3376453server # [ 0.276856] rcu: Hierarchical SRCU implementation.454server # [ 0.277612] rcu: Max phase no-delay instances is 400.455builder # [ 0.288490] smp: Bringing up secondary CPUs ...456builder # [ 0.288863] smp: Brought up 1 node, 1 CPU457server # [ 0.282464] smp: Bringing up secondary CPUs ...458builder # [ 0.289576] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)459server # [ 0.282999] smp: Brought up 1 node, 1 CPU460server # [ 0.283705] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)461builder # [ 0.290019] Memory: 941056K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 99608K reserved, 0K cma-reserved)462builder # [ 0.291113] devtmpfs: initialized463server # [ 0.284146] Memory: 941036K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 99600K reserved, 0K cma-reserved)464builder # [ 0.291974] x86/mm: Memory block size: 128MB465server # [ 0.285214] devtmpfs: initialized466server # [ 0.286031] x86/mm: Memory block size: 128MB467builder # [ 0.293712] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)468server # [ 0.287739] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)469builder # [ 0.294815] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).470builder # [ 0.295949] pinctrl core: initialized pinctrl subsystem471server # [ 0.288893] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).472builder # [ 0.297174] PM: RTC time: 19:30:33, date: 2026-10-04473server # [ 0.290060] pinctrl core: initialized pinctrl subsystem474server # [ 0.291211] PM: RTC time: 19:30:33, date: 2026-10-04475builder # [ 0.300815] NET: Registered PF_NETLINK/PF_ROUTE protocol family476server # [ 0.294783] NET: Registered PF_NETLINK/PF_ROUTE protocol family477builder # [ 0.302012] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations478server # [ 0.296068] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations479builder # [ 0.302866] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations480server # [ 0.296992] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations481builder # [ 0.304003] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations482server # [ 0.298127] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations483builder # [ 0.304859] audit: initializing netlink subsys (disabled)484server # [ 0.298987] audit: initializing netlink subsys (disabled)485builder # [ 0.306068] thermal_sys: Registered thermal governor 'fair_share'486server # [ 0.300120] thermal_sys: Registered thermal governor 'fair_share'487builder # [ 0.306070] thermal_sys: Registered thermal governor 'bang_bang'488server # [ 0.300122] thermal_sys: Registered thermal governor 'bang_bang'489builder # [ 0.306849] thermal_sys: Registered thermal governor 'step_wise'490server # [ 0.300979] thermal_sys: Registered thermal governor 'step_wise'491builder # [ 0.307801] thermal_sys: Registered thermal governor 'user_space'492server # [ 0.301950] thermal_sys: Registered thermal governor 'user_space'493builder # [ 0.308582] audit: type=2000 audit(1791142234.567:1): state=initialized audit_enabled=0 res=1494server # [ 0.302731] audit: type=2000 audit(1791142234.569:1): state=initialized audit_enabled=0 res=1495builder # [ 0.309851] thermal_sys: Registered thermal governor 'power_allocator'496builder # [ 0.309865] cpuidle: using governor menu497server # [ 0.303980] thermal_sys: Registered thermal governor 'power_allocator'498server # [ 0.304005] cpuidle: using governor menu499builder # [ 0.312097] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5500server # [ 0.306218] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5501builder # [ 0.313111] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]502server # [ 0.307199] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]503builder # [ 0.313853] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry504server # [ 0.307987] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry505builder # [ 0.314860] PCI: Using configuration type 1 for base access506server # [ 0.308990] PCI: Using configuration type 1 for base access507builder # [ 0.316031] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.508server # [ 0.310094] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.509server # [ 0.315249] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages510server # [ 0.315979] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page511builder # [ 0.323094] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages512builder # [ 0.323849] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page513server # [ 0.320979] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages514server # [ 0.321978] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page515builder # [ 0.328849] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages516builder # [ 0.329849] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page517builder # [ 0.338520] ACPI: Added _OSI(Module Device)518server # [ 0.332370] ACPI: Added _OSI(Module Device)519builder # [ 0.338850] ACPI: Added _OSI(Processor Device)520server # [ 0.332979] ACPI: Added _OSI(Processor Device)521builder # [ 0.339597] ACPI: Added _OSI(Processor Aggregator Device)522server # [ 0.333695] ACPI: Added _OSI(Processor Aggregator Device)523builder # [ 0.346438] ACPI: 1 ACPI AML tables successfully acquired and loaded524server # [ 0.342531] ACPI: 1 ACPI AML tables successfully acquired and loaded525server # [ 0.343956] ACPI: Interpreter enabled526builder # [ 0.349811] ACPI: Interpreter enabled527server # [ 0.344444] ACPI: PM: (supports S0 S3 S4 S5)528builder # [ 0.350349] ACPI: PM: (supports S0 S3 S4 S5)529builder # [ 0.350849] ACPI: Using IOAPIC for interrupt routing530builder # [ 0.351711] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug531server # [ 0.346978] ACPI: Using IOAPIC for interrupt routing532server # [ 0.347881] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug533server # [ 0.350978] PCI: Using E820 reservations for host bridge windows534builder # [ 0.356849] PCI: Using E820 reservations for host bridge windows535server # [ 0.352067] ACPI: Enabled 2 GPEs in block 00 to 3F536builder # [ 0.357977] ACPI: Enabled 2 GPEs in block 00 to 3F537server # [ 0.360745] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])538builder # [ 0.366712] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])539server # [ 0.361558] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]540builder # [ 0.368038] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]541server # [ 0.362057] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]542builder # [ 0.368927] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]543server # [ 0.363102] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]544builder # [ 0.369973] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]545server # [ 0.364351] PCI host bridge to bus 0000:00546builder # [ 0.371223] PCI host bridge to bus 0000:00547server # [ 0.364983] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]548builder # [ 0.371853] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]549server # [ 0.365978] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]550builder # [ 0.372849] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]551server # [ 0.366978] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]552builder # [ 0.373849] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]553server # [ 0.367978] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]554builder # [ 0.374849] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]555server # [ 0.368978] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]556builder # [ 0.375848] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]557server # [ 0.369979] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]558builder # [ 0.376848] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]559server # [ 0.370984] pci_bus 0000:00: root bus resource [bus 00-ff]560builder # [ 0.377849] pci_bus 0000:00: root bus resource [bus 00-ff]561server # [ 0.371997] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint562builder # [ 0.378822] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint563server # [ 0.373463] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint564builder # [ 0.380289] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint565server # [ 0.376044] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]566server # [ 0.376990] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]567builder # [ 0.382966] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]568server # [ 0.377969] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]569builder # [ 0.383862] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]570builder # [ 0.384871] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]571server # [ 0.379372] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]572builder # [ 0.386177] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]573server # [ 0.380695] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint574builder # [ 0.387613] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint575server # [ 0.382988] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]576server # [ 0.383881] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]577server # [ 0.384771] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]578builder # [ 0.390895] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]579builder # [ 0.391750] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]580server # [ 0.385845] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]581builder # [ 0.392599] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]582server # [ 0.387746] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint583builder # [ 0.392856] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]584builder # [ 0.394456] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint585server # [ 0.389996] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]586server # [ 0.390876] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]587server # [ 0.391772] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]588builder # [ 0.396890] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]589builder # [ 0.397782] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]590server # [ 0.393412] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint591builder # [ 0.398629] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]592builder # [ 0.400354] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint593server # [ 0.395986] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]594server # [ 0.396984] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]595builder # [ 0.402889] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]596server # [ 0.397993] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]597builder # [ 0.403754] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]598server # [ 0.399519] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint599builder # [ 0.404688] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]600builder # [ 0.406320] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint601server # [ 0.400988] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]602server # [ 0.401859] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]603server # [ 0.402775] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]604server # [ 0.403876] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]605builder # [ 0.409224] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]606builder # [ 0.410524] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]607server # [ 0.405524] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint608builder # [ 0.410871] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]609builder # [ 0.411854] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]610server # [ 0.406990] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]611server # [ 0.407952] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]612builder # [ 0.413430] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint613server # [ 0.409395] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint614builder # [ 0.414894] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]615server # [ 0.410990] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]616builder # [ 0.415870] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]617server # [ 0.412000] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]618builder # [ 0.418161] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint619server # [ 0.413612] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint620builder # [ 0.419862] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]621server # [ 0.414991] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]622builder # [ 0.420870] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]623server # [ 0.416000] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]624server # [ 0.417514] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint625builder # [ 0.422492] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint626server # [ 0.418991] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]627builder # [ 0.423891] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]628server # [ 0.419978] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]629builder # [ 0.424870] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]630server # [ 0.421520] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint631builder # [ 0.426388] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint632server # [ 0.422999] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]633builder # [ 0.427861] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]634server # [ 0.423863] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]635builder # [ 0.428859] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]636server # [ 0.424720] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]637builder # [ 0.430384] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint638server # [ 0.425520] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint639server # [ 0.427994] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]640builder # [ 0.432901] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]641server # [ 0.428872] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]642builder # [ 0.434497] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]643server # [ 0.429739] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]644builder # [ 0.434870] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]645server # [ 0.430535] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint646builder # [ 0.436436] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint647server # [ 0.431577] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]648builder # [ 0.438556] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]649server # [ 0.432178] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint650builder # [ 0.438856] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]651builder # [ 0.439871] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]652server # [ 0.433632] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]653server # [ 0.434182] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint654builder # [ 0.441958] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint655server # [ 0.435636] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]656builder # [ 0.443694] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]657builder # [ 0.444762] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint658builder # [ 0.446623] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]659builder # [ 0.447041] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint660builder # [ 0.448533] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]661server # [ 0.436190] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint662server # [ 0.438929] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]663server # [ 0.440040] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint664server # [ 0.441592] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO665server # [ 0.442226] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint666server # [ 0.444006] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]667server # [ 0.444889] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]668server # [ 0.446088] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint669builder # [ 0.449823] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint670server # [ 0.447903] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]671builder # [ 0.452486] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]672builder # [ 0.453721] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint673server # [ 0.451981] ACPI: PCI: Interrupt link LNKA configured for IRQ 10674builder # [ 0.455135] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO675server # [ 0.453026] ACPI: PCI: Interrupt link LNKB configured for IRQ 10676builder # [ 0.456159] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint677server # [ 0.454089] ACPI: PCI: Interrupt link LNKC configured for IRQ 11678server # [ 0.455079] ACPI: PCI: Interrupt link LNKD configured for IRQ 11679server # [ 0.456078] ACPI: PCI: Interrupt link LNKE configured for IRQ 10680server # [ 0.457076] ACPI: PCI: Interrupt link LNKF configured for IRQ 10681builder # [ 0.458909] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]682server # [ 0.458079] ACPI: PCI: Interrupt link LNKG configured for IRQ 11683builder # [ 0.459856] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]684server # [ 0.459079] ACPI: PCI: Interrupt link LNKH configured for IRQ 11685builder # [ 0.461179] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint686server # [ 0.460017] ACPI: PCI: Interrupt link GSIA configured for IRQ 16687server # [ 0.460990] ACPI: PCI: Interrupt link GSIB configured for IRQ 17688builder # [ 0.462773] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]689server # [ 0.461995] ACPI: PCI: Interrupt link GSIC configured for IRQ 18690server # [ 0.462993] ACPI: PCI: Interrupt link GSID configured for IRQ 19691server # [ 0.463991] ACPI: PCI: Interrupt link GSIE configured for IRQ 20692server # [ 0.464992] ACPI: PCI: Interrupt link GSIF configured for IRQ 21693builder # [ 0.466836] ACPI: PCI: Interrupt link LNKA configured for IRQ 10694server # [ 0.465990] ACPI: PCI: Interrupt link GSIG configured for IRQ 22695server # [ 0.466962] ACPI: PCI: Interrupt link GSIH configured for IRQ 23696builder # [ 0.467733] ACPI: PCI: Interrupt link LNKB configured for IRQ 10697builder # [ 0.468955] ACPI: PCI: Interrupt link LNKC configured for IRQ 11698server # [ 0.468837] iommu: Default domain type: Translated699builder # [ 0.469984] ACPI: PCI: Interrupt link LNKD configured for IRQ 11700server # [ 0.469572] iommu: DMA domain TLB invalidation policy: lazy mode701server # [ 0.470222] ACPI: bus type USB registered702builder # [ 0.470972] ACPI: PCI: Interrupt link LNKE configured for IRQ 10703server # [ 0.470980] usbcore: registered new interface driver usbfs704builder # [ 0.471946] ACPI: PCI: Interrupt link LNKF configured for IRQ 10705server # [ 0.471875] usbcore: registered new interface driver hub706builder # [ 0.472951] ACPI: PCI: Interrupt link LNKG configured for IRQ 11707server # [ 0.472646] usbcore: registered new device driver usb708builder # [ 0.473947] ACPI: PCI: Interrupt link LNKH configured for IRQ 11709server # [ 0.473834] NetLabel: Initializing710builder # [ 0.474887] ACPI: PCI: Interrupt link GSIA configured for IRQ 16711server # [ 0.474417] NetLabel: domain hash size = 128712builder # [ 0.475861] ACPI: PCI: Interrupt link GSIB configured for IRQ 17713server # [ 0.474999] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO714builder # [ 0.476863] ACPI: PCI: Interrupt link GSIC configured for IRQ 18715server # [ 0.475939] NetLabel: unlabeled traffic allowed by default716builder # [ 0.477863] ACPI: PCI: Interrupt link GSID configured for IRQ 19717server # [ 0.476854] PCI: Using ACPI for IRQ routing718builder # [ 0.478863] ACPI: PCI: Interrupt link GSIE configured for IRQ 20719builder # [ 0.479861] ACPI: PCI: Interrupt link GSIF configured for IRQ 21720builder # [ 0.480861] ACPI: PCI: Interrupt link GSIG configured for IRQ 22721builder # [ 0.481860] ACPI: PCI: Interrupt link GSIH configured for IRQ 23722builder # [ 0.483902] iommu: Default domain type: Translated723builder # [ 0.484726] iommu: DMA domain TLB invalidation policy: lazy mode724builder # [ 0.485929] ACPI: bus type USB registered725builder # [ 0.486694] usbcore: registered new interface driver usbfs726builder # [ 0.487589] usbcore: registered new interface driver hub727builder # [ 0.488606] usbcore: registered new device driver usb728builder # [ 0.489805] NetLabel: Initializing729builder # [ 0.490321] NetLabel: domain hash size = 128730builder # [ 0.490848] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO731builder # [ 0.491882] NetLabel: unlabeled traffic allowed by default732builder # [ 0.492848] PCI: Using ACPI for IRQ routing733server # [ 0.519585] pci 0000:00:01.0: vgaarb: setting as boot VGA device734server # [ 0.519974] pci 0000:00:01.0: vgaarb: bridge control possible735server # [ 0.519974] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none736server # [ 0.519982] vgaarb: loaded737server # [ 0.520737] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0738server # [ 0.520979] hpet0: 3 comparators, 64-bit 100.000000 MHz counter739server # [ 0.526071] clocksource: Switched to clocksource kvm-clock740server # [ 0.529595] VFS: Disk quotas dquot_6.6.0741server # [ 0.530337] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)742server # [ 0.531691] pnp: PnP ACPI init743server # [ 0.532554] ACPI: IRQ 4 override to edge(!), high(!)744builder # [ 0.536754] pci 0000:00:01.0: vgaarb: setting as boot VGA device745server # [ 0.533509] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved746builder # [ 0.536845] pci 0000:00:01.0: vgaarb: bridge control possible747server # [ 0.535043] pnp: PnP ACPI: found 5 devices748builder # [ 0.536845] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none749builder # [ 0.536853] vgaarb: loaded750builder # [ 0.537656] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0751builder # [ 0.538516] hpet0: 3 comparators, 64-bit 100.000000 MHz counter752server # [ 0.542594] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns753builder # [ 0.542937] clocksource: Switched to clocksource kvm-clock754server # [ 0.544083] clocksource: Switched to clocksource acpi_pm755server # [ 0.545059] NET: Registered PF_INET protocol family756builder # [ 0.544702] VFS: Disk quotas dquot_6.6.0757server # [ 0.546094] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)758builder # [ 0.547362] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)759builder # [ 0.548864] pnp: PnP ACPI init760builder # [ 0.549702] ACPI: IRQ 4 override to edge(!), high(!)761builder # [ 0.550745] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved762builder # [ 0.552362] pnp: PnP ACPI: found 5 devices763builder # [ 0.560036] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns764builder # [ 0.561562] clocksource: Switched to clocksource acpi_pm765builder # [ 0.562589] NET: Registered PF_INET protocol family766server # [ 0.563249] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)767builder # [ 0.563663] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)768server # [ 0.564742] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)769server # [ 0.566077] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)770server # [ 0.567424] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)771server # [ 0.568675] TCP: Hash tables configured (established 8192 bind 8192)772server # [ 0.569827] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)773server # [ 0.571136] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)774server # [ 0.572262] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)775server # [ 0.573485] NET: Registered PF_UNIX/PF_LOCAL protocol family776server # [ 0.574460] NET: Registered PF_XDP protocol family777server # [ 0.575341] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]778server # [ 0.576378] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]779server # [ 0.577418] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]780server # [ 0.578553] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]781server # [ 0.579692] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]782server # [ 0.580865] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]783builder # [ 0.581024] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)784server # [ 0.582696] ACPI: \_SB_.GSIA: Enabled at IRQ 16785builder # [ 0.582609] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)786server # [ 0.584784] ACPI: \_SB_.GSIB: Enabled at IRQ 17787builder # [ 0.583997] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)788builder # [ 0.585352] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)789server # [ 0.586741] ACPI: \_SB_.GSIC: Enabled at IRQ 18790builder # [ 0.586628] TCP: Hash tables configured (established 8192 bind 8192)791server # [ 0.588792] ACPI: \_SB_.GSID: Enabled at IRQ 19792builder # [ 0.587787] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)793builder # [ 0.589070] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)794server # [ 0.590449] PCI: CLS 0 bytes, default 64795builder # [ 0.590258] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)796server # [ 0.591397] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns797builder # [ 0.591496] NET: Registered PF_UNIX/PF_LOCAL protocol family798server # [ 0.593099] Trying to unpack rootfs image as initramfs...799builder # [ 0.592569] NET: Registered PF_XDP protocol family800builder # [ 0.593437] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]801builder # [ 0.594507] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]802builder # [ 0.595572] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]803builder # [ 0.596758] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]804builder # [ 0.597905] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]805builder # [ 0.599069] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]806builder # [ 0.600903] ACPI: \_SB_.GSIA: Enabled at IRQ 16807builder # [ 0.603055] ACPI: \_SB_.GSIB: Enabled at IRQ 17808builder # [ 0.605636] ACPI: \_SB_.GSIC: Enabled at IRQ 18809builder # [ 0.607828] ACPI: \_SB_.GSID: Enabled at IRQ 19810builder # [ 0.609681] PCI: CLS 0 bytes, default 64811builder # [ 0.610858] Trying to unpack rootfs image as initramfs...812builder # [ 0.614831] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns813server # [ 0.638674] Initialise system trusted keyrings814server # [ 0.639664] workingset: timestamp_bits=40 max_order=18 bucket_order=0815builder # [ 0.656573] Initialise system trusted keyrings816builder # [ 0.659791] workingset: timestamp_bits=40 max_order=18 bucket_order=0817server # [ 0.661049] Key type asymmetric registered818server # [ 0.663788] Asymmetric key parser 'x509' registered819server # [ 0.664648] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)820server # [ 0.667941] io scheduler mq-deadline registered821server # [ 0.668736] io scheduler kyber registered822server # [ 0.671362] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled823server # [ 0.672673] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A824server # [ 0.678607] Linux agpgart interface v0.103825server # [ 0.679407] ACPI: bus type drm_connector registered826builder # [ 0.680898] Key type asymmetric registered827builder # [ 0.681679] Asymmetric key parser 'x509' registered828server # [ 0.682234] usbcore: registered new interface driver usbserial_generic829server # [ 0.683302] usbserial: USB Serial support registered for generic830builder # [ 0.682548] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)831server # [ 0.685795] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.832server # [ 0.685795] Please enable it if your BIOS has the CPPC option.833builder # [ 0.687864] io scheduler mq-deadline registered834server # [ 0.688070] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled835builder # [ 0.688662] io scheduler kyber registered836server # [ 0.691910] drop_monitor: Initializing network drop monitor service837server # [ 0.693120] NET: Registered PF_INET6 protocol family838builder # [ 0.692318] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled839builder # [ 0.693640] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A840server # [ 0.698282] Segment Routing with IPv6841server # [ 0.698991] In-situ OAM (IOAM) with IPv6842builder # [ 0.698810] Linux agpgart interface v0.103843builder # [ 0.699620] ACPI: bus type drm_connector registered844server # [ 0.702052] IPI shorthand broadcast: enabled845builder # [ 0.703088] usbcore: registered new interface driver usbserial_generic846builder # [ 0.704250] usbserial: USB Serial support registered for generic847builder # [ 0.707764] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.848builder # [ 0.707764] Please enable it if your BIOS has the CPPC option.849server # [ 0.710389] sched_clock: Marking stable (570016745, 139755321)->(783233504, -73461438)850builder # [ 0.710145] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled851builder # [ 0.712890] drop_monitor: Initializing network drop monitor service852server # [ 0.713931] registered taskstats version 1853builder # [ 0.714098] NET: Registered PF_INET6 protocol family854server # [ 0.714900] Loading compiled-in X.509 certificates855builder # [ 0.719079] Segment Routing with IPv6856builder # [ 0.719778] In-situ OAM (IOAM) with IPv6857builder # [ 0.723039] IPI shorthand broadcast: enabled858builder # [ 0.731436] sched_clock: Marking stable (591015571, 139726996)->(812877365, -82134798)859server # [ 0.733782] Demotion targets for Node 0: null860server # [ 0.734640] Key type .fscrypt registered861builder # [ 0.734926] registered taskstats version 1862builder # [ 0.735985] Loading compiled-in X.509 certificates863server # [ 0.737775] Key type fscrypt-provisioning registered864server # [ 0.738729] ima: No TPM chip found, activating TPM-bypass!865server # [ 0.739672] ima: Allocated hash algorithm: sha1866server # [ 0.741801] ima: No architecture policies found867server # [ 0.743782] PM: Magic number: 14:817:545868server # [ 0.745384] RAS: Correctable Errors collector initialized.869server # [ 0.754391] clk: Disabling unused clocks870builder # [ 0.754749] Demotion targets for Node 0: null871builder # [ 0.755658] Key type .fscrypt registered872server # [ 0.758785] PM: genpd: Disabling unused power domains873builder # [ 0.758747] Key type fscrypt-provisioning registered874builder # [ 0.759722] ima: No TPM chip found, activating TPM-bypass!875builder # [ 0.760704] ima: Allocated hash algorithm: sha1876builder # [ 0.762775] ima: No architecture policies found877builder # [ 0.765753] PM: Magic number: 14:817:545878builder # [ 0.767479] RAS: Correctable Errors collector initialized.879builder # [ 0.776410] clk: Disabling unused clocks880builder # [ 0.778761] PM: genpd: Disabling unused power domains881server # [ 0.931377] Freeing initrd memory: 29076K882server # [ 0.934570] Freeing unused decrypted memory: 2028K883server # [ 0.937242] Freeing unused kernel image (initmem) memory: 3656K884server # [ 0.938313] Write protecting the kernel read-only data: 32768k885server # [ 0.940187] Freeing unused kernel image (text/rodata gap) memory: 1168K886server # [ 0.941662] Freeing unused kernel image (rodata/data gap) memory: 676K887builder # [ 0.945543] Freeing initrd memory: 29084K888builder # [ 0.948919] Freeing unused decrypted memory: 2028K889builder # [ 0.951775] Freeing unused kernel image (initmem) memory: 3656K890builder # [ 0.952920] Write protecting the kernel read-only data: 32768k891builder # [ 0.954869] Freeing unused kernel image (text/rodata gap) memory: 1168K892builder # [ 0.956418] Freeing unused kernel image (rodata/data gap) memory: 676K893server # [ 0.992415] x86/mm: Checked W+X mappings: passed, no W+X pages found.894server # [ 0.993515] Run /init as init process895server # [ 1.004245] systemd[1]: Inserted module 'autofs4'896builder # [ 1.007368] x86/mm: Checked W+X mappings: passed, no W+X pages found.897builder # [ 1.008529] Run /init as init process898builder # [ 1.019498] systemd[1]: Inserted module 'autofs4'899server # [ 1.024006] fuse: init (API version 7.45)900server # [ 1.031369] ACPI: \_SB_.GSIG: Enabled at IRQ 22901server # [ 1.033832] ACPI: \_SB_.GSIH: Enabled at IRQ 23902server # [ 1.036823] ACPI: \_SB_.GSIE: Enabled at IRQ 20903server # [ 1.038695] ACPI: \_SB_.GSIF: Enabled at IRQ 21904builder # [ 1.039683] fuse: init (API version 7.45)905server # [ 1.044194] virtiofs virtio5: discovered new tag: nix-store906server # [ 1.045686] virtiofs virtio5: virtio_fs_setup_dax: No cache capability907builder # [ 1.047191] ACPI: \_SB_.GSIG: Enabled at IRQ 22908builder # [ 1.049920] ACPI: \_SB_.GSIH: Enabled at IRQ 23909server # [ 1.051968] virtiofs virtio6: discovered new tag: shared910server # [ 1.053413] virtiofs virtio6: virtio_fs_setup_dax: No cache capability911builder # [ 1.053233] ACPI: \_SB_.GSIE: Enabled at IRQ 20912builder # [ 1.055503] ACPI: \_SB_.GSIF: Enabled at IRQ 21913server # [ 1.056600] virtiofs virtio7: discovered new tag: xchg914server # [ 1.058026] virtiofs virtio7: virtio_fs_setup_dax: No cache capability915builder # [ 1.061493] virtiofs virtio5: discovered new tag: nix-store916builder # [ 1.063081] virtiofs virtio5: virtio_fs_setup_dax: No cache capability917builder # [ 1.070643] virtiofs virtio6: discovered new tag: shared918builder # [ 1.072251] virtiofs virtio6: virtio_fs_setup_dax: No cache capability919builder # [ 1.075789] virtiofs virtio7: discovered new tag: xchg920builder # [ 1.077324] virtiofs virtio7: virtio_fs_setup_dax: No cache capability921server # [ 1.079874] systemd[1]: Successfully made /usr/ read-only.922builder # [ 1.098531] systemd[1]: Successfully made /usr/ read-only.923server # [ 1.416600] systemd[1]: systemd 261.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)924server # [ 1.428456] systemd[1]: Detected virtualization kvm.925server # [ 1.430563] systemd[1]: Detected architecture x86-64.926server # [ 1.432662] systemd[1]: Running in initrd.927server # [ 1.435208] systemd[1]: Initializing machine ID from random generator.928server # [ 1.438095] systemd[1]: Hostname set to <server>.929builder # [ 1.435290] systemd[1]: systemd 261.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)930builder # [ 1.447155] systemd[1]: Detected virtualization kvm.931builder # [ 1.449275] systemd[1]: Detected architecture x86-64.932builder # [ 1.451437] systemd[1]: Running in initrd.933builder # [ 1.453944] systemd[1]: Initializing machine ID from random generator.934builder # [ 1.456828] systemd[1]: Hostname set to <builder>.935server # [ 1.646563] systemd[1]: bpf-restrict-fs: LSM BPF program attached936builder # [ 1.665182] systemd[1]: bpf-restrict-fs: LSM BPF program attached937server # [ 1.686337] systemd[1]: Queued start job for default target Initrd Default Target.938server # [ 1.690153] systemd[1]: Created slice Slice /system/modprobe.939server # [ 1.691356] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.940server # [ 1.692814] systemd[1]: Expecting device /dev/disk/by-label/nixos...941server # [ 1.693949] systemd[1]: Reached target Path Units.942server # [ 1.694845] systemd[1]: Reached target Slice Units.943server # [ 1.695727] systemd[1]: Reached target Swaps.944server # [ 1.696546] systemd[1]: Reached target Timer Units.945server # [ 1.697547] systemd[1]: Listening on D-Bus System Message Bus Socket.946server # [ 1.698830] systemd[1]: Listening on Journal Socket (/dev/log).947server # [ 1.699999] systemd[1]: Listening on Journal Sockets.948server # [ 1.701014] systemd[1]: Listening on udev Control Socket.949server # [ 1.702058] systemd[1]: Listening on udev Kernel Socket.950server # [ 1.703030] systemd[1]: Reached target Socket Units.951server # [ 1.704838] systemd[1]: Starting Create List of Static Device Nodes...952server # [ 1.708616] systemd[1]: Starting Load Kernel Module configfs...953builder # [ 1.710808] systemd[1]: Queued start job for default target Initrd Default Target.954builder # [ 1.714008] systemd[1]: Created slice Slice /system/modprobe.955builder # [ 1.715212] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.956builder # [ 1.716648] systemd[1]: Expecting device /dev/disk/by-label/nixos...957builder # [ 1.717815] systemd[1]: Reached target Path Units.958builder # [ 1.718712] systemd[1]: Reached target Slice Units.959builder # [ 1.719633] systemd[1]: Reached target Swaps.960builder # [ 1.720481] systemd[1]: Reached target Timer Units.961builder # [ 1.721481] systemd[1]: Listening on D-Bus System Message Bus Socket.962builder # [ 1.722763] systemd[1]: Listening on Journal Socket (/dev/log).963builder # [ 1.723961] systemd[1]: Listening on Journal Sockets.964builder # [ 1.724999] systemd[1]: Listening on udev Control Socket.965builder # [ 1.726014] systemd[1]: Listening on udev Kernel Socket.966builder # [ 1.726981] systemd[1]: Reached target Socket Units.967server # [ 1.717965] systemd[1]: Starting Journal Service...968builder # [ 1.728696] systemd[1]: Starting Create List of Static Device Nodes...969builder # [ 1.732562] systemd[1]: Starting Load Kernel Module configfs...970server # [ 1.743978] systemd[1]: Starting Load Kernel Modules...971server # [ 1.747879] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os972builder # [ 1.739372] systemd[1]: Starting Journal Service...973server # [ 1.755846] systemd[1]: Starting Coldplug All udev Devices...974server # [ 1.766407] systemd-journald[65]: Collecting audit messages is disabled.975builder # [ 1.766927] systemd[1]: Starting Load Kernel Modules...976server # [ 1.769911] systemd[1]: Finished Create List of Static Device Nodes.977builder # [ 1.770843] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os978server # [ 1.777441] systemd[1]: modprobe@configfs.service: Deactivated successfully.979builder # [ 1.777527] systemd[1]: Starting Coldplug All udev Devices...980server # [ 1.784182] systemd[1]: Finished Load Kernel Module configfs.981builder # [ 1.789320] systemd-journald[65]: Collecting audit messages is disabled.982server # [ 1.790216] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config983builder # [ 1.793090] systemd[1]: Finished Create List of Static Device Nodes.984builder # [ 1.795648] systemd[1]: modprobe@configfs.service: Deactivated successfully.985server # [ 1.801854] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...986builder # [ 1.803138] systemd[1]: Finished Load Kernel Module configfs.987builder # [ 1.809177] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config988server # [ 1.814012] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.989server # [ 1.821814] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev990builder # [ 1.821816] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...991builder # [ 1.830314] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.992builder # [ 1.841930] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev993server # [ 1.844355] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.994server # [ 1.854012] systemd[1]: Starting Create Static Device Nodes in /dev...995builder # [ 1.856331] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.996builder # [ 1.863936] systemd[1]: Starting Create Static Device Nodes in /dev...997server # [ 1.865845] systemd[1]: Finished Load Kernel Modules.998server # [ 1.875857] systemd[1]: Starting Apply Kernel Variables...999builder # [ 1.881862] systemd[1]: Finished Load Kernel Modules.1000server # [ 1.748266] systemd-modules-load[67]: Inserted module 'dm_mod'1001builder # [ 1.888951] systemd[1]: Starting Apply Kernel Variables...1002server # [ 1.750274] systemd-modules-load[67]: Inserted module 'virtio_balloon'1003server # [ 1.891869] systemd[1]: Started Journal Service.1004server # [ 1.753292] systemd-modules-load[67]: Inserted module 'virtio_gpu'1005server # [ 1.761094] systemd[1]: Finished Create Static Device Nodes in /dev.1006builder # [ 1.904843] systemd[1]: Finished Create Static Device Nodes in /dev.1007server # [ 1.770091] systemd[1]: Reached target Preparation for Local File Systems.1008server # [ 1.771185] systemd[1]: Reached target Local File Systems.1009builder # [ 1.909966] systemd[1]: Reached target Preparation for Local File Systems.1010server # [ 1.775061] systemd[1]: Starting Create System Files and Directories...1011builder # [ 1.914840] systemd[1]: Reached target Local File Systems.1012builder # [ 1.773952] systemd-modules-load[67]: Inserted module 'dm_mod'1013builder # [ 1.778079] systemd-modules-load[67]: Inserted module 'virtio_balloon'1014builder # [ 1.779207] systemd-modules-load[67]: Inserted module 'virtio_gpu'1015server # [ 1.781042] systemd[1]: Starting Rule-based Manager for Device Events and Files...1016builder # [ 1.924094] systemd[1]: Starting Rule-based Manager for Device Events and Files...1017server # [ 1.787690] systemd[1]: Finished Apply Kernel Variables.1018builder # [ 1.930935] systemd[1]: Started Journal Service.1019builder # [ 1.795889] systemd[1]: Finished Apply Kernel Variables.1020server # [ 1.808192] systemd[1]: Finished Create System Files and Directories.1021builder # [ 1.808153] systemd[1]: Starting Create System Files and Directories...1022server # [ 1.834640] systemd-udevd[79]: Using default interface naming scheme 'v261'.1023builder # [ 1.834399] systemd[1]: Finished Create System Files and Directories.1024builder # [ 1.842305] systemd-udevd[73]: Using default interface naming scheme 'v261'.1025server # [ 1.859070] systemd[1]: Started Rule-based Manager for Device Events and Files.1026builder # [ 1.866427] systemd[1]: Started Rule-based Manager for Device Events and Files.1027server # [ 1.910667] systemd[1]: Finished Coldplug All udev Devices.1028server # [ 1.911637] systemd[1]: Reached target System Initialization.1029server # [ 1.912484] systemd[1]: Reached target Basic System.1030builder # [ 1.922867] systemd[1]: Finished Coldplug All udev Devices.1031builder # [ 1.923784] systemd[1]: Reached target System Initialization.1032builder # [ 1.924635] systemd[1]: Reached target Basic System.1033server # [ 2.240930] virtio_blk virtio2: 1/0/0 default/read/poll queues1034server # [ 2.242673] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1035server # [ 2.245611] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121036builder # [ 2.251765] virtio_blk virtio2: 1/0/0 default/read/poll queues1037server # [ 2.254467] serio: i8042 KBD port at 0x60,0x64 irq 11038builder # [ 2.256551] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1039server # [ 2.261095] serio: i8042 AUX port at 0x60,0x64 irq 121040builder # [ 2.262870] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121041server # [ 2.269102] ehci-pci 0000:00:1d.7: EHCI Host Controller1042server # [ 2.269840] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11043builder # [ 2.269718] ehci-pci 0000:00:1d.7: EHCI Host Controller1044builder # [ 2.270569] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11045server # [ 2.271568] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001046builder # [ 2.272818] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001047server # [ 2.277805] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001048server # [ 2.278664] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181049builder # [ 2.278801] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001050builder # [ 2.279713] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181051builder # [ 2.280978] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11052server # [ 2.281782] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11053server # [ 2.283097] usb usb1: Product: EHCI Host Controller1054builder # [ 2.283760] usb usb1: Product: EHCI Host Controller1055server # [ 2.284556] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1056builder # [ 2.284448] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1057server # [ 2.285783] usb usb1: SerialNumber: 0000:00:1d.71058builder # [ 2.285707] serio: i8042 KBD port at 0x60,0x64 irq 11059builder # [ 2.286429] serio: i8042 AUX port at 0x60,0x64 irq 121060builder # [ 2.287258] usb usb1: SerialNumber: 0000:00:1d.71061server # [ 2.288053] hub 1-0:1.0: USB hub found1062server # [ 2.288610] hub 1-0:1.0: 6 ports detected1063builder # [ 2.290384] hub 1-0:1.0: USB hub found1064builder # [ 2.291347] hub 1-0:1.0: 6 ports detected1065server # [ 2.296150] uhci_hcd 0000:00:1d.0: UHCI Host Controller1066server # [ 2.296888] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21067builder # [ 2.304428] uhci_hcd 0000:00:1d.0: UHCI Host Controller1068builder # [ 2.305210] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21069server # [ 2.306312] uhci_hcd 0000:00:1d.0: detected 2 ports1070server # [ 2.313304] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801071builder # [ 2.317296] uhci_hcd 0000:00:1d.0: detected 2 ports1072server # [ 2.322469] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181073builder # [ 2.322489] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801074server # [ 2.323604] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11075builder # [ 2.331532] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181076builder # [ 2.334047] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11077server # [ 2.339779] usb usb2: Product: UHCI Host Controller1078server # [ 2.340496] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1079builder # [ 2.345627] usb usb2: Product: UHCI Host Controller1080server # [ 2.350018] SCSI subsystem initialized1081builder # [ 2.352753] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1082builder # [ 2.353516] usb usb2: SerialNumber: 0000:00:1d.01083server # [ 2.354951] usb usb2: SerialNumber: 0000:00:1d.01084server # [ 2.218103] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.1085server # [ 2.219447] systemd[1]: Starting Virtual Console Setup...1086builder # [ 2.219209] (udev-worker)[87]: Network interface NamePolicy= disabled on kernel command line.1087builder # [ 2.224615] systemd[1]: Starting Virtual Console Setup...1088builder # [ 2.369113] hub 2-0:1.0: USB hub found1089builder # [ 2.375399] SCSI subsystem initialized1090builder # [ 2.375975] hub 2-0:1.0: 2 ports detected1091server # [ 2.377982] hub 2-0:1.0: USB hub found1092server # [ 2.241714] (udev-worker)[92]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1093builder # [ 2.241974] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1094builder # [ 2.245368] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1095server # [ 2.387794] hub 2-0:1.0: 2 ports detected1096server # [ 2.249378] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.1097server # [ 2.393043] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01098server # [ 2.260961] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.1099server # [ 2.405384] uhci_hcd 0000:00:1d.1: UHCI Host Controller1100builder # [ 2.264615] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1101server # [ 2.266542] systemd[1]: Finished Virtual Console Setup.1102builder # [ 2.267928] systemd[1]: Finished Virtual Console Setup.1103builder # [ 2.418946] uhci_hcd 0000:00:1d.1: UHCI Host Controller1104builder # [ 2.419689] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31105server # [ 2.422034] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31106builder # [ 2.424805] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01107server # [ 2.430824] uhci_hcd 0000:00:1d.1: detected 2 ports1108server # [ 2.431617] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01109builder # [ 2.431498] uhci_hcd 0000:00:1d.1: detected 2 ports1110server # [ 2.293926] systemd[1]: Found device /dev/disk/by-label/nixos.1111server # [ 2.296500] systemd[1]: Reached target Initrd Root Device.1112builder # [ 2.299974] systemd[1]: Found device /dev/disk/by-label/nixos.1113server # [ 2.301076] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1114builder # [ 2.440642] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01115builder # [ 2.303040] systemd[1]: Reached target Initrd Root Device.1116server # [ 2.443892] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181117builder # [ 2.304305] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1118server # [ 2.445029] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11119server # [ 2.448776] usb usb3: Product: UHCI Host Controller1120server # [ 2.449491] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1121builder # [ 2.448847] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181122builder # [ 2.449971] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11123builder # [ 2.453745] usb usb3: Product: UHCI Host Controller1124builder # [ 2.454437] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1125server # [ 2.457086] usb usb3: SerialNumber: 0000:00:1d.11126server # [ 2.459358] hub 3-0:1.0: USB hub found1127builder # [ 2.461369] usb usb3: SerialNumber: 0000:00:1d.11128server # [ 2.464043] hub 3-0:1.0: 2 ports detected1129builder # [ 2.464016] hub 3-0:1.0: USB hub found1130builder # [ 2.469029] hub 3-0:1.0: 2 ports detected1131server # [ 2.470100] uhci_hcd 0000:00:1d.2: UHCI Host Controller1132server # [ 2.471090] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41133builder # [ 2.472910] uhci_hcd 0000:00:1d.2: UHCI Host Controller1134builder # [ 2.473690] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41135server # [ 2.476901] uhci_hcd 0000:00:1d.2: detected 2 ports1136server # [ 2.477712] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01137builder # [ 2.477834] uhci_hcd 0000:00:1d.2: detected 2 ports1138builder # [ 2.478636] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01139server # [ 2.340304] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1140server # [ 2.481911] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181141builder # [ 2.482036] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181142builder # [ 2.485039] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11143server # [ 2.346661] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1144server # [ 2.489203] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11145builder # [ 2.489533] usb usb4: Product: UHCI Host Controller1146builder # [ 2.490496] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1147builder # [ 2.491477] usb usb4: SerialNumber: 0000:00:1d.21148builder # [ 2.353697] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1149server # [ 2.495044] usb usb4: Product: UHCI Host Controller1150server # [ 2.496093] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1151server # [ 2.496992] usb usb4: SerialNumber: 0000:00:1d.21152server # [ 2.499239] hub 4-0:1.0: USB hub found1153builder # [ 2.360196] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1154server # [ 2.501926] hub 4-0:1.0: 2 ports detected1155builder # [ 2.501676] hub 4-0:1.0: USB hub found1156builder # [ 2.503345] hub 4-0:1.0: 2 ports detected1157server # [ 2.519798] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1158server # [ 2.523913] usb 1-1: new high-speed USB device number 2 using ehci-pci1159builder # [ 2.526770] usb 1-1: new high-speed USB device number 2 using ehci-pci1160server # [ 2.532685] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1161server # [ 2.533620] ahci 0000:00:1f.2: flags: 64bit ncq only1162builder # [ 2.533689] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1163builder # [ 2.536055] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1164builder # [ 2.536910] ahci 0000:00:1f.2: flags: 64bit ncq only1165builder # [ 2.541481] scsi host0: ahci1166server # [ 2.543089] scsi host0: ahci1167builder # [ 2.543256] scsi host1: ahci1168builder # [ 2.544771] scsi host2: ahci1169server # [ 2.545796] scsi host1: ahci1170builder # [ 2.546412] scsi host3: ahci1171server # [ 2.547821] scsi host2: ahci1172builder # [ 2.548026] scsi host4: ahci1173server # [ 2.549466] scsi host3: ahci1174builder # [ 2.549845] scsi host5: ahci1175server # [ 2.551145] scsi host4: ahci1176builder # [ 2.550464] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11177server # [ 2.552791] scsi host5: ahci1178server # [ 2.553342] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11179builder # [ 2.552698] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11180builder # [ 2.553949] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11181server # [ 2.555470] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11182builder # [ 2.555083] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11183server # [ 2.556643] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11184builder # [ 2.556309] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11185server # [ 2.557906] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11186builder # [ 2.557478] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11187server # [ 2.559071] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11188server # [ 2.560201] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11189server # [ 2.654925] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001190server # [ 2.657784] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101191builder # [ 2.657119] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001192server # [ 2.660952] usb 1-1: Product: QEMU USB Tablet1193builder # [ 2.660013] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101194server # [ 2.662680] usb 1-1: Manufacturer: QEMU1195builder # [ 2.663357] usb 1-1: Product: QEMU USB Tablet1196server # [ 2.664322] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11197builder # [ 2.665199] usb 1-1: Manufacturer: QEMU1198builder # [ 2.666871] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11199server # [ 2.696379] hid: raw HID events driver (C) Jiri Kosina1200builder # [ 2.700139] hid: raw HID events driver (C) Jiri Kosina1201server # [ 2.627991] systemd[1]: Mounting /sysroot...1202builder # [ 2.649404] systemd[1]: Mounting /sysroot...1203server # [ 2.867050] ata1: SATA link down (SStatus 0 SControl 300)1204builder # [ 2.871842] ata4: SATA link down (SStatus 0 SControl 300)1205builder # [ 2.873665] ata2: SATA link down (SStatus 0 SControl 300)1206server # [ 2.874421] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1207builder # [ 2.876065] ata1: SATA link down (SStatus 0 SControl 300)1208server # [ 2.877077] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001209server # [ 2.878983] ata3.00: applying bridge limits1210builder # [ 2.878496] ata5: SATA link down (SStatus 0 SControl 300)1211server # [ 2.881005] ata4: SATA link down (SStatus 0 SControl 300)1212builder # [ 2.880892] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1213server # [ 2.883092] ata5: SATA link down (SStatus 0 SControl 300)1214builder # [ 2.883182] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001215server # [ 2.885389] ata2: SATA link down (SStatus 0 SControl 300)1216builder # [ 2.885382] ata3.00: applying bridge limits1217server # [ 2.887672] ata6: SATA link down (SStatus 0 SControl 300)1218builder # [ 2.887303] ata6: SATA link down (SStatus 0 SControl 300)1219builder # [ 2.889366] ata3.00: configured for UDMA/1001220server # [ 2.889915] ata3.00: configured for UDMA/1001221builder # [ 2.891651] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51222server # [ 2.892435] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51223builder # [ 2.962224] usbcore: registered new interface driver usbhid1224builder # [ 2.963027] usbhid: USB HID core driver1225server # [ 2.970890] usbcore: registered new interface driver usbhid1226server # [ 2.971673] usbhid: USB HID core driver1227builder # [ 2.983012] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1228builder # [ 2.989961] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21229server # [ 2.991948] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1230builder # [ 2.994101] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01231builder # [ 2.998196] cdrom: Uniform CD-ROM driver Revision: 3.201232server # [ 3.001001] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input21233server # [ 3.005563] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01234server # [ 3.008252] cdrom: Uniform CD-ROM driver Revision: 3.201235builder # [ 3.011048] EXT4-fs (vda): mounted filesystem e56bcf8b-8d78-45e1-a34d-aa587098b689 r/w with ordered data mode. Quota mode: none.1236builder # [ 2.878284] systemd[1]: Mounted /sysroot.1237builder # [ 2.880560] systemd[1]: Reached target Initrd Root File System.1238server # [ 3.022955] EXT4-fs (vda): mounted filesystem 37fd6ef0-b064-474c-83fc-4278552e3934 r/w with ordered data mode. Quota mode: none.1239builder # [ 2.884961] systemd[1]: Mounting /sysroot/nix/.ro-store...1240server # [ 2.889402] systemd[1]: Mounted /sysroot.1241server # [ 2.891626] systemd[1]: Reached target Initrd Root File System.1242builder # [ 2.891788] systemd[1]: Mounting /sysroot/nix/.rw-store...1243server # [ 2.895591] systemd[1]: Starting Mountpoints Configured in the Real Root...1244builder # [ 2.896135] systemd[1]: Mounting /sysroot/run...1245builder # [ 2.900333] systemd[1]: Mounting /sysroot/tmp/shared...1246builder # [ 2.910875] systemd[1]: Mounting /sysroot/tmp/xchg...1247server # [ 2.910988] systemd-sysroot-fstab-check[136]: /sysroot should be mounted in the initrd, will request daemon-reload.1248server # [ 2.913839] systemd[1]: Reload requested from client PID 136 ('systemd-sysroot') (unit initrd-parse-etc.service)...1249server # [ 2.915286] systemd[1]: Reloading...1250builder # [ 2.915234] systemd[1]: Starting Mountpoints Configured in the Real Root...1251builder # [ 2.943218] systemd-sysroot-fstab-check[144]: /sysroot should be mounted in the initrd, will request daemon-reload.1252builder # [ 2.968795] systemd[1]: Mounted /sysroot/nix/.ro-store.1253builder # [ 2.969955] systemd[1]: Mounted /sysroot/nix/.rw-store.1254builder # [ 2.970940] systemd[1]: Mounted /sysroot/run.1255builder # [ 2.972137] systemd[1]: Mounted /sysroot/tmp/shared.1256builder # [ 2.972858] systemd[1]: Mounted /sysroot/tmp/xchg.1257builder # [ 2.977772] systemd[1]: Reload requested from client PID 144 ('systemd-sysroot') (unit initrd-parse-etc.service)...1258builder # [ 2.980165] systemd[1]: Reloading...1259server # [ 2.999446] systemd[1]: Reloading finished in 82 ms.1260server # [ 3.008666] systemd-sysroot-fstab-check[136]: Requesting initrd-fs.target/start/replace...1261server # [ 3.013171] systemd-sysroot-fstab-check[136]: Requesting swap.target/start/replace...1262server # [ 3.017270] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1263server # [ 3.019137] systemd[1]: Finished Mountpoints Configured in the Real Root.1264server # [ 3.020088] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1265builder # [ 3.044175] systemd[1]: Reloading finished in 64 ms.1266builder # [ 3.052982] systemd-sysroot-fstab-check[144]: Requesting initrd-fs.target/start/replace...1267builder # [ 3.056134] systemd-sysroot-fstab-check[144]: Requesting swap.target/start/replace...1268builder # [ 3.057829] systemd[1]: Starting rw-sysroot-nix-store.service...1269builder # [ 3.061471] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1270builder # [ 3.064122] systemd[1]: Finished Mountpoints Configured in the Real Root.1271builder # [ 3.065670] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1272builder # [ 3.076369] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1273builder # [ 3.077666] systemd[1]: Finished rw-sysroot-nix-store.service.1274server # [ 3.631209] systemd[1]: Mounting /sysroot/nix/.ro-store...1275server # [ 3.636294] systemd[1]: Mounting /sysroot/nix/.rw-store...1276server # [ 3.643297] systemd[1]: Mounting /sysroot/run...1277builder # [ 3.649548] systemd[1]: Mounting /sysroot/nix/store...1278server # [ 3.656326] systemd[1]: Mounting /sysroot/tmp/shared...1279server # [ 3.664508] systemd[1]: Mounting /sysroot/tmp/xchg...1280builder # [ 3.698796] systemd[1]: Mounted /sysroot/nix/store.1281builder # [ 3.700330] systemd[1]: Reached target Initrd File Systems.1282builder # [ 3.702116] systemd[1]: Starting Find NixOS closure...1283server # [ 3.703639] systemd[1]: Mounted /sysroot/nix/.rw-store.1284builder # [ 3.704632] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1285server # [ 3.708726] systemd[1]: Mounted /sysroot/nix/.ro-store.1286server # [ 3.711040] systemd[1]: Mounted /sysroot/run.1287server # [ 3.714689] systemd[1]: Mounted /sysroot/tmp/shared.1288server # [ 3.715673] systemd[1]: Mounted /sysroot/tmp/xchg.1289server # [ 3.720086] systemd[1]: Starting rw-sysroot-nix-store.service...1290builder # [ 3.725516] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1291builder # [ 3.727306] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1292server # [ 3.731950] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1293server # [ 3.733541] systemd[1]: Finished rw-sysroot-nix-store.service.1294builder # [ 3.734845] systemd[1]: Finished Find NixOS closure.1295builder # [ 3.736348] systemd[1]: Reached target Initrd Default Target.1296builder # [ 3.738153] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1297builder # [ 3.751469] systemd[1]: Stopped target Initrd Default Target.1298builder # [ 3.752737] systemd[1]: Stopped target Basic System.1299builder # [ 3.753687] systemd[1]: Stopped target Initrd Root Device.1300builder # [ 3.754549] systemd[1]: Stopped target Path Units.1301builder # [ 3.756113] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1302builder # [ 3.757123] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1303builder # [ 3.758155] systemd[1]: Stopped target Slice Units.1304builder # [ 3.759026] systemd[1]: Stopped target Socket Units.1305builder # [ 3.759733] systemd[1]: Stopped target System Initialization.1306builder # [ 3.760575] systemd[1]: Stopped target Swaps.1307builder # [ 3.761455] systemd[1]: Stopped target Timer Units.1308builder # [ 3.762526] systemd[1]: dbus.socket: Deactivated successfully.1309builder # [ 3.763597] systemd[1]: Closed D-Bus System Message Bus Socket.1310builder # [ 3.764578] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1311builder # [ 3.766140] systemd[1]: Stopped Find NixOS closure.1312builder # [ 3.767479] systemd[1]: Starting rw-sysroot-nix-store.service...1313builder # [ 3.769164] systemd[1]: systemd-sysctl.service: Deactivated successfully.1314builder # [ 3.770286] systemd[1]: Stopped Apply Kernel Variables.1315builder # [ 3.771334] systemd[1]: systemd-modules-load.service: Deactivated successfully.1316builder # [ 3.772488] systemd[1]: Stopped Load Kernel Modules.1317builder # [ 3.773458] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1318builder # [ 3.774899] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1319builder # [ 3.776106] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1320builder # [ 3.778315] systemd[1]: Stopped Create System Files and Directories.1321builder # [ 3.779238] systemd[1]: Stopped target Local File Systems.1322builder # [ 3.780082] systemd[1]: Stopped target Preparation for Local File Systems.1323builder # [ 3.780992] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1324builder # [ 3.781931] systemd[1]: Stopped Coldplug All udev Devices.1325builder # [ 3.783779] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1326builder # [ 3.785201] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1327builder # [ 3.786573] systemd[1]: Stopped Virtual Console Setup.1328builder # [ 3.797117] systemd[1]: initrd-cleanup.service: Deactivated successfully.1329builder # [ 3.799233] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1330builder # [ 3.801914] systemd[1]: systemd-udevd.service: Deactivated successfully.1331builder # [ 3.803074] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1332builder # [ 3.810166] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1333builder # [ 3.811385] systemd[1]: Finished rw-sysroot-nix-store.service.1334builder # [ 3.812628] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1335builder # [ 3.813782] systemd[1]: Closed udev Control Socket.1336builder # [ 3.815397] systemd[1]: Starting Cleanup udev Database...1337builder # [ 3.816585] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1338builder # [ 3.817643] systemd[1]: Stopped Create Static Device Nodes in /dev.1339builder # [ 3.818483] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1340builder # [ 3.820129] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1341builder # [ 3.821158] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1342builder # [ 3.822108] systemd[1]: Stopped Create List of Static Device Nodes.1343builder # [ 3.836816] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1344builder # [ 3.839082] systemd[1]: Finished Cleanup udev Database.1345builder # [ 3.839872] systemd[1]: Reached target Switch Root.1346builder # [ 3.841673] systemd[1]: Starting NixOS Activation...1347builder # [ 3.924405] initrd-nixos-activation-start[189]: booting system configuration /nix/store/380z13x9384wfk93j6facg9z2kkbjxp0-nixos-system-builder-test1348builder # [ 3.953556] initrd-nixos-activation-start[189]: running activation script...1349builder # [ 4.188284] initrd-nixos-activation-start[212]: setting up /etc...1350builder # [ 4.295598] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1351builder # [ 4.297413] systemd[1]: Finished NixOS Activation.1352builder # [ 4.299494] systemd[1]: Starting Switch Root...1353builder # [ 4.311961] systemd[1]: Switching root.1354builder # [ 4.578967] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1355builder # [ 4.689032] NET: Registered PF_VSOCK protocol family1356server # [ 4.627487] systemd[1]: Mounting /sysroot/nix/store...1357server # [ 4.652230] systemd[1]: Mounted /sysroot/nix/store.1358server # [ 4.653591] systemd[1]: Reached target Initrd File Systems.1359server # [ 4.655490] systemd[1]: Starting Find NixOS closure...1360server # [ 4.659137] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1361server # [ 4.677939] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1362server # [ 4.679635] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1363server # [ 4.688307] systemd[1]: Finished Find NixOS closure.1364server # [ 4.689990] systemd[1]: Reached target Initrd Default Target.1365server # [ 4.691129] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1366server # [ 4.706188] systemd[1]: initrd-cleanup.service: Deactivated successfully.1367server # [ 4.707525] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1368server # [ 4.709851] systemd[1]: Stopped target Initrd Default Target.1369server # [ 4.711149] systemd[1]: Stopped target Basic System.1370server # [ 4.711889] systemd[1]: Stopped target Initrd Root Device.1371server # [ 4.713138] systemd[1]: Stopped target Path Units.1372server # [ 4.713860] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1373server # [ 4.715134] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1374server # [ 4.716361] systemd[1]: Stopped target Slice Units.1375server # [ 4.717899] systemd[1]: Stopped target Socket Units.1376server # [ 4.718671] systemd[1]: Stopped target System Initialization.1377server # [ 4.719451] systemd[1]: Stopped target Swaps.1378server # [ 4.720146] systemd[1]: Stopped target Timer Units.1379server # [ 4.720994] systemd[1]: dbus.socket: Deactivated successfully.1380server # [ 4.722826] systemd[1]: Closed D-Bus System Message Bus Socket.1381server # [ 4.723653] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1382server # [ 4.724652] systemd[1]: Stopped Find NixOS closure.1383server # [ 4.726414] systemd[1]: Starting rw-sysroot-nix-store.service...1384server # [ 4.727380] systemd[1]: systemd-sysctl.service: Deactivated successfully.1385server # [ 4.728348] systemd[1]: Stopped Apply Kernel Variables.1386server # [ 4.729481] systemd[1]: systemd-modules-load.service: Deactivated successfully.1387server # [ 4.731133] systemd[1]: Stopped Load Kernel Modules.1388server # [ 4.733125] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1389server # [ 4.734278] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1390server # [ 4.735416] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1391server # [ 4.736401] systemd[1]: Stopped Create System Files and Directories.1392server # [ 4.737288] systemd[1]: Stopped target Local File Systems.1393server # [ 4.738721] systemd[1]: Stopped target Preparation for Local File Systems.1394server # [ 4.739925] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1395server # [ 4.741137] systemd[1]: Stopped Coldplug All udev Devices.1396server # [ 4.742363] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1397server # [ 4.744146] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1398server # [ 4.745511] systemd[1]: Stopped Virtual Console Setup.1399server # [ 4.753436] systemd[1]: systemd-udevd.service: Deactivated successfully.1400server # [ 4.757161] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1401server # [ 4.758240] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1402server # [ 4.759302] systemd[1]: Closed udev Control Socket.1403server # [ 4.761900] systemd[1]: Starting Cleanup udev Database...1404server # [ 4.762679] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1405server # [ 4.763771] systemd[1]: Stopped Create Static Device Nodes in /dev.1406server # [ 4.764652] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1407server # [ 4.766392] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1408server # [ 4.768146] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1409server # [ 4.769111] systemd[1]: Stopped Create List of Static Device Nodes.1410server # [ 4.773371] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1411server # [ 4.775134] systemd[1]: Finished rw-sysroot-nix-store.service.1412server # [ 4.789133] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1413server # [ 4.791153] systemd[1]: Finished Cleanup udev Database.1414server # [ 4.792265] systemd[1]: Reached target Switch Root.1415server # [ 4.794077] systemd[1]: Starting NixOS Activation...1416server # [ 4.869065] initrd-nixos-activation-start[188]: booting system configuration /nix/store/39vygmhfn4f518m2ip151is6y5420s6r-nixos-system-server-test1417server # [ 4.897157] initrd-nixos-activation-start[188]: running activation script...1418builder # [ 5.050400] systemd[1]: systemd 261.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)1419builder # [ 5.061564] systemd[1]: Detected virtualization kvm.1420builder # [ 5.063832] systemd[1]: Detected architecture x86-64.1421builder # [ 5.065987] systemd[1]: Detected first boot.1422builder # [ 5.070477] systemd[1]: Initializing machine ID from random generator.1423builder # [ 5.191826] systemd[1]: bpf-restrict-fs: LSM BPF program attached1424server # [ 5.112819] initrd-nixos-activation-start[211]: setting up /etc...1425builder # [ 5.288070] systemd[1]: Applying preset policy.1426server # [ 5.220681] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1427server # [ 5.223081] systemd[1]: Finished NixOS Activation.1428server # [ 5.224192] systemd[1]: Starting Switch Root...1429server # [ 5.235879] systemd[1]: Switching root.1430builder # [ 5.467443] systemd[1]: Populated /etc with preset unit settings.1431server # [ 5.495823] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1432server # [ 5.592361] NET: Registered PF_VSOCK protocol family1433builder # [ 5.678380] systemd[1]: initrd-switch-root.service: Deactivated successfully.1434builder # [ 5.679873] systemd[1]: Stopped initrd-switch-root.service.1435builder # [ 5.682178] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1436builder # [ 5.684317] systemd[1]: Created slice Slice /system/getty.1437builder # [ 5.685678] systemd[1]: Created slice User and Session Slice.1438builder # [ 5.686630] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1439builder # [ 5.687891] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1440builder # [ 5.688970] systemd[1]: Expecting device /dev/hvc0...1441builder # [ 5.689655] systemd[1]: Expecting device /dev/ttyS0...1442builder # [ 5.690463] systemd[1]: Reached target Local Encrypted Volumes.1443builder # [ 5.691320] systemd[1]: Stopped target initrd-fs.target.1444builder # [ 5.692073] systemd[1]: Stopped target initrd-root-fs.target.1445builder # [ 5.692862] systemd[1]: Stopped target initrd-switch-root.target.1446builder # [ 5.693753] systemd[1]: Reached target Virtual Machines and Containers.1447builder # [ 5.694664] systemd[1]: Reached target Path Units.1448builder # [ 5.695407] systemd[1]: Reached target Remote File Systems.1449builder # [ 5.696221] systemd[1]: Reached target Slice Units.1450builder # [ 5.696920] systemd[1]: Reached target Swaps.1451builder # [ 5.710692] systemd[1]: Listening on Query the User Interactively for a Password.1452builder # [ 5.713345] systemd[1]: Listening on Process Core Dump Socket.1453builder # [ 5.715182] systemd[1]: Listening on Credential Encryption/Decryption.1454builder # [ 5.717113] systemd[1]: Listening on Factory Reset Management.1455builder # [ 5.718055] systemd[1]: Listening on Hostname Service Socket.1456builder # [ 5.721133] systemd[1]: Starting Journal Log Access Socket...1457builder # [ 5.722367] systemd[1]: Listening on Journal Audit Socket.1458builder # [ 5.724912] systemd[1]: Listening on Console Output Muting Service Socket.1459builder # [ 5.726443] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1460builder # [ 5.727587] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1461builder # [ 5.728916] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1462builder # [ 5.733178] systemd[1]: Listening on Disk Repartitioning Service Socket.1463builder # [ 5.734191] systemd[1]: Listening on udev Control Socket.1464builder # [ 5.735040] systemd[1]: Listening on udev Varlink Socket.1465builder # [ 5.737833] systemd[1]: Mounting Huge Pages File System...1466builder # [ 5.741399] systemd[1]: Mounting POSIX Message Queue File System...1467builder # [ 5.747232] systemd[1]: Mounting Kernel Debug File System...1468builder # [ 5.755563] systemd[1]: Mounting Kernel Trace File System...1469builder # [ 5.763275] systemd[1]: Starting Create List of Static Device Nodes...1470builder # [ 5.774545] systemd[1]: Starting Load Kernel Module configfs...1471builder # [ 5.778907] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1472builder # [ 5.784993] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1473builder # [ 5.793914] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1474builder # [ 5.808538] systemd[1]: Mounting FUSE Control File System...1475builder # [ 5.812284] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671476builder # [ 5.822081] systemd[1]: Starting Journal Service...1477builder # [ 5.829108] systemd[1]: Starting Load Kernel Modules...1478builder # [ 5.839941] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1479builder # [ 5.844958] systemd[1]: Starting Remount Root and Kernel File Systems...1480builder # [ 5.847018] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1481builder # [ 5.853346] systemd[1]: Starting Coldplug All udev Devices...1482builder # [ 5.858695] systemd[1]: Listening on Journal Log Access Socket.1483builder # [ 5.860918] systemd[1]: Mounted Huge Pages File System.1484builder # [ 5.862863] systemd[1]: Mounted POSIX Message Queue File System.1485builder # [ 5.865029] systemd[1]: Mounted Kernel Debug File System.1486builder # [ 5.866956] systemd[1]: Mounted Kernel Trace File System.1487builder # [ 5.868886] systemd[1]: Mounted FUSE Control File System.1488builder # [ 5.876348] systemd[1]: Finished Create List of Static Device Nodes.1489builder # [ 5.880692] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1490builder # [ 5.913028] systemd[1]: modprobe@configfs.service: Deactivated successfully.1491builder # [ 5.914665] systemd[1]: Finished Load Kernel Module configfs.1492builder # [ 5.921778] systemd[1]: Mounting Kernel Configuration File System...1493builder # [ 5.940330] systemd[1]: Mounted Kernel Configuration File System.1494builder # [ 5.949787] EXT4-fs (vda): re-mounted e56bcf8b-8d78-45e1-a34d-aa587098b689.1495builder # [ 5.957638] systemd-journald[282]: Collecting audit messages is enabled.1496server # [ 5.952040] systemd[1]: systemd 261.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)1497builder # [ 5.960127] systemd[1]: Finished Remount Root and Kernel File Systems.1498server # [ 5.961590] systemd[1]: Detected virtualization kvm.1499builder # [ 5.963109] systemd[1]: Listening on Disk Image Download Service Socket.1500server # [ 5.963426] systemd[1]: Detected architecture x86-64.1501server # [ 5.965323] systemd[1]: Detected first boot.1502builder # [ 5.964811] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1503server # [ 5.969402] systemd[1]: Initializing machine ID from random generator.1504builder # [ 5.971856] systemd[1]: Starting Load/Save OS Random Seed...1505builder # [ 5.972666] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1506builder # [ 5.983191] loop: module loaded1507builder # [ 5.862861] systemd[1]: Queued start job for default target Multi-User System.[ 6.003567] systemd[1]: Started Journal Service.1508builder # 1509builder # [ 6.005534] tun: Universal TUN/TAP device driver, 1.61510builder # [ 5.871686] systemd[1]: systemd-journald.service: Deactivated successfully.1511builder # [ 5.876289] systemd-modules-load[283]: Inserted module 'loop'1512builder # [ 5.881194] systemd[1]: Starting Flush Journal to Persistent Storage...1513builder # [ 5.890519] systemd-modules-load[283]: Inserted module 'tun'1514builder # [ 5.891553] systemd[1]: Finished Load Kernel Modules.1515builder # [ 5.894265] systemd[1]: Starting Firewall...1516builder # [ 5.902122] systemd[1]: Starting Apply Kernel Variables...1517builder # [ 5.920804] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1518builder # [ 5.929194] systemd[1]: Starting Create Static Device Nodes in /dev...1519builder # [ 5.942887] systemd[1]: Finished Load/Save OS Random Seed.1520builder # [ 5.944225] systemd[1]: Reached target First Boot Complete.1521builder # [ 5.968654] systemd-oomd[284]: No swap; memory pressure usage will be degraded1522builder # [ 6.111373] systemd-journald[282]: Received client request to flush runtime journal.1523builder # [ 6.082497] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1524builder # [ 6.083489] systemd[1]: Finished Apply Kernel Variables.1525builder # [ 6.084936] systemd[1]: Finished Create Static Device Nodes in /dev.1526builder # [ 6.087231] systemd[1]: Reached target Preparation for Local File Systems.1527builder # [ 6.088227] systemd[1]: Starting Rule-based Manager for Device Events and Files...1528builder # [ 6.099057] systemd[1]: Finished Flush Journal to Persistent Storage.1529server # [ 6.244577] systemd[1]: bpf-restrict-fs: LSM BPF program attached1530builder # [ 6.176528] systemd-udevd[315]: Using default interface naming scheme 'v261'.1531server # [ 6.327540] systemd[1]: Applying preset policy.1532builder # [ 6.251585] systemd[1]: Started Rule-based Manager for Device Events and Files.1533builder # [ 6.310436] systemd[1]: Finished Coldplug All udev Devices.1534server # [ 6.511040] systemd[1]: Populated /etc with preset unit settings.1535builder # [ 6.412682] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1536builder # [ 6.497966] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1537builder # [ 6.526558] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1538builder # [ 6.542078] systemd[1]: Mounting /run/wrappers...1539builder # [ 6.581074] systemd[1]: Mounted /run/wrappers.1540builder # [ 6.581909] systemd[1]: Reached target Local File Systems.1541builder # [ 6.582973] (udev-worker)[357]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1542server # [ 6.727508] systemd[1]: initrd-switch-root.service: Deactivated successfully.1543builder # [ 6.587208] (udev-worker)[357]: Network interface NamePolicy= disabled on kernel command line.1544server # [ 6.729007] systemd[1]: Stopped initrd-switch-root.service.1545builder # [ 6.590149] systemd[1]: Listening on Boot Loader Control Service Socket.1546server # [ 6.731355] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1547builder # [ 6.592621] systemd[1]: Starting register-nix-paths.service...1548server # [ 6.733304] systemd[1]: Created slice Slice /system/getty.1549server # [ 6.734643] systemd[1]: Created slice User and Session Slice.1550builder # [ 6.595572] systemd[1]: Starting Create SUID/SGID Wrappers...1551server # [ 6.735588] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1552server # [ 6.736855] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1553server # [ 6.737963] systemd[1]: Expecting device /dev/hvc0...1554builder # [ 6.597615] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1555server # [ 6.738668] systemd[1]: Expecting device /dev/ttyS0...1556server # [ 6.739447] systemd[1]: Reached target Local Encrypted Volumes.1557server # [ 6.740265] systemd[1]: Stopped target initrd-fs.target.1558server # [ 6.741001] systemd[1]: Stopped target initrd-root-fs.target.1559builder # [ 6.601265] systemd[1]: Starting Save Transient machine-id to Disk...1560server # [ 6.741824] systemd[1]: Stopped target initrd-switch-root.target.1561server # [ 6.742677] systemd[1]: Reached target Virtual Machines and Containers.1562builder # [ 6.603114] systemd[1]: Starting Create System Files and Directories...1563server # [ 6.743594] systemd[1]: Reached target Path Units.1564server # [ 6.744332] systemd[1]: Reached target Remote File Systems.1565server # [ 6.745093] systemd[1]: Reached target Slice Units.1566server # [ 6.745791] systemd[1]: Reached target Swaps.1567server # [ 6.747738] systemd[1]: Listening on Query the User Interactively for a Password.1568server # [ 6.750262] systemd[1]: Listening on Process Core Dump Socket.1569server # [ 6.752082] systemd[1]: Listening on Credential Encryption/Decryption.1570builder # [ 6.616752] (udev-worker)[358]: Network interface NamePolicy= disabled on kernel command line.1571server # [ 6.754015] systemd[1]: Listening on Factory Reset Management.1572server # [ 6.766568] systemd[1]: Listening on Hostname Service Socket.1573server # [ 6.769520] systemd[1]: Starting Journal Log Access Socket...1574server # [ 6.771087] systemd[1]: Listening on Journal Audit Socket.1575server # [ 6.773937] systemd[1]: Listening on Console Output Muting Service Socket.1576server # [ 6.775061] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1577server # [ 6.776151] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1578server # [ 6.777428] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1579server # [ 6.781530] systemd[1]: Listening on Disk Repartitioning Service Socket.1580server # [ 6.782566] systemd[1]: Listening on udev Control Socket.1581server # [ 6.783463] systemd[1]: Listening on udev Varlink Socket.1582server # [ 6.785870] systemd[1]: Mounting Huge Pages File System...1583server # [ 6.789829] systemd[1]: Mounting POSIX Message Queue File System...1584server # [ 6.796832] systemd[1]: Mounting Kernel Debug File System...1585server # [ 6.803867] systemd[1]: Mounting Kernel Trace File System...1586server # [ 6.810458] systemd[1]: Starting Create List of Static Device Nodes...1587server # [ 6.819027] systemd[1]: Starting Load Kernel Module configfs...1588server # [ 6.821556] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1589server # [ 6.825504] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1590builder # [ 6.688237] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1591server # [ 6.830083] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1592builder # [ 6.693368] systemd[1]: Finished Save Transient machine-id to Disk.1593server # [ 6.840976] systemd[1]: Mounting FUSE Control File System...1594server # [ 6.848200] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671595server # [ 6.872076] systemd[1]: Starting Journal Service...1596server # [ 6.877437] systemd[1]: Starting Load Kernel Modules...1597server # [ 6.888976] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1598server # [ 6.895285] systemd[1]: Starting Remount Root and Kernel File Systems...1599server # [ 6.897152] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1600builder # [ 6.760107] systemd[1]: Finished Create System Files and Directories.1601server # [ 6.902142] systemd[1]: Starting Coldplug All udev Devices...1602builder # [ 6.765074] systemd[1]: Starting Rebuild Journal Catalog...1603server # [ 6.908158] systemd[1]: Listening on Journal Log Access Socket.1604builder # [ 6.767807] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1605server # [ 6.909460] systemd[1]: Mounted Huge Pages File System.1606server # [ 6.911946] systemd[1]: Mounted POSIX Message Queue File System.1607server # [ 6.913635] systemd[1]: Mounted Kernel Debug File System.1608server # [ 6.915900] systemd[1]: Mounted Kernel Trace File System.1609server # [ 6.917893] systemd[1]: Mounted FUSE Control File System.1610server # [ 6.921586] systemd[1]: Finished Create List of Static Device Nodes.1611server # [ 6.925848] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1612server # [ 6.952010] systemd[1]: modprobe@configfs.service: Deactivated successfully.1613server # [ 6.953661] systemd[1]: Finished Load Kernel Module configfs.1614server # [ 6.958234] systemd[1]: Mounting Kernel Configuration File System...1615builder # [ 6.844570] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1616server # [ 6.985030] systemd[1]: Mounted Kernel Configuration File System.1617server # [ 7.002854] EXT4-fs (vda): re-mounted 37fd6ef0-b064-474c-83fc-4278552e3934.1618server # [ 7.007487] systemd-journald[281]: Collecting audit messages is enabled.1619server # [ 7.011778] systemd[1]: Finished Remount Root and Kernel File Systems.1620server # [ 7.013277] systemd[1]: Listening on Disk Image Download Service Socket.1621server # [ 7.015853] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1622server # [ 7.022049] systemd[1]: Starting Load/Save OS Random Seed...1623server # [ 7.022886] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1624server # [ 7.027959] loop: module loaded1625builder # [ 6.901965] systemd[1]: Finished Rebuild Journal Catalog.1626builder # [ 6.904868] systemd[1]: Starting Update is Completed...1627server # [ 6.912960] systemd[1]: Queued start job for default target Multi-User System.1628server # [ 7.053770] systemd[1]: Started Journal Service.1629server # [ 6.916360] systemd[1]: systemd-journald.service: Deactivated successfully.1630server # [ 6.923240] systemd-modules-load[282]: Inserted module 'loop'1631server # [ 6.930762] systemd[1]: Starting Flush Journal to Persistent Storage...1632server # [ 6.936962] systemd-modules-load[282]: Inserted module 'tls'1633server # [ 6.954636] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1634server # [ 6.957564] systemd[1]: Starting Create Static Device Nodes in /dev...1635builder # [ 6.958912] systemd[1]: Condition check resulted in Virtio network device being skipped.1636server # [ 7.099645] tun: Universal TUN/TAP device driver, 1.61637builder # [ 6.960170] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1638builder # [ 6.962152] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1639builder # [ 6.966088] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671640builder # [ 6.968212] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1641builder # [ 6.970111] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1642server # [ 6.973132] systemd-modules-load[282]: Inserted module 'tun'1643builder # [ 6.972753] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1644server # [ 6.978677] systemd[1]: Finished Load Kernel Modules.1645builder # [ 6.979312] systemd[1]: Finished Update is Completed.1646server # [ 6.982664] systemd[1]: Starting Firewall...1647server # [ 6.986072] systemd[1]: Starting Apply Kernel Variables...1648server # [ 6.987252] systemd[1]: Finished Load/Save OS Random Seed.1649server # [ 6.989132] systemd[1]: Reached target First Boot Complete.1650server # [ 7.002696] systemd-oomd[283]: No swap; memory pressure usage will be degraded1651server # [ 7.010265] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1652server # [ 7.152354] systemd-journald[281]: Received client request to flush runtime journal.1653server # [ 7.133402] systemd[1]: Finished Create Static Device Nodes in /dev.1654server # [ 7.138914] systemd[1]: Reached target Preparation for Local File Systems.1655server # [ 7.140083] systemd[1]: Starting Rule-based Manager for Device Events and Files...1656server # [ 7.141210] systemd[1]: Finished Apply Kernel Variables.1657server # [ 7.141986] systemd[1]: Finished Flush Journal to Persistent Storage.1658builder # [ 7.189119] systemd[1]: Finished Firewall.1659builder # [ 7.358307] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31660server # [ 7.222885] systemd-udevd[312]: Using default interface naming scheme 'v261'.1661builder # [ 7.379241] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1662builder # [ 7.253888] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1663builder # [ 7.256611] systemd[1]: Finished Create SUID/SGID Wrappers.1664builder # [ 7.400780] ACPI: button: Power Button [PWRF]1665server # [ 7.299817] systemd[1]: Started Rule-based Manager for Device Events and Files.1666builder # [ 7.309439] systemd[1]: Finished register-nix-paths.service.1667builder # [ 7.310320] systemd[1]: Reached target System Initialization.1668builder # [ 7.312147] systemd[1]: Started Discard unused filesystem blocks once a week.1669builder # [ 7.313989] systemd[1]: Started Daily Cleanup of Temporary Directories.1670builder # [ 7.314969] systemd[1]: Reached target Timer Units.1671builder # [ 7.315930] systemd[1]: Listening on D-Bus System Message Bus Socket.1672builder # [ 7.316870] systemd[1]: Starting niks3 auto-upload socket...1673builder # [ 7.326692] systemd[1]: Listening on Nix Daemon Socket.1674builder # [ 7.328290] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1675builder # [ 7.331107] systemd[1]: Listening on niks3 auto-upload socket.1676builder # [ 7.331946] systemd[1]: Reached target Socket Units.1677builder # [ 7.333975] systemd[1]: Starting D-Bus System Message Bus...1678server # [ 7.336517] systemd[1]: Finished Coldplug All udev Devices.1679builder # [ 7.412646] dbus-broker-launch[504]: Looking up NSS user entry for 'systemd-timesync'...1680builder # [ 7.418422] dbus-broker-launch[504]: NSS returned no entry for 'systemd-timesync'1681builder # [ 7.419529] dbus-broker-launch[504]: Invalid user-name in /nix/store/w7zpn81qlzxwipwrzrqxs1rw0fs02pwi-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1682builder # [ 7.410541] mousedev: PS/2 mouse device common for all mice1683builder # [ 7.430326] systemd[1]: Started D-Bus System Message Bus.1684builder # [ 7.431970] systemd[1]: Reached target Basic System.1685builder # [ 7.436135] systemd[1]: Started backdoor.service.1686builder # [ 7.440291] systemd[1]: Starting Import lastlog data into lastlog2 database...1687builder # [ 7.492340] rtc_cmos PNP0B00:00: RTC can wake from S41688builder # [ 7.508862] rtc_cmos PNP0B00:00: registered as rtc01689builder # [ 7.445197] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1690builder # [ 7.450306] systemd[1]: Starting Post-Boot Actions...1691builder # [ 7.508930] rtc_cmos PNP0B00:00: setting system clock to 2026-10-04T19:30:41 UTC (1791142241)1692server # [ 7.464289] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1693builder # [ 7.509007] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1694builder # [ 7.473873] systemd[1]: Started Reset console on configuration changes.1695builder # [ 7.478668] systemd[1]: Starting resolvconf update...1696builder # [ 7.509973] systemd-journald[282]: Time jumped backwards, rotating.1697builder # [ 7.526944] dbus-broker-launch[504]: Ready1698server # [ 7.546966] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1699server # [ 7.559415] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1700builder # [ 7.563895] systemd[1]: Finished Post-Boot Actions.1701builder # [ 7.516519] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41702builder # [ 7.517326] parport_pc 00:02: reported by Plug and Play ACPI1703builder # connecting to host...1704server # [ 7.591933] systemd[1]: Mounting /run/wrappers...1705builder # [ 7.604400] systemd[1]: Started Name Service Cache Daemon (nsncd).1706builder # [ 7.605313] systemd[1]: Reached target Host and Network Name Lookups.1707builder # [ 7.609088] systemd[1]: Reached target User and Group Name Lookups.1708builder # [ 7.611904] systemd[1]: Starting User Login Management...1709builder # [ 7.616585] nsncd[510]: Oct 04 19:30:41.731 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1710builder # [ 7.620819] systemd[1]: Finished Import lastlog data into lastlog2 database.1711builder # [ 7.517412] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1712builder # [ 7.566652] Console: switching to colour dummy device 80x251713builder # [ 7.594304] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1714server # [ 7.628877] systemd[1]: Mounted /run/wrappers.1715server # [ 7.630312] systemd[1]: Reached target Local File Systems.1716server # [ 7.633667] systemd[1]: Listening on Boot Loader Control Service Socket.1717server # [ 7.635790] systemd[1]: Starting register-nix-paths.service...1718server # [ 7.638775] systemd[1]: Starting Create SUID/SGID Wrappers...1719server # [ 7.640561] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1720server # [ 7.643458] systemd[1]: Starting Save Transient machine-id to Disk...1721server # [ 7.646137] systemd[1]: Starting Create System Files and Directories...1722server # [ 7.652311] (udev-worker)[349]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1723server # [ 7.654295] (udev-worker)[349]: Network interface NamePolicy= disabled on kernel command line.1724server # [ 7.669281] (udev-worker)[352]: Network interface NamePolicy= disabled on kernel command line.1725builder # [ 7.818300] [drm] Found bochs VGA, ID 0xb0c5.1726builder # [ 7.818302] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1727builder # [ 7.821816] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1728builder # [ 7.822508] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01729builder # [ 7.827678] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1730builder # [ 7.828453] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1731builder # [ 7.728947] systemd[1]: Stopped target Host and Network Name Lookups.1732builder # [ 7.732728] systemd[1]: Stopping Host and Network Name Lookups...1733builder # [ 7.737444] systemd[1]: Stopped target User and Group Name Lookups.1734builder # [ 7.738293] systemd[1]: Stopping User and Group Name Lookups...1735builder # [ 7.740746] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1736builder # [ 7.741612] systemd[1]: nscd.service: Deactivated successfully.1737builder # [ 7.743490] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1738builder # [ 7.745211] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1739server # [ 7.747886] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1740server # [ 7.751318] systemd[1]: Finished Save Transient machine-id to Disk.1741builder # [ 7.755445] systemd-logind[534]: New seat seat0.1742builder # [ 7.759188] systemd[1]: Started User Login Management.1743builder # [ 7.763194] systemd[1]: Starting linger-users.service...1744builder # [ 7.925368] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61745builder # [ 7.926431] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51746server # [ 7.809452] systemd[1]: Finished Create System Files and Directories.1747builder # [ 7.812551] systemd[1]: Started Name Service Cache Daemon (nsncd).1748builder # [ 7.813625] systemd[1]: Reached target Host and Network Name Lookups.1749server # [ 7.814471] systemd[1]: Starting Rebuild Journal Catalog...1750builder # [ 7.815589] systemd[1]: Reached target User and Group Name Lookups.1751server # [ 7.817662] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1752builder # [ 7.817972] nsncd[579]: Oct 04 19:30:41.947 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1753builder # [ 7.826973] systemd[1]: linger-users.service: Deactivated successfully.1754builder # [ 7.829132] systemd[1]: Finished linger-users.service.1755builder # [ 7.848528] systemd[1]: Finished resolvconf update.1756builder # [ 7.850172] systemd[1]: Reached target Preparation for Network.1757builder # [ 7.854794] systemd[1]: Starting DHCP Client...1758builder # [ 7.858160] systemd[1]: Starting Address configuration of eth1...1759builder # [ 7.862184] systemd[1]: Starting Extra networking commands....1760server # [ 7.895960] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1761builder # [ 7.944203] systemd[1]: Starting Virtual Console Setup...1762server # [ 7.950888] systemd[1]: Finished Rebuild Journal Catalog.1763server # [ 7.954127] systemd[1]: Starting Update is Completed...1764builder # [ 7.975947] network-addresses-eth1-start[610]: adding address 192.168.1.1/24... done1765builder # [ 7.995250] network-addresses-eth1-start[610]: adding address 2001:db8:1::1/64... done1766server # [ 8.017456] systemd[1]: Finished Update is Completed.1767builder # [ 8.100485] Console: switching to colour frame buffer device 160x501768server # [ 8.049576] systemd[1]: Condition check resulted in Virtio network device being skipped.1769server # [ 8.052978] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1770server # [ 8.054463] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1771server # [ 8.056047] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671772server # [ 8.059988] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1773server # [ 8.061532] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1774server # [ 8.063397] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1775builder # [ 8.203265] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1776builder # [ 8.019196] systemd[1]: Finished Address configuration of eth1.1777builder # [ 8.069629] systemd-logind[534]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1778builder # [ 8.073914] systemd-logind[534]: Watching system buttons on /dev/input/event2 (Power Button)1779builder # [ 8.106272] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1780builder # [ 8.108526] systemd[1]: Stopped Virtual Console Setup.1781builder # [ 8.109955] systemd[1]: Starting Virtual Console Setup...1782builder # [ 8.116710] dhcpcd[619]: dhcpcd-10.3.2 starting1783builder # [ 8.124650] systemd[1]: Finished Extra networking commands..1784builder # [ 8.125543] systemd[1]: Reached target Network.1785builder # [ 8.128304] systemd[1]: Starting Permit User Sessions...1786builder # [ 8.136166] dhcpcd[679]: dev: loaded udev1787builder # [ 8.295088] 8021q: 802.1Q VLAN Support v1.81788builder # [ 8.295550] 8021q: adding VLAN 0 to HW filter on device eth11789builder # [ 8.202412] systemd[1]: Finished Permit User Sessions.1790builder # [ 8.208261] systemd[1]: Started Getty on tty1.1791builder # [ 8.208966] systemd[1]: Reached target Login Prompts.1792builder # [ 8.381278] ppdev: user-space parallel port driver1793builder # [ 8.271352] systemd-logind[534]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1794server # [ 8.292147] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1795server # [ 8.294671] systemd[1]: Finished Create SUID/SGID Wrappers.1796builder # [ 8.439819] cfg80211: Loading compiled-in X.509 certificates for regulatory database1797server # [ 8.324943] systemd[1]: Finished Firewall.1798builder # [ 8.468289] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1799builder # [ 8.469435] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1800builder # [ 8.470111] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1801builder # [ 8.472786] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21802builder # [ 8.473676] cfg80211: failed to load regulatory.db1803server # [ 8.474693] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31804server # [ 8.476265] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1805server # [ 8.341631] systemd[1]: Finished register-nix-paths.service.1806server # [ 8.345192] systemd[1]: Reached target System Initialization.1807server # [ 8.346074] systemd[1]: Started Discard unused filesystem blocks once a week.1808server # [ 8.348675] systemd[1]: Started niks3 garbage collection timer.1809server # [ 8.350422] systemd[1]: Started Daily Cleanup of Temporary Directories.1810server # [ 8.353147] systemd[1]: Reached target Timer Units.1811server # [ 8.353888] systemd[1]: Listening on D-Bus System Message Bus Socket.1812server # [ 8.354957] systemd[1]: Starting niks3 server proxy socket...1813server # [ 8.356240] systemd[1]: Listening on niks3 server socket.1814server # [ 8.358379] systemd[1]: Listening on Nix Daemon Socket.1815server # [ 8.359241] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1816builder # [ 8.358948] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1817server # [ 8.360928] systemd[1]: Listening on niks3 server proxy socket.1818server # [ 8.362367] systemd[1]: Reached target Socket Units.1819server # [ 8.503842] mousedev: PS/2 mouse device common for all mice1820server # [ 8.366769] systemd[1]: Starting D-Bus System Message Bus...1821builder # [ 8.507211] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1822builder # [ 8.538380] 8021q: adding VLAN 0 to HW filter on device eth01823builder # [ 8.402279] dhcpcd[679]: eth0: waiting for carrier1824builder # [ 8.403245] dhcpcd[679]: libudev: received NULL device1825builder # [ 8.405844] dhcpcd[679]: libudev: received NULL device1826builder # [ 8.406934] dhcpcd[679]: eth0: carrier acquired1827builder # [ 8.413828] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1828builder # [ 8.417361] systemd[1]: Stopped Virtual Console Setup.1829builder # [ 8.422584] systemd[1]: Starting Virtual Console Setup...1830builder # [ 8.423894] dhcpcd[679]: DUID 00:01:00:01:32:55:65:e2:52:54:00:12:34:561831builder # [ 8.425222] dhcpcd[679]: eth0: IAID 00:12:34:561832server # [ 8.426203] dbus-broker-launch[515]: Looking up NSS user entry for 'systemd-timesync'...1833builder # [ 8.425891] dhcpcd[679]: eth0: adding address fe80::5054:ff:fe12:34561834server # [ 8.429689] dbus-broker-launch[515]: NSS returned no entry for 'systemd-timesync'1835server # [ 8.432129] dbus-broker-launch[515]: Invalid user-name in /nix/store/6nfdvkajr5nq31fv3m4f5wfjpn0yxj8i-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1836builder # [ 8.657165] kvm_amd: TSC scaling supported1837builder # [ 8.657590] kvm_amd: Nested Virtualization enabled1838builder # [ 8.658473] kvm_amd: Nested Paging enabled1839builder # [ 8.659202] kvm_amd: LBR virtualization supported1840builder # [ 8.659634] kvm_amd: Virtual VMLOAD VMSAVE supported1841builder # [ 8.660380] kvm_amd: Virtual GIF supported1842builder # [ 8.660930] kvm_amd: Virtual NMI enabled1843builder # [ 8.686532] EDAC MC: Ver: 3.0.01844server # [ 8.588448] systemd[1]: Started D-Bus System Message Bus.1845server # [ 8.589793] systemd[1]: Reached target Basic System.1846server # [ 8.607268] systemd[1]: Started backdoor.service.1847server # [ 8.615957] systemd[1]: Starting Import lastlog data into lastlog2 database...1848server # [ 8.620664] systemd[1]: Starting Generate test mTLS certs...1849builder # [ 8.620344] systemd-vconsole-setup[701]: Configuration of first virtual console was skipped, ignoring remaining ones.1850builder # [ 8.623357] systemd[1]: Finished Virtual Console Setup.1851server # [ 8.628532] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1852server # [ 8.635317] systemd[1]: Starting Post-Boot Actions...1853server # [ 8.641762] nsncd[520]: Oct 04 19:30:42.676 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1854server # connecting to host...1855server # [ 8.650394] systemd[1]: Started Reset console on configuration changes.1856server # [ 8.653212] systemd[1]: Starting resolvconf update...1857server # [ 8.658238] dbus-broker-launch[515]: Ready1858server # [ 8.658906] systemd[1]: Finished Post-Boot Actions.1859server # [ 8.661651] systemd[1]: Started Name Service Cache Daemon (nsncd).1860server # [ 8.663819] systemd[1]: Reached target Host and Network Name Lookups.1861server # [ 8.665277] systemd[1]: Reached target User and Group Name Lookups.1862server # [ 8.666123] systemd[1]: Starting User Login Management...1863server # [ 8.683544] systemd[1]: Finished Import lastlog data into lastlog2 database.1864server: Guest shell says: b'Spawning backdoor root shell...\n'1865server: connected to guest root shell1866server # [ 8.699658] niks3-test-certs-start[531]: -----1867server: (connecting took 9.45 seconds)1868server # [ 8.521868] ACPI: button: Power Button [PWRF]1869server: (finished: waiting for the VM to finish booting, in 9.45 seconds)1870server # [ 8.584722] rtc_cmos PNP0B00:00: RTC can wake from S41871server # [ 8.596405] rtc_cmos PNP0B00:00: registered as rtc01872server # [ 8.596473] rtc_cmos PNP0B00:00: setting system clock to 2026-10-04T19:30:42 UTC (1791142242)1873server # [ 8.731709] niks3-test-certs-start[560]: -----1874server # [ 8.596549] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1875server # [ 8.597112] systemd-journald[281]: Time jumped backwards, rotating.1876server # [ 8.663638] parport_pc 00:02: reported by Plug and Play ACPI1877server # [ 8.821420] niks3-test-certs-start[575]: Certificate request self-signature ok1878server # [ 8.822475] niks3-test-certs-start[575]: subject=CN=server1879builder # [ 8.827280] dhcpcd[679]: eth0: soliciting a DHCP lease1880server # [ 8.763144] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41881server # [ 8.778787] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1882builder # [ 8.984966] NET: Registered PF_PACKET protocol family1883builder # [ 8.851957] dhcpcd[679]: eth0: offered 10.0.2.15 from 10.0.2.21884builder # [ 8.854240] dhcpcd[679]: eth0: probing address 10.0.2.15/241885server # [ 8.827257] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1886server # [ 8.840251] Console: switching to colour dummy device 80x251887server # [ 9.001450] [drm] Found bochs VGA, ID 0xb0c5.1888server # [ 9.001453] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1889server # [ 8.848756] niks3-test-certs-start[595]: -----1890server # [ 8.866444] systemd-logind[545]: New seat seat0.1891server # [ 8.875746] systemd[1]: Started User Login Management.1892server # [ 8.878321] systemd[1]: Stopped target Host and Network Name Lookups.1893server # [ 8.879459] systemd[1]: Stopping Host and Network Name Lookups...1894server # [ 8.881123] systemd[1]: Stopped target User and Group Name Lookups.1895server # [ 8.886245] systemd[1]: Stopping User and Group Name Lookups...1896server # [ 8.889557] systemd[1]: Starting linger-users.service...1897server # [ 8.890383] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1898server # [ 8.893871] systemd[1]: nscd.service: Deactivated successfully.1899server # [ 8.897864] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1900server # [ 8.903806] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1901server # [ 9.094006] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1902server # [ 9.094689] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01903server # [ 8.966669] niks3-test-certs-start[596]: Certificate request self-signature ok1904server # [ 8.967668] niks3-test-certs-start[596]: subject=CN=niks3 test client1905server # [ 9.117844] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1906server # [ 9.118515] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1907server # [ 8.984221] systemd[1]: linger-users.service: Deactivated successfully.1908server # [ 8.985754] systemd[1]: Finished linger-users.service.1909server # [ 8.999072] systemd[1]: Started Name Service Cache Daemon (nsncd).1910server # [ 8.999932] systemd[1]: Reached target Host and Network Name Lookups.1911server # [ 9.001531] systemd[1]: Reached target User and Group Name Lookups.1912server # [ 9.003861] nsncd[601]: Oct 04 19:30:43.044 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1913server # [ 9.011413] systemd[1]: Finished Generate test mTLS certs.1914server # [ 9.026949] systemd[1]: Finished resolvconf update.1915server # [ 9.029234] systemd[1]: Reached target Preparation for Network.1916server # [ 9.034110] systemd[1]: Starting DHCP Client...1917server # [ 9.037375] systemd[1]: Starting Address configuration of eth1...1918server # [ 9.040184] systemd[1]: Starting Extra networking commands....1919server # [ 9.141794] network-addresses-eth1-start[638]: adding address 192.168.1.2/24... done1920server # [ 9.156430] network-addresses-eth1-start[638]: adding address 2001:db8:1::2/64... done1921server # [ 9.179668] systemd[1]: Finished Address configuration of eth1.1922server # [ 9.253232] systemd[1]: Starting Virtual Console Setup...1923server # [ 9.265520] systemd[1]: Finished Extra networking commands..1924server # [ 9.267152] systemd[1]: Reached target Network.1925server # [ 9.270598] systemd[1]: Started Mock OIDC server for testing.1926server # [ 9.276724] systemd[1]: Starting Nginx Web Server...1927server # [ 9.280891] systemd[1]: Starting PostgreSQL Server...1928server # [ 9.281845] dhcpcd[648]: dhcpcd-10.3.2 starting1929server # [ 9.292503] systemd[1]: Started RustFS S3-compatible object storage.1930server # [ 9.293372] systemd[1]: Starting Setup RustFS bucket...1931server # [ 9.301584] systemd[1]: Starting Permit User Sessions...1932server # [ 9.318519] dhcpcd[709]: dev: loaded udev1933server # [ 9.234888] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61934server # [ 9.235127] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51935server # [ 9.455293] Console: switching to colour frame buffer device 160x501936server # [ 9.476033] 8021q: 802.1Q VLAN Support v1.81937server # [ 9.476047] 8021q: adding VLAN 0 to HW filter on device eth11938server # [ 9.611392] cfg80211: Loading compiled-in X.509 certificates for regulatory database1939server # [ 9.666433] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1940server # [ 9.666548] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1941server # [ 9.667424] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21942server # [ 9.667431] cfg80211: failed to load regulatory.db1943server # [ 9.840526] 8021q: adding VLAN 0 to HW filter on device eth01944server # [ 9.938577] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1945server # [ 9.460149] systemd[1]: Finished Permit User Sessions.1946server # [ 9.804399] dhcpcd[709]: eth0: waiting for carrier1947server # [ 9.805108] systemd-logind[545]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1948server # [ 9.806405] dhcpcd[709]: eth0: carrier acquired1949server # [ 9.810923] systemd-logind[545]: Watching system buttons on /dev/input/event2 (Power Button)1950server # [ 9.812207] dhcpcd[709]: DUID 00:01:00:01:32:55:65:e3:52:54:00:12:34:561951server # [ 9.814536] systemd[1]: Started Getty on tty1.1952server # [ 9.818471] dhcpcd[709]: eth0: IAID 00:12:34:561953server # [ 9.819965] systemd[1]: Reached target Login Prompts.1954server # [ 9.822139] dhcpcd[709]: eth0: adding address fe80::5054:ff:fe12:34561955server # [ 9.833799] nginx-pre-start[737]: nginx: the configuration file /nix/store/zrm3va7lbl0i91k5xpvb2sg2jw58nznn-nginx.conf syntax is ok1956server # [ 9.835745] nginx-pre-start[737]: nginx: configuration file /nix/store/zrm3va7lbl0i91k5xpvb2sg2jw58nznn-nginx.conf test is successful1957server # [ 9.838965] mock-oidc-server[704]: Mock OIDC Server running1958server # [ 9.842591] mock-oidc-server[704]: OIDC Address: 127.0.0.1:80801959server # [ 9.847315] mock-oidc-server[704]: Issue Address: 127.0.0.1:80811960server # [ 9.848135] mock-oidc-server[704]: Issuer: http://127.0.0.1:8080/oidc1961server # [ 9.848948] mock-oidc-server[704]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1962server # [ 9.851058] mock-oidc-server[704]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1963server # [ 9.853781] mock-oidc-server[704]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1964server # [ 9.857659] systemd[1]: Started Nginx Web Server.1965server # [ 9.858405] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1966server # [ 9.862931] systemd[1]: Stopped Virtual Console Setup.1967server # [ 9.864743] systemd[1]: Starting Virtual Console Setup...1968server # [ 10.007386] ppdev: user-space parallel port driver1969server # [ 9.916242] postgresql-pre-start[743]: The files belonging to this database system will be owned by user "postgres".1970server # [ 9.920046] postgresql-pre-start[743]: This user must also own the server process.1971server # [ 9.926088] postgresql-pre-start[743]: The database cluster will be initialized with locale "en_US.UTF-8".1972server # [ 9.927323] postgresql-pre-start[743]: The default database encoding has accordingly been set to "UTF8".1973server # [ 9.928446] postgresql-pre-start[743]: The default text search configuration will be set to "english".1974server # [ 9.929530] postgresql-pre-start[743]: Data page checksums are enabled.1975server # [ 9.930387] postgresql-pre-start[743]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1976server # [ 9.931609] postgresql-pre-start[743]: creating subdirectories ... ok1977server # [ 9.932396] postgresql-pre-start[743]: selecting dynamic shared memory implementation ... posix1978server # [ 9.946579] systemd-logind[545]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1979server # [ 10.109364] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1980server # [ 10.275330] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1981server # [ 10.142135] postgresql-pre-start[743]: selecting default "max_connections" ... 1001982server # [ 10.301874] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1983server # [ 10.187253] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1984server # [ 10.188638] systemd[1]: Stopped Virtual Console Setup.1985server # [ 10.193113] systemd[1]: Starting Virtual Console Setup...1986server # [ 10.302353] postgresql-pre-start[743]: selecting default "shared_buffers" ... 128MB1987server # [ 10.509025] kvm_amd: TSC scaling supported1988server # [ 10.509484] kvm_amd: Nested Virtualization enabled1989server # [ 10.510383] kvm_amd: Nested Paging enabled1990server # [ 10.511107] kvm_amd: LBR virtualization supported1991server # [ 10.511585] kvm_amd: Virtual VMLOAD VMSAVE supported1992server # [ 10.512425] kvm_amd: Virtual GIF supported1993server # [ 10.513167] kvm_amd: Virtual NMI enabled1994server # [ 10.543490] EDAC MC: Ver: 3.0.01995server # [ 10.421938] dhcpcd[709]: eth0: soliciting a DHCP lease1996server # [ 10.578201] NET: Registered PF_PACKET protocol family1997server # [ 10.443524] dhcpcd[709]: eth0: offered 10.0.2.15 from 10.0.2.21998server # [ 10.446444] dhcpcd[709]: eth0: probing address 10.0.2.15/241999server # [ 10.536340] systemd-vconsole-setup[779]: Configuration of first virtual console was skipped, ignoring remaining ones.2000server # [ 10.542125] systemd[1]: Finished Virtual Console Setup.2001builder # [ 10.734890] dhcpcd[679]: eth0: soliciting an IPv6 router2002builder # [ 10.736975] dhcpcd[679]: eth0: Router Advertisement from fe80::22003builder # [ 10.739493] dhcpcd[679]: eth0: adding address fec0::5054:ff:fe12:3456/642004builder # [ 10.741692] dhcpcd[679]: eth0: adding route to fec0::/642005builder # [ 10.743536] dhcpcd[679]: eth0: adding default route via fe80::22006server # [ 11.223714] postgresql-pre-start[743]: selecting default time zone ... UTC2007server # [ 11.226584] postgresql-pre-start[743]: creating configuration files ... ok2008server # [ 11.410753] postgresql-pre-start[743]: running bootstrap script ... ok2009server # [ 11.795553] postgresql-pre-start[743]: performing post-bootstrap initialization ... ok2010server # [ 11.943153] postgresql-pre-start[743]: syncing data to disk ... ok2011server # [ 11.944255] postgresql-pre-start[743]: initdb: warning: enabling "trust" authentication for local connections2012server # [ 11.946260] postgresql-pre-start[743]: 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.2013server # [ 11.948179] postgresql-pre-start[743]: Success. You can now start the database server using:2014server # [ 11.949205] postgresql-pre-start[743]: pg_ctl -D /var/lib/postgresql/18 -l logfile start2015server # [ 12.025195] postgres[808]: [808] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit2016server # [ 12.027612] postgres[808]: [808] LOG: listening on IPv6 address "::1", port 54322017server # [ 12.028678] postgres[808]: [808] LOG: listening on IPv4 address "127.0.0.1", port 54322018server # [ 12.031319] postgres[808]: [808] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2019server # [ 12.043254] postgres[817]: [817] LOG: database system was shut down at 2026-10-04 19:30:45 GMT2020server # [ 12.049123] postgres[808]: [808] LOG: database system is ready to accept connections2021server # [ 12.054060] systemd[1]: Started PostgreSQL Server.2022server # [ 12.058141] systemd[1]: Starting PostgreSQL Setup Scripts...2023server: (finished: waiting for unit postgresql.service, in 12.93 seconds)2024server: waiting for unit rustfs.service2025server # [ 12.223714] postgresql-setup-start[828]: CREATE DATABASE2026server: (finished: waiting for unit rustfs.service, in 0.05 seconds)2027server: waiting for unit rustfs-setup.service2028server # [ 12.265707] dhcpcd[709]: eth0: soliciting an IPv6 router2029server # [ 12.267225] dhcpcd[709]: eth0: Router Advertisement from fe80::22030server # [ 12.268110] dhcpcd[709]: eth0: adding address fec0::5054:ff:fe12:3456/642031server # [ 12.268982] dhcpcd[709]: eth0: adding route to fec0::/642032server # [ 12.269965] dhcpcd[709]: eth0: adding default route via fe80::22033server # [ 12.277232] postgresql-setup-start[845]: CREATE ROLE2034server # [ 12.289321] postgresql-setup-start[847]: ALTER DATABASE2035server # [ 12.294209] systemd[1]: Finished PostgreSQL Setup Scripts.2036server # [ 12.295606] systemd[1]: Reached target PostgreSQL.2037builder # [ 14.347245] dhcpcd[679]: eth0: leased 10.0.2.15 for 86400 seconds2038builder # [ 14.351080] dhcpcd[679]: eth0: adding route to 10.0.2.0/242039builder # [ 14.353262] dhcpcd[679]: eth0: adding default route via 10.0.2.22040builder # [ 14.440853] systemd[1]: Started DHCP Client.2041builder # [ 14.442720] systemd[1]: Reached target Multi-User System.2042builder # [ 14.443751] systemd[1]: Startup finished in 872ms (kernel) + 3.649s (initrd) + 9.920s (userspace) = 14.442s.2043server # [ 15.116397] dhcpcd[709]: eth0: leased 10.0.2.15 for 86400 seconds2044server # [ 15.119686] dhcpcd[709]: eth0: adding route to 10.0.2.0/242045server # [ 15.121981] dhcpcd[709]: eth0: adding default route via 10.0.2.22046server # [ 15.205332] systemd[1]: Started DHCP Client.2047server # [ 30.659378] rustfs-setup-start[983]: mb s3://niks3-test2048server # [ 30.670138] systemd[1]: Finished Setup RustFS bucket.2049server # [ 30.680184] systemd[1]: Starting niks3 server...2050server # [ 30.861169] niks3-server[992]: 2026/10/04 19:31:04 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc2051server # [ 30.867293] niks3-server[992]: 2026/10/04 19:31:04 INFO OIDC authentication enabled config=/nix/store/sa3h30ggc0ih3s7pyx6hv16myf7hx0lj-niks3-oidc.json2052server # [ 30.870427] niks3-server[992]: 2026/10/04 19:31:04 INFO Loaded signing key name=niks3-test-1 path=/nix/store/bkg7syhrbck6xib4g0kaji8jiwjnl8sy-niks3-signing-key2053server # [ 30.898188] niks3-server[992]: 2026/10/04 19:31:04 INFO Using socket-activated listener address=0.0.0.0:57512054server # [ 30.899392] niks3-server[992]: 2026/10/04 19:31:04 INFO Using socket-activated listener address=/run/niks3/proxy.sock2055server # [ 30.901629] systemd[1]: Started niks3 server.2056server # [ 30.902263] niks3-server[992]: 2026/10/04 19:31:04 INFO systemd watchdog enabled interval=15s2057server # [ 30.905104] systemd[1]: Reached target Multi-User System.2058server # [ 30.905917] niks3-server[992]: 2026/10/04 19:31:04 INFO Starting HTTP server address=0.0.0.0:57512059server # [ 30.907685] niks3-server[992]: 2026/10/04 19:31:04 INFO Starting HTTP server address=/run/niks3/proxy.sock2060server # [ 30.909100] systemd[1]: Startup finished in 857ms (kernel) + 4.572s (initrd) + 25.474s (userspace) = 30.904s.2061server: (finished: waiting for unit rustfs-setup.service, in 18.83 seconds)2062server: waiting for unit mock-oidc.service2063server: (finished: waiting for unit mock-oidc.service, in 0.02 seconds)2064server: waiting for unit niks3.service2065server: (finished: waiting for unit niks3.service, in 0.02 seconds)2066server: waiting for TCP port 5751 on localhost2067server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!2068server: (finished: waiting for TCP port 5751 on localhost, in 0.03 seconds)2069server: waiting for TCP port 8080 on localhost2070server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!2071server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds)2072server: waiting for TCP port 9000 on localhost2073server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!2074server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)2075server: must succeed: mkdir -p /tmp/test-config2076server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds)2077server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token2078server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)2079server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32080server # [ 31.324080] systemd[1]: Created slice Slice /system/nix-daemon.2081server # [ 31.326385] systemd[1]: Started Nix Daemon instance (PID 1043/UID 0).2082server # [ 31.371425] nix-daemon[1045]: remote pid 1043 is unknown user (trusted)2083server # [ 31.387539] systemd[1]: nix-daemon@0-1-1043_1044-0.service: Deactivated successfully.2084server # [ 31.393702] niks3-server[992]: 2026/10/04 19:31:05 INFO Received push request method=POST path=/api/pushes2085server # time=2026-10-04T19:31:05.460Z level=INFO msg="Uploading 5 paths to server (0 already cached)"2086server # time=2026-10-04T19:31:05.461Z level=INFO msg="Uploading xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 (273.1KB)"2087server # time=2026-10-04T19:31:05.463Z level=INFO msg="Uploading i3jw341xs88r6wxf1j22rjx1mmd8cfjw-libidn2-2.3.8 (359.5KB)"2088server # time=2026-10-04T19:31:05.465Z level=INFO msg="Uploading lm3pknxi0ipypy3lxh1wmm8wvvavdwrn-glibc-2.42-84 (33.4MB)"2089server # time=2026-10-04T19:31:05.467Z level=INFO msg="Uploading bv3lx708clpz3m6zbd5742np4dlry0yj-libunistring-1.4.2 (2.0MB)"2090server # time=2026-10-04T19:31:05.469Z level=INFO msg="Uploading m07syxhld8hpprrdmzq565ziia6vlw9l-xgcc-15.3.0-libgcc (193.0KB)"2091server # [ 31.577354] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.ls2092server # [ 31.619760] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=nar/1ixxaz0n8nfxd2w29qq8frn6pjvdnjj3pl139gbi7jksrj9jd7jg.nar.zst2093server # [ 31.631517] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.ls2094server # [ 31.661529] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=bv3lx708clpz3m6zbd5742np4dlry0yj.ls2095server # [ 31.677887] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=nar/1bvhsl1iqnbbgmxgp2hi0j8nj5ip5n6h6m2awanbnhwrl8fazjvh.nar.zst2096server # [ 31.704775] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=nar/1gz22gfl2lcahqzma75rvxgmk9zcfrvarzywlarwy4hkf1k4iz44.nar.zst2097server # [ 31.708932] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.ls2098server # [ 31.726888] niks3-server[992]: 2026/10/04 19:31:05 INFO Registered completed upload object_key=nar/0g2qsgc7dv9zl3i5nc523gicpy0xk2z4r1x4msr0inmsqjpm1ym9.nar.zst2099server # [ 32.364333] niks3-server[992]: 2026/10/04 19:31:06 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.ls2100server # [ 32.396337] niks3-server[992]: 2026/10/04 19:31:06 INFO Received complete multipart upload request method=POST path=/api/multipart/complete2101server # [ 32.414138] niks3-server[992]: 2026/10/04 19:31:06 INFO Completed multipart upload object_key=nar/06kkplxk31y11zksgcrdjv0hf6ymlmxvlc0i2h08g3zc22is465s.nar.zst upload_id=NTY2NTBkMTQtMWE4Ni00YjcwLWJiMTQtYTg5NDhkNjllMWEyLjMxY2MxMTI1LWNlZDQtNGZjYS1iMmQyLWJiNjFjYjg4ODJjN3gxNzkxMTQyMjY1NDQ4Njk5NjIy parts=12102server # [ 32.417538] niks3-server[992]: 2026/10/04 19:31:06 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign2103server # [ 32.421505] niks3-server[992]: 2026/10/04 19:31:06 INFO Signed narinfos id=1 count=52104server # time=2026-10-04T19:31:06.465Z level=INFO msg="Uploading 5 narinfos"2105server # [ 32.432069] niks3-server[992]: 2026/10/04 19:31:06 INFO Registered completed upload object_key=bv3lx708clpz3m6zbd5742np4dlry0yj.narinfo2106server # [ 32.448083] niks3-server[992]: 2026/10/04 19:31:06 INFO Received complete push request method=POST path=/api/pushes/1/complete2107server # [ 32.455270] niks3-server[992]: 2026/10/04 19:31:06 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.narinfo2108server # [ 32.466102] niks3-server[992]: 2026/10/04 19:31:06 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.narinfo2109server # time=2026-10-04T19:31:06.517Z level=INFO msg="Upload complete. (1.194s)"2110server # [ 32.479237] niks3-server[992]: 2026/10/04 19:31:06 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo2111server # [ 32.482256] niks3-server[992]: 2026/10/04 19:31:06 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.narinfo2112server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 1.30 seconds)2113server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token2114server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)2115server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32116server # [ 32.548165] niks3-server[992]: 2026/10/04 19:31:06 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]2117server # [ 32.583206] systemd[1]: Started Nix Daemon instance (PID 1073/UID 0).2118server # [ 32.622618] nix-daemon[1075]: remote pid 1073 is unknown user (trusted)2119server # [ 32.633387] systemd[1]: nix-daemon@1-2-1073_1074-0.service: Deactivated successfully.2120server # [ 32.636691] niks3-server[992]: 2026/10/04 19:31:06 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]2121server # time=2026-10-04T19:31:06.683Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2122server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.14 seconds)2123server: waiting for unit nginx.service2124server: (finished: waiting for unit nginx.service, in 0.02 seconds)2125server: waiting for TCP port 443 on localhost2126server # Connection to localhost (::1) 443 port [tcp/https] succeeded!2127server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)2128server: must succeed: /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32129server # time=2026-10-04T19:31:06.773Z 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.pem2130server # time=2026-10-04T19:31:06.789Z level=INFO msg="All 1 paths already cached"2131server: (finished: must succeed: /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.06 seconds)2132server: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures2133server: (finished: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures, in 0.03 seconds)2134server: must fail: /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32135server # time=2026-10-04T19:31:06.830Z 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)"2136server: (finished: must fail: /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.01 seconds)2137server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32138server # time=2026-10-04T19:31:06.877Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem2139server # time=2026-10-04T19:31:06.886Z level=INFO msg="All 1 paths already cached"2140server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.05 seconds)2141server: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client'2142server # -----2143server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl req -newkey ec -pkeyopt ec_paramgen_curve:prime256v1 -nodes -keyout other.key -out other.csr -subj '/CN=other client', in 0.02 seconds)2144server: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem2145server # Certificate request self-signature ok2146server # subject=CN=other client2147server: (finished: must succeed: cd /etc/niks3-test-certs && /nix/store/44gxi8li8y8wrxrb93sya9hljkpigsqw-openssl-3.6.4-bin/bin/openssl x509 -req -in other.csr -CA ca.pem -CAkey ca.key -CAcreateserial -days 1 -out other.pem, in 0.03 seconds)2148server: must fail: /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32149server # time=2026-10-04T19:31:06.977Z 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.pem2150server # [ 32.941394] niks3-server[992]: 2026/10/04 19:31:06 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2151server # [ 32.975271] systemd[1]: Started Nix Daemon instance (PID 1139/UID 0).2152server # [ 33.015291] nix-daemon[1141]: remote pid 1139 is unknown user (trusted)2153server # [ 33.026264] systemd[1]: nix-daemon@2-3-1139_1140-0.service: Deactivated successfully.2154server # [ 33.032366] niks3-server[992]: 2026/10/04 19:31:07 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2155server # time=2026-10-04T19:31:07.077Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2156server: (finished: must fail: /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.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/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.15 seconds)2157server: must succeed: mkdir -p /tmp/test-store2158server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.01 seconds)2159server: must succeed: 2160 export AWS_ACCESS_KEY_ID=rustfsadmin2161export AWS_SECRET_ACCESS_KEY=rustfsadmin2162 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.321632164server: (finished: must succeed: 2165 export AWS_ACCESS_KEY_ID=rustfsadmin2166export AWS_SECRET_ACCESS_KEY=rustfsadmin2167 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32168, in 0.52 seconds)2169server: must succeed: 2170cat > /tmp/test-drv.nix << 'EOF'2171derivation {2172 name = "test-build-log";2173 system = builtins.currentSystem;2174 builder = "/bin/sh";2175 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2176}2177EOF21782179server: (finished: must succeed: 2180cat > /tmp/test-drv.nix << 'EOF'2181derivation {2182 name = "test-build-log";2183 system = builtins.currentSystem;2184 builder = "/bin/sh";2185 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2186}2187EOF2188, in 0.01 seconds)2189server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix2190server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2191server # [ 33.639806] systemd[1]: Started Nix Daemon instance (PID 1183/UID 0).2192server # [ 33.681643] nix-daemon[1187]: remote pid 1183 is unknown user (trusted)2193server # this derivation will be built:2194server # /nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2195server # building '/nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv'...2196server # test-build-log> test build log output2197server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.19 seconds)2198server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2199server # [ 33.787059] systemd[1]: nix-daemon@3-4-1183_1184-0.service: Deactivated successfully.2200server # [ 33.864979] systemd[1]: Started Nix Daemon instance (PID 1214/UID 0).2201server # [ 33.905222] nix-daemon[1216]: remote pid 1214 is unknown user (trusted)2202server # [ 33.915651] systemd[1]: nix-daemon@4-5-1214_1215-0.service: Deactivated successfully.2203server # [ 33.920511] niks3-server[992]: 2026/10/04 19:31:07 INFO Received push request method=POST path=/api/pushes2204server # time=2026-10-04T19:31:07.970Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2205server # time=2026-10-04T19:31:07.971Z level=INFO msg="Uploading dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log (128B)"2206server # [ 33.954793] niks3-server[992]: 2026/10/04 19:31:07 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign2207server # [ 33.959091] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=log/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2208server # [ 33.961300] niks3-server[992]: 2026/10/04 19:31:08 INFO Signed narinfos id=2 count=12209server # time=2026-10-04T19:31:08.006Z level=INFO msg="Uploading 1 narinfos"2210server # [ 33.966373] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst2211server # [ 33.969185] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.ls2212server # [ 33.974534] niks3-server[992]: 2026/10/04 19:31:08 INFO Received complete push request method=POST path=/api/pushes/2/complete2213server # [ 33.978837] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2214server # time=2026-10-04T19:31:08.024Z level=INFO msg="Upload complete. (151ms)"2215server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log, in 0.21 seconds)2216server: must succeed: 2217 export AWS_ACCESS_KEY_ID=rustfsadmin2218export AWS_SECRET_ACCESS_KEY=rustfsadmin2219 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log22202221server: (finished: must succeed: 2222 export AWS_ACCESS_KEY_ID=rustfsadmin2223export AWS_SECRET_ACCESS_KEY=rustfsadmin2224 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2225, in 0.11 seconds)2226subtest: push --stdin streams paths and reports each one2227server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'2228server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2229server # [ 34.145252] systemd[1]: Started Nix Daemon instance (PID 1232/UID 0).2230server # [ 34.184704] nix-daemon[1236]: remote pid 1232 is unknown user (trusted)2231server # this derivation will be built:2232server # /nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2233server # building '/nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv'...2234server: (finished: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }', in 0.17 seconds)2235server: must succeed: printf '%s\n\n%s\n' /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --stdin2236server # [ 34.278921] systemd[1]: nix-daemon@5-6-1232_1233-0.service: Deactivated successfully.2237server # [ 34.379069] systemd[1]: Started Nix Daemon instance (PID 1265/UID 0).2238server # [ 34.418686] nix-daemon[1267]: remote pid 1265 is unknown user (trusted)2239server # [ 34.430127] systemd[1]: nix-daemon@6-7-1265_1266-0.service: Deactivated successfully.2240server # [ 34.432935] niks3-server[992]: 2026/10/04 19:31:08 INFO Received push request method=POST path=/api/pushes2241server # time=2026-10-04T19:31:08.497Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2242server # time=2026-10-04T19:31:08.498Z level=INFO msg="Uploading 3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test (120B)"2243server # [ 34.475580] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.ls2244server # [ 34.479606] niks3-server[992]: 2026/10/04 19:31:08 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign2245server # [ 34.483181] niks3-server[992]: 2026/10/04 19:31:08 INFO Signed narinfos id=3 count=12246server # time=2026-10-04T19:31:08.527Z level=INFO msg="Uploading 1 narinfos"2247server # [ 34.488684] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=log/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2248server # [ 34.495606] niks3-server[992]: 2026/10/04 19:31:08 INFO Received complete push request method=POST path=/api/pushes/3/complete2249server # [ 34.497375] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst2250server # time=2026-10-04T19:31:08.543Z level=INFO msg="Upload complete. (177ms)"2251server # [ 34.502714] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.narinfo2252server: (finished: must succeed: printf '%s\n\n%s\n' /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log | NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --stdin, in 0.24 seconds)2253server: must succeed: 2254 export AWS_ACCESS_KEY_ID=rustfsadmin2255export AWS_SECRET_ACCESS_KEY=rustfsadmin2256 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2257 2258server: (finished: must succeed: 2259 export AWS_ACCESS_KEY_ID=rustfsadmin2260export AWS_SECRET_ACCESS_KEY=rustfsadmin2261 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2262 , in 0.15 seconds)2263(finished: subtest: push --stdin streams paths and reports each one, in 0.56 seconds)2264server: must succeed: readlink /etc/niks3-test/symlink-wrapper2265server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.01 seconds)2266server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2267server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds)2268server: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2269server: (finished: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds)2270server: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2271server: (finished: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds)2272server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2273server # [ 34.789661] systemd[1]: Started Nix Daemon instance (PID 1312/UID 0).2274server # [ 34.829596] nix-daemon[1314]: remote pid 1312 is unknown user (trusted)2275server # [ 34.840524] systemd[1]: nix-daemon@7-8-1312_1313-0.service: Deactivated successfully.2276server # [ 34.843908] niks3-server[992]: 2026/10/04 19:31:08 INFO Received push request method=POST path=/api/pushes2277server # time=2026-10-04T19:31:08.894Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2278server # time=2026-10-04T19:31:08.895Z level=INFO msg="Uploading x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package (536B)"2279server # time=2026-10-04T19:31:08.897Z level=INFO msg="Uploading 1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper (192B)"2280server # [ 34.883412] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.ls2281server # [ 34.886831] niks3-server[992]: 2026/10/04 19:31:08 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign2282server # [ 34.890057] niks3-server[992]: 2026/10/04 19:31:08 INFO Signed narinfos id=4 count=22283server # time=2026-10-04T19:31:08.935Z level=INFO msg="Uploading 2 narinfos"2284server # [ 34.893771] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2285server # [ 34.897747] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=nar/0v342j6sk8pcizzisl5bwzw5xdrdjdmynpgxd3f6827yp5bzbrl7.nar.zst2286server # [ 34.900326] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.ls2287server # [ 34.908578] niks3-server[992]: 2026/10/04 19:31:08 INFO Received complete push request method=POST path=/api/pushes/4/complete2288server # [ 34.914549] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.narinfo2289server # [ 34.918343] niks3-server[992]: 2026/10/04 19:31:08 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.narinfo2290server # time=2026-10-04T19:31:08.964Z level=INFO msg="Upload complete. (170ms)"2291server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.22 seconds)2292server: must succeed: 2293 export AWS_ACCESS_KEY_ID=rustfsadmin2294export AWS_SECRET_ACCESS_KEY=rustfsadmin2295 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper22962297server: (finished: must succeed: 2298 export AWS_ACCESS_KEY_ID=rustfsadmin2299export AWS_SECRET_ACCESS_KEY=rustfsadmin2300 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2301, in 0.14 seconds)2302server: must succeed: 2303cat > /tmp/oidc-test.nix << 'EOF'2304derivation {2305 name = "oidc-test";2306 system = builtins.currentSystem;2307 builder = "/bin/sh";2308 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2309}2310EOF23112312server: (finished: must succeed: 2313cat > /tmp/oidc-test.nix << 'EOF'2314derivation {2315 name = "oidc-test";2316 system = builtins.currentSystem;2317 builder = "/bin/sh";2318 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2319}2320EOF2321, in 0.01 seconds)2322server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2323server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2324server # [ 35.155122] systemd[1]: Started Nix Daemon instance (PID 1341/UID 0).2325server # [ 35.200335] nix-daemon[1345]: remote pid 1341 is unknown user (trusted)2326server # this derivation will be built:2327server # /nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2328server # building '/nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv'...2329server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.20 seconds)2330server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg'2331server # [ 35.293764] systemd[1]: nix-daemon@8-9-1341_1342-0.service: Deactivated successfully.2332server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg', in 0.03 seconds)2333server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkRLYTA3UThZbzJjSzdZMmZJM2dBYzRjY256eWE0cDVVTHdvS3VWV2d0V0UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTExNDU4NjksImlhdCI6MTc5MTE0MjI2OSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.iNTCQyJZYSO_UXYr_6B0YocgRWhC_wm9lOtJittK5T4styrYsGqG-T1OQ67mWDdaLtggWsN3tdtWkyTd8gCng3qB7uz8cWHSqjLqvsUgkuFWQjROF4zw17W-_W38UTJIX1ifs9zXiKI4Np-tNALwa8eeP1GGgh0nAQ4qMdE0KRggKoIaSksjCeXLL7_4jvTWA8exbX0jC75jh9dUtUCiQYvlj4gJ5_nPfT7yz9uwJs09j-b6w0QIMb2rdCkpV49UagkMlo-JKw60kSeDWHjqgJHVZ523DiAhiqS1XI_dn7dgHstambOE0o9u2K42tbYq5VG-8nUy8jriGIpMl8wdgQ' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2334server # time=2026-10-04T19:31:09.371Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2335server # [ 35.400962] systemd[1]: Started Nix Daemon instance (PID 1376/UID 0).2336server # [ 35.440526] nix-daemon[1378]: remote pid 1376 is unknown user (trusted)2337server # [ 35.451280] systemd[1]: nix-daemon@9-10-1376_1377-0.service: Deactivated successfully.2338server # [ 35.455476] niks3-server[992]: 2026/10/04 19:31:09 INFO Received push request method=POST path=/api/pushes2339server # time=2026-10-04T19:31:09.504Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2340server # time=2026-10-04T19:31:09.505Z level=INFO msg="Uploading hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test (136B)"2341server # [ 35.482845] niks3-server[992]: 2026/10/04 19:31:09 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2342server # [ 35.486155] niks3-server[992]: 2026/10/04 19:31:09 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign2343server # [ 35.488590] niks3-server[992]: 2026/10/04 19:31:09 INFO Signed narinfos id=5 count=12344server # time=2026-10-04T19:31:09.533Z level=INFO msg="Uploading 1 narinfos"2345server # [ 35.497627] niks3-server[992]: 2026/10/04 19:31:09 INFO Received complete push request method=POST path=/api/pushes/5/complete2346server # [ 35.501635] niks3-server[992]: 2026/10/04 19:31:09 INFO Registered completed upload object_key=log/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2347server # time=2026-10-04T19:31:09.550Z level=INFO msg="Upload complete. (144ms)"2348server # [ 35.508886] niks3-server[992]: 2026/10/04 19:31:09 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.ls2349server # [ 35.510771] niks3-server[992]: 2026/10/04 19:31:09 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.narinfo2350server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkRLYTA3UThZbzJjSzdZMmZJM2dBYzRjY256eWE0cDVVTHdvS3VWV2d0V0UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTExNDU4NjksImlhdCI6MTc5MTE0MjI2OSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.iNTCQyJZYSO_UXYr_6B0YocgRWhC_wm9lOtJittK5T4styrYsGqG-T1OQ67mWDdaLtggWsN3tdtWkyTd8gCng3qB7uz8cWHSqjLqvsUgkuFWQjROF4zw17W-_W38UTJIX1ifs9zXiKI4Np-tNALwa8eeP1GGgh0nAQ4qMdE0KRggKoIaSksjCeXLL7_4jvTWA8exbX0jC75jh9dUtUCiQYvlj4gJ5_nPfT7yz9uwJs09j-b6w0QIMb2rdCkpV49UagkMlo-JKw60kSeDWHjqgJHVZ523DiAhiqS1XI_dn7dgHstambOE0o9u2K42tbYq5VG-8nUy8jriGIpMl8wdgQ' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test, in 0.20 seconds)2351server: must succeed: 2352cat > /tmp/oidc-test2.nix << 'EOF'2353derivation {2354 name = "oidc-test2";2355 system = builtins.currentSystem;2356 builder = "/bin/sh";2357 args = [ "-c" "echo 'OIDC test 2' > $out" ];2358}2359EOF23602361server: (finished: must succeed: 2362cat > /tmp/oidc-test2.nix << 'EOF'2363derivation {2364 name = "oidc-test2";2365 system = builtins.currentSystem;2366 builder = "/bin/sh";2367 args = [ "-c" "echo 'OIDC test 2' > $out" ];2368}2369EOF2370, in 0.01 seconds)2371server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2372server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2373server # [ 35.576732] systemd[1]: Started Nix Daemon instance (PID 1388/UID 0).2374server # [ 35.615773] nix-daemon[1392]: remote pid 1388 is unknown user (trusted)2375server # this derivation will be built:2376server # /nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv2377server # building '/nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv'...2378server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.17 seconds)2379server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg'2380server # [ 35.709785] systemd[1]: nix-daemon@10-11-1388_1389-0.service: Deactivated successfully.2381server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.03 seconds)2382server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkRLYTA3UThZbzJjSzdZMmZJM2dBYzRjY256eWE0cDVVTHdvS3VWV2d0V0UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTExNDU4NjksImlhdCI6MTc5MTE0MjI2OSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.Gcm5XIQz7iaJLvZ5TpA-8EEQWnkJwrQI9iSV81J92pXTgDD-4e8l4uBFYiM5aMTlCSzoj1sLWxzTd3_SoZNCh80J8z82hlk7WAu8hkxLNY0TnOpeg8Wza0pzsW7wv61x_Jh8QJXieGd-RT40dG9PPXYHBg9AUQMNEl73NWNKwjPgyKgSvlr0CM_MmQvgzV_snDFraZVCuQ57kdbk1CZoFKDaCQYFRhK2tTb8UtWwS3TGvHezox7O3_1WQV8SvyrC2pJ-R74u0tX5PxFl_a4_bB-qqeyKSw9sW7CwSp9VumeSl3ccOkpRsFwO1CjdEP_zZuKFPq6kzy_HJk6QjCSBkA' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22383server # time=2026-10-04T19:31:09.788Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2384server # [ 35.782834] niks3-server[992]: 2026/10/04 19:31:09 WARN Authentication failed token_preview=eyJhbGciOi...Jk6QjCSBkA token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2385server # [ 35.818431] systemd[1]: Started Nix Daemon instance (PID 1422/UID 0).2386server # [ 35.859554] nix-daemon[1424]: remote pid 1422 is unknown user (trusted)2387server # [ 35.870905] systemd[1]: nix-daemon@11-12-1422_1423-0.service: Deactivated successfully.2388server # time=2026-10-04T19:31:09.918Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2389server # [ 35.875646] niks3-server[992]: 2026/10/04 19:31:09 WARN Authentication failed token_preview=eyJhbGciOi...Jk6QjCSBkA token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2390server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkRLYTA3UThZbzJjSzdZMmZJM2dBYzRjY256eWE0cDVVTHdvS3VWV2d0V0UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTExNDU4NjksImlhdCI6MTc5MTE0MjI2OSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.Gcm5XIQz7iaJLvZ5TpA-8EEQWnkJwrQI9iSV81J92pXTgDD-4e8l4uBFYiM5aMTlCSzoj1sLWxzTd3_SoZNCh80J8z82hlk7WAu8hkxLNY0TnOpeg8Wza0pzsW7wv61x_Jh8QJXieGd-RT40dG9PPXYHBg9AUQMNEl73NWNKwjPgyKgSvlr0CM_MmQvgzV_snDFraZVCuQ57kdbk1CZoFKDaCQYFRhK2tTb8UtWwS3TGvHezox7O3_1WQV8SvyrC2pJ-R74u0tX5PxFl_a4_bB-qqeyKSw9sW7CwSp9VumeSl3ccOkpRsFwO1CjdEP_zZuKFPq6kzy_HJk6QjCSBkA' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.15 seconds)2391server: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg'2392server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://wrong:5751&repository_owner=myorg', in 0.02 seconds)2393server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkRLYTA3UThZbzJjSzdZMmZJM2dBYzRjY256eWE0cDVVTHdvS3VWV2d0V0UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MTE0NTg2OSwiaWF0IjoxNzkxMTQyMjY5LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.xXWIENleWerH5Xma0omxR91U6VlX-XyKZV9uRmLo7rqoRCT8BXH6MpJvnHIUj46e_z544oaJpAh8aKVy_X_BluChRARXPpDw5q7B8mVHAUt8ANRnN0Xq4om-V3dV-VcjwS0-BmLtiLFZPLsZH5sgs981DATz_u_hcmst4eUH9f3Lqf5-DVoWiJGZMqnl8uUiAf389wksIjw650bTGx6B6YXRa-DyEcbgmdT4tVeyyKRVnpTM_JXIZ4yStN1Rt2dMBZ2jV5ZwFs90Gh5qBUtZViT8cGXi1iEpTFTuswik7j2xELbyLct9xy_EAXV4r0Tc_7VcADSk6QsIsalMsSvfoQ' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22394server # time=2026-10-04T19:31:09.961Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2395server # [ 35.956165] niks3-server[992]: 2026/10/04 19:31:09 WARN Authentication failed token_preview=eyJhbGciOi...salMsSvfoQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2396server # [ 35.991687] systemd[1]: Started Nix Daemon instance (PID 1444/UID 0).2397server # [ 36.032440] nix-daemon[1446]: remote pid 1444 is unknown user (trusted)2398server # [ 36.043302] systemd[1]: nix-daemon@12-13-1444_1445-0.service: Deactivated successfully.2399server # [ 36.048117] niks3-server[992]: 2026/10/04 19:31:10 WARN Authentication failed token_preview=eyJhbGciOi...salMsSvfoQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2400server # time=2026-10-04T19:31:10.093Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2401server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6IkRLYTA3UThZbzJjSzdZMmZJM2dBYzRjY256eWE0cDVVTHdvS3VWV2d0V0UiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MTE0NTg2OSwiaWF0IjoxNzkxMTQyMjY5LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.xXWIENleWerH5Xma0omxR91U6VlX-XyKZV9uRmLo7rqoRCT8BXH6MpJvnHIUj46e_z544oaJpAh8aKVy_X_BluChRARXPpDw5q7B8mVHAUt8ANRnN0Xq4om-V3dV-VcjwS0-BmLtiLFZPLsZH5sgs981DATz_u_hcmst4eUH9f3Lqf5-DVoWiJGZMqnl8uUiAf389wksIjw650bTGx6B6YXRa-DyEcbgmdT4tVeyyKRVnpTM_JXIZ4yStN1Rt2dMBZ2jV5ZwFs90Gh5qBUtZViT8cGXi1iEpTFTuswik7j2xELbyLct9xy_EAXV4r0Tc_7VcADSk6QsIsalMsSvfoQ' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.15 seconds)2402server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22403server # time=2026-10-04T19:31:10.109Z level=WARN msg="--auth-token is deprecated: tokens on the command line are visible in /proc and shell history; use --auth-token-path or --auth-token-script"2404server # [ 36.103837] niks3-server[992]: 2026/10/04 19:31:10 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2405server # [ 36.138282] systemd[1]: Started Nix Daemon instance (PID 1464/UID 0).2406server # [ 36.179189] nix-daemon[1466]: remote pid 1464 is unknown user (trusted)2407server # [ 36.190069] systemd[1]: nix-daemon@13-14-1464_1465-0.service: Deactivated successfully.2408server # [ 36.193073] niks3-server[992]: 2026/10/04 19:31:10 WARN Authentication failed token_preview=not-a-valid-jwt-token token_length=21 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2409server # time=2026-10-04T19:31:10.240Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2410server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.15 seconds)2411server: must succeed: 2412 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.324132414server # [ 36.248899] niks3-server[992]: 2026/10/04 19:31:10 INFO Received create pin request method=POST path=/api/pins/hello-pin2415server # [ 36.258483] niks3-server[992]: 2026/10/04 19:31:10 INFO Created/updated pin name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 narinfo_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo2416server # time=2026-10-04T19:31:10.304Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32417server: (finished: must succeed: 2418 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32419, in 0.06 seconds)2420server: must succeed: 2421 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list24222423server # [ 36.313613] niks3-server[992]: 2026/10/04 19:31:10 INFO Received list pins request method=GET path=/api/pins2424server: (finished: must succeed: 2425 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list2426, in 0.05 seconds)2427server: must succeed: 2428 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --names-only24292430server # [ 36.364108] niks3-server[992]: 2026/10/04 19:31:10 INFO Received list pins request method=GET path=/api/pins2431server: (finished: must succeed: 2432 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --names-only2433, in 0.05 seconds)2434server: must succeed: 2435 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --json24362437server # [ 36.414234] niks3-server[992]: 2026/10/04 19:31:10 INFO Received list pins request method=GET path=/api/pins2438server: (finished: must succeed: 2439 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --json2440, in 0.05 seconds)2441server: must succeed: 2442 export S3_ENDPOINT_URL=http://localhost:90002443 export AWS_ACCESS_KEY_ID=rustfsadmin2444 export AWS_SECRET_ACCESS_KEY=rustfsadmin2445 /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin24462447server: (finished: must succeed: 2448 export S3_ENDPOINT_URL=http://localhost:90002449 export AWS_ACCESS_KEY_ID=rustfsadmin2450 export AWS_SECRET_ACCESS_KEY=rustfsadmin2451 /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2452, in 0.02 seconds)2453server: must succeed: 2454 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log24552456server # time=2026-10-04T19:31:10.534Z level=INFO msg="All 1 paths already cached"2457server # [ 36.492561] niks3-server[992]: 2026/10/04 19:31:10 INFO Received create pin request method=POST path=/api/pins/ca-pin2458server # time=2026-10-04T19:31:10.543Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2459server # [ 36.501865] niks3-server[992]: 2026/10/04 19:31:10 INFO Created/updated pin name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log narinfo_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2460server: (finished: must succeed: 2461 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2462, in 0.06 seconds)2463server: must succeed: 2464 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --names-only24652466server # [ 36.554934] niks3-server[992]: 2026/10/04 19:31:10 INFO Received list pins request method=GET path=/api/pins2467server: (finished: must succeed: 2468 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --names-only2469, in 0.05 seconds)2470server: must succeed: 2471 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins delete hello-pin24722473server # [ 36.605108] niks3-server[992]: 2026/10/04 19:31:10 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2474server # time=2026-10-04T19:31:10.657Z level=INFO msg="Deleted pin" name=hello-pin2475server # [ 36.615331] niks3-server[992]: 2026/10/04 19:31:10 INFO Deleted pin name=hello-pin2476server: (finished: must succeed: 2477 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins delete hello-pin2478, in 0.06 seconds)2479server: must succeed: 2480 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --names-only24812482server # [ 36.668066] niks3-server[992]: 2026/10/04 19:31:10 INFO Received list pins request method=GET path=/api/pins2483server: (finished: must succeed: 2484 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins list --names-only2485, in 0.05 seconds)2486server: must fail: 2487 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent24882489server # [ 36.718181] niks3-server[992]: 2026/10/04 19:31:10 INFO Received create pin request method=POST path=/api/pins/bad-pin2490server # [ 36.720210] niks3-server[992]: 2026/10/04 19:31:10 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2491server # time=2026-10-04T19:31:10.765Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2492server: (finished: must fail: 2493 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/f121ch79qbs8l3p33ykk6vb4j5xmm28i-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2494, in 0.05 seconds)2495server: must succeed: systemctl start niks3-gc.service2496server # [ 36.745325] systemd[1]: Starting niks3 garbage collection...2497server # [ 36.786426] niks3[1593]: time=2026-10-04T19:31:10.829Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2498server # [ 36.789901] niks3-server[992]: 2026/10/04 19:31:10 INFO Starting cleanup of old closures method=DELETE path=/api/closures2499server # [ 36.791266] niks3[1593]: time=2026-10-04T19:31:10.833Z level=INFO msg="Garbage collection started"2500server # [ 36.793670] niks3-server[992]: 2026/10/04 19:31:10 INFO Aborted multipart uploads count=0 kept=02501server # [ 36.802300] niks3-server[992]: 2026/10/04 19:31:10 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02502server # [ 36.807918] niks3-server[992]: 2026/10/04 19:31:10 INFO Vacuumed table table=pending_closures2503server # [ 36.812786] niks3-server[992]: 2026/10/04 19:31:10 INFO Vacuumed table table=pending_objects2504server # [ 36.815913] niks3-server[992]: 2026/10/04 19:31:10 INFO Vacuumed table table=multipart_uploads2505server # [ 36.818841] niks3-server[992]: 2026/10/04 19:31:10 INFO Vacuumed table table=closures2506server # [ 36.822171] niks3-server[992]: 2026/10/04 19:31:10 INFO Vacuumed table table=objects2507server # [ 38.792575] niks3[1593]: time=2026-10-04T19:31:12.834Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=3 objects_deleted=0 objects_failed=02508server # [ 38.799899] niks3[1593]: time=2026-10-04T19:31:12.834Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02509server # [ 38.810510] systemd[1]: niks3-gc.service: Deactivated successfully.2510server # [ 38.814141] systemd[1]: Finished niks3 garbage collection.2511server # [ 38.817826] systemd[1]: niks3-gc.service: Consumed 34ms CPU time over 2.067s wall clock time, 2.7M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2512server: (finished: must succeed: systemctl start niks3-gc.service, in 2.10 seconds)2513builder: waiting for unit niks3-auto-upload.socket2514builder: waiting for the VM to finish booting2515builder: Guest shell says: b'Spawning backdoor root shell...\n'2516builder: connected to guest root shell2517builder: (connecting took 0.00 seconds)2518builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2519builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.05 seconds)2520builder: must succeed: test -S /run/niks3/upload-to-cache.sock2521builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.02 seconds)2522builder: must succeed: grep post-build-hook /etc/nix/nix.conf2523builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.02 seconds)2524builder: must succeed: 2525cat > /tmp/test-drv.nix << 'EOF'2526derivation {2527 name = "post-build-hook-test";2528 system = builtins.currentSystem;2529 builder = "/bin/sh";2530 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2531}2532EOF25332534builder: (finished: must succeed: 2535cat > /tmp/test-drv.nix << 'EOF'2536derivation {2537 name = "post-build-hook-test";2538 system = builtins.currentSystem;2539 builder = "/bin/sh";2540 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2541}2542EOF2543, in 0.01 seconds)2544builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2545builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2546builder # [ 38.986715] systemd[1]: Created slice Slice /system/nix-daemon.2547builder # [ 38.989883] systemd[1]: Started Nix Daemon instance (PID 789/UID 0).2548builder # [ 39.035113] nix-daemon[793]: remote pid 789 is unknown user (trusted)2549builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 447 ms (attempt 1/5)2550builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1057 ms (attempt 2/5)2551builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 2065 ms (attempt 3/5)2552builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 4041 ms (attempt 4/5)2553builder # warning: Failed to setup the substituter at URI 'https://cache.nixos.org/': error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6)2554builder # this derivation will be built:2555builder # /nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2556builder # building '/nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv'...2557builder # [ 46.845203] systemd[1]: Started niks3 auto-upload daemon.2558builder # [ 46.949143] niks3-hook[818]: time=2026-10-04T19:31:21.079Z level=INFO msg="niks3-hook serve starting" socket=/run/niks3/upload-to-cache.sock socket-activated=true db-path=/var/lib/niks3-hook/upload-queue.db batch-size=5 idle-exit-timeout=5s drain-timeout=0s2559builder # [ 46.958382] niks3-hook[818]: time=2026-10-04T19:31:21.088Z level=INFO msg="Upload queue status" pending=12560builder # [ 46.960543] niks3-hook[818]: time=2026-10-04T19:31:21.091Z level=INFO msg="Uploading batch" count=12561builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 8.05 seconds)2562builder: waiting for unit niks3-auto-upload.service2563builder # [ 46.983232] systemd[1]: nix-daemon@0-1-789_790-0.service: Deactivated successfully.2564builder # [ 46.984957] systemd[1]: nix-daemon@0-1-789_790-0.service: Consumed 152ms CPU time over 7.993s wall clock time, 20.6M memory peak, 1.4K outgoing IP traffic.2565builder: (finished: waiting for unit niks3-auto-upload.service, in 0.06 seconds)2566??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2567 File "/nix/store/vk7cx7y36sxavc4g6cv4sra2zxk4xp68-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392568builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2569??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2570 File "/nix/store/vk7cx7y36sxavc4g6cv4sra2zxk4xp68-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392571builder # [ 47.044497] systemd[1]: Started Nix Daemon instance (PID 832/UID 0).2572builder # [ 47.094424] nix-daemon[839]: remote pid 832 is unknown user (trusted)2573builder # [ 47.105573] systemd[1]: nix-daemon@1-2-832_833-0.service: Deactivated successfully.2574server # [ 47.111439] niks3-server[992]: 2026/10/04 19:31:21 INFO Received push request method=POST path=/api/pushes2575builder # [ 47.124411] niks3-hook[818]: time=2026-10-04T19:31:21.255Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2576builder # [ 47.125908] niks3-hook[818]: time=2026-10-04T19:31:21.256Z level=INFO msg="Uploading ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test (144B)"2577server # [ 47.168583] niks3-server[992]: 2026/10/04 19:31:21 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2578server # [ 47.177217] niks3-server[992]: 2026/10/04 19:31:21 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign2579builder # [ 47.184937] niks3-hook[818]: time=2026-10-04T19:31:21.315Z level=INFO msg="Uploading 1 narinfos"2580server # [ 47.185365] niks3-server[992]: 2026/10/04 19:31:21 INFO Signed narinfos id=6 count=12581server # [ 47.191854] niks3-server[992]: 2026/10/04 19:31:21 INFO Registered completed upload object_key=log/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2582server # [ 47.194291] niks3-server[992]: 2026/10/04 19:31:21 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.ls2583server # [ 47.200954] niks3-server[992]: 2026/10/04 19:31:21 INFO Received complete push request method=POST path=/api/pushes/6/complete2584server # [ 47.204260] niks3-server[992]: 2026/10/04 19:31:21 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.narinfo2585builder # [ 47.204087] niks3-hook[818]: time=2026-10-04T19:31:21.334Z level=INFO msg="Upload complete. (243ms)"2586builder # [ 51.957537] niks3-hook[818]: time=2026-10-04T19:31:26.087Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2587builder # [ 51.962268] niks3-hook[818]: time=2026-10-04T19:31:26.092Z level=INFO msg="niks3-hook serve stopped"2588builder # [ 51.983424] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2589builder # [ 51.986314] systemd[1]: niks3-auto-upload.service: Consumed 112ms CPU time over 5.137s wall clock time, 11M memory peak, 68K written to disk, 5.7K incoming IP traffic, 8.5K outgoing IP traffic.2590builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.23 seconds)2591server: must succeed: 2592 export AWS_ACCESS_KEY_ID=rustfsadmin2593export AWS_SECRET_ACCESS_KEY=rustfsadmin2594 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test25952596server: (finished: must succeed: 2597 export AWS_ACCESS_KEY_ID=rustfsadmin2598export AWS_SECRET_ACCESS_KEY=rustfsadmin2599 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/hook-test-store /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2600, in 0.19 seconds)2601server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2602server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test, in 0.06 seconds)2603(finished: run the VM test script, in 53.56 seconds)2604test script finished in 53.84s2605cleanup2606kill QemuMachine (pid 47)2607builder # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2608builder # [2026-10-04T19:31:27Z INFO virtiofsd] Client disconnected, shutting down2609builder # [2026-10-04T19:31:27Z INFO virtiofsd] Client disconnected, shutting down2610builder # [2026-10-04T19:31:27Z INFO virtiofsd] Client disconnected, shutting down2611kill QemuMachine (pid 48)2612server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2613server # [2026-10-04T19:31:27Z INFO virtiofsd] Client disconnected, shutting down2614server # [2026-10-04T19:31:27Z INFO virtiofsd] Client disconnected, shutting down2615server # [2026-10-04T19:31:27Z INFO virtiofsd] Client disconnected, shutting down2616(finished: cleanup, in 0.51 seconds)2617additionally exposed symbols:2618 builder, server,2619 vlan1,2620 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_ssh2621Hello store path: /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32622Test output path: /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2623Symlink wrapper store path: /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2624Symlink wrapper points to: /nix/store/x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package/bin/test-program2625OIDC test store path: /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2626Valid OIDC token obtained (length=677)2627OIDC push with valid token: SUCCESS2628Invalid OIDC token obtained (wrong org)2629OIDC push with wrong org: correctly rejected2630Wrong audience OIDC token obtained2631OIDC push with wrong audience: correctly rejected2632OIDC push with malformed token: correctly rejected2633All OIDC tests passed!2634All pin tests passed!2635Built store path: /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2636Post-build-hook pipeline test passed!