vm-test-run-nixos-test-niks3
checks.x86_64-linux.nixos-test-niks3-lix
· build #301
· 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 vm10builder # Disk image does not exist, creating the virtualisation disk image...11builder: QEMU running (pid 48)12builder # Formatting '/build/vm-state-builder/tmp.fNFxFzlv4T', fmt=raw size=107374182413builder # mke2fs 1.47.4 (6-Mar-2025)14builder # Discarding device blocks: 0/262144 done15builder # Creating filesystem with 262144 4k blocks and 65536 inodes16builder # Filesystem UUID: 853dee97-ef0a-4222-b5eb-835a445c35e017builder # Superblock backups stored on blocks:18builder # 32768, 98304, 163840, 22937619builder # 20builder # Allocating group tables: 0/8 done21builder # Writing inode tables: 0/8 done22builder # Creating journal (8192 blocks): done23builder # Writing superblocks and filesystem accounting information: 0/8 done24builder # 25builder # Virtualisation disk image created.26builder # Starting virtiofs daemons...27builder # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28builder # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29builder # [2026-10-01T08:26:46Z INFO virtiofsd] Waiting for vhost-user socket connection...30builder # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31builder # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32builder # [2026-10-01T08:26:46Z INFO virtiofsd] Waiting for vhost-user socket connection...33builder # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34builder # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35builder # [2026-10-01T08:26:46Z INFO virtiofsd] Waiting for vhost-user socket connection...36builder # [2026-10-01T08:26:46Z INFO virtiofsd] Client connected, servicing requests37builder # [2026-10-01T08:26:46Z INFO virtiofsd] Client connected, servicing requests38builder # [2026-10-01T08:26:46Z INFO virtiofsd] Client connected, servicing requests39server: QEMU running (pid 47)40server # Disk image does not exist, creating the virtualisation disk image...41server # Formatting '/build/vm-state-server/tmp.71RJ7BZTuV', fmt=raw size=107374182442(finished: start all VMs, in 0.26 seconds)43server # mke2fs 1.47.4 (6-Mar-2025)44server: waiting for unit postgresql.service45server # Discarding device blocks: 0/262144 done46server: waiting for the VM to finish booting47server # Creating filesystem with 262144 4k blocks and 65536 inodes48server # Filesystem UUID: 57c17e64-9225-4e37-9989-3ae6127bc32149server # Superblock backups stored on blocks:50server # 32768, 98304, 163840, 22937651server # 52server # Allocating group tables: 0/8 done53server # Writing inode tables: 0/8 done54server # Creating journal (8192 blocks): done55server # Writing superblocks and filesystem accounting information: 0/8 done56server # 57server # Virtualisation disk image created.58server # Starting virtiofs daemons...59server # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)60server # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61server # [2026-10-01T08:26:46Z INFO virtiofsd] Waiting for vhost-user socket connection...62server # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63server # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64server # [2026-10-01T08:26:46Z INFO virtiofsd] Waiting for vhost-user socket connection...65server # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66server # [2026-10-01T08:26:46Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether67server # [2026-10-01T08:26:46Z INFO virtiofsd] Waiting for vhost-user socket connection...68server # [2026-10-01T08:26:46Z INFO virtiofsd] Client connected, servicing requests69server # [2026-10-01T08:26:46Z INFO virtiofsd] Client connected, servicing requests70server # [2026-10-01T08:26:46Z INFO virtiofsd] Client connected, servicing requests71builder # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)72server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)73builder # 74builder # 75builder # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0076builder # Press Ctrl-B to configure iPXE (PCI 00:02.0)...77builder # 78builder # 79builder # 80builder # 81builder # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0082builder # Press Ctrl-B to configure iPXE (PCI 00:05.0)...83builder # 84builder # 85server # 86server # 87server # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0088server # Press Ctrl-B to configure iPXE (PCI 00:02.0)...89server # 90server # 91server # 92server # 93builder # Booting from ROM...94server # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0095server # Press Ctrl-B to configure iPXE (PCI 00:05.0)...96server # 97server # 98server # Booting from ROM...99builder # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026100builder # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/01ph8r57zn7q7j8gckshmla2m1yn047z-nixos-system-builder-test/init regInfo=/nix/.ro-store/ps914crxrzdsfph2pj219nxqz2bd3q6s-closure-info/registration console=ttyS0,115200n8 console=tty0101builder # [ 0.000000] BIOS-provided physical RAM map:102builder # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable103builder # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved104builder # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved105builder # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable106builder # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved107builder # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved108builder # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved109builder # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved110builder # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved111builder # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved112builder # [ 0.000000] NX (Execute Disable) protection: active113builder # [ 0.000000] APIC: Static calls initialized114builder # [ 0.000000] SMBIOS 2.8 present.115builder # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014116builder # [ 0.000000] DMI: Memory slots populated: 1/1117builder # [ 0.000000] Hypervisor detected: KVM118builder # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000119builder # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00120builder # [ 0.000000] kvm-clock: using sched offset of 458344240 cycles121builder # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns122builder # [ 0.000004] tsc: Detected 2400.012 MHz processor123builder # [ 0.000811] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000124builder # [ 0.000837] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs125builder # [ 0.000840] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT126builder # [ 0.002768] found SMP MP-table at [mem 0x000f5450-0x000f545f]127builder # [ 0.002779] Using GB pages for direct mapping128builder # [ 0.002891] RAMDISK: [mem 0x3e369000-0x3ffcffff]129builder # [ 0.002898] ACPI: Early table checksum verification disabled130builder # [ 0.002900] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )131builder # [ 0.002904] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)132builder # [ 0.002908] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)133builder # [ 0.002914] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)134builder # [ 0.002916] ACPI: FACS 0x000000003FFE0000 000040135builder # [ 0.002918] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)136builder # [ 0.002919] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)137builder # [ 0.002921] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)138builder # [ 0.002922] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)139builder # [ 0.002924] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]140builder # [ 0.002925] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]141builder # [ 0.002925] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]142builder # [ 0.002926] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]143builder # [ 0.002926] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]144builder # [ 0.002927] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]145builder # [ 0.002927] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]146builder # [ 0.003143] No NUMA configuration found147builder # [ 0.003144] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]148builder # [ 0.003146] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]149builder # [ 0.005209] Zone ranges:150builder # [ 0.005209] DMA [mem 0x0000000000001000-0x0000000000ffffff]151builder # [ 0.005211] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]152builder # [ 0.005212] Normal empty153builder # [ 0.005212] Device empty154builder # [ 0.005213] Movable zone start for each node155builder # [ 0.005214] Early memory node ranges156builder # [ 0.005214] node 0: [mem 0x0000000000001000-0x000000000009efff]157builder # [ 0.005215] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]158builder # [ 0.005216] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]159builder # [ 0.005234] On node 0, zone DMA: 1 pages in unavailable ranges160builder # [ 0.005479] On node 0, zone DMA: 97 pages in unavailable ranges161builder # [ 0.021848] On node 0, zone DMA32: 40 pages in unavailable ranges162builder # [ 0.022288] ACPI: PM-Timer IO Port: 0x608163server # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 25 14:35:54 UTC 2026164builder # [ 0.022297] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])165builder # [ 0.022322] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23166builder # [ 0.022324] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)167builder # [ 0.022326] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)168server # [ 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/b8j47s6j4jkmk7y8pqhvsxwm6ljc386x-nixos-system-server-test/init regInfo=/nix/.ro-store/44gzhmq9si1lvdh0h8j56v07w8pyhjv2-closure-info/registration console=ttyS0,115200n8 console=tty0169builder # [ 0.022327] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)170server # [ 0.000000] BIOS-provided physical RAM map:171builder # [ 0.022328] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)172server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable173builder # [ 0.022329] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)174server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved175builder # [ 0.022331] ACPI: Using ACPI (MADT) for SMP configuration information176server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved177builder # [ 0.022332] ACPI: HPET id: 0x8086a201 base: 0xfed00000178builder # [ 0.022335] TSC deadline timer available179server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable180builder # [ 0.022339] CPU topo: Max. logical packages: 1181server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved182builder # [ 0.022339] CPU topo: Max. logical dies: 1183builder # [ 0.022340] CPU topo: Max. dies per package: 1184server # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved185builder # [ 0.022343] CPU topo: Max. threads per core: 1186server # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved187builder # [ 0.022343] CPU topo: Num. cores per package: 1188builder # [ 0.022344] CPU topo: Num. threads per package: 1189server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved190builder # [ 0.022344] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs191server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved192builder # [ 0.022360] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()193server # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved194server # [ 0.000000] NX (Execute Disable) protection: active195builder # [ 0.022386] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]196server # [ 0.000000] APIC: Static calls initialized197server # [ 0.000000] SMBIOS 2.8 present.198builder # [ 0.022387] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]199builder # [ 0.022388] [mem 0x40000000-0xafffffff] available for PCI devices200server # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014201builder # [ 0.022389] Booting paravirtualized kernel on KVM202server # [ 0.000000] DMI: Memory slots populated: 1/1203server # [ 0.000000] Hypervisor detected: KVM204builder # [ 0.022392] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns205server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000206server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00207builder # [ 0.026819] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1208server # [ 0.000000] kvm-clock: using sched offset of 505422303 cycles209builder # [ 0.028837] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152210builder # [ 0.028878] kvm-guest: PV spinlocks disabled, single CPU211server # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns212server # [ 0.000006] tsc: Detected 2400.012 MHz processor213server # [ 0.000814] last_pfn = 0x3ffd8 max_arch_pfn = 0x10000000000214server # [ 0.000842] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs215server # [ 0.000845] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT216builder # [ 0.028879] 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/01ph8r57zn7q7j8gckshmla2m1yn047z-nixos-system-builder-test/init regInfo=/nix/.ro-store/ps914crxrzdsfph2pj219nxqz2bd3q6s-closure-info/registration console=ttyS0,115200n8 console=tty0217server # [ 0.002741] found SMP MP-table at [mem 0x000f5450-0x000f545f]218server # [ 0.002755] Using GB pages for direct mapping219server # [ 0.002806] RAMDISK: [mem 0x3e36b000-0x3ffcffff]220builder # [ 0.028967] Unknown kernel command line parameters "regInfo=/nix/.ro-store/ps914crxrzdsfph2pj219nxqz2bd3q6s-closure-info/registration", will be passed to user space.221server # [ 0.002816] ACPI: Early table checksum verification disabled222builder # [ 0.028979] random: crng init done223server # [ 0.002820] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )224builder # [ 0.028980] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes225server # [ 0.002824] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)226builder # [ 0.029972] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)227server # [ 0.002830] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)228builder # [ 0.029984] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)229builder # [ 0.030014] Fallback order for Node 0: 0230server # [ 0.002837] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)231builder # [ 0.030016] Built 1 zonelists, mobility grouping on. Total pages: 262006232server # [ 0.002839] ACPI: FACS 0x000000003FFE0000 000040233builder # [ 0.030017] Policy zone: DMA32234server # [ 0.002841] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)235builder # [ 0.032424] mem auto-init: stack:all(zero), heap alloc:on, heap free:off236builder # [ 0.034658] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1237server # [ 0.002842] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)238builder # [ 0.036737] allocated 2097152 bytes of page_ext239builder # [ 0.046406] ftrace: allocating 48804 entries in 192 pages240server # [ 0.002844] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)241builder # [ 0.046408] ftrace: allocated 192 pages with 2 groups242builder # [ 0.047199] Dynamic Preempt: lazy243server # [ 0.002845] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)244builder # [ 0.047318] rcu: Preemptible hierarchical RCU implementation.245server # [ 0.002846] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]246builder # [ 0.047318] rcu: RCU event tracing is enabled.247server # [ 0.002847] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]248builder # [ 0.047319] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.249server # [ 0.002848] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]250builder # [ 0.047320] Trampoline variant of Tasks RCU enabled.251builder # [ 0.047320] Rude variant of Tasks RCU enabled.252server # [ 0.002848] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]253builder # [ 0.047321] Tracing variant of Tasks RCU enabled.254server # [ 0.002849] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]255builder # [ 0.047321] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.256server # [ 0.002849] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]257builder # [ 0.047322] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1258server # [ 0.002850] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]259server # [ 0.003098] No NUMA configuration found260builder # [ 0.047342] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.261server # [ 0.003099] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]262builder # [ 0.047344] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.263server # [ 0.003102] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]264server # [ 0.005258] Zone ranges:265builder # [ 0.047344] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.266server # [ 0.005260] DMA [mem 0x0000000000001000-0x0000000000ffffff]267builder # [ 0.051597] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16268server # [ 0.005261] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]269server # [ 0.005262] Normal empty270builder # [ 0.051905] rcu: srcu_init: Setting srcu_struct sizes based on contention.271server # [ 0.005263] Device empty272server # [ 0.005264] Movable zone start for each node273server # [ 0.005264] Early memory node ranges274builder # [ 0.051911] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns275server # [ 0.005265] node 0: [mem 0x0000000000001000-0x000000000009efff]276builder # [ 0.052011] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)277server # [ 0.005266] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]278builder # [ 0.055522] Console: colour VGA+ 80x25279builder # [ 0.055525] printk: legacy console [tty0] enabled280server # [ 0.005267] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]281builder # [ 0.084952] printk: legacy console [ttyS0] enabled282server # [ 0.005287] On node 0, zone DMA: 1 pages in unavailable ranges283builder # [ 0.188739] ACPI: Core revision 20250807284server # [ 0.005539] On node 0, zone DMA: 97 pages in unavailable ranges285server # [ 0.022360] On node 0, zone DMA32: 40 pages in unavailable ranges286server # [ 0.022853] ACPI: PM-Timer IO Port: 0x608287builder # [ 0.189610] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns288server # [ 0.022867] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])289builder # [ 0.191180] APIC: Switch to symmetric I/O mode setup290server # [ 0.022904] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23291builder # [ 0.192179] x2apic enabled292server # [ 0.022907] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)293builder # [ 0.192971] APIC: Switched APIC routing to: physical x2apic294server # [ 0.022909] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)295server # [ 0.022910] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)296server # [ 0.022911] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)297builder # [ 0.194854] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1298server # [ 0.022912] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)299server # [ 0.022914] ACPI: Using ACPI (MADT) for SMP configuration information300builder # [ 0.195870] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns301server # [ 0.022915] ACPI: HPET id: 0x8086a201 base: 0xfed00000302server # [ 0.022920] TSC deadline timer available303builder # [ 0.197559] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)304server # [ 0.022924] CPU topo: Max. logical packages: 1305server # [ 0.022925] CPU topo: Max. logical dies: 1306builder # [ 0.198642] x86/cpu: User Mode Instruction Prevention (UMIP) activated307server # [ 0.022925] CPU topo: Max. dies per package: 1308server # [ 0.022929] CPU topo: Max. threads per core: 1309builder # [ 0.200582] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127310server # [ 0.022929] CPU topo: Num. cores per package: 1311server # [ 0.022930] CPU topo: Num. threads per package: 1312builder # [ 0.201521] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0313server # [ 0.022930] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs314server # [ 0.022956] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()315builder # [ 0.202560] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto316server # [ 0.022991] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]317builder # [ 0.203557] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl318server # [ 0.022993] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]319server # [ 0.022994] [mem 0x40000000-0xafffffff] available for PCI devices320builder # [ 0.205556] Transient Scheduler Attacks: Vulnerable: No microcode321server # [ 0.022996] Booting paravirtualized kernel on KVM322builder # [ 0.206556] Spectre V2 : Mitigation: Enhanced / Automatic IBRS323server # [ 0.023000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns324builder # [ 0.207557] Speculative Return Stack Overflow: Mitigation: Safe RET325server # [ 0.027456] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1326server # [ 0.029529] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152327server # [ 0.029579] kvm-guest: PV spinlocks disabled, single CPU328builder # [ 0.208556] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization329builder # [ 0.210563] Spectre V2 : Enabling IBPB for BPF330builder # [ 0.211303] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier331server # [ 0.029581] 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/b8j47s6j4jkmk7y8pqhvsxwm6ljc386x-nixos-system-server-test/init regInfo=/nix/.ro-store/44gzhmq9si1lvdh0h8j56v07w8pyhjv2-closure-info/registration console=ttyS0,115200n8 console=tty0332builder # [ 0.212557] active return thunk: srso_alias_return_thunk333server # [ 0.029684] Unknown kernel command line parameters "regInfo=/nix/.ro-store/44gzhmq9si1lvdh0h8j56v07w8pyhjv2-closure-info/registration", will be passed to user space.334builder # [ 0.213578] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'335server # [ 0.029697] random: crng init done336server # [ 0.029698] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes337builder # [ 0.214556] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'338server # [ 0.030724] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)339builder # [ 0.215556] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'340server # [ 0.030738] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)341builder # [ 0.216556] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'342server # [ 0.030772] Fallback order for Node 0: 0343builder # [ 0.217556] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'344server # [ 0.030775] Built 1 zonelists, mobility grouping on. Total pages: 262006345server # [ 0.030776] Policy zone: DMA32346builder # [ 0.218556] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'347server # [ 0.033304] mem auto-init: stack:all(zero), heap alloc:on, heap free:off348server # [ 0.035843] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1349builder # [ 0.219556] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'350server # [ 0.037979] allocated 2097152 bytes of page_ext351server # [ 0.047937] ftrace: allocating 48804 entries in 192 pages352builder # [ 0.221557] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256353server # [ 0.047939] ftrace: allocated 192 pages with 2 groups354server # [ 0.048804] Dynamic Preempt: lazy355builder # [ 0.222556] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64356server # [ 0.048990] rcu: Preemptible hierarchical RCU implementation.357builder # [ 0.223557] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512358server # [ 0.048991] rcu: RCU event tracing is enabled.359builder # [ 0.224556] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024360server # [ 0.048992] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.361server # [ 0.048993] Trampoline variant of Tasks RCU enabled.362builder # [ 0.225556] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8363server # [ 0.048994] Rude variant of Tasks RCU enabled.364server # [ 0.048994] Tracing variant of Tasks RCU enabled.365builder # [ 0.226556] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.366server # [ 0.048995] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.367server # [ 0.048995] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1368server # [ 0.049018] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.369server # [ 0.049019] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.370server # [ 0.049020] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.371server # [ 0.053361] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16372server # [ 0.053664] rcu: srcu_init: Setting srcu_struct sizes based on contention.373server # [ 0.053673] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns374server # [ 0.053781] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)375server # [ 0.057365] Console: colour VGA+ 80x25376server # [ 0.057368] printk: legacy console [tty0] enabled377server # [ 0.086908] printk: legacy console [ttyS0] enabled378server # [ 0.189474] ACPI: Core revision 20250807379server # [ 0.190375] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns380server # [ 0.192006] APIC: Switch to symmetric I/O mode setup381server # [ 0.193058] x2apic enabled382server # [ 0.193814] APIC: Switched APIC routing to: physical x2apic383server # [ 0.195730] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1384server # [ 0.196771] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns385server # [ 0.198535] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)386server # [ 0.200602] x86/cpu: User Mode Instruction Prevention (UMIP) activated387server # [ 0.201649] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127388server # [ 0.202532] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0389server # [ 0.203536] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto390server # [ 0.204532] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl391server # [ 0.205532] Transient Scheduler Attacks: Vulnerable: No microcode392server # [ 0.206531] Spectre V2 : Mitigation: Enhanced / Automatic IBRS393server # [ 0.207532] Speculative Return Stack Overflow: Mitigation: Safe RET394builder # [ 0.260757] Freeing SMP alternatives memory: 44K395builder # [ 0.261555] pid_max: default: 32768 minimum: 301396server # [ 0.209338] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization397builder # [ 0.262186] LSM: initializing lsm=capability,landlock,yama,bpf,ima398server # [ 0.210540] Spectre V2 : Enabling IBPB for BPF399builder # [ 0.263401] landlock: Up and running.400builder # [ 0.264021] Yama: becoming mindful.401server # [ 0.212077] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier402builder # [ 0.264777] LSM support for eBPF active403server # [ 0.212533] active return thunk: srso_alias_return_thunk404builder # [ 0.265645] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)405server # [ 0.213435] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'406builder # [ 0.266580] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)407server # [ 0.214531] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'408server # [ 0.215531] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'409server # [ 0.216531] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'410builder # [ 0.269338] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)411server # [ 0.217531] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'412builder # [ 0.270054] Performance Events: Fam17h+ core perfctr, AMD PMU driver.413server # [ 0.218531] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'414builder # [ 0.270561] ... version: 2415builder # [ 0.271303] ... bit width: 48416server # [ 0.219531] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'417builder # [ 0.271559] ... generic counters: 6418server # [ 0.220532] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256419builder # [ 0.272324] ... generic bitmap: 000000000000003f420server # [ 0.222299] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64421builder # [ 0.272569] ... fixed-purpose counters: 0422server # [ 0.223288] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512423builder # [ 0.273272] ... fixed-purpose bitmap: 0000000000000000424builder # [ 0.273559] ... value mask: 0000ffffffffffff425server # [ 0.224288] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024426builder # [ 0.274474] ... max period: 00007fffffffffff427server # [ 0.225277] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8428builder # [ 0.275251] ... global_ctrl mask: 000000000000003f429server # [ 0.226270] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.430builder # [ 0.275665] signal: max sigframe size: 3376431builder # [ 0.276482] rcu: Hierarchical SRCU implementation.432builder # [ 0.277204] rcu: Max phase no-delay instances is 400.433builder # [ 0.282005] smp: Bringing up secondary CPUs ...434builder # [ 0.282573] smp: Brought up 1 node, 1 CPU435builder # [ 0.283268] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)436builder # [ 0.283756] Memory: 941036K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 99608K reserved, 0K cma-reserved)437builder # [ 0.284791] devtmpfs: initialized438builder # [ 0.285656] x86/mm: Memory block size: 128MB439builder # [ 0.287375] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)440builder # [ 0.288554] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).441builder # [ 0.289638] pinctrl core: initialized pinctrl subsystem442builder # [ 0.290786] PM: RTC time: 08:26:46, date: 2026-10-01443builder # [ 0.294244] NET: Registered PF_NETLINK/PF_ROUTE protocol family444builder # [ 0.294901] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations445builder # [ 0.295573] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations446builder # [ 0.296693] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations447builder # [ 0.297568] audit: initializing netlink subsys (disabled)448builder # [ 0.298771] thermal_sys: Registered thermal governor 'fair_share'449builder # [ 0.298773] thermal_sys: Registered thermal governor 'bang_bang'450builder # [ 0.299561] thermal_sys: Registered thermal governor 'step_wise'451builder # [ 0.300562] audit: type=2000 audit(1790843206.688:1): state=initialized audit_enabled=0 res=1452builder # [ 0.302562] thermal_sys: Registered thermal governor 'user_space'453server # [ 0.261962] Freeing SMP alternatives memory: 44K454builder # [ 0.302563] thermal_sys: Registered thermal governor 'power_allocator'455server # [ 0.262535] pid_max: default: 32768 minimum: 301456builder # [ 0.303571] cpuidle: using governor menu457server # [ 0.263646] LSM: initializing lsm=capability,landlock,yama,bpf,ima458builder # [ 0.305760] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5459server # [ 0.264642] landlock: Up and running.460server # [ 0.265269] Yama: becoming mindful.461builder # [ 0.306775] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]462server # [ 0.266193] LSM support for eBPF active463builder # [ 0.307562] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry464server # [ 0.267095] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)465builder # [ 0.308571] PCI: Using configuration type 1 for base access466server # [ 0.267551] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)467builder # [ 0.309716] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.468server # [ 0.270768] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)469server # [ 0.272092] Performance Events: Fam17h+ core perfctr, AMD PMU driver.470server # [ 0.272541] ... version: 2471server # [ 0.273308] ... bit width: 48472server # [ 0.273534] ... generic counters: 6473server # [ 0.274269] ... generic bitmap: 000000000000003f474server # [ 0.274534] ... fixed-purpose counters: 0475builder # [ 0.316817] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages476server # [ 0.275271] ... fixed-purpose bitmap: 0000000000000000477builder # [ 0.317560] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page478server # [ 0.275533] ... value mask: 0000ffffffffffff479server # [ 0.276480] ... max period: 00007fffffffffff480server # [ 0.277284] ... global_ctrl mask: 000000000000003f481server # [ 0.277659] signal: max sigframe size: 3376482server # [ 0.278541] rcu: Hierarchical SRCU implementation.483builder # [ 0.322560] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages484server # [ 0.279380] rcu: Max phase no-delay instances is 400.485builder # [ 0.323560] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page486server # [ 0.284859] smp: Bringing up secondary CPUs ...487server # [ 0.285551] smp: Brought up 1 node, 1 CPU488server # [ 0.286288] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)489server # [ 0.286764] Memory: 941024K/1048024K available (17263K kernel code, 2728K rwdata, 13660K rodata, 3656K init, 2968K bss, 99600K reserved, 0K cma-reserved)490builder # [ 0.333408] ACPI: Added _OSI(Module Device)491server # [ 0.287807] devtmpfs: initialized492builder # [ 0.334115] ACPI: Added _OSI(Processor Device)493server # [ 0.288642] x86/mm: Memory block size: 128MB494builder # [ 0.334559] ACPI: Added _OSI(Processor Aggregator Device)495server # [ 0.290313] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)496server # [ 0.291515] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).497server # [ 0.292645] pinctrl core: initialized pinctrl subsystem498server # [ 0.293833] PM: RTC time: 08:26:46, date: 2026-10-01499builder # [ 0.341996] ACPI: 1 ACPI AML tables successfully acquired and loaded500server # [ 0.297381] NET: Registered PF_NETLINK/PF_ROUTE protocol family501server # [ 0.298724] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations502builder # [ 0.345956] ACPI: Interpreter enabled503server # [ 0.299549] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations504builder # [ 0.346574] ACPI: PM: (supports S0 S3 S4 S5)505builder # [ 0.347323] ACPI: Using IOAPIC for interrupt routing506server # [ 0.300681] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations507server # [ 0.301547] audit: initializing netlink subsys (disabled)508server # [ 0.302789] thermal_sys: Registered thermal governor 'fair_share'509builder # [ 0.349616] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug510server # [ 0.302791] thermal_sys: Registered thermal governor 'bang_bang'511builder # [ 0.350560] PCI: Using E820 reservations for host bridge windows512server # [ 0.303535] thermal_sys: Registered thermal governor 'step_wise'513server # [ 0.304500] thermal_sys: Registered thermal governor 'user_space'514server # [ 0.305292] audit: type=2000 audit(1790843206.744:1): state=initialized audit_enabled=0 res=1515builder # [ 0.353701] ACPI: Enabled 2 GPEs in block 00 to 3F516server # [ 0.306539] thermal_sys: Registered thermal governor 'power_allocator'517server # [ 0.306557] cpuidle: using governor menu518server # [ 0.308834] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5519server # [ 0.309762] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]520server # [ 0.310538] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry521server # [ 0.311547] PCI: Using configuration type 1 for base access522builder # [ 0.360827] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])523server # [ 0.312666] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.524builder # [ 0.361565] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]525builder # [ 0.362639] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]526builder # [ 0.363683] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]527builder # [ 0.364928] PCI host bridge to bus 0000:00528builder # [ 0.365564] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]529server # [ 0.317804] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages530builder # [ 0.366559] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]531server # [ 0.318534] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page532builder # [ 0.367559] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]533builder # [ 0.368559] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]534builder # [ 0.369559] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]535builder # [ 0.370559] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]536server # [ 0.323535] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages537builder # [ 0.371560] pci_bus 0000:00: root bus resource [bus 00-ff]538server # [ 0.324534] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page539builder # [ 0.372542] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint540builder # [ 0.373996] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint541builder # [ 0.376645] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]542server # [ 0.334907] ACPI: Added _OSI(Module Device)543builder # [ 0.377573] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]544server # [ 0.335535] ACPI: Added _OSI(Processor Device)545builder # [ 0.378549] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]546server # [ 0.336301] ACPI: Added _OSI(Processor Aggregator Device)547builder # [ 0.379689] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]548builder # [ 0.381520] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint549builder # [ 0.383598] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]550builder # [ 0.384510] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]551builder # [ 0.385358] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]552server # [ 0.344604] ACPI: 1 ACPI AML tables successfully acquired and loaded553builder # [ 0.386469] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]554builder # [ 0.387899] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint555server # [ 0.348966] ACPI: Interpreter enabled556server # [ 0.349550] ACPI: PM: (supports S0 S3 S4 S5)557server # [ 0.350318] ACPI: Using IOAPIC for interrupt routing558builder # [ 0.390596] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]559builder # [ 0.391481] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]560builder # [ 0.392379] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]561server # [ 0.352620] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug562builder # [ 0.394002] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint563server # [ 0.353535] PCI: Using E820 reservations for host bridge windows564builder # [ 0.396511] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]565server # [ 0.356690] ACPI: Enabled 2 GPEs in block 00 to 3F566builder # [ 0.397248] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]567builder # [ 0.397580] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]568builder # [ 0.399122] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint569builder # [ 0.400600] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]570server # [ 0.364342] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])571builder # [ 0.401491] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]572builder # [ 0.402362] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]573server # [ 0.365322] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]574builder # [ 0.403438] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]575server # [ 0.366616] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]576server # [ 0.367658] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]577builder # [ 0.404916] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint578server # [ 0.368904] PCI host bridge to bus 0000:00579server # [ 0.369539] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]580builder # [ 0.406602] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]581server # [ 0.370534] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]582builder # [ 0.407570] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]583server # [ 0.371534] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]584server # [ 0.372534] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]585builder # [ 0.409161] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint586server # [ 0.373534] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]587builder # [ 0.410573] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]588server # [ 0.374535] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]589builder # [ 0.411539] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]590server # [ 0.375534] pci_bus 0000:00: root bus resource [bus 00-ff]591server # [ 0.376588] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint592builder # [ 0.413026] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint593server # [ 0.378033] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint594builder # [ 0.414601] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]595builder # [ 0.415564] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]596builder # [ 0.417116] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint597builder # [ 0.418608] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]598server # [ 0.380631] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]599builder # [ 0.419566] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]600server # [ 0.381548] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]601server # [ 0.382556] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]602builder # [ 0.421104] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint603server # [ 0.383918] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]604builder # [ 0.423193] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]605builder # [ 0.423567] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]606server # [ 0.385407] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint607builder # [ 0.424523] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]608builder # [ 0.426002] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint609server # [ 0.388575] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]610server # [ 0.389504] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]611server # [ 0.390362] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]612builder # [ 0.428599] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]613server # [ 0.391386] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]614builder # [ 0.429453] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]615builder # [ 0.430345] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]616server # [ 0.392972] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint617builder # [ 0.431972] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint618builder # [ 0.433302] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]619server # [ 0.396573] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]620server # [ 0.397498] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]621builder # [ 0.433807] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint622server # [ 0.398319] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]623builder # [ 0.435255] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]624server # [ 0.400107] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint625builder # [ 0.435771] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint626builder # [ 0.437936] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]627server # [ 0.402798] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]628server # [ 0.404229] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]629server # [ 0.404556] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]630server # [ 0.406115] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint631server # [ 0.408575] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]632server # [ 0.409490] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]633server # [ 0.410338] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]634builder # [ 0.438806] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint635server # [ 0.411541] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]636builder # [ 0.441157] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]637server # [ 0.413140] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint638builder # [ 0.441870] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint639builder # [ 0.442851] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO640server # [ 0.414549] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]641server # [ 0.415550] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]642builder # [ 0.443814] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint643server # [ 0.417131] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint644builder # [ 0.445588] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]645builder # [ 0.446401] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]646builder # [ 0.447637] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint647server # [ 0.419154] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]648server # [ 0.419557] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]649builder # [ 0.449122] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]650server # [ 0.421222] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint651builder # [ 0.452649] ACPI: PCI: Interrupt link LNKA configured for IRQ 10652server # [ 0.423578] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]653server # [ 0.424556] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]654builder # [ 0.453665] ACPI: PCI: Interrupt link LNKB configured for IRQ 10655builder # [ 0.454665] ACPI: PCI: Interrupt link LNKC configured for IRQ 11656server # [ 0.426115] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint657builder # [ 0.455659] ACPI: PCI: Interrupt link LNKD configured for IRQ 11658builder # [ 0.456656] ACPI: PCI: Interrupt link LNKE configured for IRQ 10659builder # [ 0.457664] ACPI: PCI: Interrupt link LNKF configured for IRQ 10660server # [ 0.428316] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]661builder # [ 0.458663] ACPI: PCI: Interrupt link LNKG configured for IRQ 11662server # [ 0.429324] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]663builder # [ 0.459658] ACPI: PCI: Interrupt link LNKH configured for IRQ 11664builder # [ 0.460598] ACPI: PCI: Interrupt link GSIA configured for IRQ 16665server # [ 0.431039] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint666builder # [ 0.461564] ACPI: PCI: Interrupt link GSIB configured for IRQ 17667builder # [ 0.462517] ACPI: PCI: Interrupt link GSIC configured for IRQ 18668builder # [ 0.463496] ACPI: PCI: Interrupt link GSID configured for IRQ 19669server # [ 0.433411] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]670builder # [ 0.464321] ACPI: PCI: Interrupt link GSIE configured for IRQ 20671server # [ 0.434237] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]672builder # [ 0.465293] ACPI: PCI: Interrupt link GSIF configured for IRQ 21673server # [ 0.434556] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]674builder # [ 0.465575] ACPI: PCI: Interrupt link GSIG configured for IRQ 22675builder # [ 0.466524] ACPI: PCI: Interrupt link GSIH configured for IRQ 23676server # [ 0.436730] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint677builder # [ 0.468293] iommu: Default domain type: Translated678builder # [ 0.468568] iommu: DMA domain TLB invalidation policy: lazy mode679builder # [ 0.469798] ACPI: bus type USB registered680server # [ 0.439362] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]681builder # [ 0.470576] usbcore: registered new interface driver usbfs682server # [ 0.440225] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]683builder # [ 0.471509] usbcore: registered new interface driver hub684server # [ 0.440556] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]685builder # [ 0.472234] usbcore: registered new device driver usb686builder # [ 0.473551] NetLabel: Initializing687server # [ 0.442147] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint688builder # [ 0.474153] NetLabel: domain hash size = 128689builder # [ 0.474560] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO690server # [ 0.443376] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]691builder # [ 0.475498] NetLabel: unlabeled traffic allowed by default692builder # [ 0.476245] PCI: Using ACPI for IRQ routing693server # [ 0.444780] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint694server # [ 0.446441] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]695server # [ 0.447467] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint696server # [ 0.449336] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]697server # [ 0.450472] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint698server # [ 0.452543] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]699server # [ 0.453842] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint700server # [ 0.454834] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO701server # [ 0.455825] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint702server # [ 0.457595] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]703server # [ 0.458515] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]704server # [ 0.460061] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint705server # [ 0.461403] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]706server # [ 0.465400] ACPI: PCI: Interrupt link LNKA configured for IRQ 10707server # [ 0.466435] ACPI: PCI: Interrupt link LNKB configured for IRQ 10708server # [ 0.467443] ACPI: PCI: Interrupt link LNKC configured for IRQ 11709server # [ 0.468441] ACPI: PCI: Interrupt link LNKD configured for IRQ 11710server # [ 0.469447] ACPI: PCI: Interrupt link LNKE configured for IRQ 10711server # [ 0.470436] ACPI: PCI: Interrupt link LNKF configured for IRQ 10712builder # [ 0.519651] pci 0000:00:01.0: vgaarb: setting as boot VGA device713builder # [ 0.520555] pci 0000:00:01.0: vgaarb: bridge control possible714server # [ 0.471410] ACPI: PCI: Interrupt link LNKG configured for IRQ 11715server # [ 0.472392] ACPI: PCI: Interrupt link LNKH configured for IRQ 11716builder # [ 0.520555] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none717builder # [ 0.520564] vgaarb: loaded718server # [ 0.473327] ACPI: PCI: Interrupt link GSIA configured for IRQ 16719builder # [ 0.521304] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0720server # [ 0.474298] ACPI: PCI: Interrupt link GSIB configured for IRQ 17721builder # [ 0.521562] hpet0: 3 comparators, 64-bit 100.000000 MHz counter722server # [ 0.474548] ACPI: PCI: Interrupt link GSIC configured for IRQ 18723server # [ 0.475504] ACPI: PCI: Interrupt link GSID configured for IRQ 19724server # [ 0.476320] ACPI: PCI: Interrupt link GSIE configured for IRQ 20725server # [ 0.477419] ACPI: PCI: Interrupt link GSIF configured for IRQ 21726server # [ 0.478320] ACPI: PCI: Interrupt link GSIG configured for IRQ 22727server # [ 0.479322] ACPI: PCI: Interrupt link GSIH configured for IRQ 23728builder # [ 0.527651] clocksource: Switched to clocksource kvm-clock729server # [ 0.481502] iommu: Default domain type: Translated730server # [ 0.482195] iommu: DMA domain TLB invalidation policy: lazy mode731server # [ 0.482843] ACPI: bus type USB registered732server # [ 0.483621] usbcore: registered new interface driver usbfs733builder # [ 0.531064] VFS: Disk quotas dquot_6.6.0734server # [ 0.484555] usbcore: registered new interface driver hub735builder # [ 0.532865] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)736server # [ 0.485467] usbcore: registered new device driver usb737builder # [ 0.534208] pnp: PnP ACPI init738builder # [ 0.535014] ACPI: IRQ 4 override to edge(!), high(!)739server # [ 0.487430] NetLabel: Initializing740server # [ 0.487969] NetLabel: domain hash size = 128741builder # [ 0.535958] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved742server # [ 0.488534] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO743builder # [ 0.537462] pnp: PnP ACPI: found 5 devices744server # [ 0.489596] NetLabel: unlabeled traffic allowed by default745server # [ 0.490552] PCI: Using ACPI for IRQ routing746builder # [ 0.544957] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns747builder # [ 0.546366] clocksource: Switched to clocksource acpi_pm748builder # [ 0.547333] NET: Registered PF_INET protocol family749builder # [ 0.548307] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)750builder # [ 0.564846] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)751builder # [ 0.566354] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)752builder # [ 0.567628] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)753builder # [ 0.568928] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)754builder # [ 0.570118] TCP: Hash tables configured (established 8192 bind 8192)755builder # [ 0.571168] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)756builder # [ 0.572436] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)757builder # [ 0.573516] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)758builder # [ 0.574707] NET: Registered PF_UNIX/PF_LOCAL protocol family759builder # [ 0.575626] NET: Registered PF_XDP protocol family760builder # [ 0.576460] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]761builder # [ 0.577443] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]762builder # [ 0.578427] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]763builder # [ 0.579509] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]764builder # [ 0.580588] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]765builder # [ 0.581677] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]766builder # [ 0.583394] ACPI: \_SB_.GSIA: Enabled at IRQ 16767server # [ 0.535337] pci 0000:00:01.0: vgaarb: setting as boot VGA device768builder # [ 0.585591] ACPI: \_SB_.GSIB: Enabled at IRQ 17769server # [ 0.535530] pci 0000:00:01.0: vgaarb: bridge control possible770server # [ 0.535530] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none771builder # [ 0.588061] ACPI: \_SB_.GSIC: Enabled at IRQ 18772server # [ 0.535539] vgaarb: loaded773server # [ 0.536319] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0774builder # [ 0.590012] ACPI: \_SB_.GSID: Enabled at IRQ 19775server # [ 0.537191] hpet0: 3 comparators, 64-bit 100.000000 MHz counter776builder # [ 0.591714] PCI: CLS 0 bytes, default 64777builder # [ 0.592809] Trying to unpack rootfs image as initramfs...778server # [ 0.541637] clocksource: Switched to clocksource kvm-clock779builder # [ 0.596709] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns780server # [ 0.545321] VFS: Disk quotas dquot_6.6.0781server # [ 0.546064] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)782server # [ 0.547503] pnp: PnP ACPI init783server # [ 0.548351] ACPI: IRQ 4 override to edge(!), high(!)784server # [ 0.549353] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved785server # [ 0.550989] pnp: PnP ACPI: found 5 devices786server # [ 0.558566] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns787server # [ 0.560128] clocksource: Switched to clocksource acpi_pm788server # [ 0.561118] NET: Registered PF_INET protocol family789server # [ 0.562165] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)790server # [ 0.578777] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)791server # [ 0.580448] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)792server # [ 0.581823] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)793server # [ 0.583251] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)794builder # [ 0.638295] Initialise system trusted keyrings795server # [ 0.584555] TCP: Hash tables configured (established 8192 bind 8192)796builder # [ 0.639237] workingset: timestamp_bits=40 max_order=18 bucket_order=0797server # [ 0.585719] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)798server # [ 0.587097] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)799server # [ 0.588263] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)800server # [ 0.589509] NET: Registered PF_UNIX/PF_LOCAL protocol family801server # [ 0.590523] NET: Registered PF_XDP protocol family802server # [ 0.591432] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]803server # [ 0.592490] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]804server # [ 0.593556] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]805server # [ 0.594723] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]806server # [ 0.595904] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]807server # [ 0.597085] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]808server # [ 0.598920] ACPI: \_SB_.GSIA: Enabled at IRQ 16809server # [ 0.601571] ACPI: \_SB_.GSIB: Enabled at IRQ 17810server # [ 0.603540] ACPI: \_SB_.GSIC: Enabled at IRQ 18811builder # [ 0.658622] Key type asymmetric registered812server # [ 0.605507] ACPI: \_SB_.GSID: Enabled at IRQ 19813server # [ 0.607225] PCI: CLS 0 bytes, default 64814server # [ 0.608131] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns815builder # [ 0.663679] Asymmetric key parser 'x509' registered816server # [ 0.609963] Trying to unpack rootfs image as initramfs...817builder # [ 0.664524] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)818builder # [ 0.666781] io scheduler mq-deadline registered819builder # [ 0.667531] io scheduler kyber registered820builder # [ 0.670205] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled821builder # [ 0.671457] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A822builder # [ 0.677294] Linux agpgart interface v0.103823builder # [ 0.678055] ACPI: bus type drm_connector registered824builder # [ 0.681133] usbcore: registered new interface driver usbserial_generic825builder # [ 0.682169] usbserial: USB Serial support registered for generic826builder # [ 0.684680] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.827builder # [ 0.684680] Please enable it if your BIOS has the CPPC option.828builder # [ 0.686868] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled829builder # [ 0.691808] drop_monitor: Initializing network drop monitor service830builder # [ 0.692961] NET: Registered PF_INET6 protocol family831builder # [ 0.696153] Segment Routing with IPv6832builder # [ 0.696815] In-situ OAM (IOAM) with IPv6833builder # [ 0.699981] IPI shorthand broadcast: enabled834builder # [ 0.708304] sched_clock: Marking stable (567015837, 140644591)->(776613137, -68952709)835server # [ 0.655299] Initialise system trusted keyrings836builder # [ 0.712816] registered taskstats version 1837server # [ 0.658950] workingset: timestamp_bits=40 max_order=18 bucket_order=0838builder # [ 0.713789] Loading compiled-in X.509 certificates839builder # [ 0.730069] Demotion targets for Node 0: null840server # [ 0.678307] Key type asymmetric registered841builder # [ 0.732705] Key type .fscrypt registered842builder # [ 0.733372] Key type fscrypt-provisioning registered843builder # [ 0.734321] ima: No TPM chip found, activating TPM-bypass!844builder # [ 0.736672] ima: Allocated hash algorithm: sha1845server # [ 0.682892] Asymmetric key parser 'x509' registered846builder # [ 0.737453] ima: No architecture policies found847server # [ 0.683749] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)848server # [ 0.687018] io scheduler mq-deadline registered849builder # [ 0.741673] PM: Magic number: 14:320:421850server # [ 0.687840] io scheduler kyber registered851builder # [ 0.743267] RAS: Correctable Errors collector initialized.852server # [ 0.691511] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled853server # [ 0.694897] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A854builder # [ 0.751305] clk: Disabling unused clocks855server # [ 0.698310] Linux agpgart interface v0.103856server # [ 0.699167] ACPI: bus type drm_connector registered857builder # [ 0.755696] PM: genpd: Disabling unused power domains858server # [ 0.702349] usbcore: registered new interface driver usbserial_generic859server # [ 0.703479] usbserial: USB Serial support registered for generic860server # [ 0.705898] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.861server # [ 0.705898] Please enable it if your BIOS has the CPPC option.862server # [ 0.708250] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled863server # [ 0.714084] drop_monitor: Initializing network drop monitor service864server # [ 0.715383] NET: Registered PF_INET6 protocol family865server # [ 0.718407] Segment Routing with IPv6866server # [ 0.721904] In-situ OAM (IOAM) with IPv6867server # [ 0.723342] IPI shorthand broadcast: enabled868server # [ 0.731812] sched_clock: Marking stable (591016283, 139858715)->(802832036, -71957038)869server # [ 0.735098] registered taskstats version 1870server # [ 0.736116] Loading compiled-in X.509 certificates871server # [ 0.755114] Demotion targets for Node 0: null872server # [ 0.757937] Key type .fscrypt registered873server # [ 0.758630] Key type fscrypt-provisioning registered874server # [ 0.759639] ima: No TPM chip found, activating TPM-bypass!875server # [ 0.761898] ima: Allocated hash algorithm: sha1876server # [ 0.762711] ima: No architecture policies found877server # [ 0.765889] PM: Magic number: 14:320:421878server # [ 0.767607] RAS: Correctable Errors collector initialized.879server # [ 0.776564] clk: Disabling unused clocks880server # [ 0.779899] PM: genpd: Disabling unused power domains881builder # [ 0.914996] Freeing initrd memory: 29084K882builder # [ 0.918095] Freeing unused decrypted memory: 2028K883builder # [ 0.920562] Freeing unused kernel image (initmem) memory: 3656K884builder # [ 0.921618] Write protecting the kernel read-only data: 32768k885builder # [ 0.923411] Freeing unused kernel image (text/rodata gap) memory: 1168K886builder # [ 0.924850] Freeing unused kernel image (rodata/data gap) memory: 676K887builder # [ 0.975562] x86/mm: Checked W+X mappings: passed, no W+X pages found.888builder # [ 0.976663] Run /init as init process889builder # [ 0.987060] systemd[1]: Inserted module 'autofs4'890builder # [ 1.006891] fuse: init (API version 7.45)891server # [ 0.960174] Freeing initrd memory: 29076K892builder # [ 1.014275] ACPI: \_SB_.GSIG: Enabled at IRQ 22893builder # [ 1.016635] ACPI: \_SB_.GSIH: Enabled at IRQ 23894server # [ 0.963363] Freeing unused decrypted memory: 2028K895builder # [ 1.019469] ACPI: \_SB_.GSIE: Enabled at IRQ 20896server # [ 0.965981] Freeing unused kernel image (initmem) memory: 3656K897server # [ 0.967072] Write protecting the kernel read-only data: 32768k898builder # [ 1.021364] ACPI: \_SB_.GSIF: Enabled at IRQ 21899server # [ 0.968893] Freeing unused kernel image (text/rodata gap) memory: 1168K900server # [ 0.970361] Freeing unused kernel image (rodata/data gap) memory: 676K901builder # [ 1.026160] virtiofs virtio5: discovered new tag: nix-store902builder # [ 1.027903] virtiofs virtio5: virtio_fs_setup_dax: No cache capability903builder # [ 1.034841] virtiofs virtio6: discovered new tag: shared904builder # [ 1.036346] virtiofs virtio6: virtio_fs_setup_dax: No cache capability905builder # [ 1.039624] virtiofs virtio7: discovered new tag: xchg906builder # [ 1.041070] virtiofs virtio7: virtio_fs_setup_dax: No cache capability907builder # [ 1.064712] systemd[1]: Successfully made /usr/ read-only.908server # [ 1.021009] x86/mm: Checked W+X mappings: passed, no W+X pages found.909server # [ 1.022183] Run /init as init process910server # [ 1.033252] systemd[1]: Inserted module 'autofs4'911server # [ 1.053712] fuse: init (API version 7.45)912server # [ 1.061634] ACPI: \_SB_.GSIG: Enabled at IRQ 22913server # [ 1.064349] ACPI: \_SB_.GSIH: Enabled at IRQ 23914server # [ 1.067695] ACPI: \_SB_.GSIE: Enabled at IRQ 20915server # [ 1.070035] ACPI: \_SB_.GSIF: Enabled at IRQ 21916server # [ 1.075577] virtiofs virtio5: discovered new tag: nix-store917server # [ 1.077266] virtiofs virtio5: virtio_fs_setup_dax: No cache capability918server # [ 1.084854] virtiofs virtio6: discovered new tag: shared919server # [ 1.086451] virtiofs virtio6: virtio_fs_setup_dax: No cache capability920server # [ 1.090106] virtiofs virtio7: discovered new tag: xchg921server # [ 1.091521] virtiofs virtio7: virtio_fs_setup_dax: No cache capability922server # [ 1.113709] systemd[1]: Successfully made /usr/ read-only.923builder # [ 1.401726] 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)924builder # [ 1.413855] systemd[1]: Detected virtualization kvm.925builder # [ 1.415981] systemd[1]: Detected architecture x86-64.926builder # [ 1.418162] systemd[1]: Running in initrd.927builder # [ 1.420804] systemd[1]: Initializing machine ID from random generator.928builder # [ 1.423724] systemd[1]: Hostname set to <builder>.929server # [ 1.450603] 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)930server # [ 1.462730] systemd[1]: Detected virtualization kvm.931server # [ 1.464919] systemd[1]: Detected architecture x86-64.932server # [ 1.467028] systemd[1]: Running in initrd.933server # [ 1.469486] systemd[1]: Initializing machine ID from random generator.934server # [ 1.472433] systemd[1]: Hostname set to <server>.935builder # [ 1.635340] systemd[1]: bpf-restrict-fs: LSM BPF program attached936builder # [ 1.675006] systemd[1]: Queued start job for default target Initrd Default Target.937builder # [ 1.679051] systemd[1]: Created slice Slice /system/modprobe.938builder # [ 1.680262] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.939builder # [ 1.681725] systemd[1]: Expecting device /dev/disk/by-label/nixos...940builder # [ 1.682869] systemd[1]: Reached target Path Units.941builder # [ 1.683826] systemd[1]: Reached target Slice Units.942builder # [ 1.684750] systemd[1]: Reached target Swaps.943builder # [ 1.685579] systemd[1]: Reached target Timer Units.944builder # [ 1.686608] systemd[1]: Listening on D-Bus System Message Bus Socket.945builder # [ 1.687888] systemd[1]: Listening on Journal Socket (/dev/log).946builder # [ 1.689092] systemd[1]: Listening on Journal Sockets.947builder # [ 1.690103] systemd[1]: Listening on udev Control Socket.948builder # [ 1.691139] systemd[1]: Listening on udev Kernel Socket.949builder # [ 1.692093] systemd[1]: Reached target Socket Units.950builder # [ 1.694010] systemd[1]: Starting Create List of Static Device Nodes...951builder # [ 1.698729] systemd[1]: Starting Load Kernel Module configfs...952builder # [ 1.708389] systemd[1]: Starting Journal Service...953builder # [ 1.734299] systemd[1]: Starting Load Kernel Modules...954server # [ 1.684817] systemd[1]: bpf-restrict-fs: LSM BPF program attached955builder # [ 1.738793] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os956builder # [ 1.748894] systemd[1]: Starting Coldplug All udev Devices...957builder # [ 1.758328] systemd-journald[65]: Collecting audit messages is disabled.958builder # [ 1.761913] systemd[1]: Finished Create List of Static Device Nodes.959builder # [ 1.769327] systemd[1]: modprobe@configfs.service: Deactivated successfully.960builder # [ 1.775068] systemd[1]: Finished Load Kernel Module configfs.961server # [ 1.723134] systemd[1]: Queued start job for default target Initrd Default Target.962server # [ 1.727168] systemd[1]: Created slice Slice /system/modprobe.963server # [ 1.728412] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.964builder # [ 1.782124] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config965server # [ 1.729925] systemd[1]: Expecting device /dev/disk/by-label/nixos...966server # [ 1.731047] systemd[1]: Reached target Path Units.967server # [ 1.731936] systemd[1]: Reached target Slice Units.968server # [ 1.732805] systemd[1]: Reached target Swaps.969server # [ 1.733637] systemd[1]: Reached target Timer Units.970server # [ 1.734681] systemd[1]: Listening on D-Bus System Message Bus Socket.971server # [ 1.735963] systemd[1]: Listening on Journal Socket (/dev/log).972server # [ 1.737146] systemd[1]: Listening on Journal Sockets.973server # [ 1.738150] systemd[1]: Listening on udev Control Socket.974server # [ 1.739194] systemd[1]: Listening on udev Kernel Socket.975server # [ 1.740197] systemd[1]: Reached target Socket Units.976builder # [ 1.793742] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...977server # [ 1.742089] systemd[1]: Starting Create List of Static Device Nodes...978server # [ 1.745947] systemd[1]: Starting Load Kernel Module configfs...979builder # [ 1.805514] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.980builder # [ 1.816072] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev981server # [ 1.755067] systemd[1]: Starting Journal Service...982server # [ 1.782066] systemd[1]: Starting Load Kernel Modules...983builder # [ 1.838749] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.984server # [ 1.785978] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os985builder # [ 1.848993] systemd[1]: Starting Create Static Device Nodes in /dev...986server # [ 1.796105] systemd[1]: Starting Coldplug All udev Devices...987server # [ 1.804178] systemd-journald[65]: Collecting audit messages is disabled.988builder # [ 1.862782] systemd[1]: Finished Load Kernel Modules.989server # [ 1.810965] systemd[1]: Finished Create List of Static Device Nodes.990server # [ 1.814529] systemd[1]: modprobe@configfs.service: Deactivated successfully.991builder # [ 1.871759] systemd[1]: Starting Apply Kernel Variables...992server # [ 1.823971] systemd[1]: Finished Load Kernel Module configfs.993server # [ 1.829323] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config994builder # [ 1.742113] systemd-modules-load[66]: Inserted module 'dm_mod'995builder # [ 1.746183] systemd-modules-load[66]: Inserted module 'virtio_balloon'996builder # [ 1.747287] systemd-modules-load[66]: Inserted module 'virtio_gpu'997builder # [ 1.891818] systemd[1]: Started Journal Service.998server # [ 1.842951] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...999builder # [ 1.757955] systemd[1]: Finished Create Static Device Nodes in /dev.1000builder # [ 1.762992] systemd[1]: Reached target Preparation for Local File Systems.1001builder # [ 1.767069] systemd[1]: Reached target Local File Systems.1002server # [ 1.853144] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.1003builder # [ 1.772072] systemd[1]: Starting Create System Files and Directories...1004server # [ 1.861911] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev1005builder # [ 1.776139] systemd[1]: Starting Rule-based Manager for Device Events and Files...1006builder # [ 1.787533] systemd[1]: Finished Apply Kernel Variables.1007server # [ 1.886531] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1008server # [ 1.896178] systemd[1]: Starting Create Static Device Nodes in /dev...1009builder # [ 1.810256] systemd[1]: Finished Create System Files and Directories.1010server # [ 1.909963] systemd[1]: Finished Load Kernel Modules.1011builder # [ 1.834383] systemd-udevd[80]: Using default interface naming scheme 'v261'.1012server # [ 1.922572] systemd[1]: Starting Apply Kernel Variables...1013server # [ 1.781967] systemd-modules-load[67]: Inserted module 'dm_mod'1014server # [ 1.787131] systemd-modules-load[67]: Inserted module 'virtio_balloon'1015server # [ 1.789297] systemd-modules-load[67]: Inserted module 'virtio_gpu'1016server # [ 1.933241] systemd[1]: Started Journal Service.1017builder # [ 1.856606] systemd[1]: Started Rule-based Manager for Device Events and Files.1018server # [ 1.810164] systemd[1]: Finished Create Static Device Nodes in /dev.1019server # [ 1.812624] systemd[1]: Reached target Preparation for Local File Systems.1020server # [ 1.817088] systemd[1]: Reached target Local File Systems.1021server # [ 1.820084] systemd[1]: Starting Create System Files and Directories...1022server # [ 1.827081] systemd[1]: Starting Rule-based Manager for Device Events and Files...1023server # [ 1.836614] systemd[1]: Finished Apply Kernel Variables.1024builder # [ 1.911826] systemd[1]: Finished Coldplug All udev Devices.1025builder # [ 1.912858] systemd[1]: Reached target System Initialization.1026builder # [ 1.914152] systemd[1]: Reached target Basic System.1027server # [ 1.863711] systemd[1]: Finished Create System Files and Directories.1028server # [ 1.886976] systemd-udevd[80]: Using default interface naming scheme 'v261'.1029server # [ 1.912221] systemd[1]: Started Rule-based Manager for Device Events and Files.1030server # [ 1.954991] systemd[1]: Finished Coldplug All udev Devices.1031server # [ 1.955907] systemd[1]: Reached target System Initialization.1032server # [ 1.956746] systemd[1]: Reached target Basic System.1033builder # [ 2.249404] virtio_blk virtio2: 1/0/0 default/read/poll queues1034builder # [ 2.261065] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121035builder # [ 2.262268] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1036builder # [ 2.275876] serio: i8042 KBD port at 0x60,0x64 irq 11037builder # [ 2.276624] serio: i8042 AUX port at 0x60,0x64 irq 121038builder # [ 2.285790] ehci-pci 0000:00:1d.7: EHCI Host Controller1039builder # [ 2.286551] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11040builder # [ 2.288498] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001041builder # [ 2.296686] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001042builder # [ 2.298091] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181043builder # [ 2.299205] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11044builder # [ 2.301428] usb usb1: Product: EHCI Host Controller1045builder # [ 2.303417] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1046builder # [ 2.304322] usb usb1: SerialNumber: 0000:00:1d.71047builder # [ 2.305461] hub 1-0:1.0: USB hub found1048builder # [ 2.306546] hub 1-0:1.0: 6 ports detected1049builder # [ 2.311898] uhci_hcd 0000:00:1d.0: UHCI Host Controller1050builder # [ 2.312693] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21051builder # [ 2.327137] uhci_hcd 0000:00:1d.0: detected 2 ports1052builder # [ 2.334851] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801053server # [ 2.287509] virtio_blk virtio2: 1/0/0 default/read/poll queues1054builder # [ 2.343817] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181055builder # [ 2.344994] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11056server # [ 2.298257] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)1057server # [ 2.303371] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,121058builder # [ 2.361296] SCSI subsystem initialized1059builder # [ 2.363204] usb usb2: Product: UHCI Host Controller1060server # [ 2.313974] ehci-pci 0000:00:1d.7: EHCI Host Controller1061server # [ 2.314719] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 11062builder # [ 2.369098] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1063server # [ 2.316539] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb0001064builder # [ 2.232069] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.1065server # [ 2.319453] serio: i8042 KBD port at 0x60,0x64 irq 11066builder # [ 2.374790] usb usb2: SerialNumber: 0000:00:1d.01067server # [ 2.322903] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.001068server # [ 2.323824] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.181069server # [ 2.325610] serio: i8042 AUX port at 0x60,0x64 irq 121070server # [ 2.326438] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=11071server # [ 2.329343] usb usb1: Product: EHCI Host Controller1072builder # [ 2.243179] systemd[1]: Starting Virtual Console Setup...1073server # [ 2.330422] usb usb1: Manufacturer: Linux 6.18.54 ehci_hcd1074server # [ 2.332022] usb usb1: SerialNumber: 0000:00:1d.71075builder # [ 2.386436] hub 2-0:1.0: USB hub found1076server # [ 2.334983] hub 1-0:1.0: USB hub found1077server # [ 2.335557] hub 1-0:1.0: 6 ports detected1078server # [ 2.339664] uhci_hcd 0000:00:1d.0: UHCI Host Controller1079server # [ 2.340710] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 21080builder # [ 2.395088] hub 2-0:1.0: 2 ports detected1081server # [ 2.344551] uhci_hcd 0000:00:1d.0: detected 2 ports1082server # [ 2.349447] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c1801083builder # [ 2.264719] (udev-worker)[84]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1084builder # [ 2.266889] (udev-worker)[84]: Network interface NamePolicy= disabled on kernel command line.1085server # [ 2.358658] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181086server # [ 2.359799] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=11087server # [ 2.372555] usb usb2: Product: UHCI Host Controller1088builder # [ 2.427383] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01089builder # [ 2.289923] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones.1090server # [ 2.379475] usb usb2: Manufacturer: Linux 6.18.54 uhci_hcd1091builder # [ 2.295452] systemd[1]: Finished Virtual Console Setup.1092server # [ 2.387252] usb usb2: SerialNumber: 0000:00:1d.01093builder # [ 2.442528] uhci_hcd 0000:00:1d.1: UHCI Host Controller1094builder # [ 2.443330] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31095server # [ 2.394248] hub 2-0:1.0: USB hub found1096server # [ 2.398246] hub 2-0:1.0: 2 ports detected1097builder # [ 2.454794] uhci_hcd 0000:00:1d.1: detected 2 ports1098builder # [ 2.455614] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01099builder # [ 2.462623] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181100builder # [ 2.323212] systemd[1]: Found device /dev/disk/by-label/nixos.1101server # [ 2.270376] systemd[1]: Starting Virtual Console Setup...1102builder # [ 2.325068] systemd[1]: Reached target Initrd Root Device.1103server # [ 2.273274] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.1104builder # [ 2.328068] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1105builder # [ 2.470676] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11106builder # [ 2.475508] usb usb3: Product: UHCI Host Controller1107builder # [ 2.476401] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1108builder # [ 2.477250] usb usb3: SerialNumber: 0000:00:1d.11109server # [ 2.425337] SCSI subsystem initialized1110builder # [ 2.480019] hub 3-0:1.0: USB hub found1111builder # [ 2.485268] hub 3-0:1.0: 2 ports detected1112server # [ 2.433364] uhci_hcd 0000:00:1d.1: UHCI Host Controller1113server # [ 2.434186] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 31114builder # [ 2.494479] uhci_hcd 0000:00:1d.2: UHCI Host Controller1115builder # [ 2.495316] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41116builder # [ 2.498744] uhci_hcd 0000:00:1d.2: detected 2 ports1117builder # [ 2.499610] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01118builder # [ 2.503716] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181119server # [ 2.310380] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1120builder # [ 2.505694] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11121builder # [ 2.508237] usb usb4: Product: UHCI Host Controller1122server # [ 2.314212] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.1123server # [ 2.318460] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.1124builder # [ 2.375665] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks1125server # [ 2.462619] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input01126server # [ 2.324209] systemd[1]: Finished Virtual Console Setup.1127builder # [ 2.518012] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1128builder # [ 2.518851] usb usb4: SerialNumber: 0000:00:1d.21129builder # [ 2.383988] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1130server # [ 2.472053] uhci_hcd 0000:00:1d.1: detected 2 ports1131builder # [ 2.526899] hub 4-0:1.0: USB hub found1132builder # [ 2.529036] hub 4-0:1.0: 2 ports detected1133server # [ 2.478113] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a01134server # [ 2.487433] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181135server # [ 2.488626] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=11136builder # [ 2.544686] usb 1-1: new high-speed USB device number 2 using ehci-pci1137server # [ 2.357680] systemd[1]: Found device /dev/disk/by-label/nixos.1138builder # [ 2.552095] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1139server # [ 2.359771] systemd[1]: Reached target Initrd Root Device.1140server # [ 2.500785] usb usb3: Product: UHCI Host Controller1141server # [ 2.502932] usb usb3: Manufacturer: Linux 6.18.54 uhci_hcd1142server # [ 2.503673] usb usb3: SerialNumber: 0000:00:1d.11143server # [ 2.364954] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...1144builder # [ 2.564186] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1145server # [ 2.511272] hub 3-0:1.0: USB hub found1146server # [ 2.512788] hub 3-0:1.0: 2 ports detected1147builder # [ 2.567126] ahci 0000:00:1f.2: flags: 64bit ncq only1148builder # [ 2.574729] scsi host0: ahci1149server # [ 2.522904] uhci_hcd 0000:00:1d.2: UHCI Host Controller1150server # [ 2.523620] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 41151builder # [ 2.578770] scsi host1: ahci1152builder # [ 2.580745] scsi host2: ahci1153builder # [ 2.582544] scsi host3: ahci1154server # [ 2.529431] uhci_hcd 0000:00:1d.2: detected 2 ports1155builder # [ 2.584312] scsi host4: ahci1156server # [ 2.531056] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c01157builder # [ 2.586014] scsi host5: ahci1158builder # [ 2.587560] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11159server # [ 2.534030] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.181160builder # [ 2.588885] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11161builder # [ 2.590132] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11162server # [ 2.536910] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=11163builder # [ 2.591280] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11164server # [ 2.538933] usb usb4: Product: UHCI Host Controller1165builder # [ 2.592471] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11166server # [ 2.539662] usb usb4: Manufacturer: Linux 6.18.54 uhci_hcd1167builder # [ 2.593687] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11168server # [ 2.542905] usb usb4: SerialNumber: 0000:00:1d.21169server # [ 2.545077] hub 4-0:1.0: USB hub found1170server # [ 2.548955] hub 4-0:1.0: 2 ports detected1171server # [ 2.411529] systemd-fsck[104]: nixos: clean, 12/65536 files, 13019/262144 blocks1172server # [ 2.416154] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.1173server # [ 2.578902] usb 1-1: new high-speed USB device number 2 using ehci-pci1174server # [ 2.595664] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode1175server # [ 2.596953] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)1176server # [ 2.600497] ahci 0000:00:1f.2: flags: 64bit ncq only1177server # [ 2.607042] scsi host0: ahci1178server # [ 2.608505] scsi host1: ahci1179server # [ 2.610394] scsi host2: ahci1180server # [ 2.612179] scsi host3: ahci1181server # [ 2.613913] scsi host4: ahci1182server # [ 2.615525] scsi host5: ahci1183server # [ 2.616605] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 11184server # [ 2.618330] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 11185server # [ 2.619475] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 11186server # [ 2.620708] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 11187builder # [ 2.674987] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001188server # [ 2.621916] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 11189builder # [ 2.676130] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101190server # [ 2.623057] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 11191builder # [ 2.677485] usb 1-1: Product: QEMU USB Tablet1192builder # [ 2.678173] usb 1-1: Manufacturer: QEMU1193builder # [ 2.678809] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11194builder # [ 2.694879] hid: raw HID events driver (C) Jiri Kosina1195builder # [ 2.618622] systemd[1]: Mounting /sysroot...1196server # [ 2.709252] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.001197server # [ 2.712199] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=101198server # [ 2.715427] usb 1-1: Product: QEMU USB Tablet1199server # [ 2.717286] usb 1-1: Manufacturer: QEMU1200server # [ 2.718979] usb 1-1: SerialNumber: 28754-0000:00:1d.7-11201server # [ 2.752763] hid: raw HID events driver (C) Jiri Kosina1202server # [ 2.669291] systemd[1]: Mounting /sysroot...1203builder # [ 2.905148] ata1: SATA link down (SStatus 0 SControl 300)1204builder # [ 2.907530] ata2: SATA link down (SStatus 0 SControl 300)1205builder # [ 2.910353] ata4: SATA link down (SStatus 0 SControl 300)1206builder # [ 2.912807] ata5: SATA link down (SStatus 0 SControl 300)1207builder # [ 2.915102] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1208builder # [ 2.917716] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001209builder # [ 2.919690] ata3.00: applying bridge limits1210builder # [ 2.921757] ata6: SATA link down (SStatus 0 SControl 300)1211builder # [ 2.924031] ata3.00: configured for UDMA/1001212builder # [ 2.926357] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51213server # [ 2.938671] ata4: SATA link down (SStatus 0 SControl 300)1214server # [ 2.940994] ata1: SATA link down (SStatus 0 SControl 300)1215server # [ 2.943665] ata6: SATA link down (SStatus 0 SControl 300)1216server # [ 2.946062] ata5: SATA link down (SStatus 0 SControl 300)1217server # [ 2.948299] ata2: SATA link down (SStatus 0 SControl 300)1218builder # [ 3.004786] usbcore: registered new interface driver usbhid1219builder # [ 3.005609] usbhid: USB HID core driver1220server # [ 2.950733] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)1221server # [ 2.953238] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/1001222server # [ 2.955332] ata3.00: applying bridge limits1223server # [ 2.957204] ata3.00: configured for UDMA/1001224server # [ 2.959692] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 51225builder # [ 3.025821] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1226builder # [ 3.038365] 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/input21227builder # [ 3.040221] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01228builder # [ 3.044776] cdrom: Uniform CD-ROM driver Revision: 3.201229builder # [ 3.059124] EXT4-fs (vda): mounted filesystem 853dee97-ef0a-4222-b5eb-835a445c35e0 r/w with ordered data mode. Quota mode: none.1230builder # [ 2.923873] systemd[1]: Mounted /sysroot.1231builder # [ 2.926350] systemd[1]: Reached target Initrd Root File System.1232builder # [ 2.931142] systemd[1]: Mounting /sysroot/nix/.ro-store...1233builder # [ 2.936782] systemd[1]: Mounting /sysroot/nix/.rw-store...1234builder # [ 2.944153] systemd[1]: Mounting /sysroot/run...1235builder # [ 2.952587] systemd[1]: Mounting /sysroot/tmp/shared...1236server # [ 3.041994] usbcore: registered new interface driver usbhid1237server # [ 3.042794] usbhid: USB HID core driver1238builder # [ 2.961153] systemd[1]: Mounting /sysroot/tmp/xchg...1239server # [ 3.054923] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray1240builder # [ 2.970425] systemd[1]: Starting Mountpoints Configured in the Real Root...1241server # [ 3.069120] 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.073098] cdrom: Uniform CD-ROM driver Revision: 3.201243server # [ 3.074079] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input01244builder # [ 3.003100] systemd-sysroot-fstab-check[144]: /sysroot should be mounted in the initrd, will request daemon-reload.1245server # [ 3.090066] EXT4-fs (vda): mounted filesystem 57c17e64-9225-4e37-9989-3ae6127bc321 r/w with ordered data mode. Quota mode: none.1246server # [ 2.957525] systemd[1]: Mounted /sysroot.1247server # [ 2.959379] systemd[1]: Reached target Initrd Root File System.1248server # [ 2.963072] systemd[1]: Starting Mountpoints Configured in the Real Root...1249builder # [ 3.018898] systemd[1]: Mounted /sysroot/nix/.ro-store.1250builder # [ 3.020071] systemd[1]: Mounted /sysroot/nix/.rw-store.1251builder # [ 3.021117] systemd[1]: Mounted /sysroot/run.1252builder # [ 3.021995] systemd[1]: Mounted /sysroot/tmp/shared.1253builder # [ 3.022953] systemd[1]: Mounted /sysroot/tmp/xchg.1254builder # [ 3.028594] systemd[1]: Reload requested from client PID 144 ('systemd-sysroot') (unit initrd-parse-etc.service)...1255builder # [ 3.031085] systemd[1]: Reloading...1256server # [ 2.979071] systemd-sysroot-fstab-check[136]: /sysroot should be mounted in the initrd, will request daemon-reload.1257server # [ 2.983078] systemd[1]: Reload requested from client PID 136 ('systemd-sysroot') (unit initrd-parse-etc.service)...1258server # [ 2.984578] systemd[1]: Reloading...1259builder # [ 3.095245] systemd[1]: Reloading finished in 64 ms.1260builder # [ 3.104189] systemd-sysroot-fstab-check[144]: Requesting initrd-fs.target/start/replace...1261builder # [ 3.107132] systemd-sysroot-fstab-check[144]: Requesting swap.target/start/replace...1262builder # [ 3.108810] systemd[1]: Starting rw-sysroot-nix-store.service...1263builder # [ 3.111313] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1264builder # [ 3.114060] systemd[1]: Finished Mountpoints Configured in the Real Root.1265builder # [ 3.115048] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1266server # [ 3.067909] systemd[1]: Reloading finished in 81 ms.1267builder # [ 3.126782] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1268builder # [ 3.128332] systemd[1]: Finished rw-sysroot-nix-store.service.1269server # [ 3.077163] systemd-sysroot-fstab-check[136]: Requesting initrd-fs.target/start/replace...1270builder # [ 3.131307] systemd[1]: Mounting /sysroot/nix/store...1271server # [ 3.079198] systemd-sysroot-fstab-check[136]: Requesting swap.target/start/replace...1272server # [ 3.084670] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1273server # [ 3.087066] systemd[1]: Finished Mountpoints Configured in the Real Root.1274server # [ 3.088133] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1275builder # [ 3.153871] systemd[1]: Mounted /sysroot/nix/store.1276builder # [ 3.155319] systemd[1]: Reached target Initrd File Systems.1277builder # [ 3.157097] systemd[1]: Starting Find NixOS closure...1278builder # [ 3.159644] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1279builder # [ 3.179798] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1280builder # [ 3.186230] systemd[1]: Finished Find NixOS closure.1281builder # [ 3.188145] systemd[1]: Reached target Initrd Default Target.1282builder # [ 3.189656] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1283builder # [ 3.203639] systemd[1]: Stopped target Initrd Default Target.1284builder # [ 3.205043] systemd[1]: Stopped target Basic System.1285builder # [ 3.205916] systemd[1]: Stopped target Initrd Root Device.1286builder # [ 3.206874] systemd[1]: Stopped target Path Units.1287builder # [ 3.208105] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1288builder # [ 3.209151] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1289builder # [ 3.210526] systemd[1]: Stopped target Slice Units.1290builder # [ 3.211563] systemd[1]: Stopped target Socket Units.1291builder # [ 3.213064] systemd[1]: Stopped target System Initialization.1292builder # [ 3.213919] systemd[1]: Stopped target Swaps.1293builder # [ 3.214690] systemd[1]: Stopped target Timer Units.1294builder # [ 3.215549] systemd[1]: dbus.socket: Deactivated successfully.1295builder # [ 3.217116] systemd[1]: Closed D-Bus System Message Bus Socket.1296builder # [ 3.217952] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1297builder # [ 3.218983] systemd[1]: Stopped Find NixOS closure.1298builder # [ 3.220474] systemd[1]: Starting rw-sysroot-nix-store.service...1299builder # [ 3.221404] systemd[1]: systemd-sysctl.service: Deactivated successfully.1300builder # [ 3.223328] systemd[1]: Stopped Apply Kernel Variables.1301builder # [ 3.224368] systemd[1]: systemd-modules-load.service: Deactivated successfully.1302builder # [ 3.225580] systemd[1]: Stopped Load Kernel Modules.1303builder # [ 3.226527] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1304builder # [ 3.228125] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1305builder # [ 3.229209] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1306builder # [ 3.230231] systemd[1]: Stopped Create System Files and Directories.1307builder # [ 3.232213] systemd[1]: Stopped target Local File Systems.1308builder # [ 3.233251] systemd[1]: Stopped target Preparation for Local File Systems.1309builder # [ 3.234332] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1310builder # [ 3.235542] systemd[1]: Stopped Coldplug All udev Devices.1311builder # [ 3.237057] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1312builder # [ 3.238631] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1313builder # [ 3.239683] systemd[1]: Stopped Virtual Console Setup.1314builder # [ 3.249628] systemd[1]: initrd-cleanup.service: Deactivated successfully.1315builder # [ 3.253262] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1316builder # [ 3.259602] systemd[1]: systemd-udevd.service: Deactivated successfully.1317builder # [ 3.261276] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1318builder # [ 3.264542] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1319builder # [ 3.265600] systemd[1]: Closed udev Control Socket.1320builder # [ 3.267080] systemd[1]: Starting Cleanup udev Database...1321builder # [ 3.267889] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1322builder # [ 3.270066] systemd[1]: Stopped Create Static Device Nodes in /dev.1323builder # [ 3.271050] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1324builder # [ 3.272199] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1325builder # [ 3.273236] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1326builder # [ 3.274226] systemd[1]: Stopped Create List of Static Device Nodes.1327builder # [ 3.275456] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1328builder # [ 3.277081] systemd[1]: Finished rw-sysroot-nix-store.service.1329builder # [ 3.294766] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1330builder # [ 3.296594] systemd[1]: Finished Cleanup udev Database.1331builder # [ 3.297739] systemd[1]: Reached target Switch Root.1332builder # [ 3.299626] systemd[1]: Starting NixOS Activation...1333builder # [ 3.377745] initrd-nixos-activation-start[189]: booting system configuration /nix/store/01ph8r57zn7q7j8gckshmla2m1yn047z-nixos-system-builder-test1334builder # [ 3.407056] initrd-nixos-activation-start[189]: running activation script...1335builder # [ 3.614277] initrd-nixos-activation-start[212]: setting up /etc...1336builder # [ 3.721364] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1337builder # [ 3.724079] systemd[1]: Finished NixOS Activation.1338builder # [ 3.725332] systemd[1]: Starting Switch Root...1339server # [ 3.671605] systemd[1]: Mounting /sysroot/nix/.ro-store...1340server # [ 3.679184] systemd[1]: Mounting /sysroot/nix/.rw-store...1341server # [ 3.685158] systemd[1]: Mounting /sysroot/run...1342builder # [ 3.740159] systemd[1]: Switching root.1343server # [ 3.696306] systemd[1]: Mounting /sysroot/tmp/shared...1344server # [ 3.707622] systemd[1]: Mounting /sysroot/tmp/xchg...1345server # [ 3.745554] systemd[1]: Mounted /sysroot/nix/.rw-store.1346server # [ 3.752433] systemd[1]: Mounted /sysroot/nix/.ro-store.1347server # [ 3.755184] systemd[1]: Mounted /sysroot/run.1348server # [ 3.755932] systemd[1]: Mounted /sysroot/tmp/shared.1349server # [ 3.756917] systemd[1]: Mounted /sysroot/tmp/xchg.1350server # [ 3.761242] systemd[1]: Starting rw-sysroot-nix-store.service...1351server # [ 3.774080] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1352server # [ 3.775105] systemd[1]: Finished rw-sysroot-nix-store.service.1353server # [ 3.776546] systemd[1]: Mounting /sysroot/nix/store...1354server # [ 3.799482] systemd[1]: Mounted /sysroot/nix/store.1355server # [ 3.800759] systemd[1]: Reached target Initrd File Systems.1356server # [ 3.803078] systemd[1]: Starting Find NixOS closure...1357server # [ 3.805459] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1358builder # [ 4.008276] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1359server # [ 3.824215] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1360server # [ 3.832398] systemd[1]: Finished Find NixOS closure.1361server # [ 3.834187] systemd[1]: Reached target Initrd Default Target.1362server # [ 3.835268] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1363server # [ 3.849286] systemd[1]: Stopped target Initrd Default Target.1364server # [ 3.850401] systemd[1]: Stopped target Basic System.1365server # [ 3.852169] systemd[1]: Stopped target Initrd Root Device.1366server # [ 3.852940] systemd[1]: Stopped target Path Units.1367server # [ 3.853669] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1368server # [ 3.854701] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1369server # [ 3.855752] systemd[1]: Stopped target Slice Units.1370server # [ 3.856546] systemd[1]: Stopped target Socket Units.1371server # [ 3.857308] systemd[1]: Stopped target System Initialization.1372server # [ 3.858429] systemd[1]: Stopped target Swaps.1373server # [ 3.859324] systemd[1]: Stopped target Timer Units.1374server # [ 3.861123] systemd[1]: dbus.socket: Deactivated successfully.1375server # [ 3.862085] systemd[1]: Closed D-Bus System Message Bus Socket.1376server # [ 3.862932] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1377server # [ 3.863993] systemd[1]: Stopped Find NixOS closure.1378server # [ 3.865360] systemd[1]: Starting rw-sysroot-nix-store.service...1379server # [ 3.867198] systemd[1]: systemd-sysctl.service: Deactivated successfully.1380server # [ 3.868344] systemd[1]: Stopped Apply Kernel Variables.1381server # [ 3.869403] systemd[1]: systemd-modules-load.service: Deactivated successfully.1382server # [ 3.870786] systemd[1]: Stopped Load Kernel Modules.1383server # [ 3.871754] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1384server # [ 3.873169] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1385server # [ 3.875116] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1386server # [ 3.876117] systemd[1]: Stopped Create System Files and Directories.1387server # [ 3.877183] systemd[1]: Stopped target Local File Systems.1388server # [ 3.878109] systemd[1]: Stopped target Preparation for Local File Systems.1389server # [ 3.880116] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1390server # [ 3.881335] systemd[1]: Stopped Coldplug All udev Devices.1391server # [ 3.882091] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1392server # [ 3.884112] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1393server # [ 3.885087] systemd[1]: Stopped Virtual Console Setup.1394server # [ 3.894831] systemd[1]: initrd-cleanup.service: Deactivated successfully.1395server # [ 3.899212] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1396server # [ 3.901828] systemd[1]: systemd-udevd.service: Deactivated successfully.1397builder # [ 4.096792] NET: Registered PF_VSOCK protocol family1398server # [ 3.905200] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1399server # [ 3.908498] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1400server # [ 3.909609] systemd[1]: Closed udev Control Socket.1401server # [ 3.910638] systemd[1]: Starting Cleanup udev Database...1402server # [ 3.911511] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1403server # [ 3.913131] systemd[1]: Stopped Create Static Device Nodes in /dev.1404server # [ 3.913969] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1405server # [ 3.915061] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1406server # [ 3.915995] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1407server # [ 3.917101] systemd[1]: Stopped Create List of Static Device Nodes.1408server # [ 3.919301] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1409server # [ 3.920681] systemd[1]: Finished rw-sysroot-nix-store.service.1410server # [ 3.938030] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1411server # [ 3.939520] systemd[1]: Finished Cleanup udev Database.1412server # [ 3.941303] systemd[1]: Reached target Switch Root.1413server # [ 3.942571] systemd[1]: Starting NixOS Activation...1414server # [ 4.018817] initrd-nixos-activation-start[188]: booting system configuration /nix/store/b8j47s6j4jkmk7y8pqhvsxwm6ljc386x-nixos-system-server-test1415server # [ 4.046629] initrd-nixos-activation-start[188]: running activation script...1416server # [ 4.255073] initrd-nixos-activation-start[211]: setting up /etc...1417builder # [ 4.455285] 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)1418builder # [ 4.465055] systemd[1]: Detected virtualization kvm.1419builder # [ 4.466831] systemd[1]: Detected architecture x86-64.1420builder # [ 4.468794] systemd[1]: Detected first boot.1421builder # [ 4.472870] systemd[1]: Initializing machine ID from random generator.1422server # [ 4.359478] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1423server # [ 4.361962] systemd[1]: Finished NixOS Activation.1424server # [ 4.362975] systemd[1]: Starting Switch Root...1425server # [ 4.376083] systemd[1]: Switching root.1426server # [ 4.639530] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).1427builder # [ 4.713615] systemd[1]: bpf-restrict-fs: LSM BPF program attached1428server # [ 4.731493] NET: Registered PF_VSOCK protocol family1429builder # [ 4.820603] systemd[1]: Applying preset policy.1430builder # [ 4.983113] systemd[1]: Populated /etc with preset unit settings.1431server # [ 5.091206] 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)1432server # [ 5.100840] systemd[1]: Detected virtualization kvm.1433server # [ 5.102644] systemd[1]: Detected architecture x86-64.1434server # [ 5.104551] systemd[1]: Detected first boot.1435server # [ 5.108930] systemd[1]: Initializing machine ID from random generator.1436builder # [ 5.171444] systemd[1]: initrd-switch-root.service: Deactivated successfully.1437builder # [ 5.173598] systemd[1]: Stopped initrd-switch-root.service.1438builder # [ 5.176263] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1439builder # [ 5.178534] systemd[1]: Created slice Slice /system/getty.1440builder # [ 5.180034] systemd[1]: Created slice User and Session Slice.1441builder # [ 5.180949] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1442builder # [ 5.182126] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1443builder # [ 5.183192] systemd[1]: Expecting device /dev/hvc0...1444builder # [ 5.183950] systemd[1]: Expecting device /dev/ttyS0...1445builder # [ 5.184725] systemd[1]: Reached target Local Encrypted Volumes.1446builder # [ 5.185526] systemd[1]: Stopped target initrd-fs.target.1447builder # [ 5.186315] systemd[1]: Stopped target initrd-root-fs.target.1448builder # [ 5.187090] systemd[1]: Stopped target initrd-switch-root.target.1449builder # [ 5.187918] systemd[1]: Reached target Virtual Machines and Containers.1450builder # [ 5.188843] systemd[1]: Reached target Path Units.1451builder # [ 5.189543] systemd[1]: Reached target Remote File Systems.1452builder # [ 5.190341] systemd[1]: Reached target Slice Units.1453builder # [ 5.191043] systemd[1]: Reached target Swaps.1454builder # [ 5.193089] systemd[1]: Listening on Query the User Interactively for a Password.1455builder # [ 5.195564] systemd[1]: Listening on Process Core Dump Socket.1456builder # [ 5.197391] systemd[1]: Listening on Credential Encryption/Decryption.1457builder # [ 5.199318] systemd[1]: Listening on Factory Reset Management.1458builder # [ 5.211880] systemd[1]: Listening on Hostname Service Socket.1459builder # [ 5.215134] systemd[1]: Starting Journal Log Access Socket...1460builder # [ 5.216317] systemd[1]: Listening on Journal Audit Socket.1461builder # [ 5.219085] systemd[1]: Listening on Console Output Muting Service Socket.1462builder # [ 5.220512] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1463builder # [ 5.221679] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1464builder # [ 5.222974] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1465builder # [ 5.227022] systemd[1]: Listening on Disk Repartitioning Service Socket.1466builder # [ 5.228044] systemd[1]: Listening on udev Control Socket.1467builder # [ 5.228902] systemd[1]: Listening on udev Varlink Socket.1468builder # [ 5.231402] systemd[1]: Mounting Huge Pages File System...1469builder # [ 5.233947] systemd[1]: Mounting POSIX Message Queue File System...1470builder # [ 5.240721] systemd[1]: Mounting Kernel Debug File System...1471builder # [ 5.247237] systemd[1]: Mounting Kernel Trace File System...1472builder # [ 5.255933] systemd[1]: Starting Create List of Static Device Nodes...1473builder # [ 5.266723] systemd[1]: Starting Load Kernel Module configfs...1474builder # [ 5.267574] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1475builder # [ 5.274181] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1476builder # [ 5.280384] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1477builder # [ 5.298753] systemd[1]: Mounting FUSE Control File System...1478builder # [ 5.299608] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671479builder # [ 5.315456] systemd[1]: Starting Journal Service...1480builder # [ 5.319790] systemd[1]: Starting Load Kernel Modules...1481builder # [ 5.327275] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1482builder # [ 5.336305] systemd[1]: Starting Remount Root and Kernel File Systems...1483builder # [ 5.338107] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1484builder # [ 5.344736] systemd[1]: Starting Coldplug All udev Devices...1485builder # [ 5.349224] systemd[1]: Listening on Journal Log Access Socket.1486builder # [ 5.352366] systemd[1]: Mounted Huge Pages File System.1487builder # [ 5.354478] systemd[1]: Mounted POSIX Message Queue File System.1488builder # [ 5.356863] systemd[1]: Mounted Kernel Debug File System.1489builder # [ 5.358497] systemd[1]: Mounted Kernel Trace File System.1490builder # [ 5.362968] systemd[1]: Finished Create List of Static Device Nodes.1491builder # [ 5.364076] systemd[1]: Mounted FUSE Control File System.1492builder # [ 5.368461] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1493builder # [ 5.387765] systemd[1]: modprobe@configfs.service: Deactivated successfully.1494builder # [ 5.389138] systemd[1]: Finished Load Kernel Module configfs.1495builder # [ 5.393785] systemd[1]: Mounting Kernel Configuration File System...1496builder # [ 5.421174] systemd[1]: Mounted Kernel Configuration File System.1497server # [ 5.369196] systemd[1]: bpf-restrict-fs: LSM BPF program attached1498builder # [ 5.440756] EXT4-fs (vda): re-mounted 853dee97-ef0a-4222-b5eb-835a445c35e0.1499builder # [ 5.453728] systemd[1]: Finished Remount Root and Kernel File Systems.1500builder # [ 5.455521] systemd[1]: Listening on Disk Image Download Service Socket.1501builder # [ 5.456810] loop: module loaded1502builder # [ 5.457826] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1503builder # [ 5.460883] systemd-journald[282]: Collecting audit messages is enabled.1504builder # [ 5.464511] systemd[1]: Starting Load/Save OS Random Seed...1505builder # [ 5.466143] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1506builder # [ 5.486204] tun: Universal TUN/TAP device driver, 1.61507builder # [ 5.496573] systemd[1]: Finished Load Kernel Modules.1508builder # [ 5.502397] systemd[1]: Starting Firewall...1509builder # [ 5.362732] systemd[1]: Queued start job for default target Multi-User System.1510builder # [ 5.365416] systemd[1]: systemd-journald.service: Deactivated successfully.1511builder # [ 5.507367] systemd[1]: Starting Apply Kernel Variables...1512server # [ 5.453920] systemd[1]: Applying preset policy.1513builder # [ 5.508255] systemd[1]: Started Journal Service.1514builder # [ 5.375616] systemd-modules-load[283]: Inserted module 'loop'1515builder # [ 5.382230] systemd-modules-load[283]: Inserted module 'tun'1516builder # [ 5.387658] systemd[1]: Starting Flush Journal to Persistent Storage...1517builder # [ 5.406179] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1518builder # [ 5.413085] systemd[1]: Starting Create Static Device Nodes in /dev...1519builder # [ 5.430164] systemd[1]: Finished Load/Save OS Random Seed.1520builder # [ 5.431702] systemd[1]: Reached target First Boot Complete.1521builder # [ 5.457373] systemd-oomd[284]: No swap; memory pressure usage will be degraded1522builder # [ 5.604182] systemd-journald[282]: Received client request to flush runtime journal.1523server # [ 5.621772] systemd[1]: Populated /etc with preset unit settings.1524builder # [ 5.567332] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1525builder # [ 5.569650] systemd[1]: Finished Apply Kernel Variables.1526builder # [ 5.572140] systemd[1]: Finished Create Static Device Nodes in /dev.1527builder # [ 5.572989] systemd[1]: Reached target Preparation for Local File Systems.1528builder # [ 5.575989] systemd[1]: Starting Rule-based Manager for Device Events and Files...1529builder # [ 5.581862] systemd[1]: Finished Flush Journal to Persistent Storage.1530builder # [ 5.665726] systemd-udevd[314]: Using default interface naming scheme 'v261'.1531builder # [ 5.744218] systemd[1]: Started Rule-based Manager for Device Events and Files.1532server # [ 5.835671] systemd[1]: initrd-switch-root.service: Deactivated successfully.1533server # [ 5.837163] systemd[1]: Stopped initrd-switch-root.service.1534server # [ 5.839449] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1535server # [ 5.841402] systemd[1]: Created slice Slice /system/getty.1536server # [ 5.842805] systemd[1]: Created slice User and Session Slice.1537server # [ 5.843754] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1538server # [ 5.844970] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1539server # [ 5.846105] systemd[1]: Expecting device /dev/hvc0...1540server # [ 5.846782] systemd[1]: Expecting device /dev/ttyS0...1541server # [ 5.847536] systemd[1]: Reached target Local Encrypted Volumes.1542server # [ 5.848416] systemd[1]: Stopped target initrd-fs.target.1543server # [ 5.849239] systemd[1]: Stopped target initrd-root-fs.target.1544server # [ 5.850014] systemd[1]: Stopped target initrd-switch-root.target.1545server # [ 5.850858] systemd[1]: Reached target Virtual Machines and Containers.1546server # [ 5.851763] systemd[1]: Reached target Path Units.1547server # [ 5.852490] systemd[1]: Reached target Remote File Systems.1548server # [ 5.853292] systemd[1]: Reached target Slice Units.1549server # [ 5.853988] systemd[1]: Reached target Swaps.1550server # [ 5.855823] systemd[1]: Listening on Query the User Interactively for a Password.1551server # [ 5.858262] systemd[1]: Listening on Process Core Dump Socket.1552server # [ 5.860063] systemd[1]: Listening on Credential Encryption/Decryption.1553builder # [ 5.783239] systemd[1]: Finished Coldplug All udev Devices.1554server # [ 5.861935] systemd[1]: Listening on Factory Reset Management.1555server # [ 5.874456] systemd[1]: Listening on Hostname Service Socket.1556server # [ 5.877335] systemd[1]: Starting Journal Log Access Socket...1557server # [ 5.879020] systemd[1]: Listening on Journal Audit Socket.1558server # [ 5.881520] systemd[1]: Listening on Console Output Muting Service Socket.1559server # [ 5.882970] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1560server # [ 5.884147] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1561server # [ 5.885443] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1562server # [ 5.889464] systemd[1]: Listening on Disk Repartitioning Service Socket.1563server # [ 5.890604] systemd[1]: Listening on udev Control Socket.1564server # [ 5.891494] systemd[1]: Listening on udev Varlink Socket.1565server # [ 5.893873] systemd[1]: Mounting Huge Pages File System...1566server # [ 5.897267] systemd[1]: Mounting POSIX Message Queue File System...1567server # [ 5.904280] systemd[1]: Mounting Kernel Debug File System...1568server # [ 5.911781] systemd[1]: Mounting Kernel Trace File System...1569server # [ 5.920508] systemd[1]: Starting Create List of Static Device Nodes...1570server # [ 5.926755] systemd[1]: Starting Load Kernel Module configfs...1571server # [ 5.931552] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1572server # [ 5.938943] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1573server # [ 5.947530] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1574server # [ 5.958648] systemd[1]: Mounting FUSE Control File System...1575server # [ 5.963556] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671576server # [ 5.976313] systemd[1]: Starting Journal Service...1577server # [ 5.980979] systemd[1]: Starting Load Kernel Modules...1578builder # [ 5.894791] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1579server # [ 5.986185] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1580server # [ 5.994410] systemd[1]: Starting Remount Root and Kernel File Systems...1581server # [ 5.997040] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1582server # [ 6.003337] systemd[1]: Starting Coldplug All udev Devices...1583server # [ 6.007756] systemd[1]: Listening on Journal Log Access Socket.1584server # [ 6.010512] systemd[1]: Mounted Huge Pages File System.1585server # [ 6.013040] systemd[1]: Mounted POSIX Message Queue File System.1586server # [ 6.016032] systemd[1]: Mounted Kernel Debug File System.1587server # [ 6.017935] systemd[1]: Mounted Kernel Trace File System.1588server # [ 6.018779] systemd[1]: Mounted FUSE Control File System.1589server # [ 6.022567] systemd[1]: Finished Create List of Static Device Nodes.1590server # [ 6.029763] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1591server # [ 6.049648] systemd[1]: modprobe@configfs.service: Deactivated successfully.1592server # [ 6.051331] systemd[1]: Finished Load Kernel Module configfs.1593server # [ 6.055992] systemd[1]: Mounting Kernel Configuration File System...1594builder # [ 5.994106] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1595server # [ 6.083560] systemd[1]: Mounted Kernel Configuration File System.1596builder # [ 6.016257] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1597server # [ 6.105357] systemd-journald[281]: Collecting audit messages is enabled.1598server # [ 6.107906] EXT4-fs (vda): re-mounted 57c17e64-9225-4e37-9989-3ae6127bc321.1599server # [ 6.116983] systemd[1]: Finished Remount Root and Kernel File Systems.1600server # [ 6.118170] systemd[1]: Listening on Disk Image Download Service Socket.1601builder # [ 6.035135] systemd[1]: Mounting /run/wrappers...1602server # [ 6.120962] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1603server # [ 6.126044] loop: module loaded1604server # [ 6.127380] systemd[1]: Starting Load/Save OS Random Seed...1605server # [ 6.128236] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1606server # [ 6.149891] systemd[1]: Started Journal Service.1607server # [ 6.011742] systemd[1]: Queued start job for default target Multi-User System.1608server # [ 6.015250] systemd[1]: systemd-journald.service: Deactivated successfully.1609server # [ 6.020211] systemd-modules-load[282]: Inserted module 'loop'1610builder # [ 6.073604] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line.1611builder # [ 6.076757] systemd[1]: Mounted /run/wrappers.1612builder # [ 6.078532] systemd[1]: Reached target Local File Systems.1613builder # [ 6.080121] systemd[1]: Listening on Boot Loader Control Service Socket.1614server # [ 6.028510] systemd[1]: Starting Flush Journal to Persistent Storage...1615builder # [ 6.082165] systemd[1]: Starting register-nix-paths.service...1616builder # [ 6.085859] systemd[1]: Starting Create SUID/SGID Wrappers...1617builder # [ 6.087589] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1618builder # [ 6.091233] systemd[1]: Starting Save Transient machine-id to Disk...1619builder # [ 6.097095] systemd[1]: Starting Create System Files and Directories...1620server # [ 6.045926] systemd-modules-load[282]: Inserted module 'tls'1621builder # [ 6.099564] (udev-worker)[357]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1622builder # [ 6.101469] (udev-worker)[357]: Network interface NamePolicy= disabled on kernel command line.1623server # [ 6.056927] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1624server # [ 6.062430] systemd[1]: Starting Create Static Device Nodes in /dev...1625server # [ 6.203479] tun: Universal TUN/TAP device driver, 1.61626server # [ 6.070704] systemd-modules-load[282]: Inserted module 'tun'1627server # [ 6.074232] systemd[1]: Finished Load Kernel Modules.1628server # [ 6.080183] systemd[1]: Starting Firewall...1629server # [ 6.087065] systemd[1]: Starting Apply Kernel Variables...1630server # [ 6.088346] systemd[1]: Finished Load/Save OS Random Seed.1631server # [ 6.091125] systemd[1]: Reached target First Boot Complete.1632server # [ 6.106203] systemd-oomd[283]: No swap; memory pressure usage will be degraded1633server # [ 6.251828] systemd-journald[281]: Received client request to flush runtime journal.1634builder # [ 6.204957] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1635builder # [ 6.210301] systemd[1]: Finished Save Transient machine-id to Disk.1636builder # [ 6.259760] systemd[1]: Finished Create System Files and Directories.1637builder # [ 6.266223] systemd[1]: Starting Rebuild Journal Catalog...1638builder # [ 6.268929] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1639server # [ 6.232496] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1640server # [ 6.235348] systemd[1]: Finished Create Static Device Nodes in /dev.1641server # [ 6.237235] systemd[1]: Reached target Preparation for Local File Systems.1642server # [ 6.238673] systemd[1]: Starting Rule-based Manager for Device Events and Files...1643server # [ 6.240523] systemd[1]: Finished Apply Kernel Variables.1644server # [ 6.241552] systemd[1]: Finished Flush Journal to Persistent Storage.1645builder # [ 6.349791] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1646server # [ 6.309317] systemd-udevd[313]: Using default interface naming scheme 'v261'.1647builder # [ 6.405188] systemd[1]: Finished Rebuild Journal Catalog.1648builder # [ 6.408730] systemd[1]: Starting Update is Completed...1649server # [ 6.382629] systemd[1]: Started Rule-based Manager for Device Events and Files.1650builder # [ 6.474170] systemd[1]: Finished Update is Completed.1651builder # [ 6.497922] systemd[1]: Condition check resulted in Virtio network device being skipped.1652builder # [ 6.499115] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1653builder # [ 6.501245] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1654builder # [ 6.503224] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671655builder # [ 6.506332] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1656builder # [ 6.509051] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1657builder # [ 6.510779] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1658server # [ 6.462188] systemd[1]: Finished Coldplug All udev Devices.1659server # [ 6.552670] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1660builder # [ 6.641095] systemd[1]: Finished Firewall.1661server # [ 6.639109] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1662server # [ 6.659322] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.1663builder # [ 6.858499] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31664builder # [ 6.727321] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1665builder # [ 6.731269] systemd[1]: Finished Create SUID/SGID Wrappers.1666builder # [ 6.878527] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1667server # [ 6.699298] systemd[1]: Mounting /run/wrappers...1668server # [ 6.712359] (udev-worker)[353]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1669server # [ 6.714788] (udev-worker)[353]: Network interface NamePolicy= disabled on kernel command line.1670builder # [ 6.769561] systemd[1]: Finished register-nix-paths.service.1671builder # [ 6.773135] systemd[1]: Reached target System Initialization.1672builder # [ 6.773957] systemd[1]: Started Discard unused filesystem blocks once a week.1673builder # [ 6.776695] systemd[1]: Started Daily Cleanup of Temporary Directories.1674server # [ 6.723632] (udev-worker)[354]: Network interface NamePolicy= disabled on kernel command line.1675builder # [ 6.777647] systemd[1]: Reached target Timer Units.1676builder # [ 6.781377] systemd[1]: Listening on D-Bus System Message Bus Socket.1677builder # [ 6.783421] systemd[1]: Starting niks3 auto-upload socket...1678builder # [ 6.784265] systemd[1]: Listening on Nix Daemon Socket.1679builder # [ 6.785884] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1680builder # [ 6.787404] systemd[1]: Starting D-Bus System Message Bus...1681builder # [ 6.788985] systemd[1]: Listening on niks3 auto-upload socket.1682builder # [ 6.789834] systemd[1]: Reached target Socket Units.1683server # [ 6.739346] systemd[1]: Mounted /run/wrappers.1684server # [ 6.740523] systemd[1]: Reached target Local File Systems.1685server # [ 6.742682] systemd[1]: Listening on Boot Loader Control Service Socket.1686server # [ 6.747080] systemd[1]: Starting register-nix-paths.service...1687server # [ 6.750221] systemd[1]: Starting Create SUID/SGID Wrappers...1688server # [ 6.751065] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1689server # [ 6.753917] systemd[1]: Starting Save Transient machine-id to Disk...1690server # [ 6.756262] systemd[1]: Starting Create System Files and Directories...1691builder # [ 6.843813] dbus-broker-launch[503]: Looking up NSS user entry for 'systemd-timesync'...1692builder # [ 6.849599] dbus-broker-launch[503]: NSS returned no entry for 'systemd-timesync'1693builder # [ 6.850880] dbus-broker-launch[503]: Invalid user-name in /nix/store/w7zpn81qlzxwipwrzrqxs1rw0fs02pwi-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1694builder # [ 6.861536] systemd[1]: Started D-Bus System Message Bus.1695builder # [ 6.862406] systemd[1]: Reached target Basic System.1696builder # [ 6.866277] systemd[1]: Started backdoor.service.1697builder # [ 6.869384] systemd[1]: Starting Import lastlog data into lastlog2 database...1698builder # [ 6.875339] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1699builder # [ 6.878268] systemd[1]: Starting Post-Boot Actions...1700builder # [ 6.884682] systemd[1]: Started Reset console on configuration changes.1701builder # [ 6.906114] systemd[1]: Starting resolvconf update...1702server # [ 6.856064] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1703server # [ 6.860582] systemd[1]: Finished Save Transient machine-id to Disk.1704builder # [ 6.954192] dbus-broker-launch[503]: Ready1705server # [ 6.914097] systemd[1]: Finished Create System Files and Directories.1706server # [ 6.919557] systemd[1]: Starting Rebuild Journal Catalog...1707server # [ 6.922201] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1708builder # [ 7.003708] systemd[1]: Finished Post-Boot Actions.1709builder # connecting to host...1710builder # [ 6.895738] ACPI: button: Power Button [PWRF]1711builder # [ 6.901801] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41712builder # [ 7.044287] systemd[1]: Finished Import lastlog data into lastlog2 database.1713builder # [ 6.943216] mousedev: PS/2 mouse device common for all mice1714builder # [ 7.035470] rtc_cmos PNP0B00:00: RTC can wake from S41715builder # [ 7.041479] rtc_cmos PNP0B00:00: registered as rtc01716server # [ 7.000235] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1717builder # [ 7.054451] nsncd[507]: Oct 01 08:26:53.647 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1718builder # [ 7.056366] systemd[1]: Started Name Service Cache Daemon (nsncd).1719builder # [ 7.057259] systemd[1]: Reached target Host and Network Name Lookups.1720builder # [ 7.058183] systemd[1]: Reached target User and Group Name Lookups.1721builder # [ 7.061151] systemd[1]: Starting User Login Management...1722builder # [ 7.041542] rtc_cmos PNP0B00:00: setting system clock to 2026-10-01T08:26:53 UTC (1790843213)1723builder # [ 7.077588] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1724server # [ 7.052280] systemd[1]: Finished Rebuild Journal Catalog.1725server # [ 7.058447] systemd[1]: Starting Update is Completed...1726builder # [ 7.144085] systemd[1]: Stopped target Host and Network Name Lookups.1727builder # [ 7.145122] systemd[1]: Stopping Host and Network Name Lookups...1728builder # [ 7.145987] systemd[1]: Stopped target User and Group Name Lookups.1729builder # [ 7.149496] systemd[1]: Stopping User and Group Name Lookups...1730builder # [ 7.152108] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1731server # [ 7.099383] systemd[1]: Condition check resulted in Virtio network device being skipped.1732builder # [ 7.152967] systemd[1]: nscd.service: Deactivated successfully.1733builder # [ 7.156540] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1734builder # [ 7.139448] parport_pc 00:02: reported by Plug and Play ACPI1735builder # [ 7.139541] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1736builder # [ 7.162897] Console: switching to colour dummy device 80x251737builder # [ 7.225003] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1738builder # [ 7.166621] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1739server # [ 7.150201] systemd[1]: Finished Update is Completed.1740server # [ 7.152102] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1741server # [ 7.154409] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1742server # [ 7.156390] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671743server # [ 7.159526] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1744server # [ 7.161596] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1745server # [ 7.163439] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1746builder # [ 7.365128] [drm] Found bochs VGA, ID 0xb0c5.1747builder # [ 7.365133] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1748builder # [ 7.217982] systemd[1]: Started Name Service Cache Daemon (nsncd).1749builder # [ 7.227281] systemd[1]: Reached target Host and Network Name Lookups.1750builder # [ 7.230102] nsncd[578]: Oct 01 08:26:53.815 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1751builder # [ 7.234327] systemd[1]: Reached target User and Group Name Lookups.1752builder # [ 7.235449] systemd-logind[535]: New seat seat0.1753builder # [ 7.377430] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1754builder # [ 7.237672] systemd[1]: Started User Login Management.1755builder # [ 7.239050] systemd[1]: Starting linger-users.service...1756builder # [ 7.251657] systemd[1]: Finished resolvconf update.1757builder # [ 7.252479] systemd[1]: Reached target Preparation for Network.1758builder # [ 7.257739] systemd[1]: Starting DHCP Client...1759builder # [ 7.261175] systemd[1]: Starting Address configuration of eth1...1760builder # [ 7.264356] systemd[1]: Starting Extra networking commands....1761builder # [ 7.412723] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01762builder # [ 7.314591] systemd[1]: linger-users.service: Deactivated successfully.1763builder # [ 7.318321] systemd[1]: Finished linger-users.service.1764builder # [ 7.475405] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1765builder # [ 7.476098] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1766builder # [ 7.382156] network-addresses-eth1-start[605]: adding address 192.168.1.1/24... done1767builder # [ 7.396695] network-addresses-eth1-start[605]: adding address 2001:db8:1::1/64... done1768builder # [ 7.418521] systemd[1]: Finished Address configuration of eth1.1769server # [ 7.505118] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input31770server # [ 7.531740] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console1771server # [ 7.426619] systemd[1]: Finished register-nix-paths.service.1772server # [ 7.428899] systemd[1]: Finished Firewall.1773server # [ 7.429673] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1774server # [ 7.431217] systemd[1]: Finished Create SUID/SGID Wrappers.1775server # [ 7.433153] systemd[1]: Reached target System Initialization.1776server # [ 7.438239] systemd[1]: Started Discard unused filesystem blocks once a week.1777server # [ 7.439215] systemd[1]: Started niks3 garbage collection timer.1778server # [ 7.440813] systemd[1]: Started Daily Cleanup of Temporary Directories.1779server # [ 7.441768] systemd[1]: Reached target Timer Units.1780server # [ 7.443346] systemd[1]: Listening on D-Bus System Message Bus Socket.1781builder # [ 7.498886] dhcpcd[615]: dhcpcd-10.3.2 starting1782server # [ 7.446236] systemd[1]: Starting niks3 server proxy socket...1783server # [ 7.447715] systemd[1]: Listening on niks3 server socket.1784builder # [ 7.506762] dhcpcd[671]: dev: loaded udev1785server # [ 7.454336] systemd[1]: Listening on Nix Daemon Socket.1786server # [ 7.455687] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1787server # [ 7.457170] systemd[1]: Listening on niks3 server proxy socket.1788server # [ 7.458108] systemd[1]: Reached target Socket Units.1789server # [ 7.460615] systemd[1]: Starting D-Bus System Message Bus...1790builder # [ 7.515359] systemd[1]: Finished Extra networking commands..1791builder # [ 7.516334] systemd[1]: Reached target Network.1792builder # [ 7.518699] systemd[1]: Starting Permit User Sessions...1793builder # [ 7.577579] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61794builder # [ 7.550150] systemd[1]: Starting Virtual Console Setup...1795builder # [ 7.577869] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51796builder # [ 7.664040] Console: switching to colour frame buffer device 160x501797server # [ 7.519171] dbus-broker-launch[515]: Looking up NSS user entry for 'systemd-timesync'...1798server # [ 7.522289] dbus-broker-launch[515]: NSS returned no entry for 'systemd-timesync'1799server # [ 7.523437] dbus-broker-launch[515]: Invalid user-name in /nix/store/lym08h8xm68n3lg62g022zrli4baq836-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1800server # [ 7.535068] systemd[1]: Started D-Bus System Message Bus.1801server # [ 7.536245] systemd[1]: Reached target Basic System.1802builder # [ 7.591081] systemd[1]: Finished Permit User Sessions.1803server # [ 7.539406] systemd[1]: Started backdoor.service.1804server # [ 7.542174] systemd[1]: Starting Import lastlog data into lastlog2 database...1805server # [ 7.546298] systemd[1]: Starting Generate test mTLS certs...1806server # [ 7.549880] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1807server # [ 7.556114] systemd[1]: Starting Post-Boot Actions...1808server # [ 7.560466] systemd[1]: Started Reset console on configuration changes.1809builder # [ 7.676879] 8021q: 802.1Q VLAN Support v1.81810server # [ 7.570736] systemd[1]: Starting resolvconf update...1811builder # [ 7.676892] 8021q: adding VLAN 0 to HW filter on device eth11812builder # [ 7.774196] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1813builder # [ 7.639450] systemd[1]: Started Getty on tty1.1814builder # [ 7.640322] systemd[1]: Reached target Login Prompts.1815builder # [ 7.667506] systemd-logind[535]: Watching system buttons on /dev/input/event2 (Power Button)1816server # [ 7.615907] dbus-broker-launch[515]: Ready1817builder # [ 7.669662] systemd-logind[535]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1818builder # [ 7.690394] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1819builder # [ 7.691465] systemd[1]: Stopped Virtual Console Setup.1820builder # [ 7.692194] systemd[1]: Starting Virtual Console Setup...1821builder # [ 7.837744] cfg80211: Loading compiled-in X.509 certificates for regulatory database1822server # [ 7.668297] systemd[1]: Finished Post-Boot Actions.1823builder # [ 7.728714] systemd-logind[535]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1824builder # [ 7.875854] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1825builder # [ 7.876536] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1826builder # [ 7.879120] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21827builder # [ 7.879986] cfg80211: failed to load regulatory.db1828server # [ 7.688326] nsncd[520]: Oct 01 08:26:54.609 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1829server # [ 7.696096] systemd[1]: Started Name Service Cache Daemon (nsncd).1830server # [ 7.699078] systemd[1]: Reached target Host and Network Name Lookups.1831server # [ 7.700419] systemd[1]: Reached target User and Group Name Lookups.1832server # [ 7.701545] systemd[1]: Starting User Login Management...1833server # connecting to host...1834builder # [ 7.931955] 8021q: adding VLAN 0 to HW filter on device eth01835server: Guest shell says: b'Spawning backdoor root shell...\n'1836builder # [ 7.793349] dhcpcd[671]: eth0: waiting for carrier1837builder # [ 7.795449] dhcpcd[671]: libudev: received NULL device1838builder # [ 7.796488] dhcpcd[671]: libudev: received NULL device1839server # [ 7.745170] systemd[1]: Finished Import lastlog data into lastlog2 database.1840builder # [ 7.797683] dhcpcd[671]: eth0: carrier acquired1841builder # [ 7.806773] dhcpcd[671]: DUID 00:01:00:01:32:50:d5:ce:52:54:00:12:34:561842server # [ 7.582886] ACPI: button: Power Button [PWRF]1843builder # [ 7.807879] dhcpcd[671]: eth0: IAID 00:12:34:561844server # [ 7.590777] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input41845builder # [ 7.949226] ppdev: user-space parallel port driver1846server # [ 7.753297] niks3-test-certs-start[533]: -----1847builder # [ 7.809301] dhcpcd[671]: eth0: adding address fe80::5054:ff:fe12:34561848server: connected to guest root shell1849server: (connecting took 8.45 seconds)1850server: (finished: waiting for the VM to finish booting, in 8.45 seconds)1851server # [ 7.792545] niks3-test-certs-start[565]: -----1852server # [ 7.620663] mousedev: PS/2 mouse device common for all mice1853server # [ 7.669401] rtc_cmos PNP0B00:00: RTC can wake from S41854builder # [ 7.999293] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1855builder # [ 7.876652] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1856builder # [ 8.018438] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1857server # [ 7.716532] rtc_cmos PNP0B00:00: registered as rtc01858server # [ 7.716603] rtc_cmos PNP0B00:00: setting system clock to 2026-10-01T08:26:54 UTC (1790843214)1859server # [ 7.716693] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs1860builder # [ 7.894098] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1861builder # [ 7.895676] systemd[1]: Stopped Virtual Console Setup.1862builder # [ 7.902135] systemd[1]: Starting Virtual Console Setup...1863server # [ 7.878960] niks3-test-certs-start[576]: Certificate request self-signature ok1864server # [ 7.880480] niks3-test-certs-start[576]: subject=CN=server1865server # [ 7.890817] Console: switching to colour dummy device 80x251866server # [ 7.894996] parport_pc 00:02: reported by Plug and Play ACPI1867server # [ 7.895112] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]1868server # [ 7.901499] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized1869server # [ 8.064772] [drm] Found bochs VGA, ID 0xb0c5.1870server # [ 8.064778] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.1871server # [ 7.909256] niks3-test-certs-start[595]: -----1872builder # [ 8.136099] kvm_amd: TSC scaling supported1873builder # [ 8.136602] kvm_amd: Nested Virtualization enabled1874server # [ 7.931319] systemd-logind[544]: New seat seat0.1875builder # [ 8.137508] kvm_amd: Nested Paging enabled1876server # [ 7.943823] systemd[1]: Stopped target Host and Network Name Lookups.1877builder # [ 8.138094] kvm_amd: LBR virtualization supported1878builder # [ 8.138586] kvm_amd: Virtual VMLOAD VMSAVE supported1879server # [ 7.944768] systemd[1]: Stopping Host and Network Name Lookups...1880builder # [ 8.139311] kvm_amd: Virtual GIF supported1881builder # [ 8.139879] kvm_amd: Virtual NMI enabled1882server # [ 7.946026] systemd[1]: Stopped target User and Group Name Lookups.1883server # [ 7.951334] systemd[1]: Stopping User and Group Name Lookups...1884server # [ 7.952684] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...1885server # [ 7.954869] systemd[1]: Started User Login Management.1886server # [ 7.958168] systemd[1]: Starting linger-users.service...1887server # [ 7.958895] systemd[1]: nscd.service: Deactivated successfully.1888server # [ 7.964550] systemd[1]: Stopped Name Service Cache Daemon (nsncd).1889server # [ 7.965501] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1890builder # [ 8.164455] EDAC MC: Ver: 3.0.01891server # [ 8.142033] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic1892server # [ 8.142716] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 01893server # [ 8.031270] niks3-test-certs-start[596]: Certificate request self-signature ok1894server # [ 8.032921] niks3-test-certs-start[596]: subject=CN=niks3 test client1895server # [ 8.035935] systemd[1]: linger-users.service: Deactivated successfully.1896server # [ 8.040592] systemd[1]: Finished linger-users.service.1897builder # [ 8.093978] systemd-vconsole-setup[699]: Configuration of first virtual console was skipped, ignoring remaining ones.1898builder # [ 8.097416] systemd[1]: Finished Virtual Console Setup.1899server # [ 8.051414] systemd[1]: Started Name Service Cache Daemon (nsncd).1900server # [ 8.053479] systemd[1]: Reached target Host and Network Name Lookups.1901server # [ 8.054414] systemd[1]: Reached target User and Group Name Lookups.1902server # [ 8.056621] nsncd[603]: Oct 01 08:26:54.975 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1903builder # [ 8.119394] dhcpcd[671]: eth0: soliciting a DHCP lease1904server # [ 8.066735] systemd[1]: Finished Generate test mTLS certs.1905builder # [ 8.268708] NET: Registered PF_PACKET protocol family1906builder # [ 8.132521] dhcpcd[671]: eth0: offered 10.0.2.15 from 10.0.2.21907builder # [ 8.134152] dhcpcd[671]: eth0: probing address 10.0.2.15/241908server # [ 8.082051] systemd[1]: Finished resolvconf update.1909server # [ 8.083562] systemd[1]: Reached target Preparation for Network.1910server # [ 8.229338] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt1911server # [ 8.230030] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD1912server # [ 8.091927] systemd[1]: Starting DHCP Client...1913server # [ 8.096298] systemd[1]: Starting Address configuration of eth1...1914server # [ 8.098783] systemd[1]: Starting Extra networking commands....1915server # [ 8.184245] network-addresses-eth1-start[638]: adding address 192.168.1.2/24... done1916server # [ 8.200537] network-addresses-eth1-start[638]: adding address 2001:db8:1::2/64... done1917server # [ 8.221546] systemd[1]: Finished Address configuration of eth1.1918server # [ 8.302198] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input61919server # [ 8.304127] systemd[1]: Finished Extra networking commands..1920server # [ 8.304955] systemd[1]: Reached target Network.1921server # [ 8.308415] systemd[1]: Started Mock OIDC server for testing.1922server # [ 8.313366] systemd[1]: Starting Nginx Web Server...1923server # [ 8.318402] systemd[1]: Starting PostgreSQL Server...1924server # [ 8.323102] systemd[1]: Started RustFS S3-compatible object storage.1925server # [ 8.328304] systemd[1]: Starting Setup RustFS bucket...1926server # [ 8.336360] systemd[1]: Starting Permit User Sessions...1927server # [ 8.342724] systemd[1]: Starting Virtual Console Setup...1928server # [ 8.359221] dhcpcd[654]: dhcpcd-10.3.2 starting1929server # [ 8.302445] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input51930server # [ 8.382383] dhcpcd[712]: dev: loaded udev1931server # [ 8.377604] Console: switching to colour frame buffer device 160x501932server # [ 8.452301] systemd[1]: Finished Permit User Sessions.1933server # [ 8.548190] 8021q: 802.1Q VLAN Support v1.81934server # [ 8.548204] 8021q: adding VLAN 0 to HW filter on device eth11935server # [ 8.679556] cfg80211: Loading compiled-in X.509 certificates for regulatory database1936server # [ 8.731699] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1937server # [ 8.731813] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1938server # [ 8.733561] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21939server # [ 8.733568] cfg80211: failed to load regulatory.db1940server # [ 8.899744] 8021q: adding VLAN 0 to HW filter on device eth01941server # [ 8.949522] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device1942server # [ 8.578187] systemd-logind[544]: Watching system buttons on /dev/input/event2 (Power Button)1943server # [ 8.814917] dhcpcd[712]: libudev: received NULL device1944server # [ 8.820117] systemd-logind[544]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)1945server # [ 8.824524] dhcpcd[712]: eth0: waiting for carrier1946server # [ 8.825291] systemd-logind[544]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1947server # [ 8.827524] dhcpcd[712]: libudev: received NULL device1948server # [ 8.830635] systemd[1]: Started Getty on tty1.1949server # [ 8.834154] dhcpcd[712]: eth0: carrier acquired1950server # [ 8.835636] systemd[1]: Reached target Login Prompts.1951server # [ 8.838133] dhcpcd[712]: DUID 00:01:00:01:32:50:d5:cf:52:54:00:12:34:561952server # [ 8.842631] dhcpcd[712]: eth0: IAID 00:12:34:561953server # [ 8.843870] dhcpcd[712]: eth0: adding address fe80::5054:ff:fe12:34561954server # [ 8.853818] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1955server # [ 8.854936] systemd[1]: Stopped Virtual Console Setup.1956server # [ 8.858718] systemd[1]: Starting Virtual Console Setup...1957server # [ 8.882263] mock-oidc-server[704]: Mock OIDC Server running1958server # [ 8.885518] mock-oidc-server[704]: OIDC Address: 127.0.0.1:80801959server # [ 8.886436] mock-oidc-server[704]: Issue Address: 127.0.0.1:80811960server # [ 8.887267] mock-oidc-server[704]: Issuer: http://127.0.0.1:8080/oidc1961server # [ 8.889898] mock-oidc-server[704]: JWKS: http://127.0.0.1:8080/oidc/.well-known/jwks.json1962server # [ 8.890953] mock-oidc-server[704]: Discovery: http://127.0.0.1:8080/oidc/.well-known/openid-configuration1963server # [ 8.892646] mock-oidc-server[704]: Issue tokens: http://127.0.0.1:8081/issue?sub=...1964server # [ 8.911240] nginx-pre-start[737]: nginx: the configuration file /nix/store/zrm3va7lbl0i91k5xpvb2sg2jw58nznn-nginx.conf syntax is ok1965server # [ 8.913729] nginx-pre-start[737]: nginx: configuration file /nix/store/zrm3va7lbl0i91k5xpvb2sg2jw58nznn-nginx.conf test is successful1966server # [ 8.924950] systemd[1]: Started Nginx Web Server.1967server # [ 8.935115] postgresql-pre-start[743]: The files belonging to this database system will be owned by user "postgres".1968server # [ 8.936611] postgresql-pre-start[743]: This user must also own the server process.1969server # [ 8.941961] postgresql-pre-start[743]: The database cluster will be initialized with locale "en_US.UTF-8".1970server # [ 8.943148] postgresql-pre-start[743]: The default database encoding has accordingly been set to "UTF8".1971server # [ 8.944384] postgresql-pre-start[743]: The default text search configuration will be set to "english".1972server # [ 8.945543] postgresql-pre-start[743]: Data page checksums are enabled.1973server # [ 8.946447] postgresql-pre-start[743]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok1974server # [ 8.949197] postgresql-pre-start[743]: creating subdirectories ... ok1975server # [ 8.952762] postgresql-pre-start[743]: selecting dynamic shared memory implementation ... posix1976server # [ 9.120323] ppdev: user-space parallel port driver1977server # [ 9.138204] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1978server # [ 9.293218] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)1979server # [ 9.175648] postgresql-pre-start[743]: selecting default "max_connections" ... 1001980server # [ 9.322758] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)1981server # [ 9.212663] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1982server # [ 9.214354] systemd[1]: Stopped Virtual Console Setup.1983server # [ 9.216596] systemd[1]: Starting Virtual Console Setup...1984server # [ 9.352283] postgresql-pre-start[743]: selecting default "shared_buffers" ... 128MB1985server # [ 9.552598] kvm_amd: TSC scaling supported1986server # [ 9.554176] kvm_amd: Nested Virtualization enabled1987server # [ 9.554756] kvm_amd: Nested Paging enabled1988server # [ 9.555361] kvm_amd: LBR virtualization supported1989server # [ 9.555807] kvm_amd: Virtual VMLOAD VMSAVE supported1990server # [ 9.557330] kvm_amd: Virtual GIF supported1991server # [ 9.557729] kvm_amd: Virtual NMI enabled1992server # [ 9.601177] EDAC MC: Ver: 3.0.01993server # [ 9.547865] systemd-vconsole-setup[778]: Configuration of first virtual console was skipped, ignoring remaining ones.1994server # [ 9.552109] systemd[1]: Finished Virtual Console Setup.1995builder # [ 9.689560] dhcpcd[671]: eth0: soliciting an IPv6 router1996builder # [ 9.692604] dhcpcd[671]: eth0: Router Advertisement from fe80::21997builder # [ 9.694760] dhcpcd[671]: eth0: adding address fec0::5054:ff:fe12:3456/641998builder # [ 9.696952] dhcpcd[671]: eth0: adding route to fec0::/641999builder # [ 9.698837] dhcpcd[671]: eth0: adding default route via fe80::22000server # [ 9.745320] dhcpcd[712]: eth0: soliciting a DHCP lease2001server # [ 9.898465] NET: Registered PF_PACKET protocol family2002server # [ 9.765691] dhcpcd[712]: eth0: offered 10.0.2.15 from 10.0.2.22003server # [ 9.766770] dhcpcd[712]: eth0: probing address 10.0.2.15/242004server # [ 10.208912] postgresql-pre-start[743]: selecting default time zone ... UTC2005server # [ 10.211472] postgresql-pre-start[743]: creating configuration files ... ok2006server # [ 10.390495] postgresql-pre-start[743]: running bootstrap script ... ok2007server # [ 10.769316] postgresql-pre-start[743]: performing post-bootstrap initialization ... ok2008server # [ 10.902716] postgresql-pre-start[743]: syncing data to disk ... ok2009server # [ 10.904491] postgresql-pre-start[743]: initdb: warning: enabling "trust" authentication for local connections2010server # [ 10.905749] postgresql-pre-start[743]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.2011server # [ 10.907603] postgresql-pre-start[743]: Success. You can now start the database server using:2012server # [ 10.908755] postgresql-pre-start[743]: pg_ctl -D /var/lib/postgresql/18 -l logfile start2013server # [ 10.978145] postgres[810]: [810] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit2014server # [ 10.980545] postgres[810]: [810] LOG: listening on IPv6 address "::1", port 54322015server # [ 10.981635] postgres[810]: [810] LOG: listening on IPv4 address "127.0.0.1", port 54322016server # [ 10.983864] postgres[810]: [810] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2017server # [ 10.995262] postgres[819]: [819] LOG: database system was shut down at 2026-10-01 08:26:57 GMT2018server # [ 11.000381] postgres[810]: [810] LOG: database system is ready to accept connections2019server # [ 11.005209] systemd[1]: Started PostgreSQL Server.2020server # [ 11.008138] systemd[1]: Starting PostgreSQL Setup Scripts...2021server # [ 11.055031] dhcpcd[712]: eth0: soliciting an IPv6 router2022server # [ 11.055846] dhcpcd[712]: eth0: Router Advertisement from fe80::22023server # [ 11.056812] dhcpcd[712]: eth0: adding address fec0::5054:ff:fe12:3456/642024server # [ 11.058086] dhcpcd[712]: eth0: adding route to fec0::/642025server # [ 11.058830] dhcpcd[712]: eth0: adding default route via fe80::22026server # [ 11.137225] postgresql-setup-start[830]: CREATE DATABASE2027server # [ 11.166549] postgresql-setup-start[835]: CREATE ROLE2028server # [ 11.180047] postgresql-setup-start[837]: ALTER DATABASE2029server # [ 11.184170] systemd[1]: Finished PostgreSQL Setup Scripts.2030server # [ 11.185583] systemd[1]: Reached target PostgreSQL.2031server: (finished: waiting for unit postgresql.service, in 11.90 seconds)2032server: waiting for unit rustfs.service2033server: (finished: waiting for unit rustfs.service, in 0.02 seconds)2034server: waiting for unit rustfs-setup.service2035builder # [ 13.750798] dhcpcd[671]: eth0: leased 10.0.2.15 for 86400 seconds2036builder # [ 13.751943] dhcpcd[671]: eth0: adding route to 10.0.2.0/242037builder # [ 13.752884] dhcpcd[671]: eth0: adding default route via 10.0.2.22038builder # [ 13.812417] systemd[1]: Started DHCP Client.2039builder # [ 13.814075] systemd[1]: Reached target Multi-User System.2040builder # [ 13.815198] systemd[1]: Startup finished in 839ms (kernel) + 3.095s (initrd) + 9.880s (userspace) = 13.814s.2041server # [ 15.222296] dhcpcd[712]: eth0: leased 10.0.2.15 for 86400 seconds2042server # [ 15.225311] dhcpcd[712]: eth0: adding route to 10.0.2.0/242043server # [ 15.227994] dhcpcd[712]: eth0: adding default route via 10.0.2.22044server # [ 15.312753] systemd[1]: Started DHCP Client.2045server # [ 24.992645] rustfs-setup-start[968]: mb s3://niks3-test2046server # [ 25.004715] systemd[1]: Finished Setup RustFS bucket.2047server # [ 25.010577] systemd[1]: Starting niks3 server...2048server # [ 25.124553] postgres[983]: [983] ERROR: relation "goose_db_version" does not exist at character 362049server # [ 25.127151] postgres[983]: [983] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC2050server # [ 25.149216] niks3-server[978]: 2026/10/01 08:27:12 OK 20241026095416_initial_model.sql (12.66ms)2051server # [ 25.153739] niks3-server[978]: 2026/10/01 08:27:12 OK 20251210153512_drop_unused_gin_index.sql (4.68ms)2052server # [ 25.159107] niks3-server[978]: 2026/10/01 08:27:12 OK 20251218171726_add_pins.sql (3.88ms)2053server # [ 25.163268] niks3-server[978]: 2026/10/01 08:27:12 OK 20260628120000_add_object_size_and_stats.sql (4.13ms)2054server # [ 25.167628] niks3-server[978]: 2026/10/01 08:27:12 OK 20260905000000_add_claims.sql (4.33ms)2055server # [ 25.171348] niks3-server[978]: 2026/10/01 08:27:12 OK 20260920000000_drop_claims.sql (3.7ms)2056server # [ 25.174157] niks3-server[978]: 2026/10/01 08:27:12 OK 20260923120000_add_pushes.sql (2.88ms)2057server # [ 25.175529] niks3-server[978]: 2026/10/01 08:27:12 goose: successfully migrated database to version: 202609231200002058server # [ 25.179587] niks3-server[978]: 2026/10/01 08:27:12 OK 1_commit_pending_closure.sql (3.96ms)2059server # [ 25.182294] niks3-server[978]: 2026/10/01 08:27:12 OK 2_object_stats_trigger.sql (2.68ms)2060server # [ 25.184868] niks3-server[978]: 2026/10/01 08:27:12 OK 3_commit_push.sql (2.49ms)2061server # [ 25.185852] niks3-server[978]: 2026/10/01 08:27:12 goose: up to current file version: 32062server # [ 25.194159] niks3-server[978]: 2026/10/01 08:27:12 INFO OIDC provider initialized name=test issuer=http://127.0.0.1:8080/oidc2063server # [ 25.195646] niks3-server[978]: 2026/10/01 08:27:12 INFO OIDC authentication enabled config=/nix/store/sa3h30ggc0ih3s7pyx6hv16myf7hx0lj-niks3-oidc.json2064server # [ 25.197733] niks3-server[978]: 2026/10/01 08:27:12 INFO Loaded signing key name=niks3-test-1 path=/nix/store/bkg7syhrbck6xib4g0kaji8jiwjnl8sy-niks3-signing-key2065server # [ 25.226483] niks3-server[978]: 2026/10/01 08:27:12 INFO Using socket-activated listener address=0.0.0.0:57512066server # [ 25.227753] niks3-server[978]: 2026/10/01 08:27:12 INFO Using socket-activated listener address=/run/niks3/proxy.sock2067server # [ 25.230163] niks3-server[978]: 2026/10/01 08:27:12 INFO systemd watchdog enabled interval=15s2068server # [ 25.231303] systemd[1]: Started niks3 server.2069server # [ 25.233944] systemd[1]: Reached target Multi-User System.2070server # [ 25.234977] niks3-server[978]: 2026/10/01 08:27:12 INFO Starting HTTP server address=0.0.0.0:57512071server # [ 25.236262] niks3-server[978]: 2026/10/01 08:27:12 INFO Starting HTTP server address=/run/niks3/proxy.sock2072server # [ 25.237764] systemd[1]: Startup finished in 885ms (kernel) + 3.683s (initrd) + 20.663s (userspace) = 25.232s.2073server: (finished: waiting for unit rustfs-setup.service, in 14.60 seconds)2074server: waiting for unit mock-oidc.service2075server: (finished: waiting for unit mock-oidc.service, in 0.03 seconds)2076server: waiting for unit niks3.service2077server: (finished: waiting for unit niks3.service, in 0.02 seconds)2078server: waiting for TCP port 5751 on localhost2079server # Connection to localhost (127.0.0.1) 5751 port [tcp/*] succeeded!2080server: (finished: waiting for TCP port 5751 on localhost, in 0.02 seconds)2081server: waiting for TCP port 8080 on localhost2082server # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!2083server: (finished: waiting for TCP port 8080 on localhost, in 0.02 seconds)2084server: waiting for TCP port 9000 on localhost2085server # Connection to localhost (127.0.0.1) 9000 port [tcp/cslistener] succeeded!2086server: (finished: waiting for TCP port 9000 on localhost, in 0.02 seconds)2087server: must succeed: mkdir -p /tmp/test-config2088server: (finished: must succeed: mkdir -p /tmp/test-config, in 0.01 seconds)2089server: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token2090server: (finished: must succeed: echo -n 'test-token-that-is-at-least-36-characters-long' > /tmp/test-config/auth-token, in 0.01 seconds)2091server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32092server # [ 26.083292] systemd[1]: Created slice Slice /system/nix-daemon.2093server # [ 26.087056] systemd[1]: Started Nix Daemon instance (PID 1028/UID 0).2094server # [ 26.129590] nix-daemon[1030]: remote pid 1028 is unknown user (trusted)2095server # [ 26.144981] systemd[1]: nix-daemon@0-1-1028_1029-0.service: Deactivated successfully.2096server # [ 26.150452] niks3-server[978]: 2026/10/01 08:27:13 INFO Received push request method=POST path=/api/pushes2097server # time=2026-10-01T08:27:13.096Z level=INFO msg="Uploading 5 paths to server (0 already cached)"2098server # time=2026-10-01T08:27:13.097Z level=INFO msg="Uploading i3jw341xs88r6wxf1j22rjx1mmd8cfjw-libidn2-2.3.8 (359.5KB)"2099server # time=2026-10-01T08:27:13.099Z level=INFO msg="Uploading lm3pknxi0ipypy3lxh1wmm8wvvavdwrn-glibc-2.42-84 (33.4MB)"2100server # time=2026-10-01T08:27:13.099Z level=INFO msg="Uploading xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 (273.1KB)"2101server # time=2026-10-01T08:27:13.099Z level=INFO msg="Uploading bv3lx708clpz3m6zbd5742np4dlry0yj-libunistring-1.4.2 (2.0MB)"2102server # time=2026-10-01T08:27:13.099Z level=INFO msg="Uploading m07syxhld8hpprrdmzq565ziia6vlw9l-xgcc-15.3.0-libgcc (193.0KB)"2103server # [ 26.345066] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.ls2104server # [ 26.399418] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=nar/1bvhsl1iqnbbgmxgp2hi0j8nj5ip5n6h6m2awanbnhwrl8fazjvh.nar.zst2105server # [ 26.422492] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=bv3lx708clpz3m6zbd5742np4dlry0yj.ls2106server # [ 26.440064] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=nar/1gz22gfl2lcahqzma75rvxgmk9zcfrvarzywlarwy4hkf1k4iz44.nar.zst2107server # [ 26.456624] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.ls2108server # [ 26.469320] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=nar/1ixxaz0n8nfxd2w29qq8frn6pjvdnjj3pl139gbi7jksrj9jd7jg.nar.zst2109server # [ 26.493884] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=nar/0g2qsgc7dv9zl3i5nc523gicpy0xk2z4r1x4msr0inmsqjpm1ym9.nar.zst2110server # [ 26.499954] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.ls2111server # [ 27.052607] niks3-server[978]: 2026/10/01 08:27:13 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.ls2112server # [ 27.082111] niks3-server[978]: 2026/10/01 08:27:14 INFO Received complete multipart upload request method=POST path=/api/multipart/complete2113server # [ 27.100106] niks3-server[978]: 2026/10/01 08:27:14 INFO Completed multipart upload object_key=nar/06kkplxk31y11zksgcrdjv0hf6ymlmxvlc0i2h08g3zc22is465s.nar.zst upload_id=ZTNjMTczMDUtZDJjOC00NDM1LTgxMjEtMmE5MDY5YjM0MjI5LjkzOTVjYzE1LTc2N2ItNDk2MC1iZGFkLWE5NTQxNzdhMDM4Y3gxNzkwODQzMjMzMDg1MzI0Mzg5 parts=12114server # [ 27.103657] niks3-server[978]: 2026/10/01 08:27:14 INFO Received sign narinfos request method=POST path=/api/pushes/1/sign2115server # [ 27.108684] niks3-server[978]: 2026/10/01 08:27:14 INFO Signed narinfos id=1 count=52116server # time=2026-10-01T08:27:14.033Z level=INFO msg="Uploading 5 narinfos"2117server # [ 27.133065] niks3-server[978]: 2026/10/01 08:27:14 INFO Received complete push request method=POST path=/api/pushes/1/complete2118server # [ 27.142739] niks3-server[978]: 2026/10/01 08:27:14 INFO Registered completed upload object_key=i3jw341xs88r6wxf1j22rjx1mmd8cfjw.narinfo2119server # [ 27.147187] niks3-server[978]: 2026/10/01 08:27:14 INFO Registered completed upload object_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo2120server # [ 27.152163] niks3-server[978]: 2026/10/01 08:27:14 INFO Registered completed upload object_key=m07syxhld8hpprrdmzq565ziia6vlw9l.narinfo2121server # [ 27.154970] niks3-server[978]: 2026/10/01 08:27:14 INFO Registered completed upload object_key=bv3lx708clpz3m6zbd5742np4dlry0yj.narinfo2122server # [ 27.171512] niks3-server[978]: 2026/10/01 08:27:14 INFO Registered completed upload object_key=lm3pknxi0ipypy3lxh1wmm8wvvavdwrn.narinfo2123server # time=2026-10-01T08:27:14.097Z level=INFO msg="Upload complete. (1.131s)"2124server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 1.22 seconds)2125server: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token2126server: (finished: must succeed: echo -n 'invalid-token' > /tmp/test-config/invalid-token, in 0.01 seconds)2127server: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32128server # [ 27.236522] niks3-server[978]: 2026/10/01 08:27:14 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]2129server # [ 27.269663] systemd[1]: Started Nix Daemon instance (PID 1056/UID 0).2130server # [ 27.307396] nix-daemon[1058]: remote pid 1056 is unknown user (trusted)2131server # [ 27.318117] systemd[1]: nix-daemon@1-2-1056_1057-0.service: Deactivated successfully.2132server # [ 27.323289] niks3-server[978]: 2026/10/01 08:27:14 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]2133server # time=2026-10-01T08:27:14.249Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2134server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/invalid-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.14 seconds)2135server: waiting for unit nginx.service2136server: (finished: waiting for unit nginx.service, in 0.02 seconds)2137server: waiting for TCP port 443 on localhost2138server # Connection to localhost (::1) 443 port [tcp/https] succeeded!2139server: (finished: waiting for TCP port 443 on localhost, in 0.02 seconds)2140server: must succeed: /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32141server # time=2026-10-01T08:27:14.332Z 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.pem2142server # time=2026-10-01T08:27:14.347Z level=INFO msg="All 1 paths already cached"2143server: (finished: must succeed: /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/client.pem --client-key /etc/niks3-test-certs/client.key /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.06 seconds)2144server: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures2145server: (finished: must fail: curl -sf -X POST -H 'X-SSL-Client-Verify: SUCCESS' -H 'X-SSL-Client-Dn: CN=niks3 test client' -d '{}' http://127.0.0.1:5751/api/pending_closures, in 0.02 seconds)2146server: must fail: /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32147server # time=2026-10-01T08:27:14.386Z 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)"2148server: (finished: must fail: /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.01 seconds)2149server: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32150server # time=2026-10-01T08:27:14.430Z level=INFO msg="Configuring client TLS" cert="" key="" ca=/etc/niks3-test-certs/ca.pem2151server # time=2026-10-01T08:27:14.439Z level=INFO msg="All 1 paths already cached"2152server: (finished: must succeed: NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.05 seconds)2153server: 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'2154server # -----2155server: (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)2156server: 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.pem2157server # Certificate request self-signature ok2158server # subject=CN=other client2159server: (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)2160server: must fail: /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32161server # time=2026-10-01T08:27:14.526Z 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.pem2162server # [ 27.610342] niks3-server[978]: 2026/10/01 08:27:14 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2163server # [ 27.643249] systemd[1]: Started Nix Daemon instance (PID 1126/UID 0).2164server # [ 27.682822] nix-daemon[1128]: remote pid 1126 is unknown user (trusted)2165server # [ 27.693542] systemd[1]: nix-daemon@2-3-1126_1127-0.service: Deactivated successfully.2166server # [ 27.699716] niks3-server[978]: 2026/10/01 08:27:14 WARN mTLS auth: subject not in bound subjects subject="CN=other client"2167server # time=2026-10-01T08:27:14.625Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2168server: (finished: must fail: /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --server-url https://server --ca-cert /etc/niks3-test-certs/ca.pem --client-cert /etc/niks3-test-certs/other.pem --client-key /etc/niks3-test-certs/other.key /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3, in 0.14 seconds)2169server: must succeed: mkdir -p /tmp/test-store2170server: (finished: must succeed: mkdir -p /tmp/test-store, in 0.01 seconds)2171server: must succeed: 2172 export AWS_ACCESS_KEY_ID=rustfsadmin2173export AWS_SECRET_ACCESS_KEY=rustfsadmin2174 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.321752176server: (finished: must succeed: 2177 export AWS_ACCESS_KEY_ID=rustfsadmin2178export AWS_SECRET_ACCESS_KEY=rustfsadmin2179 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32180, in 0.55 seconds)2181server: must succeed: 2182cat > /tmp/test-drv.nix << 'EOF'2183derivation {2184 name = "test-build-log";2185 system = builtins.currentSystem;2186 builder = "/bin/sh";2187 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2188}2189EOF21902191server: (finished: must succeed: 2192cat > /tmp/test-drv.nix << 'EOF'2193derivation {2194 name = "test-build-log";2195 system = builtins.currentSystem;2196 builder = "/bin/sh";2197 args = [ "-c" "echo 'test build log output'; echo 'hello world' > $out" ];2198}2199EOF2200, in 0.01 seconds)2201server: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix2202server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2203server # [ 28.325397] systemd[1]: Started Nix Daemon instance (PID 1172/UID 0).2204server # [ 28.364900] nix-daemon[1176]: remote pid 1172 is unknown user (trusted)2205server # this derivation will be built:2206server # /nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2207server # building '/nix/store/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv'...2208server # test-build-log> test build log output2209server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix, in 0.17 seconds)2210server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2211server # [ 28.462668] systemd[1]: nix-daemon@3-4-1172_1173-0.service: Deactivated successfully.2212server # [ 28.539080] systemd[1]: Started Nix Daemon instance (PID 1203/UID 0).2213server # [ 28.577582] nix-daemon[1205]: remote pid 1203 is unknown user (trusted)2214server # [ 28.587758] systemd[1]: nix-daemon@4-5-1203_1204-0.service: Deactivated successfully.2215server # [ 28.592621] niks3-server[978]: 2026/10/01 08:27:15 INFO Received push request method=POST path=/api/pushes2216server # time=2026-10-01T08:27:15.522Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2217server # time=2026-10-01T08:27:15.523Z level=INFO msg="Uploading dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log (128B)"2218server # [ 28.626450] niks3-server[978]: 2026/10/01 08:27:15 INFO Received sign narinfos request method=POST path=/api/pushes/2/sign2219server # [ 28.628587] niks3-server[978]: 2026/10/01 08:27:15 INFO Registered completed upload object_key=log/dmxprr36mb5n38x36zfbbcsax7r84h48-test-build-log.drv2220server # [ 28.630685] niks3-server[978]: 2026/10/01 08:27:15 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.ls2221server # time=2026-10-01T08:27:15.556Z level=INFO msg="Uploading 1 narinfos"2222server # [ 28.634115] niks3-server[978]: 2026/10/01 08:27:15 INFO Signed narinfos id=2 count=12223server # [ 28.641873] niks3-server[978]: 2026/10/01 08:27:15 INFO Registered completed upload object_key=nar/00zns3gj9hwz2a4b0i07y7nmxybq59lh24bl3xsxblcl6333mjil.nar.zst2224server # [ 28.645274] niks3-server[978]: 2026/10/01 08:27:15 INFO Received complete push request method=POST path=/api/pushes/2/complete2225server # [ 28.650678] niks3-server[978]: 2026/10/01 08:27:15 INFO Registered completed upload object_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2226server # time=2026-10-01T08:27:15.577Z level=INFO msg="Upload complete. (149ms)"2227server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log, in 0.20 seconds)2228server: must succeed: 2229 export AWS_ACCESS_KEY_ID=rustfsadmin2230export AWS_SECRET_ACCESS_KEY=rustfsadmin2231 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log22322233server: (finished: must succeed: 2234 export AWS_ACCESS_KEY_ID=rustfsadmin2235export AWS_SECRET_ACCESS_KEY=rustfsadmin2236 nix log --store 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2237, in 0.11 seconds)2238subtest: push --stdin streams paths and reports each one2239server: must succeed: nix-build --no-out-link -E 'derivation { name = "stdin-test"; system = builtins.currentSystem; builder = "/bin/sh"; args = [ "-c" "echo stdin > $out" ]; }'2240server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2241server # [ 28.812073] systemd[1]: Started Nix Daemon instance (PID 1221/UID 0).2242server # [ 28.849245] nix-daemon[1225]: remote pid 1221 is unknown user (trusted)2243server # this derivation will be built:2244server # /nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2245server # building '/nix/store/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv'...2246server: (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.16 seconds)2247server: 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/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --stdin2248server # [ 28.938573] systemd[1]: nix-daemon@5-6-1221_1222-0.service: Deactivated successfully.2249server # [ 29.016480] systemd[1]: Started Nix Daemon instance (PID 1253/UID 0).2250server # [ 29.052936] nix-daemon[1255]: remote pid 1253 is unknown user (trusted)2251server # [ 29.063084] systemd[1]: nix-daemon@6-7-1253_1254-0.service: Deactivated successfully.2252server # [ 29.068162] niks3-server[978]: 2026/10/01 08:27:15 INFO Received push request method=POST path=/api/pushes2253server # time=2026-10-01T08:27:15.996Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2254server # time=2026-10-01T08:27:15.997Z level=INFO msg="Uploading 3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test (120B)"2255server # [ 29.097075] niks3-server[978]: 2026/10/01 08:27:16 INFO Received sign narinfos request method=POST path=/api/pushes/3/sign2256server # [ 29.099577] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.ls2257server # [ 29.101382] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=log/qky132yim5rs9zg5kyvqahjdlj4k28r1-stdin-test.drv2258server # time=2026-10-01T08:27:16.027Z level=INFO msg="Uploading 1 narinfos"2259server # [ 29.105978] niks3-server[978]: 2026/10/01 08:27:16 INFO Signed narinfos id=3 count=12260server # [ 29.109614] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=nar/1hj1r0g515rfxjx685mfn3dgr6akqbn4gf64snlv6bxaxph69lna.nar.zst2261server # [ 29.113995] niks3-server[978]: 2026/10/01 08:27:16 INFO Received complete push request method=POST path=/api/pushes/3/complete2262server # [ 29.122507] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=3lzkfq9fx7rridh3i11ly8xyqdbqb44w.narinfo2263server # time=2026-10-01T08:27:16.050Z level=INFO msg="Upload complete. (147ms)"2264server: (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/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --stdin, in 0.20 seconds)2265server: must succeed: 2266 export AWS_ACCESS_KEY_ID=rustfsadmin2267export AWS_SECRET_ACCESS_KEY=rustfsadmin2268 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2269 2270server: (finished: must succeed: 2271 export AWS_ACCESS_KEY_ID=rustfsadmin2272export AWS_SECRET_ACCESS_KEY=rustfsadmin2273 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/stdin-store /nix/store/3lzkfq9fx7rridh3i11ly8xyqdbqb44w-stdin-test2274 , in 0.14 seconds)2275(finished: subtest: push --stdin streams paths and reports each one, in 0.50 seconds)2276server: must succeed: readlink /etc/niks3-test/symlink-wrapper2277server: (finished: must succeed: readlink /etc/niks3-test/symlink-wrapper, in 0.01 seconds)2278server: must succeed: readlink /etc/static/niks3-test/symlink-wrapper2279server: (finished: must succeed: readlink /etc/static/niks3-test/symlink-wrapper, in 0.01 seconds)2280server: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2281server: (finished: must succeed: test -L /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds)2282server: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2283server: (finished: must succeed: readlink /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.01 seconds)2284server: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2285server # [ 29.390921] systemd[1]: Started Nix Daemon instance (PID 1301/UID 0).2286server # [ 29.429323] nix-daemon[1303]: remote pid 1301 is unknown user (trusted)2287server # [ 29.439672] systemd[1]: nix-daemon@7-8-1301_1302-0.service: Deactivated successfully.2288server # [ 29.444525] niks3-server[978]: 2026/10/01 08:27:16 INFO Received push request method=POST path=/api/pushes2289server # time=2026-10-01T08:27:16.373Z level=INFO msg="Uploading 2 paths to server (0 already cached)"2290server # time=2026-10-01T08:27:16.374Z level=INFO msg="Uploading 1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper (192B)"2291server # time=2026-10-01T08:27:16.376Z level=INFO msg="Uploading x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package (536B)"2292server # [ 29.473599] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.ls2293server # [ 29.484070] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.ls2294server # [ 29.486811] niks3-server[978]: 2026/10/01 08:27:16 INFO Received sign narinfos request method=POST path=/api/pushes/4/sign2295server # [ 29.488762] niks3-server[978]: 2026/10/01 08:27:16 INFO Signed narinfos id=4 count=22296server # [ 29.490928] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=nar/0v342j6sk8pcizzisl5bwzw5xdrdjdmynpgxd3f6827yp5bzbrl7.nar.zst2297server # time=2026-10-01T08:27:16.416Z level=INFO msg="Uploading 2 narinfos"2298server # [ 29.502741] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=nar/0820av37plmfgrxw6pcbym6phk1sv14dilyrablrnkzb7z1fd5js.nar.zst2299server # [ 29.508168] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=1czajpbpppk7h9y4k4w3hxz0xs25srq3.narinfo2300server # [ 29.510084] niks3-server[978]: 2026/10/01 08:27:16 INFO Received complete push request method=POST path=/api/pushes/4/complete2301server # time=2026-10-01T08:27:16.438Z level=INFO msg="Upload complete. (159ms)"2302server # [ 29.517703] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=x9d9hyw8xybijhpvipmkiryn9n8nw3ij.narinfo2303server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper, in 0.21 seconds)2304server: 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/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper23082309server: (finished: must succeed: 2310 export AWS_ACCESS_KEY_ID=rustfsadmin2311export AWS_SECRET_ACCESS_KEY=rustfsadmin2312 nix copy --from 's3://niks3-test?endpoint=http://server:9000®ion=us-east-1' --to /tmp/test-store /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2313, in 0.13 seconds)2314server: must succeed: 2315cat > /tmp/oidc-test.nix << 'EOF'2316derivation {2317 name = "oidc-test";2318 system = builtins.currentSystem;2319 builder = "/bin/sh";2320 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2321}2322EOF23232324server: (finished: must succeed: 2325cat > /tmp/oidc-test.nix << 'EOF'2326derivation {2327 name = "oidc-test";2328 system = builtins.currentSystem;2329 builder = "/bin/sh";2330 args = [ "-c" "echo 'OIDC test derivation' > $out" ];2331}2332EOF2333, in 0.01 seconds)2334server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link2335server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2336server # [ 29.709913] systemd[1]: Started Nix Daemon instance (PID 1330/UID 0).2337server # [ 29.749913] nix-daemon[1334]: remote pid 1330 is unknown user (trusted)2338server # this derivation will be built:2339server # /nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2340server # building '/nix/store/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv'...2341server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test.nix --no-out-link, in 0.16 seconds)2342server: 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'2343server # [ 29.839454] systemd[1]: nix-daemon@8-9-1330_1331-0.service: Deactivated successfully.2344server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:myorg/myrepo:ref:refs/heads/main&aud=http://server:5751&repository_owner=myorg', in 0.03 seconds)2345server: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRXQ282T09MbDB3ZVZ0bGpXUUtZd2hHdmo3Z3QzcFhFVmktQnpodktKZzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NDY4MzYsImlhdCI6MTc5MDg0MzIzNiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.O-ApgZhgZKEUv6txJfKuJeKSDbHqAClAGWn_umI67HF2IAT1WdHHQfqeFdcOq_xbj7sWKir1iajqJRJXKdKo70JQAotlCS9o00JgLCMdVABsmdprybPysPt_uorKI7LCp0t_KiNPClBEsonZKh95O9BsscnuexCJ9bvmeiYnxhNXZks2_NC8Vw8humDlTb5wOC-yYPuy1Feo92NX84jJtmOla3IFaTtW1I3VvdeJyDJx6o1vQSo1m4KeGc2VVw14rd7CJ5w6wuUhoeS780T1FV2TIi4uSTP34bFlHZ4o1LMKBRZQ5_Z-MAyIEYItuiqochcRI7XJuPjCy7slG6fH9Q' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2346server # time=2026-10-01T08:27:16.795Z 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"2347server # [ 29.940890] systemd[1]: Started Nix Daemon instance (PID 1364/UID 0).2348server # [ 29.979333] nix-daemon[1366]: remote pid 1364 is unknown user (trusted)2349server # [ 29.989495] systemd[1]: nix-daemon@9-10-1364_1365-0.service: Deactivated successfully.2350server # [ 29.995251] niks3-server[978]: 2026/10/01 08:27:16 INFO Received push request method=POST path=/api/pushes2351server # time=2026-10-01T08:27:16.922Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2352server # time=2026-10-01T08:27:16.923Z level=INFO msg="Uploading hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test (136B)"2353server # [ 30.021501] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.ls2354server # [ 30.024945] niks3-server[978]: 2026/10/01 08:27:16 INFO Received sign narinfos request method=POST path=/api/pushes/5/sign2355server # [ 30.028216] niks3-server[978]: 2026/10/01 08:27:16 INFO Signed narinfos id=5 count=12356server # time=2026-10-01T08:27:16.953Z level=INFO msg="Uploading 1 narinfos"2357server # [ 30.033446] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=log/87yp7lxi58c0mlg2mkpyfybkk9ikzy6n-oidc-test.drv2358server # [ 30.037940] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=nar/17h0qgwf5334wia961ibqy9hmpsyvfhrvj5ji9m031klp6sx4v0q.nar.zst2359server # [ 30.042254] niks3-server[978]: 2026/10/01 08:27:16 INFO Received complete push request method=POST path=/api/pushes/5/complete2360server # time=2026-10-01T08:27:16.970Z level=INFO msg="Upload complete. (142ms)"2361server # [ 30.048502] niks3-server[978]: 2026/10/01 08:27:16 INFO Registered completed upload object_key=hp57zlhhih1lr1f0hfrf8sca8fvgxc2i.narinfo2362server: (finished: must succeed: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRXQ282T09MbDB3ZVZ0bGpXUUtZd2hHdmo3Z3QzcFhFVmktQnpodktKZzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NDY4MzYsImlhdCI6MTc5MDg0MzIzNiwiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoibXlvcmciLCJzdWIiOiJyZXBvOm15b3JnL215cmVwbzpyZWY6cmVmcy9oZWFkcy9tYWluIn0.O-ApgZhgZKEUv6txJfKuJeKSDbHqAClAGWn_umI67HF2IAT1WdHHQfqeFdcOq_xbj7sWKir1iajqJRJXKdKo70JQAotlCS9o00JgLCMdVABsmdprybPysPt_uorKI7LCp0t_KiNPClBEsonZKh95O9BsscnuexCJ9bvmeiYnxhNXZks2_NC8Vw8humDlTb5wOC-yYPuy1Feo92NX84jJtmOla3IFaTtW1I3VvdeJyDJx6o1vQSo1m4KeGc2VVw14rd7CJ5w6wuUhoeS780T1FV2TIi4uSTP34bFlHZ4o1LMKBRZQ5_Z-MAyIEYItuiqochcRI7XJuPjCy7slG6fH9Q' /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test, in 0.19 seconds)2363server: must succeed: 2364cat > /tmp/oidc-test2.nix << 'EOF'2365derivation {2366 name = "oidc-test2";2367 system = builtins.currentSystem;2368 builder = "/bin/sh";2369 args = [ "-c" "echo 'OIDC test 2' > $out" ];2370}2371EOF23722373server: (finished: must succeed: 2374cat > /tmp/oidc-test2.nix << 'EOF'2375derivation {2376 name = "oidc-test2";2377 system = builtins.currentSystem;2378 builder = "/bin/sh";2379 args = [ "-c" "echo 'OIDC test 2' > $out" ];2380}2381EOF2382, in 0.01 seconds)2383server: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link2384server # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2385server # [ 30.111676] systemd[1]: Started Nix Daemon instance (PID 1376/UID 0).2386server # [ 30.148284] nix-daemon[1380]: remote pid 1376 is unknown user (trusted)2387server # this derivation will be built:2388server # /nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv2389server # building '/nix/store/1sl2zp65pyaksf29w1lxwgl7mc3mrw4x-oidc-test2.drv'...2390server: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/oidc-test2.nix --no-out-link, in 0.16 seconds)2391server: 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'2392server # [ 30.236580] systemd[1]: nix-daemon@10-11-1376_1377-0.service: Deactivated successfully.2393server: (finished: must succeed: curl -s 'http://127.0.0.1:8081/issue?sub=repo:otherorg/repo:ref:refs/heads/main&aud=http://server:5751&repository_owner=otherorg', in 0.03 seconds)2394server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRXQ282T09MbDB3ZVZ0bGpXUUtZd2hHdmo3Z3QzcFhFVmktQnpodktKZzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NDY4MzcsImlhdCI6MTc5MDg0MzIzNywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.E922ZFQHZwy0DryHDdU90dXpk_flr-a3XWA-9M8dAyOzf166rnrji4c3IeXpdLj-o0bQ9D5rgRmukzBmgSSBI6epyPIaGEC_BTfiuZW4XwNWPk9d1pTD7seVCXrj-TZVSLyWIsei4V2J2e7mNnPqXFFXW-QDUWofxPhwTBRohws3TXXaSCMF6ADRkMXGZalrQSjsUh_GejjogRZcU-WOvxZ53VD9opOijyd8KFVZwxGjKqrN5PITtwa1mEqjixmclPtabXoihvVjSrbyJG4MjBSo17JtZl--fNU0MGPLUkVXM7WdnXGqqwWDz4aYBaxSdxPeFl85IUTpOLsOD17b6Q' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22395server # time=2026-10-01T08:27:17.192Z 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"2396server # [ 30.305144] niks3-server[978]: 2026/10/01 08:27:17 WARN Authentication failed token_preview=eyJhbGciOi...OLsOD17b6Q token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2397server # [ 30.338730] systemd[1]: Started Nix Daemon instance (PID 1410/UID 0).2398server # [ 30.379320] nix-daemon[1412]: remote pid 1410 is unknown user (trusted)2399server # [ 30.389552] systemd[1]: nix-daemon@11-12-1410_1411-0.service: Deactivated successfully.2400server # time=2026-10-01T08:27:17.318Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2401server # [ 30.397128] niks3-server[978]: 2026/10/01 08:27:17 WARN Authentication failed token_preview=eyJhbGciOi...OLsOD17b6Q token_length=682 oidc_error="no rule matched: claim \"repository_owner\" value [otherorg] not in allowed patterns [myorg]" oidc_provider=test tried_providers=[test]2402server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRXQ282T09MbDB3ZVZ0bGpXUUtZd2hHdmo3Z3QzcFhFVmktQnpodktKZzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vc2VydmVyOjU3NTEiLCJleHAiOjE3OTA4NDY4MzcsImlhdCI6MTc5MDg0MzIzNywiaXNzIjoiaHR0cDovLzEyNy4wLjAuMTo4MDgwL29pZGMiLCJyZXBvc2l0b3J5X293bmVyIjoib3RoZXJvcmciLCJzdWIiOiJyZXBvOm90aGVyb3JnL3JlcG86cmVmOnJlZnMvaGVhZHMvbWFpbiJ9.E922ZFQHZwy0DryHDdU90dXpk_flr-a3XWA-9M8dAyOzf166rnrji4c3IeXpdLj-o0bQ9D5rgRmukzBmgSSBI6epyPIaGEC_BTfiuZW4XwNWPk9d1pTD7seVCXrj-TZVSLyWIsei4V2J2e7mNnPqXFFXW-QDUWofxPhwTBRohws3TXXaSCMF6ADRkMXGZalrQSjsUh_GejjogRZcU-WOvxZ53VD9opOijyd8KFVZwxGjKqrN5PITtwa1mEqjixmclPtabXoihvVjSrbyJG4MjBSo17JtZl--fNU0MGPLUkVXM7WdnXGqqwWDz4aYBaxSdxPeFl85IUTpOLsOD17b6Q' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.14 seconds)2403server: 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'2404server: (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)2405server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRXQ282T09MbDB3ZVZ0bGpXUUtZd2hHdmo3Z3QzcFhFVmktQnpodktKZzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDg0NjgzNywiaWF0IjoxNzkwODQzMjM3LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.AVF_d2h2eHVLj2GLI9DEulpCZ16Jnm5qfcHHY-xbRJqHzvRuko_dDxWcYFdLCwHHZP_Rn4B6ekdFz450Q7vCARyKSyuGJbu54bXOxVvqE-4tboGAHMEjwogWSVloCTrFSvJCgG3rPMWt0cMqrolTTRRhogz-IMSA-77altUQn-0eEx2BpCPoA7eXPtMeVd6ib3P0uvGfN0cyByhUBG5CG9JY3QqZA7hmhHTi4A3KaAIirxlZO19g1Q5oPN8lR0yjwGwXimbFdrlD_rv9sJj0DEzUcXLJ-MCAcTtGDwcU9B8ttqx5yDnw-kb_thJRvv6OK_PNNdBNEOTt73JBi0QozQ' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22406server # time=2026-10-01T08:27:17.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"2407server # [ 30.470828] niks3-server[978]: 2026/10/01 08:27:17 WARN Authentication failed token_preview=eyJhbGciOi...73JBi0QozQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2408server # [ 30.504395] systemd[1]: Started Nix Daemon instance (PID 1433/UID 0).2409server # [ 30.541180] nix-daemon[1435]: remote pid 1433 is unknown user (trusted)2410server # [ 30.551927] systemd[1]: nix-daemon@12-13-1433_1434-0.service: Deactivated successfully.2411server # [ 30.556638] niks3-server[978]: 2026/10/01 08:27:17 WARN Authentication failed token_preview=eyJhbGciOi...73JBi0QozQ token_length=676 oidc_error="no provider could verify the token (signature or issuer mismatch)" oidc_provider="" tried_providers=[test]2412server # time=2026-10-01T08:27:17.482Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2413server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'eyJhbGciOiJSUzI1NiIsImtpZCI6ImRXQ282T09MbDB3ZVZ0bGpXUUtZd2hHdmo3Z3QzcFhFVmktQnpodktKZzgiLCJ0eXAiOiJKV1QifQ.eyJhdWQiOiJodHRwOi8vd3Jvbmc6NTc1MSIsImV4cCI6MTc5MDg0NjgzNywiaWF0IjoxNzkwODQzMjM3LCJpc3MiOiJodHRwOi8vMTI3LjAuMC4xOjgwODAvb2lkYyIsInJlcG9zaXRvcnlfb3duZXIiOiJteW9yZyIsInN1YiI6InJlcG86bXlvcmcvbXlyZXBvOnJlZjpyZWZzL2hlYWRzL21haW4ifQ.AVF_d2h2eHVLj2GLI9DEulpCZ16Jnm5qfcHHY-xbRJqHzvRuko_dDxWcYFdLCwHHZP_Rn4B6ekdFz450Q7vCARyKSyuGJbu54bXOxVvqE-4tboGAHMEjwogWSVloCTrFSvJCgG3rPMWt0cMqrolTTRRhogz-IMSA-77altUQn-0eEx2BpCPoA7eXPtMeVd6ib3P0uvGfN0cyByhUBG5CG9JY3QqZA7hmhHTi4A3KaAIirxlZO19g1Q5oPN8lR0yjwGwXimbFdrlD_rv9sJj0DEzUcXLJ-MCAcTtGDwcU9B8ttqx5yDnw-kb_thJRvv6OK_PNNdBNEOTt73JBi0QozQ' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.14 seconds)2414server: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test22415server # time=2026-10-01T08:27:17.497Z 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"2416server # [ 30.609260] niks3-server[978]: 2026/10/01 08:27:17 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]2417server # [ 30.642276] systemd[1]: Started Nix Daemon instance (PID 1453/UID 0).2418server # [ 30.681208] nix-daemon[1455]: remote pid 1453 is unknown user (trusted)2419server # [ 30.691428] systemd[1]: nix-daemon@13-14-1453_1454-0.service: Deactivated successfully.2420server # [ 30.695293] niks3-server[978]: 2026/10/01 08:27:17 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]2421server # time=2026-10-01T08:27:17.622Z level=ERROR msg="Fatal error" error="pushing paths: creating pending closures: creating push: server returned 401: Unauthorized\n"2422server: (finished: must fail: NIKS3_SERVER_URL=http://server:5751 /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --auth-token 'not-a-valid-jwt-token' /nix/store/7a745016z2pfi6bnynd5cagkrhpjgz09-oidc-test2, in 0.14 seconds)2423server: must succeed: 2424 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.324252426server # [ 30.747560] niks3-server[978]: 2026/10/01 08:27:17 INFO Received create pin request method=POST path=/api/pins/hello-pin2427server # [ 30.757742] niks3-server[978]: 2026/10/01 08:27:17 INFO Created/updated pin name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.3 narinfo_key=xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi.narinfo2428server # time=2026-10-01T08:27:17.683Z level=INFO msg="Created pin" name=hello-pin store_path=/nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32429server: (finished: must succeed: 2430 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins create hello-pin /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32431, in 0.06 seconds)2432server: must succeed: 2433 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list24342435server # [ 30.810140] niks3-server[978]: 2026/10/01 08:27:17 INFO Received list pins request method=GET path=/api/pins2436server: (finished: must succeed: 2437 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list2438, in 0.05 seconds)2439server: must succeed: 2440 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --names-only24412442server # [ 30.857650] niks3-server[978]: 2026/10/01 08:27:17 INFO Received list pins request method=GET path=/api/pins2443server: (finished: must succeed: 2444 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --names-only2445, in 0.05 seconds)2446server: must succeed: 2447 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --json24482449server # [ 30.904450] niks3-server[978]: 2026/10/01 08:27:17 INFO Received list pins request method=GET path=/api/pins2450server: (finished: must succeed: 2451 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --json2452, in 0.05 seconds)2453server: must succeed: 2454 export S3_ENDPOINT_URL=http://localhost:90002455 export AWS_ACCESS_KEY_ID=rustfsadmin2456 export AWS_SECRET_ACCESS_KEY=rustfsadmin2457 /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin24582459server: (finished: must succeed: 2460 export S3_ENDPOINT_URL=http://localhost:90002461 export AWS_ACCESS_KEY_ID=rustfsadmin2462 export AWS_SECRET_ACCESS_KEY=rustfsadmin2463 /nix/store/59rphn0qd8k90mdggjdak165a0ysaclw-s5cmd-2.3.0/bin/s5cmd cat s3://niks3-test/pins/hello-pin2464, in 0.02 seconds)2465server: must succeed: 2466 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log24672468server # time=2026-10-01T08:27:17.900Z level=INFO msg="All 1 paths already cached"2469server # [ 30.978277] niks3-server[978]: 2026/10/01 08:27:17 INFO Received create pin request method=POST path=/api/pins/ca-pin2470server # time=2026-10-01T08:27:17.908Z level=INFO msg="Created pin" name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2471server # [ 30.986776] niks3-server[978]: 2026/10/01 08:27:17 INFO Created/updated pin name=ca-pin store_path=/nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log narinfo_key=dv59n3bx90hi3c1q8jg1dshwsg6jf2sv.narinfo2472server: (finished: must succeed: 2473 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 push --pin ca-pin /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2474, in 0.06 seconds)2475server: must succeed: 2476 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --names-only24772478server # [ 31.071576] niks3-server[978]: 2026/10/01 08:27:17 INFO Received list pins request method=GET path=/api/pins2479server: (finished: must succeed: 2480 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --names-only2481, in 0.08 seconds)2482server: must succeed: 2483 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins delete hello-pin24842485server # [ 31.119252] niks3-server[978]: 2026/10/01 08:27:18 INFO Received delete pin request method=DELETE path=/api/pins/hello-pin2486server # [ 31.128295] niks3-server[978]: 2026/10/01 08:27:18 INFO Deleted pin name=hello-pin2487server # time=2026-10-01T08:27:18.052Z level=INFO msg="Deleted pin" name=hello-pin2488server: (finished: must succeed: 2489 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins delete hello-pin2490, in 0.06 seconds)2491server: must succeed: 2492 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --names-only24932494server # [ 31.179434] niks3-server[978]: 2026/10/01 08:27:18 INFO Received list pins request method=GET path=/api/pins2495server: (finished: must succeed: 2496 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins list --names-only2497, in 0.05 seconds)2498server: must fail: 2499 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent25002501server # [ 31.226471] niks3-server[978]: 2026/10/01 08:27:18 INFO Received create pin request method=POST path=/api/pins/bad-pin2502server # [ 31.228533] niks3-server[978]: 2026/10/01 08:27:18 ERROR Failed to get closure for pin narinfo_key=aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa.narinfo error="no rows in result set"2503server # time=2026-10-01T08:27:18.154Z level=ERROR msg="Fatal error" error="creating pin: server returned 404: closure not found: store path must be pushed before pinning\n"2504server: (finished: must fail: 2505 NIKS3_SERVER_URL=http://server:5751 NIKS3_AUTH_TOKEN_FILE=/tmp/test-config/auth-token /nix/store/0q480frmkhpcrpjj5rz2vlgclbhm2578-niks3-1.13.0/bin/niks3 pins create bad-pin /nix/store/aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa-nonexistent2506, in 0.05 seconds)2507server: must succeed: systemctl start niks3-gc.service2508server # [ 31.256702] systemd[1]: Starting niks3 garbage collection...2509server # [ 31.300355] niks3[1581]: time=2026-10-01T08:27:18.223Z level=INFO msg="Starting garbage collection" older-than=720h failed-uploads-older-than=6h force=false2510server # [ 31.303808] niks3-server[978]: 2026/10/01 08:27:18 INFO Starting cleanup of old closures method=DELETE path=/api/closures2511server # [ 31.305212] niks3[1581]: time=2026-10-01T08:27:18.226Z level=INFO msg="Garbage collection started"2512server # [ 31.308308] niks3-server[978]: 2026/10/01 08:27:18 INFO Aborted multipart uploads count=02513server # [ 31.316054] niks3-server[978]: 2026/10/01 08:27:18 INFO Garbage collection completed failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02514server # [ 31.322066] niks3-server[978]: 2026/10/01 08:27:18 INFO Vacuumed table table=pending_closures2515server # [ 31.326165] niks3-server[978]: 2026/10/01 08:27:18 INFO Vacuumed table table=pending_objects2516server # [ 31.329725] niks3-server[978]: 2026/10/01 08:27:18 INFO Vacuumed table table=multipart_uploads2517server # [ 31.332758] niks3-server[978]: 2026/10/01 08:27:18 INFO Vacuumed table table=closures2518server # [ 31.336252] niks3-server[978]: 2026/10/01 08:27:18 INFO Vacuumed table table=objects2519server # [ 33.306755] niks3[1581]: time=2026-10-01T08:27:20.228Z level=INFO msg="Garbage collection progress" phase="" failed_uploads_deleted=0 old_closures_deleted=0 objects_marked=3 objects_deleted=0 objects_failed=02520server # [ 33.313605] niks3[1581]: time=2026-10-01T08:27:20.228Z level=INFO msg="Garbage collection completed successfully" failed-uploads-deleted=0 old-closures-deleted=0 objects-marked-for-deletion=3 objects-deleted-after-grace-period=0 objects-failed-to-delete=02521server # [ 33.324302] systemd[1]: niks3-gc.service: Deactivated successfully.2522server # [ 33.328141] systemd[1]: Finished niks3 garbage collection.2523server # [ 33.331973] systemd[1]: niks3-gc.service: Consumed 34ms CPU time over 2.070s wall clock time, 2.5M memory peak, 1.2K incoming IP traffic, 823B outgoing IP traffic.2524server: (finished: must succeed: systemctl start niks3-gc.service, in 2.11 seconds)2525builder: waiting for unit niks3-auto-upload.socket2526builder: waiting for the VM to finish booting2527builder: Guest shell says: b'Spawning backdoor root shell...\n'2528builder: connected to guest root shell2529builder: (connecting took 0.00 seconds)2530builder: (finished: waiting for the VM to finish booting, in 0.00 seconds)2531builder: (finished: waiting for unit niks3-auto-upload.socket, in 0.02 seconds)2532builder: must succeed: test -S /run/niks3/upload-to-cache.sock2533builder: (finished: must succeed: test -S /run/niks3/upload-to-cache.sock, in 0.01 seconds)2534builder: must succeed: grep post-build-hook /etc/nix/nix.conf2535builder: (finished: must succeed: grep post-build-hook /etc/nix/nix.conf, in 0.01 seconds)2536builder: must succeed: 2537cat > /tmp/test-drv.nix << 'EOF'2538derivation {2539 name = "post-build-hook-test";2540 system = builtins.currentSystem;2541 builder = "/bin/sh";2542 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2543}2544EOF25452546builder: (finished: must succeed: 2547cat > /tmp/test-drv.nix << 'EOF'2548derivation {2549 name = "post-build-hook-test";2550 system = builtins.currentSystem;2551 builder = "/bin/sh";2552 args = [ "-c" "echo 'hello from post-build-hook test' > $out" ];2553}2554EOF2555, in 0.01 seconds)2556builder: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link2557builder # warning: Nix search path entry '/nix/var/nix/profiles/per-user/root/channels' does not exist, ignoring2558builder # [ 33.520335] systemd[1]: Created slice Slice /system/nix-daemon.2559builder # [ 33.523063] systemd[1]: Started Nix Daemon instance (PID 786/UID 0).2560builder # [ 33.568734] nix-daemon[790]: remote pid 786 is unknown user (trusted)2561builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 470 ms (attempt 1/5)2562builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1084 ms (attempt 2/5)2563builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 1915 ms (attempt 3/5)2564builder # warning: error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6); retrying in 4068 ms (attempt 4/5)2565builder # warning: Failed to setup the substituter at URI 'https://cache.nixos.org/': error: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve host: cache.nixos.org (curl error code=6)2566builder # this derivation will be built:2567builder # /nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2568builder # building '/nix/store/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv'...2569builder # [ 41.312878] systemd[1]: Started niks3 auto-upload daemon.2570builder # [ 41.424854] niks3-hook[814]: time=2026-10-01T08:27:28.023Z 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=0s2571builder # [ 41.436247] niks3-hook[814]: time=2026-10-01T08:27:28.034Z level=INFO msg="Upload queue status" pending=12572builder # [ 41.438026] niks3-hook[814]: time=2026-10-01T08:27:28.036Z level=INFO msg="Uploading batch" count=12573builder: (finished: must succeed: nix-build --log-format bar-with-logs /tmp/test-drv.nix --no-out-link, in 7.99 seconds)2574builder: waiting for unit niks3-auto-upload.service2575builder # [ 41.459989] systemd[1]: nix-daemon@0-1-786_787-0.service: Deactivated successfully.2576builder # [ 41.462749] systemd[1]: nix-daemon@0-1-786_787-0.service: Consumed 153ms CPU time over 7.938s wall clock time, 20.6M memory peak, 1.4K outgoing IP traffic.2577builder: (finished: waiting for unit niks3-auto-upload.service, in 0.07 seconds)2578??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2579 File "/nix/store/vk7cx7y36sxavc4g6cv4sra2zxk4xp68-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392580builder: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive2581??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.2582 File "/nix/store/vk7cx7y36sxavc4g6cv4sra2zxk4xp68-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 392583builder # [ 41.524390] systemd[1]: Started Nix Daemon instance (PID 828/UID 0).2584builder # [ 41.577094] nix-daemon[833]: remote pid 828 is unknown user (trusted)2585builder # [ 41.588567] systemd[1]: nix-daemon@1-2-828_829-0.service: Deactivated successfully.2586server # [ 41.542198] niks3-server[978]: 2026/10/01 08:27:28 INFO Received push request method=POST path=/api/pushes2587builder # [ 41.607087] niks3-hook[814]: time=2026-10-01T08:27:28.206Z level=INFO msg="Uploading 1 paths to server (0 already cached)"2588builder # [ 41.608842] niks3-hook[814]: time=2026-10-01T08:27:28.207Z level=INFO msg="Uploading ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test (144B)"2589server # [ 41.600928] niks3-server[978]: 2026/10/01 08:27:28 INFO Received sign narinfos request method=POST path=/api/pushes/6/sign2590server # [ 41.605861] niks3-server[978]: 2026/10/01 08:27:28 INFO Registered completed upload object_key=nar/1a0vfw93shmvlz6lac7d1lv82jxzfdswigh5s32hpvncf74i1i0y.nar.zst2591builder # [ 41.663993] niks3-hook[814]: time=2026-10-01T08:27:28.262Z level=INFO msg="Uploading 1 narinfos"2592server # [ 41.611141] niks3-server[978]: 2026/10/01 08:27:28 INFO Signed narinfos id=6 count=12593server # [ 41.617252] niks3-server[978]: 2026/10/01 08:27:28 INFO Registered completed upload object_key=log/18i07rp8izmxp36zd16yskand976x033-post-build-hook-test.drv2594server # [ 41.627098] niks3-server[978]: 2026/10/01 08:27:28 INFO Received complete push request method=POST path=/api/pushes/6/complete2595server # [ 41.634846] niks3-server[978]: 2026/10/01 08:27:28 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.narinfo2596builder # [ 41.687663] niks3-hook[814]: time=2026-10-01T08:27:28.286Z level=INFO msg="Upload complete. (249ms)"2597server # [ 41.637270] niks3-server[978]: 2026/10/01 08:27:28 INFO Registered completed upload object_key=ykg16qhbiwqmava2ahq779zd0dfd9mjd.ls2598builder # [ 46.434685] niks3-hook[814]: time=2026-10-01T08:27:33.032Z level=INFO msg="Idle timeout reached and queue is empty, shutting down"2599builder # [ 46.439697] niks3-hook[814]: time=2026-10-01T08:27:33.037Z level=INFO msg="niks3-hook serve stopped"2600builder # [ 46.452432] systemd[1]: niks3-auto-upload.service: Deactivated successfully.2601builder # [ 46.457373] systemd[1]: niks3-auto-upload.service: Consumed 119ms CPU time over 5.143s wall clock time, 11.1M memory peak, 68K written to disk, 5.9K incoming IP traffic, 8.9K outgoing IP traffic.2602builder: (finished: waiting for success: test $(systemctl is-active niks3-auto-upload.service) = inactive, in 5.23 seconds)2603server: must succeed: 2604 export AWS_ACCESS_KEY_ID=rustfsadmin2605export AWS_SECRET_ACCESS_KEY=rustfsadmin2606 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-test26072608server: (finished: must succeed: 2609 export AWS_ACCESS_KEY_ID=rustfsadmin2610export AWS_SECRET_ACCESS_KEY=rustfsadmin2611 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-test2612, in 0.14 seconds)2613server: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2614server: (finished: must succeed: nix --store /tmp/hook-test-store store cat /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test, in 0.04 seconds)2615(finished: run the VM test script, in 47.82 seconds)2616test script finished in 47.90s2617cleanup2618kill QemuMachine (pid 48)2619builder # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2620builder # [2026-10-01T08:27:33Z INFO virtiofsd] Client disconnected, shutting down2621builder # [2026-10-01T08:27:33Z INFO virtiofsd] Client disconnected, shutting down2622builder # [2026-10-01T08:27:33Z INFO virtiofsd] Client disconnected, shutting down2623kill QemuMachine (pid 47)2624server # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2625server # [2026-10-01T08:27:33Z INFO virtiofsd] Client disconnected, shutting down2626server # [2026-10-01T08:27:33Z INFO virtiofsd] Client disconnected, shutting down2627server # [2026-10-01T08:27:33Z INFO virtiofsd] Client disconnected, shutting down2628(finished: cleanup, in 0.47 seconds)2629additionally exposed symbols:2630 builder, server,2631 vlan1,2632 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_ssh2633Hello store path: /nix/store/xl1h9i29pgq2q5cszjhm5wpfxfbbqwyi-hello-2.12.32634Test output path: /nix/store/dv59n3bx90hi3c1q8jg1dshwsg6jf2sv-test-build-log2635Symlink wrapper store path: /nix/store/1czajpbpppk7h9y4k4w3hxz0xs25srq3-symlink-wrapper2636Symlink wrapper points to: /nix/store/x9d9hyw8xybijhpvipmkiryn9n8nw3ij-base-package/bin/test-program2637OIDC test store path: /nix/store/hp57zlhhih1lr1f0hfrf8sca8fvgxc2i-oidc-test2638Valid OIDC token obtained (length=677)2639OIDC push with valid token: SUCCESS2640Invalid OIDC token obtained (wrong org)2641OIDC push with wrong org: correctly rejected2642Wrong audience OIDC token obtained2643OIDC push with wrong audience: correctly rejected2644OIDC push with malformed token: correctly rejected2645All OIDC tests passed!2646All pin tests passed!2647Built store path: /nix/store/ykg16qhbiwqmava2ahq779zd0dfd9mjd-post-build-hook-test2648Post-build-hook pipeline test passed!