vm-test-run-buildbot-nix-poller
checks.aarch64-linux.poller
· build #67
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7buildbot: waiting for unit sshd.service8buildbot: waiting for the VM to finish booting9buildbot: starting vm10buildbot # Disk image does not exist, creating the virtualisation disk image...11buildbot: QEMU running (pid 45)12buildbot # Formatting '/build/vm-state-buildbot/tmp.OA1ImcnECq', fmt=raw size=107374182413buildbot # mke2fs 1.47.4 (6-Mar-2025)14buildbot # Discarding device blocks: 0/262144 done15buildbot # Creating filesystem with 262144 4k blocks and 65536 inodes16buildbot # Filesystem UUID: ee4bdefa-bc0f-4d5f-b460-883a3c3b173417buildbot # Superblock backups stored on blocks:18buildbot # 32768, 98304, 163840, 22937619buildbot # 20buildbot # Allocating group tables: 0/8 done21buildbot # Writing inode tables: 0/8 done22buildbot # Creating journal (8192 blocks): done23buildbot # Writing superblocks and filesystem accounting information: 0/8 done24buildbot # 25buildbot # Virtualisation disk image created.26buildbot # Starting virtiofs daemons...27buildbot # [2026-09-20T07:27:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28buildbot # [2026-09-20T07:27:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29buildbot # [2026-09-20T07:27:37Z INFO virtiofsd] Waiting for vhost-user socket connection...30buildbot # [2026-09-20T07:27:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31buildbot # [2026-09-20T07:27:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32buildbot # [2026-09-20T07:27:37Z INFO virtiofsd] Waiting for vhost-user socket connection...33buildbot # [2026-09-20T07:27:37Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34buildbot # [2026-09-20T07:27:37Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35buildbot # [2026-09-20T07:27:37Z INFO virtiofsd] Waiting for vhost-user socket connection...36buildbot # [2026-09-20T07:27:37Z INFO virtiofsd] Client connected, servicing requests37buildbot # [2026-09-20T07:27:37Z INFO virtiofsd] Client connected, servicing requests38buildbot # [2026-09-20T07:27:37Z INFO virtiofsd] Client connected, servicing requests39buildbot # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40buildbot # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202641buildbot # [ 0.000000] KASLR enabled42buildbot # [ 0.000000] random: crng init done43buildbot # [ 0.000000] Machine model: linux,dummy-virt44buildbot # [ 0.000000] efi: UEFI not found.45buildbot # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT46buildbot # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]47buildbot # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]48buildbot # [ 0.000000] Zone ranges:49buildbot # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]50buildbot # [ 0.000000] DMA32 empty51buildbot # [ 0.000000] Normal empty52buildbot # [ 0.000000] Device empty53buildbot # [ 0.000000] Movable zone start for each node54buildbot # [ 0.000000] Early memory node ranges55buildbot # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]56buildbot # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]57buildbot # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000058buildbot # [ 0.000000] psci: probing for conduit method from DT.59buildbot # [ 0.000000] psci: PSCIv1.3 detected in firmware.60buildbot # [ 0.000000] psci: Using standard PSCI v0.2 function IDs61buildbot # [ 0.000000] psci: Trusted OS migration not required62buildbot # [ 0.000000] psci: SMC Calling Convention v1.163buildbot # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)64buildbot # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u31129665buildbot # [ 0.000000] Detected PIPT I-cache on CPU066buildbot # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)67buildbot # [ 0.000000] CPU features: detected: GICv3 CPU interface68buildbot # [ 0.000000] CPU features: detected: Spectre-v469buildbot # [ 0.000000] CPU features: detected: Spectre-BHB70buildbot # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3871buildbot # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2372buildbot # [ 0.000000] alternatives: applying boot alternatives73buildbot # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/zqabzgl6wbzk2pn5bshxydhi02k7qs81-nixos-system-buildbot-test/init regInfo=/nix/store/pjf2mb3cb3mjrsjf4i2y40mag17hgnn2-closure-info/registration console=ttyAMA0,115200n8 console=tty074buildbot # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/pjf2mb3cb3mjrsjf4i2y40mag17hgnn2-closure-info/registration", will be passed to user space.75buildbot # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes76buildbot # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)77buildbot # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)78buildbot # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB79buildbot # [ 0.000000] software IO TLB: area num 1.80buildbot # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)81buildbot # [ 0.000000] Fallback order for Node 0: 082buildbot # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214483buildbot # [ 0.000000] Policy zone: DMA84buildbot # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off85buildbot # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=186buildbot # [ 0.000000] allocated 2097152 bytes of page_ext87buildbot # [ 0.000000] ftrace: allocating 74950 entries in 294 pages88buildbot # [ 0.000000] ftrace: allocated 294 pages with 4 groups89buildbot # [ 0.000000] rcu: Hierarchical RCU implementation.90buildbot # [ 0.000000] rcu: RCU event tracing is enabled.91buildbot # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.92buildbot # [ 0.000000] Trampoline variant of Tasks RCU enabled.93buildbot # [ 0.000000] Rude variant of Tasks RCU enabled.94buildbot # [ 0.000000] Tracing variant of Tasks RCU enabled.95buildbot # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.96buildbot # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=197buildbot # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.98buildbot # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.99buildbot # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.100buildbot # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0101buildbot # [ 0.000000] GICv3: 256 SPIs implemented102buildbot # [ 0.000000] GICv3: 0 Extended SPIs implemented103buildbot # [ 0.000000] Root IRQ handler: gic_handle_irq104buildbot # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI105buildbot # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0106buildbot # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000107buildbot # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]108buildbot # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)109buildbot # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)110buildbot # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000111buildbot # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000112buildbot # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.113buildbot # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns114buildbot # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).115buildbot # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns116buildbot # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns117buildbot # [ 0.000028] arm-pv: using stolen time PV118buildbot # [ 0.000379] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119buildbot # [ 0.000552] Console: colour dummy device 80x25120buildbot # [ 0.000560] printk: legacy console [tty0] enabled121buildbot # [ 0.000745] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122buildbot # [ 0.000752] pid_max: default: 32768 minimum: 301123buildbot # [ 0.000843] LSM: initializing lsm=capability,landlock,yama,bpf,ima124buildbot # [ 0.001007] landlock: Up and running.125buildbot # [ 0.001010] Yama: becoming mindful.126buildbot # [ 0.001418] LSM support for eBPF active127buildbot # [ 0.001546] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128buildbot # [ 0.001565] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129buildbot # [ 0.003440] rcu: Hierarchical SRCU implementation.130buildbot # [ 0.003446] rcu: Max phase no-delay instances is 1000.131buildbot # [ 0.004679] fsl-mc MSI: its@8080000 domain created132buildbot # [ 0.004769] EFI services will not be available.133buildbot # [ 0.004851] smp: Bringing up secondary CPUs ...134buildbot # [ 0.004860] smp: Brought up 1 node, 1 CPU135buildbot # [ 0.004863] SMP: Total of 1 processors activated.136buildbot # [ 0.004866] CPU: All CPU(s) started at EL1137buildbot # [ 0.004878] CPU features: detected: Branch Target Identification138buildbot # [ 0.004882] CPU features: detected: ARMv8.4 Translation Table Level139buildbot # [ 0.004885] CPU features: detected: Instruction cache invalidation not required for I/D coherence140buildbot # [ 0.004889] CPU features: detected: Data cache clean to the PoU not required for I/D coherence141buildbot # [ 0.004892] CPU features: detected: Common not Private translations142buildbot # [ 0.004895] CPU features: detected: CRC32 instructions143buildbot # [ 0.004898] CPU features: detected: Data cache clean to Point of Deep Persistence144buildbot # [ 0.004902] CPU features: detected: Data cache clean to Point of Persistence145buildbot # [ 0.004905] CPU features: detected: Data independent timing control (DIT)146buildbot # [ 0.004908] CPU features: detected: E0PD147buildbot # [ 0.004911] CPU features: detected: Enhanced Counter Virtualization148buildbot # [ 0.004914] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)149buildbot # [ 0.004917] CPU features: detected: Enhanced Virtualization Traps150buildbot # [ 0.004920] CPU features: detected: Fine Grained Traps151buildbot # [ 0.004923] CPU features: detected: Generic authentication (architected QARMA5 algorithm)152buildbot # [ 0.004928] CPU features: detected: RCpc load-acquire (LDAPR)153buildbot # [ 0.004931] CPU features: detected: LSE atomic instructions154buildbot # [ 0.004933] CPU features: detected: Privileged Access Never155buildbot # [ 0.004936] CPU features: detected: PMUv3156buildbot # [ 0.004939] CPU features: detected: RAS Extension Support157buildbot # [ 0.004941] CPU features: detected: RASv1p1 Extension Support158buildbot # [ 0.004944] CPU features: detected: Random Number Generator159buildbot # [ 0.004947] CPU features: detected: Speculation barrier (SB)160buildbot # [ 0.004949] CPU features: detected: Stage-2 Force Write-Back161buildbot # [ 0.004952] CPU features: detected: TLB range maintenance instructions162buildbot # [ 0.004957] CPU features: detected: Speculative Store Bypassing Safe (SSBS)163buildbot # [ 0.004994] alternatives: applying system-wide alternatives164buildbot # [ 0.007800] CPU features: detected: BBM Level 2 without TLB conflict abort165buildbot # [ 0.007955] Memory: 893360K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 113896K reserved, 32768K cma-reserved)166buildbot # [ 0.008315] devtmpfs: initialized167buildbot # [ 0.009971] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)168buildbot # [ 0.009998] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).169buildbot # [ 0.010173] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL170buildbot # [ 0.010177] 0 pages in range for non-PLT usage171buildbot # [ 0.010179] 508272 pages in range for PLT usage172buildbot # [ 0.010281] pinctrl core: initialized pinctrl subsystem173buildbot # [ 0.011023] DMI not present or invalid.174buildbot # [ 0.014135] NET: Registered PF_NETLINK/PF_ROUTE protocol family175buildbot # [ 0.016377] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations176buildbot # [ 0.016528] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations177buildbot # [ 0.016685] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations178buildbot # [ 0.016709] audit: initializing netlink subsys (disabled)179buildbot # [ 0.017478] thermal_sys: Registered thermal governor 'fair_share'180buildbot # [ 0.017480] thermal_sys: Registered thermal governor 'bang_bang'181buildbot # [ 0.017484] thermal_sys: Registered thermal governor 'step_wise'182buildbot # [ 0.017486] thermal_sys: Registered thermal governor 'user_space'183buildbot # [ 0.017489] thermal_sys: Registered thermal governor 'power_allocator'184buildbot # [ 0.017530] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1185buildbot # [ 0.017540] cpuidle: using governor ladder186buildbot # [ 0.017545] cpuidle: using governor menu187buildbot # [ 0.017732] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.188buildbot # [ 0.017747] ASID allocator initialised with 65536 entries189buildbot # [ 0.018835] Serial: AMBA PL011 UART driver190buildbot # [ 0.024118] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1191buildbot # [ 0.024269] printk: console [ttyAMA0] enabled192buildbot # [ 0.148067] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages193buildbot # [ 0.148090] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page194buildbot # [ 0.148096] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages195buildbot # [ 0.148100] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page196buildbot # [ 0.148105] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages197buildbot # [ 0.148109] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page198buildbot # [ 0.148114] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages199buildbot # [ 0.148118] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page200buildbot # [ 0.155632] fbcon: Taking over console201buildbot # [ 0.155646] ACPI: Interpreter disabled.202buildbot # [ 0.157513] iommu: Default domain type: Translated203buildbot # [ 0.157524] iommu: DMA domain TLB invalidation policy: strict mode204buildbot # [ 0.159259] SCSI subsystem initialized205buildbot # [ 0.166880] usbcore: registered new interface driver usbfs206buildbot # [ 0.166915] usbcore: registered new interface driver hub207buildbot # [ 0.166939] usbcore: registered new device driver usb208buildbot # [ 0.167214] pps_core: LinuxPPS API ver. 1 registered209buildbot # [ 0.167221] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>210buildbot # [ 0.167231] PTP clock support registered211buildbot # [ 0.167278] EDAC MC: Ver: 3.0.0212buildbot # [ 0.171881] scmi_core: SCMI protocol bus registered213buildbot # [ 0.172840] FPGA manager framework214buildbot # [ 0.173777] vgaarb: loaded215buildbot # [ 0.174434] clocksource: Switched to clocksource arch_sys_counter216buildbot # [ 0.175056] VFS: Disk quotas dquot_6.6.0217buildbot # [ 0.175083] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)218buildbot # [ 0.177406] netfs: FS-Cache loaded219buildbot # [ 0.177524] pnp: PnP ACPI: disabled220buildbot # [ 0.183529] NET: Registered PF_INET protocol family221buildbot # [ 0.183700] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)222buildbot # [ 0.212366] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)223buildbot # [ 0.212411] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)224buildbot # [ 0.212435] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)225buildbot # [ 0.212474] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)226buildbot # [ 0.212552] TCP: Hash tables configured (established 8192 bind 8192)227buildbot # [ 0.212631] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)228buildbot # [ 0.212687] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)229buildbot # [ 0.212739] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)230buildbot # [ 0.212831] NET: Registered PF_UNIX/PF_LOCAL protocol family231buildbot # [ 0.212854] NET: Registered PF_XDP protocol family232buildbot # [ 0.212872] PCI: CLS 0 bytes, default 64233buildbot # [ 0.213116] Trying to unpack rootfs image as initramfs...234buildbot # [ 0.227871] kvm [1]: HYP mode not available235buildbot # [ 0.318994] Initialise system trusted keyrings236buildbot # [ 0.319716] workingset: timestamp_bits=42 max_order=18 bucket_order=0237buildbot # [ 0.320975] squashfs: version 4.0 (2009/01/31) Phillip Lougher238buildbot # [ 0.321726] 9p: Installing v9fs 9p2000 file system support239buildbot # [ 0.342399] Key type asymmetric registered240buildbot # [ 0.342422] Asymmetric key parser 'x509' registered241buildbot # [ 0.350532] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)242buildbot # [ 0.351581] io scheduler mq-deadline registered243buildbot # [ 0.351593] io scheduler kyber registered244buildbot # [ 0.362584] pl061_gpio 9030000.pl061: PL061 GPIO chip registered245buildbot # [ 0.363979] ledtrig-cpu: registered to indicate activity on CPUs246buildbot # [ 0.364347] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:247buildbot # [ 0.364364] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000248buildbot # [ 0.364377] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000249buildbot # [ 0.364386] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000250buildbot # [ 0.364408] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits251buildbot # [ 0.364433] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]252buildbot # [ 0.364504] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00253buildbot # [ 0.364515] pci_bus 0000:00: root bus resource [bus 00-ff]254buildbot # [ 0.364521] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]255buildbot # [ 0.364526] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]256buildbot # [ 0.364532] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]257buildbot # [ 0.364593] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint258buildbot # [ 0.365039] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint259buildbot # [ 0.365226] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]260buildbot # [ 0.365244] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]261buildbot # [ 0.365274] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]262buildbot # [ 0.365290] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]263buildbot # [ 0.365752] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint264buildbot # [ 0.365936] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]265buildbot # [ 0.365952] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]266buildbot # [ 0.365981] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267buildbot # [ 0.366431] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268buildbot # [ 0.386672] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]269buildbot # [ 0.386692] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]270buildbot # [ 0.386721] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271buildbot # [ 0.387183] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272buildbot # [ 0.387367] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]273buildbot # [ 0.387383] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]274buildbot # [ 0.387413] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275buildbot # [ 0.387429] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]276buildbot # [ 0.387885] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277buildbot # [ 0.388073] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]278buildbot # [ 0.388103] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279buildbot # [ 0.388556] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280buildbot # [ 0.388744] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]281buildbot # [ 0.388774] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282buildbot # [ 0.389169] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283buildbot # [ 0.389351] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]284buildbot # [ 0.389622] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint285buildbot # [ 0.389811] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]286buildbot # [ 0.389842] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287buildbot # [ 0.390296] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint288buildbot # [ 0.410537] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]289buildbot # [ 0.410581] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290buildbot # [ 0.411068] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint291buildbot # [ 0.411259] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]292buildbot # [ 0.411289] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]293buildbot # [ 0.411757] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint294buildbot # [ 0.412030] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]295buildbot # [ 0.412048] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]296buildbot # [ 0.412077] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]297buildbot # [ 0.412549] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint298buildbot # [ 0.412732] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]299buildbot # [ 0.412749] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]300buildbot # [ 0.412778] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]301buildbot # [ 0.413468] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned302buildbot # [ 0.413480] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned303buildbot # [ 0.413485] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned304buildbot # [ 0.413530] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned305buildbot # [ 0.413577] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned306buildbot # [ 0.413624] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned307buildbot # [ 0.413672] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned308buildbot # [ 0.413718] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned309buildbot # [ 0.413766] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned310buildbot # [ 0.413813] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned311buildbot # [ 0.413859] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned312buildbot # [ 0.413906] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned313buildbot # [ 0.413987] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned314buildbot # [ 0.414040] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned315buildbot # [ 0.414061] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned316buildbot # [ 0.414083] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned317buildbot # [ 0.414104] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned318buildbot # [ 0.414126] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned319buildbot # [ 0.414148] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned320buildbot # [ 0.414169] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned321buildbot # [ 0.414192] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned322buildbot # [ 0.414216] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned323buildbot # [ 0.414238] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned324buildbot # [ 0.414260] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned325buildbot # [ 0.414282] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned326buildbot # [ 0.414304] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned327buildbot # [ 0.414325] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned328buildbot # [ 0.414346] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned329buildbot # [ 0.414367] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned330buildbot # [ 0.414389] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned331buildbot # [ 0.414410] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned332buildbot # [ 0.414436] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]333buildbot # [ 0.458490] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]334buildbot # [ 0.458503] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]335buildbot # [ 0.459351] pci 0000:00:07.0: enabling device (0000 -> 0002)336buildbot # [ 0.481760] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)337buildbot # [ 0.488653] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)338buildbot # [ 0.494638] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)339buildbot # [ 0.496502] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)340buildbot # [ 0.498380] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)341buildbot # [ 0.508737] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)342buildbot # [ 0.510389] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)343buildbot # [ 0.520470] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)344buildbot # [ 0.527009] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)345buildbot # [ 0.528973] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)346buildbot # [ 0.538970] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)347buildbot # [ 0.547762] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled348buildbot # [ 0.550264] msm_serial: driver initialized349buildbot # [ 0.550412] SuperH (H)SCI(F) driver initialized350buildbot # [ 0.558567] STM32 USART driver initialized351buildbot # [ 0.586668] loop: module loaded352buildbot # [ 0.586831] virtio_blk virtio2: 1/0/0 default/read/poll queues353buildbot # [ 0.587559] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)354buildbot # [ 0.595011] megasas: 07.734.00.00-rc1355buildbot # [ 0.595672] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]356buildbot # [ 0.597542] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000357buildbot # [ 0.597567] Intel/Sharp Extended Query Table at 0x0031358buildbot # [ 0.599132] Using buffer write method359buildbot # [ 0.599190] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]360buildbot # [ 0.600967] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000361buildbot # [ 0.600989] Intel/Sharp Extended Query Table at 0x0031362buildbot # [ 0.618497] Using buffer write method363buildbot # [ 0.618524] Concatenating MTD devices:364buildbot # [ 0.618528] (0): "0.flash"365buildbot # [ 0.618532] (1): "0.flash"366buildbot # [ 0.618535] into device "0.flash"367buildbot # [ 0.845251] Freeing initrd memory: 26972K368buildbot # [ 0.851092] tun: Universal TUN/TAP device driver, 1.6369buildbot # [ 0.854728] thunder_xcv, ver 1.0370buildbot # [ 0.854761] thunder_bgx, ver 1.0371buildbot # [ 0.854799] nicpf, ver 1.0372buildbot # [ 0.855332] e1000: Intel(R) PRO/1000 Network Driver373buildbot # [ 0.855339] e1000: Copyright (c) 1999-2006 Intel Corporation.374buildbot # [ 0.855362] e1000e: Intel(R) PRO/1000 Network Driver375buildbot # [ 0.855370] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.376buildbot # [ 0.855397] igb: Intel(R) Gigabit Ethernet Network Driver377buildbot # [ 0.855403] igb: Copyright (c) 2007-2014 Intel Corporation.378buildbot # [ 0.855424] igbvf: Intel(R) Gigabit Virtual Function Network Driver379buildbot # [ 0.855430] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.380buildbot # [ 0.855555] sky2: driver version 1.30381buildbot # [ 0.857041] usbcore: registered new interface driver usb-storage382buildbot # [ 0.857157] usbcore: registered new interface driver usbserial_generic383buildbot # [ 0.857173] usbserial: USB Serial support registered for generic384buildbot # [ 0.857736] hv_vmbus: registering driver hyperv_keyboard385buildbot # [ 0.858937] ehci-pci 0000:00:07.0: EHCI Host Controller386buildbot # [ 0.858960] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1387buildbot # [ 0.859116] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000388buildbot # [ 0.870886] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00389buildbot # [ 0.871170] hub 1-0:1.0: USB hub found390buildbot # [ 0.871185] hub 1-0:1.0: 6 ports detected391buildbot # [ 0.874155] rtc-pl031 9010000.pl031: registered as rtc0392buildbot # [ 0.874186] rtc-pl031 9010000.pl031: setting system clock to 2026-09-20T07:27:39 UTC (1789889259)393buildbot # [ 0.876376] i2c_dev: i2c /dev entries driver394buildbot # [ 0.879905] sdhci: Secure Digital Host Controller Interface driver395buildbot # [ 0.879917] sdhci: Copyright(c) Pierre Ossman396buildbot # [ 0.880167] Synopsys Designware Multimedia Card Interface Driver397buildbot # [ 0.880522] sdhci-pltfm: SDHCI platform and OF driver helper398buildbot # [ 0.881972] hid: raw HID events driver (C) Jiri Kosina399buildbot # [ 0.882203] usbcore: registered new interface driver usbhid400buildbot # [ 0.882212] usbhid: USB HID core driver401buildbot # [ 0.887745] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available402buildbot # [ 0.889210] drop_monitor: Initializing network drop monitor service403buildbot # [ 0.889389] NET: Registered PF_INET6 protocol family404buildbot # [ 0.892371] Segment Routing with IPv6405buildbot # [ 0.892390] In-situ OAM (IOAM) with IPv6406buildbot # [ 0.892418] NET: Registered PF_PACKET protocol family407buildbot # [ 0.894082] 9pnet: Installing 9P2000 support408buildbot # [ 0.894127] Key type dns_resolver registered409buildbot # [ 0.901030] registered taskstats version 1410buildbot # [ 0.901175] Loading compiled-in X.509 certificates411buildbot # [ 0.909596] Demotion targets for Node 0: null412buildbot # [ 0.909699] Key type .fscrypt registered413buildbot # [ 0.909708] Key type fscrypt-provisioning registered414buildbot # [ 0.909800] ima: No TPM chip found, activating TPM-bypass!415buildbot # [ 0.909819] ima: Allocated hash algorithm: sha1416buildbot # [ 0.909841] ima: No architecture policies found417buildbot # [ 0.913804] input: gpio-keys as /devices/platform/gpio-keys/input/input0418buildbot # [ 0.931303] clk: Disabling unused clocks419buildbot # [ 0.931325] PM: genpd: Disabling unused power domains420buildbot # [ 0.935396] Freeing unused kernel memory: 4736K421buildbot # [ 0.935603] Run /init as init process422buildbot # [ 0.952657] systemd[1]: Successfully made /usr/ read-only.423buildbot # [ 1.118513] usb 1-1: new high-speed USB device number 2 using ehci-pci424buildbot # [ 1.271080] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1425buildbot # [ 1.287410] 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)426buildbot # [ 1.299467] systemd[1]: Detected virtualization qemu.427buildbot # [ 1.301559] systemd[1]: Detected architecture arm64.428buildbot # [ 1.303522] systemd[1]: Running in initrd.429buildbot # [ 1.306095] systemd[1]: Initializing machine ID from random generator.430buildbot # [ 1.309085] systemd[1]: Hostname set to <buildbot>.431buildbot # [ 1.354687] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0432buildbot # [ 1.474484] usb 1-2: new high-speed USB device number 3 using ehci-pci433buildbot # [ 1.611135] systemd[1]: bpf-restrict-fs: LSM BPF program attached434buildbot # [ 1.635358] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2435buildbot # [ 1.642721] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0436buildbot # [ 1.723968] systemd[1]: Queued start job for default target Initrd Default Target.437buildbot # [ 1.732897] systemd[1]: Created slice Slice /system/modprobe.438buildbot # [ 1.734066] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.439buildbot # [ 1.735452] systemd[1]: Expecting device /dev/disk/by-label/nixos...440buildbot # [ 1.736492] systemd[1]: Reached target Path Units.441buildbot # [ 1.737302] systemd[1]: Reached target Slice Units.442buildbot # [ 1.738119] systemd[1]: Reached target Swaps.443buildbot # [ 1.738916] systemd[1]: Reached target Timer Units.444buildbot # [ 1.739925] systemd[1]: Listening on D-Bus System Message Bus Socket.445buildbot # [ 1.741245] systemd[1]: Listening on Journal Socket (/dev/log).446buildbot # [ 1.742369] systemd[1]: Listening on Journal Sockets.447buildbot # [ 1.743467] systemd[1]: Listening on udev Control Socket.448buildbot # [ 1.743591] systemd[1]: Listening on udev Kernel Socket.449buildbot # [ 1.743614] systemd[1]: Reached target Socket Units.450buildbot # [ 1.747707] systemd[1]: Starting Create List of Static Device Nodes...451buildbot # [ 1.748839] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs452buildbot # [ 1.758593] systemd[1]: Mounting Kernel Configuration File System...453buildbot # [ 1.766745] systemd[1]: Starting Journal Service...454buildbot # [ 1.790856] systemd[1]: Starting Load Kernel Modules...455buildbot # [ 1.790979] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os456buildbot # [ 1.812920] systemd-journald[72]: Collecting audit messages is disabled.457buildbot # [ 1.813071] systemd[1]: Starting Coldplug All udev Devices...458buildbot # [ 1.838597] systemd[1]: Finished Create List of Static Device Nodes.459buildbot # [ 1.839375] systemd[1]: Mounted Kernel Configuration File System.460buildbot # [ 1.846779] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...461buildbot # [ 1.872538] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.462buildbot # [ 1.883071] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.463buildbot # [ 1.886819] systemd[1]: Starting Create Static Device Nodes in /dev...464buildbot # [ 1.894700] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev465buildbot # [ 1.903745] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0466buildbot # [ 1.903987] [drm] features: -virgl +edid -resource_blob -host_visible467buildbot # [ 1.903997] [drm] features: -context_init468buildbot # [ 1.904671] [drm] number of scanouts: 1469buildbot # [ 1.904689] [drm] number of cap sets: 0470buildbot # [ 1.931094] systemd[1]: Finished Create Static Device Nodes in /dev.471buildbot # [ 1.931445] systemd[1]: Reached target Preparation for Local File Systems.472buildbot # [ 1.931475] systemd[1]: Reached target Local File Systems.473buildbot # [ 1.935353] systemd[1]: Starting Rule-based Manager for Device Events and Files...474buildbot # [ 1.938843] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic475buildbot # [ 1.938862] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0476buildbot # [ 1.970707] Console: switching to colour frame buffer device 160x50477buildbot # [ 1.977225] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device478buildbot # [ 1.995801] systemd[1]: Finished Load Kernel Modules.479buildbot # [ 2.007237] systemd[1]: Starting Apply Kernel Variables...480buildbot # [ 2.009116] systemd-modules-load[74]: Inserted module 'dm_mod'481buildbot # [ 2.024750] systemd[1]: Started Journal Service.482buildbot # [ 2.013503] systemd-modules-load[74]: Module 'virtio_balloon' is built in483buildbot # [ 2.014566] systemd-modules-load[74]: Module 'virtio_console' is built in484buildbot # [ 2.015640] systemd-modules-load[74]: Inserted module 'virtio_gpu'485buildbot # [ 2.026590] systemd-modules-load[74]: Module 'virtio_rng' is built in486buildbot # [ 2.036223] systemd[1]: Starting Create System Files and Directories...487buildbot # [ 2.038661] systemd-udevd[79]: Using default interface naming scheme 'v261'.488buildbot # [ 2.068965] systemd[1]: Finished Apply Kernel Variables.489buildbot # [ 2.087925] systemd[1]: Finished Create System Files and Directories.490buildbot # [ 2.101034] systemd[1]: Started Rule-based Manager for Device Events and Files.491buildbot # [ 2.156097] systemd[1]: Starting Virtual Console Setup...492buildbot # [ 2.204444] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.493buildbot # [ 2.207830] systemd[1]: Finished Virtual Console Setup.494buildbot # [ 2.803770] systemd[1]: Finished Coldplug All udev Devices.495buildbot # [ 2.804858] systemd[1]: Reached target System Initialization.496buildbot # [ 2.805676] systemd[1]: Reached target Basic System.497buildbot # [ 2.943768] (udev-worker)[91]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.498buildbot # [ 2.981389] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.499buildbot # [ 2.989057] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.500buildbot # [ 3.062780] systemd[1]: Found device /dev/disk/by-label/nixos.501buildbot # [ 3.068489] systemd[1]: Reached target Initrd Root Device.502buildbot # [ 3.076089] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...503buildbot # [ 3.125211] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks504buildbot # [ 3.130196] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.505buildbot # [ 3.136142] systemd[1]: Mounting /sysroot...506buildbot # [ 3.186205] EXT4-fs (vda): mounted filesystem ee4bdefa-bc0f-4d5f-b460-883a3c3b1734 r/w with ordered data mode. Quota mode: none.507buildbot # [ 3.178115] systemd[1]: Mounted /sysroot.508buildbot # [ 3.181929] systemd[1]: Reached target Initrd Root File System.509buildbot # [ 3.184347] systemd[1]: Starting Mountpoints Configured in the Real Root...510buildbot # [ 3.212123] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.511buildbot # [ 3.220113] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...512buildbot # [ 3.222266] systemd[1]: Reloading...513buildbot # [ 3.431576] systemd[1]: Reloading finished in 212 ms.514buildbot # [ 3.458203] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...515buildbot # [ 3.463467] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...516buildbot # [ 3.466989] systemd[1]: initrd-parse-etc.service: Deactivated successfully.517buildbot # [ 3.470075] systemd[1]: Finished Mountpoints Configured in the Real Root.518buildbot # [ 3.471671] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.519buildbot # [ 3.815117] systemd[1]: Mounting /sysroot/nix/.ro-store...520buildbot # [ 3.831006] systemd[1]: Mounting /sysroot/nix/.rw-store...521buildbot # [ 3.835470] systemd[1]: Mounting /sysroot/run...522buildbot # [ 3.854374] systemd[1]: Mounting /sysroot/tmp/shared...523buildbot # [ 3.895227] systemd[1]: Mounting /sysroot/tmp/xchg...524buildbot # [ 3.896952] systemd[1]: Mounted /sysroot/nix/.rw-store.525buildbot # [ 3.927202] fuse: init (API version 7.45)526buildbot # [ 3.937208] virtiofs virtio6: discovered new tag: nix-store527buildbot # [ 3.937977] virtiofs virtio6: virtio_fs_setup_dax: No cache capability528buildbot # [ 3.932112] systemd[1]: Starting rw-sysroot-nix-store.service...529buildbot # [ 3.952146] virtiofs virtio7: discovered new tag: shared530buildbot # [ 3.952893] virtiofs virtio7: virtio_fs_setup_dax: No cache capability531buildbot # [ 3.956930] virtiofs virtio8: discovered new tag: xchg532buildbot # [ 3.957643] virtiofs virtio8: virtio_fs_setup_dax: No cache capability533buildbot # [ 3.983763] systemd[1]: Mounted /sysroot/nix/.ro-store.534buildbot # [ 3.985912] systemd[1]: Mounted /sysroot/run.535buildbot # [ 3.987391] systemd[1]: Mounted /sysroot/tmp/shared.536buildbot # [ 3.996842] systemd[1]: Mounted /sysroot/tmp/xchg.537buildbot # [ 3.999371] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.538buildbot # [ 4.004734] systemd[1]: Finished rw-sysroot-nix-store.service.539buildbot # [ 4.009542] systemd[1]: Mounting /sysroot/nix/store...540buildbot # [ 4.049630] systemd[1]: Mounted /sysroot/nix/store.541buildbot # [ 4.051475] systemd[1]: Reached target Initrd File Systems.542buildbot # [ 4.056132] systemd[1]: Starting Find NixOS closure...543buildbot # [ 4.061448] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...544buildbot # [ 4.096647] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.545buildbot # [ 4.106316] systemd[1]: Finished Find NixOS closure.546buildbot # [ 4.107888] systemd[1]: Reached target Initrd Default Target.547buildbot # [ 4.112998] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...548buildbot # [ 4.138111] systemd[1]: Stopped target Initrd Default Target.549buildbot # [ 4.141781] systemd[1]: Stopped target Basic System.550buildbot # [ 4.144186] systemd[1]: Stopped target Initrd Root Device.551buildbot # [ 4.146474] systemd[1]: Stopped target Path Units.552buildbot # [ 4.148841] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.553buildbot # [ 4.152147] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.554buildbot # [ 4.156784] systemd[1]: Stopped target Slice Units.555buildbot # [ 4.157554] systemd[1]: Stopped target Socket Units.556buildbot # [ 4.161479] systemd[1]: Stopped target System Initialization.557buildbot # [ 4.163108] systemd[1]: Stopped target Swaps.558buildbot # [ 4.166429] systemd[1]: Stopped target Timer Units.559buildbot # [ 4.169396] systemd[1]: dbus.socket: Deactivated successfully.560buildbot # [ 4.170279] systemd[1]: Closed D-Bus System Message Bus Socket.561buildbot # [ 4.173574] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.562buildbot # [ 4.181492] systemd[1]: Stopped Find NixOS closure.563buildbot # [ 4.183668] systemd[1]: Starting rw-sysroot-nix-store.service...564buildbot # [ 4.187345] systemd[1]: systemd-sysctl.service: Deactivated successfully.565buildbot # [ 4.192980] systemd[1]: Stopped Apply Kernel Variables.566buildbot # [ 4.193748] systemd[1]: systemd-modules-load.service: Deactivated successfully.567buildbot # [ 4.194742] systemd[1]: Stopped Load Kernel Modules.568buildbot # [ 4.195434] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.569buildbot # [ 4.202495] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.570buildbot # [ 4.204312] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.571buildbot # [ 4.206456] systemd[1]: Stopped Create System Files and Directories.572buildbot # [ 4.207362] systemd[1]: Stopped target Local File Systems.573buildbot # [ 4.208364] systemd[1]: Stopped target Preparation for Local File Systems.574buildbot # [ 4.209291] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.575buildbot # [ 4.210262] systemd[1]: Stopped Coldplug All udev Devices.576buildbot # [ 4.211059] systemd[1]: Stopping Rule-based Manager for Device Events and Files...577buildbot # [ 4.216152] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.578buildbot # [ 4.220543] systemd[1]: Stopped Virtual Console Setup.579buildbot # [ 4.221332] systemd[1]: initrd-cleanup.service: Deactivated successfully.580buildbot # [ 4.222254] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.581buildbot # [ 4.224180] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.582buildbot # [ 4.225816] systemd[1]: Finished rw-sysroot-nix-store.service.583buildbot # [ 4.226737] systemd[1]: systemd-udevd.service: Deactivated successfully.584buildbot # [ 4.227662] systemd[1]: Stopped Rule-based Manager for Device Events and Files.585buildbot # [ 4.228765] systemd[1]: systemd-udevd.service: Consumed 1.111s CPU time over 2.293s wall clock time, 17.6M memory peak.586buildbot # [ 4.230164] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.587buildbot # [ 4.231142] systemd[1]: Closed udev Control Socket.588buildbot # [ 4.231841] systemd[1]: Starting Cleanup udev Database...589buildbot # [ 4.236223] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.590buildbot # [ 4.237867] systemd[1]: Stopped Create Static Device Nodes in /dev.591buildbot # [ 4.240283] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.592buildbot # [ 4.244206] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.593buildbot # [ 4.245225] systemd[1]: kmod-static-nodes.service: Deactivated successfully.594buildbot # [ 4.248152] systemd[1]: Stopped Create List of Static Device Nodes.595buildbot # [ 4.270269] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.596buildbot # [ 4.272469] systemd[1]: Finished Cleanup udev Database.597buildbot # [ 4.274483] systemd[1]: Reached target Switch Root.598buildbot # [ 4.275599] systemd[1]: Starting NixOS Activation...599buildbot # [ 4.355099] initrd-nixos-activation-start[166]: booting system configuration /nix/store/zqabzgl6wbzk2pn5bshxydhi02k7qs81-nixos-system-buildbot-test600buildbot # [ 4.386346] initrd-nixos-activation-start[166]: running activation script...601buildbot # [ 4.610209] initrd-nixos-activation-start[189]: setting up /etc...602buildbot # [ 4.729359] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.603buildbot # [ 4.732112] systemd[1]: Finished NixOS Activation.604buildbot # [ 4.733434] systemd[1]: Starting Switch Root...605buildbot # [ 4.755058] systemd[1]: Switching root.606buildbot # [ 4.940354] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).607buildbot # [ 5.462616] 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)608buildbot # [ 5.475084] systemd[1]: Detected virtualization qemu.609buildbot # [ 5.478102] systemd[1]: Detected architecture arm64.610buildbot # [ 5.481985] systemd[1]: Detected first boot.611buildbot # [ 5.487659] systemd[1]: Initializing machine ID from random generator.612buildbot # [ 5.806429] systemd[1]: bpf-restrict-fs: LSM BPF program attached613buildbot # [ 6.006177] NET: Registered PF_VSOCK protocol family614buildbot # [ 6.013250] Guest personality initialized and is inactive615buildbot # [ 6.015359] VMCI host device registered (name=vmci, major=10, minor=261)616buildbot # [ 6.015380] Initialized host personality617buildbot # [ 6.076024] systemd[1]: Applying preset policy.618buildbot # [ 6.390700] systemd[1]: Populated /etc with preset unit settings.619buildbot # [ 6.620596] systemd[1]: initrd-switch-root.service: Deactivated successfully.620buildbot # [ 6.622246] systemd[1]: Stopped initrd-switch-root.service.621buildbot # [ 6.627066] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.622buildbot # [ 6.629917] systemd[1]: Created slice Slice /system/getty.623buildbot # [ 6.632021] systemd[1]: Created slice User and Session Slice.624buildbot # [ 6.634169] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.625buildbot # [ 6.635663] systemd[1]: Started Forward Password Requests to Wall Directory Watch.626buildbot # [ 6.637437] systemd[1]: Expecting device /dev/hvc0...627buildbot # [ 6.639041] systemd[1]: Expecting device /dev/ttyAMA0...628buildbot # [ 6.640622] systemd[1]: Reached target Local Encrypted Volumes.629buildbot # [ 6.642281] systemd[1]: Stopped target initrd-fs.target.630buildbot # [ 6.643854] systemd[1]: Stopped target initrd-root-fs.target.631buildbot # [ 6.645475] systemd[1]: Stopped target initrd-switch-root.target.632buildbot # [ 6.647224] systemd[1]: Reached target Virtual Machines and Containers.633buildbot # [ 6.649745] systemd[1]: Reached target Path Units.634buildbot # [ 6.651639] systemd[1]: Reached target Remote File Systems.635buildbot # [ 6.653557] systemd[1]: Reached target Slice Units.636buildbot # [ 6.655480] systemd[1]: Reached target Swaps.637buildbot # [ 6.658675] systemd[1]: Listening on Query the User Interactively for a Password.638buildbot # [ 6.661606] systemd[1]: Listening on Process Core Dump Socket.639buildbot # [ 6.663822] systemd[1]: Listening on Credential Encryption/Decryption.640buildbot # [ 6.666143] systemd[1]: Listening on Factory Reset Management.641buildbot # [ 6.667366] systemd[1]: Listening on Hostname Service Socket.642buildbot # [ 6.671363] systemd[1]: Starting Journal Log Access Socket...643buildbot # [ 6.673489] systemd[1]: Listening on Journal Audit Socket.644buildbot # [ 6.676162] systemd[1]: Listening on Console Output Muting Service Socket.645buildbot # [ 6.677666] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.646buildbot # [ 6.679548] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os647buildbot # [ 6.682795] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki648buildbot # [ 6.688279] systemd[1]: Listening on Disk Repartitioning Service Socket.649buildbot # [ 6.689610] systemd[1]: Listening on udev Control Socket.650buildbot # [ 6.691226] systemd[1]: Listening on udev Varlink Socket.651buildbot # [ 6.695379] systemd[1]: Mounting Huge Pages File System...652buildbot # [ 6.703560] systemd[1]: Mounting POSIX Message Queue File System...653buildbot # [ 6.712644] systemd[1]: Mounting Kernel Debug File System...654buildbot # [ 6.727213] systemd[1]: Mounting Kernel Trace File System...655buildbot # [ 6.739347] systemd[1]: Starting Create List of Static Device Nodes...656buildbot # [ 6.741545] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs657buildbot # [ 6.763174] systemd[1]: Mounting Kernel Configuration File System...658buildbot # [ 6.765354] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm659buildbot # [ 6.767986] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore660buildbot # [ 6.769784] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse661buildbot # [ 6.789848] systemd[1]: Mounting FUSE Control File System...662buildbot # [ 6.792156] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67663buildbot # [ 6.818926] systemd[1]: Starting Journal Service...664buildbot # [ 6.827549] systemd[1]: Starting Load Kernel Modules...665buildbot # [ 6.847223] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...666buildbot # [ 6.855813] systemd[1]: Starting Remount Root and Kernel File Systems...667buildbot # [ 6.856173] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os668buildbot # [ 6.884304] systemd[1]: Starting Coldplug All udev Devices...669buildbot # [ 6.895166] systemd[1]: Listening on Journal Log Access Socket.670buildbot # [ 6.898798] systemd[1]: Mounted Huge Pages File System.671buildbot # [ 6.907828] systemd-journald[261]: Collecting audit messages is enabled.672buildbot # [ 6.912058] systemd[1]: Mounted POSIX Message Queue File System.673buildbot # [ 6.917478] systemd[1]: Mounted Kernel Debug File System.674buildbot # [ 6.905891] systemd[1]: Queued start job for default target Multi-User System.675buildbot # [ 6.925823] systemd[1]: Started Journal Service.676buildbot # [ 6.913484] systemd[1]: systemd-journald.service: Deactivated successfully.677buildbot # [ 6.924764] systemd[1]: Mounted Kernel Trace File System.678buildbot # [ 6.927767] systemd[1]: Finished Create List of Static Device Nodes.679buildbot # [ 6.930786] systemd[1]: Mounted Kernel Configuration File System.680buildbot # [ 6.931962] systemd-modules-load[262]: Module 'atkbd' is built in681buildbot # [ 6.947559] EXT4-fs (vda): re-mounted ee4bdefa-bc0f-4d5f-b460-883a3c3b1734.682buildbot # [ 6.942087] systemd-modules-load[262]: Module 'loop' is built in683buildbot # [ 6.960157] systemd[1]: Finished Remount Root and Kernel File Systems.684buildbot # [ 6.961130] systemd[1]: Listening on Disk Image Download Service Socket.685buildbot # [ 6.962448] systemd-modules-load[262]: Inserted module 'tls'686buildbot # [ 6.975650] systemd[1]: Starting Flush Journal to Persistent Storage...687buildbot # [ 6.977316] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore688buildbot # [ 6.984299] systemd[1]: Starting Load/Save OS Random Seed...689buildbot # [ 6.987965] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...690buildbot # [ 6.989063] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os691buildbot # [ 6.990229] systemd[1]: Mounted FUSE Control File System.692buildbot # [ 6.994481] systemd[1]: Finished Load Kernel Modules.693buildbot # [ 7.007688] systemd[1]: Starting Apply Kernel Variables...694buildbot # [ 7.026915] systemd-oomd[263]: No swap; memory pressure usage will be degraded695buildbot # [ 7.049950] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.696buildbot # [ 7.096173] systemd-journald[261]: Received client request to flush runtime journal.697buildbot # [ 7.136253] systemd[1]: Finished Load/Save OS Random Seed.698buildbot # [ 7.137212] systemd[1]: Reached target First Boot Complete.699buildbot # [ 7.138003] systemd[1]: Finished Flush Journal to Persistent Storage.700buildbot # [ 7.217029] systemd[1]: Finished Apply Kernel Variables.701buildbot # [ 7.446185] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.702buildbot # [ 7.448174] systemd[1]: Starting Create Static Device Nodes in /dev...703buildbot # [ 7.718487] systemd[1]: Finished Coldplug All udev Devices.704buildbot # [ 7.727673] systemd[1]: Finished Create Static Device Nodes in /dev.705buildbot # [ 7.730248] systemd[1]: Reached target Preparation for Local File Systems.706buildbot # [ 7.734517] systemd[1]: Mounting /run/wrappers...707buildbot # [ 7.736475] systemd[1]: Starting Rule-based Manager for Device Events and Files...708buildbot # [ 7.778442] systemd[1]: Mounted /run/wrappers.709buildbot # [ 7.779179] systemd[1]: Reached target Local File Systems.710buildbot # [ 7.785348] systemd[1]: Listening on Boot Loader Control Service Socket.711buildbot # [ 7.788168] systemd[1]: Starting register-nix-paths.service...712buildbot # [ 7.795260] systemd[1]: Starting Create SUID/SGID Wrappers...713buildbot # [ 7.796415] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.714buildbot # [ 7.809386] systemd[1]: Starting Save Transient machine-id to Disk...715buildbot # [ 7.818894] systemd[1]: Starting Create System Files and Directories...716buildbot # [ 7.894624] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.717buildbot # [ 7.904778] systemd[1]: Finished Save Transient machine-id to Disk.718buildbot # [ 7.945061] systemd-udevd[293]: Using default interface naming scheme 'v261'.719buildbot # [ 7.978405] systemd[1]: Finished Create System Files and Directories.720buildbot # [ 7.982287] systemd[1]: Starting Rebuild Journal Catalog...721buildbot # [ 7.989592] systemd[1]: Starting Record System Boot/Shutdown in UTMP...722buildbot # [ 8.088353] systemd[1]: Finished Record System Boot/Shutdown in UTMP.723buildbot # [ 8.108937] systemd[1]: Started Rule-based Manager for Device Events and Files.724buildbot # [ 8.112438] systemd[1]: Finished Rebuild Journal Catalog.725buildbot # [ 8.122087] systemd[1]: Starting Update is Completed...726buildbot # [ 8.192334] systemd[1]: Finished Update is Completed.727buildbot # [ 8.707838] systemd[1]: Finished register-nix-paths.service.728buildbot # [ 8.730409] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.729buildbot # [ 8.732553] systemd[1]: Finished Create SUID/SGID Wrappers.730buildbot # [ 8.735608] systemd[1]: Reached target System Initialization.731buildbot # [ 8.740394] systemd[1]: Started Discard unused filesystem blocks once a week.732buildbot # [ 8.741412] systemd[1]: Started Daily Cleanup of Temporary Directories.733buildbot # [ 8.742332] systemd[1]: Reached target Timer Units.734buildbot # [ 8.743062] systemd[1]: Listening on D-Bus System Message Bus Socket.735buildbot # [ 8.743949] systemd[1]: Listening on Nix Daemon Socket.736buildbot # [ 8.754518] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).737buildbot # [ 8.760216] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.738buildbot # [ 8.762265] systemd[1]: Reached target Socket Units.739buildbot # [ 8.764491] systemd[1]: Reached target Basic System.740buildbot # [ 8.769400] systemd[1]: Starting Import lastlog data into lastlog2 database...741buildbot # [ 8.771760] systemd[1]: Starting Name Service Cache Daemon (nsncd)...742buildbot # [ 8.778448] systemd[1]: Starting Post-Boot Actions...743buildbot # [ 8.792098] systemd[1]: Started Reset console on configuration changes.744buildbot # [ 8.807433] systemd[1]: Starting resolvconf update...745buildbot # [ 8.808431] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.746buildbot # [ 8.836102] systemd[1]: Starting D-Bus System Message Bus...747buildbot # [ 8.883184] systemd[1]: Finished Post-Boot Actions.748buildbot # [ 8.886055] nsncd[379]: Sep 20 07:27:47.525 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"749buildbot # [ 8.904320] systemd[1]: Started Name Service Cache Daemon (nsncd).750buildbot # [ 8.905538] systemd[1]: Reached target Host and Network Name Lookups.751buildbot # [ 8.906592] systemd[1]: Reached target User and Group Name Lookups.752buildbot # [ 8.926444] systemd[1]: Starting User Login Management...753buildbot # [ 8.967968] systemd[1]: Finished Import lastlog data into lastlog2 database.754buildbot # [ 9.018743] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3755buildbot # [ 9.081797] dbus-broker-launch[385]: Looking up NSS user entry for 'systemd-timesync'...756buildbot # [ 9.088715] systemd-logind[400]: New seat seat0.757buildbot # [ 9.102591] systemd[1]: Started User Login Management.758buildbot # [ 9.105719] dbus-broker-launch[385]: NSS returned no entry for 'systemd-timesync'759buildbot # [ 9.108908] dbus-broker-launch[385]: Invalid user-name in /nix/store/h575a3ycikmkyiz2l7czcr2yfzngd7ln-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"760buildbot # [ 9.116687] systemd[1]: Starting linger-users.service...761buildbot # [ 9.171964] systemd[1]: Stopped target Host and Network Name Lookups.762buildbot # [ 9.177980] systemd[1]: Stopping Host and Network Name Lookups...763buildbot # [ 9.179036] systemd[1]: Stopped target User and Group Name Lookups.764buildbot # [ 9.188296] systemd[1]: Stopping User and Group Name Lookups...765buildbot # [ 9.189211] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...766buildbot # [ 9.190100] systemd[1]: nscd.service: Deactivated successfully.767buildbot # [ 9.194490] systemd[1]: Stopped Name Service Cache Daemon (nsncd).768buildbot # [ 9.213334] systemd[1]: Starting Name Service Cache Daemon (nsncd)...769buildbot # [ 9.229506] systemd[1]: Started D-Bus System Message Bus.770buildbot # [ 9.259980] systemd[1]: linger-users.service: Deactivated successfully.771buildbot # [ 9.261065] systemd[1]: Finished linger-users.service.772buildbot # [ 9.272743] dbus-broker-launch[385]: Ready773buildbot # [ 9.320284] nsncd[450]: Sep 20 07:27:47.958 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"774buildbot # [ 9.328314] systemd[1]: Started Name Service Cache Daemon (nsncd).775buildbot # [ 9.329767] systemd[1]: Reached target Host and Network Name Lookups.776buildbot # [ 9.331381] systemd[1]: Reached target User and Group Name Lookups.777buildbot # [ 9.397351] systemd[1]: Finished resolvconf update.778buildbot # [ 9.400405] systemd[1]: Reached target Preparation for Network.779buildbot # [ 9.407002] systemd[1]: Starting DHCP Client...780buildbot # [ 9.414171] systemd[1]: Starting Extra networking commands....781buildbot # [ 9.615670] dhcpcd[482]: dhcpcd-10.3.2 starting782buildbot # [ 9.631148] dhcpcd[511]: dev: loaded udev783buildbot # [ 9.636649] dhcpcd[511]: no valid interfaces found784buildbot # [ 9.639809] dhcpcd[511]: no valid interfaces found785buildbot # [ 9.666054] systemd[1]: Starting Virtual Console Setup...786buildbot # [ 9.697274] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.787buildbot # [ 9.701051] systemd[1]: Stopped Virtual Console Setup.788buildbot # [ 9.719815] systemd[1]: Starting Virtual Console Setup...789buildbot # [ 9.738036] systemd[1]: Finished Extra networking commands..790buildbot # [ 9.750730] systemd[1]: Reached target Network.791buildbot # [ 9.755087] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs792buildbot # [ 9.763773] systemd[1]: Starting Nginx Web Server...793buildbot # [ 9.768964] systemd[1]: Starting PostgreSQL Server...794buildbot # [ 9.784163] systemd[1]: Starting SSH Daemon...795buildbot # [ 9.795490] systemd[1]: Starting Permit User Sessions...796buildbot # [ 9.903772] systemd[1]: Finished Permit User Sessions.797buildbot # [ 9.917363] systemd[1]: Started Getty on tty1.798buildbot # [ 9.918091] systemd[1]: Reached target Login Prompts.799buildbot # [ 9.973998] sshd[540]: Server listening on 0.0.0.0 port 22.800buildbot # [ 9.974925] sshd[540]: Server listening on :: port 22.801buildbot # [ 9.983678] systemd[1]: Started SSH Daemon.802buildbot # [ 9.986854] systemd[1]: Starting Setup git test repository...803buildbot # [ 10.174146] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.804buildbot # [ 10.188495] setup-git-repo-start[558]: hint: Using 'master' as the name for the initial branch. This default branch name805buildbot # [ 10.190012] setup-git-repo-start[558]: hint: will change to "main" in Git 3.0. To configure the initial branch name806buildbot # [ 10.191380] setup-git-repo-start[558]: hint: to use in all of your new repositories, which will suppress this warning,807buildbot # [ 10.204432] setup-git-repo-start[558]: hint: call:808buildbot # [ 10.205142] setup-git-repo-start[558]: hint:809buildbot # [ 10.205743] setup-git-repo-start[558]: hint: git config --global init.defaultBranch <name>810buildbot # [ 10.206860] setup-git-repo-start[558]: hint:811buildbot # [ 10.207470] setup-git-repo-start[558]: hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and812buildbot # [ 10.223824] setup-git-repo-start[558]: hint: 'development'. The just-created branch can be renamed via this command:813buildbot # [ 10.233748] setup-git-repo-start[558]: hint:814buildbot # [ 10.234414] setup-git-repo-start[558]: hint: git branch -m <name>815buildbot # [ 10.235242] setup-git-repo-start[558]: hint:816buildbot # [ 10.235864] setup-git-repo-start[558]: hint: Disable this message with "git config set advice.defaultBranchName false"817buildbot # [ 10.249897] setup-git-repo-start[558]: Initialized empty Git repository in /srv/repos/test-flake.git/818buildbot # [ 10.251331] nginx-pre-start[551]: nginx: the configuration file /nix/store/l444n3yfqjgcf1szh5zz6jz6gci46g29-nginx.conf syntax is ok819buildbot # [ 10.263004] nginx-pre-start[551]: nginx: configuration file /nix/store/l444n3yfqjgcf1szh5zz6jz6gci46g29-nginx.conf test is successful820buildbot # [ 10.267991] systemd[1]: Started Nginx Web Server.821buildbot # [ 10.270057] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.822buildbot # [ 10.282985] systemd[1]: Started backdoor.service.823buildbot # [ 10.304500] setup-git-repo-start[564]: hint: Using 'master' as the name for the initial branch. This default branch name824buildbot # [ 10.305960] setup-git-repo-start[564]: hint: will change to "main" in Git 3.0. To configure the initial branch name825buildbot # [ 10.307303] setup-git-repo-start[564]: hint: to use in all of your new repositories, which will suppress this warning,826buildbot # [ 10.321758] setup-git-repo-start[564]: hint: call:827buildbot # [ 10.322470] setup-git-repo-start[564]: hint:828buildbot # [ 10.323070] setup-git-repo-start[564]: hint: git config --global init.defaultBranch <name>829buildbot # [ 10.333823] setup-git-repo-start[564]: hint:830buildbot # [ 10.334481] setup-git-repo-start[564]: hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and831buildbot # [ 10.335745] setup-git-repo-start[564]: hint: 'development'. The just-created branch can be renamed via this command:832buildbot # [ 10.351880] setup-git-repo-start[564]: hint:833buildbot # [ 10.356489] setup-git-repo-start[564]: hint: git branch -m <name>834buildbot # [ 10.357368] setup-git-repo-start[564]: hint:835buildbot # [ 10.357961] setup-git-repo-start[564]: hint: Disable this message with "git config set advice.defaultBranchName false"836buildbot # [ 10.359317] setup-git-repo-start[564]: Initialized empty Git repository in /tmp/test-flake/.git/837buildbot # [ 10.376129] postgresql-pre-start[554]: The files belonging to this database system will be owned by user "postgres".838buildbot # [ 10.377543] postgresql-pre-start[554]: This user must also own the server process.839buildbot # [ 10.378746] postgresql-pre-start[554]: The database cluster will be initialized with locale "en_US.UTF-8".840buildbot # [ 10.379974] postgresql-pre-start[554]: The default database encoding has accordingly been set to "UTF8".841buildbot # [ 10.404324] postgresql-pre-start[554]: The default text search configuration will be set to "english".842buildbot # [ 10.405587] postgresql-pre-start[554]: Data page checksums are enabled.843buildbot # [ 10.406477] postgresql-pre-start[554]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok844buildbot # [ 10.407739] postgresql-pre-start[554]: creating subdirectories ... ok845buildbot # [ 10.426377] postgresql-pre-start[554]: selecting dynamic shared memory implementation ... posix846buildbot # connecting to host...847buildbot # [ 10.509673] 8021q: 802.1Q VLAN Support v1.8848buildbot # [ 10.533638] (udev-worker)[350]: Network interface NamePolicy= disabled on kernel command line.849buildbot # [ 10.540646] (udev-worker)[347]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.850buildbot # [ 10.552816] (udev-worker)[347]: Network interface NamePolicy= disabled on kernel command line.851buildbot: Guest shell says: b'Spawning backdoor root shell...\n'852buildbot: connected to guest root shell853buildbot: (connecting took 10.92 seconds)854buildbot: (finished: waiting for the VM to finish booting, in 11.39 seconds)855buildbot # [ 10.669925] setup-git-repo-start[572]: warning: you don't have Internet access; disabling some network-dependent features856buildbot # [ 10.681614] postgresql-pre-start[554]: selecting default "max_connections" ... 100857buildbot # [ 10.779052] cfg80211: Loading compiled-in X.509 certificates for regulatory database858buildbot: (finished: waiting for unit sshd.service, in 11.60 seconds)859buildbot: waiting for unit setup-git-repo.service860buildbot # [ 10.818671] setup-git-repo-start[593]: [master (root-commit) abab4eb] Initial commit861buildbot # [ 10.819799] setup-git-repo-start[593]: 1 file changed, 39 insertions(+)862buildbot # [ 10.829432] setup-git-repo-start[593]: create mode 100644 flake.nix863buildbot # [ 10.830591] systemd-vconsole-setup[535]: Configuration of first virtual console was skipped, ignoring remaining ones.864buildbot # [ 10.846314] systemd[1]: Finished Virtual Console Setup.865buildbot # [ 10.870165] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'866buildbot # [ 10.871542] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'867buildbot # [ 10.876559] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2868buildbot # [ 10.876898] cfg80211: failed to load regulatory.db869buildbot # [ 10.949925] postgresql-pre-start[554]: selecting default "shared_buffers" ... 128MB870buildbot # [ 10.971904] systemd[1]: Condition check resulted in Virtio network device being skipped.871buildbot # [ 10.977901] systemd[1]: Starting Address configuration of eth1...872buildbot # [ 11.005660] setup-git-repo-start[611]: To /srv/repos/test-flake.git873buildbot # [ 11.012426] setup-git-repo-start[611]: * [new branch] master -> master874buildbot # [ 11.018794] setup-git-repo-start[611]: branch 'master' set up to track 'origin/master'.875buildbot # [ 11.135023] 8021q: adding VLAN 0 to HW filter on device eth1876buildbot # [ 11.167165] 8021q: adding VLAN 0 to HW filter on device eth0877buildbot # [ 11.154622] dhcpcd[511]: eth0: waiting for carrier878buildbot # [ 11.157631] dhcpcd[511]: eth0: carrier acquired879buildbot # [ 11.161575] network-addresses-eth1-start[620]: adding address 192.168.1.1/24... done880buildbot # [ 11.187005] dhcpcd[511]: DUID 00:01:00:01:32:42:47:75:52:54:00:12:34:56881buildbot # [ 11.187963] dhcpcd[511]: eth0: IAID 00:12:34:56882buildbot # [ 11.191342] dhcpcd[511]: eth0: adding address fe80::5054:ff:fe12:3456883buildbot # [ 11.196088] network-addresses-eth1-start[620]: adding address 2001:db8:1::1/64... done884buildbot # [ 11.231821] systemd[1]: Finished Address configuration of eth1.885buildbot # [ 11.262066] systemd-logind[400]: Watching system buttons on /dev/input/event0 (gpio-keys)886buildbot # [ 11.304899] setup-git-repo-start[624]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 328 ms (attempt 1/5)887buildbot # [ 11.393678] mousedev: PS/2 mouse device common for all mice888buildbot # [ 11.425119] systemd-logind[400]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)889buildbot # [ 11.633447] setup-git-repo-start[624]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 588 ms (attempt 2/5)890buildbot # [ 12.187097] dhcpcd[511]: eth0: soliciting a DHCP lease891buildbot # [ 12.192494] dhcpcd[511]: eth0: offered 10.0.2.15 from 10.0.2.2892buildbot # [ 12.200214] dhcpcd[511]: eth0: probing address 10.0.2.15/24893buildbot # [ 12.222177] setup-git-repo-start[624]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 1349 ms (attempt 3/5)894buildbot # [ 12.702002] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.895buildbot # [ 12.707226] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.896buildbot # [ 12.709886] systemd[1]: Stopped Virtual Console Setup.897buildbot # [ 12.710657] systemd[1]: Stopping Virtual Console Setup...898buildbot # [ 12.717743] systemd[1]: Starting Virtual Console Setup...899buildbot # [ 12.738885] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.900buildbot # [ 12.739987] systemd[1]: Stopped Virtual Console Setup.901buildbot # [ 12.746713] systemd[1]: Starting Virtual Console Setup...902buildbot # [ 12.760490] systemd-logind[400]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)903buildbot # [ 12.918482] dhcpcd[511]: eth0: soliciting an IPv6 router904buildbot # [ 12.919975] dhcpcd[511]: eth0: Router Advertisement from fe80::2905buildbot # [ 12.921023] dhcpcd[511]: eth0: adding address fec0::5054:ff:fe12:3456/64906buildbot # [ 12.921903] dhcpcd[511]: eth0: adding route to fec0::/64907buildbot # [ 12.922672] dhcpcd[511]: eth0: adding default route via fe80::2908buildbot # [ 12.930040] systemd-vconsole-setup[666]: Configuration of first virtual console was skipped, ignoring remaining ones.909buildbot # [ 12.933882] systemd[1]: Finished Virtual Console Setup.910buildbot # [ 13.213069] postgresql-pre-start[554]: selecting default time zone ... UTC911buildbot # [ 13.215664] postgresql-pre-start[554]: creating configuration files ... ok912buildbot # [ 13.440152] postgresql-pre-start[554]: running bootstrap script ... ok913buildbot # [ 13.572817] setup-git-repo-start[624]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 2041 ms (attempt 4/5)914buildbot # [ 13.932293] postgresql-pre-start[554]: performing post-bootstrap initialization ... ok915buildbot # [ 14.138740] postgresql-pre-start[554]: syncing data to disk ... ok916buildbot # [ 14.139667] postgresql-pre-start[554]: initdb: warning: enabling "trust" authentication for local connections917buildbot # [ 14.141076] postgresql-pre-start[554]: 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.918buildbot # [ 14.143097] postgresql-pre-start[554]: Success. You can now start the database server using:919buildbot # [ 14.144254] postgresql-pre-start[554]: pg_ctl -D /var/lib/postgresql/18 -l logfile start920buildbot # [ 14.231549] postgres[685]: [685] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit921buildbot # [ 14.235794] postgres[685]: [685] LOG: listening on IPv6 address "::1", port 5432922buildbot # [ 14.237093] postgres[685]: [685] LOG: listening on IPv4 address "127.0.0.1", port 5432923buildbot # [ 14.239839] postgres[685]: [685] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"924buildbot # [ 14.250140] postgres[694]: [694] LOG: database system was shut down at 2026-09-20 07:27:52 GMT925buildbot # [ 14.256900] postgres[685]: [685] LOG: database system is ready to accept connections926buildbot # [ 14.260933] systemd[1]: Started PostgreSQL Server.927buildbot # [ 14.268303] systemd[1]: Starting PostgreSQL Setup Scripts...928buildbot # [ 14.433326] postgresql-setup-start[705]: CREATE DATABASE929buildbot # [ 14.473010] postgresql-setup-start[710]: CREATE ROLE930buildbot # [ 14.489472] postgresql-setup-start[712]: ALTER DATABASE931buildbot # [ 14.495179] systemd[1]: Finished PostgreSQL Setup Scripts.932buildbot # [ 14.498652] systemd[1]: Reached target PostgreSQL.933buildbot # [ 14.500472] systemd[1]: Starting Buildbot Continuous Integration Server....934buildbot # [ 14.534662] buildbot-master-pre-start[717]: mkdir: created directory '/var/lib/buildbot/master'935buildbot # [ 15.617092] setup-git-repo-start[624]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org936buildbot # [ 15.619951] setup-git-repo-start[624]: this derivation will be built:937buildbot # [ 15.621112] setup-git-repo-start[624]: /nix/store/lqx3p33b67znqs9dzp4mdp81b11fiwzz-skippable-test.drv938buildbot # [ 15.726772] setup-git-repo-start[624]: building '/nix/store/lqx3p33b67znqs9dzp4mdp81b11fiwzz-skippable-test.drv'...939buildbot # [ 15.762682] systemd[1]: Finished Setup git test repository.940buildbot: (finished: waiting for unit setup-git-repo.service, in 5.44 seconds)941buildbot: waiting for unit multi-user.target942buildbot # [ 17.080558] buildbot-master-pre-start[719]: updating existing installation943buildbot # [ 17.080699] buildbot-master-pre-start[719]: not touching existing buildbot.tac944buildbot # [ 17.080733] buildbot-master-pre-start[719]: creating buildbot.tac.new instead945buildbot # [ 17.080763] buildbot-master-pre-start[719]: creating /var/lib/buildbot/master/master.cfg.sample946buildbot # [ 17.080793] buildbot-master-pre-start[719]: creating database (postgresql://@/buildbot)947buildbot # [ 17.080825] buildbot-master-pre-start[719]: buildmaster configured in /var/lib/buildbot/master948buildbot # [ 17.248676] dhcpcd[511]: eth0: leased 10.0.2.15 for 86400 seconds949buildbot # [ 17.252583] dhcpcd[511]: eth0: adding route to 10.0.2.0/24950buildbot # [ 17.253435] dhcpcd[511]: eth0: adding default route via 10.0.2.2951buildbot # [ 17.747007] systemd[1]: Started DHCP Client.952buildbot # [ 17.758462] systemd[1]: Started Buildbot Continuous Integration Server..953buildbot # [ 17.765535] systemd[1]: Started Buildbot Worker..954buildbot # [ 17.768634] systemd[1]: Reached target Multi-User System.955buildbot # [ 17.772363] systemd[1]: Startup finished in 925ms (kernel) + 4.103s (initrd) + 12.743s (userspace) = 17.772s.956buildbot: (finished: waiting for unit multi-user.target, in 2.41 seconds)957subtest: Master and worker services start958buildbot: waiting for unit buildbot-master.service959buildbot: (finished: waiting for unit buildbot-master.service, in 0.10 seconds)960buildbot: waiting for unit buildbot-worker.service961buildbot: (finished: waiting for unit buildbot-worker.service, in 0.10 seconds)962buildbot: waiting for TCP port 8010 on localhost963buildbot # [ 19.287264] twistd[824]: Starting worker local-worker-000964buildbot # [ 19.287479] twistd[824]: 2026-09-20T07:27:57+0000 [-] Loading /nix/store/zxbh9svi0g0i80pg7z3gd6hmk17ck3yf-buildbot_nix/buildbot_nix/worker.py...965buildbot # [ 19.292098] twistd[824]: 2026-09-20T07:27:57+0000 [-] Loaded.966buildbot # [ 19.292949] twistd[824]: 2026-09-20T07:27:57+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] twistd 26.4.0 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14 3.14.7) starting up.967buildbot # [ 19.295290] twistd[824]: 2026-09-20T07:27:57+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.968buildbot # [ 19.304261] twistd[824]: 2026-09-20T07:27:57+0000 [-] Starting Worker -- version: 2026.09.20969buildbot # [ 19.305404] twistd[824]: 2026-09-20T07:27:57+0000 [-] recording hostname in twistd.hostname970buildbot # [ 19.306501] twistd[824]: 2026-09-20T07:27:57+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0xea1d907c0050>971buildbot # [ 19.310327] twistd[824]: 2026-09-20T07:27:57+0000 [twisted.application._client_service.ClientService#info] Scheduling retry 1 to connect <twisted.internet.endpoints.TCP4ClientEndpoint object at 0xea1d907c0590> in 1.5039935504788637 seconds.972buildbot # [ 19.313370] twistd[824]: 2026-09-20T07:27:57+0000 [buildbot_worker.pb.BotFactory#info] Stopping factory <buildbot_worker.pb.BotFactory object at 0xea1d907c0050>973buildbot # [ 20.653964] twistd[823]: 2026-09-20T07:27:57+0000 [-] Loading /var/lib/buildbot/master/buildbot.tac...974buildbot # [ 20.656554] twistd[823]: 2026-09-20T07:27:59+0000 [-] Loaded.975buildbot # [ 20.657326] twistd[823]: 2026-09-20T07:27:59+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] twistd 26.4.0 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14 3.14.7) starting up.976buildbot # [ 20.659596] twistd[823]: 2026-09-20T07:27:59+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.977buildbot # [ 20.661601] twistd[823]: 2026-09-20T07:27:59+0000 [-] Starting BuildMaster -- buildbot.version: 4.3.0978buildbot # [ 20.668050] twistd[823]: 2026-09-20T07:27:59+0000 [-] Loading configuration from '/nix/store/w0z2qzjpi74dqish8xg27f5ijkdl3bzj-master.cfg'979buildbot # [ 20.818056] twistd[824]: 2026-09-20T07:27:59+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0xea1d907c0050>980buildbot # [ 20.823593] twistd[824]: 2026-09-20T07:27:59+0000 [twisted.application._client_service.ClientService#info] Scheduling retry 2 to connect <twisted.internet.endpoints.TCP4ClientEndpoint object at 0xea1d907c0590> in 2.93193140296883 seconds.981buildbot # [ 20.827168] twistd[824]: 2026-09-20T07:27:59+0000 [buildbot_worker.pb.BotFactory#info] Stopping factory <buildbot_worker.pb.BotFactory object at 0xea1d907c0050>982buildbot # [ 21.343072] twistd[823]: 2026-09-20T07:27:59+0000 [-] Setting up database with URL 'postgresql://@/buildbot'983buildbot # [ 21.462793] twistd[823]: 2026-09-20T07:28:00+0000 [-] adding 9 new builders, removing 0984buildbot # [ 21.865388] twistd[823]: 2026-09-20T07:28:00+0000 [-] adding 3 new services, removing 0985buildbot # [ 22.080291] twistd[823]: 2026-09-20T07:28:00+0000 [-] adding 1 new change_sources, removing 0986buildbot # [ 22.101034] twistd[823]: 2026-09-20T07:28:00+0000 [-] gitpoller: using workdir '/var/lib/buildbot/master/gitpoller-work'987buildbot # [ 22.120252] twistd[823]: 2026-09-20T07:28:00+0000 [-] adding 14 new schedulers, removing 0988buildbot # [ 22.395114] sshd-session[882]: Accepted publickey for root from ::1 port 57760 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW8989buildbot # [ 22.415662] sshd-session[882]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)990buildbot # [ 22.427415] twistd[823]: 2026-09-20T07:28:01+0000 [-] BuildbotSite starting on 8010991buildbot # [ 22.430968] twistd[823]: 2026-09-20T07:28:01+0000 [buildbot.www.service.BuildbotSite#info] Starting factory <buildbot.www.service.BuildbotSite object at 0xf3a4a455d160>992buildbot # [ 22.437149] twistd[823]: 2026-09-20T07:28:01+0000 [-] adding 5 new workers, removing 0993buildbot # [ 22.463361] twistd[823]: 2026-09-20T07:28:01+0000 [-] PBServerFactory starting on 9989994buildbot # [ 22.466719] twistd[823]: 2026-09-20T07:28:01+0000 [twisted.spread.pb.PBServerFactory#info] Starting factory <twisted.spread.pb.PBServerFactory object at 0xf3a4a455d7f0>995buildbot # [ 22.483511] systemd[1]: Created slice Slice /user/0.996buildbot # [ 22.488127] systemd[1]: Starting User Runtime Directory /run/user/0...997buildbot # [ 22.516321] systemd-logind[400]: New session '1' of user 'root' with class 'user' and type 'tty'.998buildbot # [ 22.569158] twistd[823]: 2026-09-20T07:28:01+0000 [-] Starting Worker -- version: 2026.09.20999buildbot # [ 22.571699] twistd[823]: 2026-09-20T07:28:01+0000 [-] recording hostname in twistd.hostname1000buildbot # [ 22.574175] twistd[823]: 2026-09-20T07:28:01+0000 [-] message from master: attached1001buildbot # [ 22.581886] twistd[823]: 2026-09-20T07:28:01+0000 [-] Got workerinfo from '__Janitor'1002buildbot # [ 22.586740] systemd[1]: Finished User Runtime Directory /run/user/0.1003buildbot # [ 22.602995] twistd[823]: 2026-09-20T07:28:01+0000 [-] bot attached1004buildbot # [ 22.603903] twistd[823]: 2026-09-20T07:28:01+0000 [-] Worker __Janitor attached to __Janitor1005buildbot # [ 22.608500] twistd[823]: 2026-09-20T07:28:01+0000 [-] message from master: attached1006buildbot # [ 22.609587] systemd[1]: Starting User Manager for UID 0...1007buildbot # [ 22.696340] (systemd)[887]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0)1008buildbot # [ 22.706677] systemd-logind[400]: New session '2' of user 'root' with class 'manager-early' and type 'unspecified'.1009buildbot # [ 22.747028] twistd[823]: 2026-09-20T07:28:01+0000 [-] BuildMaster is running1010buildbot # [ 22.842414] systemd[887]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1011buildbot # [ 23.048481] systemd[887]: Queued start job for default target Main User Target.1012buildbot # [ 23.057203] systemd[887]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1013buildbot # [ 23.060418] systemd[887]: Created slice User Application Slice.1014buildbot # [ 23.061431] systemd[887]: Started Daily Cleanup of User's Temporary Directories.1015buildbot # [ 23.062540] systemd[887]: Reached target Paths.1016buildbot # [ 23.064378] systemd[887]: Reached target Timers.1017buildbot # [ 23.067435] systemd[887]: Starting D-Bus User Message Bus Socket...1018buildbot # [ 23.069570] systemd[887]: Starting Create User Files and Directories...1019buildbot # [ 23.116731] systemd[887]: Finished Create User Files and Directories.1020buildbot # [ 23.136104] systemd[887]: Listening on D-Bus User Message Bus Socket.1021buildbot # [ 23.137151] systemd[887]: Reached target Sockets.1022buildbot # [ 23.139891] systemd[887]: Reached target Basic System.1023buildbot # [ 23.144714] systemd[887]: Run user-specific NixOS activation skipped, unmet condition check ConditionUser=!@system1024buildbot # [ 23.146122] systemd[887]: Reached target Main User Target.1025buildbot # [ 23.146904] systemd[887]: Startup finished in 399ms.1026buildbot # [ 23.147615] systemd[1]: Started User Manager for UID 0.1027buildbot # [ 23.165015] systemd[1]: Started Session 1 of User root.1028buildbot # [ 23.201085] sshd-session[904]: Received disconnect from ::1 port 57760:11: disconnected by user1029buildbot # [ 23.202342] sshd-session[904]: Disconnected from user root ::1 port 577601030buildbot # [ 23.203232] sshd-session[882]: pam_unix(sshd:session): session closed for user root1031buildbot # [ 23.210724] systemd[1]: session-1.scope: Deactivated successfully.1032buildbot # Connection to localhost (127.0.0.1) 8010 port [tcp/*] succeeded!1033buildbot # [ 23.216809] systemd-logind[400]: Session 1 logged out. Waiting for processes to exit.1034buildbot # [ 23.228149] systemd-logind[400]: Removed session 1.1035buildbot: (finished: waiting for TCP port 8010 on localhost, in 4.39 seconds)1036??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1037 File "/nix/store/d3bsqqyiqphqrb0s8sivc5iq46djqzda-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391038buildbot: waiting for success: curl --fail --head http://localhost:80101039??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1040 File "/nix/store/d3bsqqyiqphqrb0s8sivc5iq46djqzda-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391041buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1042buildbot # Dload Upload Total Spent Left Speed1043buildbot # 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 01044buildbot: (finished: waiting for success: curl --fail --head http://localhost:8010, in 0.12 seconds)1045(finished: subtest: Master and worker services start, in 4.73 seconds)1046buildbot: waiting for success: curl http://localhost:8010/api/v2/projects1047buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1048buildbot # Dload Upload Total Spent Left Speed1049buildbot # 0 0 0 0 0 0 0 0 0100 266 100 266 0 0 10966 0 0100 266 100 266 0 0 9949 0 0100 266 100 266 0 0 8809 0 01050buildbot: (finished: waiting for success: curl http://localhost:8010/api/v2/projects, in 0.07 seconds)1051subtest: Wait for poller to initialize1052buildbot: waiting for success: journalctl -u buildbot-master.service | grep "gitpoller: processing changes from"1053buildbot # [ 23.439882] sshd-session[914]: Accepted publickey for root from ::1 port 57766 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81054buildbot # [ 23.461139] sshd-session[914]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1055buildbot # [ 23.485051] systemd-logind[400]: New session '3' of user 'root' with class 'user' and type 'tty'.1056buildbot # [ 23.493513] systemd[1]: Started Session 3 of User root.1057buildbot # [ 23.529873] sshd-session[927]: Received disconnect from ::1 port 57766:11: disconnected by user1058buildbot # [ 23.531145] sshd-session[927]: Disconnected from user root ::1 port 577661059buildbot # [ 23.534576] sshd-session[914]: pam_unix(sshd:session): session closed for user root1060buildbot # [ 23.540803] systemd[1]: session-3.scope: Deactivated successfully.1061buildbot # [ 23.546741] systemd-logind[400]: Session 3 logged out. Waiting for processes to exit.1062buildbot # [ 23.550886] systemd-logind[400]: Removed session 3.1063buildbot # [ 23.577688] twistd[823]: 2026-09-20T07:28:02+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1064buildbot # [ 23.767328] twistd[824]: 2026-09-20T07:28:02+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0xea1d907c0050>1065buildbot # [ 23.806698] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] worker 'local-worker-000' attaching from IPv4Address(type='TCP', host='127.0.0.1', port=38642)1066buildbot # [ 23.819325] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1067buildbot # [ 23.832969] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Got workerinfo from 'local-worker-000'1068buildbot # [ 23.860253] twistd[823]: 2026-09-20T07:28:02+0000 [-] bot attached1069buildbot # [ 23.864086] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-build1070buildbot # [ 23.872093] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/run-effect1071buildbot # [ 23.874605] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/run-scheduled-effect1072buildbot # [ 23.883004] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-dependency-failed1073buildbot # [ 23.890744] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-failed-eval1074buildbot # [ 23.904185] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-eval1075buildbot # [ 23.906305] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-cached-failure1076buildbot # [ 23.944401] twistd[823]: 2026-09-20T07:28:02+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-register-gcroot1077buildbot # [ 23.946121] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1078buildbot # [ 23.947316] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1079buildbot # [ 23.995169] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1080buildbot # [ 24.018319] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1081buildbot # [ 24.019519] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1082buildbot # [ 24.032406] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1083buildbot # [ 24.033570] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1084buildbot # [ 24.034675] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] message from master: attached1085buildbot # [ 24.035775] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] Connected to buildmaster; worker is ready1086buildbot # [ 24.067196] twistd[824]: 2026-09-20T07:28:02+0000 [Broker,client] sending application-level keepalives every 600 seconds1087buildbot: (finished: waiting for success: journalctl -u buildbot-master.service | grep "gitpoller: processing changes from", in 1.13 seconds)1088(finished: subtest: Wait for poller to initialize, in 1.13 seconds)1089subtest: Push new commit to trigger poller1090buildbot: must succeed: 1091 cd /tmp/test-flake1092 echo '# Updated at test time' >> flake.nix1093 git add flake.nix1094 git commit -m 'Test commit to trigger poller'1095 git push origin master1096 1097buildbot # Enumerating objects: 5, done.1098buildbot # Counting objects: 20% (1/5)Counting objects: 40% (2/5)Counting objects: 60% (3/5)Counting objects: 80% (4/5)Counting objects: 100% (5/5)Counting objects: 100% (5/5), done.1099buildbot # Compressing objects: 50% (1/2)Compressing objects: 100% (2/2)Compressing objects: 100% (2/2), done.1100buildbot # Writing objects: 33% (1/3)Writing objects: 66% (2/3)Writing objects: 100% (3/3)Writing objects: 100% (3/3), 299 bytes | 149.00 KiB/s, done.1101buildbot # Total 3 (delta 1), reused 0 (delta 0), pack-reused 0 (from 0)1102buildbot # To /srv/repos/test-flake.git1103buildbot # abab4eb..4cb3011 master -> master1104buildbot: (finished: must succeed: 1105 cd /tmp/test-flake1106 echo '# Updated at test time' >> flake.nix1107 git add flake.nix1108 git commit -m 'Test commit to trigger poller'1109 git push origin master1110 , in 0.11 seconds)1111(finished: subtest: Push new commit to trigger poller, in 0.11 seconds)1112subtest: Poller triggers builds and verifies results1113??? Warning (UserWarning): retry(): The 'timeout_seconds' argument is deprecated. Use 'timeout' instead.1114 File "/nix/store/d3bsqqyiqphqrb0s8sivc5iq46djqzda-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1281115buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1116buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1117buildbot # Dload Upload Total Spent Left Speed1118buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3555 0 0100 51 100 51 0 0 3021 0 0100 51 100 51 0 0 2643 0 01119buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.05 seconds)1120buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1121buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1122buildbot # Dload Upload Total Spent Left Speed1123buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1783 0 0100 51 100 51 0 0 1517 0 0100 51 100 51 0 0 1349 0 01124buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1125buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1126buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1127buildbot # Dload Upload Total Spent Left Speed1128buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2137 0 0100 51 100 51 0 0 1805 0 0100 51 100 51 0 0 1579 0 01129buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1130buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1131buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1132buildbot # Dload Upload Total Spent Left Speed1133buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2162 0 0100 51 100 51 0 0 1825 0 0100 51 100 51 0 0 1576 0 01134buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1135buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1136buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1137buildbot # Dload Upload Total Spent Left Speed1138buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2171 0 0100 51 100 51 0 0 1749 0 0100 51 100 51 0 0 1510 0 01139buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1140buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1141buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1142buildbot # Dload Upload Total Spent Left Speed1143buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2130 0 0100 51 100 51 0 0 1792 0 0100 51 100 51 0 0 1554 0 01144buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1145buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1146buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1147buildbot # Dload Upload Total Spent Left Speed1148buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2089 0 0100 51 100 51 0 0 1730 0 0100 51 100 51 0 0 1514 0 01149buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1150buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1151buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1152buildbot # Dl[ 32.402764] sshd-session[994]: Accepted publickey for root from ::1 port 36036 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81153buildbot # oad Upload Total Spent Left Speed1154buildbot # 0 0 0 0 0 0 0 0 0[ 32.423924] sshd-session[994]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1155buildbot # [ 32.440809] systemd-logind[400]: New session '4' of user 'root' with class 'user' and type 'tty'.1156buildbot # [ 32.442499] systemd[1]: Started Session 4 of User root.1157buildbot # 100 51 100 51 0 0 843 0 0100 51 100 51 0 0 800 0 0100 51 100 51 0 0 758 0 01158buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1159buildbot # [ 32.475817] sshd-session[1000]: Received disconnect from ::1 port 36036:11: disconnected by user1160buildbot # [ 32.477517] sshd-session[1000]: Disconnected from user root ::1 port 360361161buildbot # [ 32.478489] sshd-session[994]: pam_unix(sshd:session): session closed for user root1162buildbot # [ 32.495062] systemd[1]: session-4.scope: Deactivated successfully.1163buildbot # [ 32.495961] systemd-logind[400]: Session 4 logged out. Waiting for processes to exit.1164buildbot # [ 32.503148] systemd-logind[400]: Removed session 4.1165buildbot # [ 32.661495] sshd-session[1005]: Accepted publickey for root from ::1 port 36048 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81166buildbot # [ 32.677896] sshd-session[1005]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1167buildbot # [ 32.692157] systemd-logind[400]: New session '5' of user 'root' with class 'user' and type 'tty'.1168buildbot # [ 32.700515] systemd[1]: Started Session 5 of User root.1169buildbot # [ 32.745301] sshd-session[1008]: Received disconnect from ::1 port 36048:11: disconnected by user1170buildbot # [ 32.747029] sshd-session[1008]: Disconnected from user root ::1 port 360481171buildbot # [ 32.753664] sshd-session[1005]: pam_unix(sshd:session): session closed for user root1172buildbot # [ 32.758702] systemd-logind[400]: Session 5 logged out. Waiting for processes to exit.1173buildbot # [ 32.762884] systemd[1]: session-5.scope: Deactivated successfully.1174buildbot # [ 32.763979] systemd-logind[400]: Removed session 5.1175buildbot # [ 32.777637] twistd[823]: 2026-09-20T07:28:11+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1176buildbot # [ 32.815593] twistd[823]: 2026-09-20T07:28:11+0000 [-] gitpoller: processing 1 changes: ['4cb3011a4302342580d5fac2c56674393ff915b2'] from "ssh://root@localhost/srv/repos/test-flake.git" branch "refs/heads/master"1177buildbot # [ 32.925684] twistd[823]: 2026-09-20T07:28:11+0000 [-] added change with revision 4cb3011a4302342580d5fac2c56674393ff915b2 to database1178buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1179buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1180buildbot # Dload Upload Total Spent Left Speed1181buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1786 0 0100 51 100 51 0 0 1474 0 0100 51 100 51 0 0 1288 0 01182buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1183buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1184buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1185buildbot # Dload Upload Total Spent Left Speed1186buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 4283 0 0100 51 100 51 0 0 3204 0 0100 51 100 51 0 0 2634 0 01187buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.05 seconds)1188buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1189buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1190buildbot # Dload Upload Total Spent Left Speed1191buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3478 0 0100 51 100 51 0 0 2541 0 0100 51 100 51 0 0 2047 0 01192buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1193buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1194buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1195buildbot # Dload Upload Total Spent Left Speed1196buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2388 0 0100 51 100 51 0 0 1997 0 0100 51 100 51 0 0 1730 0 01197buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1198buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1199buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1200buildbot # Dload Upload Total Spent Left Speed1201buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2307 0 0100 51 100 51 0 0 1902 0 0100 51 100 51 0 0 1657 0 01202buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1203buildbot # [ 37.968877] twistd[823]: 2026-09-20T07:28:16+0000 [-] added buildset 1 to database1204buildbot # [ 38.132633] twistd[823]: 2026-09-20T07:28:16+0000 [-] starting build <Build test-flake/nix-eval number:None results:success> using worker <WorkerForBuilder builder='test-flake/nix-eval' worker='local-worker-000' state=AVAILABLE>1205buildbot # [ 38.142005] twistd[823]: 2026-09-20T07:28:16+0000 [-] <Build test-flake/nix-eval number:None results:success>.startBuild1206buildbot # [ 38.223480] twistd[823]: 2026-09-20T07:28:16+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1207buildbot # [ 38.228626] twistd[823]: 2026-09-20T07:28:16+0000 [-] starting build <Build test-flake/nix-eval number:1 results:success>.. pinging the worker <WorkerForBuilder builder='test-flake/nix-eval' worker='local-worker-000' state=BUILDING>1208buildbot # [ 38.231432] twistd[823]: 2026-09-20T07:28:16+0000 [-] sending ping1209buildbot # [ 38.234839] twistd[823]: 2026-09-20T07:28:16+0000 [Broker,0,127.0.0.1] ping finished: success1210buildbot # [ 38.237868] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] message from master: ping1211buildbot # [ 38.259533] twistd[823]: 2026-09-20T07:28:16+0000 [-] <RemoteShellCommand '['git', '--version']'>: RemoteCommand.run [0]1212buildbot # [ 38.265179] twistd[823]: 2026-09-20T07:28:16+0000 [-] command '['git', '--version']' in dir 'build'1213buildbot # [ 38.266388] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] (command 0): startCommand:shell1214buildbot # [ 38.267519] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] (command ['git', '--version']): RunProcess._startCommand1215buildbot # [ 38.274565] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] (command ['git', '--version']): git --version1216buildbot # [ 38.275878] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] (command ['git', '--version']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1217buildbot # [ 38.281693] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] (command ['git', '--version']): watching logfiles {}1218buildbot # [ 38.285497] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] (command ['git', '--version']): argv: [b'git', b'--version']1219buildbot # [ 38.286969] twistd[824]: 2026-09-20T07:28:16+0000 [Broker,client] (command ['git', '--version']): using PTY: False1220buildbot # [ 38.295149] twistd[824]: 2026-09-20T07:28:16+0000 [-] (command ['git', '--version']): command finished with signal None, exit code 0, elapsedTime: 0.0282631221buildbot # [ 38.297031] twistd[824]: 2026-09-20T07:28:16+0000 [-] (command 0): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d907c27b0>1222buildbot # [ 38.319062] twistd[823]: 2026-09-20T07:28:16+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', '--version']'> rc=01223buildbot # [ 38.360703] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteCommand 'stat' at 267888455649328>: RemoteCommand.run [1]1224buildbot # [ 38.364817] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 1): startCommand:stat1225buildbot # [ 38.366206] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 1): StatFile /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build/.buildbot-patched failed: [Errno 2] No such file or directory: '/var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build/.buildbot-patched'1226buildbot # [ 38.373717] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 1): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.StatFile object at 0xea1d90effb60>1227buildbot # [ 38.376272] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteCommand 'stat' at 267888455649328> rc=21228buildbot # [ 38.385663] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteCommand 'mkdir' at 267888455694160>: RemoteCommand.run [2]1229buildbot # [ 38.413285] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 2): startCommand:mkdir1230buildbot # [ 38.414560] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 2): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.MakeDirectory object at 0xea1d907c1010>1231buildbot # [ 38.419885] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteCommand 'mkdir' at 267888455694160> rc=01232buildbot # [ 38.424458] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteCommand 'downloadFile' at 267888455695440>: RemoteCommand.run [3]1233buildbot # [ 38.461207] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 3): startCommand:downloadFile1234buildbot # [ 38.467025] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 3): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.transfer.WorkerFileDownloadCommand object at 0xea1d907c0d70>1235buildbot # [ 38.477957] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteCommand 'downloadFile' at 267888455695440> rc=01236buildbot # [ 38.485209] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteCommand 'listdir' at 267888455695760>: RemoteCommand.run [4]1237buildbot # [ 38.516945] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 4): startCommand:listdir1238buildbot # [ 38.522106] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 4): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.ListDir object at 0xea1d907c0c20>1239buildbot # [ 38.531792] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteCommand 'listdir' at 267888455695760> rc=01240buildbot # [ 38.540749] twistd[823]: 2026-09-20T07:28:17+0000 [-] No git repo present, making full clone1241buildbot # [ 38.546087] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteShellCommand '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']'>: RemoteCommand.run [5]1242buildbot # [ 38.554528] twistd[823]: 2026-09-20T07:28:17+0000 [-] command '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']' in dir 'build'1243buildbot # [ 38.569628] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 5): startCommand:shell1244buildbot # [ 38.576397] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']): RunProcess._startCommand1245buildbot # [ 38.596142] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']): git -c 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"' clone --branch master ssh://root@localhost/srv/repos/test-flake.git . --progress1246buildbot # [ 38.615700] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1247buildbot # [ 38.622002] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']): watching logfiles {}1248buildbot # [ 38.629974] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']): argv: [b'git', b'-c', b'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', b'clone', b'--branch', b'master', b'ssh://root@localhost/srv/repos/test-flake.git', b'.', b'--progress']1249buildbot # [ 38.641495] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']): using PTY: False1250buildbot # [ 38.818300] sshd-session[1044]: Accepted publickey for root from ::1 port 36060 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81251buildbot # [ 38.835875] sshd-session[1044]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1252buildbot # [ 38.851847] systemd-logind[400]: New session '6' of user 'root' with class 'user' and type 'tty'.1253buildbot # [ 38.858859] systemd[1]: Started Session 6 of User root.1254buildbot # [ 38.901662] sshd-session[1047]: Received disconnect from ::1 port 36060:11: disconnected by user1255buildbot # [ 38.903059] sshd-session[1047]: Disconnected from user root ::1 port 360601256buildbot # [ 38.905894] sshd-session[1044]: pam_unix(sshd:session): session closed for user root1257buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1258buildbot # [ 38.909357] systemd-logind[400]: Session 6 logged out. Waiting for processes to exit.1259buildbot # [ 38.912327] systemd[1]: session-6.scope: Deactivated successfully.1260buildbot # [ 38.915358] systemd-logind[400]: Removed session 6.1261buildbot # [ 38.943884] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']): command finished with signal None, exit code 0, elapsedTime: 0.3698791262buildbot # [ 38.964540] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command 5): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90e596d0>1263buildbot # [ 38.966709] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'clone', '--branch', 'master', 'ssh://root@localhost/srv/repos/test-flake.git', '.', '--progress']'> rc=01264buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1265buildbot # Dload Upload Total Spent [ 38.996478] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteShellCommand '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']'>: RemoteCommand.run [6]1266buildbot # [ 38.999724] twistd[823]: 2026-09-20T07:28:17+0000 [-] command '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']' in dir 'build'1267buildbot # Left Speed1268buildbot # 0 0 0 0 0 0 0 0 0[ 39.020143] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 6): startCommand:shell1269buildbot # [ 39.021324] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']): RunProcess._startCommand1270buildbot # 100 401 100 401 0 0 7655 0 0100 401 100 401 0 0 7325 0 [ 39.033378] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']): git -c 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"' checkout -f 4cb3011a4302342580d5fac2c56674393ff915b21271buildbot # 0100 401 100 401 0 0 6258 0 01272buildbot # [ 39.047978] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1273buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1274buildbot # [ 39.057632] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']): watching logfiles {}1275buildbot # [ 39.061194] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']): argv: [b'git', b'-c', b'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', b'checkout', b'-f', b'4cb3011a4302342580d5fac2c56674393ff915b2']1276buildbot # [ 39.068266] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']): using PTY: False1277buildbot # [ 39.071693] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']): command finished with signal None, exit code 0, elapsedTime: 0.0514031278buildbot # [ 39.079079] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command 6): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90e5a990>1279buildbot # [ 39.082782] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'checkout', '-f', '4cb3011a4302342580d5fac2c56674393ff915b2']'> rc=01280buildbot # [ 39.101852] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteShellCommand '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']'>: RemoteCommand.run [7]1281buildbot # [ 39.108391] twistd[823]: 2026-09-20T07:28:17+0000 [-] command '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']' in dir 'build'1282buildbot # [ 39.117347] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 7): startCommand:shell1283buildbot # [ 39.118532] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']): RunProcess._startCommand1284buildbot # [ 39.122198] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']): git -c 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"' submodule update --init --recursive1285buildbot # [ 39.127549] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1286buildbot # [ 39.133031] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']): watching logfiles {}1287buildbot # [ 39.138352] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']): argv: [b'git', b'-c', b'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', b'submodule', b'update', b'--init', b'--recursive']1288buildbot # [ 39.144274] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']): using PTY: False1289buildbot # [ 39.211734] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command ['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']): command finished with signal None, exit code 0, elapsedTime: 0.1086701290buildbot # [ 39.217923] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command 7): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90dca780>1291buildbot # [ 39.220394] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', '-c', 'core.sshCommand=ssh -o "BatchMode=yes" -i "/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot/ssh-key"', 'submodule', 'update', '--init', '--recursive']'> rc=01292buildbot # [ 39.236225] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteShellCommand '['git', 'rev-parse', 'HEAD']'>: RemoteCommand.run [8]1293buildbot # [ 39.237736] twistd[823]: 2026-09-20T07:28:17+0000 [-] command '['git', 'rev-parse', 'HEAD']' in dir 'build'1294buildbot # [ 39.253376] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 8): startCommand:shell1295buildbot # [ 39.260156] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): RunProcess._startCommand1296buildbot # [ 39.261674] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): git rev-parse HEAD1297buildbot # [ 39.263110] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1298buildbot # [ 39.273745] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): watching logfiles {}1299buildbot # [ 39.275249] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): argv: [b'git', b'rev-parse', b'HEAD']1300buildbot # [ 39.277372] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): using PTY: False1301buildbot # [ 39.283001] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command ['git', 'rev-parse', 'HEAD']): command finished with signal None, exit code 0, elapsedTime: 0.0265721302buildbot # [ 39.285005] twistd[824]: 2026-09-20T07:28:17+0000 [-] (command 8): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90dca8b0>1303buildbot # [ 39.309602] twistd[823]: 2026-09-20T07:28:17+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', 'rev-parse', 'HEAD']'> rc=01304buildbot # [ 39.332101] twistd[823]: 2026-09-20T07:28:17+0000 [-] Got Git revision 4cb3011a4302342580d5fac2c56674393ff915b21305buildbot # [ 39.334939] twistd[823]: 2026-09-20T07:28:17+0000 [-] <RemoteCommand 'rmdir' at 267888456287248>: RemoteCommand.run [9]1306buildbot # [ 39.355586] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command 9): startCommand:rmdir1307buildbot # [ 39.359481] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): RunProcess._startCommand1308buildbot # [ 39.365077] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): rm -rf /var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot1309buildbot # [ 39.367998] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): in dir /var/lib/buildbot-worker/worker-000 (timeout 120 secs)1310buildbot # [ 39.371733] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): watching logfiles {}1311buildbot # [ 39.374728] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): argv: [b'rm', b'-rf', b'/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']1312buildbot # [ 39.380770] twistd[824]: 2026-09-20T07:28:17+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): using PTY: False1313buildbot # [ 39.386703] twistd[824]: 2026-09-20T07:28:18+0000 [-] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): command finished with signal None, exit code 0, elapsedTime: 0.0321001314buildbot # [ 39.389478] twistd[824]: 2026-09-20T07:28:18+0000 [-] (command 9): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.RemoveDirectory object at 0xea1d907c3380>1315buildbot # [ 39.405969] twistd[823]: 2026-09-20T07:28:18+0000 [Broker,0,127.0.0.1] <RemoteCommand 'rmdir' at 267888456287248> rc=01316buildbot # [ 39.428077] twistd[823]: 2026-09-20T07:28:18+0000 [-] releaseLocks(GitLocalPrMerge(default_branch='master', repourl=Interpolate('ssh://root@localhost/srv/repos/test-flake.git'), method='clean', submodules=True, haltOnFailure=True, logEnviron=False, sshPrivateKey='-----BEGIN OPENSSH PRIVATE KEY-----\nb3BlbnNzaC1rZXktdjEAAAAABG5vbmUAAAAEbm9uZQAAAAAAAAABAAAAMwAAAAtzc2gtZW\nQyNTUxOQAAACBG+sEWLfMtuYxA4kvzcEgx8GkX6r7zt+hLnsiedIyX1wAAAJhFK1T9RStU\n/QAAAAtzc2gtZWQyNTUxOQAAACBG+sEWLfMtuYxA4kvzcEgx8GkX6r7zt+hLnsiedIyX1w\nAAAED1I5G8QWiUPUYhutClVIyCYqRZ3MYUj90NtABLcaSPZkb6wRYt8y25jEDiS/NwSDHw\naRfqvvO36EueyJ50jJfXAAAADnRlc3RAbG9jYWxob3N0AQIDBAUGBw==\n-----END OPENSSH PRIVATE KEY-----\n', sshKnownHosts=None)): []1317buildbot # [ 39.451753] twistd[823]: 2026-09-20T07:28:18+0000 [-] step 'git' complete: success (None)1318buildbot # [ 39.462005] twistd[823]: 2026-09-20T07:28:18+0000 [-] acquireLocks(step NixEvalCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xf3a4a528c1a0>, env={'CLICOLOR_FORCE': '1'}, name='Evaluate flake', nix_eval_config=NixEvalConfig(supported_systems=['aarch64-linux'], failed_build_report_limit=47, worker_count=1, max_memory_size=2048, eval_lock=<buildbot.locks.MasterLock object at 0xf3a4a528d940>, gcroots_user='buildbot-worker', cache_failed_builds=False, show_trace=False), haltOnFailure=True, locks=[<buildbot.locks.LockAccess object at 0xf3a4a528ecf0>], drv_gcroots_dir=Interpolate('/nix/var/nix/gcroots/per-user/buildbot-worker/%(prop:project)s/drvs/%(prop:workername)s/'), logEnviron=False), locks [(<MasterLock(nix-eval, 1)>, <buildbot.locks.LockAccess object at 0xf3a4a528ecf0>)])1319buildbot # [ 39.481498] twistd[823]: 2026-09-20T07:28:18+0000 [-] <RemoteShellCommand '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']'>: RemoteCommand.run [10]1320buildbot # [ 39.483512] twistd[823]: 2026-09-20T07:28:18+0000 [-] command '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']' in dir 'build'1321buildbot # [ 39.492272] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command 10): startCommand:shell1322buildbot # [ 39.498031] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): RunProcess._startCommand1323buildbot # [ 39.499991] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): sh -c 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi'1324buildbot # [ 39.505658] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1325buildbot # [ 39.508710] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): watching logfiles {}1326buildbot # [ 39.510909] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): argv: [b'sh', b'-c', b'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']1327buildbot # [ 39.515313] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): using PTY: False1328buildbot # [ 39.520916] twistd[824]: 2026-09-20T07:28:18+0000 [-] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): command finished with signal None, exit code 0, elapsedTime: 0.0331611329buildbot # [ 39.524146] twistd[824]: 2026-09-20T07:28:18+0000 [-] (command 10): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90deee70>1330buildbot # [ 39.545874] twistd[823]: 2026-09-20T07:28:18+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']'> rc=01331buildbot # [ 39.550634] twistd[823]: 2026-09-20T07:28:18+0000 [-] <RemoteShellCommand '['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']'>: RemoteCommand.run [11]1332buildbot # [ 39.555985] twistd[823]: 2026-09-20T07:28:18+0000 [-] command '['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']' in dir 'build'1333buildbot # [ 39.593516] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command 11): startCommand:shell1334buildbot # [ 39.597747] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']): RunProcess._startCommand1335buildbot # [ 39.610081] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']): nix-eval-jobs --option eval-cache false --workers 1 --max-memory-size 2048 --option accept-flake-config true --gc-roots-dir /nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/ --force-recurse --check-cache-status --flake '.#checks'1336buildbot # [ 39.619612] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1337buildbot # [ 39.626318] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']): watching logfiles {}1338buildbot # [ 39.634178] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']): argv: [b'nix-eval-jobs', b'--option', b'eval-cache', b'false', b'--workers', b'1', b'--max-memory-size', b'2048', b'--option', b'accept-flake-config', b'true', b'--gc-roots-dir', b'/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', b'--force-recurse', b'--check-cache-status', b'--flake', b'.#checks']1339buildbot # [ 39.643464] twistd[824]: 2026-09-20T07:28:18+0000 [Broker,client] (command ['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']): using PTY: False1340buildbot # [ 39.769919] systemd[1]: Started Nix Daemon.1341buildbot # [ 39.837292] nix-daemon[1090]: accepted connection from pid 1088, user buildbot-worker1342buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1343buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1344buildbot # Dload Upload Total Spent Left Speed1345buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 17906 0 0100 401 100 401 0 0 15017 0 0100 401 100 401 0 0 13124 0 01346buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.12 seconds)1347buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1348buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1349buildbot # Dload Upload Total Spent Left Speed1350buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 14786 0 0100 401 100 401 0 0 12533 0 0100 401 100 401 0 0 11155 0 01351buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1352buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1353buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1354buildbot # Dload Upload Total Spent Left Speed1355buildbot # 0 0 0 0 0 0 0 0 0[ 42.364854] sshd-session[1108]: Accepted publickey for root from ::1 port 52400 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81356buildbot # 100 401 100 401 0 0 11538 0 0100 401 100 401 0 0 9462 0 0100 401 100 401 0 0 8773 0 01357buildbot # [ 42.389764] sshd-session[1108]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1358buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.12 seconds)1359buildbot # [ 42.402171] systemd-logind[400]: New session '7' of user 'root' with class 'user' and type 'tty'.1360buildbot # [ 42.408713] systemd[1]: Started Session 7 of User root.1361buildbot # [ 42.429692] sshd-session[1114]: Received disconnect from ::1 port 52400:11: disconnected by user1362buildbot # [ 42.431043] sshd-session[1114]: Disconnected from user root ::1 port 524001363buildbot # [ 42.432742] sshd-session[1108]: pam_unix(sshd:session): session closed for user root1364buildbot # [ 42.445063] systemd[1]: session-7.scope: Deactivated successfully.1365buildbot # [ 42.446100] systemd-logind[400]: Session 7 logged out. Waiting for processes to exit.1366buildbot # [ 42.452371] systemd-logind[400]: Removed session 7.1367buildbot # [ 42.616382] sshd-session[1119]: Accepted publickey for root from ::1 port 52412 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81368buildbot # [ 42.633257] sshd-session[1119]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1369buildbot # [ 42.647527] systemd-logind[400]: New session '8' of user 'root' with class 'user' and type 'tty'.1370buildbot # [ 42.652966] systemd[1]: Started Session 8 of User root.1371buildbot # [ 42.687655] sshd-session[1122]: Received disconnect from ::1 port 52412:11: disconnected by user1372buildbot # [ 42.689917] sshd-session[1122]: Disconnected from user root ::1 port 524121373buildbot # [ 42.691301] sshd-session[1119]: pam_unix(sshd:session): session closed for user root1374buildbot # [ 42.699591] systemd[1]: session-8.scope: Deactivated successfully.1375buildbot # [ 42.706031] systemd-logind[400]: Session 8 logged out. Waiting for processes to exit.1376buildbot # [ 42.710152] systemd-logind[400]: Removed session 8.1377buildbot # [ 42.718339] twistd[823]: 2026-09-20T07:28:21+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1378buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1379buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1380buildbot # Dload Upload Total Spent Left Speed1381buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 24129 0 0100 401 100 401 0 0 18649 0 0100 401 100 401 0 0 15465 0 01382buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1383buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1384buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1385buildbot # Dload Upload Total Spent Left Speed1386buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 15130 0 0100 401 100 401 0 0 12867 0 0100 401 100 401 0 0 11379 0 01387buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1388buildbot # [ 45.048568] nix-daemon[1090]: accepted connection from pid 1140, user buildbot-worker1389buildbot # [ 45.065563] nix-daemon[1090]: accepted connection from pid 1088, user buildbot-worker1390buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1391buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1392buildbot # Dload Upload Total Spent Left Speed1393buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 16415 0 0100 401 100 401 0 0 13792 0 0100 401 100 401 0 0 12081 0 01394buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1395buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1396buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1397buildbot # Dload Upload Total Spent Left Speed1398buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 15114 0 0100 401 100 401 0 0 12685 0 0100 401 100 401 0 0 11175 0 01399buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1400buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1401buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1402buildbot # Dload Upload Total Spent Left Speed1403buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 13141 0 0100 401 100 401 0 0 10855 0 0100 401 100 401 0 0 9527 0 01404buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1405buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1406buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1407buildbot # Dload Upload Total Spent Left Speed1408buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 16336 0 0100 401 100 401 0 0 13807 0 0100 401 100 401 0 0 12078 0 01409buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1410buildbot # [ 49.651217] nix-daemon[1090]: accepted connection from pid 1088, user buildbot-worker1411buildbot # [ 49.716901] twistd[824]: 2026-09-20T07:28:28+0000 [-] (command ['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']): command finished with signal None, exit code 0, elapsedTime: 10.1193631412buildbot # [ 49.734397] twistd[824]: 2026-09-20T07:28:28+0000 [-] (command 11): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90f4b8a0>1413buildbot # [ 49.741510] twistd[823]: 2026-09-20T07:28:28+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['nix-eval-jobs', '--option', 'eval-cache', 'false', '--workers', '1', '--max-memory-size', '2048', '--option', 'accept-flake-config', 'true', '--gc-roots-dir', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/', '--force-recurse', '--check-cache-status', '--flake', '.#checks']'> rc=01414buildbot # [ 49.780647] twistd[823]: 2026-09-20T07:28:28+0000 [-] releaseLocks(NixEvalCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xf3a4a528c1a0>, env={'CLICOLOR_FORCE': '1'}, name='Evaluate flake', nix_eval_config=NixEvalConfig(supported_systems=['aarch64-linux'], failed_build_report_limit=47, worker_count=1, max_memory_size=2048, eval_lock=<buildbot.locks.MasterLock object at 0xf3a4a528d940>, gcroots_user='buildbot-worker', cache_failed_builds=False, show_trace=False), haltOnFailure=True, locks=[<buildbot.locks.LockAccess object at 0xf3a4a528ecf0>], drv_gcroots_dir=Interpolate('/nix/var/nix/gcroots/per-user/buildbot-worker/%(prop:project)s/drvs/%(prop:workername)s/'), logEnviron=False)): [(<MasterLock(nix-eval, 1)>, <buildbot.locks.LockAccess object at 0xf3a4a528ecf0>)]1415buildbot # [ 49.805778] twistd[823]: 2026-09-20T07:28:28+0000 [-] step 'Evaluate flake' complete: success (None)1416buildbot # [ 49.850177] twistd[823]: 2026-09-20T07:28:28+0000 [-] added buildset 2 to database1417buildbot # [ 49.883851] twistd[823]: 2026-09-20T07:28:28+0000 [-] added buildset 3 to database1418buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1419buildbot # [ 50.061272] twistd[823]: 2026-09-20T07:28:28+0000 [-] starting build <Build test-flake/nix-build number:None results:success> using worker <WorkerForBuilder builder='test-flake/nix-build' worker='local-worker-000' state=AVAILABLE>1420buildbot # [ 50.074098] twistd[823]: 2026-09-20T07:28:28+0000 [-] <Build test-flake/nix-build number:None results:success>.startBuild1421buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1422buildbot # Dload Upload Total Spent Left Speed1423buildbot # 0 0 0 0 0 0 0 0 0100 750 100 750 0 0 19408 0 0100 750 100 750 0 0 17257 0 0100 750 100 750 0 0 14699 0 01424buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.14 seconds)1425buildbot # [ 50.185500] twistd[823]: 2026-09-20T07:28:28+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1426buildbot # [ 50.190226] twistd[823]: 2026-09-20T07:28:28+0000 [-] starting build <Build test-flake/nix-build number:1 results:success>.. pinging the worker <WorkerForBuilder builder='test-flake/nix-build' worker='local-worker-000' state=BUILDING>1427buildbot # [ 50.194529] twistd[823]: 2026-09-20T07:28:28+0000 [-] sending ping1428buildbot # [ 50.195432] twistd[823]: 2026-09-20T07:28:28+0000 [Broker,0,127.0.0.1] ping finished: success1429buildbot # [ 50.197151] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] message from master: ping1430buildbot # [ 50.220118] twistd[823]: 2026-09-20T07:28:28+0000 [-] <RemoteShellCommand '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']'>: RemoteCommand.run [12]1431buildbot # [ 50.223716] twistd[823]: 2026-09-20T07:28:28+0000 [-] command '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']' in dir 'build'1432buildbot # [ 50.237035] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] (command 12): startCommand:shell1433buildbot # [ 50.238258] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']): RunProcess._startCommand1434buildbot # [ 50.242211] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']): nix build -L --option keep-going true --max-silent-time 1200 --accept-flake-config --out-link result-aarch64-linux.failing-test '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*'1435buildbot # [ 50.248367] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-build/build (timeout 10800 secs)1436buildbot # [ 50.256564] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']): watching logfiles {}1437buildbot # [ 50.260828] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']): argv: [b'nix', b'build', b'-L', b'--option', b'keep-going', b'true', b'--max-silent-time', b'1200', b'--accept-flake-config', b'--out-link', b'result-aarch64-linux.failing-test', b'/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']1438buildbot # [ 50.267685] twistd[824]: 2026-09-20T07:28:28+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']): using PTY: False1439buildbot # [ 50.309725] nix-daemon[1090]: accepted connection from pid 1168, user buildbot-worker1440buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1441buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1442buildbot # Dload Upload Total Spent Left Speed1443buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 18371 0 0100 751 100 751 0 0 16282 0 0100 751 100 751 0 0 14821 0 01444buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.12 seconds)1445buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1446buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1447buildbot # Dload Upload Total Spent Left Speed1448buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 28180 0 0100 751 100 751 0 0 24860 0 0100 751 100 751 0 0 22014 0 01449buildbot # [ 52.354259] sshd-session[1183]: Accepted publickey for root from ::1 port 54898 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81450buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1451buildbot # [ 52.364648] sshd-session[1183]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1452buildbot # [ 52.373139] systemd-logind[400]: New session '9' of user 'root' with class 'user' and type 'tty'.1453buildbot # [ 52.386413] systemd[1]: Started Session 9 of User root.1454buildbot # [ 52.408895] sshd-session[1189]: Received disconnect from ::1 port 54898:11: disconnected by user1455buildbot # [ 52.410404] sshd-session[1189]: Disconnected from user root ::1 port 548981456buildbot # [ 52.411487] sshd-session[1183]: pam_unix(sshd:session): session closed for user root1457buildbot # [ 52.417141] systemd-logind[400]: Session 9 logged out. Waiting for processes to exit.1458buildbot # [ 52.426664] systemd[1]: session-9.scope: Deactivated successfully.1459buildbot # [ 52.427623] systemd-logind[400]: Removed session 9.1460buildbot # [ 52.597318] sshd-session[1194]: Accepted publickey for root from ::1 port 54908 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81461buildbot # [ 52.614586] sshd-session[1194]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1462buildbot # [ 52.628106] systemd-logind[400]: New session '10' of user 'root' with class 'user' and type 'tty'.1463buildbot # [ 52.637324] systemd[1]: Started Session 10 of User root.1464buildbot # [ 52.676362] sshd-session[1197]: Received disconnect from ::1 port 54908:11: disconnected by user1465buildbot # [ 52.678333] sshd-session[1197]: Disconnected from user root ::1 port 549081466buildbot # [ 52.679750] sshd-session[1194]: pam_unix(sshd:session): session closed for user root1467buildbot # [ 52.688474] systemd[1]: session-10.scope: Deactivated successfully.1468buildbot # [ 52.692311] systemd-logind[400]: Session 10 logged out. Waiting for processes to exit.1469buildbot # [ 52.696197] systemd-logind[400]: Removed session 10.1470buildbot # [ 52.702727] twistd[823]: 2026-09-20T07:28:31+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1471buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1472buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1473buildbot # Dload Upload Total Spent Left Speed1474buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 41368 0 0100 751 100 751 0 0 32397 0 0100 751 100 751 0 0 27135 0 01475buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1476buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1477buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1478buildbot # Dload Upload Total Spent Left Speed1479buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 49411 0 0100 751 100 751 0 0 37132 0 0100 751 100 751 0 0 30329 0 01480buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1481buildbot # [ 54.796262] twistd[824]: 2026-09-20T07:28:33+0000 [-] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']): command finished with signal None, exit code 1, elapsedTime: 4.5668221482buildbot # [ 54.806218] twistd[824]: 2026-09-20T07:28:33+0000 [-] (command 12): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90f4bf00>1483buildbot # [ 54.811737] twistd[823]: 2026-09-20T07:28:33+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.failing-test', '/nix/store/1rz7z5l65lpcjdjcl22xwd06g4b3wywz-failing-test.drv^*']'> rc=11484buildbot # [ 54.849583] twistd[823]: 2026-09-20T07:28:33+0000 [-] releaseLocks(NixBuildCommand(name='Build flake attr', command=['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', Interpolate('result-%(prop:attr)s'), Interpolate('%(prop:drv_path)s^*')], timeout=10800, haltOnFailure=True, logEnviron=False)): []1485buildbot # [ 54.864207] twistd[823]: 2026-09-20T07:28:33+0000 [-] step 'Build flake attr' complete: failure (["'nix build ...' (failure)"])1486buildbot # [ 54.867618] twistd[823]: 2026-09-20T07:28:33+0000 [-] <Build test-flake/nix-build number:1 results:failure>: build finished1487buildbot # [ 54.878111] twistd[823]: 2026-09-20T07:28:33+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1488buildbot # [ 55.018367] twistd[823]: 2026-09-20T07:28:33+0000 [-] starting build <Build test-flake/nix-build number:None results:success> using worker <WorkerForBuilder builder='test-flake/nix-build' worker='local-worker-000' state=AVAILABLE>1489buildbot # [ 55.028936] twistd[823]: 2026-09-20T07:28:33+0000 [-] <Build test-flake/nix-build number:None results:success>.startBuild1490buildbot # [ 55.119368] twistd[823]: 2026-09-20T07:28:33+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1491buildbot # [ 55.124103] twistd[823]: 2026-09-20T07:28:33+0000 [-] starting build <Build test-flake/nix-build number:1 results:success>.. pinging the worker <WorkerForBuilder builder='test-flake/nix-build' worker='local-worker-000' state=BUILDING>1492buildbot # [ 55.126684] twistd[823]: 2026-09-20T07:28:33+0000 [-] sending ping1493buildbot # [ 55.127485] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] message from master: ping1494buildbot # [ 55.130009] twistd[823]: 2026-09-20T07:28:33+0000 [Broker,0,127.0.0.1] ping finished: success1495buildbot # [ 55.154445] twistd[823]: 2026-09-20T07:28:33+0000 [-] <RemoteShellCommand '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']'>: RemoteCommand.run [13]1496buildbot # [ 55.162565] twistd[823]: 2026-09-20T07:28:33+0000 [-] command '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']' in dir 'build'1497buildbot # [ 55.170524] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] (command 13): startCommand:shell1498buildbot # [ 55.171689] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']): RunProcess._startCommand1499buildbot # [ 55.175632] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']): nix build -L --option keep-going true --max-silent-time 1200 --accept-flake-config --out-link result-aarch64-linux.test '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*'1500buildbot # [ 55.181393] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-build/build (timeout 10800 secs)1501buildbot # [ 55.186139] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']): watching logfiles {}1502buildbot # [ 55.190045] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']): argv: [b'nix', b'build', b'-L', b'--option', b'keep-going', b'true', b'--max-silent-time', b'1200', b'--accept-flake-config', b'--out-link', b'result-aarch64-linux.test', b'/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']1503buildbot # [ 55.196548] twistd[824]: 2026-09-20T07:28:33+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']): using PTY: False1504buildbot # [ 55.236969] nix-daemon[1090]: accepted connection from pid 1225, user buildbot-worker1505buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1506buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1507buildbot # Dload Upload Total Spent Left Speed1508buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 34264 0 0100 1127 100 1127 0 0 29409 0 0100 1127 100 1127 0 0 26034 0 01509buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1510buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1511buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1512buildbot # Dload Upload Total Spent Left Speed1513buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 40992 0 0100 1127 100 1127 0 0 34207 0 0100 1127 100 1127 0 0 29705 0 01514buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1515buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1516buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1517buildbot # Dload Upload Total Spent Left Speed1518buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 36435 0 0100 1127 100 1127 0 0 32021 0 0100 1127 100 1127 0 0 28502 0 01519buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.13 seconds)1520buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1521buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1522buildbot # Dload Upload Total Spent Left Speed1523buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 22331 0 0100 1127 100 1127 0 0 19885 0 0100 1127 100 1127 0 0 17891 0 01524buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.17 seconds)1525buildbot # [ 59.873384] twistd[824]: 2026-09-20T07:28:38+0000 [-] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']): command finished with signal None, exit code 0, elapsedTime: 4.7198391526buildbot # [ 59.880336] twistd[824]: 2026-09-20T07:28:38+0000 [-] (command 13): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90dfb950>1527buildbot # [ 59.888514] twistd[823]: 2026-09-20T07:28:38+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-aarch64-linux.test', '/nix/store/aphv0x43ggg93wqzz8pr7f6kypv8y3sa-test.drv^*']'> rc=01528buildbot # [ 59.927550] twistd[823]: 2026-09-20T07:28:38+0000 [-] releaseLocks(NixBuildCommand(name='Build flake attr', command=['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', Interpolate('result-%(prop:attr)s'), Interpolate('%(prop:drv_path)s^*')], timeout=10800, haltOnFailure=True, logEnviron=False)): []1529buildbot # [ 59.943272] twistd[823]: 2026-09-20T07:28:38+0000 [-] step 'Build flake attr' complete: success (None)1530buildbot # [ 59.971691] twistd[823]: 2026-09-20T07:28:38+0000 [-] added buildset 4 to database1531buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1532buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1533buildbot # Dload Upload Total Spent Left Speed1534buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 58251 0 0100 1127 100 1127 0 0 51510 0 0100 1127 100 1127 0 0 42594 0 01535buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.08 seconds)1536buildbot # [ 60.134132] twistd[823]: 2026-09-20T07:28:38+0000 [-] starting build <Build test-flake/nix-register-gcroot number:None results:success> using worker <WorkerForBuilder builder='test-flake/nix-register-gcroot' worker='local-worker-000' state=AVAILABLE>1537buildbot # [ 60.141314] twistd[823]: 2026-09-20T07:28:38+0000 [-] <Build test-flake/nix-register-gcroot number:None results:success>.startBuild1538buildbot # [ 60.188166] twistd[823]: 2026-09-20T07:28:38+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1539buildbot # [ 60.193115] twistd[823]: 2026-09-20T07:28:38+0000 [-] starting build <Build test-flake/nix-register-gcroot number:1 results:success>.. pinging the worker <WorkerForBuilder builder='test-flake/nix-register-gcroot' worker='local-worker-000' state=BUILDING>1540buildbot # [ 60.195972] twistd[823]: 2026-09-20T07:28:38+0000 [-] sending ping1541buildbot # [ 60.198102] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] message from master: ping1542buildbot # [ 60.201681] twistd[823]: 2026-09-20T07:28:38+0000 [Broker,0,127.0.0.1] ping finished: success1543buildbot # [ 60.219344] twistd[823]: 2026-09-20T07:28:38+0000 [-] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']'>: RemoteCommand.run [14]1544buildbot # [ 60.227962] twistd[823]: 2026-09-20T07:28:38+0000 [-] command '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']' in dir 'build'1545buildbot # [ 60.235706] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] (command 14): startCommand:shell1546buildbot # [ 60.238394] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']): RunProcess._startCommand1547buildbot # [ 60.241608] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']): nix-store --add-root /nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test -r /nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test1548buildbot # [ 60.246220] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-register-gcroot/build (timeout 1200 secs)1549buildbot # [ 60.250459] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']): watching logfiles {}1550buildbot # [ 60.256067] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']): argv: [b'nix-store', b'--add-root', b'/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', b'-r', b'/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']1551buildbot # [ 60.261243] twistd[824]: 2026-09-20T07:28:38+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']): using PTY: False1552buildbot # [ 60.303185] nix-daemon[1090]: accepted connection from pid 1258, user buildbot-worker1553buildbot # [ 60.320252] twistd[824]: 2026-09-20T07:28:38+0000 [-] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']): command finished with signal None, exit code 0, elapsedTime: 0.0944051554buildbot # [ 60.328158] twistd[824]: 2026-09-20T07:28:38+0000 [-] (command 14): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90dfba50>1555buildbot # [ 60.336373] twistd[823]: 2026-09-20T07:28:38+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.test', '-r', '/nix/store/4s73dg6jd7g31ryhgw4jiz4l9kji4g0b-test']'> rc=01556buildbot # [ 60.365926] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(ShellCommand(name='Register gcroot', command=['nix-store', '--add-root', Interpolate('/nix/var/nix/gcroots/per-user/buildbot-worker/%(prop:project)s/%(prop:attr)s'), '-r', Property(out_path)], logEnviron=False)): []1557buildbot # [ 60.378434] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'Register gcroot' complete: success (None)1558buildbot # [ 60.380874] twistd[823]: 2026-09-20T07:28:39+0000 [-] <Build test-flake/nix-register-gcroot number:1 results:success>: build finished1559buildbot # [ 60.390153] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1560buildbot # [ 60.532003] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(Trigger(name='Register gcroot', waitForFinish=True, schedulerNames=['test-flake-nix-register-gcroot'], haltOnFailure=True, flunkOnFailure=True, sourceStamps=[], alwaysUseLatest=False, updateSourceStamp=False, doStepIf=<function nix_build_steps.<locals>.<lambda> at 0xf3a4a5307e20>, copy_properties=['out_path', 'attr'], set_properties={'report_status': False})): []1561buildbot # [ 60.546559] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'Register gcroot' complete: success (None)1562buildbot # [ 60.569523] twistd[823]: 2026-09-20T07:28:39+0000 [-] <RemoteShellCommand '['rm', '-f', 'result-aarch64-linux.test']'>: RemoteCommand.run [15]1563buildbot # [ 60.571181] twistd[823]: 2026-09-20T07:28:39+0000 [-] command '['rm', '-f', 'result-aarch64-linux.test']' in dir 'build'1564buildbot # [ 60.575403] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command 15): startCommand:shell1565buildbot # [ 60.580063] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): RunProcess._startCommand1566buildbot # [ 60.581704] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): rm -f result-aarch64-linux.test1567buildbot # [ 60.583372] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-build/build (timeout 1200 secs)1568buildbot # [ 60.588897] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): watching logfiles {}1569buildbot # [ 60.590803] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): argv: [b'rm', b'-f', b'result-aarch64-linux.test']1570buildbot # [ 60.594573] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): using PTY: False1571buildbot # [ 60.597747] twistd[824]: 2026-09-20T07:28:39+0000 [-] (command ['rm', '-f', 'result-aarch64-linux.test']): command finished with signal None, exit code 0, elapsedTime: 0.0292391572buildbot # [ 60.599715] twistd[824]: 2026-09-20T07:28:39+0000 [-] (command 15): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90804050>1573buildbot # [ 60.621474] twistd[823]: 2026-09-20T07:28:39+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['rm', '-f', 'result-aarch64-linux.test']'> rc=01574buildbot # [ 60.647156] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(ShellCommand(name='Delete temporary gcroots', command=['rm', '-f', Interpolate('result-%(prop:attr)s')], logEnviron=False)): []1575buildbot # [ 60.657244] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'Delete temporary gcroots' complete: success (None)1576buildbot # [ 60.671881] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(UpdateBuildOutput(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xf3a4a528c1a0>, name='Update build output', path=PosixPath('/var/lib/buildbot-outputs'), branch_config={})): []1577buildbot # [ 60.680740] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'Update build output' complete: success (None)1578buildbot # [ 60.682028] twistd[823]: 2026-09-20T07:28:39+0000 [-] <Build test-flake/nix-build number:1 results:success>: build finished1579buildbot # [ 60.704110] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1580buildbot # [ 60.857091] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(BuildTrigger(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xf3a4a528c1a0>, trigger_config=TriggerConfig(builds_scheduler='test-flake-nix-build', failed_eval_scheduler='test-flake-nix-failed-eval', dependency_failed_scheduler='test-flake-nix-dependency-failed', cached_failure_scheduler='test-flake-nix-cached-failure'), jobs_config=JobsConfig(successful_jobs=[], failed_jobs=[], cache_failed_builds=False, failed_build_report_limit=47), nix_attr_prefix='checks', name='build flake')): []1581buildbot # [ 60.888554] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'build flake' complete: failure (['(1 success, 1 failure, 1 skipped)'])1582buildbot # [ 60.908076] twistd[823]: 2026-09-20T07:28:39+0000 [-] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']'>: RemoteCommand.run [16]1583buildbot # [ 60.911188] twistd[823]: 2026-09-20T07:28:39+0000 [-] command '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']' in dir 'build'1584buildbot # [ 60.923578] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command 16): startCommand:shell1585buildbot # [ 60.925160] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']): RunProcess._startCommand1586buildbot # [ 60.929037] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']): nix-store --add-root /nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test -r /nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test1587buildbot # [ 60.934283] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1588buildbot # [ 60.938938] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']): watching logfiles {}1589buildbot # [ 60.942660] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']): argv: [b'nix-store', b'--add-root', b'/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', b'-r', b'/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']1590buildbot # [ 60.948207] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']): using PTY: False1591buildbot # [ 60.987332] nix-daemon[1090]: accepted connection from pid 1271, user buildbot-worker1592buildbot # [ 61.004855] twistd[824]: 2026-09-20T07:28:39+0000 [-] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']): command finished with signal None, exit code 0, elapsedTime: 0.0985451593buildbot # [ 61.013975] twistd[824]: 2026-09-20T07:28:39+0000 [-] (command 16): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d90804500>1594buildbot # [ 61.016987] twistd[823]: 2026-09-20T07:28:39+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/aarch64-linux.skippable-test', '-r', '/nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test']'> rc=01595buildbot # [ 61.047469] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(ProcessSkippedBuilds(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xf3a4a528c1a0>, gcroots_user='buildbot-worker', branch_config={}, outputs_path=PosixPath('/var/lib/buildbot-outputs'), name='Process skipped builds', doStepIf=<function nix_eval_config.<locals>.<lambda> at 0xf3a4a5307060>, hideStepIf=<function nix_eval_config.<locals>.<lambda> at 0xf3a4a5307110>)): []1596buildbot # [ 61.059872] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'Process skipped builds' complete: success (None)1597buildbot # [ 61.077416] twistd[823]: 2026-09-20T07:28:39+0000 [-] <RemoteShellCommand '['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']'>: RemoteCommand.run [17]1598buildbot # [ 61.079691] twistd[823]: 2026-09-20T07:28:39+0000 [-] command '['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']' in dir 'build'1599buildbot # [ 61.088420] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command 17): startCommand:shell1600buildbot # [ 61.089600] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): RunProcess._startCommand1601buildbot # [ 61.091745] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): rm -rf /nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/1602buildbot # [ 61.097769] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1603buildbot # [ 61.101251] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): watching logfiles {}1604buildbot # [ 61.105212] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): argv: [b'rm', b'-rf', b'/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']1605buildbot # [ 61.109870] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): using PTY: False1606buildbot # [ 61.112059] twistd[824]: 2026-09-20T07:28:39+0000 [-] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): command finished with signal None, exit code 0, elapsedTime: 0.0337391607buildbot # [ 61.114544] twistd[824]: 2026-09-20T07:28:39+0000 [-] (command 17): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d907c9630>1608buildbot # [ 61.129974] twistd[823]: 2026-09-20T07:28:39+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']'> rc=01609buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1610buildbot # [ 61.159978] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(ShellCommand(name='Cleanup drv paths', command=['rm', '-rf', Interpolate('/nix/var/nix/gcroots/per-user/buildbot-worker/%(prop:project)s/drvs/%(prop:workername)s/')], alwaysRun=True, logEnviron=False)): []1611buildbot # [ 61.179266] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'Cleanup drv paths' complete: success (None)1612buildbot # [ 61.199911] twistd[823]: 2026-09-20T07:28:39+0000 [-] releaseLocks(BuildbotEffectsCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xf3a4a528c1a0>, env={}, name='Evaluate effects', command=['buildbot-effects', 'list', '--rev', Property(revision), '--branch', Property(branch), '--repo', Property(project)], flunkOnFailure=True, doStepIf=<function nix_eval_config.<locals>.<lambda> at 0xf3a4a53075e0>, logEnviron=False)): []1613buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1614buildbot # Dload Upload Total Spent [ 61.215906] twistd[823]: 2026-09-20T07:28:39+0000 [-] step 'Evaluate effects' complete: skipped (None)1615buildbot # Left Speed1616buildbot # 0 0 0 0 0 0 0 0 0100 1521 100 1521 0 0 65670 0 0100 1521 100 1521 0 0 59593 0 0100 1521 100 1521 0 0 52952 0 01617buildbot # [ 61.248543] twistd[823]: 2026-09-20T07:28:39+0000 [-] <RemoteShellCommand '['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']'>: RemoteCommand.run [18]1618buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.12 seconds)1619buildbot # [ 61.259621] twistd[823]: 2026-09-20T07:28:39+0000 [-] command '['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']' in dir 'build'1620buildbot # [ 61.265233] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command 18): startCommand:shell1621buildbot # [ 61.266415] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']): RunProcess._startCommand1622buildbot # [ 61.270056] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']): buildbot-effects list-schedules --rev 4cb3011a4302342580d5fac2c56674393ff915b2 --branch master --repo test-flake1623buildbot # [ 61.274135] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1624buildbot # [ 61.280781] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']): watching logfiles {}1625buildbot # [ 61.283313] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']): argv: [b'buildbot-effects', b'list-schedules', b'--rev', b'4cb3011a4302342580d5fac2c56674393ff915b2', b'--branch', b'master', b'--repo', b'test-flake']1626buildbot # [ 61.287635] twistd[824]: 2026-09-20T07:28:39+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']): using PTY: False1627buildbot # [ 61.470871] nix-daemon[1090]: accepted connection from pid 1283, user buildbot-worker1628buildbot # [ 61.508107] twistd[824]: 2026-09-20T07:28:40+0000 [-] (command ['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']): command finished with signal None, exit code 0, elapsedTime: 0.2503881629buildbot # [ 61.511143] twistd[824]: 2026-09-20T07:28:40+0000 [-] (command 18): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xea1d907c9a90>1630buildbot # [ 61.516922] twistd[823]: 2026-09-20T07:28:40+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['buildbot-effects', 'list-schedules', '--rev', '4cb3011a4302342580d5fac2c56674393ff915b2', '--branch', 'master', '--repo', 'test-flake']'> rc=01631buildbot # [ 61.549397] twistd[823]: 2026-09-20T07:28:40+0000 [-] releaseLocks(ScheduledEffectsEvaluateCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xf3a4a528c1a0>, schedules_cache_file='/var/lib/buildbot/scheduled-effects-cache/test-flake-schedules.json', env={}, name='Evaluate scheduled effects', command=['buildbot-effects', 'list-schedules', '--rev', Property(revision), '--branch', Property(branch), '--repo', Property(project)], flunkOnFailure=False, warnOnFailure=True, alwaysRun=True, doStepIf=<function nix_eval_config.<locals>.<lambda> at 0xf3a4a53078a0>, hideStepIf=<function nix_eval_config.<locals>.<lambda> at 0xf3a4a5307950>, logEnviron=False)): []1632buildbot # [ 61.565265] twistd[823]: 2026-09-20T07:28:40+0000 [-] step 'Evaluate scheduled effects' complete: success (None)1633buildbot # [ 61.567881] twistd[823]: 2026-09-20T07:28:40+0000 [-] <Build test-flake/nix-eval number:1 results:failure>: build finished1634buildbot # [ 61.585800] twistd[823]: 2026-09-20T07:28:40+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1635buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1636buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1637buildbot # Dload Upload Total Spent Left Speed1638buildbot # 0 0 0 0 0 0 0 0 0100 1530 100 1530 0 0 68450 0 0100 1530 100 1530 0 0 52647 0 0100 1530 100 1530 0 0 45368 0 01639buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1640buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/2/properties1641buildbot # [ 62.390012] sshd-session[1297]: Accepted publickey for root from ::1 port 41044 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81642buildbot # [ 62.404294] sshd-session[1297]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1643buildbot # % Total % Received % Xferd Average Speed Time Time [ 62.424420] systemd-logind[400]: New session '11' of user 'root' with class 'user' and type 'tty'.1644buildbot # Time Current1645buildbot # Dload Upload Total [ 62.428951] systemd[1]: Started Session 11 of User root.1646buildbot # Spent Left Speed1647buildbot # 0 0 0 0 0 0 0 0 0100 2172 100 2172 0 0 79917 0 0100 2172 100 2172 0 0 73151 0 0100 2172 100 2172 0 0 66915 0 01648buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/2/properties, in 0.09 seconds)1649buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/4/properties1650buildbot # [ 62.470515] sshd-session[1306]: Received disconnect from ::1 port 41044:11: disconnected by user1651buildbot # [ 62.471711] sshd-session[1306]: Disconnected from user root ::1 port 410441652buildbot # [ 62.478716] sshd-session[1297]: pam_unix(sshd:session): session closed for user root1653buildbot # [ 62.481836] systemd[1]: session-11.scope: Deactivated successfully.1654buildbot # [ 62.490625] systemd-logind[400]: Session 11 logged out. Waiting for processes to exit.1655buildbot # [ 62.495818] systemd-logind[400]: Removed session 11.1656buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1657buildbot # Dload Upload Total Spent Left Speed1658buildbot # 0 0 0 0 0 0 0 0 0100 1548 100 1548 0 0 46361 0 0100 1548 100 1548 0 0 42233 0 0100 1548 100 1548 0 0 35989 0 01659buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/4/properties, in 0.12 seconds)1660buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/3/properties1661buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1662buildbot # Dload Upload Total Spent Left Speed1663buildbot # 0 0 0 0 0 0 0 0 0100 2132 100 2132 0 0 181.4k 0 0100 2132 100 2132 0 0 149.7k 0 0100 2132 100 2132 0 0 102.7k 0 01664buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/3/properties, in 0.05 seconds)1665buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/1/properties1666buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1667buildbot # Dload Upload Total Spent Left Speed1668buildbot # 0 0 0 0 0 0 0 0 0100 2489 100 2489 0 0 210.0k 0 0100 2489 100 2489 0 0 174.0k 0 0100 2489 100 2489 0 0 149.1k 0 01669buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/1/properties, in 0.04 seconds)1670(finished: subtest: Poller triggers builds and verifies results, in 38.01 seconds)1671subtest: Verify output paths are written for skipped builds1672buildbot: must succeed: nix config show system1673buildbot # [ 62.698404] sshd-session[1314]: Accepted publickey for root from ::1 port 41048 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81674buildbot # [ 62.708222] sshd-session[1314]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1675buildbot # [ 62.718837] systemd-logind[400]: New session '12' of user 'root' with class 'user' and type 'tty'.1676buildbot # [ 62.726535] systemd[1]: Started Session 12 of User root.1677buildbot: (finished: must succeed: nix config show system, in 0.09 seconds)1678buildbot: must succeed: 1679 nix eval --raw /tmp/test-flake#checks.aarch64-linux.skippable-test.outPath1680 1681buildbot # [ 62.785287] sshd-session[1326]: Received disconnect from ::1 port 41048:11: disconnected by user1682buildbot # [ 62.786588] sshd-session[1326]: Disconnected from user root ::1 port 410481683buildbot # [ 62.790346] sshd-session[1314]: pam_unix(sshd:session): session closed for user root1684buildbot # [ 62.794686] systemd[1]: session-12.scope: Deactivated successfully.1685buildbot # [ 62.798654] systemd-logind[400]: Session 12 logged out. Waiting for processes to exit.1686buildbot # [ 62.799776] systemd-logind[400]: Removed session 12.1687buildbot # [ 62.810226] twistd[823]: 2026-09-20T07:28:41+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1688buildbot: (finished: must succeed: 1689 nix eval --raw /tmp/test-flake#checks.aarch64-linux.skippable-test.outPath1690 , in 0.15 seconds)1691buildbot: waiting for success: test -f /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test1692buildbot: (finished: waiting for success: test -f /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test, in 0.01 seconds)1693buildbot: must succeed: cat /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test1694buildbot: (finished: must succeed: cat /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test, in 0.02 seconds)1695(finished: subtest: Verify output paths are written for skipped builds, in 0.28 seconds)1696(finished: run the VM test script, in 63.77 seconds)1697test script finished in 63.81s1698cleanup1699kill QemuMachine (pid 45)1700buildbot # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)1701buildbot # [2026-09-20T07:28:41Z INFO virtiofsd] Client disconnected, shutting down1702buildbot # [2026-09-20T07:28:41Z INFO virtiofsd] Client disconnected, shutting down1703buildbot # [2026-09-20T07:28:41Z INFO virtiofsd] Client disconnected, shutting down1704(finished: cleanup, in 0.05 seconds)1705additionally exposed symbols:1706 buildbot,1707 vlan1,1708 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_ssh1709Poller has started processing1710Pushed new commit to repository1711Expected output path for skippable-test: /nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test