vm-test-run-buildbot-nix-poller
checks.aarch64-linux.poller
· build #65
· 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: QEMU running (pid 45)11buildbot # Disk image does not exist, creating the virtualisation disk image...12buildbot # Formatting '/build/vm-state-buildbot/tmp.TCfwL5tjD7', 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: 6c1c5b99-2e66-41b0-afd2-fb2b89ba55c017buildbot # 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-13T07:11:05Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28buildbot # [2026-09-13T07:11:05Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29buildbot # [2026-09-13T07:11:05Z INFO virtiofsd] Waiting for vhost-user socket connection...30buildbot # [2026-09-13T07:11:05Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31buildbot # [2026-09-13T07:11:05Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32buildbot # [2026-09-13T07:11:05Z INFO virtiofsd] Waiting for vhost-user socket connection...33buildbot # [2026-09-13T07:11:05Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34buildbot # [2026-09-13T07:11:05Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35buildbot # [2026-09-13T07:11:05Z INFO virtiofsd] Waiting for vhost-user socket connection...36buildbot # [2026-09-13T07:11:05Z INFO virtiofsd] Client connected, servicing requests37buildbot # [2026-09-13T07:11:05Z INFO virtiofsd] Client connected, servicing requests38buildbot # [2026-09-13T07:11:05Z INFO virtiofsd] Client connected, servicing requests39buildbot # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]40buildbot # [ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 11 09:49:46 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 s186648 r8192 d116456 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/1bcrzv0r56n27b4dc0bf6xdnaqflq0zq-nixos-system-buildbot-test/init regInfo=/nix/store/jxwpyznlqrd7f0qdc5azdgw35b4ybi9z-closure-info/registration console=ttyAMA0,115200n8 console=tty074buildbot # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/jxwpyznlqrd7f0qdc5azdgw35b4ybi9z-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 74894 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 @44ce0000 (indirect, esz 8, psz 64K, shr 1)109buildbot # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (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.000030] arm-pv: using stolen time PV118buildbot # [ 0.000416] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)119buildbot # [ 0.000566] Console: colour dummy device 80x25120buildbot # [ 0.000574] printk: legacy console [tty0] enabled121buildbot # [ 0.000756] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)122buildbot # [ 0.000762] pid_max: default: 32768 minimum: 301123buildbot # [ 0.000828] LSM: initializing lsm=capability,landlock,yama,bpf,ima124buildbot # [ 0.000949] landlock: Up and running.125buildbot # [ 0.000952] Yama: becoming mindful.126buildbot # [ 0.001399] LSM support for eBPF active127buildbot # [ 0.001518] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)128buildbot # [ 0.001537] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)129buildbot # [ 0.002596] cacheinfo: Unable to detect cache hierarchy for CPU 0130buildbot # [ 0.003335] rcu: Hierarchical SRCU implementation.131buildbot # [ 0.003339] rcu: Max phase no-delay instances is 1000.132buildbot # [ 0.004515] fsl-mc MSI: its@8080000 domain created133buildbot # [ 0.004604] EFI services will not be available.134buildbot # [ 0.004666] smp: Bringing up secondary CPUs ...135buildbot # [ 0.004673] smp: Brought up 1 node, 1 CPU136buildbot # [ 0.004676] SMP: Total of 1 processors activated.137buildbot # [ 0.004679] CPU: All CPU(s) started at EL1138buildbot # [ 0.004692] CPU features: detected: Branch Target Identification139buildbot # [ 0.004697] CPU features: detected: ARMv8.4 Translation Table Level140buildbot # [ 0.004700] CPU features: detected: Instruction cache invalidation not required for I/D coherence141buildbot # [ 0.004703] CPU features: detected: Data cache clean to the PoU not required for I/D coherence142buildbot # [ 0.004707] CPU features: detected: Common not Private translations143buildbot # [ 0.004710] CPU features: detected: CRC32 instructions144buildbot # [ 0.004713] CPU features: detected: Data cache clean to Point of Deep Persistence145buildbot # [ 0.004716] CPU features: detected: Data cache clean to Point of Persistence146buildbot # [ 0.004720] CPU features: detected: Data independent timing control (DIT)147buildbot # [ 0.004723] CPU features: detected: E0PD148buildbot # [ 0.004726] CPU features: detected: Enhanced Counter Virtualization149buildbot # [ 0.004729] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)150buildbot # [ 0.004732] CPU features: detected: Enhanced Virtualization Traps151buildbot # [ 0.004735] CPU features: detected: Fine Grained Traps152buildbot # [ 0.004739] CPU features: detected: Generic authentication (architected QARMA5 algorithm)153buildbot # [ 0.004743] CPU features: detected: RCpc load-acquire (LDAPR)154buildbot # [ 0.004746] CPU features: detected: LSE atomic instructions155buildbot # [ 0.004749] CPU features: detected: Privileged Access Never156buildbot # [ 0.004752] CPU features: detected: PMUv3157buildbot # [ 0.004755] CPU features: detected: RAS Extension Support158buildbot # [ 0.004758] CPU features: detected: RASv1p1 Extension Support159buildbot # [ 0.004760] CPU features: detected: Random Number Generator160buildbot # [ 0.004763] CPU features: detected: Speculation barrier (SB)161buildbot # [ 0.004765] CPU features: detected: Stage-2 Force Write-Back162buildbot # [ 0.004768] CPU features: detected: TLB range maintenance instructions163buildbot # [ 0.004773] CPU features: detected: Speculative Store Bypassing Safe (SSBS)164buildbot # [ 0.004808] alternatives: applying system-wide alternatives165buildbot # [ 0.007700] CPU features: detected: BBM Level 2 without TLB conflict abort166buildbot # [ 0.007900] Memory: 893504K/1048576K available (24448K kernel code, 7090K rwdata, 26360K rodata, 4736K init, 1107K bss, 113760K reserved, 32768K cma-reserved)167buildbot # [ 0.008229] devtmpfs: initialized168buildbot # [ 0.009858] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)169buildbot # [ 0.009879] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).170buildbot # [ 0.010052] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL171buildbot # [ 0.010057] 0 pages in range for non-PLT usage172buildbot # [ 0.010057] 508288 pages in range for PLT usage173buildbot # [ 0.010161] pinctrl core: initialized pinctrl subsystem174buildbot # [ 0.010896] DMI not present or invalid.175buildbot # [ 0.013916] NET: Registered PF_NETLINK/PF_ROUTE protocol family176buildbot # [ 0.016110] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations177buildbot # [ 0.016264] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations178buildbot # [ 0.016419] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations179buildbot # [ 0.016441] audit: initializing netlink subsys (disabled)180buildbot # [ 0.017006] thermal_sys: Registered thermal governor 'fair_share'181buildbot # [ 0.017007] thermal_sys: Registered thermal governor 'bang_bang'182buildbot # [ 0.017011] thermal_sys: Registered thermal governor 'step_wise'183buildbot # [ 0.017014] thermal_sys: Registered thermal governor 'user_space'184buildbot # [ 0.017018] thermal_sys: Registered thermal governor 'power_allocator'185buildbot # [ 0.017041] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1186buildbot # [ 0.017051] cpuidle: using governor ladder187buildbot # [ 0.017056] cpuidle: using governor menu188buildbot # [ 0.017251] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.189buildbot # [ 0.017265] ASID allocator initialised with 65536 entries190buildbot # [ 0.018533] Serial: AMBA PL011 UART driver191buildbot # [ 0.023546] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1192buildbot # [ 0.023666] printk: console [ttyAMA0] enabled193buildbot # [ 0.148308] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages194buildbot # [ 0.148325] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page195buildbot # [ 0.148331] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages196buildbot # [ 0.148335] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page197buildbot # [ 0.148339] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages198buildbot # [ 0.148343] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page199buildbot # [ 0.148348] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages200buildbot # [ 0.148352] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page201buildbot # [ 0.155843] fbcon: Taking over console202buildbot # [ 0.155858] ACPI: Interpreter disabled.203buildbot # [ 0.157734] iommu: Default domain type: Translated204buildbot # [ 0.157743] iommu: DMA domain TLB invalidation policy: strict mode205buildbot # [ 0.159436] SCSI subsystem initialized206buildbot # [ 0.164705] usbcore: registered new interface driver usbfs207buildbot # [ 0.164741] usbcore: registered new interface driver hub208buildbot # [ 0.164760] usbcore: registered new device driver usb209buildbot # [ 0.165023] pps_core: LinuxPPS API ver. 1 registered210buildbot # [ 0.165028] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>211buildbot # [ 0.165045] PTP clock support registered212buildbot # [ 0.165091] EDAC MC: Ver: 3.0.0213buildbot # [ 0.169842] scmi_core: SCMI protocol bus registered214buildbot # [ 0.170809] FPGA manager framework215buildbot # [ 0.171774] vgaarb: loaded216buildbot # [ 0.172391] clocksource: Switched to clocksource arch_sys_counter217buildbot # [ 0.178690] VFS: Disk quotas dquot_6.6.0218buildbot # [ 0.178735] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)219buildbot # [ 0.180337] netfs: FS-Cache loaded220buildbot # [ 0.180886] pnp: PnP ACPI: disabled221buildbot # [ 0.184295] NET: Registered PF_INET protocol family222buildbot # [ 0.185069] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)223buildbot # [ 0.213142] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)224buildbot # [ 0.213181] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)225buildbot # [ 0.213204] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)226buildbot # [ 0.213246] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)227buildbot # [ 0.213321] TCP: Hash tables configured (established 8192 bind 8192)228buildbot # [ 0.213396] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)229buildbot # [ 0.213457] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)230buildbot # [ 0.213482] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)231buildbot # [ 0.213560] NET: Registered PF_UNIX/PF_LOCAL protocol family232buildbot # [ 0.213586] NET: Registered PF_XDP protocol family233buildbot # [ 0.213605] PCI: CLS 0 bytes, default 64234buildbot # [ 0.213829] Trying to unpack rootfs image as initramfs...235buildbot # [ 0.229792] kvm [1]: HYP mode not available236buildbot # [ 0.320882] Initialise system trusted keyrings237buildbot # [ 0.321660] workingset: timestamp_bits=42 max_order=18 bucket_order=0238buildbot # [ 0.322945] squashfs: version 4.0 (2009/01/31) Phillip Lougher239buildbot # [ 0.323756] 9p: Installing v9fs 9p2000 file system support240buildbot # [ 0.344369] Key type asymmetric registered241buildbot # [ 0.344387] Asymmetric key parser 'x509' registered242buildbot # [ 0.352504] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)243buildbot # [ 0.353576] io scheduler mq-deadline registered244buildbot # [ 0.353587] io scheduler kyber registered245buildbot # [ 0.364524] pl061_gpio 9030000.pl061: PL061 GPIO chip registered246buildbot # [ 0.365889] ledtrig-cpu: registered to indicate activity on CPUs247buildbot # [ 0.366254] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:248buildbot # [ 0.366270] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000249buildbot # [ 0.366283] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000250buildbot # [ 0.366292] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000251buildbot # [ 0.366327] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits252buildbot # [ 0.366350] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]253buildbot # [ 0.366420] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00254buildbot # [ 0.366430] pci_bus 0000:00: root bus resource [bus 00-ff]255buildbot # [ 0.366436] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]256buildbot # [ 0.366442] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]257buildbot # [ 0.366447] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]258buildbot # [ 0.366500] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint259buildbot # [ 0.366950] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint260buildbot # [ 0.367136] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]261buildbot # [ 0.367153] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]262buildbot # [ 0.367183] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263buildbot # [ 0.367200] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]264buildbot # [ 0.367656] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint265buildbot # [ 0.367842] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]266buildbot # [ 0.367859] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]267buildbot # [ 0.367889] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]268buildbot # [ 0.368356] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint269buildbot # [ 0.389270] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]270buildbot # [ 0.389290] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]271buildbot # [ 0.389320] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]272buildbot # [ 0.389777] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint273buildbot # [ 0.389963] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]274buildbot # [ 0.389979] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]275buildbot # [ 0.390009] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]276buildbot # [ 0.390028] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]277buildbot # [ 0.390483] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint278buildbot # [ 0.390679] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]279buildbot # [ 0.390710] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]280buildbot # [ 0.391164] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint281buildbot # [ 0.391353] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]282buildbot # [ 0.391384] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]283buildbot # [ 0.391786] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint284buildbot # [ 0.391969] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]285buildbot # [ 0.392214] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint286buildbot # [ 0.412455] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]287buildbot # [ 0.412497] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288buildbot # [ 0.412985] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint289buildbot # [ 0.413176] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]290buildbot # [ 0.413207] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]291buildbot # [ 0.413650] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint292buildbot # [ 0.413834] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]293buildbot # [ 0.413864] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]294buildbot # [ 0.414312] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint295buildbot # [ 0.414566] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]296buildbot # [ 0.414584] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]297buildbot # [ 0.414614] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]298buildbot # [ 0.415072] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint299buildbot # [ 0.415253] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]300buildbot # [ 0.415268] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]301buildbot # [ 0.415298] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302buildbot # [ 0.415854] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned303buildbot # [ 0.415866] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned304buildbot # [ 0.415871] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned305buildbot # [ 0.415915] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned306buildbot # [ 0.415962] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned307buildbot # [ 0.416008] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned308buildbot # [ 0.416055] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned309buildbot # [ 0.416101] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned310buildbot # [ 0.416148] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned311buildbot # [ 0.416195] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned312buildbot # [ 0.416240] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned313buildbot # [ 0.416286] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned314buildbot # [ 0.416356] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned315buildbot # [ 0.442146] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned316buildbot # [ 0.442172] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned317buildbot # [ 0.442194] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned318buildbot # [ 0.442216] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned319buildbot # [ 0.442238] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned320buildbot # [ 0.442267] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned321buildbot # [ 0.442289] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned322buildbot # [ 0.442311] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned323buildbot # [ 0.442338] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned324buildbot # [ 0.442360] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned325buildbot # [ 0.442383] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned326buildbot # [ 0.442406] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned327buildbot # [ 0.442428] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned328buildbot # [ 0.442449] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned329buildbot # [ 0.442471] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned330buildbot # [ 0.442492] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned331buildbot # [ 0.442513] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned332buildbot # [ 0.442535] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned333buildbot # [ 0.442561] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]334buildbot # [ 0.442571] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]335buildbot # [ 0.442575] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]336buildbot # [ 0.443379] pci 0000:00:07.0: enabling device (0000 -> 0002)337buildbot # [ 0.487388] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)338buildbot # [ 0.493587] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)339buildbot # [ 0.495523] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)340buildbot # [ 0.505658] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)341buildbot # [ 0.507599] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)342buildbot # [ 0.517930] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)343buildbot # [ 0.519563] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)344buildbot # [ 0.522857] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)345buildbot # [ 0.529230] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)346buildbot # [ 0.532220] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)347buildbot # [ 0.542468] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)348buildbot # [ 0.557634] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled349buildbot # [ 0.560127] msm_serial: driver initialized350buildbot # [ 0.560276] SuperH (H)SCI(F) driver initialized351buildbot # [ 0.560329] STM32 USART driver initialized352buildbot # [ 0.591097] loop: module loaded353buildbot # [ 0.591276] virtio_blk virtio2: 1/0/0 default/read/poll queues354buildbot # [ 0.592025] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)355buildbot # [ 0.596929] megasas: 07.734.00.00-rc1356buildbot # [ 0.597598] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]357buildbot # [ 0.600177] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000358buildbot # [ 0.600203] Intel/Sharp Extended Query Table at 0x0031359buildbot # [ 0.609311] Using buffer write method360buildbot # [ 0.609383] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]361buildbot # [ 0.610902] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000362buildbot # [ 0.610925] Intel/Sharp Extended Query Table at 0x0031363buildbot # [ 0.624413] Using buffer write method364buildbot # [ 0.624438] Concatenating MTD devices:365buildbot # [ 0.624442] (0): "0.flash"366buildbot # [ 0.624446] (1): "0.flash"367buildbot # [ 0.624449] into device "0.flash"368buildbot # [ 0.850616] Freeing initrd memory: 26900K369buildbot # [ 0.856589] tun: Universal TUN/TAP device driver, 1.6370buildbot # [ 0.860216] thunder_xcv, ver 1.0371buildbot # [ 0.860255] thunder_bgx, ver 1.0372buildbot # [ 0.860277] nicpf, ver 1.0373buildbot # [ 0.862048] e1000: Intel(R) PRO/1000 Network Driver374buildbot # [ 0.862060] e1000: Copyright (c) 1999-2006 Intel Corporation.375buildbot # [ 0.862087] e1000e: Intel(R) PRO/1000 Network Driver376buildbot # [ 0.862096] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.377buildbot # [ 0.862124] igb: Intel(R) Gigabit Ethernet Network Driver378buildbot # [ 0.862130] igb: Copyright (c) 2007-2014 Intel Corporation.379buildbot # [ 0.862152] igbvf: Intel(R) Gigabit Virtual Function Network Driver380buildbot # [ 0.862158] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.381buildbot # [ 0.862295] sky2: driver version 1.30382buildbot # [ 0.863884] usbcore: registered new interface driver usb-storage383buildbot # [ 0.863931] usbcore: registered new interface driver usbserial_generic384buildbot # [ 0.863945] usbserial: USB Serial support registered for generic385buildbot # [ 0.865095] ehci-pci 0000:00:07.0: EHCI Host Controller386buildbot # [ 0.865126] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1387buildbot # [ 0.865340] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000388buildbot # [ 0.875943] hv_vmbus: registering driver hyperv_keyboard389buildbot # [ 0.876434] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00390buildbot # [ 0.876718] hub 1-0:1.0: USB hub found391buildbot # [ 0.876739] hub 1-0:1.0: 6 ports detected392buildbot # [ 0.879781] rtc-pl031 9010000.pl031: registered as rtc0393buildbot # [ 0.879807] rtc-pl031 9010000.pl031: setting system clock to 2026-09-13T07:11:06 UTC (1789283466)394buildbot # [ 0.880130] i2c_dev: i2c /dev entries driver395buildbot # [ 0.885462] sdhci: Secure Digital Host Controller Interface driver396buildbot # [ 0.885471] sdhci: Copyright(c) Pierre Ossman397buildbot # [ 0.885733] Synopsys Designware Multimedia Card Interface Driver398buildbot # [ 0.886102] sdhci-pltfm: SDHCI platform and OF driver helper399buildbot # [ 0.887594] hid: raw HID events driver (C) Jiri Kosina400buildbot # [ 0.887824] usbcore: registered new interface driver usbhid401buildbot # [ 0.887831] usbhid: USB HID core driver402buildbot # [ 0.893412] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available403buildbot # [ 0.894895] drop_monitor: Initializing network drop monitor service404buildbot # [ 0.895014] NET: Registered PF_INET6 protocol family405buildbot # [ 0.898062] Segment Routing with IPv6406buildbot # [ 0.898091] In-situ OAM (IOAM) with IPv6407buildbot # [ 0.898138] NET: Registered PF_PACKET protocol family408buildbot # [ 0.899763] 9pnet: Installing 9P2000 support409buildbot # [ 0.899805] Key type dns_resolver registered410buildbot # [ 0.906561] registered taskstats version 1411buildbot # [ 0.906706] Loading compiled-in X.509 certificates412buildbot # [ 0.914989] Demotion targets for Node 0: null413buildbot # [ 0.915083] Key type .fscrypt registered414buildbot # [ 0.915093] Key type fscrypt-provisioning registered415buildbot # [ 0.915178] ima: No TPM chip found, activating TPM-bypass!416buildbot # [ 0.915197] ima: Allocated hash algorithm: sha1417buildbot # [ 0.915219] ima: No architecture policies found418buildbot # [ 0.919135] input: gpio-keys as /devices/platform/gpio-keys/input/input0419buildbot # [ 0.937391] clk: Disabling unused clocks420buildbot # [ 0.937415] PM: genpd: Disabling unused power domains421buildbot # [ 0.941615] Freeing unused kernel memory: 4736K422buildbot # [ 0.941806] Run /init as init process423buildbot # [ 0.958487] systemd[1]: Successfully made /usr/ read-only.424buildbot # [ 1.124462] usb 1-1: new high-speed USB device number 2 using ehci-pci425buildbot # [ 1.276937] 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/input1426buildbot # [ 1.293029] 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)427buildbot # [ 1.305316] systemd[1]: Detected virtualization qemu.428buildbot # [ 1.307387] systemd[1]: Detected architecture arm64.429buildbot # [ 1.309316] systemd[1]: Running in initrd.430buildbot # [ 1.311822] systemd[1]: Initializing machine ID from random generator.431buildbot # [ 1.314713] systemd[1]: Hostname set to <buildbot>.432buildbot # [ 1.360657] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0433buildbot # [ 1.484431] usb 1-2: new high-speed USB device number 3 using ehci-pci434buildbot # [ 1.612920] systemd[1]: bpf-restrict-fs: LSM BPF program attached435buildbot # [ 1.643044] 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/input2436buildbot # [ 1.648704] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0437buildbot # [ 1.718944] systemd[1]: Queued start job for default target Initrd Default Target.438buildbot # [ 1.726617] systemd[1]: Created slice Slice /system/modprobe.439buildbot # [ 1.727700] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.440buildbot # [ 1.728982] systemd[1]: Expecting device /dev/disk/by-label/nixos...441buildbot # [ 1.729939] systemd[1]: Reached target Path Units.442buildbot # [ 1.730690] systemd[1]: Reached target Slice Units.443buildbot # [ 1.731444] systemd[1]: Reached target Swaps.444buildbot # [ 1.732110] systemd[1]: Reached target Timer Units.445buildbot # [ 1.733070] systemd[1]: Listening on D-Bus System Message Bus Socket.446buildbot # [ 1.734177] systemd[1]: Listening on Journal Socket (/dev/log).447buildbot # [ 1.735219] systemd[1]: Listening on Journal Sockets.448buildbot # [ 1.736123] systemd[1]: Listening on udev Control Socket.449buildbot # [ 1.737184] systemd[1]: Listening on udev Kernel Socket.450buildbot # [ 1.737993] systemd[1]: Reached target Socket Units.451buildbot # [ 1.740213] systemd[1]: Starting Create List of Static Device Nodes...452buildbot # [ 1.741353] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs453buildbot # [ 1.751739] systemd[1]: Mounting Kernel Configuration File System...454buildbot # [ 1.776618] systemd[1]: Starting Journal Service...455buildbot # [ 1.784565] systemd[1]: Starting Load Kernel Modules...456buildbot # [ 1.785488] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os457buildbot # [ 1.793379] systemd[1]: Starting Coldplug All udev Devices...458buildbot # [ 1.812839] systemd[1]: Finished Create List of Static Device Nodes.459buildbot # [ 1.813657] systemd[1]: Mounted Kernel Configuration File System.460buildbot # [ 1.824838] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...461buildbot # [ 1.855439] systemd-journald[72]: Collecting audit messages is disabled.462buildbot # [ 1.865029] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.463buildbot # [ 1.867390] systemd[1]: Starting Create Static Device Nodes in /dev...464buildbot # [ 1.879659] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.465buildbot # [ 1.888690] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev466buildbot # [ 1.901549] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0467buildbot # [ 1.901781] [drm] features: -virgl +edid -resource_blob -host_visible468buildbot # [ 1.901791] [drm] features: -context_init469buildbot # [ 1.902590] [drm] number of scanouts: 1470buildbot # [ 1.902607] [drm] number of cap sets: 0471buildbot # [ 1.917001] systemd[1]: Finished Create Static Device Nodes in /dev.472buildbot # [ 1.917314] systemd[1]: Reached target Preparation for Local File Systems.473buildbot # [ 1.917343] systemd[1]: Reached target Local File Systems.474buildbot # [ 1.924743] systemd[1]: Starting Rule-based Manager for Device Events and Files...475buildbot # [ 1.932535] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic476buildbot # [ 1.932558] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0477buildbot # [ 1.952662] Console: switching to colour frame buffer device 160x50478buildbot # [ 1.976994] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device479buildbot # [ 1.993025] systemd[1]: Finished Load Kernel Modules.480buildbot # [ 1.995960] systemd[1]: Starting Apply Kernel Variables...481buildbot # [ 2.031122] systemd[1]: Started Journal Service.482buildbot # [ 2.022542] systemd-modules-load[73]: Inserted module 'dm_mod'483buildbot # [ 2.023744] systemd-modules-load[73]: Module 'virtio_balloon' is built in484buildbot # [ 2.027343] systemd-modules-load[73]: Module 'virtio_console' is built in485buildbot # [ 2.031220] systemd-modules-load[73]: Inserted module 'virtio_gpu'486buildbot # [ 2.036332] systemd-modules-load[73]: Module 'virtio_rng' is built in487buildbot # [ 2.037594] systemd-udevd[79]: Using default interface naming scheme 'v261'.488buildbot # [ 2.038679] systemd[1]: Starting Create System Files and Directories...489buildbot # [ 2.048125] systemd[1]: Finished Apply Kernel Variables.490buildbot # [ 2.060705] systemd[1]: Finished Create System Files and Directories.491buildbot # [ 2.077628] systemd[1]: Started Rule-based Manager for Device Events and Files.492buildbot # [ 2.129410] systemd[1]: Starting Virtual Console Setup...493buildbot # [ 2.177271] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.494buildbot # [ 2.180471] systemd[1]: Finished Virtual Console Setup.495buildbot # [ 2.748472] systemd[1]: Finished Coldplug All udev Devices.496buildbot # [ 2.750421] systemd[1]: Reached target System Initialization.497buildbot # [ 2.752108] systemd[1]: Reached target Basic System.498buildbot # [ 2.883962] (udev-worker)[96]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.499buildbot # [ 2.923506] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.500buildbot # [ 2.926685] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.501buildbot # [ 2.980229] systemd[1]: Found device /dev/disk/by-label/nixos.502buildbot # [ 2.985130] systemd[1]: Reached target Initrd Root Device.503buildbot # [ 2.988347] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...504buildbot # [ 3.039137] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks505buildbot # [ 3.047581] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.506buildbot # [ 3.059286] systemd[1]: Mounting /sysroot...507buildbot # [ 3.111121] EXT4-fs (vda): mounted filesystem 6c1c5b99-2e66-41b0-afd2-fb2b89ba55c0 r/w with ordered data mode. Quota mode: none.508buildbot # [ 3.100106] systemd[1]: Mounted /sysroot.509buildbot # [ 3.100759] systemd[1]: Reached target Initrd Root File System.510buildbot # [ 3.102484] systemd[1]: Starting Mountpoints Configured in the Real Root...511buildbot # [ 3.123313] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.512buildbot # [ 3.130989] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...513buildbot # [ 3.135191] systemd[1]: Reloading...514buildbot # [ 3.336090] systemd[1]: Reloading finished in 197 ms.515buildbot # [ 3.366157] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...516buildbot # [ 3.369073] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...517buildbot # [ 3.372487] systemd[1]: initrd-parse-etc.service: Deactivated successfully.518buildbot # [ 3.376662] systemd[1]: Finished Mountpoints Configured in the Real Root.519buildbot # [ 3.378330] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.520buildbot # [ 3.796232] systemd[1]: Mounting /sysroot/nix/.ro-store...521buildbot # [ 3.799837] systemd[1]: Mounting /sysroot/nix/.rw-store...522buildbot # [ 3.811006] systemd[1]: Mounting /sysroot/run...523buildbot # [ 3.821446] systemd[1]: Mounting /sysroot/tmp/shared...524buildbot # [ 3.842836] systemd[1]: Mounting /sysroot/tmp/xchg...525buildbot # [ 3.898033] systemd[1]: Mounted /sysroot/nix/.rw-store.526buildbot # [ 3.928394] fuse: init (API version 7.45)527buildbot # [ 3.941694] virtiofs virtio6: discovered new tag: nix-store528buildbot # [ 3.942455] virtiofs virtio6: virtio_fs_setup_dax: No cache capability529buildbot # [ 3.933287] systemd[1]: Starting rw-sysroot-nix-store.service...530buildbot # [ 3.934839] systemd[1]: Mounted /sysroot/run.531buildbot # [ 3.958055] virtiofs virtio7: discovered new tag: shared532buildbot # [ 3.958815] virtiofs virtio7: virtio_fs_setup_dax: No cache capability533buildbot # [ 3.963468] virtiofs virtio8: discovered new tag: xchg534buildbot # [ 3.964178] virtiofs virtio8: virtio_fs_setup_dax: No cache capability535buildbot # [ 3.967173] systemd[1]: Mounted /sysroot/nix/.ro-store.536buildbot # [ 3.971962] systemd[1]: Mounted /sysroot/tmp/shared.537buildbot # [ 3.977109] systemd[1]: Mounted /sysroot/tmp/xchg.538buildbot # [ 3.980538] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.539buildbot # [ 3.983544] systemd[1]: Finished rw-sysroot-nix-store.service.540buildbot # [ 4.276644] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory541buildbot # [ 4.282190] (udev-worker)[95]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory542buildbot # [ 4.312109] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.543buildbot # [ 4.313202] systemd[1]: Stopped Virtual Console Setup.544buildbot # [ 4.314590] systemd[1]: Stopping Virtual Console Setup...545buildbot # [ 4.315388] systemd[1]: Starting Virtual Console Setup...546buildbot # [ 4.324984] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.547buildbot # [ 4.327367] systemd[1]: Stopped Virtual Console Setup.548buildbot # [ 4.328591] systemd[1]: Starting Virtual Console Setup...549buildbot # [ 4.356413] systemd-vconsole-setup[152]: Configuration of first virtual console was skipped, ignoring remaining ones.550buildbot # [ 4.358816] systemd[1]: Finished Virtual Console Setup.551buildbot # [ 4.792069] systemd[1]: Mounting /sysroot/nix/store...552buildbot # [ 4.861532] systemd[1]: Mounted /sysroot/nix/store.553buildbot # [ 4.865172] systemd[1]: Reached target Initrd File Systems.554buildbot # [ 4.869540] systemd[1]: Starting Find NixOS closure...555buildbot # [ 4.877422] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...556buildbot # [ 4.921549] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.557buildbot # [ 4.926805] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.558buildbot # [ 4.940781] systemd[1]: Finished Find NixOS closure.559buildbot # [ 4.943454] systemd[1]: Reached target Initrd Default Target.560buildbot # [ 4.948464] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...561buildbot # [ 4.971750] systemd[1]: Stopped target Initrd Default Target.562buildbot # [ 4.973964] systemd[1]: Stopped target Basic System.563buildbot # [ 4.976327] systemd[1]: Stopped target Initrd Root Device.564buildbot # [ 4.980742] systemd[1]: Stopped target Path Units.565buildbot # [ 4.981786] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.566buildbot # [ 4.988181] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.567buildbot # [ 4.989763] systemd[1]: Stopped target Slice Units.568buildbot # [ 4.990791] systemd[1]: Stopped target Socket Units.569buildbot # [ 4.992410] systemd[1]: Stopped target System Initialization.570buildbot # [ 5.000666] systemd[1]: Stopped target Swaps.571buildbot # [ 5.001546] systemd[1]: Stopped target Timer Units.572buildbot # [ 5.002494] systemd[1]: dbus.socket: Deactivated successfully.573buildbot # [ 5.003581] systemd[1]: Closed D-Bus System Message Bus Socket.574buildbot # [ 5.014821] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.575buildbot # [ 5.023151] systemd[1]: Stopped Find NixOS closure.576buildbot # [ 5.024189] systemd[1]: Starting rw-sysroot-nix-store.service...577buildbot # [ 5.027614] systemd[1]: systemd-sysctl.service: Deactivated successfully.578buildbot # [ 5.031366] systemd[1]: Stopped Apply Kernel Variables.579buildbot # [ 5.033752] systemd[1]: systemd-modules-load.service: Deactivated successfully.580buildbot # [ 5.036247] systemd[1]: Stopped Load Kernel Modules.581buildbot # [ 5.037680] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.582buildbot # [ 5.040226] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.583buildbot # [ 5.044416] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.584buildbot # [ 5.045537] systemd[1]: Stopped Create System Files and Directories.585buildbot # [ 5.046695] systemd[1]: Stopped target Local File Systems.586buildbot # [ 5.048448] systemd[1]: Stopped target Preparation for Local File Systems.587buildbot # [ 5.049535] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.588buildbot # [ 5.050586] systemd[1]: Stopped Coldplug All udev Devices.589buildbot # [ 5.051410] systemd[1]: Stopping Rule-based Manager for Device Events and Files...590buildbot # [ 5.052583] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.591buildbot # [ 5.053633] systemd[1]: Stopped Virtual Console Setup.592buildbot # [ 5.054391] systemd[1]: initrd-cleanup.service: Deactivated successfully.593buildbot # [ 5.055362] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.594buildbot # [ 5.060273] systemd[1]: systemd-udevd.service: Deactivated successfully.595buildbot # [ 5.064174] systemd[1]: Stopped Rule-based Manager for Device Events and Files.596buildbot # [ 5.065357] systemd[1]: systemd-udevd.service: Consumed 1.386s CPU time over 3.128s wall clock time, 21.7M memory peak.597buildbot # [ 5.068174] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.598buildbot # [ 5.072150] systemd[1]: Closed udev Control Socket.599buildbot # [ 5.072866] systemd[1]: Starting Cleanup udev Database...600buildbot # [ 5.073633] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.601buildbot # [ 5.076162] systemd[1]: Stopped Create Static Device Nodes in /dev.602buildbot # [ 5.077037] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.603buildbot # [ 5.080379] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.604buildbot # [ 5.081362] systemd[1]: kmod-static-nodes.service: Deactivated successfully.605buildbot # [ 5.084264] systemd[1]: Stopped Create List of Static Device Nodes.606buildbot # [ 5.085142] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.607buildbot # [ 5.088252] systemd[1]: Finished rw-sysroot-nix-store.service.608buildbot # [ 5.106972] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.609buildbot # [ 5.109997] systemd[1]: Finished Cleanup udev Database.610buildbot # [ 5.110793] systemd[1]: Reached target Switch Root.611buildbot # [ 5.112684] systemd[1]: Starting NixOS Activation...612buildbot # [ 5.191916] initrd-nixos-activation-start[176]: booting system configuration /nix/store/1bcrzv0r56n27b4dc0bf6xdnaqflq0zq-nixos-system-buildbot-test613buildbot # [ 5.223662] initrd-nixos-activation-start[176]: running activation script...614buildbot # [ 5.459981] initrd-nixos-activation-start[199]: setting up /etc...615buildbot # [ 5.579045] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.616buildbot # [ 5.581829] systemd[1]: Finished NixOS Activation.617buildbot # [ 5.582923] systemd[1]: Starting Switch Root...618buildbot # [ 5.598491] systemd[1]: Switching root.619buildbot # [ 5.793665] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).620buildbot # [ 6.313194] 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)621buildbot # [ 6.325697] systemd[1]: Detected virtualization qemu.622buildbot # [ 6.328570] systemd[1]: Detected architecture arm64.623buildbot # [ 6.332064] systemd[1]: Detected first boot.624buildbot # [ 6.337712] systemd[1]: Initializing machine ID from random generator.625buildbot # [ 6.662838] systemd[1]: bpf-restrict-fs: LSM BPF program attached626buildbot # [ 6.881173] NET: Registered PF_VSOCK protocol family627buildbot # [ 6.887948] Guest personality initialized and is inactive628buildbot # [ 6.889956] VMCI host device registered (name=vmci, major=10, minor=261)629buildbot # [ 6.889983] Initialized host personality630buildbot # [ 6.956138] systemd[1]: Applying preset policy.631buildbot # [ 7.269433] systemd[1]: Populated /etc with preset unit settings.632buildbot # [ 7.524005] systemd[1]: initrd-switch-root.service: Deactivated successfully.633buildbot # [ 7.526004] systemd[1]: Stopped initrd-switch-root.service.634buildbot # [ 7.530588] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.635buildbot # [ 7.535232] systemd[1]: Created slice Slice /system/getty.636buildbot # [ 7.537384] systemd[1]: Created slice User and Session Slice.637buildbot # [ 7.538629] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.638buildbot # [ 7.541334] systemd[1]: Started Forward Password Requests to Wall Directory Watch.639buildbot # [ 7.543480] systemd[1]: Expecting device /dev/hvc0...640buildbot # [ 7.545377] systemd[1]: Expecting device /dev/ttyAMA0...641buildbot # [ 7.547220] systemd[1]: Reached target Local Encrypted Volumes.642buildbot # [ 7.549267] systemd[1]: Stopped target initrd-fs.target.643buildbot # [ 7.551101] systemd[1]: Stopped target initrd-root-fs.target.644buildbot # [ 7.553088] systemd[1]: Stopped target initrd-switch-root.target.645buildbot # [ 7.555042] systemd[1]: Reached target Virtual Machines and Containers.646buildbot # [ 7.557143] systemd[1]: Reached target Path Units.647buildbot # [ 7.558942] systemd[1]: Reached target Remote File Systems.648buildbot # [ 7.560864] systemd[1]: Reached target Slice Units.649buildbot # [ 7.562670] systemd[1]: Reached target Swaps.650buildbot # [ 7.566266] systemd[1]: Listening on Query the User Interactively for a Password.651buildbot # [ 7.569420] systemd[1]: Listening on Process Core Dump Socket.652buildbot # [ 7.571711] systemd[1]: Listening on Credential Encryption/Decryption.653buildbot # [ 7.574188] systemd[1]: Listening on Factory Reset Management.654buildbot # [ 7.575453] systemd[1]: Listening on Hostname Service Socket.655buildbot # [ 7.579654] systemd[1]: Starting Journal Log Access Socket...656buildbot # [ 7.581859] systemd[1]: Listening on Journal Audit Socket.657buildbot # [ 7.585167] systemd[1]: Listening on Console Output Muting Service Socket.658buildbot # [ 7.586813] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.659buildbot # [ 7.588363] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os660buildbot # [ 7.591115] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki661buildbot # [ 7.598435] systemd[1]: Listening on Disk Repartitioning Service Socket.662buildbot # [ 7.599818] systemd[1]: Listening on udev Control Socket.663buildbot # [ 7.601540] systemd[1]: Listening on udev Varlink Socket.664buildbot # [ 7.605986] systemd[1]: Mounting Huge Pages File System...665buildbot # [ 7.613511] systemd[1]: Mounting POSIX Message Queue File System...666buildbot # [ 7.625724] systemd[1]: Mounting Kernel Debug File System...667buildbot # [ 7.630641] systemd[1]: Mounting Kernel Trace File System...668buildbot # [ 7.644935] systemd[1]: Starting Create List of Static Device Nodes...669buildbot # [ 7.651335] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs670buildbot # [ 7.682843] systemd[1]: Mounting Kernel Configuration File System...671buildbot # [ 7.684737] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm672buildbot # [ 7.689491] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore673buildbot # [ 7.696666] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse674buildbot # [ 7.720115] systemd[1]: Mounting FUSE Control File System...675buildbot # [ 7.724694] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67676buildbot # [ 7.747537] systemd[1]: Starting Journal Service...677buildbot # [ 7.760940] systemd[1]: Starting Load Kernel Modules...678buildbot # [ 7.776385] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...679buildbot # [ 7.786066] systemd[1]: Starting Remount Root and Kernel File Systems...680buildbot # [ 7.786473] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os681buildbot # [ 7.792502] systemd[1]: Starting Coldplug All udev Devices...682buildbot # [ 7.799079] systemd[1]: Listening on Journal Log Access Socket.683buildbot # [ 7.810194] systemd[1]: Mounted Huge Pages File System.684buildbot # [ 7.811796] systemd[1]: Mounted POSIX Message Queue File System.685buildbot # [ 7.817716] systemd-journald[270]: Collecting audit messages is enabled.686buildbot # [ 7.831754] systemd[1]: Started Journal Service.687buildbot # [ 7.810972] systemd[1]: Queued start job for default target Multi-User System.688buildbot # [ 7.816574] systemd[1]: systemd-journald.service: Deactivated successfully.689buildbot # [ 7.817740] systemd[1]: Mounted Kernel Debug File System.690buildbot # [ 7.818675] systemd[1]: Mounted Kernel Trace File System.691buildbot # [ 7.824153] systemd[1]: Finished Create List of Static Device Nodes.692buildbot # [ 7.826274] systemd[1]: Mounted Kernel Configuration File System.693buildbot # [ 7.849466] systemd-modules-load[271]: Module 'atkbd' is built in694buildbot # [ 7.856482] systemd-modules-load[271]: Module 'loop' is built in695buildbot # [ 7.888387] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...696buildbot # [ 7.897067] systemd-modules-load[271]: Inserted module 'tls'697buildbot # [ 7.912342] systemd[1]: Finished Load Kernel Modules.698buildbot # [ 7.918911] systemd[1]: Starting Apply Kernel Variables...699buildbot # [ 7.953895] EXT4-fs (vda): re-mounted 6c1c5b99-2e66-41b0-afd2-fb2b89ba55c0.700buildbot # [ 7.966096] systemd[1]: Finished Remount Root and Kernel File Systems.701buildbot # [ 7.970503] systemd[1]: Listening on Disk Image Download Service Socket.702buildbot # [ 7.974669] systemd[1]: Starting Flush Journal to Persistent Storage...703buildbot # [ 7.975660] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore704buildbot # [ 7.987309] systemd[1]: Starting Load/Save OS Random Seed...705buildbot # [ 7.992327] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os706buildbot # [ 7.993545] systemd[1]: Mounted FUSE Control File System.707buildbot # [ 8.022547] systemd-oomd[273]: No swap; memory pressure usage will be degraded708buildbot # [ 8.039362] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.709buildbot # [ 8.093557] systemd-journald[270]: Received client request to flush runtime journal.710buildbot # [ 8.136543] systemd[1]: Finished Load/Save OS Random Seed.711buildbot # [ 8.137509] systemd[1]: Reached target First Boot Complete.712buildbot # [ 8.138328] systemd[1]: Finished Apply Kernel Variables.713buildbot # [ 8.148330] systemd[1]: Finished Flush Journal to Persistent Storage.714buildbot # [ 8.304895] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.715buildbot # [ 8.308547] systemd[1]: Starting Create Static Device Nodes in /dev...716buildbot # [ 8.746261] systemd[1]: Finished Coldplug All udev Devices.717buildbot # [ 8.752817] systemd[1]: Finished Create Static Device Nodes in /dev.718buildbot # [ 8.755111] systemd[1]: Reached target Preparation for Local File Systems.719buildbot # [ 8.760281] systemd[1]: Mounting /run/wrappers...720buildbot # [ 8.762161] systemd[1]: Starting Rule-based Manager for Device Events and Files...721buildbot # [ 8.816243] systemd[1]: Mounted /run/wrappers.722buildbot # [ 8.818137] systemd[1]: Reached target Local File Systems.723buildbot # [ 8.821831] systemd[1]: Listening on Boot Loader Control Service Socket.724buildbot # [ 8.826021] systemd[1]: Starting register-nix-paths.service...725buildbot # [ 8.831557] systemd[1]: Starting Create SUID/SGID Wrappers...726buildbot # [ 8.834289] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.727buildbot # [ 8.850517] systemd[1]: Starting Save Transient machine-id to Disk...728buildbot # [ 8.867038] systemd[1]: Starting Create System Files and Directories...729buildbot # [ 8.877533] systemd-udevd[302]: Using default interface naming scheme 'v261'.730buildbot # [ 8.946559] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.731buildbot # [ 8.953807] systemd[1]: Finished Save Transient machine-id to Disk.732buildbot # [ 9.030601] systemd[1]: Finished Create System Files and Directories.733buildbot # [ 9.036321] systemd[1]: Starting Rebuild Journal Catalog...734buildbot # [ 9.058809] systemd[1]: Starting Record System Boot/Shutdown in UTMP...735buildbot # [ 9.059842] systemd[1]: Started Rule-based Manager for Device Events and Files.736buildbot # [ 9.153333] systemd[1]: Finished Record System Boot/Shutdown in UTMP.737buildbot # [ 9.187998] systemd[1]: Finished Rebuild Journal Catalog.738buildbot # [ 9.194786] systemd[1]: Starting Update is Completed...739buildbot # [ 9.259271] systemd[1]: Finished Update is Completed.740buildbot # [ 9.561659] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs741buildbot # [ 9.606543] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse742buildbot # [ 9.813589] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.743buildbot # [ 9.823276] systemd[1]: Finished register-nix-paths.service.744buildbot # [ 9.858102] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.745buildbot # [ 9.883530] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.746buildbot # [ 9.885383] systemd[1]: Finished Create SUID/SGID Wrappers.747buildbot # [ 9.888839] systemd[1]: Reached target System Initialization.748buildbot # [ 9.891647] systemd[1]: Started Discard unused filesystem blocks once a week.749buildbot # [ 9.897107] systemd[1]: Started Daily Cleanup of Temporary Directories.750buildbot # [ 9.898064] systemd[1]: Reached target Timer Units.751buildbot # [ 9.898818] systemd[1]: Listening on D-Bus System Message Bus Socket.752buildbot # [ 9.899709] systemd[1]: Listening on Nix Daemon Socket.753buildbot # [ 9.908360] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).754buildbot # [ 9.911661] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.755buildbot # [ 9.916924] systemd[1]: Reached target Socket Units.756buildbot # [ 9.920724] systemd[1]: Reached target Basic System.757buildbot # [ 9.922442] systemd[1]: Started backdoor.service.758buildbot # [ 9.923710] systemd[1]: Starting Import lastlog data into lastlog2 database...759buildbot # [ 9.936172] systemd[1]: Starting Name Service Cache Daemon (nsncd)...760buildbot # [ 9.957454] systemd[1]: Starting Post-Boot Actions...761buildbot # [ 9.972717] systemd[1]: Started Reset console on configuration changes.762buildbot # [ 10.002800] systemd[1]: Starting resolvconf update...763buildbot # [ 10.003657] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.764buildbot # [ 10.057978] systemd[1]: Starting D-Bus System Message Bus...765buildbot # connecting to host...766buildbot # [ 10.062937] nsncd[389]: Sep 13 07:11:15.702 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"767buildbot # [ 10.077208] systemd[1]: Started Name Service Cache Daemon (nsncd).768buildbot # [ 10.091852] systemd[1]: Reached target Host and Network Name Lookups.769buildbot # [ 10.112254] systemd[1]: Reached target User and Group Name Lookups.770buildbot: Guest shell says: b'Spawning backdoor root shell...\n'771buildbot: connected to guest root shell772buildbot: (connecting took 10.49 seconds)773buildbot: (finished: waiting for the VM to finish booting, in 10.91 seconds)774buildbot # [ 10.150392] systemd[1]: Starting User Login Management...775buildbot # [ 10.155999] (udev-worker)[358]: Network interface NamePolicy= disabled on kernel command line.776buildbot # [ 10.165388] systemd[1]: Finished Post-Boot Actions.777buildbot # [ 10.258252] (udev-worker)[356]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.778buildbot # [ 10.271039] (udev-worker)[356]: Network interface NamePolicy= disabled on kernel command line.779buildbot # [ 10.275618] systemd[1]: Finished Import lastlog data into lastlog2 database.780buildbot # [ 10.363241] dbus-broker-launch[396]: Looking up NSS user entry for 'systemd-timesync'...781buildbot # [ 10.370929] dbus-broker-launch[396]: NSS returned no entry for 'systemd-timesync'782buildbot # [ 10.373591] dbus-broker-launch[396]: Invalid user-name in /nix/store/7yjdwfr20k6976bbkrv6qw9bq0cdv28j-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"783buildbot # [ 10.389814] systemd-logind[411]: New seat seat0.784buildbot # [ 10.390612] systemd[1]: Started User Login Management.785buildbot # [ 10.391320] systemd[1]: Starting linger-users.service...786buildbot # [ 10.419531] systemd[1]: Started D-Bus System Message Bus.787buildbot # [ 10.476566] systemd[1]: linger-users.service: Deactivated successfully.788buildbot # [ 10.480615] dbus-broker-launch[396]: Ready789buildbot # [ 10.487272] systemd[1]: Finished linger-users.service.790buildbot # [ 10.508143] systemd[1]: Stopped target Host and Network Name Lookups.791buildbot # [ 10.509087] systemd[1]: Stopping Host and Network Name Lookups...792buildbot # [ 10.509910] systemd[1]: Stopped target User and Group Name Lookups.793buildbot # [ 10.517286] systemd[1]: Stopping User and Group Name Lookups...794buildbot # [ 10.518230] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...795buildbot # [ 10.524776] systemd[1]: nscd.service: Deactivated successfully.796buildbot # [ 10.525624] systemd[1]: Stopped Name Service Cache Daemon (nsncd).797buildbot # [ 10.541929] systemd[1]: Starting Name Service Cache Daemon (nsncd)...798buildbot # [ 10.606239] systemd[1]: Started Name Service Cache Daemon (nsncd).799buildbot # [ 10.611135] nsncd[479]: Sep 13 07:11:16.247 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"800buildbot # [ 10.616484] systemd[1]: Reached target Host and Network Name Lookups.801buildbot # [ 10.617424] systemd[1]: Reached target User and Group Name Lookups.802buildbot # [ 10.627694] systemd-logind[411]: Watching system buttons on /dev/input/event0 (gpio-keys)803buildbot # [ 10.632904] systemd[1]: Finished resolvconf update.804buildbot # [ 10.633619] systemd[1]: Reached target Preparation for Network.805buildbot # [ 10.638799] systemd[1]: Starting DHCP Client...806buildbot # [ 10.647415] systemd[1]: Starting Extra networking commands....807buildbot # [ 10.682991] systemd[1]: Condition check resulted in Virtio network device being skipped.808buildbot # [ 10.693420] systemd[1]: Starting Address configuration of eth1...809buildbot # [ 10.867184] network-addresses-eth1-start[505]: adding address 192.168.1.1/24... done810buildbot # [ 10.898002] network-addresses-eth1-start[505]: adding address 2001:db8:1::1/64... done811buildbot # [ 10.919891] mousedev: PS/2 mouse device common for all mice812buildbot # [ 10.917763] dhcpcd[508]: dhcpcd-10.3.2 starting813buildbot # [ 10.934374] systemd[1]: Finished Address configuration of eth1.814buildbot # [ 10.939207] dhcpcd[557]: dev: loaded udev815buildbot # [ 10.995428] 8021q: 802.1Q VLAN Support v1.8816buildbot # [ 10.995798] 8021q: adding VLAN 0 to HW filter on device eth1817buildbot # [ 10.984192] systemd[1]: Finished Extra networking commands..818buildbot # [ 10.985046] systemd[1]: Reached target Network.819buildbot # [ 10.994710] systemd[1]: Starting Nginx Web Server...820buildbot # [ 10.998978] systemd[1]: Starting PostgreSQL Server...821buildbot # [ 11.014984] systemd[1]: Starting SSH Daemon...822buildbot # [ 11.020271] systemd[1]: Starting Permit User Sessions...823buildbot # [ 11.176126] systemd[1]: Finished Permit User Sessions.824buildbot # [ 11.185051] systemd[1]: Started Getty on tty1.825buildbot # [ 11.200340] systemd[1]: Reached target Login Prompts.826buildbot # [ 11.217490] systemd-logind[411]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)827buildbot # [ 11.245036] cfg80211: Loading compiled-in X.509 certificates for regulatory database828buildbot # [ 11.240311] sshd[572]: Server listening on 0.0.0.0 port 22.829buildbot # [ 11.241151] sshd[572]: Server listening on :: port 22.830buildbot # [ 11.249427] systemd[1]: Started SSH Daemon.831buildbot # [ 11.252523] systemd[1]: Starting Setup git test repository...832buildbot # [ 11.314031] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'833buildbot # [ 11.314519] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'834buildbot # [ 11.316960] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2835buildbot # [ 11.317259] cfg80211: failed to load regulatory.db836buildbot # [ 11.454549] 8021q: adding VLAN 0 to HW filter on device eth0837buildbot # [ 11.439880] dhcpcd[557]: eth0: waiting for carrier838buildbot # [ 11.442129] dhcpcd[557]: eth0: carrier acquired839buildbot # [ 11.460313] dhcpcd[557]: DUID 00:01:00:01:32:39:09:15:52:54:00:12:34:56840buildbot # [ 11.461289] dhcpcd[557]: eth0: IAID 00:12:34:56841buildbot # [ 11.461910] dhcpcd[557]: eth0: adding address fe80::5054:ff:fe12:3456842buildbot # [ 11.472405] nginx-pre-start[583]: nginx: the configuration file /nix/store/bf9pk7dsa60gnzdipz9432mycbm2ln2a-nginx.conf syntax is ok843buildbot # [ 11.473999] nginx-pre-start[583]: nginx: configuration file /nix/store/bf9pk7dsa60gnzdipz9432mycbm2ln2a-nginx.conf test is successful844buildbot # [ 11.475690] setup-git-repo-start[595]: hint: Using 'master' as the name for the initial branch. This default branch name845buildbot # [ 11.492301] setup-git-repo-start[595]: hint: will change to "main" in Git 3.0. To configure the initial branch name846buildbot # [ 11.493690] setup-git-repo-start[595]: hint: to use in all of your new repositories, which will suppress this warning,847buildbot # [ 11.495045] setup-git-repo-start[595]: hint: call:848buildbot # [ 11.495677] setup-git-repo-start[595]: hint:849buildbot # [ 11.516426] setup-git-repo-start[595]: hint: git config --global init.defaultBranch <name>850buildbot # [ 11.517554] setup-git-repo-start[595]: hint:851buildbot # [ 11.518154] setup-git-repo-start[595]: hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and852buildbot # [ 11.519425] setup-git-repo-start[595]: hint: 'development'. The just-created branch can be renamed via this command:853buildbot # [ 11.537112] setup-git-repo-start[595]: hint:854buildbot # [ 11.537746] setup-git-repo-start[595]: hint: git branch -m <name>855buildbot # [ 11.538580] setup-git-repo-start[595]: hint:856buildbot # [ 11.539145] setup-git-repo-start[595]: hint: Disable this message with "git config set advice.defaultBranchName false"857buildbot # [ 11.554753] setup-git-repo-start[595]: Initialized empty Git repository in /srv/repos/test-flake.git/858buildbot # [ 11.565284] systemd[1]: Started Nginx Web Server.859buildbot # [ 11.566366] setup-git-repo-start[608]: hint: Using 'master' as the name for the initial branch. This default branch name860buildbot # [ 11.567757] setup-git-repo-start[608]: hint: will change to "main" in Git 3.0. To configure the initial branch name861buildbot # [ 11.579036] setup-git-repo-start[608]: hint: to use in all of your new repositories, which will suppress this warning,862buildbot # [ 11.586409] setup-git-repo-start[608]: hint: call:863buildbot # [ 11.587100] setup-git-repo-start[608]: hint:864buildbot # [ 11.587688] setup-git-repo-start[608]: hint: git config --global init.defaultBranch <name>865buildbot # [ 11.597262] setup-git-repo-start[608]: hint:866buildbot # [ 11.597911] setup-git-repo-start[608]: hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and867buildbot # [ 11.599189] setup-git-repo-start[608]: hint: 'development'. The just-created branch can be renamed via this command:868buildbot # [ 11.611424] setup-git-repo-start[608]: hint:869buildbot # [ 11.613291] setup-git-repo-start[608]: hint: git branch -m <name>870buildbot # [ 11.614166] setup-git-repo-start[608]: hint:871buildbot # [ 11.614941] setup-git-repo-start[608]: hint: Disable this message with "git config set advice.defaultBranchName false"872buildbot # [ 11.625529] setup-git-repo-start[608]: Initialized empty Git repository in /tmp/test-flake/.git/873buildbot # [ 11.638533] postgresql-pre-start[589]: The files belonging to this database system will be owned by user "postgres".874buildbot # [ 11.639907] postgresql-pre-start[589]: This user must also own the server process.875buildbot: (finished: waiting for unit sshd.service, in 12.42 seconds)876buildbot: waiting for unit setup-git-repo.service877buildbot # [ 11.656116] postgresql-pre-start[589]: The database cluster will be initialized with locale "en_US.UTF-8".878buildbot # [ 11.662871] postgresql-pre-start[589]: The default database encoding has accordingly been set to "UTF8".879buildbot # [ 11.672740] postgresql-pre-start[589]: The default text search configuration will be set to "english".880buildbot # [ 11.673985] postgresql-pre-start[589]: Data page checksums are enabled.881buildbot # [ 11.674870] postgresql-pre-start[589]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok882buildbot # [ 11.692508] postgresql-pre-start[589]: creating subdirectories ... ok883buildbot # [ 11.693401] postgresql-pre-start[589]: selecting dynamic shared memory implementation ... posix884buildbot # [ 11.872907] postgresql-pre-start[589]: selecting default "max_connections" ... 100885buildbot # [ 11.950156] postgresql-pre-start[589]: selecting default "shared_buffers" ... 128MB886buildbot # [ 11.954488] setup-git-repo-start[629]: [master (root-commit) cb68269] Initial commit887buildbot # [ 11.955565] setup-git-repo-start[629]: 1 file changed, 39 insertions(+)888buildbot # [ 11.958056] setup-git-repo-start[629]: create mode 100644 flake.nix889buildbot # [ 12.078730] setup-git-repo-start[638]: To /srv/repos/test-flake.git890buildbot # [ 12.079671] setup-git-repo-start[638]: * [new branch] master -> master891buildbot # [ 12.085441] setup-git-repo-start[638]: branch 'master' set up to track 'origin/master'.892buildbot # [ 12.128546] dhcpcd[557]: eth0: soliciting a DHCP lease893buildbot # [ 12.136665] dhcpcd[557]: eth0: offered 10.0.2.15 from 10.0.2.2894buildbot # [ 12.140942] dhcpcd[557]: eth0: probing address 10.0.2.15/24895buildbot # [ 12.330866] setup-git-repo-start[654]: 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 282 ms (attempt 1/5)896buildbot # [ 12.614030] setup-git-repo-start[654]: 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 650 ms (attempt 2/5)897buildbot # [ 12.774737] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3898buildbot # [ 13.144288] systemd[1]: Starting Virtual Console Setup...899buildbot # [ 13.180471] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.900buildbot # [ 13.229790] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.901buildbot # [ 13.233895] systemd[1]: Stopped Virtual Console Setup.902buildbot # [ 13.241127] systemd[1]: Starting Virtual Console Setup...903buildbot # [ 13.266585] systemd-logind[411]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)904buildbot # [ 13.274677] setup-git-repo-start[654]: 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 1277 ms (attempt 3/5)905buildbot # [ 13.453535] systemd-vconsole-setup[671]: Configuration of first virtual console was skipped, ignoring remaining ones.906buildbot # [ 13.458563] systemd[1]: Finished Virtual Console Setup.907buildbot # [ 13.658450] dhcpcd[557]: eth0: soliciting an IPv6 router908buildbot # [ 13.659303] dhcpcd[557]: eth0: Router Advertisement from fe80::2909buildbot # [ 13.660447] dhcpcd[557]: eth0: adding address fec0::5054:ff:fe12:3456/64910buildbot # [ 13.661391] dhcpcd[557]: eth0: adding route to fec0::/64911buildbot # [ 13.662145] dhcpcd[557]: eth0: adding default route via fe80::2912buildbot # [ 13.801169] postgresql-pre-start[589]: selecting default time zone ... UTC913buildbot # [ 13.803775] postgresql-pre-start[589]: creating configuration files ... ok914buildbot # [ 14.046694] postgresql-pre-start[589]: running bootstrap script ... ok915buildbot # [ 14.533108] postgresql-pre-start[589]: performing post-bootstrap initialization ... ok916buildbot # [ 14.552424] setup-git-repo-start[654]: 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 2130 ms (attempt 4/5)917buildbot # [ 14.693502] postgresql-pre-start[589]: syncing data to disk ... ok918buildbot # [ 14.694467] postgresql-pre-start[589]: initdb: warning: enabling "trust" authentication for local connections919buildbot # [ 14.695751] postgresql-pre-start[589]: 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.920buildbot # [ 14.697980] postgresql-pre-start[589]: Success. You can now start the database server using:921buildbot # [ 14.699161] postgresql-pre-start[589]: pg_ctl -D /var/lib/postgresql/18 -l logfile start922buildbot # [ 14.787738] postgres[685]: [685] LOG: starting PostgreSQL 18.6 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit923buildbot # [ 14.792194] postgres[685]: [685] LOG: listening on IPv6 address "::1", port 5432924buildbot # [ 14.793361] postgres[685]: [685] LOG: listening on IPv4 address "127.0.0.1", port 5432925buildbot # [ 14.795539] postgres[685]: [685] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"926buildbot # [ 14.806426] postgres[694]: [694] LOG: database system was shut down at 2026-09-13 07:11:20 GMT927buildbot # [ 14.813167] postgres[685]: [685] LOG: database system is ready to accept connections928buildbot # [ 14.816757] systemd[1]: Started PostgreSQL Server.929buildbot # [ 14.824644] systemd[1]: Starting PostgreSQL Setup Scripts...930buildbot # [ 14.992449] postgresql-setup-start[705]: CREATE DATABASE931buildbot # [ 15.031025] postgresql-setup-start[710]: CREATE ROLE932buildbot # [ 15.046033] postgresql-setup-start[712]: ALTER DATABASE933buildbot # [ 15.051365] systemd[1]: Finished PostgreSQL Setup Scripts.934buildbot # [ 15.054703] systemd[1]: Reached target PostgreSQL.935buildbot # [ 15.056539] systemd[1]: Starting Buildbot Continuous Integration Server....936buildbot # [ 15.110196] buildbot-master-pre-start[721]: mkdir: created directory '/var/lib/buildbot/master'937buildbot # [ 16.685696] setup-git-repo-start[654]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org938buildbot # [ 16.685824] setup-git-repo-start[654]: this derivation will be built:939buildbot # [ 16.685888] setup-git-repo-start[654]: /nix/store/lqx3p33b67znqs9dzp4mdp81b11fiwzz-skippable-test.drv940buildbot # [ 16.798219] setup-git-repo-start[654]: building '/nix/store/lqx3p33b67znqs9dzp4mdp81b11fiwzz-skippable-test.drv'...941buildbot # [ 16.841894] systemd[1]: Finished Setup git test repository.942buildbot # [ 17.069060] dhcpcd[557]: eth0: leased 10.0.2.15 for 86400 seconds943buildbot # [ 17.069997] dhcpcd[557]: eth0: adding route to 10.0.2.0/24944buildbot # [ 17.070752] dhcpcd[557]: eth0: adding default route via 10.0.2.2945buildbot: (finished: waiting for unit setup-git-repo.service, in 5.59 seconds)946buildbot: waiting for unit multi-user.target947buildbot # [ 17.268649] systemd[1]: Started DHCP Client.948buildbot # [ 17.712915] buildbot-master-pre-start[723]: updating existing installation949buildbot # [ 17.713925] buildbot-master-pre-start[723]: not touching existing buildbot.tac950buildbot # [ 17.714872] buildbot-master-pre-start[723]: creating buildbot.tac.new instead951buildbot # [ 17.715788] buildbot-master-pre-start[723]: creating /var/lib/buildbot/master/master.cfg.sample952buildbot # [ 17.724187] buildbot-master-pre-start[723]: creating database (postgresql://@/buildbot)953buildbot # [ 17.725283] buildbot-master-pre-start[723]: buildmaster configured in /var/lib/buildbot/master954buildbot # [ 18.161335] systemd[1]: Started Buildbot Continuous Integration Server..955buildbot # [ 18.170760] systemd[1]: Started Buildbot Worker..956buildbot # [ 18.172862] systemd[1]: Reached target Multi-User System.957buildbot # [ 18.173658] systemd[1]: Startup finished in 925ms (kernel) + 4.948s (initrd) + 12.297s (userspace) = 18.172s.958buildbot: (finished: waiting for unit multi-user.target, in 1.19 seconds)959subtest: Master and worker services start960buildbot: waiting for unit buildbot-master.service961buildbot: (finished: waiting for unit buildbot-master.service, in 0.09 seconds)962buildbot: waiting for unit buildbot-worker.service963buildbot: (finished: waiting for unit buildbot-worker.service, in 0.08 seconds)964buildbot: waiting for TCP port 8010 on localhost965buildbot # [ 19.435415] twistd[819]: Starting worker local-worker-000966buildbot # [ 19.435579] twistd[819]: 2026-09-13T07:11:24+0000 [-] Loading /nix/store/zxbh9svi0g0i80pg7z3gd6hmk17ck3yf-buildbot_nix/buildbot_nix/worker.py...967buildbot # [ 19.439751] twistd[819]: 2026-09-13T07:11:25+0000 [-] Loaded.968buildbot # [ 19.440788] twistd[819]: 2026-09-13T07:11:25+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.969buildbot # [ 19.443079] twistd[819]: 2026-09-13T07:11:25+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.970buildbot # [ 19.445642] twistd[819]: 2026-09-13T07:11:25+0000 [-] Starting Worker -- version: 2026.09.13971buildbot # [ 19.446753] twistd[819]: 2026-09-13T07:11:25+0000 [-] recording hostname in twistd.hostname972buildbot # [ 19.447829] twistd[819]: 2026-09-13T07:11:25+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0xe7ddcb9b0050>973buildbot # [ 19.454291] twistd[819]: 2026-09-13T07:11:25+0000 [twisted.application._client_service.ClientService#info] Scheduling retry 1 to connect <twisted.internet.endpoints.TCP4ClientEndpoint object at 0xe7ddcb9b0590> in 1.7673791580473286 seconds.974buildbot # [ 19.457190] twistd[819]: 2026-09-13T07:11:25+0000 [buildbot_worker.pb.BotFactory#info] Stopping factory <buildbot_worker.pb.BotFactory object at 0xe7ddcb9b0050>975buildbot # [ 20.343994] twistd[818]: 2026-09-13T07:11:24+0000 [-] Loading /var/lib/buildbot/master/buildbot.tac...976buildbot # [ 20.344747] twistd[818]: 2026-09-13T07:11:25+0000 [-] Loaded.977buildbot # [ 20.344792] twistd[818]: 2026-09-13T07:11:25+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.978buildbot # [ 20.344826] twistd[818]: 2026-09-13T07:11:25+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.979buildbot # [ 20.344859] twistd[818]: 2026-09-13T07:11:25+0000 [-] Starting BuildMaster -- buildbot.version: 4.3.0980buildbot # [ 20.355915] twistd[818]: 2026-09-13T07:11:25+0000 [-] Loading configuration from '/nix/store/w0z2qzjpi74dqish8xg27f5ijkdl3bzj-master.cfg'981buildbot # [ 20.746921] twistd[818]: 2026-09-13T07:11:26+0000 [-] Setting up database with URL 'postgresql://@/buildbot'982buildbot # [ 20.834713] twistd[818]: 2026-09-13T07:11:26+0000 [-] adding 9 new builders, removing 0983buildbot # [ 21.040885] twistd[818]: 2026-09-13T07:11:26+0000 [-] adding 3 new services, removing 0984buildbot # [ 21.225606] twistd[819]: 2026-09-13T07:11:26+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0xe7ddcb9b0050>985buildbot # [ 21.236196] twistd[819]: 2026-09-13T07:11:26+0000 [twisted.application._client_service.ClientService#info] Scheduling retry 2 to connect <twisted.internet.endpoints.TCP4ClientEndpoint object at 0xe7ddcb9b0590> in 2.6888838668568416 seconds.986buildbot # [ 21.246300] twistd[819]: 2026-09-13T07:11:26+0000 [buildbot_worker.pb.BotFactory#info] Stopping factory <buildbot_worker.pb.BotFactory object at 0xe7ddcb9b0050>987buildbot # [ 21.287709] twistd[818]: 2026-09-13T07:11:26+0000 [-] adding 1 new change_sources, removing 0988buildbot # [ 21.298175] twistd[818]: 2026-09-13T07:11:26+0000 [-] gitpoller: using workdir '/var/lib/buildbot/master/gitpoller-work'989buildbot # [ 21.312070] twistd[818]: 2026-09-13T07:11:26+0000 [-] adding 14 new schedulers, removing 0990buildbot # [ 21.500099] twistd[818]: 2026-09-13T07:11:27+0000 [-] BuildbotSite starting on 8010991buildbot # [ 21.503222] twistd[818]: 2026-09-13T07:11:27+0000 [buildbot.www.service.BuildbotSite#info] Starting factory <buildbot.www.service.BuildbotSite object at 0xee66a8165010>992buildbot # [ 21.505523] twistd[818]: 2026-09-13T07:11:27+0000 [-] adding 5 new workers, removing 0993buildbot # [ 21.515521] twistd[818]: 2026-09-13T07:11:27+0000 [-] PBServerFactory starting on 9989994buildbot # [ 21.518140] twistd[818]: 2026-09-13T07:11:27+0000 [twisted.spread.pb.PBServerFactory#info] Starting factory <twisted.spread.pb.PBServerFactory object at 0xee66a8165a90>995buildbot # [ 21.564552] sshd-session[874]: Accepted publickey for root from ::1 port 40566 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW8996buildbot # [ 21.578304] twistd[818]: 2026-09-13T07:11:27+0000 [-] Starting Worker -- version: 2026.09.13997buildbot # [ 21.579461] twistd[818]: 2026-09-13T07:11:27+0000 [-] recording hostname in twistd.hostname998buildbot # [ 21.583996] twistd[818]: 2026-09-13T07:11:27+0000 [-] message from master: attached999buildbot # [ 21.586728] twistd[818]: 2026-09-13T07:11:27+0000 [-] Got workerinfo from '__Janitor'1000buildbot # [ 21.589532] sshd-session[874]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1001buildbot # [ 21.594982] twistd[818]: 2026-09-13T07:11:27+0000 [-] bot attached1002buildbot # [ 21.596085] twistd[818]: 2026-09-13T07:11:27+0000 [-] Worker __Janitor attached to __Janitor1003buildbot # [ 21.598978] twistd[818]: 2026-09-13T07:11:27+0000 [-] message from master: attached1004buildbot # [ 21.646679] systemd[1]: Created slice Slice /user/0.1005buildbot # [ 21.652947] systemd[1]: Starting User Runtime Directory /run/user/0...1006buildbot # [ 21.667918] twistd[818]: 2026-09-13T07:11:27+0000 [-] BuildMaster is running1007buildbot # [ 21.682719] systemd-logind[411]: New session '1' of user 'root' with class 'user' and type 'tty'.1008buildbot # [ 21.698197] systemd[1]: Finished User Runtime Directory /run/user/0.1009buildbot # [ 21.702239] systemd[1]: Starting User Manager for UID 0...1010buildbot # [ 21.725550] (systemd)[880]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0)1011buildbot # [ 21.730832] systemd-logind[411]: New session '2' of user 'root' with class 'manager-early' and type 'unspecified'.1012buildbot # [ 21.826513] systemd[880]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1013buildbot # Connection to localhost (127.0.0.1) 8010 port [tcp/*] succeeded!1014buildbot: (finished: waiting for TCP port 8010 on localhost, in 3.35 seconds)1015??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1016 File "/nix/store/yyamlhbvx0lp00fbf9sswdcq9ckfh7qj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391017buildbot: waiting for success: curl --fail --head http://localhost:80101018??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1019 File "/nix/store/yyamlhbvx0lp00fbf9sswdcq9ckfh7qj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391020buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1021buildbot # Dload Upload Total Spent Left Speed1022buildbot # 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 01023buildbot: (finished: waiting for success: curl --fail --head http://localhost:8010, in 0.11 seconds)1024(finished: subtest: Master and worker services start, in 3.63 seconds)1025buildbot: waiting for success: curl http://localhost:8010/api/v2/projects1026buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1027buildbot # Dload Upload Total Spent Left Speed1028buildbot # 0 0 0 0 0 0 0 0 0100 266 100 266 0 0 11863 0 0100 266 100 266 0 0 10457 0 0100 266 100 266 0 0 9575 0 01029buildbot: (finished: waiting for success: curl http://localhost:8010/api/v2/projects, in 0.09 seconds)1030subtest: Wait for poller to initialize1031buildbot: waiting for success: journalctl -u buildbot-master.service | grep "gitpoller: processing changes from"1032buildbot # [ 22.197184] systemd[880]: Queued start job for default target Main User Target.1033buildbot # [ 22.204979] systemd[880]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1034buildbot # [ 22.208713] systemd[880]: Created slice User Application Slice.1035buildbot # [ 22.209578] systemd[880]: Started Daily Cleanup of User's Temporary Directories.1036buildbot # [ 22.210619] systemd[880]: Reached target Paths.1037buildbot # [ 22.211261] systemd[880]: Reached target Timers.1038buildbot # [ 22.213462] systemd[880]: Starting D-Bus User Message Bus Socket...1039buildbot # [ 22.215391] systemd[880]: Starting Create User Files and Directories...1040buildbot # [ 22.245830] systemd[880]: Finished Create User Files and Directories.1041buildbot # [ 22.260070] systemd[880]: Listening on D-Bus User Message Bus Socket.1042buildbot # [ 22.262515] systemd[880]: Reached target Sockets.1043buildbot # [ 22.263249] systemd[880]: Reached target Basic System.1044buildbot # [ 22.263967] systemd[880]: Run user-specific NixOS activation skipped, unmet condition check ConditionUser=!@system1045buildbot # [ 22.267200] systemd[880]: Reached target Main User Target.1046buildbot # [ 22.267986] systemd[880]: Startup finished in 516ms.1047buildbot # [ 22.270763] systemd[1]: Started User Manager for UID 0.1048buildbot # [ 22.271521] systemd[1]: Started Session 1 of User root.1049buildbot # [ 22.291223] sshd-session[911]: Received disconnect from ::1 port 40566:11: disconnected by user1050buildbot # [ 22.294550] sshd-session[911]: Disconnected from user root ::1 port 405661051buildbot # [ 22.295503] sshd-session[874]: pam_unix(sshd:session): session closed for user root1052buildbot # [ 22.311150] systemd[1]: session-1.scope: Deactivated successfully.1053buildbot # [ 22.313947] systemd-logind[411]: Session 1 logged out. Waiting for processes to exit.1054buildbot # [ 22.315123] systemd-logind[411]: Removed session 1.1055buildbot # [ 22.474411] sshd-session[916]: Accepted publickey for root from ::1 port 40580 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81056buildbot # [ 22.489863] sshd-session[916]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1057buildbot # [ 22.501090] systemd-logind[411]: New session '3' of user 'root' with class 'user' and type 'tty'.1058buildbot # [ 22.513323] systemd[1]: Started Session 3 of User root.1059buildbot # [ 22.559797] sshd-session[919]: Received disconnect from ::1 port 40580:11: disconnected by user1060buildbot # [ 22.566419] sshd-session[919]: Disconnected from user root ::1 port 405801061buildbot # [ 22.567737] sshd-session[916]: pam_unix(sshd:session): session closed for user root1062buildbot # [ 22.574349] systemd[1]: session-3.scope: Deactivated successfully.1063buildbot # [ 22.580148] systemd-logind[411]: Session 3 logged out. Waiting for processes to exit.1064buildbot # [ 22.581533] systemd-logind[411]: Removed session 3.1065buildbot # [ 22.600612] twistd[818]: 2026-09-13T07:11:28+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1066buildbot: (finished: waiting for success: journalctl -u buildbot-master.service | grep "gitpoller: processing changes from", in 1.11 seconds)1067(finished: subtest: Wait for poller to initialize, in 1.11 seconds)1068subtest: Push new commit to trigger poller1069buildbot: must succeed: 1070 cd /tmp/test-flake1071 echo '# Updated at test time' >> flake.nix1072 git add flake.nix1073 git commit -m 'Test commit to trigger poller'1074 git push origin master1075 1076buildbot # Enumerating objects: 5, done.1077buildbot # 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.1078buildbot # Compressing objects: 50% (1/2)Compressing objects: 100% (2/2)Compressing objects: 100% (2/2), done.1079buildbot # Writing objects: 33% (1/3)Writing objects: 66% (2/3)Writing objects: 100% (3/3)Writing objects: 100% (3/3), 300 bytes | 150.00 KiB/s, done.1080buildbot # Total 3 (delta 1), reused 0 (delta 0), pack-reused 0 (from 0)1081buildbot # To /srv/repos/test-flake.git1082buildbot # cb68269..02f47bc master -> master1083buildbot: (finished: must succeed: 1084 cd /tmp/test-flake1085 echo '# Updated at test time' >> flake.nix1086 git add flake.nix1087 git commit -m 'Test commit to trigger poller'1088 git push origin master1089 , in 0.11 seconds)1090(finished: subtest: Push new commit to trigger poller, in 0.11 seconds)1091subtest: Poller triggers builds and verifies results1092??? Warning (UserWarning): retry(): The 'timeout_seconds' argument is deprecated. Use 'timeout' instead.1093 File "/nix/store/yyamlhbvx0lp00fbf9sswdcq9ckfh7qj-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/machine/__init__.py", line 1281094buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1095buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1096buildbot # Dload Upload Total Spent Left Speed1097buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3289 0 0100 51 100 51 0 0 2825 0 0100 51 100 51 0 0 2440 0 01098buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.05 seconds)1099buildbot # [ 23.930100] twistd[819]: 2026-09-13T07:11:29+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0xe7ddcb9b0050>1100buildbot # [ 23.951022] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] worker 'local-worker-000' attaching from IPv4Address(type='TCP', host='127.0.0.1', port=37568)1101buildbot # [ 23.958470] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1102buildbot # [ 23.972476] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Got workerinfo from 'local-worker-000'1103buildbot # [ 23.994445] twistd[818]: 2026-09-13T07:11:29+0000 [-] bot attached1104buildbot # [ 24.006825] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-build1105buildbot # [ 24.015407] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/run-scheduled-effect1106buildbot # [ 24.028811] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-dependency-failed1107buildbot # [ 24.052849] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-failed-eval1108buildbot # [ 24.055030] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-eval1109buildbot # [ 24.100624] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-register-gcroot1110buildbot # [ 24.102230] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-cached-failure1111buildbot # [ 24.103795] twistd[818]: 2026-09-13T07:11:29+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/run-effect1112buildbot # [ 24.136471] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1113buildbot # [ 24.137653] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1114buildbot # [ 24.138754] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1115buildbot # [ 24.139828] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1116buildbot # [ 24.167224] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1117buildbot # [ 24.174315] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1118buildbot # [ 24.175464] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1119buildbot # [ 24.189810] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] message from master: attached1120buildbot # [ 24.190991] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] Connected to buildmaster; worker is ready1121buildbot # [ 24.196359] twistd[819]: 2026-09-13T07:11:29+0000 [Broker,client] sending application-level keepalives every 600 seconds1122buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1123buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1124buildbot # Dload Upload Total Spent Left Speed1125buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2400 0 0100 51 100 51 0 0 1975 0 0100 51 100 51 0 0 1722 0 01126buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1127buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1128buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1129buildbot # Dload Upload Total Spent Left Speed1130buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1998 0 0100 51 100 51 0 0 1667 0 0100 51 100 51 0 0 1465 0 01131buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1132buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1133buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1134buildbot # Dload Upload Total Spent Left Speed1135buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3241 0 0100 51 100 51 0 0 2439 0 0100 51 100 51 0 0 1995 0 01136buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1137buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1138buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1139buildbot # Dload Upload Total Spent Left Speed1140buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2247 0 0100 51 100 51 0 0 1883 0 0100 51 100 51 0 0 1641 0 01141buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1142buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1143buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1144buildbot # Dload Upload Total Spent Left Speed1145buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3774 0 0100 51 100 51 0 0 2788 0 0100 51 100 51 0 0 2251 0 01146buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1147buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1148buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1149buildbot # Dload Upload Total Spent Left Speed1150buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2256 0 0100 51 100 51 0 0 1861 0 0100 51 100 51 0 0 1623 0 01151buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1152buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1153buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1154buildbot # Dload Upload Total Spent Left Speed1155buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2369 0 0100 51 100 51 0 0 1951 0 0100 51 100 51 0 0 1699 0 01156buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1157buildbot # [ 31.538251] sshd-session[989]: Accepted publickey for root from ::1 port 59440 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81158buildbot # [ 31.558362] sshd-session[989]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1159buildbot # [ 31.576111] systemd-logind[411]: New session '4' of user 'root' with class 'user' and type 'tty'.1160buildbot # [ 31.585086] systemd[1]: Started Session 4 of User root.1161buildbot # [ 31.613578] sshd-session[992]: Received disconnect from ::1 port 59440:11: disconnected by user1162buildbot # [ 31.615658] sshd-session[992]: Disconnected from user root ::1 port 594401163buildbot # [ 31.618464] sshd-session[989]: pam_unix(sshd:session): session closed for user root1164buildbot # [ 31.638806] systemd[1]: session-4.scope: Deactivated successfully.1165buildbot # [ 31.639989] systemd-logind[411]: Session 4 logged out. Waiting for processes to exit.1166buildbot # [ 31.643753] systemd-logind[411]: Removed session 4.1167buildbot # [ 31.809602] sshd-session[997]: Accepted publickey for root from ::1 port 59452 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81168buildbot # [ 31.827657] sshd-session[997]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1169buildbot # [ 31.839837] systemd-logind[411]: New session '5' of user 'root' with class 'user' and type 'tty'.1170buildbot # [ 31.852568] systemd[1]: Started Session 5 of User root.1171buildbot # [ 31.900872] sshd-session[1000]: Received disconnect from ::1 port 59452:11: disconnected by user1172buildbot # [ 31.902944] sshd-session[1000]: Disconnected from user root ::1 port 594521173buildbot # [ 31.910290] sshd-session[997]: pam_unix(sshd:session): session closed for user root1174buildbot # [ 31.917933] systemd[1]: session-5.scope: Deactivated successfully.1175buildbot # [ 31.922930] systemd-logind[411]: Session 5 logged out. Waiting for processes to exit.1176buildbot # [ 31.927455] systemd-logind[411]: Removed session 5.1177buildbot # [ 31.939230] twistd[818]: 2026-09-13T07:11:37+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1178buildbot # [ 31.975540] twistd[818]: 2026-09-13T07:11:37+0000 [-] gitpoller: processing 1 changes: ['02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6'] from "ssh://root@localhost/srv/repos/test-flake.git" branch "refs/heads/master"1179buildbot # [ 32.084110] twistd[818]: 2026-09-13T07:11:37+0000 [-] added change with revision 02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6 to database1180buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1181buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1182buildbot # Dload Upload Total Spent Left Speed1183buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 4457 0 0100 51 100 51 0 0 3690 0 0100 51 100 51 0 0 3156 0 01184buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.05 seconds)1185buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1186buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1187buildbot # Dload Upload Total Spent Left Speed1188buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1828 0 0100 51 100 51 0 0 1513 0 0100 51 100 51 0 0 1324 0 01189buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1190buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1191buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1192buildbot # Dload Upload Total Spent Left Speed1193buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 2075 0 0100 51 100 51 0 0 1754 0 0100 51 100 51 0 0 1544 0 01194buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1195buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1196buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1197buildbot # Dload Upload Total Spent Left Speed1198buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 3910 0 0100 51 100 51 0 0 2775 0 0100 51 100 51 0 0 2237 0 01199buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1200buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1201buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1202buildbot # Dload Upload Total Spent Left Speed1203buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1921 0 0100 51 100 51 0 0 1561 0 0100 51 100 51 0 0 1356 0 01204buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1205buildbot # [ 37.147976] twistd[818]: 2026-09-13T07:11:42+0000 [-] added buildset 1 to database1206buildbot # [ 37.316487] twistd[818]: 2026-09-13T07:11:42+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>1207buildbot # [ 37.326460] twistd[818]: 2026-09-13T07:11:42+0000 [-] <Build test-flake/nix-eval number:None results:success>.startBuild1208buildbot # [ 37.410162] twistd[818]: 2026-09-13T07:11:43+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1209buildbot # [ 37.415548] twistd[818]: 2026-09-13T07:11:43+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>1210buildbot # [ 37.420239] twistd[818]: 2026-09-13T07:11:43+0000 [-] sending ping1211buildbot # [ 37.423070] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] message from master: ping1212buildbot # [ 37.425631] twistd[818]: 2026-09-13T07:11:43+0000 [Broker,0,127.0.0.1] ping finished: success1213buildbot # [ 37.447481] twistd[818]: 2026-09-13T07:11:43+0000 [-] <RemoteShellCommand '['git', '--version']'>: RemoteCommand.run [0]1214buildbot # [ 37.452188] twistd[818]: 2026-09-13T07:11:43+0000 [-] command '['git', '--version']' in dir 'build'1215buildbot # [ 37.453483] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 0): startCommand:shell1216buildbot # [ 37.454724] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command ['git', '--version']): RunProcess._startCommand1217buildbot # [ 37.463781] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command ['git', '--version']): git --version1218buildbot # [ 37.466164] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command ['git', '--version']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1219buildbot # [ 37.470007] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command ['git', '--version']): watching logfiles {}1220buildbot # [ 37.474016] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command ['git', '--version']): argv: [b'git', b'--version']1221buildbot # [ 37.475486] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command ['git', '--version']): using PTY: False1222buildbot # [ 37.482539] twistd[819]: 2026-09-13T07:11:43+0000 [-] (command ['git', '--version']): command finished with signal None, exit code 0, elapsedTime: 0.0233041223buildbot # [ 37.484416] twistd[819]: 2026-09-13T07:11:43+0000 [-] (command 0): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcb9b27b0>1224buildbot # [ 37.511206] twistd[818]: 2026-09-13T07:11:43+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', '--version']'> rc=01225buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1226buildbot # [ 37.558822] twistd[818]: 2026-09-13T07:11:43+0000 [-] <RemoteCommand 'stat' at 262124545477328>: RemoteCommand.run [1]1227buildbot # [ 37.564779] twistd[818]: 2026-09-13T07:11:43+0000 [Broker,0,127.0.0.1] <RemoteCommand 'stat' at 262124545477328> rc=21228buildbot # [ 37.566228] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 1): startCommand:stat1229buildbot # [ 37.567366] twistd[819]: 2026-09-13T07:11:43+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'1230buildbot # [ 37.578509] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 1): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.StatFile object at 0xe7ddcc0efb60>1231buildbot # [ 37.594108] twistd[818]: 2026-09-13T07:11:43+0000 [-] <RemoteCommand 'mkdir' at 262124545766288>: RemoteCommand.run [2]1232buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1233buildbot # Dload Upload Total Spent Left Speed1234buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 38502 0 [ 37.613707] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 2): startCommand:mkdir1235buildbot # [ 37.614909] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 2): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.MakeDirectory object at 0xe7ddcb9b1010>1236buildbot # 0100 401 100 401 0 0 21302 0 [ 37.623208] twistd[818]: 2026-09-13T07:11:43+0000 [Broker,0,127.0.0.1] <RemoteCommand 'mkdir' at 262124545766288> rc=01237buildbot # 0100 401 100 401 0 0 15709 0 [ 37.628332] twistd[818]: 2026-09-13T07:11:43+0000 [-] <RemoteCommand 'downloadFile' at 262124545767568>: RemoteCommand.run [3]1238buildbot # 01239buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1240buildbot # [ 37.661134] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 3): startCommand:downloadFile1241buildbot # [ 37.664761] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 3): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.transfer.WorkerFileDownloadCommand object at 0xe7ddcb9b0d70>1242buildbot # [ 37.670162] twistd[818]: 2026-09-13T07:11:43+0000 [Broker,0,127.0.0.1] <RemoteCommand 'downloadFile' at 262124545767568> rc=01243buildbot # [ 37.675114] twistd[818]: 2026-09-13T07:11:43+0000 [-] <RemoteCommand 'listdir' at 262124545766928>: RemoteCommand.run [4]1244buildbot # [ 37.709143] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 4): startCommand:listdir1245buildbot # [ 37.710781] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 4): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.ListDir object at 0xe7ddcb9b0c20>1246buildbot # [ 37.717287] twistd[818]: 2026-09-13T07:11:43+0000 [Broker,0,127.0.0.1] <RemoteCommand 'listdir' at 262124545766928> rc=01247buildbot # [ 37.724075] twistd[818]: 2026-09-13T07:11:43+0000 [-] No git repo present, making full clone1248buildbot # [ 37.726609] twistd[818]: 2026-09-13T07:11:43+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]1249buildbot # [ 37.731829] twistd[818]: 2026-09-13T07:11:43+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'1250buildbot # [ 37.757239] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 5): startCommand:shell1251buildbot # [ 37.762775] twistd[819]: 2026-09-13T07:11:43+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._startCommand1252buildbot # [ 37.777084] twistd[819]: 2026-09-13T07:11:43+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 . --progress1253buildbot # [ 37.794517] twistd[819]: 2026-09-13T07:11:43+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)1254buildbot # [ 37.800631] twistd[819]: 2026-09-13T07:11:43+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 {}1255buildbot # [ 37.806025] twistd[819]: 2026-09-13T07:11:43+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']1256buildbot # [ 37.815117] twistd[819]: 2026-09-13T07:11:43+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: False1257buildbot # [ 37.979866] sshd-session[1038]: Accepted publickey for root from ::1 port 59464 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81258buildbot # [ 37.992854] sshd-session[1038]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1259buildbot # [ 38.002901] systemd-logind[411]: New session '6' of user 'root' with class 'user' and type 'tty'.1260buildbot # [ 38.009296] systemd[1]: Started Session 6 of User root.1261buildbot # [ 38.053551] sshd-session[1042]: Received disconnect from ::1 port 59464:11: disconnected by user1262buildbot # [ 38.057614] sshd-session[1042]: Disconnected from user root ::1 port 594641263buildbot # [ 38.059354] sshd-session[1038]: pam_unix(sshd:session): session closed for user root1264buildbot # [ 38.064145] systemd[1]: session-6.scope: Deactivated successfully.1265buildbot # [ 38.066964] systemd-logind[411]: Session 6 logged out. Waiting for processes to exit.1266buildbot # [ 38.070859] systemd-logind[411]: Removed session 6.1267buildbot # [ 38.081446] twistd[819]: 2026-09-13T07:11:43+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.3207241268buildbot # [ 38.092884] twistd[819]: 2026-09-13T07:11:43+0000 [-] (command 5): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcc0496d0>1269buildbot # [ 38.095189] twistd[818]: 2026-09-13T07:11:43+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=01270buildbot # [ 38.116075] twistd[818]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']'>: RemoteCommand.run [6]1271buildbot # [ 38.119370] twistd[818]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']' in dir 'build'1272buildbot # [ 38.133005] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 6): startCommand:shell1273buildbot # [ 38.137103] twistd[819]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']): RunProcess._startCommand1274buildbot # [ 38.146100] twistd[819]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']): 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 02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb61275buildbot # [ 38.153655] twistd[819]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1276buildbot # [ 38.158314] twistd[819]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']): watching logfiles {}1277buildbot # [ 38.162914] twistd[819]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']): 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'02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']1278buildbot # [ 38.170648] twistd[819]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']): using PTY: False1279buildbot # [ 38.177855] twistd[819]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']): command finished with signal None, exit code 0, elapsedTime: 0.0417571280buildbot # [ 38.181667] twistd[819]: 2026-09-13T07:11:43+0000 [-] (command 6): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcc04a990>1281buildbot # [ 38.189809] twistd[818]: 2026-09-13T07:11:43+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', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6']'> rc=01282buildbot # [ 38.212076] twistd[818]: 2026-09-13T07:11:43+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]1283buildbot # [ 38.215228] twistd[818]: 2026-09-13T07:11:43+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'1284buildbot # [ 38.237028] twistd[819]: 2026-09-13T07:11:43+0000 [Broker,client] (command 7): startCommand:shell1285buildbot # [ 38.240495] twistd[819]: 2026-09-13T07:11:43+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._startCommand1286buildbot # [ 38.243716] twistd[819]: 2026-09-13T07:11:43+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 --recursive1287buildbot # [ 38.257280] twistd[819]: 2026-09-13T07:11:43+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)1288buildbot # [ 38.262439] twistd[819]: 2026-09-13T07:11:43+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 {}1289buildbot # [ 38.266987] twistd[819]: 2026-09-13T07:11:43+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']1290buildbot # [ 38.273631] twistd[819]: 2026-09-13T07:11:43+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: False1291buildbot # [ 38.340124] twistd[819]: 2026-09-13T07:11:43+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.1002651292buildbot # [ 38.343712] twistd[819]: 2026-09-13T07:11:43+0000 [-] (command 7): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcbfba780>1293buildbot # [ 38.350607] twistd[818]: 2026-09-13T07:11:43+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=01294buildbot # [ 38.365967] twistd[818]: 2026-09-13T07:11:44+0000 [-] <RemoteShellCommand '['git', 'rev-parse', 'HEAD']'>: RemoteCommand.run [8]1295buildbot # [ 38.367480] twistd[818]: 2026-09-13T07:11:44+0000 [-] command '['git', 'rev-parse', 'HEAD']' in dir 'build'1296buildbot # [ 38.388965] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command 8): startCommand:shell1297buildbot # [ 38.392472] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): RunProcess._startCommand1298buildbot # [ 38.394004] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): git rev-parse HEAD1299buildbot # [ 38.395411] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1300buildbot # [ 38.405176] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): watching logfiles {}1301buildbot # [ 38.406703] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): argv: [b'git', b'rev-parse', b'HEAD']1302buildbot # [ 38.408674] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): using PTY: False1303buildbot # [ 38.414288] twistd[819]: 2026-09-13T07:11:44+0000 [-] (command ['git', 'rev-parse', 'HEAD']): command finished with signal None, exit code 0, elapsedTime: 0.0222881304buildbot # [ 38.416240] twistd[819]: 2026-09-13T07:11:44+0000 [-] (command 8): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcbfba8b0>1305buildbot # [ 38.445911] twistd[818]: 2026-09-13T07:11:44+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', 'rev-parse', 'HEAD']'> rc=01306buildbot # [ 38.468086] twistd[818]: 2026-09-13T07:11:44+0000 [-] Got Git revision 02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb61307buildbot # [ 38.469561] twistd[818]: 2026-09-13T07:11:44+0000 [-] <RemoteCommand 'rmdir' at 262124545768208>: RemoteCommand.run [9]1308buildbot # [ 38.495512] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command 9): startCommand:rmdir1309buildbot # [ 38.499614] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): RunProcess._startCommand1310buildbot # [ 38.505675] twistd[819]: 2026-09-13T07:11:44+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.buildbot1311buildbot # [ 38.509194] twistd[819]: 2026-09-13T07:11:44+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)1312buildbot # [ 38.514981] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): watching logfiles {}1313buildbot # [ 38.517913] twistd[819]: 2026-09-13T07:11:44+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']1314buildbot # [ 38.521541] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): using PTY: False1315buildbot # [ 38.527146] twistd[819]: 2026-09-13T07:11:44+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.0325051316buildbot # [ 38.530035] twistd[819]: 2026-09-13T07:11:44+0000 [-] (command 9): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.RemoveDirectory object at 0xe7ddcb9b3380>1317buildbot # [ 38.549574] twistd[818]: 2026-09-13T07:11:44+0000 [Broker,0,127.0.0.1] <RemoteCommand 'rmdir' at 262124545768208> rc=01318buildbot # [ 38.573335] twistd[818]: 2026-09-13T07:11:44+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)): []1319buildbot # [ 38.597321] twistd[818]: 2026-09-13T07:11:44+0000 [-] step 'git' complete: success (None)1320buildbot # [ 38.606208] twistd[818]: 2026-09-13T07:11:44+0000 [-] acquireLocks(step NixEvalCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xee66a868c1a0>, 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 0xee66a868d940>, gcroots_user='buildbot-worker', cache_failed_builds=False, show_trace=False), haltOnFailure=True, locks=[<buildbot.locks.LockAccess object at 0xee66a868ecf0>], 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 0xee66a868ecf0>)])1321buildbot # [ 38.627738] twistd[818]: 2026-09-13T07:11:44+0000 [-] <RemoteShellCommand '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']'>: RemoteCommand.run [10]1322buildbot # [ 38.632631] twistd[818]: 2026-09-13T07:11:44+0000 [-] command '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']' in dir 'build'1323buildbot # [ 38.634463] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command 10): startCommand:shell1324buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1325buildbot # [ 38.635596] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): RunProcess._startCommand1326buildbot # [ 38.644060] twistd[819]: 2026-09-13T07:11:44+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'1327buildbot # [ 38.646361] twistd[819]: 2026-09-13T07:11:44+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)1328buildbot # [ 38.655955] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): watching logfiles {}1329buildbot # [ 38.664448] twistd[819]: 2026-09-13T07:11:44+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']1330buildbot # [ 38.671088] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): using PTY: False1331buildbot # [ 38.673639] twistd[819]: 2026-09-13T07:11:44+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.0337251332buildbot # [ 38.676271] twistd[819]: 2026-09-13T07:11:44+0000 [-] (command 10): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcbfdee70>1333buildbot # [ 38.682312] twistd[818]: 2026-09-13T07:11:44+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']'> rc=01334buildbot # [ 38.686864] twistd[818]: 2026-09-13T07:11:44+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]1335buildbot # [ 38.691657] twistd[818]: 2026-09-13T07:11:44+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'1336buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1337buildbot # Dload Upload Total Spent Left Speed1338buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 38781 0 0100 401 100 401 0 0 31520 0 0100 401 100 401 0 0 26212 0 01339buildbot # [ 38.727531] twistd[819]: 2026-09-13T07:11:44+0000 [Broker,client] (command 11): startCommand:shell1340buildbot # [ 38.732771] twistd[819]: 2026-09-13T07:11:44+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._startCommand1341buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1342buildbot # [ 38.742203] twistd[819]: 2026-09-13T07:11:44+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'1343buildbot # [ 38.750079] twistd[819]: 2026-09-13T07:11:44+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)1344buildbot # [ 38.757869] twistd[819]: 2026-09-13T07:11:44+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 {}1345buildbot # [ 38.762607] twistd[819]: 2026-09-13T07:11:44+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']1346buildbot # [ 38.770924] twistd[819]: 2026-09-13T07:11:44+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: False1347buildbot # [ 38.883269] systemd[1]: Started Nix Daemon.1348buildbot # [ 38.946787] nix-daemon[1085]: accepted connection from pid 1083, user buildbot-worker1349buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1350buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1351buildbot # Dload Upload Total Spent Left Speed1352buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 17586 0 0100 401 100 401 0 0 14518 0 0100 401 100 401 0 0 12674 0 01353buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1354buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1355buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1356buildbot # Dload Upload Total Spent Left Speed1357buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 14868 0 0100 401 100 401 0 0 12631 0 0100 401 100 401 0 0 11215 0 01358buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1359buildbot # [ 41.535413] sshd-session[1103]: Accepted publickey for root from ::1 port 44000 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81360buildbot # [ 41.556124] sshd-session[1103]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1361buildbot # [ 41.569989] systemd-logind[411]: New session '7' of user 'root' with class 'user' and type 'tty'.1362buildbot # [ 41.579716] systemd[1]: Started Session 7 of User root.1363buildbot # [ 41.604926] sshd-session[1106]: Received disconnect from ::1 port 44000:11: disconnected by user1364buildbot # [ 41.606493] sshd-session[1106]: Disconnected from user root ::1 port 440001365buildbot # [ 41.608335] sshd-session[1103]: pam_unix(sshd:session): session closed for user root1366buildbot # [ 41.626787] systemd[1]: session-7.scope: Deactivated successfully.1367buildbot # [ 41.629693] systemd-logind[411]: Session 7 logged out. Waiting for processes to exit.1368buildbot # [ 41.631046] systemd-logind[411]: Removed session 7.1369buildbot # [ 41.805480] sshd-session[1111]: Accepted publickey for root from ::1 port 44006 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81370buildbot # [ 41.825070] sshd-session[1111]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1371buildbot # [ 41.837781] systemd-logind[411]: New session '8' of user 'root' with class 'user' and type 'tty'.1372buildbot # [ 41.845061] systemd[1]: Started Session 8 of User root.1373buildbot # [ 41.882514] sshd-session[1114]: Received disconnect from ::1 port 44006:11: disconnected by user1374buildbot # [ 41.886623] sshd-session[1114]: Disconnected from user root ::1 port 440061375buildbot # [ 41.890473] sshd-session[1111]: pam_unix(sshd:session): session closed for user root1376buildbot # [ 41.894439] systemd[1]: session-8.scope: Deactivated successfully.1377buildbot # [ 41.900160] systemd-logind[411]: Session 8 logged out. Waiting for processes to exit.1378buildbot # [ 41.905685] systemd-logind[411]: Removed session 8.1379buildbot # [ 41.908534] twistd[818]: 2026-09-13T07:11:47+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1380buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1381buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1382buildbot # Dload Upload Total Spent Left Speed1383buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 26545 0 0100 401 100 401 0 0 21936 0 0100 401 100 401 0 0 19124 0 01384buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.06 seconds)1385buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1386buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1387buildbot # Dload Upload Total Spent Left Speed1388buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 16540 0 0100 401 100 401 0 0 13346 0 0100 401 100 401 0 0 11617 0 01389buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1390buildbot # [ 43.692622] nix-daemon[1085]: accepted connection from pid 1132, user buildbot-worker1391buildbot # [ 43.714038] nix-daemon[1085]: accepted connection from pid 1083, user buildbot-worker1392buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1393buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1394buildbot # Dload Upload Total Spent Left Speed1395buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 15712 0 0100 401 100 401 0 0 13655 0 0100 401 100 401 0 0 12145 0 01396buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.12 seconds)1397buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1398buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1399buildbot # Dload Upload Total Spent Left Speed1400buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 14091 0 0100 401 100 401 0 0 12313 0 0100 401 100 401 0 0 11148 0 01401buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.13 seconds)1402buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1403buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1404buildbot # Dload Upload Total Spent Left Speed1405buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 12630 0 0100 401 100 401 0 0 11160 0 0100 401 100 401 0 0 10133 0 01406buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.13 seconds)1407buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1408buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1409buildbot # Dload Upload Total Spent Left Speed1410buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 17546 0 0100 401 100 401 0 0 14912 0 0100 401 100 401 0 0 13098 0 01411buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1412buildbot # [ 48.060462] twistd[819]: 2026-09-13T07:11:53+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: 9.3245781413buildbot # [ 48.081049] twistd[819]: 2026-09-13T07:11:53+0000 [-] (command 11): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcc13b8a0>1414buildbot # [ 48.089586] twistd[818]: 2026-09-13T07:11:53+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=01415buildbot # [ 48.148413] twistd[818]: 2026-09-13T07:11:53+0000 [-] releaseLocks(NixEvalCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xee66a868c1a0>, 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 0xee66a868d940>, gcroots_user='buildbot-worker', cache_failed_builds=False, show_trace=False), haltOnFailure=True, locks=[<buildbot.locks.LockAccess object at 0xee66a868ecf0>], 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 0xee66a868ecf0>)]1416buildbot # [ 48.173133] twistd[818]: 2026-09-13T07:11:53+0000 [-] step 'Evaluate flake' complete: success (None)1417buildbot # [ 48.221382] twistd[818]: 2026-09-13T07:11:53+0000 [-] added buildset 2 to database1418buildbot # [ 48.252433] twistd[818]: 2026-09-13T07:11:53+0000 [-] added buildset 3 to database1419buildbot # [ 48.406674] twistd[818]: 2026-09-13T07:11:54+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 # [ 48.420071] twistd[818]: 2026-09-13T07:11:54+0000 [-] <Build test-flake/nix-build number:None results:success>.startBuild1421buildbot # [ 48.508121] twistd[818]: 2026-09-13T07:11:54+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1422buildbot # [ 48.513201] twistd[818]: 2026-09-13T07:11:54+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>1423buildbot # [ 48.515906] twistd[818]: 2026-09-13T07:11:54+0000 [-] sending ping1424buildbot # [ 48.518128] twistd[819]: 2026-09-13T07:11:54+0000 [Broker,client] message from master: ping1425buildbot # [ 48.521645] twistd[818]: 2026-09-13T07:11:54+0000 [Broker,0,127.0.0.1] ping finished: success1426buildbot # [ 48.543942] twistd[818]: 2026-09-13T07:11:54+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]1427buildbot # [ 48.555051] twistd[818]: 2026-09-13T07:11:54+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'1428buildbot # [ 48.559812] twistd[819]: 2026-09-13T07:11:54+0000 [Broker,client] (command 12): startCommand:shell1429buildbot # [ 48.563968] twistd[819]: 2026-09-13T07:11:54+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._startCommand1430buildbot # [ 48.568235] twistd[819]: 2026-09-13T07:11:54+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^*'1431buildbot # [ 48.574234] twistd[819]: 2026-09-13T07:11:54+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)1432buildbot # [ 48.579351] twistd[819]: 2026-09-13T07:11:54+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 {}1433buildbot # [ 48.583846] twistd[819]: 2026-09-13T07:11:54+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^*']1434buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1435buildbot # [ 48.591536] twistd[819]: 2026-09-13T07:11:54+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: False1436buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1437buildbot # Dload Upload Total Spent Left Speed1438buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 37990 0 0100 751 100 751 0 0 29622 0 0100 751 100 751 0 0 23739 0 01439buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.08 seconds)1440buildbot # [ 48.682169] nix-daemon[1085]: accepted connection from pid 1156, user buildbot-worker1441buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1442buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1443buildbot # Dload Upload Total Spent Left Speed1444buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 28395 0 0100 751 100 751 0 0 24243 0 0100 751 100 751 0 0 21433 0 01445buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1446buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1447buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1448buildbot # Dload Upload Total Spent Left Speed1449buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 49983 0 0100 751 100 751 0 0 37053 0 0100 751 100 751 0 0 29983 0 01450buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1451buildbot # [ 51.548295] sshd-session[1177]: Accepted publickey for root from ::1 port 40072 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81452buildbot # [ 51.568205] sshd-session[1177]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1453buildbot # [ 51.583910] systemd-logind[411]: New session '9' of user 'root' with class 'user' and type 'tty'.1454buildbot # [ 51.597110] systemd[1]: Started Session 9 of User root.1455buildbot # [ 51.632677] sshd-session[1180]: Received disconnect from ::1 port 40072:11: disconnected by user1456buildbot # [ 51.634743] sshd-session[1180]: Disconnected from user root ::1 port 400721457buildbot # [ 51.637208] sshd-session[1177]: pam_unix(sshd:session): session closed for user root1458buildbot # [ 51.652557] systemd[1]: session-9.scope: Deactivated successfully.1459buildbot # [ 51.653823] systemd-logind[411]: Session 9 logged out. Waiting for processes to exit.1460buildbot # [ 51.662899] systemd-logind[411]: Removed session 9.1461buildbot # [ 51.838336] sshd-session[1185]: Accepted publickey for root from ::1 port 40088 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81462buildbot # [ 51.858227] sshd-session[1185]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1463buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1464buildbot # [ 51.872169] systemd-logind[411]: New session '10' of user 'root' with class 'user' and type 'tty'.1465buildbot # [ 51.887825] systemd[1]: Started Session 10 of User root.1466buildbot # [ 51.935148] sshd-session[1191]: Received disconnect from ::1 port 40088:11: disconnected by user1467buildbot # [ 51.941596] sshd-session[1191]: Disconnected from user root ::1 port 400881468buildbot # [ 51.942616] sshd-session[1185]: pam_unix(sshd:session): session closed for user root1469buildbot # % Total % Received[ 51.955106] systemd[1]: session-10.scope: Deactivated successfully.1470buildbot # % Xferd Average Speed Time Time Time Current1471buildbot # Dload Upload Total S[ 51.956002] systemd-logind[411]: Session 10 logged out. Waiting for processes to exit.1472buildbot # [ 51.962248] systemd-logind[411]: Removed session 10.1473buildbot # pent Left Speed1474buildbot # 0 0 0 0 0 [ 51.968446] twistd[818]: 2026-09-13T07:11:57+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1475buildbot # 0 0 0 0100 751 100 751 0 0 15953 0 0100 751 100 751 0 0 15191 0 0100 751 100 751 0 0 13533 0 01476buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1477buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1478buildbot # % Total % Received % Xferd Average Speed Time Time Time Cu[ 53.081456] twistd[819]: 2026-09-13T07:11:58+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.5380421479buildbot # rrent1480buildbot # Dload Upload Total Spent Left Speed1481buildbot # 0 0 0 0 0 0 0 0 0[ 53.099626] twistd[819]: 2026-09-13T07:11:58+0000 [-] (command 12): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcc13bf00>1482buildbot # [ 53.107488] twistd[818]: 2026-09-13T07:11:58+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=11483buildbot # 100 751 100 751 0 0 20004 0 0100 751 100 751 0 0 18793 0 0100 751 100 751 0 0 15439 0 01484buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.12 seconds)1485buildbot # [ 53.145458] twistd[818]: 2026-09-13T07:11:58+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)): []1486buildbot # [ 53.162796] twistd[818]: 2026-09-13T07:11:58+0000 [-] step 'Build flake attr' complete: failure (["'nix build ...' (failure)"])1487buildbot # [ 53.166125] twistd[818]: 2026-09-13T07:11:58+0000 [-] <Build test-flake/nix-build number:1 results:failure>: build finished1488buildbot # [ 53.176075] twistd[818]: 2026-09-13T07:11:58+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1489buildbot # [ 53.323947] twistd[818]: 2026-09-13T07:11:58+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>1490buildbot # [ 53.335620] twistd[818]: 2026-09-13T07:11:58+0000 [-] <Build test-flake/nix-build number:None results:success>.startBuild1491buildbot # [ 53.421755] twistd[818]: 2026-09-13T07:11:59+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1492buildbot # [ 53.427189] twistd[818]: 2026-09-13T07:11:59+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>1493buildbot # [ 53.431199] twistd[818]: 2026-09-13T07:11:59+0000 [-] sending ping1494buildbot # [ 53.434225] twistd[818]: 2026-09-13T07:11:59+0000 [Broker,0,127.0.0.1] ping finished: success1495buildbot # [ 53.435354] twistd[819]: 2026-09-13T07:11:59+0000 [Broker,client] message from master: ping1496buildbot # [ 53.457653] twistd[818]: 2026-09-13T07:11:59+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]1497buildbot # [ 53.467982] twistd[818]: 2026-09-13T07:11:59+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'1498buildbot # [ 53.471171] twistd[819]: 2026-09-13T07:11:59+0000 [Broker,client] (command 13): startCommand:shell1499buildbot # [ 53.475437] twistd[819]: 2026-09-13T07:11:59+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._startCommand1500buildbot # [ 53.479637] twistd[819]: 2026-09-13T07:11:59+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^*'1501buildbot # [ 53.485355] twistd[819]: 2026-09-13T07:11:59+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)1502buildbot # [ 53.489879] twistd[819]: 2026-09-13T07:11:59+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 {}1503buildbot # [ 53.494075] twistd[819]: 2026-09-13T07:11:59+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^*']1504buildbot # [ 53.500646] twistd[819]: 2026-09-13T07:11:59+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: False1505buildbot # [ 53.540891] nix-daemon[1085]: accepted connection from pid 1216, user buildbot-worker1506buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1507buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1508buildbot # Dload Upload Total Spent Left Speed1509buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 71369 0 0100 1127 100 1127 0 0 54497 0 0100 1127 100 1127 0 0 44798 0 01510buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1511buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1512buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1513buildbot # Dload Upload Total Spent Left Speed1514buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 47123 0 0100 1127 100 1127 0 0 40009 0 0100 1127 100 1127 0 0 35158 0 01515buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1516buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1517buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1518buildbot # Dload Upload Total Spent Left Speed1519buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 39532 0 0100 1127 100 1127 0 0 34432 0 0100 1127 100 1127 0 0 30629 0 01520buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1521buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1522buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1523buildbot # Dload Upload Total Spent Left Speed1524buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 46094 0 0100 1127 100 1127 0 0 39087 0 0100 1127 100 1127 0 0 34332 0 01525buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1526buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1527buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1528buildbot # Dload Upload Total Spent Left Speed1529buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 78651 0 0100 1127 100 1127 0 0 59203 0 0100 1127 100 1127 0 0 48311 0 01530buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.09 seconds)1531buildbot # [ 58.771858] twistd[819]: 2026-09-13T07:12:04+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: 5.3158941532buildbot # [ 58.779562] twistd[819]: 2026-09-13T07:12:04+0000 [-] (command 13): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcbfef950>1533buildbot # [ 58.783936] twistd[818]: 2026-09-13T07:12:04+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=01534buildbot # [ 58.808642] twistd[818]: 2026-09-13T07:12:04+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)): []1535buildbot # [ 58.823230] twistd[818]: 2026-09-13T07:12:04+0000 [-] step 'Build flake attr' complete: success (None)1536buildbot # [ 58.848667] twistd[818]: 2026-09-13T07:12:04+0000 [-] added buildset 4 to database1537buildbot # [ 58.996754] twistd[818]: 2026-09-13T07:12:04+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>1538buildbot # [ 59.006038] twistd[818]: 2026-09-13T07:12:04+0000 [-] <Build test-flake/nix-register-gcroot number:None results:success>.startBuild1539buildbot # [ 59.072121] twistd[818]: 2026-09-13T07:12:04+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1540buildbot # [ 59.078270] twistd[818]: 2026-09-13T07:12:04+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>1541buildbot # [ 59.082908] twistd[818]: 2026-09-13T07:12:04+0000 [-] sending ping1542buildbot # [ 59.086030] twistd[819]: 2026-09-13T07:12:04+0000 [Broker,client] message from master: ping1543buildbot # [ 59.087255] twistd[818]: 2026-09-13T07:12:04+0000 [Broker,0,127.0.0.1] ping finished: success1544buildbot # [ 59.110339] twistd[818]: 2026-09-13T07:12:04+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]1545buildbot # [ 59.116966] twistd[818]: 2026-09-13T07:12:04+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'1546buildbot # [ 59.119943] twistd[819]: 2026-09-13T07:12:04+0000 [Broker,client] (command 14): startCommand:shell1547buildbot # [ 59.125503] twistd[819]: 2026-09-13T07:12:04+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._startCommand1548buildbot # [ 59.131348] twistd[819]: 2026-09-13T07:12:04+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-test1549buildbot # [ 59.136297] twistd[819]: 2026-09-13T07:12:04+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)1550buildbot # [ 59.140389] twistd[819]: 2026-09-13T07:12:04+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 {}1551buildbot # [ 59.143731] twistd[819]: 2026-09-13T07:12:04+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']1552buildbot # [ 59.149139] twistd[819]: 2026-09-13T07:12:04+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: False1553buildbot # [ 59.188877] nix-daemon[1085]: accepted connection from pid 1249, user buildbot-worker1554buildbot # [ 59.206668] twistd[819]: 2026-09-13T07:12:04+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.0960421555buildbot # [ 59.212383] twistd[819]: 2026-09-13T07:12:04+0000 [-] (command 14): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcbfefa50>1556buildbot # [ 59.214709] twistd[818]: 2026-09-13T07:12:04+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=01557buildbot # [ 59.242756] twistd[818]: 2026-09-13T07:12:04+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)): []1558buildbot # [ 59.261699] twistd[818]: 2026-09-13T07:12:04+0000 [-] step 'Register gcroot' complete: success (None)1559buildbot # [ 59.262975] twistd[818]: 2026-09-13T07:12:04+0000 [-] <Build test-flake/nix-register-gcroot number:1 results:success>: build finished1560buildbot # [ 59.280178] twistd[818]: 2026-09-13T07:12:04+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1561buildbot # [ 59.406556] twistd[818]: 2026-09-13T07:12:05+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 0xee66a8707e20>, copy_properties=['out_path', 'attr'], set_properties={'report_status': False})): []1562buildbot # [ 59.422205] twistd[818]: 2026-09-13T07:12:05+0000 [-] step 'Register gcroot' complete: success (None)1563buildbot # [ 59.440108] twistd[818]: 2026-09-13T07:12:05+0000 [-] <RemoteShellCommand '['rm', '-f', 'result-aarch64-linux.test']'>: RemoteCommand.run [15]1564buildbot # [ 59.441800] twistd[818]: 2026-09-13T07:12:05+0000 [-] command '['rm', '-f', 'result-aarch64-linux.test']' in dir 'build'1565buildbot # [ 59.447590] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command 15): startCommand:shell1566buildbot # [ 59.452105] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): RunProcess._startCommand1567buildbot # [ 59.453754] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): rm -f result-aarch64-linux.test1568buildbot # [ 59.455427] twistd[819]: 2026-09-13T07:12:05+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)1569buildbot # [ 59.462805] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): watching logfiles {}1570buildbot # [ 59.464896] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): argv: [b'rm', b'-f', b'result-aarch64-linux.test']1571buildbot # [ 59.467805] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-f', 'result-aarch64-linux.test']): using PTY: False1572buildbot # [ 59.471505] twistd[819]: 2026-09-13T07:12:05+0000 [-] (command ['rm', '-f', 'result-aarch64-linux.test']): command finished with signal None, exit code 0, elapsedTime: 0.0247041573buildbot # [ 59.473720] twistd[819]: 2026-09-13T07:12:05+0000 [-] (command 15): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcb9f4050>1574buildbot # [ 59.501673] twistd[818]: 2026-09-13T07:12:05+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['rm', '-f', 'result-aarch64-linux.test']'> rc=01575buildbot # [ 59.525666] twistd[818]: 2026-09-13T07:12:05+0000 [-] releaseLocks(ShellCommand(name='Delete temporary gcroots', command=['rm', '-f', Interpolate('result-%(prop:attr)s')], logEnviron=False)): []1576buildbot # [ 59.535277] twistd[818]: 2026-09-13T07:12:05+0000 [-] step 'Delete temporary gcroots' complete: success (None)1577buildbot # [ 59.549653] twistd[818]: 2026-09-13T07:12:05+0000 [-] releaseLocks(UpdateBuildOutput(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xee66a868c1a0>, name='Update build output', path=PosixPath('/var/lib/buildbot-outputs'), branch_config={})): []1578buildbot # [ 59.558242] twistd[818]: 2026-09-13T07:12:05+0000 [-] step 'Update build output' complete: success (None)1579buildbot # [ 59.560404] twistd[818]: 2026-09-13T07:12:05+0000 [-] <Build test-flake/nix-build number:1 results:success>: build finished1580buildbot # [ 59.578181] twistd[818]: 2026-09-13T07:12:05+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1581buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1582buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1583buildbot # Dload Upload Total Spent Left Speed1584buildbot # 0 0 0 0 0 0 0 0 [ 59.761303] twistd[818]: 2026-09-13T07:12:05+0000 [-] releaseLocks(BuildTrigger(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xee66a868c1a0>, 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')): []1585buildbot # 0100 1496 100 1496 0 0 41150 0 0100 1496 100 1496 0 0 35424 0 0100 1496 100 1496 0 0 33565 0 [ 59.804628] twistd[818]: 2026-09-13T07:12:05+0000 [-] step 'build flake' complete: failure (['(1 success, 1 failure, 1 skipped)'])1586buildbot # 01587buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.19 seconds)1588buildbot # [ 59.823398] twistd[818]: 2026-09-13T07:12:05+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]1589buildbot # [ 59.832135] twistd[818]: 2026-09-13T07:12:05+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'1590buildbot # [ 59.835408] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command 16): startCommand:shell1591buildbot # [ 59.843965] twistd[819]: 2026-09-13T07:12:05+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._startCommand1592buildbot # [ 59.847781] twistd[819]: 2026-09-13T07:12:05+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-test1593buildbot # [ 59.853386] twistd[819]: 2026-09-13T07:12:05+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)1594buildbot # [ 59.857946] twistd[819]: 2026-09-13T07:12:05+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 {}1595buildbot # [ 59.861785] twistd[819]: 2026-09-13T07:12:05+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']1596buildbot # [ 59.867365] twistd[819]: 2026-09-13T07:12:05+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: False1597buildbot # [ 59.906684] nix-daemon[1085]: accepted connection from pid 1265, user buildbot-worker1598buildbot # [ 59.924112] twistd[819]: 2026-09-13T07:12:05+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.0927291599buildbot # [ 59.927628] twistd[819]: 2026-09-13T07:12:05+0000 [-] (command 16): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcb9f4500>1600buildbot # [ 59.932798] twistd[818]: 2026-09-13T07:12:05+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=01601buildbot # [ 59.961880] twistd[818]: 2026-09-13T07:12:05+0000 [-] releaseLocks(ProcessSkippedBuilds(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xee66a868c1a0>, 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 0xee66a8707060>, hideStepIf=<function nix_eval_config.<locals>.<lambda> at 0xee66a8707110>)): []1602buildbot # [ 59.975102] twistd[818]: 2026-09-13T07:12:05+0000 [-] step 'Process skipped builds' complete: success (None)1603buildbot # [ 59.988056] twistd[818]: 2026-09-13T07:12:05+0000 [-] <RemoteShellCommand '['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']'>: RemoteCommand.run [17]1604buildbot # [ 59.990351] twistd[818]: 2026-09-13T07:12:05+0000 [-] command '['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']' in dir 'build'1605buildbot # [ 60.000140] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command 17): startCommand:shell1606buildbot # [ 60.001327] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): RunProcess._startCommand1607buildbot # [ 60.003459] twistd[819]: 2026-09-13T07:12:05+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/1608buildbot # [ 60.010480] twistd[819]: 2026-09-13T07:12:05+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)1609buildbot # [ 60.013982] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): watching logfiles {}1610buildbot # [ 60.017991] twistd[819]: 2026-09-13T07:12:05+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/']1611buildbot # [ 60.021259] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): using PTY: False1612buildbot # [ 60.025338] twistd[819]: 2026-09-13T07:12:05+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.0298141613buildbot # [ 60.027929] twistd[819]: 2026-09-13T07:12:05+0000 [-] (command 17): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcb9b9630>1614buildbot # [ 60.041676] twistd[818]: 2026-09-13T07:12:05+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=01615buildbot # [ 60.065749] twistd[818]: 2026-09-13T07:12:05+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)): []1616buildbot # [ 60.074911] twistd[818]: 2026-09-13T07:12:05+0000 [-] step 'Cleanup drv paths' complete: success (None)1617buildbot # [ 60.085626] twistd[818]: 2026-09-13T07:12:05+0000 [-] releaseLocks(BuildbotEffectsCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xee66a868c1a0>, 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 0xee66a87075e0>, logEnviron=False)): []1618buildbot # [ 60.097556] twistd[818]: 2026-09-13T07:12:05+0000 [-] step 'Evaluate effects' complete: skipped (None)1619buildbot # [ 60.112631] twistd[818]: 2026-09-13T07:12:05+0000 [-] <RemoteShellCommand '['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']'>: RemoteCommand.run [18]1620buildbot # [ 60.115286] twistd[818]: 2026-09-13T07:12:05+0000 [-] command '['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']' in dir 'build'1621buildbot # [ 60.123752] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command 18): startCommand:shell1622buildbot # [ 60.125423] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']): RunProcess._startCommand1623buildbot # [ 60.130279] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']): buildbot-effects list-schedules --rev 02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6 --branch master --repo test-flake1624buildbot # [ 60.134409] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1625buildbot # [ 60.138807] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']): watching logfiles {}1626buildbot # [ 60.143145] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']): argv: [b'buildbot-effects', b'list-schedules', b'--rev', b'02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', b'--branch', b'master', b'--repo', b'test-flake']1627buildbot # [ 60.147517] twistd[819]: 2026-09-13T07:12:05+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']): using PTY: False1628buildbot # [ 60.329927] nix-daemon[1085]: accepted connection from pid 1274, user buildbot-worker1629buildbot # [ 60.364075] twistd[819]: 2026-09-13T07:12:06+0000 [-] (command ['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']): command finished with signal None, exit code 0, elapsedTime: 0.2519701630buildbot # [ 60.367024] twistd[819]: 2026-09-13T07:12:06+0000 [-] (command 18): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0xe7ddcb9b9a90>1631buildbot # [ 60.374128] twistd[818]: 2026-09-13T07:12:06+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['buildbot-effects', 'list-schedules', '--rev', '02f47bc7459dd5dab5aa0e3dec6a7bbb22bf5fb6', '--branch', 'master', '--repo', 'test-flake']'> rc=01632buildbot # [ 60.405596] twistd[818]: 2026-09-13T07:12:06+0000 [-] releaseLocks(ScheduledEffectsEvaluateCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0xee66a868c1a0>, 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 0xee66a87078a0>, hideStepIf=<function nix_eval_config.<locals>.<lambda> at 0xee66a8707950>, logEnviron=False)): []1633buildbot # [ 60.421526] twistd[818]: 2026-09-13T07:12:06+0000 [-] step 'Evaluate scheduled effects' complete: success (None)1634buildbot # [ 60.424209] twistd[818]: 2026-09-13T07:12:06+0000 [-] <Build test-flake/nix-eval number:1 results:failure>: build finished1635buildbot # [ 60.454224] twistd[818]: 2026-09-13T07:12:06+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1636buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1637buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1638buildbot # Dload Upload Total Spent Left Speed1639buildbot # 0 0 0 0 0 0 0 0 0100 1530 100 1530 0 0 62464 0 0100 1530 100 1530 0 0 52926 0 0100 1530 100 1530 0 0 46585 0 01640buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.11 seconds)1641buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/2/properties1642buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1643buildbot # Dload Upload Total Spent Left Speed1644buildbot # 0 0 0 0 0 0 0 0 0100 2172 100 2172 0 0 133.6k 0 0100 2172 100 2172 0 0 107.8k 0 0100 2172 100 2172 0 0 94913 0 01645buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/2/properties, in 0.06 seconds)1646buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/4/properties1647buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1648buildbot # Dload Upload Total Spent Left Speed1649buildbot # 0 0 0 0 0 0 0 0 0100 1548 100 1548 0 0 116.4k 0 0100 1548 100 1548 0 0 99097 0 0100 1548 100 1548 0 0 85054 0 01650buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/4/properties, in 0.05 seconds)1651buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/3/properties1652buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1653buildbot # Dload Upload Total Spent Left Speed1654buildbot # 0 0 0 0 0 0 0 0 0100 2132 100 2132 0 0 137.4k 0 0100 2132 100 2132 0 0 118.5k 0 0100 2132 100 2132 0 0 103.1k 0 01655buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/3/properties, in 0.05 seconds)1656buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/1/properties1657buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1658buildbot # Dload Upload Total Spent Left Speed1659buildbot # 0 0 0 0 0 0 0 0 0100 2489 100 2489 0 0 187.8k 0 0100 2489 100 2489 0 0 157.7k 0 0100 2489 100 2489 0 0 136.8k 0 01660buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/1/properties, in 0.05 seconds)1661(finished: subtest: Poller triggers builds and verifies results, in 37.75 seconds)1662subtest: Verify output paths are written for skipped builds1663buildbot: must succeed: nix config show system1664buildbot: (finished: must succeed: nix config show system, in 0.05 seconds)1665buildbot: must succeed: 1666 nix eval --raw /tmp/test-flake#checks.aarch64-linux.skippable-test.outPath1667 1668buildbot: (finished: must succeed: 1669 nix eval --raw /tmp/test-flake#checks.aarch64-linux.skippable-test.outPath1670 , in 0.08 seconds)1671buildbot: waiting for success: test -f /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test1672buildbot: (finished: waiting for success: test -f /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test, in 0.02 seconds)1673buildbot: must succeed: cat /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test1674buildbot: (finished: must succeed: cat /var/lib/buildbot-outputs/unknown/test-flake/master/aarch64-linux.skippable-test, in 0.01 seconds)1675(finished: subtest: Verify output paths are written for skipped builds, in 0.17 seconds)1676(finished: run the VM test script, in 62.06 seconds)1677test script finished in 62.10s1678cleanup1679kill QemuMachine (pid 45)1680buildbot # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)1681buildbot # [2026-09-13T07:12:07Z INFO virtiofsd] Client disconnected, shutting down1682buildbot # [2026-09-13T07:12:07Z INFO virtiofsd] Client disconnected, shutting down1683buildbot # [2026-09-13T07:12:07Z INFO virtiofsd] Client disconnected, shutting down1684(finished: cleanup, in 0.29 seconds)1685additionally exposed symbols:1686 buildbot,1687 vlan1,1688 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_ssh1689Poller has started processing1690Pushed new commit to repository1691Expected output path for skippable-test: /nix/store/fv115ywyg5wphpv3wy4iln1r9dmsk42f-skippable-test