vm-test-run-nixos-test-niks3
checks.x86_64-linux.nixos-test-niks3
· build #222
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.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...12builder # Disk image does not exist, creating the virtualisation disk image...13server # Formatting '/build/vm-state-server/tmp.XP08P3TXAq', fmt=raw size=107374182414builder # Formatting '/build/vm-state-builder/tmp.QThfM8GYjI', fmt=raw size=107374182415server # mke2fs 1.47.4 (6-Mar-2025)16builder # mke2fs 1.47.4 (6-Mar-2025)17server # Discarding device blocks: 0/262144 done18builder # Discarding device blocks: 0/262144 done19server # Creating filesystem with 262144 4k blocks and 65536 inodes20builder # Creating filesystem with 262144 4k blocks and 65536 inodes21server # Filesystem UUID: ddf1e02b-39a3-441b-932b-84856b0859ea22builder # Filesystem UUID: c477f26d-6fc3-4c87-8dca-fda3748a4b9923server # Superblock backups stored on blocks:24builder # Superblock backups stored on blocks:25server # 32768, 98304, 163840, 22937626builder # 32768, 98304, 163840, 22937627server # 28builder # 29server # Allocating group tables: 0/8 done30builder # Allocating group tables: 0/8 done31server # Writing inode tables: 0/8 done32builder # Writing inode tables: 0/8 done33server # Creating journal (8192 blocks): done34builder # Creating journal (8192 blocks): done35server # Writing superblocks and filesystem accounting information: 0/8 done36builder # Writing superblocks and filesystem accounting information: 0/8 done37server # 38builder # 39server # Virtualisation disk image created.40builder # Virtualisation disk image created.41server # Starting virtiofs daemons...42builder # Starting virtiofs daemons...43server # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)44builder # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)45server # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether46builder # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether47server # [2026-09-19T10:55:11Z INFO virtiofsd] Waiting for vhost-user socket connection...48builder # [2026-09-19T10:55:11Z INFO virtiofsd] Waiting for vhost-user socket connection...49server # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)50builder # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)51server # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether52builder # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether53server # [2026-09-19T10:55:11Z INFO virtiofsd] Waiting for vhost-user socket connection...54builder # [2026-09-19T10:55:11Z INFO virtiofsd] Waiting for vhost-user socket connection...55server # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)56builder # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)57server # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether58builder # [2026-09-19T10:55:11Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether59server # [2026-09-19T10:55:11Z INFO virtiofsd] Waiting for vhost-user socket connection...60builder # [2026-09-19T10:55:11Z INFO virtiofsd] Waiting for vhost-user socket connection...61server # [2026-09-19T10:55:11Z INFO virtiofsd] Client connected, servicing requests62builder # [2026-09-19T10:55:11Z INFO virtiofsd] Client connected, servicing requests63server # [2026-09-19T10:55:11Z INFO virtiofsd] Client connected, servicing requests64builder # [2026-09-19T10:55:11Z INFO virtiofsd] Client connected, servicing requests65server # [2026-09-19T10:55:11Z INFO virtiofsd] Client connected, servicing requests66builder # [2026-09-19T10:55:11Z INFO virtiofsd] Client connected, servicing requests67builder: QEMU running (pid 47)68(finished: start all VMs, in 0.22 seconds)69server: waiting for unit postgresql.service70server: waiting for the VM to finish booting71server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)72builder # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)73server # 74server # 75server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0076server # Press Ctrl-B to configure iPXE (PCI 00:02.0)...77server # 78server # 79server # 80server # 81server # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0082server # Press Ctrl-B to configure iPXE (PCI 00:05.0)...83server # 84server # 85builder # 86builder # 87builder # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0088builder # Press Ctrl-B to configure iPXE (PCI 00:02.0)...89builder # 90builder # 91builder # 92builder # 93builder # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0094builder # Press Ctrl-B to configure iPXE (PCI 00:05.0)...95builder # 96builder # 97server # Booting from ROM...98builder # Booting from ROM...99server # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026100server # [ 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/5wji2h86vrfd5hbq69zzh7558g0w0vx1-nixos-system-server-test/init regInfo=/nix/store/zb7x6qmdxxlknkhhxvi2iigrb6048pc8-closure-info/registration console=ttyS0,115200n8 console=tty0101server # [ 0.000000] BIOS-provided physical RAM map:102server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable103server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved104server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved105server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable106server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved107server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved108server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved109server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved110server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved111server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved112server # [ 0.000000] NX (Execute Disable) protection: active113server # [ 0.000000] APIC: Static calls initialized114server # [ 0.000000] SMBIOS 2.8 present.115server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014116server # [ 0.000000] DMI: Memory slots populated: 1/1117server # [ 0.000000] Hypervisor detected: KVM118server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000119server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00120server # [ 0.000000] kvm-clock: using sched offset of 453711371 cycles121server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns122server # [ 0.000005] tsc: Detected 2400.012 MHz processor123server # [ 0.000812] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000124server # [ 0.000840] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs125server # [ 0.000843] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT126server # [ 0.002731] found SMP MP-table at [mem 0x000f5450-0x000f545f]127server # [ 0.002742] Using GB pages for direct mapping128server # [ 0.002793] RAMDISK: [mem 0x3e36c000-0x3ffcffff]129server # [ 0.002804] ACPI: Early table checksum verification disabled130server # [ 0.002806] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )131builder # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 2026132server # [ 0.002809] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)133server # [ 0.002813] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)134server # [ 0.002821] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)135server # [ 0.002824] ACPI: FACS 0x000000003FFE0000 000040136builder # [ 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/gw9fmf2610hk69sp14in39i13w3bz4c5-nixos-system-builder-test/init regInfo=/nix/store/rz59045b9zj9qbr757mlg9biydm1zzp2-closure-info/registration console=ttyS0,115200n8 console=tty0137server # [ 0.002825] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)138builder # [ 0.000000] BIOS-provided physical RAM map:139server # [ 0.002827] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)140builder # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable141builder # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved142server # [ 0.002828] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)143builder # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved144server # [ 0.002830] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)145builder # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable146server # [ 0.002831] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]147builder # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved148server # [ 0.002832] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]149builder # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved150server # [ 0.002833] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]151builder # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved152server # [ 0.002833] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]153builder # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved154server # [ 0.002834] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]155builder # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved156server # [ 0.002834] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]157builder # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved158server # [ 0.002834] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]159builder # [ 0.000000] NX (Execute Disable) protection: active160server # [ 0.003068] No NUMA configuration found161builder # [ 0.000000] APIC: Static calls initialized162builder # [ 0.000000] SMBIOS 2.8 present.163server # [ 0.003068] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]164server # [ 0.003071] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]165builder # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014166server # [ 0.005336] Zone ranges:167builder # [ 0.000000] DMI: Memory slots populated: 1/1168server # [ 0.005337] DMA [mem 0x0000000000001000-0x0000000000ffffff]169builder # [ 0.000000] Hypervisor detected: KVM170server # [ 0.005339] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]171builder # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000172server # [ 0.005340] Normal empty173server # [ 0.005341] Device empty174builder # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00175server # [ 0.005341] Movable zone start for each node176builder # [ 0.000001] kvm-clock: using sched offset of 501692162 cycles177server # [ 0.005342] Early memory node ranges178server # [ 0.005342] node 0: [mem 0x0000000000001000-0x000000000009efff]179builder # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns180server # [ 0.005343] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]181builder # [ 0.000005] tsc: Detected 2400.012 MHz processor182builder # [ 0.000813] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000183server # [ 0.005344] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]184server # [ 0.005364] On node 0, zone DMA: 1 pages in unavailable ranges185builder # [ 0.000840] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs186server # [ 0.005622] On node 0, zone DMA: 97 pages in unavailable ranges187builder # [ 0.000843] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT188server # [ 0.023074] On node 0, zone DMA32: 40 pages in unavailable ranges189builder # [ 0.002736] found SMP MP-table at [mem 0x000f5450-0x000f545f]190server # [ 0.023523] ACPI: PM-Timer IO Port: 0x608191builder # [ 0.002748] Using GB pages for direct mapping192server # [ 0.023534] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])193builder # [ 0.002830] RAMDISK: [mem 0x3e36c000-0x3ffcffff]194builder # [ 0.002837] ACPI: Early table checksum verification disabled195server # [ 0.023559] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23196builder # [ 0.002840] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )197server # [ 0.023562] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)198server # [ 0.023563] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)199builder # [ 0.002844] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)200server # [ 0.023565] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)201builder # [ 0.002848] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)202server # [ 0.023566] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)203builder # [ 0.002856] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)204server # [ 0.023566] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)205builder # [ 0.002858] ACPI: FACS 0x000000003FFE0000 000040206server # [ 0.023568] ACPI: Using ACPI (MADT) for SMP configuration information207builder # [ 0.002859] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)208server # [ 0.023569] ACPI: HPET id: 0x8086a201 base: 0xfed00000209server # [ 0.023572] TSC deadline timer available210builder # [ 0.002861] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)211server # [ 0.023576] CPU topo: Max. logical packages: 1212server # [ 0.023577] CPU topo: Max. logical dies: 1213builder # [ 0.002862] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)214server # [ 0.023577] CPU topo: Max. dies per package: 1215server # [ 0.023581] CPU topo: Max. threads per core: 1216builder # [ 0.002864] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)217server # [ 0.023581] CPU topo: Num. cores per package: 1218builder # [ 0.002865] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]219server # [ 0.023582] CPU topo: Num. threads per package: 1220server # [ 0.023582] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs221builder # [ 0.002866] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]222server # [ 0.023597] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()223builder # [ 0.002867] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]224builder # [ 0.002867] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]225server # [ 0.023625] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]226builder # [ 0.002868] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]227server # [ 0.023627] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]228server # [ 0.023628] [mem 0x40000000-0xafffffff] available for PCI devices229builder # [ 0.002868] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]230server # [ 0.023630] Booting paravirtualized kernel on KVM231builder # [ 0.002869] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]232builder # [ 0.003095] No NUMA configuration found233server # [ 0.023632] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns234builder # [ 0.003096] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]235server # [ 0.028080] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1236builder # [ 0.003099] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]237builder # [ 0.005313] Zone ranges:238server # [ 0.030250] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152239builder # [ 0.005313] DMA [mem 0x0000000000001000-0x0000000000ffffff]240server # [ 0.030295] kvm-guest: PV spinlocks disabled, single CPU241builder # [ 0.005315] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]242builder # [ 0.005316] Normal empty243builder # [ 0.005317] Device empty244builder # [ 0.005318] Movable zone start for each node245builder # [ 0.005319] Early memory node ranges246builder # [ 0.005319] node 0: [mem 0x0000000000001000-0x000000000009efff]247builder # [ 0.005320] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]248server # [ 0.030296] 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/5wji2h86vrfd5hbq69zzh7558g0w0vx1-nixos-system-server-test/init regInfo=/nix/store/zb7x6qmdxxlknkhhxvi2iigrb6048pc8-closure-info/registration console=ttyS0,115200n8 console=tty0249builder # [ 0.005321] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]250builder # [ 0.005340] On node 0, zone DMA: 1 pages in unavailable ranges251server # [ 0.030387] Unknown kernel command line parameters "regInfo=/nix/store/zb7x6qmdxxlknkhhxvi2iigrb6048pc8-closure-info/registration", will be passed to user space.252builder # [ 0.005602] On node 0, zone DMA: 97 pages in unavailable ranges253server # [ 0.030400] random: crng init done254builder # [ 0.022868] On node 0, zone DMA32: 40 pages in unavailable ranges255server # [ 0.030400] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes256builder # [ 0.023314] ACPI: PM-Timer IO Port: 0x608257server # [ 0.031449] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)258builder # [ 0.023324] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])259builder # [ 0.023352] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23260server # [ 0.031461] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)261server # [ 0.031492] Fallback order for Node 0: 0262builder # [ 0.023355] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)263server # [ 0.031494] Built 1 zonelists, mobility grouping on. Total pages: 262006264builder # [ 0.023357] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)265server # [ 0.031495] Policy zone: DMA32266builder # [ 0.023358] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)267server # [ 0.034154] mem auto-init: stack:all(zero), heap alloc:on, heap free:off268builder # [ 0.023359] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)269server # [ 0.036565] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1270server # [ 0.038784] allocated 2097152 bytes of page_ext271builder # [ 0.023359] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)272server # [ 0.048533] ftrace: allocating 48736 entries in 192 pages273builder # [ 0.023362] ACPI: Using ACPI (MADT) for SMP configuration information274server # [ 0.048535] ftrace: allocated 192 pages with 2 groups275builder # [ 0.023363] ACPI: HPET id: 0x8086a201 base: 0xfed00000276server # [ 0.049376] Dynamic Preempt: lazy277builder # [ 0.023366] TSC deadline timer available278server # [ 0.049521] rcu: Preemptible hierarchical RCU implementation.279builder # [ 0.023370] CPU topo: Max. logical packages: 1280server # [ 0.049521] rcu: RCU event tracing is enabled.281builder # [ 0.023370] CPU topo: Max. logical dies: 1282builder # [ 0.023371] CPU topo: Max. dies per package: 1283server # [ 0.049522] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.284builder # [ 0.023374] CPU topo: Max. threads per core: 1285server # [ 0.049523] Trampoline variant of Tasks RCU enabled.286builder # [ 0.023375] CPU topo: Num. cores per package: 1287server # [ 0.049524] Rude variant of Tasks RCU enabled.288builder # [ 0.023375] CPU topo: Num. threads per package: 1289server # [ 0.049524] Tracing variant of Tasks RCU enabled.290builder # [ 0.023376] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs291server # [ 0.049525] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.292builder # [ 0.023400] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()293server # [ 0.049525] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1294builder # [ 0.023436] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]295server # [ 0.049579] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.296builder # [ 0.023438] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]297server # [ 0.049581] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.298builder # [ 0.023439] [mem 0x40000000-0xafffffff] available for PCI devices299builder # [ 0.023440] Booting paravirtualized kernel on KVM300server # [ 0.049582] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.301server # [ 0.053926] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16302builder # [ 0.023443] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns303server # [ 0.054198] rcu: srcu_init: Setting srcu_struct sizes based on contention.304builder # [ 0.027870] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1305builder # [ 0.030143] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152306server # [ 0.054206] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns307builder # [ 0.030191] kvm-guest: PV spinlocks disabled, single CPU308server # [ 0.054312] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)309server # [ 0.057851] Console: colour VGA+ 80x25310server # [ 0.057854] printk: legacy console [tty0] enabled311server # [ 0.087600] printk: legacy console [ttyS0] enabled312server # [ 0.192735] ACPI: Core revision 20250807313builder # [ 0.030192] 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/gw9fmf2610hk69sp14in39i13w3bz4c5-nixos-system-builder-test/init regInfo=/nix/store/rz59045b9zj9qbr757mlg9biydm1zzp2-closure-info/registration console=ttyS0,115200n8 console=tty0314server # [ 0.193653] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns315builder # [ 0.030282] Unknown kernel command line parameters "regInfo=/nix/store/rz59045b9zj9qbr757mlg9biydm1zzp2-closure-info/registration", will be passed to user space.316server # [ 0.195240] APIC: Switch to symmetric I/O mode setup317builder # [ 0.030293] random: crng init done318server # [ 0.196252] x2apic enabled319builder # [ 0.030294] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes320server # [ 0.197021] APIC: Switched APIC routing to: physical x2apic321builder # [ 0.031329] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)322builder # [ 0.031342] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)323builder # [ 0.031370] Fallback order for Node 0: 0324server # [ 0.198849] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1325builder # [ 0.031372] Built 1 zonelists, mobility grouping on. Total pages: 262006326builder # [ 0.031373] Policy zone: DMA32327server # [ 0.199845] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns328builder # [ 0.033753] mem auto-init: stack:all(zero), heap alloc:on, heap free:off329builder # [ 0.036102] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1330server # [ 0.201597] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)331builder # [ 0.038304] allocated 2097152 bytes of page_ext332builder # [ 0.048001] ftrace: allocating 48736 entries in 192 pages333server # [ 0.202688] x86/cpu: User Mode Instruction Prevention (UMIP) activated334builder # [ 0.048003] ftrace: allocated 192 pages with 2 groups335builder # [ 0.049024] Dynamic Preempt: lazy336server # [ 0.204612] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127337builder # [ 0.049180] rcu: Preemptible hierarchical RCU implementation.338server # [ 0.205543] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0339builder # [ 0.049181] rcu: RCU event tracing is enabled.340builder # [ 0.049181] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.341builder # [ 0.049183] Trampoline variant of Tasks RCU enabled.342server # [ 0.206397] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto343builder # [ 0.049183] Rude variant of Tasks RCU enabled.344builder # [ 0.049184] Tracing variant of Tasks RCU enabled.345server # [ 0.207595] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl346builder # [ 0.049184] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.347server # [ 0.209595] Transient Scheduler Attacks: Vulnerable: No microcode348builder # [ 0.049185] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1349server # [ 0.210594] Spectre V2 : Mitigation: Enhanced / Automatic IBRS350builder # [ 0.049210] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.351server # [ 0.211595] Speculative Return Stack Overflow: Mitigation: Safe RET352builder # [ 0.049212] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.353builder # [ 0.049212] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.354builder # [ 0.053564] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16355builder # [ 0.053832] rcu: srcu_init: Setting srcu_struct sizes based on contention.356server # [ 0.212593] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization357builder # [ 0.053838] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns358server # [ 0.213602] Spectre V2 : Enabling IBPB for BPF359builder # [ 0.053939] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)360server # [ 0.214343] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier361builder # [ 0.057477] Console: colour VGA+ 80x25362builder # [ 0.057479] printk: legacy console [tty0] enabled363server # [ 0.215595] active return thunk: srso_alias_return_thunk364builder # [ 0.087000] printk: legacy console [ttyS0] enabled365server # [ 0.216616] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'366builder # [ 0.191543] ACPI: Core revision 20250807367server # [ 0.217594] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'368builder # [ 0.192508] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns369server # [ 0.218594] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'370builder # [ 0.194119] APIC: Switch to symmetric I/O mode setup371server # [ 0.219594] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'372builder # [ 0.195104] x2apic enabled373server # [ 0.220594] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'374builder # [ 0.195895] APIC: Switched APIC routing to: physical x2apic375server # [ 0.222386] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'376builder # [ 0.197779] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1377server # [ 0.223435] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'378server # [ 0.224590] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256379builder # [ 0.198780] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns380server # [ 0.225594] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64381server # [ 0.226594] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512382builder # [ 0.200562] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)383server # [ 0.227594] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024384builder # [ 0.201648] x86/cpu: User Mode Instruction Prevention (UMIP) activated385server # [ 0.228593] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8386builder # [ 0.203682] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127387builder # [ 0.204559] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0388server # [ 0.229594] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.389builder # [ 0.205563] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto390builder # [ 0.206560] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl391builder # [ 0.208559] Transient Scheduler Attacks: Vulnerable: No microcode392builder # [ 0.209559] Spectre V2 : Mitigation: Enhanced / Automatic IBRS393builder # [ 0.210559] Speculative Return Stack Overflow: Mitigation: Safe RET394builder # [ 0.211559] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization395builder # [ 0.213567] Spectre V2 : Enabling IBPB for BPF396builder # [ 0.214560] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier397builder # [ 0.215559] active return thunk: srso_alias_return_thunk398builder # [ 0.217272] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'399builder # [ 0.218559] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'400builder # [ 0.219559] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'401builder # [ 0.220559] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'402builder # [ 0.221559] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'403builder # [ 0.222559] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'404builder # [ 0.223559] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'405builder # [ 0.224559] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256406builder # [ 0.226361] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64407builder # [ 0.227360] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512408builder # [ 0.228559] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024409builder # [ 0.229559] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8410builder # [ 0.230559] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.411server # [ 0.264382] Freeing SMP alternatives memory: 44K412server # [ 0.265190] pid_max: default: 32768 minimum: 301413server # [ 0.265702] LSM: initializing lsm=capability,landlock,yama,bpf,ima414server # [ 0.266703] landlock: Up and running.415server # [ 0.267595] Yama: becoming mindful.416server # [ 0.268811] LSM support for eBPF active417server # [ 0.269702] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)418server # [ 0.270615] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)419server # [ 0.273481] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)420server # [ 0.274181] Performance Events: Fam17h+ core perfctr, AMD PMU driver.421server # [ 0.274599] ... version: 2422server # [ 0.275329] ... bit width: 48423server # [ 0.275597] ... generic counters: 6424server # [ 0.276367] ... generic bitmap: 000000000000003f425server # [ 0.276606] ... fixed-purpose counters: 0426server # [ 0.277377] ... fixed-purpose bitmap: 0000000000000000427server # [ 0.277597] ... value mask: 0000ffffffffffff428server # [ 0.278550] ... max period: 00007fffffffffff429server # [ 0.279315] ... global_ctrl mask: 000000000000003f430server # [ 0.279709] signal: max sigframe size: 3376431server # [ 0.280523] rcu: Hierarchical SRCU implementation.432server # [ 0.281211] rcu: Max phase no-delay instances is 400.433server # [ 0.286181] smp: Bringing up secondary CPUs ...434server # [ 0.286610] smp: Brought up 1 node, 1 CPU435builder # [ 0.265121] Freeing SMP alternatives memory: 44K436builder # [ 0.265562] pid_max: default: 32768 minimum: 301437server # [ 0.287271] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)438builder # [ 0.266661] LSM: initializing lsm=capability,landlock,yama,bpf,ima439builder # [ 0.267654] landlock: Up and running.440server # [ 0.287727] Memory: 941032K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 99596K reserved, 0K cma-reserved)441builder # [ 0.268559] Yama: becoming mindful.442server # [ 0.288832] devtmpfs: initialized443builder # [ 0.269369] LSM support for eBPF active444server # [ 0.289699] x86/mm: Memory block size: 128MB445builder # [ 0.270133] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)446builder # [ 0.270591] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)447server # [ 0.291405] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)448server # [ 0.292581] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).449builder # [ 0.273874] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)450server # [ 0.293687] pinctrl core: initialized pinctrl subsystem451server # [ 0.294903] PM: RTC time: 10:55:11, date: 2026-09-19452builder # [ 0.275125] Performance Events: Fam17h+ core perfctr, AMD PMU driver.453builder # [ 0.275568] ... version: 2454builder # [ 0.276333] ... bit width: 48455builder # [ 0.276561] ... generic counters: 6456builder # [ 0.277292] ... generic bitmap: 000000000000003f457server # [ 0.298398] NET: Registered PF_NETLINK/PF_ROUTE protocol family458builder # [ 0.277561] ... fixed-purpose counters: 0459builder # [ 0.278316] ... fixed-purpose bitmap: 0000000000000000460server # [ 0.299730] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations461builder # [ 0.278561] ... value mask: 0000ffffffffffff462server # [ 0.300615] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations463builder # [ 0.279505] ... max period: 00007fffffffffff464builder # [ 0.280293] ... global_ctrl mask: 000000000000003f465server # [ 0.301758] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations466builder # [ 0.280679] signal: max sigframe size: 3376467server # [ 0.302608] audit: initializing netlink subsys (disabled)468builder # [ 0.281510] rcu: Hierarchical SRCU implementation.469builder # [ 0.282202] rcu: Max phase no-delay instances is 400.470server # [ 0.303826] thermal_sys: Registered thermal governor 'fair_share'471server # [ 0.303828] thermal_sys: Registered thermal governor 'bang_bang'472server # [ 0.304598] thermal_sys: Registered thermal governor 'step_wise'473server # [ 0.305600] audit: type=2000 audit(1789815312.298:1): state=initialized audit_enabled=0 res=1474server # [ 0.307600] thermal_sys: Registered thermal governor 'user_space'475builder # [ 0.287073] smp: Bringing up secondary CPUs ...476server # [ 0.307601] thermal_sys: Registered thermal governor 'power_allocator'477builder # [ 0.287576] smp: Brought up 1 node, 1 CPU478server # [ 0.308622] cpuidle: using governor menu479builder # [ 0.288241] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)480server # [ 0.310846] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5481builder # [ 0.288768] Memory: 941040K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 99596K reserved, 0K cma-reserved)482server # [ 0.311892] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]483builder # [ 0.289800] devtmpfs: initialized484server # [ 0.312601] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry485builder # [ 0.290688] x86/mm: Memory block size: 128MB486server # [ 0.313608] PCI: Using configuration type 1 for base access487server # [ 0.314750] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.488builder # [ 0.292408] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)489builder # [ 0.293526] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).490builder # [ 0.294648] pinctrl core: initialized pinctrl subsystem491builder # [ 0.295775] PM: RTC time: 10:55:11, date: 2026-09-19492builder # [ 0.299279] NET: Registered PF_NETLINK/PF_ROUTE protocol family493server # [ 0.321893] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages494server # [ 0.322598] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page495builder # [ 0.299919] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations496builder # [ 0.300578] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations497builder # [ 0.301709] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations498builder # [ 0.302571] audit: initializing netlink subsys (disabled)499server # [ 0.327598] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages500builder # [ 0.303753] thermal_sys: Registered thermal governor 'fair_share'501server # [ 0.328598] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page502builder # [ 0.303755] thermal_sys: Registered thermal governor 'bang_bang'503builder # [ 0.304563] thermal_sys: Registered thermal governor 'step_wise'504builder # [ 0.305538] thermal_sys: Registered thermal governor 'user_space'505builder # [ 0.306373] audit: type=2000 audit(1789815312.328:1): state=initialized audit_enabled=0 res=1506builder # [ 0.308564] thermal_sys: Registered thermal governor 'power_allocator'507builder # [ 0.308579] cpuidle: using governor menu508builder # [ 0.310841] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5509server # [ 0.337174] ACPI: Added _OSI(Module Device)510builder # [ 0.311799] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]511server # [ 0.337599] ACPI: Added _OSI(Processor Device)512server # [ 0.338338] ACPI: Added _OSI(Processor Aggregator Device)513builder # [ 0.312565] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry514builder # [ 0.313575] PCI: Using configuration type 1 for base access515builder # [ 0.314768] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.516server # [ 0.345125] ACPI: 1 ACPI AML tables successfully acquired and loaded517builder # [ 0.319820] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages518builder # [ 0.320563] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page519server # [ 0.348505] ACPI: Interpreter enabled520server # [ 0.349087] ACPI: PM: (supports S0 S3 S4 S5)521server # [ 0.349598] ACPI: Using IOAPIC for interrupt routing522builder # [ 0.325562] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages523server # [ 0.350549] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug524builder # [ 0.326561] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page525server # [ 0.355597] PCI: Using E820 reservations for host bridge windows526server # [ 0.356736] ACPI: Enabled 2 GPEs in block 00 to 3F527builder # [ 0.337014] ACPI: Added _OSI(Module Device)528builder # [ 0.337563] ACPI: Added _OSI(Processor Device)529builder # [ 0.338317] ACPI: Added _OSI(Processor Aggregator Device)530server # [ 0.365579] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])531server # [ 0.367154] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]532server # [ 0.367690] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]533server # [ 0.368723] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]534server # [ 0.369935] PCI host bridge to bus 0000:00535server # [ 0.370602] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]536builder # [ 0.347140] ACPI: 1 ACPI AML tables successfully acquired and loaded537server # [ 0.371598] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]538server # [ 0.372597] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]539server # [ 0.373598] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]540builder # [ 0.350987] ACPI: Interpreter enabled541server # [ 0.374597] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]542builder # [ 0.351577] ACPI: PM: (supports S0 S3 S4 S5)543builder # [ 0.352307] ACPI: Using IOAPIC for interrupt routing544server # [ 0.375597] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]545server # [ 0.376597] pci_bus 0000:00: root bus resource [bus 00-ff]546builder # [ 0.352613] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug547builder # [ 0.353563] PCI: Using E820 reservations for host bridge windows548server # [ 0.377610] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint549server # [ 0.379025] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint550builder # [ 0.356689] ACPI: Enabled 2 GPEs in block 00 to 3F551server # [ 0.381725] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]552server # [ 0.382651] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]553server # [ 0.383619] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]554builder # [ 0.365377] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])555builder # [ 0.366350] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]556server # [ 0.384920] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]557builder # [ 0.368070] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]558server # [ 0.386325] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint559builder # [ 0.368684] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]560builder # [ 0.369914] PCI host bridge to bus 0000:00561builder # [ 0.370566] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]562server # [ 0.389641] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]563builder # [ 0.371561] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]564server # [ 0.390505] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]565builder # [ 0.372562] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]566server # [ 0.391351] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]567server # [ 0.392484] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]568builder # [ 0.373562] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]569builder # [ 0.374561] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]570server # [ 0.393955] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint571builder # [ 0.375562] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]572builder # [ 0.376562] pci_bus 0000:00: root bus resource [bus 00-ff]573builder # [ 0.377619] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint574server # [ 0.395637] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]575server # [ 0.396544] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]576builder # [ 0.379041] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint577server # [ 0.397399] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]578server # [ 0.399058] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint579server # [ 0.401605] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]580server # [ 0.403323] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]581builder # [ 0.381648] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]582builder # [ 0.382573] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]583server # [ 0.403619] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]584builder # [ 0.383577] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]585server # [ 0.405184] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint586builder # [ 0.384879] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]587server # [ 0.406605] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]588builder # [ 0.386323] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint589server # [ 0.407491] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]590server # [ 0.408330] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]591builder # [ 0.389603] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]592server # [ 0.408603] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]593builder # [ 0.390509] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]594server # [ 0.410168] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint595builder # [ 0.391380] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]596builder # [ 0.392411] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]597server # [ 0.411609] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]598server # [ 0.412619] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]599builder # [ 0.393954] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint600server # [ 0.414196] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint601builder # [ 0.396605] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]602builder # [ 0.397568] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]603server # [ 0.415638] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]604builder # [ 0.398567] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]605server # [ 0.416619] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]606builder # [ 0.400138] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint607server # [ 0.418154] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint608server # [ 0.419640] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]609server # [ 0.420619] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]610builder # [ 0.402833] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]611builder # [ 0.404252] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]612server # [ 0.422125] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint613builder # [ 0.404584] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]614builder # [ 0.406113] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint615server # [ 0.423639] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]616server # [ 0.424620] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]617builder # [ 0.407569] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]618server # [ 0.426130] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint619builder # [ 0.408449] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]620builder # [ 0.409344] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]621server # [ 0.427616] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]622builder # [ 0.410450] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]623server # [ 0.428537] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]624server # [ 0.429422] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]625builder # [ 0.412130] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint626server # [ 0.431062] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint627builder # [ 0.413574] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]628builder # [ 0.414584] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]629server # [ 0.433604] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]630builder # [ 0.416157] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint631server # [ 0.434502] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]632server # [ 0.435406] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]633builder # [ 0.417600] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]634server # [ 0.437055] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint635builder # [ 0.418584] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]636server # [ 0.438210] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]637builder # [ 0.420179] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint638server # [ 0.438819] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint639builder # [ 0.421574] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]640builder # [ 0.422583] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]641server # [ 0.440247] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]642server # [ 0.440844] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint643builder # [ 0.424170] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint644server # [ 0.442248] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]645builder # [ 0.425603] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]646builder # [ 0.426584] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]647builder # [ 0.428128] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint648builder # [ 0.429618] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]649builder # [ 0.430552] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]650builder # [ 0.431363] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]651builder # [ 0.433074] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint652server # [ 0.442870] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint653builder # [ 0.435569] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]654server # [ 0.445238] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]655builder # [ 0.436404] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]656server # [ 0.445837] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint657builder # [ 0.437326] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]658server # [ 0.446913] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO659builder # [ 0.439022] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint660server # [ 0.447871] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint661builder # [ 0.440242] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]662server # [ 0.449608] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]663builder # [ 0.440762] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint664server # [ 0.450503] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]665builder # [ 0.442248] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]666server # [ 0.451707] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint667builder # [ 0.442757] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint668server # [ 0.453261] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]669builder # [ 0.444239] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]670server # [ 0.457291] ACPI: PCI: Interrupt link LNKA configured for IRQ 10671server # [ 0.457709] ACPI: PCI: Interrupt link LNKB configured for IRQ 10672server # [ 0.458696] ACPI: PCI: Interrupt link LNKC configured for IRQ 11673server # [ 0.459706] ACPI: PCI: Interrupt link LNKD configured for IRQ 11674server # [ 0.460694] ACPI: PCI: Interrupt link LNKE configured for IRQ 10675server # [ 0.461694] ACPI: PCI: Interrupt link LNKF configured for IRQ 10676server # [ 0.462700] ACPI: PCI: Interrupt link LNKG configured for IRQ 11677server # [ 0.463697] ACPI: PCI: Interrupt link LNKH configured for IRQ 11678server # [ 0.464635] ACPI: PCI: Interrupt link GSIA configured for IRQ 16679builder # [ 0.444800] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint680server # [ 0.465613] ACPI: PCI: Interrupt link GSIB configured for IRQ 17681builder # [ 0.447173] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]682server # [ 0.466613] ACPI: PCI: Interrupt link GSIC configured for IRQ 18683server # [ 0.467609] ACPI: PCI: Interrupt link GSID configured for IRQ 19684builder # [ 0.447840] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint685server # [ 0.468566] ACPI: PCI: Interrupt link GSIE configured for IRQ 20686server # [ 0.469368] ACPI: PCI: Interrupt link GSIF configured for IRQ 21687builder # [ 0.448850] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO688server # [ 0.470362] ACPI: PCI: Interrupt link GSIG configured for IRQ 22689builder # [ 0.449812] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint690server # [ 0.470612] ACPI: PCI: Interrupt link GSIH configured for IRQ 23691builder # [ 0.451579] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]692server # [ 0.472671] iommu: Default domain type: Translated693builder # [ 0.452442] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]694server # [ 0.473468] iommu: DMA domain TLB invalidation policy: lazy mode695server # [ 0.474635] ACPI: bus type USB registered696builder # [ 0.453652] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint697server # [ 0.475422] usbcore: registered new interface driver usbfs698server # [ 0.476333] usbcore: registered new interface driver hub699builder # [ 0.455204] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]700server # [ 0.476605] usbcore: registered new device driver usb701server # [ 0.478379] NetLabel: Initializing702server # [ 0.478604] NetLabel: domain hash size = 128703server # [ 0.479350] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO704builder # [ 0.459256] ACPI: PCI: Interrupt link LNKA configured for IRQ 10705server # [ 0.479637] NetLabel: unlabeled traffic allowed by default706server # [ 0.480565] PCI: Using ACPI for IRQ routing707builder # [ 0.459681] ACPI: PCI: Interrupt link LNKB configured for IRQ 10708builder # [ 0.460681] ACPI: PCI: Interrupt link LNKC configured for IRQ 11709builder # [ 0.461668] ACPI: PCI: Interrupt link LNKD configured for IRQ 11710builder # [ 0.462659] ACPI: PCI: Interrupt link LNKE configured for IRQ 10711builder # [ 0.463657] ACPI: PCI: Interrupt link LNKF configured for IRQ 10712builder # [ 0.464662] ACPI: PCI: Interrupt link LNKG configured for IRQ 11713builder # [ 0.465660] ACPI: PCI: Interrupt link LNKH configured for IRQ 11714builder # [ 0.466602] ACPI: PCI: Interrupt link GSIA configured for IRQ 16715builder # [ 0.467579] ACPI: PCI: Interrupt link GSIB configured for IRQ 17716builder # [ 0.468577] ACPI: PCI: Interrupt link GSIC configured for IRQ 18717builder # [ 0.469574] ACPI: PCI: Interrupt link GSID configured for IRQ 19718builder # [ 0.470574] ACPI: PCI: Interrupt link GSIE configured for IRQ 20719builder # [ 0.471573] ACPI: PCI: Interrupt link GSIF configured for IRQ 21720builder # [ 0.472576] ACPI: PCI: Interrupt link GSIG configured for IRQ 22721builder # [ 0.473575] ACPI: PCI: Interrupt link GSIH configured for IRQ 23722builder # [ 0.475634] iommu: Default domain type: Translated723builder # [ 0.476496] iommu: DMA domain TLB invalidation policy: lazy mode724builder # [ 0.477639] ACPI: bus type USB registered725builder # [ 0.478444] usbcore: registered new interface driver usbfs726builder # [ 0.479363] usbcore: registered new interface driver hub727builder # [ 0.480290] usbcore: registered new device driver usb728builder # [ 0.481509] NetLabel: Initializing729builder # [ 0.482016] NetLabel: domain hash size = 128730builder # [ 0.482562] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO731builder # [ 0.483577] NetLabel: unlabeled traffic allowed by default732builder # [ 0.484535] PCI: Using ACPI for IRQ routing733server # [ 0.524675] pci 0000:00:01.0: vgaarb: setting as boot VGA device734server # [ 0.525593] pci 0000:00:01.0: vgaarb: bridge control possible735server # [ 0.525593] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none736server # [ 0.525601] vgaarb: loaded737server # [ 0.526349] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0738server # [ 0.526599] hpet0: 3 comparators, 64-bit 100.000000 MHz counter739server # [ 0.530681] clocksource: Switched to clocksource kvm-clock740server # [ 0.534276] VFS: Disk quotas dquot_6.6.0741server # [ 0.535012] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)742server # [ 0.536451] pnp: PnP ACPI init743server # [ 0.537228] ACPI: IRQ 4 override to edge(!), high(!)744server # [ 0.538236] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved745server # [ 0.539838] pnp: PnP ACPI: found 5 devices746server # [ 0.547539] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns747builder # [ 0.528739] pci 0000:00:01.0: vgaarb: setting as boot VGA device748server # [ 0.549052] clocksource: Switched to clocksource acpi_pm749builder # [ 0.529558] pci 0000:00:01.0: vgaarb: bridge control possible750server # [ 0.550056] NET: Registered PF_INET protocol family751builder # [ 0.529558] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none752server # [ 0.551116] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)753builder # [ 0.529566] vgaarb: loaded754builder # [ 0.530296] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0755builder # [ 0.530564] hpet0: 3 comparators, 64-bit 100.000000 MHz counter756builder # [ 0.534639] clocksource: Switched to clocksource kvm-clock757builder # [ 0.538215] VFS: Disk quotas dquot_6.6.0758builder # [ 0.539012] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)759builder # [ 0.540487] pnp: PnP ACPI init760builder # [ 0.541325] ACPI: IRQ 4 override to edge(!), high(!)761builder # [ 0.542333] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved762builder # [ 0.543973] pnp: PnP ACPI: found 5 devices763server # [ 0.567957] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)764server # [ 0.569558] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)765server # [ 0.570919] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)766server # [ 0.572294] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)767server # [ 0.573588] TCP: Hash tables configured (established 8192 bind 8192)768server # [ 0.574743] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)769builder # [ 0.551596] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns770server # [ 0.576057] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)771builder # [ 0.553113] clocksource: Switched to clocksource acpi_pm772builder # [ 0.554111] NET: Registered PF_INET protocol family773server # [ 0.577222] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)774server # [ 0.578433] NET: Registered PF_UNIX/PF_LOCAL protocol family775builder # [ 0.555163] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)776server # [ 0.579366] NET: Registered PF_XDP protocol family777server # [ 0.580256] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]778server # [ 0.581288] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]779server # [ 0.582317] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]780server # [ 0.583499] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]781server # [ 0.584660] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]782server # [ 0.585821] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]783server # [ 0.587606] ACPI: \_SB_.GSIA: Enabled at IRQ 16784server # [ 0.589868] ACPI: \_SB_.GSIB: Enabled at IRQ 17785server # [ 0.592176] ACPI: \_SB_.GSIC: Enabled at IRQ 18786server # [ 0.594268] ACPI: \_SB_.GSID: Enabled at IRQ 19787server # [ 0.596015] PCI: CLS 0 bytes, default 64788builder # [ 0.572133] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)789builder # [ 0.573690] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)790server # [ 0.596995] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns791builder # [ 0.575099] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)792server # [ 0.598787] Trying to unpack rootfs image as initramfs...793builder # [ 0.576436] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)794builder # [ 0.577747] TCP: Hash tables configured (established 8192 bind 8192)795builder # [ 0.578902] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)796builder # [ 0.580225] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)797builder # [ 0.581399] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)798builder # [ 0.582654] NET: Registered PF_UNIX/PF_LOCAL protocol family799builder # [ 0.583646] NET: Registered PF_XDP protocol family800builder # [ 0.584521] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]801builder # [ 0.585558] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]802builder # [ 0.586587] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]803builder # [ 0.587766] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]804builder # [ 0.588936] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]805builder # [ 0.590107] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]806builder # [ 0.591937] ACPI: \_SB_.GSIA: Enabled at IRQ 16807builder # [ 0.594323] ACPI: \_SB_.GSIB: Enabled at IRQ 17808builder # [ 0.596268] ACPI: \_SB_.GSIC: Enabled at IRQ 18809builder # [ 0.598304] ACPI: \_SB_.GSID: Enabled at IRQ 19810builder # [ 0.600047] PCI: CLS 0 bytes, default 64811builder # [ 0.600982] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns812builder # [ 0.602830] Trying to unpack rootfs image as initramfs...813server # [ 0.644455] Initialise system trusted keyrings814server # [ 0.647455] workingset: timestamp_bits=40 max_order=18 bucket_order=0815server # [ 0.668590] Key type asymmetric registered816server # [ 0.669313] Asymmetric key parser 'x509' registered817server # [ 0.670185] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)818builder # [ 0.648164] Initialise system trusted keyrings819builder # [ 0.649185] workingset: timestamp_bits=40 max_order=18 bucket_order=0820server # [ 0.675545] io scheduler mq-deadline registered821server # [ 0.676334] io scheduler kyber registered822server # [ 0.679050] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled823server # [ 0.682426] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A824server # [ 0.686044] Linux agpgart interface v0.103825server # [ 0.686874] ACPI: bus type drm_connector registered826server # [ 0.689863] usbcore: registered new interface driver usbserial_generic827server # [ 0.691008] usbserial: USB Serial support registered for generic828builder # [ 0.670708] Key type asymmetric registered829server # [ 0.694437] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.830server # [ 0.694437] Please enable it if your BIOS has the CPPC option.831server # [ 0.696809] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled832builder # [ 0.674755] Asymmetric key parser 'x509' registered833builder # [ 0.675626] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)834server # [ 0.701548] drop_monitor: Initializing network drop monitor service835builder # [ 0.678877] io scheduler mq-deadline registered836server # [ 0.702763] NET: Registered PF_INET6 protocol family837builder # [ 0.679635] io scheduler kyber registered838server # [ 0.705852] Segment Routing with IPv6839builder # [ 0.683389] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled840server # [ 0.708427] In-situ OAM (IOAM) with IPv6841server # [ 0.709491] IPI shorthand broadcast: enabled842builder # [ 0.686758] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A843builder # [ 0.690035] Linux agpgart interface v0.103844builder # [ 0.690863] ACPI: bus type drm_connector registered845builder # [ 0.694155] usbcore: registered new interface driver usbserial_generic846server # [ 0.717811] sched_clock: Marking stable (574014940, 143387513)->(794238679, -76836226)847builder # [ 0.695292] usbserial: USB Serial support registered for generic848builder # [ 0.697754] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.849server # [ 0.722511] registered taskstats version 1850builder # [ 0.697754] Please enable it if your BIOS has the CPPC option.851server # [ 0.723457] Loading compiled-in X.509 certificates852builder # [ 0.700060] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled853builder # [ 0.704872] drop_monitor: Initializing network drop monitor service854builder # [ 0.706100] NET: Registered PF_INET6 protocol family855builder # [ 0.709241] Segment Routing with IPv6856builder # [ 0.711770] In-situ OAM (IOAM) with IPv6857builder # [ 0.712855] IPI shorthand broadcast: enabled858server # [ 0.741414] Demotion targets for Node 0: null859server # [ 0.742311] Key type .fscrypt registered860builder # [ 0.721096] sched_clock: Marking stable (579013481, 141720096)->(792191919, -71458342)861server # [ 0.745407] Key type fscrypt-provisioning registered862server # [ 0.746334] ima: No TPM chip found, activating TPM-bypass!863server # [ 0.747267] ima: Allocated hash algorithm: sha1864server # [ 0.748089] ima: No architecture policies found865builder # [ 0.725972] registered taskstats version 1866builder # [ 0.726978] Loading compiled-in X.509 certificates867server # [ 0.750421] PM: Magic number: 10:397:932868server # [ 0.752000] RAS: Correctable Errors collector initialized.869server # [ 0.762093] clk: Disabling unused clocks870server # [ 0.764416] PM: genpd: Disabling unused power domains871builder # [ 0.745749] Demotion targets for Node 0: null872builder # [ 0.746678] Key type .fscrypt registered873builder # [ 0.749740] Key type fscrypt-provisioning registered874builder # [ 0.750692] ima: No TPM chip found, activating TPM-bypass!875builder # [ 0.751629] ima: Allocated hash algorithm: sha1876builder # [ 0.752538] ima: No architecture policies found877builder # [ 0.754754] PM: Magic number: 10:397:932878builder # [ 0.756384] RAS: Correctable Errors collector initialized.879builder # [ 0.766399] clk: Disabling unused clocks880builder # [ 0.768749] PM: genpd: Disabling unused power domains881server # [ 0.923336] Freeing initrd memory: 29072K882server # [ 0.926573] Freeing unused decrypted memory: 2028K883server # [ 0.929177] Freeing unused kernel image (initmem) memory: 3644K884server # [ 0.930319] Write protecting the kernel read-only data: 32768k885server # [ 0.932164] Freeing unused kernel image (text/rodata gap) memory: 1200K886server # [ 0.933741] Freeing unused kernel image (rodata/data gap) memory: 736K887builder # [ 0.927187] Freeing initrd memory: 29072K888builder # [ 0.930404] Freeing unused decrypted memory: 2028K889builder # [ 0.933047] Freeing unused kernel image (initmem) memory: 3644K890builder # [ 0.934162] Write protecting the kernel read-only data: 32768k891builder # [ 0.936063] Freeing unused kernel image (text/rodata gap) memory: 1200K892builder # [ 0.937652] Freeing unused kernel image (rodata/data gap) memory: 736K893server # [ 0.984585] x86/mm: Checked W+X mappings: passed, no W+X pages found.894server # [ 0.985698] Run /init as init process895server # [ 0.996434] systemd[1]: Inserted module 'autofs4'896builder # [ 0.988850] x86/mm: Checked W+X mappings: passed, no W+X pages found.897builder # [ 0.989992] Run /init as init process898server # [ 1.016813] fuse: init (API version 7.45)899server # [ 1.024236] ACPI: \_SB_.GSIG: Enabled at IRQ 22900builder # [ 1.001194] systemd[1]: Inserted module 'autofs4'901server # [ 1.026599] ACPI: \_SB_.GSIH: Enabled at IRQ 23902server # [ 1.029655] ACPI: \_SB_.GSIE: Enabled at IRQ 20903server # [ 1.031633] ACPI: \_SB_.GSIF: Enabled at IRQ 21904server # [ 1.036852] virtiofs virtio5: discovered new tag: nix-store905server # [ 1.038480] virtiofs virtio5: virtio_fs_setup_dax: No cache capability906builder # [ 1.021505] fuse: init (API version 7.45)907server # [ 1.047026] virtiofs virtio6: discovered new tag: shared908server # [ 1.048698] virtiofs virtio6: virtio_fs_setup_dax: No cache capability909builder # [ 1.028902] ACPI: \_SB_.GSIG: Enabled at IRQ 22910server # [ 1.052157] virtiofs virtio7: discovered new tag: xchg911server # [ 1.053677] virtiofs virtio7: virtio_fs_setup_dax: No cache capability912builder # [ 1.031334] ACPI: \_SB_.GSIH: Enabled at IRQ 23913builder # [ 1.034423] ACPI: \_SB_.GSIE: Enabled at IRQ 20914builder # [ 1.036498] ACPI: \_SB_.GSIF: Enabled at IRQ 21915builder # [ 1.041619] virtiofs virtio5: discovered new tag: nix-store916builder # [ 1.043187] virtiofs virtio5: virtio_fs_setup_dax: No cache capability917builder # [ 1.050152] virtiofs virtio6: discovered new tag: shared918builder # [ 1.051891] virtiofs virtio6: virtio_fs_setup_dax: No cache capability919server # [ 1.075494] systemd[1]: Successfully made /usr/ read-only.920builder # [ 1.055122] virtiofs virtio7: discovered new tag: xchg921builder # [ 1.056549] virtiofs virtio7: virtio_fs_setup_dax: No cache capability922builder # [ 1.075519] systemd[1]: Successfully made /usr/ read-only.923server # [ 1.412055] 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.424261] systemd[1]: Detected virtualization kvm.925server # [ 1.426489] systemd[1]: Detected architecture x86-64.926server # [ 1.428645] systemd[1]: Running in initrd.927server # [ 1.431147] systemd[1]: Initializing machine ID from random generator.928server # [ 1.434063] systemd[1]: Hostname set to <server>.929builder # [ 1.412387] 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.424614] systemd[1]: Detected virtualization kvm.931builder # [ 1.426850] systemd[1]: Detected architecture x86-64.932builder # [ 1.428992] systemd[1]: Running in initrd.933builder # [ 1.431414] systemd[1]: Initializing machine ID from random generator.934builder # [ 1.434276] systemd[1]: Hostname set to <builder>.935builder # [ 1.522542] systemd[1]: bpf-restrict-fs: LSM BPF program attached936builder # [ 1.560161] systemd[1]: Queued start job for default target Initrd Default Target.937builder # [ 1.563988] systemd[1]: Created slice Slice /system/modprobe.938builder # [ 1.565213] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.939builder # [ 1.566650] systemd[1]: Expecting device /dev/disk/by-label/nixos...940builder # [ 1.567849] systemd[1]: Reached target Path Units.941builder # [ 1.568789] systemd[1]: Reached target Slice Units.942builder # [ 1.569659] systemd[1]: Reached target Swaps.943builder # [ 1.570478] systemd[1]: Reached target Timer Units.944builder # [ 1.571470] systemd[1]: Listening on D-Bus System Message Bus Socket.945builder # [ 1.572711] systemd[1]: Listening on Journal Socket (/dev/log).946builder # [ 1.573943] systemd[1]: Listening on Journal Sockets.947builder # [ 1.574959] systemd[1]: Listening on udev Control Socket.948builder # [ 1.576017] systemd[1]: Listening on udev Kernel Socket.949builder # [ 1.577022] systemd[1]: Reached target Socket Units.950builder # [ 1.578799] systemd[1]: Starting Create List of Static Device Nodes...951builder # [ 1.582535] systemd[1]: Starting Load Kernel Module configfs...952builder # [ 1.589365] systemd[1]: Starting Journal Service...953builder # [ 1.616957] systemd[1]: Starting Load Kernel Modules...954builder # [ 1.620831] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os955builder # [ 1.627527] systemd[1]: Starting Coldplug All udev Devices...956server # [ 1.654824] systemd[1]: bpf-restrict-fs: LSM BPF program attached957builder # [ 1.639277] systemd-journald[66]: Collecting audit messages is disabled.958builder # [ 1.643080] systemd[1]: Finished Create List of Static Device Nodes.959builder # [ 1.646365] systemd[1]: modprobe@configfs.service: Deactivated successfully.960builder # [ 1.655124] systemd[1]: Finished Load Kernel Module configfs.961builder # [ 1.660166] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config962builder # [ 1.671816] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...963builder # [ 1.677532] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.964server # [ 1.708339] systemd[1]: Queued start job for default target Initrd Default Target.965server # [ 1.712708] systemd[1]: Created slice Slice /system/modprobe.966server # [ 1.713958] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.967builder # [ 1.690918] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev968server # [ 1.715459] systemd[1]: Expecting device /dev/disk/by-label/nixos...969server # [ 1.716608] systemd[1]: Reached target Path Units.970server # [ 1.717554] systemd[1]: Reached target Slice Units.971server # [ 1.718446] systemd[1]: Reached target Swaps.972server # [ 1.719229] systemd[1]: Reached target Timer Units.973server # [ 1.720217] systemd[1]: Listening on D-Bus System Message Bus Socket.974server # [ 1.721442] systemd[1]: Listening on Journal Socket (/dev/log).975server # [ 1.722632] systemd[1]: Listening on Journal Sockets.976server # [ 1.723670] systemd[1]: Listening on udev Control Socket.977server # [ 1.724714] systemd[1]: Listening on udev Kernel Socket.978server # [ 1.725719] systemd[1]: Reached target Socket Units.979server # [ 1.727604] systemd[1]: Starting Create List of Static Device Nodes...980server # [ 1.731478] systemd[1]: Starting Load Kernel Module configfs...981builder # [ 1.712291] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.982builder # [ 1.719921] systemd[1]: Starting Create Static Device Nodes in /dev...983server # [ 1.741618] systemd[1]: Starting Journal Service...984builder # [ 1.733840] systemd[1]: Finished Load Kernel Modules.985builder # [ 1.741913] systemd[1]: Starting Apply Kernel Variables...986server # [ 1.767593] systemd[1]: Starting Load Kernel Modules...987server # [ 1.771505] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os988builder # [ 1.756849] systemd[1]: Finished Create Static Device Nodes in /dev.989server # [ 1.781629] systemd[1]: Starting Coldplug All udev Devices...990builder # [ 1.620275] systemd-modules-load[68]: Inserted module 'dm_mod'991builder # [ 1.622274] systemd-modules-load[68]: Inserted module 'virtio_balloon'992builder # [ 1.765037] systemd[1]: Started Journal Service.993server # [ 1.789927] systemd-journald[66]: Collecting audit messages is disabled.994builder # [ 1.625749] systemd-modules-load[68]: Inserted module 'virtio_gpu'995server # [ 1.795481] systemd[1]: Finished Create List of Static Device Nodes.996server # [ 1.799218] systemd[1]: modprobe@configfs.service: Deactivated successfully.997builder # [ 1.634760] systemd[1]: Reached target Preparation for Local File Systems.998builder # [ 1.637733] systemd[1]: Reached target Local File Systems.999builder # [ 1.638809] systemd[1]: Starting Create System Files and Directories...1000server # [ 1.806819] systemd[1]: Finished Load Kernel Module configfs.1001builder # [ 1.644187] systemd[1]: Starting Rule-based Manager for Device Events and Files...1002server # [ 1.812285] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config1003builder # [ 1.650508] systemd[1]: Finished Apply Kernel Variables.1004server # [ 1.825482] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1005server # [ 1.831160] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1006builder # [ 1.668920] systemd[1]: Finished Create System Files and Directories.1007server # [ 1.845623] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1008builder # [ 1.696359] systemd-udevd[80]: Using default interface naming scheme 'v261'.1009server # [ 1.866991] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1010server # [ 1.876608] systemd[1]: Starting Create Static Device Nodes in /dev...1011builder # [ 1.718567] systemd[1]: Started Rule-based Manager for Device Events and Files.1012server # [ 1.888493] systemd[1]: Finished Load Kernel Modules.1013server # [ 1.897614] systemd[1]: Starting Apply Kernel Variables...1014server # [ 1.913509] systemd[1]: Started Journal Service.1015server # [ 1.768978] systemd-modules-load[68]: Inserted module 'dm_mod'1016server # [ 1.771982] systemd-modules-load[68]: Inserted module 'virtio_balloon'1017server # [ 1.775622] systemd-modules-load[68]: Inserted module 'virtio_gpu'1018server # [ 1.781297] systemd[1]: Finished Create Static Device Nodes in /dev.1019server # [ 1.789140] systemd[1]: Reached target Preparation for Local File Systems.1020server # [ 1.790175] systemd[1]: Reached target Local File Systems.1021server # [ 1.794070] systemd[1]: Starting Create System Files and Directories...1022builder # [ 1.773093] systemd[1]: Finished Coldplug All udev Devices.1023builder # [ 1.773912] systemd[1]: Reached target System Initialization.1024builder # [ 1.774751] systemd[1]: Reached target Basic System.1025server # [ 1.797579] systemd[1]: Starting Rule-based Manager for Device Events and Files...1026server # [ 1.804598] systemd[1]: Finished Apply Kernel Variables.1027server # [ 1.837525] systemd[1]: Finished Create System Files and Directories.1028server # [ 1.857332] systemd-udevd[80]: Using default interface naming scheme 'v261'.1029server # [ 1.880349] systemd[1]: Started Rule-based Manager for Device Events and Files.1030server # [ 1.938149] systemd[1]: Finished Coldplug All udev Devices.1031server # [ 1.940873] systemd[1]: Reached target System Initialization.1032server # [ 1.941727] systemd[1]: Reached target Basic System.1033builder # [ 2.077896] virtio_blk virtio2: 1/0/0 default/read/poll queues1034builder # [ 2.079482] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1035builder # [ 2.102179] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121036builder # [ 2.114370] serio: i8042 KBD port at 0x60,0x64 irq 11037builder # [ 2.122121] serio: i8042 AUX port at 0x60,0x64 irq 121038builder # [ 2.131677] ehci-pci 0000:00:1d.7: EHCI Host Controller1039builder # [ 2.132454] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11040builder # [ 2.134208] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001041builder # [ 2.142756] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001042builder # [ 2.143607] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181043builder # [ 2.145141] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11044builder # [ 2.146584] usb usb1: Product: EHCI Host Controller1045builder # [ 2.148906] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd1046builder # [ 2.149889] usb usb1: SerialNumber: 0000:00:1d.71047builder # [ 2.151586] hub 1-0:1.0: USB hub found1048builder # [ 2.152524] hub 1-0:1.0: 6 ports detected1049builder # [ 2.154684] uhci_hcd 0000:00:1d.0: UHCI Host Controller1050builder # [ 2.155654] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21051builder # [ 2.157361] uhci_hcd 0000:00:1d.0: detected 2 ports1052builder # [ 2.166820] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801053builder # [ 2.172191] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181054builder # [ 2.173350] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11055builder # [ 2.188207] usb usb2: Product: UHCI Host Controller1056builder # [ 2.197548] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd1057builder # [ 2.202048] usb usb2: SerialNumber: 0000:00:1d.01058builder # [ 2.203677] SCSI subsystem initialized1059builder # [ 2.066527] systemd[1]: Starting Virtual Console Setup...1060server # [ 2.233313] virtio_blk virtio2: 1/0/0 default/read/poll queues1061builder # [ 2.215828] hub 2-0:1.0: USB hub found1062builder # [ 2.220094] hub 2-0:1.0: 2 ports detected1063builder # [ 2.085126] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1064server # [ 2.253260] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1065builder # [ 2.101843] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1066builder # [ 2.103405] systemd[1]: Finished Virtual Console Setup.1067builder # [ 2.104981] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1068server # [ 2.270900] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121069builder # [ 2.108361] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1070server # [ 2.282442] serio: i8042 KBD port at 0x60,0x64 irq 11071server # [ 2.283104] serio: i8042 AUX port at 0x60,0x64 irq 121072builder # [ 2.259460] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01073builder # [ 2.260908] uhci_hcd 0000:00:1d.1: UHCI Host Controller1074builder # [ 2.261650] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31075builder # [ 2.272768] uhci_hcd 0000:00:1d.1: detected 2 ports1076builder # [ 2.273520] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01077server # [ 2.303076] ehci-pci 0000:00:1d.7: EHCI Host Controller1078server # [ 2.304012] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11079builder # [ 2.281504] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181080server # [ 2.305998] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001081builder # [ 2.282798] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11082server # [ 2.312429] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001083builder # [ 2.148859] systemd[1]: Found device /dev/disk/by-label/nixos.1084server # [ 2.314203] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181085builder # [ 2.149879] systemd[1]: Reached target Initrd Root Device.1086builder # [ 2.152128] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1087server # [ 2.317409] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11088server # [ 2.318369] usb usb1: Product: EHCI Host Controller1089builder # [ 2.295761] usb usb3: Product: UHCI Host Controller1090builder # [ 2.296441] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd1091server # [ 2.320412] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd1092server # [ 2.321130] usb usb1: SerialNumber: 0000:00:1d.71093server # [ 2.323656] hub 1-0:1.0: USB hub found1094builder # [ 2.301083] usb usb3: SerialNumber: 0000:00:1d.11095server # [ 2.325249] hub 1-0:1.0: 6 ports detected1096builder # [ 2.302201] hub 3-0:1.0: USB hub found1097builder # [ 2.303190] hub 3-0:1.0: 2 ports detected1098server # [ 2.331663] uhci_hcd 0000:00:1d.0: UHCI Host Controller1099builder # [ 2.312708] uhci_hcd 0000:00:1d.2: UHCI Host Controller1100builder # [ 2.313471] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41101builder # [ 2.318850] uhci_hcd 0000:00:1d.2: detected 2 ports1102builder # [ 2.319606] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01103server # [ 2.344109] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21104server # [ 2.346011] uhci_hcd 0000:00:1d.0: detected 2 ports1105builder # [ 2.323049] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181106builder # [ 2.325845] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11107builder # [ 2.328766] usb usb4: Product: UHCI Host Controller1108builder # [ 2.329494] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd1109server # [ 2.353574] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801110builder # [ 2.331634] usb usb4: SerialNumber: 0000:00:1d.21111builder # [ 2.333011] hub 4-0:1.0: USB hub found1112builder # [ 2.340278] hub 4-0:1.0: 2 ports detected1113server # [ 2.363239] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181114builder # [ 2.200223] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks1115builder # [ 2.205529] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1116server # [ 2.372672] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11117server # [ 2.237074] systemd[1]: Starting Virtual Console Setup...1118server # [ 2.240345] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1119server # [ 2.242363] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1120server # [ 2.391720] usb usb2: Product: UHCI Host Controller1121server # [ 2.395133] SCSI subsystem initialized1122builder # [ 2.376052] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1123builder # [ 2.378224] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1124builder # [ 2.379681] ahci 0000:00:1f.2: flags: 64bit ncq only1125server # [ 2.260056] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.1126builder # [ 2.384175] scsi host0: ahci1127server # [ 2.408415] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd1128server # [ 2.409152] usb usb2: SerialNumber: 0000:00:1d.01129builder # [ 2.387752] usb 1-1: new high-speed USB device number 2 using ehci-pci1130builder # [ 2.390775] scsi host1: ahci1131builder # [ 2.392861] scsi host2: ahci1132builder # [ 2.395184] scsi host3: ahci1133builder # [ 2.398505] scsi host4: ahci1134server # [ 2.278465] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1135builder # [ 2.400313] scsi host5: ahci1136builder # [ 2.401433] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11137builder # [ 2.403161] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11138builder # [ 2.404329] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11139server # [ 2.284876] systemd[1]: Finished Virtual Console Setup.1140builder # [ 2.405568] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11141server # [ 2.430534] hub 2-0:1.0: USB hub found1142builder # [ 2.406776] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11143builder # [ 2.407953] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11144server # [ 2.434988] hub 2-0:1.0: 2 ports detected1145server # [ 2.440705] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01146server # [ 2.447329] uhci_hcd 0000:00:1d.1: UHCI Host Controller1147server # [ 2.448271] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31148server # [ 2.315803] systemd[1]: Found device /dev/disk/by-label/nixos.1149server # [ 2.317449] systemd[1]: Reached target Initrd Root Device.1150server # [ 2.320369] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1151server # [ 2.469570] uhci_hcd 0000:00:1d.1: detected 2 ports1152server # [ 2.470343] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01153server # [ 2.475618] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181154server # [ 2.482412] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11155server # [ 2.484208] usb usb3: Product: UHCI Host Controller1156server # [ 2.488149] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd1157server # [ 2.489238] usb usb3: SerialNumber: 0000:00:1d.11158server # [ 2.490459] hub 3-0:1.0: USB hub found1159server # [ 2.492446] hub 3-0:1.0: 2 ports detected1160server # [ 2.497857] uhci_hcd 0000:00:1d.2: UHCI Host Controller1161server # [ 2.499536] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41162server # [ 2.504563] uhci_hcd 0000:00:1d.2: detected 2 ports1163server # [ 2.505357] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01164server # [ 2.363796] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1165server # [ 2.513464] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181166server # [ 2.514606] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11167server # [ 2.373352] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1168server # [ 2.519176] usb usb4: Product: UHCI Host Controller1169server # [ 2.520166] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd1170server # [ 2.522505] usb usb4: SerialNumber: 0000:00:1d.21171server # [ 2.527298] hub 4-0:1.0: USB hub found1172server # [ 2.529407] hub 4-0:1.0: 2 ports detected1173builder # [ 2.519233] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001174builder # [ 2.522119] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101175builder # [ 2.525349] usb 1-1: Product: QEMU USB Tablet1176builder # [ 2.527127] usb 1-1: Manufacturer: QEMU1177builder # [ 2.529104] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11178server # [ 2.554758] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1179server # [ 2.564531] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1180server # [ 2.565354] ahci 0000:00:1f.2: flags: 64bit ncq only1181server # [ 2.566450] usb 1-1: new high-speed USB device number 2 using ehci-pci1182server # [ 2.570481] scsi host0: ahci1183server # [ 2.572027] scsi host1: ahci1184server # [ 2.574001] scsi host2: ahci1185server # [ 2.575792] scsi host3: ahci1186server # [ 2.577273] scsi host4: ahci1187server # [ 2.578924] scsi host5: ahci1188server # [ 2.579986] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11189server # [ 2.581633] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11190server # [ 2.582757] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11191builder # [ 2.560841] hid: raw HID events driver (C) Jiri Kosina1192server # [ 2.583987] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11193server # [ 2.585166] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11194server # [ 2.586303] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11195builder # [ 2.496690] systemd[1]: Mounting /sysroot...1196server # [ 2.695907] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001197server # [ 2.698829] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101198server # [ 2.702151] usb 1-1: Product: QEMU USB Tablet1199server # [ 2.703955] usb 1-1: Manufacturer: QEMU1200server # [ 2.705653] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11201server # [ 2.738229] hid: raw HID events driver (C) Jiri Kosina1202builder # [ 2.716932] ata1: SATA link down (SStatus 0 SControl 300)1203builder # [ 2.725131] ata2: SATA link down (SStatus 0 SControl 300)1204builder # [ 2.727267] ata5: SATA link down (SStatus 0 SControl 300)1205builder # [ 2.729866] ata6: SATA link down (SStatus 0 SControl 300)1206builder # [ 2.732245] ata4: SATA link down (SStatus 0 SControl 300)1207builder # [ 2.734558] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1208builder # [ 2.737007] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001209builder # [ 2.739062] ata3.00: applying bridge limits1210builder # [ 2.740877] ata3.00: configured for UDMA/1001211builder # [ 2.753387] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51212server # [ 2.647952] systemd[1]: Mounting /sysroot...1213builder # [ 2.827853] usbcore: registered new interface driver usbhid1214builder # [ 2.828616] usbhid: USB HID core driver1215builder # [ 2.857174] 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/input21216builder # [ 2.858957] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01217builder # [ 2.863035] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1218builder # [ 2.872915] cdrom: Uniform CD-ROM driver Revision: 3.201219server # [ 2.896455] ata1: SATA link down (SStatus 0 SControl 300)1220builder # [ 2.880749] EXT4-fs (vda): mounted filesystem c477f26d-6fc3-4c87-8dca-fda3748a4b99 r/w with ordered data mode. Quota mode: none.1221server # [ 2.904216] ata6: SATA link down (SStatus 0 SControl 300)1222server # [ 2.906562] ata5: SATA link down (SStatus 0 SControl 300)1223builder # [ 2.744946] systemd[1]: Mounted /sysroot.1224server # [ 2.908738] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1225builder # [ 2.747045] systemd[1]: Reached target Initrd Root File System.1226server # [ 2.911068] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001227server # [ 2.913592] ata3.00: applying bridge limits1228builder # [ 2.750121] systemd[1]: Starting Mountpoints Configured in the Real Root...1229server # [ 2.915570] ata4: SATA link down (SStatus 0 SControl 300)1230server # [ 2.917823] ata2: SATA link down (SStatus 0 SControl 300)1231server # [ 2.920100] ata3.00: configured for UDMA/1001232server # [ 2.922574] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51233builder # [ 2.766107] systemd-sysroot-fstab-check[139]: /sysroot should be mounted in the initrd, will request daemon-reload.1234builder # [ 2.768080] systemd[1]: Reload requested from client PID 139 ('systemd-sysroot') (unit initrd-parse-etc.service)...1235builder # [ 2.770128] systemd[1]: Reloading...1236server # [ 2.996628] usbcore: registered new interface driver usbhid1237server # [ 3.004434] usbhid: USB HID core driver1238builder # [ 2.849373] systemd[1]: Reloading finished in 79 ms.1239builder # [ 2.858680] systemd-sysroot-fstab-check[139]: Requesting initrd-fs.target/start/replace...1240builder # [ 2.860766] systemd-sysroot-fstab-check[139]: Requesting swap.target/start/replace...1241server # [ 3.027946] 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/input21242server # [ 3.029752] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01243builder # [ 2.866361] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1244builder # [ 2.868067] systemd[1]: Finished Mountpoints Configured in the Real Root.1245builder # [ 2.868917] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1246server # [ 3.035625] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1247server # [ 3.047579] cdrom: Uniform CD-ROM driver Revision: 3.201248server # [ 3.052128] EXT4-fs (vda): mounted filesystem ddf1e02b-39a3-441b-932b-84856b0859ea r/w with ordered data mode. Quota mode: none.1249server # [ 2.914971] systemd[1]: Mounted /sysroot.1250server # [ 2.918540] systemd[1]: Reached target Initrd Root File System.1251server # [ 2.920992] systemd[1]: Starting Mountpoints Configured in the Real Root...1252server # [ 2.936090] systemd-sysroot-fstab-check[137]: /sysroot should be mounted in the initrd, will request daemon-reload.1253server # [ 2.939764] systemd[1]: Reload requested from client PID 137 ('systemd-sysroot') (unit initrd-parse-etc.service)...1254server # [ 2.942155] systemd[1]: Reloading...1255server # [ 3.028148] systemd[1]: Reloading finished in 86 ms.1256server # [ 3.036093] systemd-sysroot-fstab-check[137]: Requesting initrd-fs.target/start/replace...1257server # [ 3.040315] systemd-sysroot-fstab-check[137]: Requesting swap.target/start/replace...1258server # [ 3.043896] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1259server # [ 3.045314] systemd[1]: Finished Mountpoints Configured in the Real Root.1260server # [ 3.047196] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1261builder # [ 3.498206] systemd[1]: Mounting /sysroot/nix/.ro-store...1262builder # [ 3.504163] systemd[1]: Mounting /sysroot/nix/.rw-store...1263builder # [ 3.511180] systemd[1]: Mounting /sysroot/run...1264builder # [ 3.523187] systemd[1]: Mounting /sysroot/tmp/shared...1265builder # [ 3.535341] systemd[1]: Mounting /sysroot/tmp/xchg...1266builder # [ 3.570702] systemd[1]: Mounted /sysroot/nix/.ro-store.1267builder # [ 3.572571] systemd[1]: Mounted /sysroot/nix/.rw-store.1268builder # [ 3.578602] systemd[1]: Mounted /sysroot/run.1269builder # [ 3.581269] systemd[1]: Mounted /sysroot/tmp/shared.1270builder # [ 3.583278] systemd[1]: Mounted /sysroot/tmp/xchg.1271builder # [ 3.586376] systemd[1]: Starting rw-sysroot-nix-store.service...1272builder # [ 3.596312] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1273builder # [ 3.597466] systemd[1]: Finished rw-sysroot-nix-store.service.1274builder # [ 3.599735] systemd[1]: Mounting /sysroot/nix/store...1275builder # [ 3.619512] systemd[1]: Mounted /sysroot/nix/store.1276builder # [ 3.620653] systemd[1]: Reached target Initrd File Systems.1277builder # [ 3.622274] systemd[1]: Starting Find NixOS closure...1278builder # [ 3.625139] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1279server # [ 3.650201] systemd[1]: Mounting /sysroot/nix/.ro-store...1280server # [ 3.656152] systemd[1]: Mounting /sysroot/nix/.rw-store...1281builder # [ 3.642881] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1282server # [ 3.665174] systemd[1]: Mounting /sysroot/run...1283builder # [ 3.649933] systemd[1]: Finished Find NixOS closure.1284builder # [ 3.651158] systemd[1]: Reached target Initrd Default Target.1285builder # [ 3.652680] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1286server # [ 3.675173] systemd[1]: Mounting /sysroot/tmp/shared...1287server # [ 3.685070] systemd[1]: Mounting /sysroot/tmp/xchg...1288builder # [ 3.665957] systemd[1]: Stopped target Initrd Default Target.1289builder # [ 3.667277] systemd[1]: Stopped target Basic System.1290builder # [ 3.669121] systemd[1]: Stopped target Initrd Root Device.1291builder # [ 3.669928] systemd[1]: Stopped target Path Units.1292builder # [ 3.670656] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1293builder # [ 3.671852] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1294builder # [ 3.672951] systemd[1]: Stopped target Slice Units.1295builder # [ 3.673712] systemd[1]: Stopped target Socket Units.1296builder # [ 3.674629] systemd[1]: Stopped target System Initialization.1297builder # [ 3.675651] systemd[1]: Stopped target Swaps.1298builder # [ 3.676709] systemd[1]: Stopped target Timer Units.1299builder # [ 3.677611] systemd[1]: dbus.socket: Deactivated successfully.1300builder # [ 3.679128] systemd[1]: Closed D-Bus System Message Bus Socket.1301builder # [ 3.679917] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1302builder # [ 3.680921] systemd[1]: Stopped Find NixOS closure.1303builder # [ 3.682057] systemd[1]: Starting rw-sysroot-nix-store.service...1304builder # [ 3.683088] systemd[1]: systemd-sysctl.service: Deactivated successfully.1305builder # [ 3.684161] systemd[1]: Stopped Apply Kernel Variables.1306builder # [ 3.685291] systemd[1]: systemd-modules-load.service: Deactivated successfully.1307builder # [ 3.687247] systemd[1]: Stopped Load Kernel Modules.1308builder # [ 3.688155] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1309builder # [ 3.689910] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1310builder # [ 3.690982] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1311builder # [ 3.692128] systemd[1]: Stopped Create System Files and Directories.1312builder # [ 3.693096] systemd[1]: Stopped target Local File Systems.1313builder # [ 3.694443] systemd[1]: Stopped target Preparation for Local File Systems.1314builder # [ 3.695393] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1315builder # [ 3.697408] systemd[1]: Stopped Coldplug All udev Devices.1316builder # [ 3.699174] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1317builder # [ 3.701427] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1318builder # [ 3.702457] systemd[1]: Stopped Virtual Console Setup.1319server # [ 3.725413] systemd[1]: Mounted /sysroot/nix/.ro-store.1320server # [ 3.727717] systemd[1]: Mounted /sysroot/nix/.rw-store.1321server # [ 3.731624] systemd[1]: Mounted /sysroot/run.1322server # [ 3.732532] systemd[1]: Mounted /sysroot/tmp/shared.1323builder # [ 3.711542] systemd[1]: initrd-cleanup.service: Deactivated successfully.1324server # [ 3.733483] systemd[1]: Mounted /sysroot/tmp/xchg.1325builder # [ 3.713094] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1326builder # [ 3.713986] systemd[1]: systemd-udevd.service: Deactivated successfully.1327server # [ 3.737856] systemd[1]: Starting rw-sysroot-nix-store.service...1328builder # [ 3.717076] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1329builder # [ 3.720717] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1330builder # [ 3.722226] systemd[1]: Closed udev Control Socket.1331builder # [ 3.723712] systemd[1]: Starting Cleanup udev Database...1332builder # [ 3.725144] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1333builder # [ 3.726199] systemd[1]: Stopped Create Static Device Nodes in /dev.1334server # [ 3.748385] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1335builder # [ 3.727088] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1336server # [ 3.749887] systemd[1]: Finished rw-sysroot-nix-store.service.1337builder # [ 3.729111] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1338builder # [ 3.730081] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1339server # [ 3.752310] systemd[1]: Mounting /sysroot/nix/store...1340builder # [ 3.730945] systemd[1]: Stopped Create List of Static Device Nodes.1341builder # [ 3.731851] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1342builder # [ 3.733255] systemd[1]: Finished rw-sysroot-nix-store.service.1343builder # [ 3.749037] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1344builder # [ 3.751155] systemd[1]: Finished Cleanup udev Database.1345builder # [ 3.751895] systemd[1]: Reached target Switch Root.1346server # [ 3.773637] systemd[1]: Mounted /sysroot/nix/store.1347server # [ 3.775074] systemd[1]: Reached target Initrd File Systems.1348builder # [ 3.753508] systemd[1]: Starting NixOS Activation...1349server # [ 3.776654] systemd[1]: Starting Find NixOS closure...1350server # [ 3.780143] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1351server # [ 3.797190] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1352server # [ 3.805463] systemd[1]: Finished Find NixOS closure.1353server # [ 3.806717] systemd[1]: Reached target Initrd Default Target.1354server # [ 3.808914] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1355server # [ 3.822745] systemd[1]: Stopped target Initrd Default Target.1356server # [ 3.824057] systemd[1]: Stopped target Basic System.1357server # [ 3.824977] systemd[1]: Stopped target Initrd Root Device.1358server # [ 3.827111] systemd[1]: Stopped target Path Units.1359server # [ 3.827787] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1360server # [ 3.828778] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1361server # [ 3.829848] systemd[1]: Stopped target Slice Units.1362server # [ 3.830608] systemd[1]: Stopped target Socket Units.1363server # [ 3.831565] systemd[1]: Stopped target System Initialization.1364server # [ 3.832519] systemd[1]: Stopped target Swaps.1365server # [ 3.833202] systemd[1]: Stopped target Timer Units.1366server # [ 3.833901] systemd[1]: dbus.socket: Deactivated successfully.1367server # [ 3.835109] systemd[1]: Closed D-Bus System Message Bus Socket.1368server # [ 3.835881] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1369server # [ 3.837116] systemd[1]: Stopped Find NixOS closure.1370server # [ 3.839105] systemd[1]: Starting rw-sysroot-nix-store.service...1371server # [ 3.840142] systemd[1]: systemd-sysctl.service: Deactivated successfully.1372server # [ 3.841355] systemd[1]: Stopped Apply Kernel Variables.1373server # [ 3.842280] systemd[1]: systemd-modules-load.service: Deactivated successfully.1374server # [ 3.843604] systemd[1]: Stopped Load Kernel Modules.1375server # [ 3.845128] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1376server # [ 3.846215] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1377builder # [ 3.824319] initrd-nixos-activation-start[191]: booting system configuration /nix/store/gw9fmf2610hk69sp14in39i13w3bz4c5-nixos-system-builder-test1378server # [ 3.847486] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1379server # [ 3.848649] systemd[1]: Stopped Create System Files and Directories.1380server # [ 3.849845] systemd[1]: Stopped target Local File Systems.1381server # [ 3.851133] systemd[1]: Stopped target Preparation for Local File Systems.1382server # [ 3.852659] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1383server # [ 3.854130] systemd[1]: Stopped Coldplug All udev Devices.1384server # [ 3.856104] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1385server # [ 3.857074] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1386server # [ 3.858063] systemd[1]: Stopped Virtual Console Setup.1387server # [ 3.865740] systemd[1]: initrd-cleanup.service: Deactivated successfully.1388server # [ 3.870134] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1389server # [ 3.872886] systemd[1]: systemd-udevd.service: Deactivated successfully.1390builder # [ 3.851496] initrd-nixos-activation-start[191]: running activation script...1391server # [ 3.876061] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1392server # [ 3.879244] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1393server # [ 3.881685] systemd[1]: Closed udev Control Socket.1394server # [ 3.882716] systemd[1]: Starting Cleanup udev Database...1395server # [ 3.883589] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1396server # [ 3.885146] systemd[1]: Stopped Create Static Device Nodes in /dev.1397server # [ 3.885959] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1398server # [ 3.887066] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1399server # [ 3.888097] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1400server # [ 3.889103] systemd[1]: Stopped Create List of Static Device Nodes.1401server # [ 3.891515] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1402server # [ 3.892605] systemd[1]: Finished rw-sysroot-nix-store.service.1403server # [ 3.907993] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1404server # [ 3.910057] systemd[1]: Finished Cleanup udev Database.1405server # [ 3.910800] systemd[1]: Reached target Switch Root.1406server # [ 3.912589] systemd[1]: Starting NixOS Activation...1407server # [ 3.983958] initrd-nixos-activation-start[190]: booting system configuration /nix/store/5wji2h86vrfd5hbq69zzh7558g0w0vx1-nixos-system-server-test1408server # [ 4.011701] initrd-nixos-activation-start[190]: running activation script...1409builder # [ 4.042363] initrd-nixos-activation-start[214]: setting up /etc...1410builder # [ 4.151820] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1411builder # [ 4.154066] systemd[1]: Finished NixOS Activation.1412builder # [ 4.155250] systemd[1]: Starting Switch Root...1413builder # [ 4.166680] systemd[1]: Switching root.1414server # [ 4.207594] initrd-nixos-activation-start[213]: setting up /etc...1415builder # [ 4.435262] systemd-journald[66]: Received SIGTERM from PID 1 (systemd).1416server # [ 4.317805] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1417server # [ 4.319464] systemd[1]: Finished NixOS Activation.1418server # [ 4.321523] systemd[1]: Starting Switch Root...1419server # [ 4.334527] systemd[1]: Switching root.1420builder # [ 4.529689] NET: Registered PF_VSOCK protocol family1421server # [ 4.607117] systemd-journald[66]: Received SIGTERM from PID 1 (systemd).1422server # [ 4.696643] NET: Registered PF_VSOCK protocol family1423builder # [ 4.889350] 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)1424builder # [ 4.899065] systemd[1]: Detected virtualization kvm.1425builder # [ 4.900865] systemd[1]: Detected architecture x86-64.1426builder # [ 4.902776] systemd[1]: Detected first boot.1427builder # [ 4.906823] systemd[1]: Initializing machine ID from random generator.1428server # [ 5.059078] 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)1429server # [ 5.069154] systemd[1]: Detected virtualization kvm.1430server # [ 5.071050] systemd[1]: Detected architecture x86-64.1431server # [ 5.073167] systemd[1]: Detected first boot.1432server # [ 5.077544] systemd[1]: Initializing machine ID from random generator.1433builder # [ 5.148357] systemd[1]: bpf-restrict-fs: LSM BPF program attached1434builder # [ 5.246061] systemd[1]: Applying preset policy.1435server # [ 5.344804] systemd[1]: bpf-restrict-fs: LSM BPF program attached1436server # [ 5.429307] systemd[1]: Applying preset policy.1437builder # [ 5.422142] systemd[1]: Populated /etc with preset unit settings.1438server # [ 5.601432] systemd[1]: Populated /etc with preset unit settings.1439builder # [ 5.627021] systemd[1]: initrd-switch-root.service: Deactivated successfully.1440builder # [ 5.628355] systemd[1]: Stopped initrd-switch-root.service.1441builder # [ 5.630349] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1442builder # [ 5.632400] systemd[1]: Created slice Slice /system/getty.1443builder # [ 5.633807] systemd[1]: Created slice User and Session Slice.1444builder # [ 5.634659] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1445builder # [ 5.635889] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1446builder # [ 5.636991] systemd[1]: Expecting device /dev/hvc0...1447builder # [ 5.637694] systemd[1]: Expecting device /dev/ttyS0...1448builder # [ 5.638433] systemd[1]: Reached target Local Encrypted Volumes.1449builder # [ 5.639271] systemd[1]: Stopped target initrd-fs.target.1450builder # [ 5.640050] systemd[1]: Stopped target initrd-root-fs.target.1451builder # [ 5.640871] systemd[1]: Stopped target initrd-switch-root.target.1452builder # [ 5.641729] systemd[1]: Reached target Virtual Machines and Containers.1453builder # [ 5.642588] systemd[1]: Reached target Path Units.1454builder # [ 5.643311] systemd[1]: Reached target Remote File Systems.1455builder # [ 5.644120] systemd[1]: Reached target Slice Units.1456builder # [ 5.644851] systemd[1]: Reached target Swaps.1457builder # [ 5.646607] systemd[1]: Listening on Query the User Interactively for a Password.1458builder # [ 5.649189] systemd[1]: Listening on Process Core Dump Socket.1459builder # [ 5.651065] systemd[1]: Listening on Credential Encryption/Decryption.1460builder # [ 5.664532] systemd[1]: Listening on Factory Reset Management.1461builder # [ 5.665441] systemd[1]: Listening on Hostname Service Socket.1462builder # [ 5.668263] systemd[1]: Starting Journal Log Access Socket...1463builder # [ 5.669956] systemd[1]: Listening on Journal Audit Socket.1464builder # [ 5.672751] systemd[1]: Listening on Console Output Muting Service Socket.1465builder # [ 5.673836] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1466builder # [ 5.674964] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1467builder # [ 5.676248] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1468builder # [ 5.680392] systemd[1]: Listening on Disk Repartitioning Service Socket.1469builder # [ 5.681421] systemd[1]: Listening on udev Control Socket.1470builder # [ 5.682313] systemd[1]: Listening on udev Varlink Socket.1471builder # [ 5.684673] systemd[1]: Mounting Huge Pages File System...1472builder # [ 5.687142] systemd[1]: Mounting POSIX Message Queue File System...1473builder # [ 5.693866] systemd[1]: Mounting Kernel Debug File System...1474builder # [ 5.701805] systemd[1]: Mounting Kernel Trace File System...1475builder # [ 5.709808] systemd[1]: Starting Create List of Static Device Nodes...1476builder # [ 5.718880] systemd[1]: Starting Load Kernel Module configfs...1477builder # [ 5.725058] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1478builder # [ 5.735059] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1479builder # [ 5.747523] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1480builder # [ 5.757978] systemd[1]: Mounting FUSE Control File System...1481builder # [ 5.759661] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671482builder # [ 5.771495] systemd[1]: Starting Journal Service...1483builder # [ 5.779589] systemd[1]: Starting Load Kernel Modules...1484server # [ 5.810697] systemd[1]: initrd-switch-root.service: Deactivated successfully.1485server # [ 5.812109] systemd[1]: Stopped initrd-switch-root.service.1486builder # [ 5.789769] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1487server # [ 5.814769] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1488server # [ 5.816856] systemd[1]: Created slice Slice /system/getty.1489server # [ 5.818285] systemd[1]: Created slice User and Session Slice.1490server # [ 5.819167] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1491server # [ 5.820494] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1492server # [ 5.821601] systemd[1]: Expecting device /dev/hvc0...1493server # [ 5.822275] systemd[1]: Expecting device /dev/ttyS0...1494server # [ 5.823013] systemd[1]: Reached target Local Encrypted Volumes.1495builder # [ 5.800136] systemd[1]: Starting Remount Root and Kernel File Systems...1496server # [ 5.823858] systemd[1]: Stopped target initrd-fs.target.1497server # [ 5.824660] systemd[1]: Stopped target initrd-root-fs.target.1498server # [ 5.825523] systemd[1]: Stopped target initrd-switch-root.target.1499server # [ 5.826343] systemd[1]: Reached target Virtual Machines and Containers.1500server # [ 5.827278] systemd[1]: Reached target Path Units.1501server # [ 5.827965] systemd[1]: Reached target Remote File Systems.1502server # [ 5.828786] systemd[1]: Reached target Slice Units.1503server # [ 5.829598] systemd[1]: Reached target Swaps.1504builder # [ 5.806473] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1505server # [ 5.831422] systemd[1]: Listening on Query the User Interactively for a Password.1506server # [ 5.833953] systemd[1]: Listening on Process Core Dump Socket.1507server # [ 5.835770] systemd[1]: Listening on Credential Encryption/Decryption.1508builder # [ 5.817435] systemd[1]: Starting Coldplug All udev Devices...1509server # [ 5.837786] systemd[1]: Listening on Factory Reset Management.1510server # [ 5.850540] systemd[1]: Listening on Hostname Service Socket.1511builder # [ 5.828335] systemd[1]: Listening on Journal Log Access Socket.1512server # [ 5.853359] systemd[1]: Starting Journal Log Access Socket...1513server # [ 5.854781] systemd[1]: Listening on Journal Audit Socket.1514server # [ 5.857737] systemd[1]: Listening on Console Output Muting Service Socket.1515server # [ 5.858859] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1516server # [ 5.860005] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1517builder # [ 5.837671] systemd[1]: Mounted Huge Pages File System.1518server # [ 5.861403] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1519builder # [ 5.842019] systemd[1]: Mounted POSIX Message Queue File System.1520server # [ 5.865506] systemd[1]: Listening on Disk Repartitioning Service Socket.1521server # [ 5.866516] systemd[1]: Listening on udev Control Socket.1522server # [ 5.867350] systemd[1]: Listening on udev Varlink Socket.1523builder # [ 5.845057] systemd[1]: Mounted Kernel Debug File System.1524server # [ 5.869755] systemd[1]: Mounting Huge Pages File System...1525builder # [ 5.846959] systemd[1]: Mounted Kernel Trace File System.1526server # [ 5.872092] systemd[1]: Mounting POSIX Message Queue File System...1527builder # [ 5.849800] systemd[1]: Finished Create List of Static Device Nodes.1528builder # [ 5.852986] systemd[1]: modprobe@configfs.service: Deactivated successfully.1529server # [ 5.879533] systemd[1]: Mounting Kernel Debug File System...1530builder # [ 5.857188] systemd[1]: Finished Load Kernel Module configfs.1531builder # [ 5.863264] systemd-journald[284]: Collecting audit messages is enabled.1532server # [ 5.887474] systemd[1]: Mounting Kernel Trace File System...1533builder # [ 5.870267] systemd[1]: Mounting Kernel Configuration File System...1534server # [ 5.893831] systemd[1]: Starting Create List of Static Device Nodes...1535server # [ 5.902669] systemd[1]: Starting Load Kernel Module configfs...1536builder # [ 5.881915] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1537builder # [ 5.883028] EXT4-fs (vda): re-mounted c477f26d-6fc3-4c87-8dca-fda3748a4b99.1538server # [ 5.908142] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1539builder # [ 5.891377] systemd[1]: Started Journal Service.1540builder # [ 5.894604] loop: module loaded1541builder # [ 5.748973] systemd[1]: Queued start job for default target Multi-User System.1542builder # [ 5.754836] systemd[1]: systemd-journald.service: Deactivated successfully.1543server # [ 5.919749] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1544builder # [ 5.756818] systemd-modules-load[285]: Inserted module 'loop'1545server # [ 5.927653] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1546builder # [ 5.770734] systemd[1]: Mounted FUSE Control File System.1547server # [ 5.939702] systemd[1]: Mounting FUSE Control File System...1548builder # [ 5.776346] systemd[1]: Finished Load Kernel Modules.1549builder # [ 5.777280] systemd[1]: Finished Remount Root and Kernel File Systems.1550server # [ 5.942300] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671551builder # [ 5.783252] systemd-oomd[286]: No swap; memory pressure usage will be degraded1552server # [ 5.954094] systemd[1]: Starting Journal Service...1553builder # [ 5.793397] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1554builder # [ 5.799069] systemd[1]: Listening on Disk Image Download Service Socket.1555server # [ 5.965913] systemd[1]: Starting Load Kernel Modules...1556builder # [ 5.805476] systemd[1]: Starting Firewall...1557builder # [ 5.808088] systemd[1]: Starting Flush Journal to Persistent Storage...1558builder # [ 5.809126] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1559server # [ 5.979569] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1560builder # [ 5.816112] systemd[1]: Starting Load/Save OS Random Seed...1561builder # [ 5.822852] systemd[1]: Starting Apply Kernel Variables...1562builder # [ 5.823693] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1563server # [ 5.990079] systemd[1]: Starting Remount Root and Kernel File Systems...1564server # [ 5.994634] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1565server # [ 6.004823] systemd[1]: Starting Coldplug All udev Devices...1566server # [ 6.022181] systemd[1]: Listening on Journal Log Access Socket.1567server # [ 6.026643] systemd[1]: Mounted Huge Pages File System.1568server # [ 6.029025] systemd-journald[283]: Collecting audit messages is enabled.1569server # [ 6.031038] systemd[1]: Mounted POSIX Message Queue File System.1570server # [ 6.035056] systemd[1]: Mounted Kernel Debug File System.1571server # [ 6.038638] systemd[1]: Mounted Kernel Trace File System.1572server # [ 6.042996] systemd[1]: Finished Create List of Static Device Nodes.1573builder # [ 6.023004] systemd-journald[284]: Received client request to flush runtime journal.1574server # [ 6.047253] systemd[1]: modprobe@configfs.service: Deactivated successfully.1575server # [ 5.912449] systemd[1]: Queued start job for default target Multi-User System.1576server # [ 5.913968] systemd[1]: systemd-journald.service: Deactivated successfully.1577server # [ 6.060451] systemd[1]: Finished Load Kernel Module configfs.1578server # [ 6.065960] systemd[1]: Started Journal Service.1579server # [ 6.067588] loop: module loaded1580server # [ 5.931702] systemd-modules-load[284]: Inserted module 'loop'1581server # [ 5.934139] systemd[1]: Mounted FUSE Control File System.1582server # [ 6.079567] EXT4-fs (vda): re-mounted ddf1e02b-39a3-441b-932b-84856b0859ea.1583server # [ 5.956110] systemd-modules-load[284]: Inserted module 'tls'1584server # [ 5.958197] systemd[1]: Mounting Kernel Configuration File System...1585server # [ 5.963583] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1586server # [ 5.967423] systemd[1]: Finished Remount Root and Kernel File Systems.1587server # [ 5.968475] systemd[1]: Finished Load Kernel Modules.1588server # [ 5.972195] systemd[1]: Listening on Disk Image Download Service Socket.1589server # [ 5.983109] systemd[1]: Starting Firewall...1590server # [ 5.992098] systemd[1]: Starting Flush Journal to Persistent Storage...1591server # [ 5.993039] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1592server # [ 5.999115] systemd-oomd[285]: No swap; memory pressure usage will be degraded1593server # [ 6.005239] systemd[1]: Starting Load/Save OS Random Seed...1594builder # [ 5.986283] systemd[1]: Mounted Kernel Configuration File System.1595builder # [ 5.990590] systemd[1]: Finished Load/Save OS Random Seed.1596server # [ 6.014567] systemd[1]: Starting Apply Kernel Variables...1597builder # [ 5.993278] systemd[1]: Reached target First Boot Complete.1598server # [ 6.017171] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1599builder # [ 5.995771] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1600builder # [ 5.996769] systemd[1]: Starting Create Static Device Nodes in /dev...1601builder # [ 5.998284] systemd[1]: Finished Flush Journal to Persistent Storage.1602builder # [ 6.002590] systemd[1]: Finished Apply Kernel Variables.1603server # [ 6.026444] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1604server # [ 6.232031] systemd-journald[283]: Received client request to flush runtime journal.1605builder # [ 6.091992] systemd[1]: Finished Coldplug All udev Devices.1606builder # [ 6.099770] systemd[1]: Finished Create Static Device Nodes in /dev.1607builder # [ 6.102204] systemd[1]: Reached target Preparation for Local File Systems.1608builder # [ 6.104268] systemd[1]: Starting Rule-based Manager for Device Events and Files...1609builder # [ 6.150851] systemd-udevd[322]: Using default interface naming scheme 'v261'.1610server # [ 6.196435] systemd[1]: Mounted Kernel Configuration File System.1611server # [ 6.199383] systemd[1]: Finished Load/Save OS Random Seed.1612server # [ 6.201444] systemd[1]: Reached target First Boot Complete.1613server # [ 6.202339] systemd[1]: Finished Apply Kernel Variables.1614server # [ 6.203872] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1615server # [ 6.204893] systemd[1]: Starting Create Static Device Nodes in /dev...1616server # [ 6.207114] systemd[1]: Finished Flush Journal to Persistent Storage.1617builder # [ 6.201126] systemd[1]: Started Rule-based Manager for Device Events and Files.1618server # [ 6.304278] systemd[1]: Finished Coldplug All udev Devices.1619server # [ 6.310901] systemd[1]: Finished Create Static Device Nodes in /dev.1620server # [ 6.313199] systemd[1]: Reached target Preparation for Local File Systems.1621server # [ 6.315919] systemd[1]: Starting Rule-based Manager for Device Events and Files...1622builder # [ 6.322117] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1623server # [ 6.361227] systemd-udevd[324]: Using default interface naming scheme 'v261'.1624server # [ 6.413429] systemd[1]: Started Rule-based Manager for Device Events and Files.1625builder # [ 6.403701] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1626builder # [ 6.420157] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1627builder # [ 6.448889] (udev-worker)[344]: Network interface NamePolicy= disabled on kernel command line.1628builder # [ 6.451097] (udev-worker)[353]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1629builder # [ 6.453138] (udev-worker)[353]: Network interface NamePolicy= disabled on kernel command line.1630builder # [ 6.490654] systemd[1]: Mounting /run/wrappers...1631server # [ 6.527851] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1632builder # [ 6.530283] systemd[1]: Mounted /run/wrappers.1633builder # [ 6.530967] systemd[1]: Reached target Local File Systems.1634builder # [ 6.535599] systemd[1]: Listening on Boot Loader Control Service Socket.1635builder # [ 6.539278] systemd[1]: Starting register-nix-paths.service...1636builder # [ 6.544133] systemd[1]: Starting Create SUID/SGID Wrappers...1637builder # [ 6.544911] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1638builder # [ 6.550136] systemd[1]: Starting Save Transient machine-id to Disk...1639builder # [ 6.565347] systemd[1]: Starting Create System Files and Directories...1640server # [ 6.612263] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1641server # [ 6.647121] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1642builder # [ 6.643442] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1643server # [ 6.672273] systemd[1]: Mounting /run/wrappers...1644builder # [ 6.651053] systemd[1]: Finished Save Transient machine-id to Disk.1645server # [ 6.680477] (udev-worker)[352]: Network interface NamePolicy= disabled on kernel command line.1646server # [ 6.683092] (udev-worker)[355]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1647server # [ 6.686151] (udev-worker)[355]: Network interface NamePolicy= disabled on kernel command line.1648builder # [ 6.680254] systemd[1]: Condition check resulted in Virtio network device being skipped.1649builder # [ 6.681754] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1650builder # [ 6.684176] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1651builder # [ 6.685814] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671652builder # [ 6.689713] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1653builder # [ 6.691453] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1654builder # [ 6.694173] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1655server # [ 6.719576] systemd[1]: Mounted /run/wrappers.1656server # [ 6.720533] systemd[1]: Reached target Local File Systems.1657server # [ 6.724428] systemd[1]: Listening on Boot Loader Control Service Socket.1658server # [ 6.730063] systemd[1]: Starting register-nix-paths.service...1659server # [ 6.733146] systemd[1]: Starting Create SUID/SGID Wrappers...1660server # [ 6.733990] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1661server # [ 6.743165] systemd[1]: Starting Save Transient machine-id to Disk...1662server # [ 6.749224] systemd[1]: Starting Create System Files and Directories...1663builder # [ 6.727715] systemd[1]: Finished Create System Files and Directories.1664builder # [ 6.733100] systemd[1]: Starting Rebuild Journal Catalog...1665builder # [ 6.739256] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1666server # [ 6.841224] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1667server # [ 6.846670] systemd[1]: Finished Save Transient machine-id to Disk.1668builder # [ 6.840527] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1669builder # [ 6.866362] systemd[1]: Finished Rebuild Journal Catalog.1670builder # [ 6.871975] systemd[1]: Starting Update is Completed...1671server # [ 6.921358] systemd[1]: Finished Create System Files and Directories.1672server # [ 6.926756] systemd[1]: Condition check resulted in Virtio network device being skipped.1673server # [ 6.931157] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1674server # [ 6.932648] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1675server # [ 6.933888] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671676server # [ 6.940152] systemd[1]: Starting Rebuild Journal Catalog...1677server # [ 6.940955] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1678server # [ 6.943766] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1679server # [ 6.945526] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1680builder # [ 6.927762] systemd[1]: Finished Update is Completed.1681server # [ 6.953082] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1682builder # [ 7.097628] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31683builder # [ 7.121294] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1684server # [ 7.041232] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1685server # [ 7.073130] systemd[1]: Finished Rebuild Journal Catalog.1686server # [ 7.079599] systemd[1]: Starting Update is Completed...1687server # [ 7.160388] systemd[1]: Finished Update is Completed.1688server # [ 7.337081] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input31689server # [ 7.358696] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1690server # [ 7.376406] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input41691builder # [ 7.135942] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41692builder # [ 7.154888] mousedev: PS/2 mouse device common for all mice1693builder # [ 7.158776] ACPI: button: Power Button [PWRF]1694builder # [ 7.249976] systemd[1]: Finished Firewall.1695builder # [ 7.248660] rtc_cmos PNP0B00:00: RTC can wake from S41696builder # [ 7.287862] rtc_cmos PNP0B00:00: registered as rtc01697builder # [ 7.287939] rtc_cmos PNP0B00:00: setting system clock to 2026-09-19T10:55:18 UTC (1789815318)1698builder # [ 7.288012] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1699builder # [ 7.291439] systemd-journald[284]: Time jumped backwards, rotating.1700builder # [ 7.358047] parport_pc 00:02: reported by Plug and Play ACPI1701builder # [ 7.358151] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1702builder # [ 7.368067] Console: switching to colour dummy device 80x251703builder # [ 7.325775] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1704builder # [ 7.331551] systemd[1]: Finished Create SUID/SGID Wrappers.1705builder # [ 7.370999] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1706builder # [ 7.485194] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1707builder # [ 7.487399] [drm] Found bochs VGA, ID 0xb0c5.1708builder # [ 7.487401] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1709builder # [ 7.358758] systemd[1]: Finished register-nix-paths.service.1710builder # [ 7.359875] systemd[1]: Reached target System Initialization.1711builder # [ 7.503165] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1712builder # [ 7.364159] systemd[1]: Started Discard unused filesystem blocks once a week.1713builder # [ 7.365532] systemd[1]: Started Daily Cleanup of Temporary Directories.1714builder # [ 7.366592] systemd[1]: Reached target Timer Units.1715builder # [ 7.368197] systemd[1]: Listening on D-Bus System Message Bus Socket.1716builder # [ 7.370071] systemd[1]: Starting niks3 auto-upload socket...1717builder # [ 7.371417] systemd[1]: Listening on Nix Daemon Socket.1718builder # [ 7.372604] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1719builder # [ 7.515525] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1720builder # [ 7.516231] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01721builder # [ 7.380402] systemd[1]: Starting D-Bus System Message Bus...1722builder # [ 7.381329] systemd[1]: Listening on niks3 auto-upload socket.1723builder # [ 7.382260] systemd[1]: Reached target Socket Units.1724server # [ 7.441786] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1725server # [ 7.446633] systemd[1]: Finished Create SUID/SGID Wrappers.1726builder # [ 7.568579] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61727builder # [ 7.569842] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51728builder # [ 7.454680] systemd[1]: Starting Virtual Console Setup...1729server # [ 7.435820] ACPI: button: Power Button [PWRF]1730server # [ 7.436742] mousedev: PS/2 mouse device common for all mice1731server # [ 7.568346] rtc_cmos PNP0B00:00: RTC can wake from S41732builder # [ 7.487491] dbus-broker-launch[509]: Looking up NSS user entry for 'systemd-timesync'...1733server # [ 7.594589] rtc_cmos PNP0B00:00: registered as rtc01734server # [ 7.594658] rtc_cmos PNP0B00:00: setting system clock to 2026-09-19T10:55:19 UTC (1789815319)1735server # [ 7.594734] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1736builder # [ 7.495654] dbus-broker-launch[509]: NSS returned no entry for 'systemd-timesync'1737builder # [ 7.496729] dbus-broker-launch[509]: Invalid user-name in /nix/store/lqj8aqd4ypc1wi2b459df8xqc32s8287-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1738builder # [ 7.648047] Console: switching to colour frame buffer device 160x501739builder # [ 7.508941] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1740builder # [ 7.512222] systemd[1]: Stopped Virtual Console Setup.1741builder # [ 7.516712] systemd[1]: Starting Virtual Console Setup...1742builder # [ 7.678874] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1743server # [ 7.596184] systemd-journald[283]: Time jumped backwards, rotating.1744server # [ 7.609109] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1745builder # [ 7.541495] systemd[1]: Started D-Bus System Message Bus.1746builder # [ 7.542541] systemd[1]: Reached target Basic System.1747builder # [ 7.548127] systemd[1]: Started backdoor.service.1748builder # [ 7.552172] systemd[1]: Starting Import lastlog data into lastlog2 database...1749server # [ 7.613993] parport_pc 00:02: reported by Plug and Play ACPI1750server # [ 7.614078] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1751builder # [ 7.559112] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1752server # [ 7.590117] systemd[1]: Finished register-nix-paths.service.1753server # [ 7.591205] systemd[1]: Reached target System Initialization.1754builder # [ 7.572107] systemd[1]: Starting Post-Boot Actions...1755server # [ 7.593785] systemd[1]: Started Discard unused filesystem blocks once a week.1756server # [ 7.594773] systemd[1]: Started niks3 garbage collection timer.1757builder # [ 7.574246] dbus-broker-launch[509]: Ready1758server # [ 7.598155] systemd[1]: Started Daily Cleanup of Temporary Directories.1759server # [ 7.599089] systemd[1]: Reached target Timer Units.1760server # [ 7.599825] systemd[1]: Listening on D-Bus System Message Bus Socket.1761server # [ 7.603767] systemd[1]: Listening on niks3 server socket.1762server # [ 7.604933] systemd[1]: Listening on Nix Daemon Socket.1763server # [ 7.607494] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1764server # [ 7.608662] systemd[1]: Reached target Socket Units.1765server # [ 7.609858] systemd[1]: Reached target Basic System.1766builder # [ 7.588696] systemd[1]: Started Reset console on configuration changes.1767server # [ 7.614510] systemd[1]: Started backdoor.service.1768server # [ 7.629888] Console: switching to colour dummy device 80x251769server # [ 7.617424] systemd[1]: Starting Import lastlog data into lastlog2 database...1770server # [ 7.768521] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1771server # [ 7.769158] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1772server # [ 7.628374] systemd[1]: Starting Generate test mTLS certs...1773builder # [ 7.614142] systemd[1]: Starting resolvconf update...1774server # [ 7.780860] [drm] Found bochs VGA, ID 0xb0c5.1775server # [ 7.781292] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1776server # [ 7.642850] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1777server # [ 7.654379] systemd[1]: Starting Post-Boot Actions...1778server # [ 7.679502] systemd[1]: Started Reset console on configuration changes.1779server # [ 7.837270] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1780server # [ 7.837982] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01781server # [ 7.696223] systemd[1]: Starting resolvconf update...1782builder # connecting to host...1783builder # [ 7.692274] systemd[1]: Finished Post-Boot Actions.1784server # [ 7.722311] systemd[1]: Finished Firewall.1785builder # [ 7.843860] ppdev: user-space parallel port driver1786server # connecting to host...1787builder # [ 7.722856] systemd[1]: Started Name Service Cache Daemon (nsncd).1788builder # [ 7.728811] nsncd[518]: Sep 19 10:55:19.080 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1789builder # [ 7.730880] systemd[1]: Reached target Host and Network Name Lookups.1790builder # [ 7.732231] systemd[1]: Reached target User and Group Name Lookups.1791server # [ 7.761951] systemd[1]: Finished Post-Boot Actions.1792builder # [ 7.746129] systemd[1]: Starting User Login Management...1793builder # [ 7.750738] systemd[1]: Finished Import lastlog data into lastlog2 database.1794server # [ 7.778625] nsncd[518]: Sep 19 10:55:19.826 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1795server # [ 7.781842] niks3-test-certs-start[532]: -----1796server: Guest shell says: b'Spawning backdoor root shell...\n'1797server # [ 7.789592] systemd[1]: Starting D-Bus System Message Bus...1798server # [ 7.790441] systemd[1]: Started Name Service Cache Daemon (nsncd).1799server: connected to guest root shell1800server # [ 7.796164] systemd[1]: Reached target Host and Network Name Lookups.1801server: (connecting took 8.47 seconds)1802server # [ 7.797970] systemd[1]: Reached target User and Group Name Lookups.1803server: (finished: waiting for the VM to finish booting, in 8.47 seconds)1804builder # [ 7.929667] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1805server # [ 7.820534] systemd[1]: Starting User Login Management...1806server # [ 7.826254] niks3-test-certs-start[548]: -----1807server # [ 7.841451] systemd[1]: Finished Import lastlog data into lastlog2 database.1808builder # [ 7.965767] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1809builder # [ 7.830971] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1810builder # [ 7.832860] systemd[1]: Stopped Virtual Console Setup.1811builder # [ 7.848547] systemd[1]: Starting Virtual Console Setup...1812builder # [ 7.877067] systemd[1]: Stopped target Host and Network Name Lookups.1813builder # [ 7.877918] systemd[1]: Stopping Host and Network Name Lookups...1814builder # [ 7.878737] systemd[1]: Stopped target User and Group Name Lookups.1815builder # [ 7.879593] systemd[1]: Stopping User and Group Name Lookups...1816builder # [ 7.883512] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1817builder # [ 7.885696] systemd-logind[545]: New seat seat0.1818builder # [ 7.888207] systemd[1]: nscd.service: Deactivated successfully.1819builder # [ 7.891949] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1820builder # [ 7.892846] systemd-logind[545]: Watching system buttons on /dev/input/event3 (Power Button)1821builder # [ 7.893944] systemd-logind[545]: Watching system buttons on /dev/input/event2 (QEMU Virtio Keyboard)1822builder # [ 7.895162] systemd-logind[545]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1823server # [ 7.923197] niks3-test-certs-start[556]: Certificate request self-signature ok1824builder # [ 7.903982] systemd[1]: Started User Login Management.1825server # [ 7.926125] niks3-test-certs-start[556]: subject=CN=server1826builder # [ 7.915544] systemd[1]: Starting linger-users.service...1827builder # [ 7.923985] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1828server # [ 7.969204] niks3-test-certs-start[568]: -----1829builder # [ 7.982519] systemd[1]: linger-users.service: Deactivated successfully.1830builder # [ 7.986264] systemd[1]: Finished linger-users.service.1831builder # [ 8.001132] nsncd[596]: Sep 19 10:55:19.354 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1832builder # [ 8.003735] systemd[1]: Started Name Service Cache Daemon (nsncd).1833builder # [ 8.004651] systemd[1]: Reached target Host and Network Name Lookups.1834builder # [ 8.005501] systemd[1]: Reached target User and Group Name Lookups.1835server # [ 8.043255] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61836builder # [ 8.040071] systemd[1]: Finished resolvconf update.1837builder # [ 8.041262] systemd[1]: Reached target Preparation for Network.1838builder # [ 8.049089] systemd[1]: Starting DHCP Client...1839builder # [ 8.051115] systemd[1]: Starting Address configuration of eth1...1840builder # [ 8.054867] systemd[1]: Starting Extra networking commands....1841builder # [ 8.203986] kvm_amd: TSC scaling supported1842builder # [ 8.204381] kvm_amd: Nested Virtualization enabled1843builder # [ 8.206313] kvm_amd: Nested Paging enabled1844server # [ 8.043827] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51845server # [ 8.200331] Console: switching to colour frame buffer device 160x501846builder # [ 8.207976] kvm_amd: LBR virtualization supported1847server # [ 8.231269] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1848builder # [ 8.208432] kvm_amd: Virtual VMLOAD VMSAVE supported1849server # [ 8.042604] dbus-broker-launch[547]: Looking up NSS user entry for 'systemd-timesync'...1850builder # [ 8.210616] kvm_amd: Virtual GIF supported1851server # [ 8.090359] niks3-test-certs-start[578]: Certificate request self-signature ok1852builder # [ 8.211820] kvm_amd: Virtual NMI enabled1853server # [ 8.093321] niks3-test-certs-start[578]: subject=CN=niks3 test client1854server # [ 8.095628] systemd[1]: Finished Generate test mTLS certs.1855server # [ 8.097979] dbus-broker-launch[547]: NSS returned no entry for 'systemd-timesync'1856server # [ 8.101117] dbus-broker-launch[547]: Invalid user-name in /nix/store/acgi8ndlz73c06b246im61jybf4icfqj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1857server # [ 8.105354] systemd-logind[549]: New seat seat0.1858server # [ 8.107338] systemd-logind[549]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1859server # [ 8.109962] systemd-logind[549]: Watching system buttons on /dev/input/event2 (Power Button)1860server # [ 8.111342] systemd[1]: Started User Login Management.1861server # [ 8.112464] systemd[1]: Stopped target Host and Network Name Lookups.1862server # [ 8.114130] systemd[1]: Stopping Host and Network Name Lookups...1863server # [ 8.114917] systemd[1]: Stopped target User and Group Name Lookups.1864server # [ 8.118610] systemd[1]: Stopping User and Group Name Lookups...1865server # [ 8.119563] systemd[1]: Starting linger-users.service...1866server # [ 8.120423] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1867server # [ 8.121329] systemd-logind[549]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1868server # [ 8.128216] systemd[1]: Starting Virtual Console Setup...1869server # [ 8.131101] systemd[1]: nscd.service: Deactivated successfully.1870server # [ 8.136101] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1871server # [ 8.163085] systemd[1]: Started D-Bus System Message Bus.1872builder # [ 8.284560] EDAC MC: Ver: 3.0.01873server # [ 8.195253] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1874builder # [ 8.175229] network-addresses-eth1-start[622]: adding address 192.168.1.1/24... done1875server # [ 8.205541] dbus-broker-launch[547]: Ready1876server # [ 8.220122] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1877builder # [ 8.201654] network-addresses-eth1-start[622]: adding address 2001:db8:1::1/64... done1878server # [ 8.226076] systemd[1]: Stopped Virtual Console Setup.1879server # [ 8.235266] systemd[1]: linger-users.service: Deactivated successfully.1880server # [ 8.238501] systemd[1]: Finished linger-users.service.1881server # [ 8.248469] systemd[1]: Starting Virtual Console Setup...1882builder # [ 8.229393] systemd[1]: Finished Address configuration of eth1.1883builder # [ 8.242839] dhcpcd[630]: dhcpcd-10.3.2 starting1884builder # [ 8.249459] dhcpcd[682]: dev: loaded udev1885server # [ 8.275392] systemd[1]: Started Name Service Cache Daemon (nsncd).1886server # [ 8.276654] nsncd[619]: Sep 19 10:55:20.325 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1887server # [ 8.280556] systemd[1]: Reached target Host and Network Name Lookups.1888builder # [ 8.403097] 8021q: 802.1Q VLAN Support v1.81889builder # [ 8.403514] 8021q: adding VLAN 0 to HW filter on device eth11890server # [ 8.283970] systemd[1]: Reached target User and Group Name Lookups.1891builder # [ 8.268512] systemd[1]: Finished Extra networking commands..1892builder # [ 8.270870] systemd[1]: Reached target Network.1893builder # [ 8.274026] systemd[1]: Starting Permit User Sessions...1894server # [ 8.298286] systemd[1]: Finished resolvconf update.1895server # [ 8.299096] systemd[1]: Reached target Preparation for Network.1896server # [ 8.306405] systemd[1]: Starting DHCP Client...1897server # [ 8.452954] ppdev: user-space parallel port driver1898server # [ 8.312098] systemd[1]: Starting Address configuration of eth1...1899server # [ 8.317485] systemd[1]: Starting Extra networking commands....1900builder # [ 8.300074] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1901server # [ 8.508248] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1902builder # [ 8.493117] cfg80211: Loading compiled-in X.509 certificates for regulatory database1903builder # [ 8.358946] systemd[1]: Finished Permit User Sessions.1904builder # [ 8.363344] systemd[1]: Started Getty on tty1.1905builder # [ 8.365332] systemd[1]: Reached target Login Prompts.1906builder # [ 8.517824] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1907builder # [ 8.518479] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1908builder # [ 8.520409] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21909builder # [ 8.521277] cfg80211: failed to load regulatory.db1910server # [ 8.414485] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1911server # [ 8.417972] systemd[1]: Stopped Virtual Console Setup.1912server # [ 8.563404] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1913builder # [ 8.552239] 8021q: adding VLAN 0 to HW filter on device eth01914builder # [ 8.411585] dhcpcd[682]: eth0: waiting for carrier1915builder # [ 8.412547] dhcpcd[682]: eth0: carrier acquired1916builder # [ 8.415323] systemd-vconsole-setup[577]: Configuration of first virtual console was skipped, ignoring remaining ones.1917server # [ 8.439975] systemd[1]: Starting Virtual Console Setup...1918builder # [ 8.420133] systemd[1]: Finished Virtual Console Setup.1919builder # [ 8.423502] dhcpcd[682]: DUID 00:01:00:01:32:41:26:97:52:54:00:12:34:561920builder # [ 8.424561] dhcpcd[682]: eth0: IAID 00:12:34:561921builder # [ 8.425349] dhcpcd[682]: eth0: adding address fe80::5054:ff:fe12:34561922server # [ 8.447339] network-addresses-eth1-start[646]: adding address 192.168.1.2/24... done1923server # [ 8.475971] network-addresses-eth1-start[646]: adding address 2001:db8:1::2/64... done1924server # [ 8.507526] systemd[1]: Finished Address configuration of eth1.1925server # [ 8.614231] dhcpcd[661]: dhcpcd-10.3.2 starting1926server # [ 8.628677] dhcpcd[715]: dev: loaded udev1927server # [ 8.776477] kvm_amd: TSC scaling supported1928server # [ 8.776874] kvm_amd: Nested Virtualization enabled1929server # [ 8.777334] kvm_amd: Nested Paging enabled1930server # [ 8.779081] kvm_amd: LBR virtualization supported1931server # [ 8.636416] systemd[1]: Finished Extra networking commands..1932server # [ 8.638061] systemd[1]: Reached target Network.1933server # [ 8.782506] kvm_amd: Virtual VMLOAD VMSAVE supported1934server # [ 8.782967] kvm_amd: Virtual GIF supported1935server # [ 8.785061] kvm_amd: Virtual NMI enabled1936server # [ 8.643399] systemd[1]: Started Mock OIDC server for testing.1937server # [ 8.650200] systemd[1]: Starting Nginx Web Server...1938server # [ 8.664176] systemd[1]: Starting PostgreSQL Server...1939server # [ 8.813271] 8021q: 802.1Q VLAN Support v1.81940server # [ 8.813747] 8021q: adding VLAN 0 to HW filter on device eth11941server # [ 8.675278] systemd[1]: Started RustFS S3-compatible object storage.1942server # [ 8.685398] systemd[1]: Starting Setup RustFS bucket...1943server # [ 8.697494] systemd[1]: Starting Permit User Sessions...1944server # [ 8.857925] EDAC MC: Ver: 3.0.01945server # [ 8.749935] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1946server # [ 8.810233] systemd[1]: Finished Permit User Sessions.1947server # [ 8.825073] systemd[1]: Started Getty on tty1.1948server # [ 8.825719] systemd[1]: Reached target Login Prompts.1949server # [ 8.937050] mock-oidc-server[718]: Mock OIDC Server running1950server # [ 8.937851] mock-oidc-server[718]: OIDC Address: 127.0.0.1:80801951server # [ 8.938687] mock-oidc-server[718]: Issue Address: 127.0.0.1:80811952server # [ 8.942523] mock-oidc-server[718]: Issuer: http://127.0.0.1:8080/oidc1953server # [ 8.943919] mock-oidc-server[718]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1954server # [ 8.946185] mock-oidc-server[718]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1955server # [ 8.947419] mock-oidc-server[718]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1956server # [ 9.129472] cfg80211: Loading compiled-in X.509 certificates for regulatory database1957server # [ 9.198735] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1958server # [ 9.200695] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1959server # [ 9.205088] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21960server # [ 9.206046] cfg80211: failed to load regulatory.db1961server # [ 9.105155] nginx-pre-start[743]: nginx: the configuration file /nix/store/gmsfsh3cg82ymbpkm9bijq82cs3b9793-nginx.conf syntax is ok1962server # [ 9.106639] nginx-pre-start[743]: nginx: configuration file /nix/store/gmsfsh3cg82ymbpkm9bijq82cs3b9793-nginx.conf test is successful1963server # [ 9.124655] systemd[1]: Started Nginx Web Server.1964server # [ 9.309102] 8021q: adding VLAN 0 to HW filter on device eth01965server # [ 9.167077] dhcpcd[715]: eth0: waiting for carrier1966server # [ 9.168231] dhcpcd[715]: eth0: carrier acquired1967server # [ 9.176985] postgresql-pre-start[747]: The files belonging to this database system will be owned by user "postgres".1968server # [ 9.179177] postgresql-pre-start[747]: This user must also own the server process.1969server # [ 9.188379] dhcpcd[715]: DUID 00:01:00:01:32:41:26:99:52:54:00:12:34:561970server # [ 9.189805] dhcpcd[715]: eth0: IAID 00:12:34:561971server # [ 9.191270] dhcpcd[715]: eth0: adding address fe80::5054:ff:fe12:34561972server # [ 9.195395] postgresql-pre-start[747]: The database cluster will be initialized with locale "en_US.UTF-8".1973server # [ 9.196619] postgresql-pre-start[747]: The default database encoding has accordingly been set to "UTF8".1974server # [ 9.197891] postgresql-pre-start[747]: The default text search configuration will be set to "english".1975server # [ 9.199123] postgresql-pre-start[747]: Data page checksums are enabled.1976server # [ 9.201138] postgresql-pre-start[747]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1977server # [ 9.202341] postgresql-pre-start[747]: creating subdirectories ... ok1978server # [ 9.203232] postgresql-pre-start[747]: selecting dynamic shared memory implementation ... posix1979server # [ 9.332045] postgresql-pre-start[747]: selecting default "max_connections" ... 1001980server # [ 9.348838] systemd-vconsole-setup[664]: Configuration of first virtual console was skipped, ignoring remaining ones.1981server # [ 9.353854] systemd[1]: Finished Virtual Console Setup.1982server # [ 9.417948] postgresql-pre-start[747]: selecting default "shared_buffers" ... 128MB1983builder # [ 10.014100] dhcpcd[682]: eth0: soliciting a DHCP lease1984builder # [ 10.166766] NET: Registered PF_PACKET protocol family1985builder # [ 10.029597] dhcpcd[682]: eth0: offered 10.0.2.15 from 10.0.2.21986builder # [ 10.031188] dhcpcd[682]: eth0: probing address 10.0.2.15/241987server # [ 10.147749] postgresql-pre-start[747]: selecting default time zone ... UTC1988server # [ 10.150913] postgresql-pre-start[747]: creating configuration files ... ok1989server # [ 10.343081] postgresql-pre-start[747]: running bootstrap script ... ok1990server # [ 10.455550] dhcpcd[715]: eth0: soliciting a DHCP lease1991server # [ 10.623245] NET: Registered PF_PACKET protocol family1992server # [ 10.485521] dhcpcd[715]: eth0: offered 10.0.2.15 from 10.0.2.21993server # [ 10.488158] dhcpcd[715]: eth0: probing address 10.0.2.15/241994server # [ 10.750405] postgresql-pre-start[747]: performing post-bootstrap initialization ... ok1995builder # [ 10.800326] dhcpcd[682]: eth0: soliciting an IPv6 router1996builder # [ 10.801816] dhcpcd[682]: eth0: Router Advertisement from fe80::21997builder # [ 10.802645] dhcpcd[682]: eth0: adding address fec0::5054:ff:fe12:3456/641998builder # [ 10.803581] dhcpcd[682]: eth0: adding route to fec0::/641999builder # [ 10.804487] dhcpcd[682]: eth0: adding default route via fe80::22000server # [ 10.904777] postgresql-pre-start[747]: syncing data to disk ... ok2001server # [ 10.905720] postgresql-pre-start[747]: initdb: warning: enabling "trust" authentication for local connections2002server # [ 10.906912] postgresql-pre-start[747]: 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.2003server # [ 10.908823] postgresql-pre-start[747]: Success. You can now start the database server using:2004server # [ 10.909845] postgresql-pre-start[747]: pg_ctl -D /var/lib/postgresql/18 -l logfile start2005server # [ 10.983678] postgres[805]: [805] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit2006server # [ 10.986343] postgres[805]: [805] LOG: listening on IPv6 address "::1", port 54322007server # [ 10.987425] postgres[805]: [805] LOG: listening on IPv4 address "127.0.0.1", port 54322008server # [ 10.992061] postgres[805]: [805] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2009server # [ 11.002647] postgres[814]: [814] LOG: database system was shut down at 2026-09-19 10:55:22 GMT2010server # [ 11.005768] dhcpcd[715]: eth0: soliciting an IPv6 router2011server # [ 11.006992] dhcpcd[715]: eth0: Router Advertisement from fe80::22012server # [ 11.007860] dhcpcd[715]: eth0: adding address fec0::5054:ff:fe12:3456/642013server # [ 11.009030] dhcpcd[715]: eth0: adding route to fec0::/642014server # [ 11.010152] dhcpcd[715]: eth0: adding default route via fe80::22015server # [ 11.011872] postgres[805]: [805] LOG: database system is ready to accept connections2016server # [ 11.016654] systemd[1]: Started PostgreSQL Server.2017server # [ 11.022329] systemd[1]: Starting PostgreSQL Setup Scripts...2018server # [ 11.147749] postgresql-setup-start[825]: CREATE DATABASE2019server # [ 11.175981] postgresql-setup-start[830]: CREATE ROLE2020server # [ 11.188493] postgresql-setup-start[832]: ALTER DATABASE2021server # [ 11.192955] systemd[1]: Finished PostgreSQL Setup Scripts.2022server # [ 11.194739] systemd[1]: Reached target PostgreSQL.2023server: (finished: waiting for unit postgresql.service, in 12.07 seconds)2024server: waiting for unit rustfs.service2025server: (finished: waiting for unit rustfs.service, in 0.04 seconds)2026server: waiting for unit rustfs-setup.service2027builder # [ 15.022917] dhcpcd[682]: eth0: leased 10.0.2.15 for 86400 seconds2028builder # [ 15.025870] dhcpcd[682]: eth0: adding route to 10.0.2.0/242029builder # [ 15.028927] dhcpcd[682]: eth0: adding default route via 10.0.2.22030builder # [ 15.108878] systemd[1]: Started DHCP Client.2031builder # [ 15.111259] systemd[1]: Reached target Multi-User System.2032builder # [ 15.112326] systemd[1]: Startup finished in 851ms (kernel) + 3.511s (initrd) + 10.748s (userspace) = 15.112s.2033server # [ 15.506637] dhcpcd[715]: eth0: leased 10.0.2.15 for 86400 seconds2034server # [ 15.508391] dhcpcd[715]: eth0: adding route to 10.0.2.0/242035server # [ 15.509431] dhcpcd[715]: eth0: adding default route via 10.0.2.22036server # [ 15.576913] systemd[1]: Started DHCP Client.2037server # [ 21.741870] rustfs-setup-start[945]: mb s3://niks3-test2038server # [ 21.753475] systemd[1]: Finished Setup RustFS bucket.2039server # [ 21.764470] systemd[1]: Starting niks3 server...2040server: (finished: waiting for unit rustfs-setup.service, in 10.41 seconds)2041server: waiting for unit mock-oidc.service2042server: (finished: waiting for unit mock-oidc.service, in 0.07 seconds)2043server: waiting for unit niks3.service2044server # [ 21.927367] postgres[969]: [969] ERROR: relation "goose_db_version" does not exist at character 362045server # [ 21.929504] postgres[969]: [969] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC2046server # [ 21.958579] niks3-server[954]: 2026/09/19 10:55:34 OK 20241026095416_initial_model.sql (17.07ms)2047server # [ 21.966344] niks3-server[954]: 2026/09/19 10:55:34 OK 20251210153512_drop_unused_gin_index.sql (2.69ms)2048server # [ 21.970142] niks3-server[954]: 2026/09/19 10:55:34 OK 20251218171726_add_pins.sql (7.11ms)2049server # [ 21.976254] niks3-server[954]: 2026/09/19 10:55:34 OK 20260628120000_add_object_size_and_stats.sql (6.12ms)2050server # [ 21.982117] niks3-server[954]: 2026/09/19 10:55:34 OK 20260905000000_add_claims.sql (5.73ms)2051server # [ 21.983289] niks3-server[954]: 2026/09/19 10:55:34 goose: successfully migrated database to version: 202609050000002052server # [ 21.990741] niks3-server[954]: 2026/09/19 10:55:34 OK 1_commit_pending_closure.sql (8.59ms)2053server # [ 21.994205] niks3-server[954]: 2026/09/19 10:55:34 OK 2_object_stats_trigger.sql (3.47ms)2054server # [ 21.995329] niks3-server[954]: 2026/09/19 10:55:34 goose: up to current file version: 22055server # [ 22.002369] niks3-server[954]: 2026/09/19 10:55:34 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc2056server # [ 22.003744] niks3-server[954]: 2026/09/19 10:55:34 INFO OIDC authentication enabled config=/nix/store/sa3h30ggc0ih3s7pyx6hv16myf7hx0lj-niks3-oidc.json2057server # [ 22.005760] niks3-server[954]: 2026/09/19 10:55:34 INFO Loaded signing key name=niks3-test-1 path=/nix/store/bkg7syhrbck6xib4g0kaji8jiwjnl8sy-niks3-signing-key2058server # [ 22.033876] niks3-server[954]: 2026/09/19 10:55:34 INFO Using socket-activated listener address=0.0.0.0:57512059server # [ 22.036675] systemd[1]: Started niks3 server.2060server # [ 22.037366] niks3-server[954]: 2026/09/19 10:55:34 INFO systemd watchdog enabled interval=15s2061server # [ 22.038477] niks3-server[954]: 2026/09/19 10:55:34 INFO Starting HTTP server address=0.0.0.0:57512062server # [ 22.040925] systemd[1]: Reached target Multi-User System.2063server # [ 22.042198] systemd[1]: Startup finished in 845ms (kernel) + 3.683s (initrd) + 17.512s (userspace) = 22.041s.2064server: (finished: waiting for unit niks3.service, in 1.12 seconds)2065server: waiting for TCP port 5751 on localhost2066server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!2067server: (finished: waiting for TCP port 5751 on localhost, in 0.04 seconds)2068server: waiting for TCP port 8080 on localhost2069server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!2070server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds)2071server: waiting for TCP port 9000 on localhost2072server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!2073server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)2074server: must succeed: mkdir -p /tmp/test-config2075server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds)2076server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token2077server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)2078server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32079server # [ 23.283892] niks3-server[954]: 2026/09/19 10:55:35 INFO Received uploads request method=POST path=/api/pending_closures2080server # time=2026-09-19T10:55:35.355Z level=INFO msg="Uploading 5 paths to server (0 already cached)"2081server # time=2026-09-19T10:55:35.356Z level=INFO msg="Uploading lm3pknxi0ipypy3lxh1wmm8wvvavdwrn-glibc-2.42-84 (33.4MB)"2082server # time=2026-09-19T10:55:35.358Z level=INFO msg="Uploading xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 (273.1KB)"2083server # time=2026-09-19T10:55:35.360Z level=INFO msg="Uploading i3jw341xs88r6wxf1j22rjx1mmd8cfjw-libidn2-2.3.8 (359.5KB)"2084server # time=2026-09-19T10:55:35.361Z level=INFO msg="Uploading m07syxhld8hpprrdmzq565ziia6vlw9l-xgcc-15.3.0-libgcc (193.0KB)"2085server # time=2026-09-19T10:55:35.363Z level=INFO msg="Uploading bv3lx708clpz3m6zbd5742np4dlry0yj-libunistring-1.4.2 (2.0MB)"2086server # [ 23.504314] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=nar/1bvhsl1iqnbbgmxgp2hi0j8nj5ip5n6h6m2awanbnhwrl8fazjvh.nar.zst2087server # [ 23.548364] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=nar/1ixxaz0n8nfxd2w29qq8frn6pjvdnjj3pl139gbi7jksrj9jd7jg.nar.zst2088server # [ 23.571130] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.ls2089server # [ 23.575606] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=nar/1gz22gfl2lcahqzma75rvxgmk9zcfrvarzywlarwy4hkf1k4iz44.nar.zst2090server # [ 23.580949] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=bv3lx708clpz3m6zbd5742np4dlry0yj.ls2091server # [ 23.591605] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.ls2092server # [ 23.596527] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=nar/0g2qsgc7dv9zl3i5nc523gicpy0xk2z4r1x4msr0inmsqjpm1ym9.nar.zst2093server # [ 23.617973] niks3-server[954]: 2026/09/19 10:55:35 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.ls2094server # [ 24.176222] niks3-server[954]: 2026/09/19 10:55:36 INFO Received complete multipart upload request method=POST path=/api/multipart/complete2095server # [ 24.192536] niks3-server[954]: 2026/09/19 10:55:36 INFO Completed multipart upload object_key=nar/06kkplxk31y11zksgcrdjv0hf6ymlmxvlc0i2h08g3zc22is465s.nar.zst upload_id=ODQwZWZkZWMtYzdlNS00OTdhLWIwMTQtOTQzMGJmZjcxNmFjLmEzMGRhMjE1LTZhN2YtNGQwYy1hNzkzLWM4ZDk0ZDMwMzMyYXgxNzg5ODE1MzM1MzQzODk0NDAz parts=12096server # [ 24.201828] niks3-server[954]: 2026/09/19 10:55:36 INFO Received sign narinfos request method=POST path=/api/pending_closures/1/sign2097server # [ 24.205777] niks3-server[954]: 2026/09/19 10:55:36 INFO Signed narinfos id=1 count=52098server # time=2026-09-19T10:55:36.255Z level=INFO msg="Uploading 5 narinfos"2099server # [ 24.211070] niks3-server[954]: 2026/09/19 10:55:36 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.ls2100server # [ 24.230674] niks3-server[954]: 2026/09/19 10:55:36 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.narinfo2101server # [ 24.233230] niks3-server[954]: 2026/09/19 10:55:36 INFO Received complete upload request method=POST path=/api/pending_closures/1/complete2102server # [ 24.237351] niks3-server[954]: 2026/09/19 10:55:36 INFO Registered completed upload object_key=bv3lx708clpz3m6zbd5742np4dlry0yj.narinfo2103server # [ 24.239725] niks3-server[954]: 2026/09/19 10:55:36 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.narinfo2104server # [ 24.243581] niks3-server[954]: 2026/09/19 10:55:36 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.narinfo2105server # time=2026-09-19T10:55:36.296Z level=INFO msg="Upload complete. (1.018s)"2106server # [ 24.248897] niks3-server[954]: 2026/09/19 10:55:36 INFO Completed upload id=12107server # [ 24.252138] niks3-server[954]: 2026/09/19 10:55:36 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo2108server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 1.12 seconds)2109server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token2110server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)2111server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32112server # [ 24.337288] niks3-server[954]: 2026/09/19 10:55:36 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]2113server # [ 24.379441] niks3-server[954]: 2026/09/19 10:55:36 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]2114server # time=2026-09-19T10:55:36.430Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2115server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.12 seconds)2116server: waiting for unit nginx.service2117server: (finished: waiting for unit nginx.service, in 0.02 seconds)2118server: waiting for TCP port 443 on localhost2119server # Connection to localhost (::1) 443 port [tcp/https] succeeded!2120server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)2121server: must succeed: /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/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.32122server # time=2026-09-19T10:55:36.521Z 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.pem2123server # time=2026-09-19T10:55:36.537Z level=INFO msg="All 1 paths already cached"2124server: (finished: must succeed: /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/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.07 seconds)2125server: must fail: /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32126server # time=2026-09-19T10:55:36.552Z 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)"2127server: (finished: must fail: /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/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)2128server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32129server # time=2026-09-19T10:55:36.605Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem2130server # time=2026-09-19T10:55:36.614Z level=INFO msg="All 1 paths already cached"2131server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.06 seconds)2132server: 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'2133server # -----2134server: (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)2135server: 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.pem2136server # Certificate request self-signature ok2137server # subject=CN=other client2138server: (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)2139server: must fail: /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/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.32140server # time=2026-09-19T10:55:36.711Z 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.pem2141server # [ 24.670462] niks3-server[954]: 2026/09/19 10:55:36 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2142server # [ 24.712063] niks3-server[954]: 2026/09/19 10:55:36 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2143server # time=2026-09-19T10:55:36.762Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2144server: (finished: must fail: /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/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.11 seconds)2145server: must succeed: mkdir -p /tmp/test-store2146server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.01 seconds)2147server: must succeed: 2148 export AWS_ACCESS_KEY_ID=rustfsadmin2149export AWS_SECRET_ACCESS_KEY=rustfsadmin2150 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.321512152server # copying 5 paths...2153server # copying path '/nix/store/bv3lx708clpz3m6zbd5742np4dlry0yj-libunistring-1.4.2' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2154server # copying path '/nix/store/i3jw341xs88r6wxf1j22rjx1mmd8cfjw-libidn2-2.3.8' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2155server # copying path '/nix/store/m07syxhld8hpprrdmzq565ziia6vlw9l-xgcc-15.3.0-libgcc' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2156server # copying path '/nix/store/lm3pknxi0ipypy3lxh1wmm8wvvavdwrn-glibc-2.42-84' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2157server # copying path '/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2158server: (finished: must succeed: 2159 export AWS_ACCESS_KEY_ID=rustfsadmin2160export AWS_SECRET_ACCESS_KEY=rustfsadmin2161 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32162, in 0.37 seconds)2163server: must succeed: 2164cat > /tmp/test-drv.nix << 'EOF'2165derivation {2166 name = "test-build-log";2167 system = builtins.currentSystem;2168 builder = "/bin/sh";2169 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2170}2171EOF21722173server: (finished: must succeed: 2174cat > /tmp/test-drv.nix << 'EOF'2175derivation {2176 name = "test-build-log";2177 system = builtins.currentSystem;2178 builder = "/bin/sh";2179 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2180}2181EOF2182, in 0.01 seconds)2183server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix2184server # this derivation will be built:2185server # /nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2186server # building '/nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv'...2187server # test-build-log> test build log output2188server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.16 seconds)2189server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2190server # [ 25.378430] niks3-server[954]: 2026/09/19 10:55:37 INFO Received uploads request method=POST path=/api/pending_closures2191server # time=2026-09-19T10:55:37.441Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2192server # time=2026-09-19T10:55:37.442Z level=INFO msg="Uploading dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log (128B)"2193server # [ 25.409509] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst2194server # [ 25.416188] niks3-server[954]: 2026/09/19 10:55:37 INFO Received sign narinfos request method=POST path=/api/pending_closures/2/sign2195server # [ 25.418232] niks3-server[954]: 2026/09/19 10:55:37 INFO Signed narinfos id=2 count=12196server # [ 25.419731] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=log/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2197server # time=2026-09-19T10:55:37.470Z level=INFO msg="Uploading 1 narinfos"2198server # [ 25.427210] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.ls2199server # [ 25.431456] niks3-server[954]: 2026/09/19 10:55:37 INFO Received complete upload request method=POST path=/api/pending_closures/2/complete2200server # time=2026-09-19T10:55:37.482Z level=INFO msg="Upload complete. (98ms)"2201server # [ 25.434742] niks3-server[954]: 2026/09/19 10:55:37 INFO Completed upload id=22202server # [ 25.437686] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2203server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log, in 0.16 seconds)2204server: must succeed: 2205 export AWS_ACCESS_KEY_ID=rustfsadmin2206export AWS_SECRET_ACCESS_KEY=rustfsadmin2207 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log22082209server # got build log for '/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'2210server: (finished: must succeed: 2211 export AWS_ACCESS_KEY_ID=rustfsadmin2212export AWS_SECRET_ACCESS_KEY=rustfsadmin2213 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2214, in 0.08 seconds)2215subtest: push --stdin streams paths and reports each one2216server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'2217server # this derivation will be built:2218server # /nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2219server # building '/nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv'...2220server: (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.14 seconds)2221server: 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/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --stdin2222server # [ 25.760628] niks3-server[954]: 2026/09/19 10:55:37 INFO Received uploads request method=POST path=/api/pending_closures2223server # time=2026-09-19T10:55:37.814Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2224server # time=2026-09-19T10:55:37.815Z level=INFO msg="Uploading 3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test (120B)"2225server # [ 25.786342] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst2226server # [ 25.789358] niks3-server[954]: 2026/09/19 10:55:37 INFO Received sign narinfos request method=POST path=/api/pending_closures/3/sign2227server # [ 25.792512] niks3-server[954]: 2026/09/19 10:55:37 INFO Signed narinfos id=3 count=12228server # time=2026-09-19T10:55:37.842Z level=INFO msg="Uploading 1 narinfos"2229server # [ 25.797492] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=log/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2230server # [ 25.801056] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.ls2231server # [ 25.804852] niks3-server[954]: 2026/09/19 10:55:37 INFO Received complete upload request method=POST path=/api/pending_closures/3/complete2232server # time=2026-09-19T10:55:37.857Z level=INFO msg="Upload complete. (92ms)"2233server # [ 25.809644] niks3-server[954]: 2026/09/19 10:55:37 INFO Completed upload id=32234server # [ 25.811293] niks3-server[954]: 2026/09/19 10:55:37 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.narinfo2235server: (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/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --stdin, in 0.15 seconds)2236server: must succeed: 2237 export AWS_ACCESS_KEY_ID=rustfsadmin2238export AWS_SECRET_ACCESS_KEY=rustfsadmin2239 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2240 2241server # copying 1 paths...2242server # copying path '/nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2243server: (finished: must succeed: 2244 export AWS_ACCESS_KEY_ID=rustfsadmin2245export AWS_SECRET_ACCESS_KEY=rustfsadmin2246 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2247 , in 0.11 seconds)2248(finished: subtest: push --stdin streams paths and reports each one, in 0.40 seconds)2249server: must succeed: 2250cat > /tmp/ca-test.nix << 'EOF'2251derivation {2252 name = "ca-test";2253 system = builtins.currentSystem;2254 builder = "/bin/sh";2255 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];2256 __contentAddressed = true;2257 outputHashMode = "recursive";2258 outputHashAlgo = "sha256";2259}2260EOF22612262server: (finished: must succeed: 2263cat > /tmp/ca-test.nix << 'EOF'2264derivation {2265 name = "ca-test";2266 system = builtins.currentSystem;2267 builder = "/bin/sh";2268 args = [ "-c" "echo 'Hello from CA derivation' > $out" ];2269 __contentAddressed = true;2270 outputHashMode = "recursive";2271 outputHashAlgo = "sha256";2272}2273EOF2274, in 0.01 seconds)2275server: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link2276server # this derivation will be built:2277server # /nix/store/r63z2m5ya18s9prsh98knax41688hrbd-ca-test.drv2278server # building '/nix/store/r63z2m5ya18s9prsh98knax41688hrbd-ca-test.drv'...2279server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/ca-test.nix --no-out-link, in 0.14 seconds)2280server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2281server # [ 26.210277] niks3-server[954]: 2026/09/19 10:55:38 INFO Received uploads request method=POST path=/api/pending_closures2282server # time=2026-09-19T10:55:38.263Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2283server # time=2026-09-19T10:55:38.264Z level=INFO msg="Uploading 5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test (144B)"2284server # [ 26.233795] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=nar/04jj7iiy79vhzp5kk3r2751n9x3xa44j98y5bk1ds4jp31kky74n.nar.zst2285server # [ 26.237872] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=log/r63z2m5ya18s9prsh98knax41688hrbd-ca-test.drv2286server # [ 26.241616] niks3-server[954]: 2026/09/19 10:55:38 INFO Received sign narinfos request method=POST path=/api/pending_closures/4/sign2287server # [ 26.243638] niks3-server[954]: 2026/09/19 10:55:38 INFO Signed narinfos id=4 count=12288server # time=2026-09-19T10:55:38.293Z level=INFO msg="Uploading 1 narinfos"2289server # [ 26.248485] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.ls2290server # [ 26.251989] niks3-server[954]: 2026/09/19 10:55:38 INFO Received complete upload request method=POST path=/api/pending_closures/4/complete2291server # [ 26.257194] niks3-server[954]: 2026/09/19 10:55:38 INFO Completed upload id=42292server # time=2026-09-19T10:55:38.306Z level=INFO msg="Upload complete. (127ms)"2293server # [ 26.260887] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=5v8l1dc99hcg2ss10dgkivlhwbkpppjh.narinfo2294server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.18 seconds)2295server: must succeed: mkdir -p /tmp/chroot-store2296server: (finished: must succeed: mkdir -p /tmp/chroot-store, in 0.01 seconds)2297server: 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/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test23012302server # copying 1 paths...2303server # copying path '/nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2304server: (finished: must succeed: 2305 export AWS_ACCESS_KEY_ID=rustfsadmin2306export AWS_SECRET_ACCESS_KEY=rustfsadmin2307 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/chroot-store /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2308, in 0.11 seconds)2309server: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2310server: (finished: must succeed: nix --store /tmp/chroot-store store cat /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.05 seconds)2311server: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2312server # warning: 'realisation' is a deprecated alias for 'store build-trace'2313server: (finished: must succeed: nix --store /tmp/chroot-store realisation info /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test, in 0.05 seconds)2314server: must succeed: readlink /etc/niks3-test/symlink-wrapper2315server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.01 seconds)2316server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2317server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds)2318server: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2319server: (finished: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds)2320server: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2321server: (finished: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds)2322server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2323server # [ 26.613558] niks3-server[954]: 2026/09/19 10:55:38 INFO Received uploads request method=POST path=/api/pending_closures2324server # time=2026-09-19T10:55:38.666Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2325server # time=2026-09-19T10:55:38.667Z level=INFO msg="Uploading 1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper (192B)"2326server # time=2026-09-19T10:55:38.669Z level=INFO msg="Uploading x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package (536B)"2327server # [ 26.637043] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=nar/0v342j6sk8pcizzisl5bwzw5xdrdjdmynpgxd3f6827yp5bzbrl7.nar.zst2328server # [ 26.646620] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2329server # [ 26.650756] niks3-server[954]: 2026/09/19 10:55:38 INFO Received sign narinfos request method=POST path=/api/pending_closures/5/sign2330server # [ 26.652686] niks3-server[954]: 2026/09/19 10:55:38 INFO Signed narinfos id=5 count=22331server # time=2026-09-19T10:55:38.702Z level=INFO msg="Uploading 2 narinfos"2332server # [ 26.657905] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.ls2333server # [ 26.665500] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.ls2334server # [ 26.670114] niks3-server[954]: 2026/09/19 10:55:38 INFO Received complete upload request method=POST path=/api/pending_closures/5/complete2335server # [ 26.672586] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.narinfo2336server # time=2026-09-19T10:55:38.726Z level=INFO msg="Upload complete. (106ms)"2337server # [ 26.678858] niks3-server[954]: 2026/09/19 10:55:38 INFO Completed upload id=52338server # [ 26.680099] niks3-server[954]: 2026/09/19 10:55:38 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.narinfo2339server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.16 seconds)2340server: must succeed: 2341 export AWS_ACCESS_KEY_ID=rustfsadmin2342export AWS_SECRET_ACCESS_KEY=rustfsadmin2343 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper23442345server # copying 2 paths...2346server # copying path '/nix/store/x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2347server # copying path '/nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2348server: (finished: must succeed: 2349 export AWS_ACCESS_KEY_ID=rustfsadmin2350export AWS_SECRET_ACCESS_KEY=rustfsadmin2351 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2352, in 0.10 seconds)2353server: must succeed: 2354cat > /tmp/oidc-test.nix << 'EOF'2355derivation {2356 name = "oidc-test";2357 system = builtins.currentSystem;2358 builder = "/bin/sh";2359 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2360}2361EOF23622363server: (finished: must succeed: 2364cat > /tmp/oidc-test.nix << 'EOF'2365derivation {2366 name = "oidc-test";2367 system = builtins.currentSystem;2368 builder = "/bin/sh";2369 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2370}2371EOF2372, in 0.01 seconds)2373server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2374server # this derivation will be built:2375server # /nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2376server # building '/nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv'...2377server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.14 seconds)2378server: 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'2379server: (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.02 seconds)2380server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk4MTg5MzksImlhdCI6MTc4OTgxNTMzOSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.WYMWzuVEUax8N-UPank_Ig4g7VpbcSE-lxlMwXZxk7qpW4twcv8YYH4HguqnaleIHpYrK8zxYXbdOoPw3GeFJkG8uZx5YlSHqvZhNIvN6IIDS5KFrYfehZLmxVKom-An9lq8QZa3yydsW8plhwrnm4vcUDJcy3RUFCIgynh0vRw-xKloWq4qPxbE2yDBhB3ywMWyHeNHyxhZoZ78jPfANNNEEF79rySRHqzn9yAyy4e72J7pEcSl9KlcVjUp9lS5bD8d_jnLoNeS0Pwrey5OMvDGh_7546j6s3xJHiN1bX4wnjT1pkNCeee3OfloAjtGTVhUi2T5cWq9GfDVJlazzQ' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2381server # time=2026-09-19T10:55:39.020Z 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"2382server # [ 27.017388] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2383server # [ 27.059121] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2384server # [ 27.060365] niks3-server[954]: 2026/09/19 10:55:39 INFO Received uploads request method=POST path=/api/pending_closures2385server # time=2026-09-19T10:55:39.113Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2386server # time=2026-09-19T10:55:39.114Z level=INFO msg="Uploading hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test (136B)"2387server # [ 27.078394] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2388server # [ 27.084646] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2389server # [ 27.091458] niks3-server[954]: 2026/09/19 10:55:39 INFO Registered completed upload object_key=log/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2390server # [ 27.093934] niks3-server[954]: 2026/09/19 10:55:39 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2391server # [ 27.096557] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2392server # [ 27.098171] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2393server # [ 27.099309] niks3-server[954]: 2026/09/19 10:55:39 INFO Received sign narinfos request method=POST path=/api/pending_closures/6/sign2394server # [ 27.102188] niks3-server[954]: 2026/09/19 10:55:39 INFO Signed narinfos id=6 count=12395server # time=2026-09-19T10:55:39.152Z level=INFO msg="Uploading 1 narinfos"2396server # [ 27.105505] niks3-server[954]: 2026/09/19 10:55:39 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.ls2397server # [ 27.110725] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2398server # [ 27.111955] niks3-server[954]: 2026/09/19 10:55:39 INFO Received complete upload request method=POST path=/api/pending_closures/6/complete2399server # [ 27.113711] niks3-server[954]: 2026/09/19 10:55:39 INFO OIDC auth successful provider=test scopes=[write]2400server # time=2026-09-19T10:55:39.164Z level=INFO msg="Upload complete. (102ms)"2401server # [ 27.116818] niks3-server[954]: 2026/09/19 10:55:39 INFO Completed upload id=62402server # [ 27.119606] niks3-server[954]: 2026/09/19 10:55:39 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.narinfo2403server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk4MTg5MzksImlhdCI6MTc4OTgxNTMzOSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.WYMWzuVEUax8N-UPank_Ig4g7VpbcSE-lxlMwXZxk7qpW4twcv8YYH4HguqnaleIHpYrK8zxYXbdOoPw3GeFJkG8uZx5YlSHqvZhNIvN6IIDS5KFrYfehZLmxVKom-An9lq8QZa3yydsW8plhwrnm4vcUDJcy3RUFCIgynh0vRw-xKloWq4qPxbE2yDBhB3ywMWyHeNHyxhZoZ78jPfANNNEEF79rySRHqzn9yAyy4e72J7pEcSl9KlcVjUp9lS5bD8d_jnLoNeS0Pwrey5OMvDGh_7546j6s3xJHiN1bX4wnjT1pkNCeee3OfloAjtGTVhUi2T5cWq9GfDVJlazzQ' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test, in 0.16 seconds)2404server: must succeed: 2405cat > /tmp/oidc-test2.nix << 'EOF'2406derivation {2407 name = "oidc-test2";2408 system = builtins.currentSystem;2409 builder = "/bin/sh";2410 args = [ "-c" "echo 'OIDC test 2' > $out" ];2411}2412EOF24132414server: (finished: must succeed: 2415cat > /tmp/oidc-test2.nix << 'EOF'2416derivation {2417 name = "oidc-test2";2418 system = builtins.currentSystem;2419 builder = "/bin/sh";2420 args = [ "-c" "echo 'OIDC test 2' > $out" ];2421}2422EOF2423, in 0.01 seconds)2424server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2425server # this derivation will be built:2426server # /nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv2427server # building '/nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv'...2428server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.14 seconds)2429server: 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'2430server: (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.02 seconds)2431server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk4MTg5MzksImlhdCI6MTc4OTgxNTMzOSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.KvCFrscxfmeenViLKrZ4ROUVZEeQc13GRFudygItNqnHQYOPbtEVHG0yZX93DgsCGO2GoPMy0q37gn3kXMpWvUAXlZVTgfnVaGGV1oLcpuEjfNs0Vh6Z8aBXF3Sx8MTHKOCoa6lCOMlRzPl707sNI-dLG0T7mjqusJ341-FD265CaQznUgQAErYvAQXsr1TBfaxL20LMJqRjb6P8j824Yep1GJtUfNITHVqp4dzSPsILsSRAwdSQGsTrQCj1-IztRcEivUZaOa0dEMVp7OlznoHrdI33A6RGGD3zc8JnjS1DGJKv8wTjriaKykmKXpJ0DXW1bRsNz2uBwOzMOa0JRg' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22432server # time=2026-09-19T10:55:39.358Z 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"2433server # [ 27.354768] niks3-server[954]: 2026/09/19 10:55:39 WARN Authentication failed token_preview=eyJhbGciOi...wOzMOa0JRg token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2434server # [ 27.396686] niks3-server[954]: 2026/09/19 10:55:39 WARN Authentication failed token_preview=eyJhbGciOi...wOzMOa0JRg token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2435server # time=2026-09-19T10:55:39.448Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2436server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3ODk4MTg5MzksImlhdCI6MTc4OTgxNTMzOSwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.KvCFrscxfmeenViLKrZ4ROUVZEeQc13GRFudygItNqnHQYOPbtEVHG0yZX93DgsCGO2GoPMy0q37gn3kXMpWvUAXlZVTgfnVaGGV1oLcpuEjfNs0Vh6Z8aBXF3Sx8MTHKOCoa6lCOMlRzPl707sNI-dLG0T7mjqusJ341-FD265CaQznUgQAErYvAQXsr1TBfaxL20LMJqRjb6P8j824Yep1GJtUfNITHVqp4dzSPsILsSRAwdSQGsTrQCj1-IztRcEivUZaOa0dEMVp7OlznoHrdI33A6RGGD3zc8JnjS1DGJKv8wTjriaKykmKXpJ0DXW1bRsNz2uBwOzMOa0JRg' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.11 seconds)2437server: 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'2438server: (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)2439server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4OTgxODkzOSwiaWF0IjoxNzg5ODE1MzM5LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.bM6U6hDfHmmIZFyaE76BTY6YKqbXtQRqC4nJPD_jC_7aA9NKEV4sBIlU9eErq8Kro2RkPMwjHHr7bbk1NYjhUmHhY8zsvte7cjDkTJ7u4DvXC098jXhjAH41JuoPUmGroCZlZf_ST1kIaz9QalK9P8bO_zPB8zTq1m0mzCRX2YYF2twq9KaUVmVHc3z7b6GsKlHQVfdqIxGbC-gljdRFsbih8sjLu4X3vPsZ8mCyhd-kYwgZZUH82y1wYC4hn_9V2DsGuiPmFYcRJxe2Mlc2woSDpPyl3bdYt9An_AngvZh1LknKTkzIzQmOcSaOjfj4BuRwiYnIMSDokh2dNbkh-g' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22440server # time=2026-09-19T10:55:39.485Z 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"2441server # [ 27.481070] niks3-server[954]: 2026/09/19 10:55:39 WARN Authentication failed token_preview=eyJhbGciOi...kh2dNbkh-g token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2442server # [ 27.522432] niks3-server[954]: 2026/09/19 10:55:39 WARN Authentication failed token_preview=eyJhbGciOi...kh2dNbkh-g token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2443server # time=2026-09-19T10:55:39.573Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2444server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRIWFRTQ3lvdXE2RGlXYVF3bFh0TlA1NC1DNzVtdzNJY29Za0VSZmwzZlEiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc4OTgxODkzOSwiaWF0IjoxNzg5ODE1MzM5LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.bM6U6hDfHmmIZFyaE76BTY6YKqbXtQRqC4nJPD_jC_7aA9NKEV4sBIlU9eErq8Kro2RkPMwjHHr7bbk1NYjhUmHhY8zsvte7cjDkTJ7u4DvXC098jXhjAH41JuoPUmGroCZlZf_ST1kIaz9QalK9P8bO_zPB8zTq1m0mzCRX2YYF2twq9KaUVmVHc3z7b6GsKlHQVfdqIxGbC-gljdRFsbih8sjLu4X3vPsZ8mCyhd-kYwgZZUH82y1wYC4hn_9V2DsGuiPmFYcRJxe2Mlc2woSDpPyl3bdYt9An_AngvZh1LknKTkzIzQmOcSaOjfj4BuRwiYnIMSDokh2dNbkh-g' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.10 seconds)2445server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22446server # time=2026-09-19T10:55:39.589Z 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"2447server # [ 27.584119] niks3-server[954]: 2026/09/19 10:55:39 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]2448server # [ 27.626630] niks3-server[954]: 2026/09/19 10:55:39 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]2449server # time=2026-09-19T10:55:39.677Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating pending closure: server returned 401: Unauthorized\n"2450server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.10 seconds)2451server: must succeed: 2452 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.324532454server # [ 27.687960] niks3-server[954]: 2026/09/19 10:55:39 INFO Received create pin request method=POST path=/api/pins/hello-pin2455server # [ 27.697199] niks3-server[954]: 2026/09/19 10:55:39 INFO Created/updated pin name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 narinfo_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo2456server # time=2026-09-19T10:55:39.748Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32457server: (finished: must succeed: 2458 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32459, in 0.07 seconds)2460server: must succeed: 2461 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list24622463server # [ 27.757478] niks3-server[954]: 2026/09/19 10:55:39 INFO Received list pins request method=GET path=/api/pins2464server: (finished: must succeed: 2465 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list2466, in 0.06 seconds)2467server: must succeed: 2468 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --names-only24692470server # [ 27.813710] niks3-server[954]: 2026/09/19 10:55:39 INFO Received list pins request method=GET path=/api/pins2471server: (finished: must succeed: 2472 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --names-only2473, in 0.06 seconds)2474server: must succeed: 2475 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --json24762477server # [ 27.869948] niks3-server[954]: 2026/09/19 10:55:39 INFO Received list pins request method=GET path=/api/pins2478server: (finished: must succeed: 2479 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --json2480, in 0.06 seconds)2481server: must succeed: 2482 export S3_ENDPOINT_URL=http://localhost:90002483 export AWS_ACCESS_KEY_ID=rustfsadmin2484 export AWS_SECRET_ACCESS_KEY=rustfsadmin2485 /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin24862487server: (finished: must succeed: 2488 export S3_ENDPOINT_URL=http://localhost:90002489 export AWS_ACCESS_KEY_ID=rustfsadmin2490 export AWS_SECRET_ACCESS_KEY=rustfsadmin2491 /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2492, in 0.02 seconds)2493server: must succeed: 2494 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log24952496server # time=2026-09-19T10:55:40.002Z level=INFO msg="All 1 paths already cached"2497server # [ 27.955504] niks3-server[954]: 2026/09/19 10:55:40 INFO Received create pin request method=POST path=/api/pins/ca-pin2498server # time=2026-09-19T10:55:40.011Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2499server # [ 27.964468] niks3-server[954]: 2026/09/19 10:55:40 INFO Created/updated pin name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log narinfo_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2500server: (finished: must succeed: 2501 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2502, in 0.07 seconds)2503server: must succeed: 2504 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --names-only25052506server # [ 28.022626] niks3-server[954]: 2026/09/19 10:55:40 INFO Received list pins request method=GET path=/api/pins2507server: (finished: must succeed: 2508 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --names-only2509, in 0.06 seconds)2510server: must succeed: 2511 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins delete hello-pin25122513server # [ 28.079063] niks3-server[954]: 2026/09/19 10:55:40 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2514server # time=2026-09-19T10:55:40.136Z level=INFO msg="Deleted pin" name=hello-pin2515server # [ 28.088634] niks3-server[954]: 2026/09/19 10:55:40 INFO Deleted pin name=hello-pin2516server: (finished: must succeed: 2517 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins delete hello-pin2518, in 0.06 seconds)2519server: must succeed: 2520 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --names-only25212522server # [ 28.146201] niks3-server[954]: 2026/09/19 10:55:40 INFO Received list pins request method=GET path=/api/pins2523server: (finished: must succeed: 2524 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins list --names-only2525, in 0.06 seconds)2526server: must fail: 2527 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent25282529server # [ 28.202617] niks3-server[954]: 2026/09/19 10:55:40 INFO Received create pin request method=POST path=/api/pins/bad-pin2530server # [ 28.204470] niks3-server[954]: 2026/09/19 10:55:40 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2531server # time=2026-09-19T10:55:40.255Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2532server: (finished: must fail: 2533 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/4r1vklpv3f2brr3ya7my5vfsngzqibmn-niks3-1.12.0-beta.1/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2534, in 0.06 seconds)2535server: must succeed: systemctl start niks3-gc.service2536server # [ 28.228968] systemd[1]: Starting niks3 garbage collection...2537server # [ 28.287283] niks3[1538]: time=2026-09-19T10:55:40.335Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2538server # [ 28.290758] niks3-server[954]: 2026/09/19 10:55:40 INFO Starting cleanup of old closures method=DELETE path=/api/closures2539server # [ 28.292111] niks3[1538]: time=2026-09-19T10:55:40.339Z level=INFO msg="Garbage collection started"2540server # [ 28.296232] niks3-server[954]: 2026/09/19 10:55:40 INFO Aborted multipart uploads count=02541server # [ 28.307470] niks3-server[954]: 2026/09/19 10:55:40 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=4 objects-deleted-after-grace-period=0 objects-failed-to-delete=02542server # [ 28.313645] niks3-server[954]: 2026/09/19 10:55:40 INFO Vacuumed table table=pending_closures2543server # [ 28.318215] niks3-server[954]: 2026/09/19 10:55:40 INFO Vacuumed table table=pending_objects2544server # [ 28.322964] niks3-server[954]: 2026/09/19 10:55:40 INFO Vacuumed table table=multipart_uploads2545server # [ 28.326073] niks3-server[954]: 2026/09/19 10:55:40 INFO Vacuumed table table=closures2546server # [ 28.329155] niks3-server[954]: 2026/09/19 10:55:40 INFO Vacuumed table table=objects2547server # [ 30.293759] niks3[1538]: time=2026-09-19T10:55:42.341Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=4 objects_deleted=0 objects_failed=02548server # [ 30.301177] niks3[1538]: time=2026-09-19T10:55:42.341Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=4 objects-deleted-after-grace-period=0 objects-failed-to-delete=02549server # [ 30.311507] systemd[1]: niks3-gc.service: Deactivated successfully.2550server # [ 30.315161] systemd[1]: Finished niks3 garbage collection.2551server # [ 30.320265] systemd[1]: niks3-gc.service: Consumed 36ms CPU time over 2.085s wall clock time, 2.7M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2552server: (finished: must succeed: systemctl start niks3-gc.service, in 2.12 seconds)2553builder: waiting for unit niks3-auto-upload.socket2554builder: waiting for the VM to finish booting2555builder: Guest shell says: b'Spawning backdoor root shell...\n'2556builder: connected to guest root shell2557builder: (connecting took 0.00 seconds)2558builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2559builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.05 seconds)2560builder: must succeed: test -S /run/niks3/upload-to-cache.sock2561builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.01 seconds)2562builder: must succeed: grep post-build-hook /etc/nix/nix.conf2563builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.01 seconds)2564builder: must succeed: 2565cat > /tmp/test-drv.nix << 'EOF'2566derivation {2567 name = "post-build-hook-test";2568 system = builtins.currentSystem;2569 builder = "/bin/sh";2570 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2571}2572EOF25732574builder: (finished: must succeed: 2575cat > /tmp/test-drv.nix << 'EOF'2576derivation {2577 name = "post-build-hook-test";2578 system = builtins.currentSystem;2579 builder = "/bin/sh";2580 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2581}2582EOF2583, in 0.01 seconds)2584builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2585builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 27 ms (attempt 1/5)2586builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 119 ms (attempt 2/5)2587builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 104 ms (attempt 3/5)2588builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 169 ms (attempt 4/5)2589builder # warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org2590builder # this derivation will be built:2591builder # /nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2592builder # building '/nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv'...2593builder # [ 31.041557] systemd[1]: Started niks3 auto-upload daemon.2594builder # [ 31.164358] niks3-hook[809]: time=2026-09-19T10:55:42.517Z 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=0s2595builder # [ 31.173899] niks3-hook[809]: time=2026-09-19T10:55:42.527Z level=INFO msg="Upload queue status" pending=12596builder # [ 31.175508] niks3-hook[809]: time=2026-09-19T10:55:42.527Z level=INFO msg="Uploading batch" count=12597builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 0.80 seconds)2598builder: waiting for unit niks3-auto-upload.service2599builder: (finished: waiting for unit niks3-auto-upload.service, in 0.06 seconds)2600??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2601 File "/nix/store/q8bka5k1zvss9dd482k8ivsflp9lkmxw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392602builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2603??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2604 File "/nix/store/q8bka5k1zvss9dd482k8ivsflp9lkmxw-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392605builder # [ 31.248993] systemd[1]: Started Nix Daemon.2606builder # [ 31.302746] nix-daemon[828]: accepted connection from pid 821, user root (trusted)2607builder # [ 31.313082] nix-daemon[828]: reaped child process 835, status = succeeded2608server # [ 31.341526] niks3-server[954]: 2026/09/19 10:55:43 INFO Received uploads request method=POST path=/api/pending_closures2609builder # [ 31.331202] niks3-hook[809]: time=2026-09-19T10:55:42.684Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2610builder # [ 31.332704] niks3-hook[809]: time=2026-09-19T10:55:42.686Z level=INFO msg="Uploading ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test (144B)"2611server # [ 31.379377] niks3-server[954]: 2026/09/19 10:55:43 INFO Registered completed upload object_key=log/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2612server # [ 31.394112] niks3-server[954]: 2026/09/19 10:55:43 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2613server # [ 31.405437] niks3-server[954]: 2026/09/19 10:55:43 INFO Received sign narinfos request method=POST path=/api/pending_closures/7/sign2614server # [ 31.410512] niks3-server[954]: 2026/09/19 10:55:43 INFO Signed narinfos id=7 count=12615builder # [ 31.392134] niks3-hook[809]: time=2026-09-19T10:55:42.745Z level=INFO msg="Uploading 1 narinfos"2616server # [ 31.419276] niks3-server[954]: 2026/09/19 10:55:43 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.ls2617server # [ 31.427059] niks3-server[954]: 2026/09/19 10:55:43 INFO Received complete upload request method=POST path=/api/pending_closures/7/complete2618server # [ 31.431669] niks3-server[954]: 2026/09/19 10:55:43 INFO Completed upload id=72619server # [ 31.433654] niks3-server[954]: 2026/09/19 10:55:43 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.narinfo2620builder # [ 31.411546] niks3-hook[809]: time=2026-09-19T10:55:42.765Z level=INFO msg="Upload complete. (238ms)"2621builder # [ 36.173204] niks3-hook[809]: time=2026-09-19T10:55:47.526Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2622builder # [ 36.178521] niks3-hook[809]: time=2026-09-19T10:55:47.530Z level=INFO msg="niks3-hook serve stopped"2623builder # [ 36.192187] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2624builder # [ 36.196464] systemd[1]: niks3-auto-upload.service: Consumed 133ms CPU time over 5.153s wall clock time, 21.2M memory peak, 68K written to disk, 5.3K incoming IP traffic, 7.9K outgoing IP traffic.2625builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.23 seconds)2626server: must succeed: 2627 export AWS_ACCESS_KEY_ID=rustfsadmin2628export AWS_SECRET_ACCESS_KEY=rustfsadmin2629 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-test26302631server # copying 1 paths...2632server # copying path '/nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test' from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1'...2633server: (finished: must succeed: 2634 export AWS_ACCESS_KEY_ID=rustfsadmin2635export AWS_SECRET_ACCESS_KEY=rustfsadmin2636 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-test2637, in 0.14 seconds)2638server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2639server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test, in 0.05 seconds)2640(finished: run the VM test script, in 37.57 seconds)2641test script finished in 37.84s2642cleanup2643kill QemuMachine (pid 47)2644builder # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2645builder # [2026-09-19T10:55:49Z INFO virtiofsd] Client disconnected, shutting down2646builder # [2026-09-19T10:55:49Z INFO virtiofsd] Client disconnected, shutting down2647builder # [2026-09-19T10:55:49Z INFO virtiofsd] Client disconnected, shutting down2648kill QemuMachine (pid 48)2649server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2650server # [2026-09-19T10:55:49Z INFO virtiofsd] Client disconnected, shutting down2651server # [2026-09-19T10:55:49Z INFO virtiofsd] Client disconnected, shutting down2652server # [2026-09-19T10:55:49Z INFO virtiofsd] Client disconnected, shutting down2653(finished: cleanup, in 0.52 seconds)2654additionally exposed symbols:2655 builder, server,2656 vlan1,2657 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_ssh2658Hello store path: /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32659Test output path: /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2660CA derivation output path: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test2661Realisation info in chroot store: /nix/store/5v8l1dc99hcg2ss10dgkivlhwbkpppjh-ca-test26622663Symlink wrapper store path: /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2664Symlink wrapper points to: /nix/store/x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package/bin/test-program2665OIDC test store path: /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2666Valid OIDC token obtained (length=677)2667OIDC push with valid token: SUCCESS2668Invalid OIDC token obtained (wrong org)2669OIDC push with wrong org: correctly rejected2670Wrong audience OIDC token obtained2671OIDC push with wrong audience: correctly rejected2672OIDC push with malformed token: correctly rejected2673All OIDC tests passed!2674All pin tests passed!2675Built store path: /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2676Post-build-hook pipeline test passed!