vm-test-run-buildbot-nix-poller
x86_64-linux.poller
· build #32
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 buildbot,8 vlan1,9 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_ssh10buildbot: waiting for unit sshd.service11buildbot: waiting for the VM to finish booting12buildbot: starting vm13buildbot # Disk image does not exist, creating the virtualisation disk image...14buildbot: QEMU running (pid 12)15buildbot # Formatting '/build/vm-state-buildbot/tmp.Oc6eZ6lZVW', fmt=raw size=107374182416buildbot # mke2fs 1.47.4 (6-Mar-2025)17buildbot # Discarding device blocks: 0/262144 done18buildbot # Creating filesystem with 262144 4k blocks and 65536 inodes19buildbot # Filesystem UUID: b464bc12-d289-49aa-9e42-9f4c2cbed10d20buildbot # Superblock backups stored on blocks:21buildbot # 32768, 98304, 163840, 22937622buildbot # 23buildbot # Allocating group tables: 0/8 done24buildbot # Writing inode tables: 0/8 done25buildbot # Creating journal (8192 blocks): done26buildbot # Writing superblocks and filesystem accounting information: 0/8 done27buildbot # 28buildbot # Virtualisation disk image created.29buildbot # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)30buildbot # 31buildbot # 32buildbot # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1920+3EF31920 CA0033buildbot # Press Ctrl-B to configure iPXE (PCI 00:03.0)...34buildbot # 35buildbot # 36buildbot # 37buildbot # 38buildbot # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1920 3EF31920 CB0039buildbot # Press Ctrl-B to configure iPXE (PCI 00:09.0)...40buildbot # 41buildbot # 42buildbot # Booting from ROM...43buildbot # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Jul 4 11:44:22 UTC 202644buildbot # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/q796flybh6bhmq49nh0wb5pmzjpl5pfb-nixos-system-buildbot-test/init regInfo=/nix/store/b5vrylkfjw0gr6vsl4vk1iffakaf5rxz-closure-info/registration console=ttyS0,115200n8 console=tty045buildbot # [ 0.000000] BIOS-provided physical RAM map:46buildbot # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable47buildbot # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved48buildbot # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved49buildbot # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdafff] usable50buildbot # [ 0.000000] BIOS-e820: [mem 0x000000003ffdb000-0x000000003fffffff] reserved51buildbot # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved52buildbot # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved53buildbot # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved54buildbot # [ 0.000000] NX (Execute Disable) protection: active55buildbot # [ 0.000000] APIC: Static calls initialized56buildbot # [ 0.000000] SMBIOS 2.8 present.57buildbot # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201458buildbot # [ 0.000000] DMI: Memory slots populated: 1/159buildbot # [ 0.000000] Hypervisor detected: KVM60buildbot # [ 0.000000] last_pfn = 0x3ffdb max_arch_pfn = 0x1000000000061buildbot # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0062buildbot # [ 0.000000] kvm-clock: using sched offset of 481531132 cycles63buildbot # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns64buildbot # [ 0.000005] tsc: Detected 2400.010 MHz processor65buildbot # [ 0.000805] last_pfn = 0x3ffdb max_arch_pfn = 0x1000000000066buildbot # [ 0.000842] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs67buildbot # [ 0.000845] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT68buildbot # [ 0.002744] found SMP MP-table at [mem 0x000f5470-0x000f547f]69buildbot # [ 0.002755] Using GB pages for direct mapping70buildbot # [ 0.002866] RAMDISK: [mem 0x3e4ba000-0x3ffcffff]71buildbot # [ 0.002879] ACPI: Early table checksum verification disabled72buildbot # [ 0.002882] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )73buildbot # [ 0.002885] ACPI: RSDT 0x000000003FFE23CC 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)74buildbot # [ 0.002889] ACPI: FACP 0x000000003FFE2280 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)75buildbot # [ 0.002896] ACPI: DSDT 0x000000003FFE0040 002240 (v01 BOCHS BXPC 00000001 BXPC 00000001)76buildbot # [ 0.002898] ACPI: FACS 0x000000003FFE0000 00004077buildbot # [ 0.002900] ACPI: APIC 0x000000003FFE22F4 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)78buildbot # [ 0.002901] ACPI: HPET 0x000000003FFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)79buildbot # [ 0.002903] ACPI: WAET 0x000000003FFE23A4 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)80buildbot # [ 0.002904] ACPI: Reserving FACP table memory at [mem 0x3ffe2280-0x3ffe22f3]81buildbot # [ 0.002905] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe227f]82buildbot # [ 0.002906] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]83buildbot # [ 0.002906] ACPI: Reserving APIC table memory at [mem 0x3ffe22f4-0x3ffe236b]84buildbot # [ 0.002907] ACPI: Reserving HPET table memory at [mem 0x3ffe236c-0x3ffe23a3]85buildbot # [ 0.002907] ACPI: Reserving WAET table memory at [mem 0x3ffe23a4-0x3ffe23cb]86buildbot # [ 0.003385] No NUMA configuration found87buildbot # [ 0.003386] Faking a node at [mem 0x0000000000000000-0x000000003ffdafff]88buildbot # [ 0.003389] NODE_DATA(0) allocated [mem 0x3ffd5780-0x3ffdacff]89buildbot # [ 0.005842] Zone ranges:90buildbot # [ 0.005843] DMA [mem 0x0000000000001000-0x0000000000ffffff]91buildbot # [ 0.005844] DMA32 [mem 0x0000000001000000-0x000000003ffdafff]92buildbot # [ 0.005845] Normal empty93buildbot # [ 0.005846] Device empty94buildbot # [ 0.005847] Movable zone start for each node95buildbot # [ 0.005847] Early memory node ranges96buildbot # [ 0.005848] node 0: [mem 0x0000000000001000-0x000000000009efff]97buildbot # [ 0.005849] node 0: [mem 0x0000000000100000-0x000000003ffdafff]98buildbot # [ 0.005850] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdafff]99buildbot # [ 0.005926] On node 0, zone DMA: 1 pages in unavailable ranges100buildbot # [ 0.006190] On node 0, zone DMA: 97 pages in unavailable ranges101buildbot # [ 0.023865] On node 0, zone DMA32: 37 pages in unavailable ranges102buildbot # [ 0.024848] ACPI: PM-Timer IO Port: 0x608103buildbot # [ 0.024862] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])104buildbot # [ 0.024902] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23105buildbot # [ 0.024905] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)106buildbot # [ 0.024906] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)107buildbot # [ 0.024907] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)108buildbot # [ 0.024908] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)109buildbot # [ 0.024909] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)110buildbot # [ 0.024911] ACPI: Using ACPI (MADT) for SMP configuration information111buildbot # [ 0.024912] ACPI: HPET id: 0x8086a201 base: 0xfed00000112buildbot # [ 0.024916] TSC deadline timer available113buildbot # [ 0.024920] CPU topo: Max. logical packages: 1114buildbot # [ 0.024921] CPU topo: Max. logical dies: 1115buildbot # [ 0.024921] CPU topo: Max. dies per package: 1116buildbot # [ 0.024925] CPU topo: Max. threads per core: 1117buildbot # [ 0.024925] CPU topo: Num. cores per package: 1118buildbot # [ 0.024926] CPU topo: Num. threads per package: 1119buildbot # [ 0.024926] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs120buildbot # [ 0.024943] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()121buildbot # [ 0.024974] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]122buildbot # [ 0.024976] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]123buildbot # [ 0.024977] [mem 0x40000000-0xfeffbfff] available for PCI devices124buildbot # [ 0.024979] Booting paravirtualized kernel on KVM125buildbot # [ 0.024981] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns126buildbot # [ 0.029401] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1127buildbot # [ 0.031582] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152128buildbot # [ 0.031636] kvm-guest: PV spinlocks disabled, single CPU129buildbot # [ 0.031637] Kernel command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/q796flybh6bhmq49nh0wb5pmzjpl5pfb-nixos-system-buildbot-test/init regInfo=/nix/store/b5vrylkfjw0gr6vsl4vk1iffakaf5rxz-closure-info/registration console=ttyS0,115200n8 console=tty0130buildbot # [ 0.031725] Unknown kernel command line parameters "regInfo=/nix/store/b5vrylkfjw0gr6vsl4vk1iffakaf5rxz-closure-info/registration", will be passed to user space.131buildbot # [ 0.031737] random: crng init done132buildbot # [ 0.031738] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes133buildbot # [ 0.031758] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)134buildbot # [ 0.032307] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)135buildbot # [ 0.032341] Fallback order for Node 0: 0136buildbot # [ 0.032344] Built 1 zonelists, mobility grouping on. Total pages: 262009137buildbot # [ 0.032345] Policy zone: DMA32138buildbot # [ 0.034505] mem auto-init: stack:all(zero), heap alloc:on, heap free:off139buildbot # [ 0.036759] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1140buildbot # [ 0.039169] allocated 2097152 bytes of page_ext141buildbot # [ 0.048403] ftrace: allocating 48598 entries in 192 pages142buildbot # [ 0.048405] ftrace: allocated 192 pages with 2 groups143buildbot # [ 0.049227] Dynamic Preempt: lazy144buildbot # [ 0.049361] rcu: Preemptible hierarchical RCU implementation.145buildbot # [ 0.049361] rcu: RCU event tracing is enabled.146buildbot # [ 0.049362] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.147buildbot # [ 0.049363] Trampoline variant of Tasks RCU enabled.148buildbot # [ 0.049364] Rude variant of Tasks RCU enabled.149buildbot # [ 0.049364] Tracing variant of Tasks RCU enabled.150buildbot # [ 0.049365] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.151buildbot # [ 0.049365] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1152buildbot # [ 0.049379] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.153buildbot # [ 0.049381] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154buildbot # [ 0.049382] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155buildbot # [ 0.053656] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16156buildbot # [ 0.053940] rcu: srcu_init: Setting srcu_struct sizes based on contention.157buildbot # [ 0.054042] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)158buildbot # [ 0.061288] Console: colour VGA+ 80x25159buildbot # [ 0.061292] printk: legacy console [tty0] enabled160buildbot # [ 0.102330] printk: legacy console [ttyS0] enabled161buildbot # [ 0.290441] ACPI: Core revision 20250807162buildbot # [ 0.291992] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns163buildbot # [ 0.294770] APIC: Switch to symmetric I/O mode setup164buildbot # [ 0.296521] x2apic enabled165buildbot # [ 0.297767] APIC: Switched APIC routing to: physical x2apic166buildbot # [ 0.300594] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1167buildbot # [ 0.302417] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns168buildbot # [ 0.305497] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)169buildbot # [ 0.307613] x86/cpu: User Mode Instruction Prevention (UMIP) activated170buildbot # [ 0.308653] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127171buildbot # [ 0.309496] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0172buildbot # [ 0.310500] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto173buildbot # [ 0.311496] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl174buildbot # [ 0.312496] Transient Scheduler Attacks: Mitigation: Clear CPU buffers175buildbot # [ 0.314495] Spectre V2 : Mitigation: Enhanced / Automatic IBRS176buildbot # [ 0.316495] Speculative Return Stack Overflow: Mitigation: Safe RET177buildbot # [ 0.318495] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization178buildbot # [ 0.320501] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier179buildbot # [ 0.321496] active return thunk: srso_alias_return_thunk180buildbot # [ 0.323524] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'181buildbot # [ 0.324495] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'182buildbot # [ 0.326495] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'183buildbot # [ 0.328495] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'184buildbot # [ 0.329496] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'185buildbot # [ 0.330495] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'186buildbot # [ 0.331495] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'187buildbot # [ 0.332495] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'188buildbot # [ 0.334495] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'189buildbot # [ 0.336495] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256190buildbot # [ 0.337495] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64191buildbot # [ 0.338495] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512192buildbot # [ 0.339495] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024193buildbot # [ 0.340495] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8194buildbot # [ 0.342495] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16195buildbot # [ 0.344495] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24196buildbot # [ 0.345495] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.197buildbot # [ 0.379441] Freeing SMP alternatives memory: 44K198buildbot # [ 0.380497] pid_max: default: 32768 minimum: 301199buildbot # [ 0.381598] LSM: initializing lsm=capability,landlock,yama,bpf,ima200buildbot # [ 0.383621] landlock: Up and running.201buildbot # [ 0.385495] Yama: becoming mindful.202buildbot # [ 0.386708] LSM support for eBPF active203buildbot # [ 0.387604] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)204buildbot # [ 0.389520] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)205buildbot # [ 0.391909] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)206buildbot # [ 0.393070] Performance Events: Fam17h+ core perfctr, AMD PMU driver.207buildbot # [ 0.393505] ... version: 2208buildbot # [ 0.394497] ... bit width: 48209buildbot # [ 0.395497] ... generic counters: 6210buildbot # [ 0.396497] ... generic bitmap: 000000000000003f211buildbot # [ 0.397497] ... fixed-purpose counters: 0212buildbot # [ 0.398497] ... fixed-purpose bitmap: 0000000000000000213buildbot # [ 0.399497] ... value mask: 0000ffffffffffff214buildbot # [ 0.400497] ... max period: 00007fffffffffff215buildbot # [ 0.401497] ... global_ctrl mask: 000000000000003f216buildbot # [ 0.402598] signal: max sigframe size: 3376217buildbot # [ 0.403604] rcu: Hierarchical SRCU implementation.218buildbot # [ 0.404502] rcu: Max phase no-delay instances is 400.219buildbot # [ 0.410174] smp: Bringing up secondary CPUs ...220buildbot # [ 0.410513] smp: Brought up 1 node, 1 CPU221buildbot # [ 0.411500] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)222buildbot # [ 0.412699] Memory: 942440K/1048036K available (17159K kernel code, 2721K rwdata, 13560K rodata, 3636K init, 3020K bss, 98264K reserved, 0K cma-reserved)223buildbot # [ 0.413723] devtmpfs: initialized224buildbot # [ 0.414774] x86/mm: Memory block size: 128MB225buildbot # [ 0.416469] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns226buildbot # [ 0.417528] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)227buildbot # [ 0.418526] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).228buildbot # [ 0.419599] pinctrl core: initialized pinctrl subsystem229buildbot # [ 0.420827] PM: RTC time: 06:00:08, date: 2026-07-05230buildbot # [ 0.424168] NET: Registered PF_NETLINK/PF_ROUTE protocol family231buildbot # [ 0.424865] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations232buildbot # [ 0.425526] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations233buildbot # [ 0.426649] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations234buildbot # [ 0.427509] audit: initializing netlink subsys (disabled)235buildbot # [ 0.428753] thermal_sys: Registered thermal governor 'fair_share'236buildbot # [ 0.428756] thermal_sys: Registered thermal governor 'bang_bang'237buildbot # [ 0.429498] thermal_sys: Registered thermal governor 'step_wise'238buildbot # [ 0.430501] audit: type=2000 audit(1783231209.543:1): state=initialized audit_enabled=0 res=1239buildbot # [ 0.432500] thermal_sys: Registered thermal governor 'user_space'240buildbot # [ 0.432502] thermal_sys: Registered thermal governor 'power_allocator'241buildbot # [ 0.433517] cpuidle: using governor menu242buildbot # [ 0.436672] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5243buildbot # [ 0.437812] PCI: Using configuration type 1 for base access244buildbot # [ 0.438497] PCI: Using configuration type 1 for extended access245buildbot # [ 0.439705] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.246buildbot # [ 0.444787] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages247buildbot # [ 0.445498] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page248buildbot # [ 0.450497] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages249buildbot # [ 0.451497] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page250buildbot # [ 0.461884] ACPI: Added _OSI(Module Device)251buildbot # [ 0.462498] ACPI: Added _OSI(Processor Device)252buildbot # [ 0.467498] ACPI: Added _OSI(Processor Aggregator Device)253buildbot # [ 0.472393] ACPI: 1 ACPI AML tables successfully acquired and loaded254buildbot # [ 0.476331] ACPI: Interpreter enabled255buildbot # [ 0.477424] ACPI: PM: (supports S0 S3 S4 S5)256buildbot # [ 0.480497] ACPI: Using IOAPIC for interrupt routing257buildbot # [ 0.481520] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug258buildbot # [ 0.484496] PCI: Using E820 reservations for host bridge windows259buildbot # [ 0.485647] ACPI: Enabled 2 GPEs in block 00 to 0F260buildbot # [ 0.493475] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])261buildbot # [ 0.494503] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]262buildbot # [ 0.495897] acpiphp: Slot [3] registered263buildbot # [ 0.496553] acpiphp: Slot [4] registered264buildbot # [ 0.497538] acpiphp: Slot [5] registered265buildbot # [ 0.498545] acpiphp: Slot [6] registered266buildbot # [ 0.499575] acpiphp: Slot [7] registered267buildbot # [ 0.500554] acpiphp: Slot [8] registered268buildbot # [ 0.501540] acpiphp: Slot [9] registered269buildbot # [ 0.502537] acpiphp: Slot [10] registered270buildbot # [ 0.503538] acpiphp: Slot [11] registered271buildbot # [ 0.504552] acpiphp: Slot [12] registered272buildbot # [ 0.505545] acpiphp: Slot [13] registered273buildbot # [ 0.506530] acpiphp: Slot [14] registered274buildbot # [ 0.507530] acpiphp: Slot [15] registered275buildbot # [ 0.508567] acpiphp: Slot [16] registered276buildbot # [ 0.509530] acpiphp: Slot [17] registered277buildbot # [ 0.510530] acpiphp: Slot [18] registered278buildbot # [ 0.511545] acpiphp: Slot [19] registered279buildbot # [ 0.512530] acpiphp: Slot [20] registered280buildbot # [ 0.513531] acpiphp: Slot [21] registered281buildbot # [ 0.514530] acpiphp: Slot [22] registered282buildbot # [ 0.515556] acpiphp: Slot [23] registered283buildbot # [ 0.516531] acpiphp: Slot [24] registered284buildbot # [ 0.517530] acpiphp: Slot [25] registered285buildbot # [ 0.518530] acpiphp: Slot [26] registered286buildbot # [ 0.519548] acpiphp: Slot [27] registered287buildbot # [ 0.520530] acpiphp: Slot [28] registered288buildbot # [ 0.521530] acpiphp: Slot [29] registered289buildbot # [ 0.522544] acpiphp: Slot [30] registered290buildbot # [ 0.523552] acpiphp: Slot [31] registered291buildbot # [ 0.524521] PCI host bridge to bus 0000:00292buildbot # [ 0.525503] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]293buildbot # [ 0.526498] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]294buildbot # [ 0.527498] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]295buildbot # [ 0.528498] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]296buildbot # [ 0.529498] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]297buildbot # [ 0.530499] pci_bus 0000:00: root bus resource [bus 00-ff]298buildbot # [ 0.531871] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint299buildbot # [ 0.533413] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint300buildbot # [ 0.535426] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint301buildbot # [ 0.538237] pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef]302buildbot # [ 0.539563] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk303buildbot # [ 0.540497] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk304buildbot # [ 0.541498] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk305buildbot # [ 0.542498] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk306buildbot # [ 0.543936] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint307buildbot # [ 0.545585] pci 0000:00:01.2: BAR 4 [io 0xc100-0xc11f]308buildbot # [ 0.547707] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint309buildbot # [ 0.549159] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI310buildbot # [ 0.550513] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB311buildbot # [ 0.551929] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint312buildbot # [ 0.554574] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]313buildbot # [ 0.555528] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]314buildbot # [ 0.556551] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]315buildbot # [ 0.557727] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]316buildbot # [ 0.559594] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint317buildbot # [ 0.562535] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]318buildbot # [ 0.563512] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]319buildbot # [ 0.564558] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]320buildbot # [ 0.565512] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]321buildbot # [ 0.569054] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint322buildbot # [ 0.571564] pci 0000:00:04.0: BAR 0 [io 0xc140-0xc15f]323buildbot # [ 0.572512] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]324buildbot # [ 0.573551] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]325buildbot # [ 0.576575] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint326buildbot # [ 0.579479] pci 0000:00:05.0: BAR 0 [io 0xc080-0xc0bf]327buildbot # [ 0.580511] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]328buildbot # [ 0.581551] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]329buildbot # [ 0.584497] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint330buildbot # [ 0.587440] pci 0000:00:06.0: BAR 0 [io 0xc160-0xc17f]331buildbot # [ 0.588515] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]332buildbot # [ 0.589551] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]333buildbot # [ 0.592524] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint334buildbot # [ 0.595540] pci 0000:00:07.0: BAR 0 [io 0xc180-0xc19f]335buildbot # [ 0.596512] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]336buildbot # [ 0.597550] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]337buildbot # [ 0.600498] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint338buildbot # [ 0.603558] pci 0000:00:08.0: BAR 0 [io 0xc000-0xc07f]339buildbot # [ 0.604512] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]340buildbot # [ 0.605551] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]341buildbot # [ 0.609044] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint342buildbot # [ 0.611477] pci 0000:00:09.0: BAR 0 [io 0xc1a0-0xc1bf]343buildbot # [ 0.612512] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]344buildbot # [ 0.613551] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]345buildbot # [ 0.614512] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]346buildbot # [ 0.617489] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint347buildbot # [ 0.619525] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]348buildbot # [ 0.620578] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]349buildbot # [ 0.623524] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint350buildbot # [ 0.626468] pci 0000:00:0b.0: BAR 0 [io 0xc0c0-0xc0ff]351buildbot # [ 0.627513] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]352buildbot # [ 0.628550] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]353buildbot # [ 0.631497] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint354buildbot # [ 0.634534] pci 0000:00:0c.0: BAR 0 [io 0xc1c0-0xc1df]355buildbot # [ 0.635512] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]356buildbot # [ 0.636552] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]357buildbot # [ 0.644966] ACPI: PCI: Interrupt link LNKA configured for IRQ 10358buildbot # [ 0.645725] ACPI: PCI: Interrupt link LNKB configured for IRQ 10359buildbot # [ 0.646691] ACPI: PCI: Interrupt link LNKC configured for IRQ 11360buildbot # [ 0.647696] ACPI: PCI: Interrupt link LNKD configured for IRQ 11361buildbot # [ 0.648632] ACPI: PCI: Interrupt link LNKS configured for IRQ 9362buildbot # [ 0.650637] iommu: Default domain type: Translated363buildbot # [ 0.651507] iommu: DMA domain TLB invalidation policy: lazy mode364buildbot # [ 0.652762] ACPI: bus type USB registered365buildbot # [ 0.653577] usbcore: registered new interface driver usbfs366buildbot # [ 0.654528] usbcore: registered new interface driver hub367buildbot # [ 0.655507] usbcore: registered new device driver usb368buildbot # [ 0.657426] NetLabel: Initializing369buildbot # [ 0.658313] NetLabel: domain hash size = 128370buildbot # [ 0.659497] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO371buildbot # [ 0.660571] NetLabel: unlabeled traffic allowed by default372buildbot # [ 0.661511] PCI: Using ACPI for IRQ routing373buildbot # [ 0.663149] pci 0000:00:02.0: vgaarb: setting as boot VGA device374buildbot # [ 0.663492] pci 0000:00:02.0: vgaarb: bridge control possible375buildbot # [ 0.663492] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none376buildbot # [ 0.663500] vgaarb: loaded377buildbot # [ 0.664652] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0378buildbot # [ 0.665497] hpet0: 3 comparators, 64-bit 100.000000 MHz counter379buildbot # [ 0.670577] clocksource: Switched to clocksource kvm-clock380buildbot # [ 0.674877] VFS: Disk quotas dquot_6.6.0381buildbot # [ 0.676162] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)382buildbot # [ 0.678442] pnp: PnP ACPI init383buildbot # [ 0.680158] pnp: PnP ACPI: found 6 devices384buildbot # [ 0.688347] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns385buildbot # [ 0.690949] clocksource: Switched to clocksource acpi_pm386buildbot # [ 0.692710] NET: Registered PF_INET protocol family387buildbot # [ 0.694460] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)388buildbot # [ 0.712868] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)389buildbot # [ 0.715520] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)390buildbot # [ 0.717903] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)391buildbot # [ 0.720248] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)392buildbot # [ 0.722488] TCP: Hash tables configured (established 8192 bind 8192)393buildbot # [ 0.724511] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)394buildbot # [ 0.726815] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)395buildbot # [ 0.728813] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)396buildbot # [ 0.730941] NET: Registered PF_UNIX/PF_LOCAL protocol family397buildbot # [ 0.732700] NET: Registered PF_XDP protocol family398buildbot # [ 0.734256] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]399buildbot # [ 0.736169] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]400buildbot # [ 0.737998] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]401buildbot # [ 0.740041] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]402buildbot # [ 0.742092] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]403buildbot # [ 0.744269] pci 0000:00:01.0: PIIX3: Enabling Passive Release404buildbot # [ 0.746078] pci 0000:00:00.0: Limiting direct PCI/PCI transfers405buildbot # [ 0.749422] ACPI: \_SB_.LNKD: Enabled at IRQ 11406buildbot # [ 0.752858] PCI: CLS 0 bytes, default 64407buildbot # [ 0.754408] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns408buildbot # [ 0.757489] Trying to unpack rootfs image as initramfs...409buildbot # [ 0.805002] Initialise system trusted keyrings410buildbot # [ 0.808648] workingset: timestamp_bits=40 max_order=18 bucket_order=0411buildbot # [ 0.834927] Key type asymmetric registered412buildbot # [ 0.836279] Asymmetric key parser 'x509' registered413buildbot # [ 0.841646] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)414buildbot # [ 0.847631] io scheduler mq-deadline registered415buildbot # [ 0.849092] io scheduler kyber registered416buildbot # [ 0.853177] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled417buildbot # [ 0.855421] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A418buildbot # [ 0.864749] Linux agpgart interface v0.103419buildbot # [ 0.866186] ACPI: bus type drm_connector registered420buildbot # [ 0.870120] usbcore: registered new interface driver usbserial_generic421buildbot # [ 0.872107] usbserial: USB Serial support registered for generic422buildbot # [ 0.878598] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.423buildbot # [ 0.878598] Please enable it if your BIOS has the CPPC option.424buildbot # [ 0.882610] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled425buildbot # [ 0.891745] drop_monitor: Initializing network drop monitor service426buildbot # [ 0.893787] NET: Registered PF_INET6 protocol family427buildbot # [ 0.899107] Segment Routing with IPv6428buildbot # [ 0.903613] In-situ OAM (IOAM) with IPv6429buildbot # [ 0.905295] IPI shorthand broadcast: enabled430buildbot # [ 0.914040] sched_clock: Marking stable (669029714, 244546067)->(1114312753, -200736972)431buildbot # [ 0.922745] registered taskstats version 1432buildbot # [ 0.924365] Loading compiled-in X.509 certificates433buildbot # [ 0.947597] Demotion targets for Node 0: null434buildbot # [ 0.949198] Key type .fscrypt registered435buildbot # [ 0.951597] Key type fscrypt-provisioning registered436buildbot # [ 0.953295] ima: No TPM chip found, activating TPM-bypass!437buildbot # [ 0.959588] ima: Allocated hash algorithm: sha1438buildbot # [ 0.961063] ima: No architecture policies found439buildbot # [ 0.964773] PM: Magic number: 2:975:12440buildbot # [ 0.968989] RAS: Correctable Errors collector initialized.441buildbot # [ 0.980456] clk: Disabling unused clocks442buildbot # [ 0.983605] PM: genpd: Disabling unused power domains443buildbot # [ 1.103947] Freeing initrd memory: 27736K444buildbot # [ 1.107873] Freeing unused decrypted memory: 2028K445buildbot # [ 1.111256] Freeing unused kernel image (initmem) memory: 3636K446buildbot # [ 1.113212] Write protecting the kernel read-only data: 32768k447buildbot # [ 1.115966] Freeing unused kernel image (text/rodata gap) memory: 1272K448buildbot # [ 1.118417] Freeing unused kernel image (rodata/data gap) memory: 776K449buildbot # [ 1.171711] x86/mm: Checked W+X mappings: passed, no W+X pages found.450buildbot # [ 1.173702] Run /init as init process451buildbot # [ 1.185265] systemd[1]: Inserted module 'autofs4'452buildbot # [ 1.202211] fuse: init (API version 7.45)453buildbot # [ 1.209721] ACPI: \_SB_.LNKC: Enabled at IRQ 10454buildbot # [ 1.218582] ACPI: \_SB_.LNKA: Enabled at IRQ 10455buildbot # [ 1.223073] ACPI: \_SB_.LNKB: Enabled at IRQ 11456buildbot # [ 1.263539] systemd[1]: Successfully made /usr/ read-only.457buildbot # [ 1.604045] systemd[1]: systemd 260.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)458buildbot # [ 1.625838] systemd[1]: Detected virtualization kvm.459buildbot # [ 1.629820] systemd[1]: Detected architecture x86-64.460buildbot # [ 1.633817] systemd[1]: Running in initrd.461buildbot # [ 1.638166] systemd[1]: Initializing machine ID from random generator.462buildbot # [ 1.643511] systemd[1]: Hostname set to <buildbot>.463buildbot # [ 1.708117] systemd[1]: Queued start job for default target Initrd Default Target.464buildbot # [ 1.713171] systemd[1]: Created slice Slice /system/modprobe.465buildbot # [ 1.715243] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.466buildbot # [ 1.717763] systemd[1]: Expecting device /dev/disk/by-label/nixos...467buildbot # [ 1.719781] systemd[1]: Reached target Path Units.468buildbot # [ 1.721400] systemd[1]: Reached target Slice Units.469buildbot # [ 1.723047] systemd[1]: Reached target Swaps.470buildbot # [ 1.724534] systemd[1]: Reached target Timer Units.471buildbot # [ 1.726396] systemd[1]: Listening on D-Bus System Message Bus Socket.472buildbot # [ 1.728644] systemd[1]: Listening on Journal Socket (/dev/log).473buildbot # [ 1.730751] systemd[1]: Listening on Journal Sockets.474buildbot # [ 1.732598] systemd[1]: Listening on udev Control Socket.475buildbot # [ 1.734443] systemd[1]: Listening on udev Kernel Socket.476buildbot # [ 1.736225] systemd[1]: Reached target Socket Units.477buildbot # [ 1.738997] systemd[1]: Starting Create List of Static Device Nodes...478buildbot # [ 1.745110] systemd[1]: Starting Load Kernel Module 9pnet_virtio...479buildbot # [ 1.753790] systemd[1]: Starting Load Kernel Module configfs...480buildbot # [ 1.768768] systemd[1]: Starting Journal Service...481buildbot # [ 1.787672] systemd[1]: Starting Load Kernel Modules...482buildbot # [ 1.791118] netfs: FS-Cache loaded483buildbot # [ 1.794154] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki484buildbot # [ 1.813668] 9pnet: Installing 9P2000 support485buildbot # [ 1.831249] systemd[1]: Starting Coldplug All udev Devices...486buildbot # [ 1.840295] systemd-journald[67]: Collecting audit messages is disabled.487buildbot # [ 1.853106] systemd[1]: Finished Create List of Static Device Nodes.488buildbot # [ 1.863364] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.489buildbot # [ 1.875228] systemd[1]: Finished Load Kernel Module 9pnet_virtio.490buildbot # [ 1.879356] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.491buildbot # [ 1.886108] systemd[1]: modprobe@configfs.service: Deactivated successfully.492buildbot # [ 1.895347] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev493buildbot # [ 1.898120] systemd[1]: Finished Load Kernel Module configfs.494buildbot # [ 1.905337] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config495buildbot # [ 1.925813] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...496buildbot # [ 1.946185] systemd[1]: Finished Load Kernel Modules.497buildbot # [ 1.957948] systemd[1]: Starting Apply Kernel Variables...498buildbot # [ 1.978732] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.499buildbot # [ 1.994007] systemd[1]: Starting Create Static Device Nodes in /dev...500buildbot # [ 2.012732] systemd[1]: Finished Apply Kernel Variables.501buildbot # [ 2.032479] systemd[1]: Finished Create Static Device Nodes in /dev.502buildbot # [ 2.038960] systemd[1]: Reached target Preparation for Local File Systems.503buildbot # [ 2.046706] systemd[1]: Reached target Local File Systems.504buildbot # [ 2.054874] systemd[1]: Starting Rule-based Manager for Device Events and Files...505buildbot # [ 1.816186] systemd-modules-load[68]: Inserted module 'dm_mod'506buildbot # [ 1.818459] systemd-modules-load[68]: Inserted module 'virtio_balloon'507buildbot # [ 1.821444] systemd-modules-load[68]: Inserted module 'virtio_gpu'508buildbot # [ 2.074827] systemd[1]: Started Journal Service.509buildbot # [ 1.853917] systemd[1]: Starting Create System Files and Directories...510buildbot # [ 1.882565] systemd-udevd[74]: Using default interface naming scheme 'v260'.511buildbot # [ 1.890787] systemd[1]: Finished Create System Files and Directories.512buildbot # [ 1.927113] systemd[1]: Started Rule-based Manager for Device Events and Files.513buildbot # [ 2.013913] systemd[1]: Finished Coldplug All udev Devices.514buildbot # [ 2.017769] systemd[1]: Reached target System Initialization.515buildbot # [ 2.019383] systemd[1]: Reached target Basic System.516buildbot # [ 2.525619] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12517buildbot # [ 2.554069] serio: i8042 KBD port at 0x60,0x64 irq 1518buildbot # [ 2.559408] uhci_hcd 0000:00:01.2: UHCI Host Controller519buildbot # [ 2.575094] serio: i8042 AUX port at 0x60,0x64 irq 12520buildbot # [ 2.583432] virtio_blk virtio5: 1/0/0 default/read/poll queues521buildbot # [ 2.585498] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1522buildbot # [ 2.598037] SCSI subsystem initialized523buildbot # [ 2.599104] uhci_hcd 0000:00:01.2: detected 2 ports524buildbot # [ 2.609989] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100525buildbot # [ 2.618392] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)526buildbot # [ 2.628710] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18527buildbot # [ 2.630638] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1528buildbot # [ 2.661639] usb usb1: Product: UHCI Host Controller529buildbot # [ 2.667115] usb usb1: Manufacturer: Linux 6.18.38 uhci_hcd530buildbot # [ 2.680651] usb usb1: SerialNumber: 0000:00:01.2531buildbot # [ 2.692992] hub 1-0:1.0: USB hub found532buildbot # [ 2.703024] hub 1-0:1.0: 2 ports detected533buildbot # [ 2.470188] systemd[1]: Starting Virtual Console Setup...534buildbot # [ 2.479269] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.535buildbot # [ 2.483300] (udev-worker)[85]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.536buildbot # [ 2.490207] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line.537buildbot # [ 2.766913] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0538buildbot # [ 2.525113] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.539buildbot # [ 2.531306] systemd[1]: Finished Virtual Console Setup.540buildbot # [ 2.556943] systemd[1]: Found device /dev/disk/by-label/nixos.541buildbot # [ 2.558667] systemd[1]: Reached target Initrd Root Device.542buildbot # [ 2.562146] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...543buildbot # [ 2.820706] scsi host0: ata_piix544buildbot # [ 2.828164] scsi host1: ata_piix545buildbot # [ 2.835587] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0546buildbot # [ 2.841607] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0547buildbot # [ 2.601928] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks548buildbot # [ 2.611435] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.549buildbot # [ 2.616756] systemd[1]: Mounting /sysroot...550buildbot # [ 2.938605] usb 1-1: new full-speed USB device number 2 using uhci_hcd551buildbot # [ 3.003268] ata2: found unknown device (class 0)552buildbot # [ 3.009523] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100553buildbot # [ 3.018227] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5554buildbot # [ 3.087868] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray555buildbot # [ 3.106002] cdrom: Uniform CD-ROM driver Revision: 3.20556buildbot # [ 3.109577] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00557buildbot # [ 3.111911] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10558buildbot # [ 3.115580] usb 1-1: Product: QEMU USB Tablet559buildbot # [ 3.116883] usb 1-1: Manufacturer: QEMU560buildbot # [ 3.119584] usb 1-1: SerialNumber: 28754-0000:00:01.2-1561buildbot # [ 3.143063] EXT4-fs (vda): mounted filesystem b464bc12-d289-49aa-9e42-9f4c2cbed10d r/w with ordered data mode. Quota mode: none.562buildbot # [ 2.909184] systemd[1]: Mounted /sysroot.563buildbot # [ 2.911848] systemd[1]: Reached target Initrd Root File System.564buildbot # [ 2.919113] systemd[1]: Mounting /sysroot/nix/.ro-store...565buildbot # [ 2.924942] systemd[1]: Mounting /sysroot/nix/.rw-store...566buildbot # [ 2.934986] systemd[1]: Mounting /sysroot/run...567buildbot # [ 3.182194] hid: raw HID events driver (C) Jiri Kosina568buildbot # [ 2.944709] systemd[1]: Mounting /sysroot/tmp/shared...569buildbot # [ 3.202243] usbcore: registered new interface driver usbhid570buildbot # [ 2.960046] systemd[1]: Mounting /sysroot/tmp/xchg...571buildbot # [ 3.216656] usbhid: USB HID core driver572buildbot # [ 2.974112] systemd[1]: Starting Mountpoints Configured in the Real Root...573buildbot # [ 3.234644] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2574buildbot # [ 3.243433] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0575buildbot # [ 3.254329] 9p: Installing v9fs 9p2000 file system support576buildbot # [ 3.029162] systemd[1]: Mounted /sysroot/nix/.rw-store.577buildbot # [ 3.036944] systemd[1]: Mounted /sysroot/nix/.ro-store.578buildbot # [ 3.038468] systemd[1]: Mounted /sysroot/run.579buildbot # [ 3.040749] systemd-sysroot-fstab-check[129]: /sysroot should be mounted in the initrd, will request daemon-reload.580buildbot # [ 3.047620] systemd[1]: Mounted /sysroot/tmp/shared.581buildbot # [ 3.051360] systemd[1]: Mounted /sysroot/tmp/xchg.582buildbot # [ 3.073112] systemd[1]: Starting rw-sysroot-nix-store.service...583buildbot # [ 3.077098] systemd[1]: Reload requested from client PID 129 ('systemd-sysroot') (unit initrd-parse-etc.service)...584buildbot # [ 3.079839] systemd[1]: Reloading...585buildbot # [ 3.190551] systemd[1]: Reloading finished in 113 ms.586buildbot # [ 3.199098] systemd-sysroot-fstab-check[129]: Requesting initrd-fs.target/start/replace...587buildbot # [ 3.203457] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.588buildbot # [ 3.205508] systemd[1]: Finished rw-sysroot-nix-store.service.589buildbot # [ 3.210902] systemd-sysroot-fstab-check[129]: Requesting swap.target/start/replace...590buildbot # [ 3.215893] systemd[1]: initrd-parse-etc.service: Deactivated successfully.591buildbot # [ 3.217878] systemd[1]: Finished Mountpoints Configured in the Real Root.592buildbot # [ 3.219832] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.593buildbot # [ 3.224947] systemd[1]: Starting rw-sysroot-nix-store.service...594buildbot # [ 3.236979] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.595buildbot # [ 3.240120] systemd[1]: Finished rw-sysroot-nix-store.service.596buildbot # [ 3.604651] systemd[1]: Mounting /sysroot/nix/store...597buildbot # [ 3.655988] systemd[1]: Mounted /sysroot/nix/store.598buildbot # [ 3.658548] systemd[1]: Reached target Initrd File Systems.599buildbot # [ 3.661743] systemd[1]: Starting Find NixOS closure...600buildbot # [ 3.668338] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...601buildbot # [ 3.692428] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.602buildbot # [ 3.696698] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.603buildbot # [ 3.705122] systemd[1]: Finished Find NixOS closure.604buildbot # [ 3.708179] systemd[1]: Reached target Initrd Default Target.605buildbot # [ 3.710200] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...606buildbot # [ 3.729189] systemd[1]: initrd-cleanup.service: Deactivated successfully.607buildbot # [ 3.731730] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.608buildbot # [ 3.735224] systemd[1]: Stopped target Initrd Default Target.609buildbot # [ 3.737268] systemd[1]: Stopped target Basic System.610buildbot # [ 3.738711] systemd[1]: Stopped target Initrd Root Device.611buildbot # [ 3.741254] systemd[1]: Stopped target Path Units.612buildbot # [ 3.742665] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.613buildbot # [ 3.745265] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.614buildbot # [ 3.747417] systemd[1]: Stopped target Slice Units.615buildbot # [ 3.749253] systemd[1]: Stopped target Socket Units.616buildbot # [ 3.750901] systemd[1]: Stopped target System Initialization.617buildbot # [ 3.753195] systemd[1]: Stopped target Swaps.618buildbot # [ 3.754480] systemd[1]: Stopped target Timer Units.619buildbot # [ 3.756205] systemd[1]: dbus.socket: Deactivated successfully.620buildbot # [ 3.758223] systemd[1]: Closed D-Bus System Message Bus Socket.621buildbot # [ 3.759872] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.622buildbot # [ 3.762166] systemd[1]: Stopped Find NixOS closure.623buildbot # [ 3.764207] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio624buildbot # [ 3.767054] systemd[1]: Starting rw-sysroot-nix-store.service...625buildbot # [ 3.768687] systemd[1]: systemd-sysctl.service: Deactivated successfully.626buildbot # [ 3.772596] systemd[1]: Stopped Apply Kernel Variables.627buildbot # [ 3.774082] systemd[1]: systemd-modules-load.service: Deactivated successfully.628buildbot # [ 3.775948] systemd[1]: Stopped Load Kernel Modules.629buildbot # [ 3.779413] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.630buildbot # [ 3.781466] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.631buildbot # [ 3.783468] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.632buildbot # [ 3.786484] systemd[1]: Stopped Create System Files and Directories.633buildbot # [ 3.788978] systemd[1]: Stopped target Local File Systems.634buildbot # [ 3.790578] systemd[1]: Stopped target Preparation for Local File Systems.635buildbot # [ 3.793446] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.636buildbot # [ 3.796105] systemd[1]: Stopped Coldplug All udev Devices.637buildbot # [ 3.798286] systemd[1]: Stopping Rule-based Manager for Device Events and Files...638buildbot # [ 3.801931] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.639buildbot # [ 3.805123] systemd[1]: Stopped Virtual Console Setup.640buildbot # [ 3.809313] systemd[1]: systemd-udevd.service: Deactivated successfully.641buildbot # [ 3.813100] systemd[1]: Stopped Rule-based Manager for Device Events and Files.642buildbot # [ 3.815137] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.643buildbot # [ 3.817254] systemd[1]: Closed udev Control Socket.644buildbot # [ 3.819669] systemd[1]: Starting Cleanup udev Database...645buildbot # [ 3.822269] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.646buildbot # [ 3.824330] systemd[1]: Stopped Create Static Device Nodes in /dev.647buildbot # [ 3.826056] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.648buildbot # [ 3.828148] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.649buildbot # [ 3.831213] systemd[1]: kmod-static-nodes.service: Deactivated successfully.650buildbot # [ 3.833106] systemd[1]: Stopped Create List of Static Device Nodes.651buildbot # [ 3.834790] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.652buildbot # [ 3.838714] systemd[1]: Finished rw-sysroot-nix-store.service.653buildbot # [ 3.855853] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.654buildbot # [ 3.859156] systemd[1]: Finished Cleanup udev Database.655buildbot # [ 3.860847] systemd[1]: Reached target Switch Root.656buildbot # [ 3.863333] systemd[1]: Starting NixOS Activation...657buildbot # [ 4.046786] initrd-nixos-activation-start[178]: booting system configuration /nix/store/q796flybh6bhmq49nh0wb5pmzjpl5pfb-nixos-system-buildbot-test658buildbot # [ 4.120991] initrd-nixos-activation-start[178]: running activation script...659buildbot # [ 4.592606] initrd-nixos-activation-start[201]: setting up /etc...660buildbot # [ 4.901668] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.661buildbot # [ 4.904167] systemd[1]: Finished NixOS Activation.662buildbot # [ 4.906954] systemd[1]: Starting Switch Root...663buildbot # [ 4.921559] systemd[1]: Switching root.664buildbot # [ 5.182100] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).665buildbot # [ 5.343322] NET: Registered PF_VSOCK protocol family666buildbot # [ 5.735359] systemd[1]: systemd 260.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)667buildbot # [ 5.751820] systemd[1]: Detected virtualization kvm.668buildbot # [ 5.755168] systemd[1]: Detected architecture x86-64.669buildbot # [ 5.758477] systemd[1]: Detected first boot.670buildbot # [ 5.767095] systemd[1]: Initializing machine ID from random generator.671buildbot # [ 6.020254] systemd[1]: bpf-restrict-fs: LSM BPF program attached672buildbot # [ 6.152316] systemd[1]: Applying preset policy.673buildbot # [ 6.748838] systemd[1]: Populated /etc with preset unit settings.674buildbot # [ 7.366734] systemd[1]: initrd-switch-root.service: Deactivated successfully.675buildbot # [ 7.369145] systemd[1]: Stopped initrd-switch-root.service.676buildbot # [ 7.373354] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.677buildbot # [ 7.376684] systemd[1]: Created slice Slice /system/getty.678buildbot # [ 7.378776] systemd[1]: Created slice User and Session Slice.679buildbot # [ 7.380384] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.680buildbot # [ 7.382508] systemd[1]: Started Forward Password Requests to Wall Directory Watch.681buildbot # [ 7.384431] systemd[1]: Expecting device /dev/hvc0...682buildbot # [ 7.385754] systemd[1]: Expecting device /dev/ttyS0...683buildbot # [ 7.387122] systemd[1]: Reached target Local Encrypted Volumes.684buildbot # [ 7.388615] systemd[1]: Stopped target initrd-fs.target.685buildbot # [ 7.389992] systemd[1]: Stopped target initrd-root-fs.target.686buildbot # [ 7.391431] systemd[1]: Stopped target initrd-switch-root.target.687buildbot # [ 7.393009] systemd[1]: Reached target Virtual Machines and Containers.688buildbot # [ 7.394653] systemd[1]: Reached target Path Units.689buildbot # [ 7.395942] systemd[1]: Reached target Remote File Systems.690buildbot # [ 7.397398] systemd[1]: Reached target Slice Units.691buildbot # [ 7.398737] systemd[1]: Reached target Swaps.692buildbot # [ 7.402824] systemd[1]: Listening on Query the User Interactively for a Password.693buildbot # [ 7.408349] systemd[1]: Listening on Process Core Dump Socket.694buildbot # [ 7.412620] systemd[1]: Listening on Credential Encryption/Decryption.695buildbot # [ 7.416760] systemd[1]: Listening on Factory Reset Management.696buildbot # [ 7.422023] systemd[1]: Starting Journal Log Access Socket...697buildbot # [ 7.424395] systemd[1]: Listening on Journal Audit Socket.698buildbot # [ 7.429015] systemd[1]: Listening on Console Output Muting Service Socket.699buildbot # [ 7.430957] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.700buildbot # [ 7.432877] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki701buildbot # [ 7.435624] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki702buildbot # [ 7.449470] systemd[1]: Listening on Disk Repartitioning Service Socket.703buildbot # [ 7.451318] systemd[1]: Listening on udev Control Socket.704buildbot # [ 7.452908] systemd[1]: Listening on udev Varlink Socket.705buildbot # [ 7.457934] systemd[1]: Mounting Huge Pages File System...706buildbot # [ 7.462494] systemd[1]: Mounting POSIX Message Queue File System...707buildbot # [ 7.467932] systemd[1]: Mounting Kernel Debug File System...708buildbot # [ 7.477686] systemd[1]: Mounting Kernel Trace File System...709buildbot # [ 7.487239] systemd[1]: Starting Create List of Static Device Nodes...710buildbot # [ 7.490709] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio711buildbot # [ 7.506305] systemd[1]: Starting Load Kernel Module configfs...712buildbot # [ 7.508359] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm713buildbot # [ 7.510980] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore714buildbot # [ 7.513910] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse715buildbot # [ 7.545074] systemd[1]: Mounting FUSE Control File System...716buildbot # [ 7.547182] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67717buildbot # [ 7.573084] systemd[1]: Starting Journal Service...718buildbot # [ 7.578708] systemd[1]: Starting Load Kernel Modules...719buildbot # [ 7.594930] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...720buildbot # [ 7.605348] systemd[1]: Starting Remount Root and Kernel File Systems...721buildbot # [ 7.611678] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki722buildbot # [ 7.634428] systemd[1]: Starting Coldplug All udev Devices...723buildbot # [ 7.660736] systemd-journald[270]: Collecting audit messages is enabled.724buildbot # [ 7.664260] systemd[1]: Listening on Journal Log Access Socket.725buildbot # [ 7.672788] loop: module loaded726buildbot # [ 7.677303] systemd[1]: Mounted Huge Pages File System.727buildbot # [ 7.682139] systemd[1]: Mounted POSIX Message Queue File System.728buildbot # [ 7.692934] systemd[1]: Mounted Kernel Debug File System.729buildbot # [ 7.701717] EXT4-fs (vda): re-mounted b464bc12-d289-49aa-9e42-9f4c2cbed10d.730buildbot # [ 7.703781] systemd[1]: Mounted Kernel Trace File System.731buildbot # [ 7.718682] systemd[1]: Finished Create List of Static Device Nodes.732buildbot # [ 7.725809] systemd[1]: Started Journal Service.733buildbot # [ 7.483277] systemd[1]: Queued start job for default target Multi-User System.734buildbot # [ 7.485865] systemd[1]: systemd-journald.service: Deactivated successfully.735buildbot # [ 7.491380] systemd-modules-load[271]: Inserted module 'loop'736buildbot # [ 7.494679] systemd-modules-load[271]: Inserted module 'tls'737buildbot # [ 7.499895] systemd[1]: modprobe@configfs.service: Deactivated successfully.738buildbot # [ 7.502581] systemd[1]: Finished Load Kernel Module configfs.739buildbot # [ 7.508183] systemd[1]: Mounted FUSE Control File System.740buildbot # [ 7.511814] systemd[1]: Finished Load Kernel Modules.741buildbot # [ 7.516394] systemd[1]: Finished Remount Root and Kernel File Systems.742buildbot # [ 7.534076] systemd[1]: Listening on Disk Image Download Service Socket.743buildbot # [ 7.544750] systemd-oomd[272]: No swap; memory pressure usage will be degraded744buildbot # [ 7.547316] systemd[1]: Mounting Kernel Configuration File System...745buildbot # [ 7.554230] systemd[1]: Starting Flush Journal to Persistent Storage...746buildbot # [ 7.556074] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore747buildbot # [ 7.568107] systemd[1]: Starting Load/Save OS Random Seed...748buildbot # [ 7.579617] systemd[1]: Starting Apply Kernel Variables...749buildbot # [ 7.591790] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...750buildbot # [ 7.594901] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki751buildbot # [ 7.597547] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.752buildbot # [ 7.883272] systemd-journald[270]: Received client request to flush runtime journal.753buildbot # [ 7.815711] systemd[1]: Mounted Kernel Configuration File System.754buildbot # [ 7.821221] systemd[1]: Finished Load/Save OS Random Seed.755buildbot # [ 7.823873] systemd[1]: Reached target First Boot Complete.756buildbot # [ 7.827612] systemd[1]: Finished Apply Kernel Variables.757buildbot # [ 7.831347] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.758buildbot # [ 7.834809] systemd[1]: Starting Create Static Device Nodes in /dev...759buildbot # [ 7.836605] systemd[1]: Finished Flush Journal to Persistent Storage.760buildbot # [ 7.895129] systemd[1]: Finished Create Static Device Nodes in /dev.761buildbot # [ 7.899099] systemd[1]: Reached target Preparation for Local File Systems.762buildbot # [ 7.905113] systemd[1]: Starting Rule-based Manager for Device Events and Files...763buildbot # [ 7.960324] systemd[1]: Finished Coldplug All udev Devices.764buildbot # [ 8.004460] systemd-udevd[302]: Using default interface naming scheme 'v260'.765buildbot # [ 8.125806] systemd[1]: Mounting /run/wrappers...766buildbot # [ 8.131199] systemd[1]: Started Rule-based Manager for Device Events and Files.767buildbot # [ 8.169117] systemd[1]: Mounted /run/wrappers.768buildbot # [ 8.170813] systemd[1]: Reached target Local File Systems.769buildbot # [ 8.176921] systemd[1]: Listening on Boot Loader Control Service Socket.770buildbot # [ 8.181643] systemd[1]: Starting register-nix-paths.service...771buildbot # [ 8.189107] systemd[1]: Starting Create SUID/SGID Wrappers...772buildbot # [ 8.190678] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.773buildbot # [ 8.200108] systemd[1]: Starting Save Transient machine-id to Disk...774buildbot # [ 8.212401] systemd[1]: Starting Create System Files and Directories...775buildbot # [ 8.290267] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.776buildbot # [ 8.300081] systemd[1]: Finished Save Transient machine-id to Disk.777buildbot # [ 8.379407] systemd[1]: Finished Create System Files and Directories.778buildbot # [ 8.390379] systemd[1]: Starting Rebuild Journal Catalog...779buildbot # [ 8.396558] systemd[1]: Starting Record System Boot/Shutdown in UTMP...780buildbot # [ 8.494604] systemd[1]: Finished Record System Boot/Shutdown in UTMP.781buildbot # [ 8.545661] systemd[1]: Finished Rebuild Journal Catalog.782buildbot # [ 8.554849] systemd[1]: Starting Update is Completed...783buildbot # [ 8.562604] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse784buildbot # [ 8.618406] systemd[1]: Finished Update is Completed.785buildbot # [ 8.745210] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.786buildbot # [ 8.797613] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.787buildbot # [ 8.850989] (udev-worker)[323]: Network interface NamePolicy= disabled on kernel command line.788buildbot # [ 8.855597] (udev-worker)[325]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.789buildbot # [ 8.859847] (udev-worker)[325]: Network interface NamePolicy= disabled on kernel command line.790buildbot # [ 8.892746] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.791buildbot # [ 8.895885] systemd[1]: Finished Create SUID/SGID Wrappers.792buildbot # [ 9.026562] systemd[1]: Condition check resulted in Virtio network device being skipped.793buildbot # [ 9.030289] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore794buildbot # [ 9.038347] systemd[1]: Starting Create SUID/SGID Wrappers...795buildbot # [ 9.039986] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.796buildbot # [ 9.044346] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67797buildbot # [ 9.048291] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore798buildbot # [ 9.051182] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki799buildbot # [ 9.055430] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki800buildbot # [ 9.227736] systemd[1]: Finished register-nix-paths.service.801buildbot # [ 9.537225] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3802buildbot # [ 9.547412] mousedev: PS/2 mouse device common for all mice803buildbot # [ 9.567576] ACPI: button: Power Button [PWRF]804buildbot # [ 9.581081] rtc_cmos 00:05: RTC can wake from S4805buildbot # [ 9.610692] parport_pc 00:03: reported by Plug and Play ACPI806buildbot # [ 9.613398] rtc_cmos 00:05: registered as rtc0807buildbot # [ 9.614778] Floppy drive(s): fd0 is 2.88M AMI BIOS808buildbot # [ 9.636474] rtc_cmos 00:05: setting system clock to 2026-07-05T06:00:18 UTC (1783231218)809buildbot # [ 9.639012] systemd-journald[270]: Time jumped backwards, rotating.810buildbot # [ 9.642127] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]811buildbot # [ 9.662856] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs812buildbot # [ 9.726421] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0813buildbot # [ 9.737373] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console814buildbot # [ 9.751090] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD815buildbot # [ 9.755249] FDC 0 is a S82078B816buildbot # [ 9.757885] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4817buildbot # [ 9.813607] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6818buildbot # [ 9.813978] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5819buildbot # [ 9.868784] Console: switching to colour dummy device 80x25820buildbot # [ 9.924474] [drm] Found bochs VGA, ID 0xb0c5.821buildbot # [ 9.924476] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.822buildbot # [ 9.931265] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic823buildbot # [ 9.706392] systemd[1]: Starting Virtual Console Setup...824buildbot # [ 9.958645] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0825buildbot # [ 9.737159] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.826buildbot # [ 9.740715] systemd[1]: Finished Create SUID/SGID Wrappers.827buildbot # [ 10.039729] fbcon: bochs-drmdrmfb (fb0) is primary device828buildbot # [ 10.071796] ppdev: user-space parallel port driver829buildbot # [ 10.091857] Console: switching to colour frame buffer device 160x50830buildbot # [ 10.143322] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device831buildbot # [ 9.903551] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.832buildbot # [ 9.907254] systemd[1]: Stopped Virtual Console Setup.833buildbot # [ 9.916110] systemd[1]: Starting Virtual Console Setup...834buildbot # [ 9.940153] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.835buildbot # [ 9.943817] systemd[1]: Stopped Virtual Console Setup.836buildbot # [ 9.950186] systemd[1]: Starting Virtual Console Setup...837buildbot # [ 10.268986] kvm_amd: TSC scaling supported838buildbot # [ 10.270057] kvm_amd: Nested Virtualization enabled839buildbot # [ 10.271334] kvm_amd: Nested Paging enabled840buildbot # [ 10.272644] kvm_amd: LBR virtualization supported841buildbot # [ 10.276022] kvm_amd: Virtual VMLOAD VMSAVE supported842buildbot # [ 10.277225] kvm_amd: Virtual GIF supported843buildbot # [ 10.278416] kvm_amd: Virtual NMI enabled844buildbot # [ 10.327332] EDAC MC: Ver: 3.0.0845buildbot # [ 10.583472] systemd-vconsole-setup[465]: Configuration of first virtual console was skipped, ignoring remaining ones.846buildbot # [ 10.589127] systemd[1]: Finished Virtual Console Setup.847buildbot # [ 10.592101] systemd[1]: Reached target System Initialization.848buildbot # [ 10.594915] systemd[1]: Started Discard unused filesystem blocks once a week.849buildbot # [ 10.596854] systemd[1]: Started Daily Cleanup of Temporary Directories.850buildbot # [ 10.599365] systemd[1]: Reached target Timer Units.851buildbot # [ 10.600987] systemd[1]: Listening on D-Bus System Message Bus Socket.852buildbot # [ 10.603272] systemd[1]: Listening on Nix Daemon Socket.853buildbot # [ 10.605445] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).854buildbot # [ 10.608240] systemd[1]: Listening on Hostname Service Socket.855buildbot # [ 10.610097] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.856buildbot # [ 10.613269] systemd[1]: Reached target Socket Units.857buildbot # [ 10.614748] systemd[1]: Reached target Basic System.858buildbot # [ 10.619149] systemd[1]: Started backdoor.service.859buildbot # [ 10.622583] systemd[1]: Starting Import lastlog data into lastlog2 database...860buildbot # [ 10.627729] systemd[1]: Starting Name Service Cache Daemon (nsncd)...861buildbot # [ 10.633398] systemd[1]: Starting Post-Boot Actions...862buildbot # [ 10.643102] systemd[1]: Started Reset console on configuration changes.863buildbot # [ 10.653873] systemd[1]: Starting resolvconf update...864buildbot # [ 10.656346] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.865buildbot # [ 10.694389] systemd[1]: Starting D-Bus System Message Bus...866buildbot # connecting to host...867buildbot # [ 10.743632] nsncd[471]: Jul 05 06:00:19.845 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"868buildbot # [ 10.748571] systemd[1]: Finished Post-Boot Actions.869buildbot # [ 10.752273] systemd[1]: Started Name Service Cache Daemon (nsncd).870buildbot # [ 10.763863] systemd[1]: Reached target Host and Network Name Lookups.871buildbot # [ 10.765622] systemd[1]: Reached target User and Group Name Lookups.872buildbot # [ 10.782202] systemd[1]: Starting User Login Management...873buildbot: Guest shell says: b'Spawning backdoor root shell...\n'874buildbot: connected to guest root shell875buildbot: (connecting took 11.72 seconds)876buildbot: (finished: waiting for the VM to finish booting, in 11.90 seconds)877buildbot # [ 10.799273] systemd[1]: Finished Import lastlog data into lastlog2 database.878buildbot # [ 10.891360] dbus-broker-launch[476]: Looking up NSS user entry for 'systemd-timesync'...879buildbot # [ 10.911992] systemd-logind[496]: New seat seat0.880buildbot # [ 10.918261] systemd-logind[496]: Watching system buttons on /dev/input/event2 (Power Button)881buildbot # [ 10.921353] systemd-logind[496]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)882buildbot # [ 10.925135] systemd-logind[496]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)883buildbot # [ 10.931914] systemd[1]: Started User Login Management.884buildbot # [ 10.944440] systemd[1]: Stopped target Host and Network Name Lookups.885buildbot # [ 10.946231] systemd[1]: Stopping Host and Network Name Lookups...886buildbot # [ 10.948640] systemd[1]: Stopped target User and Group Name Lookups.887buildbot # [ 10.952235] systemd[1]: Stopping User and Group Name Lookups...888buildbot # [ 10.955351] dbus-broker-launch[476]: NSS returned no entry for 'systemd-timesync'889buildbot # [ 10.958154] dbus-broker-launch[476]: Invalid user-name in /nix/store/jmq6axll73jikglkq8lfn7px2nmx9y54-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"890buildbot # [ 10.965777] systemd[1]: Starting linger-users.service...891buildbot # [ 10.967538] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...892buildbot # [ 10.979109] systemd[1]: nscd.service: Deactivated successfully.893buildbot # [ 10.987395] systemd[1]: Stopped Name Service Cache Daemon (nsncd).894buildbot # [ 11.011227] systemd[1]: Started D-Bus System Message Bus.895buildbot # [ 11.046840] dbus-broker-launch[476]: Ready896buildbot # [ 11.050709] systemd[1]: linger-users.service: Deactivated successfully.897buildbot # [ 11.053760] systemd[1]: Finished linger-users.service.898buildbot # [ 11.072273] systemd[1]: Starting Name Service Cache Daemon (nsncd)...899buildbot # [ 11.087519] systemd[1]: Finished resolvconf update.900buildbot # [ 11.091137] systemd[1]: Reached target Preparation for Network.901buildbot # [ 11.097262] systemd[1]: Starting DHCP Client...902buildbot # [ 11.103274] systemd[1]: Starting Address configuration of eth1...903buildbot # [ 11.107315] systemd[1]: Starting Extra networking commands....904buildbot # [ 11.139824] systemd[1]: Started Name Service Cache Daemon (nsncd).905buildbot # [ 11.142552] nsncd[560]: Jul 05 06:00:20.247 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"906buildbot # [ 11.149295] systemd[1]: Reached target Host and Network Name Lookups.907buildbot # [ 11.152699] systemd[1]: Reached target User and Group Name Lookups.908buildbot # [ 11.245263] network-addresses-eth1-start[565]: adding address 192.168.1.1/24... done909buildbot # [ 11.264765] network-addresses-eth1-start[565]: adding address 2001:db8:1::1/64... done910buildbot # [ 11.292342] systemd[1]: Finished Address configuration of eth1.911buildbot # [ 11.308085] dhcpcd[582]: dhcpcd-10.3.2 starting912buildbot # [ 11.319981] dhcpcd[628]: dev: loaded udev913buildbot # [ 11.337990] systemd[1]: Finished Extra networking commands..914buildbot # [ 11.340912] systemd[1]: Reached target Network.915buildbot # [ 11.347199] systemd[1]: Starting Nginx Web Server...916buildbot # [ 11.598911] 8021q: 802.1Q VLAN Support v1.8917buildbot # [ 11.356229] systemd[1]: Starting PostgreSQL Server...918buildbot # [ 11.602747] 8021q: adding VLAN 0 to HW filter on device eth1919buildbot # [ 11.361550] systemd[1]: Starting SSH Daemon...920buildbot # [ 11.368847] systemd[1]: Starting Permit User Sessions...921buildbot # [ 11.485521] systemd[1]: Finished Permit User Sessions.922buildbot # [ 11.503100] systemd[1]: Started Getty on tty1.923buildbot # [ 11.504457] systemd[1]: Reached target Login Prompts.924buildbot # [ 11.527102] sshd[637]: Server listening on 0.0.0.0 port 22.925buildbot # [ 11.528707] sshd[637]: Server listening on :: port 22.926buildbot # [ 11.531851] systemd[1]: Started SSH Daemon.927buildbot # [ 11.546950] systemd[1]: Starting Setup git test repository...928buildbot # [ 11.856322] cfg80211: Loading compiled-in X.509 certificates for regulatory database929buildbot # [ 11.616429] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.930buildbot # [ 11.902979] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'931buildbot # [ 11.905333] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'932buildbot # [ 11.909663] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2933buildbot # [ 11.911333] cfg80211: failed to load regulatory.db934buildbot # [ 11.725051] setup-git-repo-start[660]: hint: Using 'master' as the name for the initial branch. This default branch name935buildbot # [ 11.728205] setup-git-repo-start[660]: hint: will change to "main" in Git 3.0. To configure the initial branch name936buildbot # [ 11.732222] setup-git-repo-start[660]: hint: to use in all of your new repositories, which will suppress this warning,937buildbot # [ 11.736149] setup-git-repo-start[660]: hint: call:938buildbot # [ 11.737439] setup-git-repo-start[660]: hint:939buildbot # [ 11.738668] setup-git-repo-start[660]: hint: git config --global init.defaultBranch <name>940buildbot # [ 11.741871] setup-git-repo-start[660]: hint:941buildbot # [ 11.744657] setup-git-repo-start[660]: hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and942buildbot # [ 11.748922] setup-git-repo-start[660]: hint: 'development'. The just-created branch can be renamed via this command:943buildbot # [ 11.751638] setup-git-repo-start[660]: hint:944buildbot # [ 11.752864] setup-git-repo-start[660]: hint: git branch -m <name>945buildbot # [ 11.755714] setup-git-repo-start[660]: hint:946buildbot # [ 11.759138] setup-git-repo-start[660]: hint: Disable this message with "git config set advice.defaultBranchName false"947buildbot # [ 11.761753] setup-git-repo-start[660]: Initialized empty Git repository in /srv/repos/test-flake.git/948buildbot # [ 12.032227] 8021q: adding VLAN 0 to HW filter on device eth0949buildbot # [ 11.789699] dhcpcd[628]: eth0: waiting for carrier950buildbot # [ 11.792994] dhcpcd[628]: eth0: carrier acquired951buildbot # [ 11.805833] postgresql-pre-start[656]: The files belonging to this database system will be owned by user "postgres".952buildbot # [ 11.809720] postgresql-pre-start[656]: This user must also own the server process.953buildbot # [ 11.812470] dhcpcd[628]: DUID 00:01:00:01:31:dc:af:74:52:54:00:12:34:56954buildbot # [ 11.815543] dhcpcd[628]: eth0: IAID 00:12:34:56955buildbot # [ 11.817852] dhcpcd[628]: eth0: adding address fe80::5054:ff:fe12:3456956buildbot # [ 11.821336] nginx-pre-start[649]: nginx: the configuration file /nix/store/3jp2m847192yznwd5l2wayzjycrnfp22-nginx.conf syntax is ok957buildbot # [ 11.825868] nginx-pre-start[649]: nginx: configuration file /nix/store/3jp2m847192yznwd5l2wayzjycrnfp22-nginx.conf test is successful958buildbot # [ 11.829196] setup-git-repo-start[672]: hint: Using 'master' as the name for the initial branch. This default branch name959buildbot # [ 11.832184] setup-git-repo-start[672]: hint: will change to "main" in Git 3.0. To configure the initial branch name960buildbot # [ 11.834836] setup-git-repo-start[672]: hint: to use in all of your new repositories, which will suppress this warning,961buildbot # [ 11.837361] setup-git-repo-start[672]: hint: call:962buildbot # [ 11.840141] setup-git-repo-start[672]: hint:963buildbot # [ 11.842307] setup-git-repo-start[672]: hint: git config --global init.defaultBranch <name>964buildbot # [ 11.845222] setup-git-repo-start[672]: hint:965buildbot # [ 11.847636] setup-git-repo-start[672]: hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and966buildbot # [ 11.850183] setup-git-repo-start[672]: hint: 'development'. The just-created branch can be renamed via this command:967buildbot # [ 11.853249] setup-git-repo-start[672]: hint:968buildbot # [ 11.854757] setup-git-repo-start[672]: hint: git branch -m <name>969buildbot # [ 11.857411] setup-git-repo-start[672]: hint:970buildbot # [ 11.858970] setup-git-repo-start[672]: hint: Disable this message with "git config set advice.defaultBranchName false"971buildbot # [ 11.862371] setup-git-repo-start[672]: Initialized empty Git repository in /tmp/test-flake/.git/972buildbot # [ 11.865420] postgresql-pre-start[656]: The database cluster will be initialized with locale "en_US.UTF-8".973buildbot # [ 11.868886] postgresql-pre-start[656]: The default database encoding has accordingly been set to "UTF8".974buildbot # [ 11.872693] postgresql-pre-start[656]: The default text search configuration will be set to "english".975buildbot # [ 11.875353] postgresql-pre-start[656]: Data page checksums are disabled.976buildbot # [ 11.877974] postgresql-pre-start[656]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok977buildbot # [ 11.880482] postgresql-pre-start[656]: creating subdirectories ... ok978buildbot # [ 11.882556] postgresql-pre-start[656]: selecting dynamic shared memory implementation ... posix979buildbot # [ 11.886088] systemd[1]: Started Nginx Web Server.980buildbot # [ 12.006695] postgresql-pre-start[656]: selecting default "max_connections" ... 100981buildbot # [ 12.079911] postgresql-pre-start[656]: selecting default "shared_buffers" ... 128MB982buildbot # [ 12.112663] setup-git-repo-start[691]: [master (root-commit) 6941a02] Initial commit983buildbot # [ 12.114525] setup-git-repo-start[691]: 1 file changed, 39 insertions(+)984buildbot # [ 12.116216] setup-git-repo-start[691]: create mode 100644 flake.nix985buildbot: (finished: waiting for unit sshd.service, in 13.23 seconds)986buildbot: waiting for unit setup-git-repo.service987buildbot # [ 12.216246] setup-git-repo-start[701]: To /srv/repos/test-flake.git988buildbot # [ 12.218777] setup-git-repo-start[701]: * [new branch] master -> master989buildbot # [ 12.224070] setup-git-repo-start[701]: branch 'master' set up to track 'origin/master'.990buildbot # [ 12.420435] dhcpcd[628]: eth0: soliciting a DHCP lease991buildbot # [ 12.691928] NET: Registered PF_PACKET protocol family992buildbot # [ 12.452723] dhcpcd[628]: eth0: offered 10.0.2.15 from 10.0.2.2993buildbot # [ 12.456381] dhcpcd[628]: eth0: probing address 10.0.2.15/24994buildbot # [ 12.481260] setup-git-repo-start[714]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org; retrying in 328 ms (attempt 1/5)995buildbot # [ 12.808744] setup-git-repo-start[714]: 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 615 ms (attempt 2/5)996buildbot # [ 13.423931] setup-git-repo-start[714]: 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 1204 ms (attempt 3/5)997buildbot # [ 14.155666] dhcpcd[628]: eth0: soliciting an IPv6 router998buildbot # [ 14.158146] dhcpcd[628]: eth0: Router Advertisement from fe80::2999buildbot # [ 14.159883] dhcpcd[628]: eth0: adding address fec0::5054:ff:fe12:3456/641000buildbot # [ 14.161762] dhcpcd[628]: eth0: adding route to fec0::/641001buildbot # [ 14.163357] dhcpcd[628]: eth0: adding default route via fe80::21002buildbot # [ 14.628951] setup-git-repo-start[714]: 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 2185 ms (attempt 4/5)1003buildbot # [ 15.153916] postgresql-pre-start[656]: selecting default time zone ... UTC1004buildbot # [ 15.159391] postgresql-pre-start[656]: creating configuration files ... ok1005buildbot # [ 15.454197] postgresql-pre-start[656]: running bootstrap script ... ok1006buildbot # [ 16.077145] postgresql-pre-start[656]: performing post-bootstrap initialization ... ok1007buildbot # [ 16.275227] postgresql-pre-start[656]: syncing data to disk ... ok1008buildbot # [ 16.277708] postgresql-pre-start[656]: initdb: warning: enabling "trust" authentication for local connections1009buildbot # [ 16.280108] postgresql-pre-start[656]: 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.1010buildbot # [ 16.283706] postgresql-pre-start[656]: Success. You can now start the database server using:1011buildbot # [ 16.285666] postgresql-pre-start[656]: pg_ctl -D /var/lib/postgresql/17 -l logfile start1012buildbot # [ 16.430654] postgres[746]: [746] LOG: starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit1013buildbot # [ 16.443230] postgres[746]: [746] LOG: listening on IPv6 address "::1", port 54321014buildbot # [ 16.445349] postgres[746]: [746] LOG: listening on IPv4 address "127.0.0.1", port 54321015buildbot # [ 16.449193] postgres[746]: [746] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1016buildbot # [ 16.465871] postgres[756]: [756] LOG: database system was shut down at 2026-07-05 06:00:25 GMT1017buildbot # [ 16.477405] postgres[746]: [746] LOG: database system is ready to accept connections1018buildbot # [ 16.486210] systemd[1]: Started PostgreSQL Server.1019buildbot # [ 16.490806] systemd[1]: Starting PostgreSQL Setup Scripts...1020buildbot # [ 16.709441] postgresql-setup-start[767]: CREATE DATABASE1021buildbot # [ 16.768506] postgresql-setup-start[772]: CREATE ROLE1022buildbot # [ 16.798630] postgresql-setup-start[774]: ALTER DATABASE1023buildbot # [ 16.806542] systemd[1]: Finished PostgreSQL Setup Scripts.1024buildbot # [ 16.809202] systemd[1]: Reached target PostgreSQL.1025buildbot # [ 16.814517] systemd[1]: Starting Buildbot Continuous Integration Server....1026buildbot # [ 16.820108] setup-git-repo-start[714]: warning: unable to download 'https://cache.nixos.org/nix-cache-info': Could not resolve hostname (6) Could not resolve host: cache.nixos.org1027buildbot # [ 16.823897] setup-git-repo-start[714]: this derivation will be built:1028buildbot # [ 16.826140] setup-git-repo-start[714]: /nix/store/jzrxnmmw1fx84z726lfx655zbscifwpf-skippable-test.drv1029buildbot # [ 16.887908] buildbot-master-pre-start[780]: mkdir: created directory '/var/lib/buildbot/master'1030buildbot # [ 16.976163] setup-git-repo-start[714]: building '/nix/store/jzrxnmmw1fx84z726lfx655zbscifwpf-skippable-test.drv'...1031buildbot # [ 17.009277] systemd[1]: Finished Setup git test repository.1032buildbot # [ 17.207906] dhcpcd[628]: eth0: leased 10.0.2.15 for 86400 seconds1033buildbot # [ 17.209563] dhcpcd[628]: eth0: adding route to 10.0.2.0/241034buildbot # [ 17.211350] dhcpcd[628]: eth0: adding default route via 10.0.2.21035buildbot # [ 17.330849] systemd[1]: Started DHCP Client.1036buildbot: (finished: waiting for unit setup-git-repo.service, in 5.40 seconds)1037buildbot: waiting for unit multi-user.target1038buildbot # [ 24.593317] buildbot-master-pre-start[782]: updating existing installation1039buildbot # [ 24.595194] buildbot-master-pre-start[782]: not touching existing buildbot.tac1040buildbot # [ 24.597090] buildbot-master-pre-start[782]: creating buildbot.tac.new instead1041buildbot # [ 24.600127] buildbot-master-pre-start[782]: creating /var/lib/buildbot/master/master.cfg.sample1042buildbot # [ 24.602212] buildbot-master-pre-start[782]: creating database (postgresql://@/buildbot)1043buildbot # [ 24.604204] buildbot-master-pre-start[782]: buildmaster configured in /var/lib/buildbot/master1044buildbot # [ 24.778558] systemd[1]: Started Buildbot Continuous Integration Server..1045buildbot # [ 24.784962] systemd[1]: Started Buildbot Worker..1046buildbot # [ 24.788265] systemd[1]: Reached target Multi-User System.1047buildbot # [ 24.790296] systemd[1]: Startup finished in 933ms (kernel) + 4.103s (initrd) + 19.752s (userspace) = 24.789s.1048buildbot: (finished: waiting for unit multi-user.target, in 7.87 seconds)1049subtest: Master and worker services start1050buildbot: waiting for unit buildbot-master.service1051buildbot: (finished: waiting for unit buildbot-master.service, in 0.09 seconds)1052buildbot: waiting for unit buildbot-worker.service1053buildbot: (finished: waiting for unit buildbot-worker.service, in 0.10 seconds)1054buildbot: waiting for TCP port 8010 on localhost1055buildbot # [ 26.454234] twistd[913]: Starting worker local-worker-0001056buildbot # [ 26.455878] twistd[913]: 2026-07-05T06:00:34+0000 [-] Loading /nix/store/zxbh9svi0g0i80pg7z3gd6hmk17ck3yf-buildbot_nix/buildbot_nix/worker.py...1057buildbot # [ 26.458935] twistd[913]: 2026-07-05T06:00:35+0000 [-] Loaded.1058buildbot # [ 26.460895] twistd[913]: 2026-07-05T06:00:35+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] twistd 25.5.0 (/nix/store/l9k0anq0z7zz81zcwy035jfwap9ga6rl-python3-3.13.13/bin/python3.13 3.13.13) starting up.1059buildbot # [ 26.465389] twistd[913]: 2026-07-05T06:00:35+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.1060buildbot # [ 26.468763] twistd[913]: 2026-07-05T06:00:35+0000 [-] Starting Worker -- version: 2026.06.261061buildbot # [ 26.471252] twistd[913]: 2026-07-05T06:00:35+0000 [-] recording hostname in twistd.hostname1062buildbot # [ 26.473707] twistd[913]: 2026-07-05T06:00:35+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0x7037cf838590>1063buildbot # [ 26.486251] twistd[913]: 2026-07-05T06:00:35+0000 [twisted.application._client_service.ClientService#info] Scheduling retry 1 to connect <twisted.internet.endpoints.TCP4ClientEndpoint object at 0x7037cf838ad0> in 1.7879398180146253 seconds.1064buildbot # [ 26.490997] twistd[913]: 2026-07-05T06:00:35+0000 [buildbot_worker.pb.BotFactory#info] Stopping factory <buildbot_worker.pb.BotFactory object at 0x7037cf838590>1065buildbot # [ 28.276211] twistd[913]: 2026-07-05T06:00:37+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0x7037cf838590>1066buildbot # [ 28.281512] twistd[913]: 2026-07-05T06:00:37+0000 [twisted.application._client_service.ClientService#info] Scheduling retry 2 to connect <twisted.internet.endpoints.TCP4ClientEndpoint object at 0x7037cf838ad0> in 2.8628757962233005 seconds.1067buildbot # [ 28.286391] twistd[913]: 2026-07-05T06:00:37+0000 [buildbot_worker.pb.BotFactory#info] Stopping factory <buildbot_worker.pb.BotFactory object at 0x7037cf838590>1068buildbot # [ 28.912963] twistd[912]: 2026-07-05T06:00:34+0000 [-] Loading /var/lib/buildbot/master/buildbot.tac...1069buildbot # [ 28.916271] twistd[912]: 2026-07-05T06:00:38+0000 [-] Loaded.1070buildbot # [ 28.917864] twistd[912]: 2026-07-05T06:00:38+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] twistd 25.5.0 (/nix/store/l9k0anq0z7zz81zcwy035jfwap9ga6rl-python3-3.13.13/bin/python3.13 3.13.13) starting up.1071buildbot # [ 28.922345] twistd[912]: 2026-07-05T06:00:38+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.1072buildbot # [ 28.925521] twistd[912]: 2026-07-05T06:00:38+0000 [-] Starting BuildMaster -- buildbot.version: 4.3.01073buildbot # [ 28.937959] twistd[912]: 2026-07-05T06:00:38+0000 [-] Loading configuration from '/nix/store/jimxyp5v1ay3slvjak51j5q1briik8gs-master.cfg'1074buildbot # [ 29.981411] twistd[912]: 2026-07-05T06:00:39+0000 [-] Setting up database with URL 'postgresql://@/buildbot'1075buildbot # [ 30.092392] twistd[912]: 2026-07-05T06:00:39+0000 [-] adding 9 new builders, removing 01076buildbot # [ 30.213171] twistd[912]: 2026-07-05T06:00:39+0000 [-] adding 3 new services, removing 01077buildbot # [ 30.356562] twistd[912]: 2026-07-05T06:00:39+0000 [-] adding 1 new change_sources, removing 01078buildbot # [ 30.361387] twistd[912]: 2026-07-05T06:00:39+0000 [-] gitpoller: using workdir '/var/lib/buildbot/master/gitpoller-work'1079buildbot # [ 30.369992] twistd[912]: 2026-07-05T06:00:39+0000 [-] adding 14 new schedulers, removing 01080buildbot # [ 30.534870] twistd[912]: 2026-07-05T06:00:39+0000 [-] BuildbotSite starting on 80101081buildbot # [ 30.537693] twistd[912]: 2026-07-05T06:00:39+0000 [buildbot.www.service.BuildbotSite#info] Starting factory <buildbot.www.service.BuildbotSite object at 0x740ac01bc980>1082buildbot # [ 30.541060] twistd[912]: 2026-07-05T06:00:39+0000 [-] adding 5 new workers, removing 01083buildbot # [ 30.557882] twistd[912]: 2026-07-05T06:00:39+0000 [-] PBServerFactory starting on 99891084buildbot # [ 30.559963] twistd[912]: 2026-07-05T06:00:39+0000 [twisted.spread.pb.PBServerFactory#info] Starting factory <twisted.spread.pb.PBServerFactory object at 0x740ac01bd400>1085buildbot # [ 30.612299] twistd[912]: 2026-07-05T06:00:39+0000 [-] Starting Worker -- version: 2026.06.261086buildbot # [ 30.614989] twistd[912]: 2026-07-05T06:00:39+0000 [-] recording hostname in twistd.hostname1087buildbot # [ 30.617203] twistd[912]: 2026-07-05T06:00:39+0000 [-] message from master: attached1088buildbot # [ 30.619945] twistd[912]: 2026-07-05T06:00:39+0000 [-] Got workerinfo from '__Janitor'1089buildbot # [ 30.629584] twistd[912]: 2026-07-05T06:00:39+0000 [-] bot attached1090buildbot # [ 30.632870] twistd[912]: 2026-07-05T06:00:39+0000 [-] Worker __Janitor attached to __Janitor1091buildbot # [ 30.634856] twistd[912]: 2026-07-05T06:00:39+0000 [-] message from master: attached1092buildbot # [ 30.647415] twistd[912]: 2026-07-05T06:00:39+0000 [-] BuildMaster is running1093buildbot # [ 30.666084] sshd-session[976]: Accepted publickey for root from ::1 port 55282 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81094buildbot # [ 30.679564] sshd-session[976]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1095buildbot # [ 30.714312] systemd[1]: Created slice Slice /user/0.1096buildbot # [ 30.718225] systemd[1]: Starting User Runtime Directory /run/user/0...1097buildbot # [ 30.733156] systemd-logind[496]: New session '1' of user 'root' with class 'user' and type 'tty'.1098buildbot # [ 30.763272] systemd[1]: Finished User Runtime Directory /run/user/0.1099buildbot # [ 30.769629] systemd[1]: Starting User Manager for UID 0...1100buildbot # [ 30.806707] (systemd)[982]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0)1101buildbot # [ 30.815216] systemd-logind[496]: New session '2' of user 'root' with class 'manager-early' and type 'unspecified'.1102buildbot # Connection to localhost (127.0.0.1) 8010 port [tcp/*] succeeded!1103buildbot: (finished: waiting for TCP port 8010 on localhost, in 5.42 seconds)1104buildbot: waiting for success: curl --fail --head http://localhost:80101105buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1106buildbot # Dload Upload Total Spent Left Speed1107buildbot # 0 0 0 0 0 0 0 0 [ 31.151946] twistd[913]: 2026-07-05T06:00:40+0000 [buildbot_worker.pb.BotFactory#info] Starting factory <buildbot_worker.pb.BotFactory object at 0x7037cf838590>1108buildbot # 0 0 0 0 0 0 [ 31.171778] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] worker 'local-worker-000' attaching from IPv4Address(type='TCP', host='127.0.0.1', port=38394)1109buildbot # 0 [ 31.176774] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1110buildbot # 0 0 [ 31.191272] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Got workerinfo from 'local-worker-000'1111buildbot # 0 0 0 0 0 0 0 0 0 [ 31.216803] twistd[912]: 2026-07-05T06:00:40+0000 [-] bot attached1112buildbot # 0 0 0 0 0[ 31.228741] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-register-gcroot1113buildbot # [ 31.231550] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/run-effect1114buildbot # [ 31.234727] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-failed-eval1115buildbot # 0 [ 31.239243] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-eval1116buildbot # [ 31.242871] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/run-scheduled-effect1117buildbot # 0 [ 31.246564] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-build1118buildbot # [ 31.249701] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-cached-failure1119buildbot # 0 [ 31.255248] twistd[912]: 2026-07-05T06:00:40+0000 [Broker,0,127.0.0.1] Worker local-worker-000 attached to test-flake/nix-dependency-failed1120buildbot # [ 31.259294] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1121buildbot # [ 31.261699] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1122buildbot # 0 [ 31.265888] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1123buildbot # [ 31.268180] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1124buildbot # [ 31.270981] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1125buildbot # [ 31.273788] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1126buildbot # [ 31.277351] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1127buildbot # [ 31.280403] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] message from master: attached1128buildbot # 01129buildbot # [ 31.294121] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] Connected to buildmaster; worker is ready1130buildbot # [ 31.298298] twistd[913]: 2026-07-05T06:00:40+0000 [Broker,client] sending application-level keepalives every 600 seconds1131buildbot: (finished: waiting for success: curl --fail --head http://localhost:8010, in 0.29 seconds)1132(finished: subtest: Master and worker services start, in 5.90 seconds)1133buildbot: waiting for success: curl http://localhost:8010/api/v2/projects1134buildbot # [ 31.329961] systemd[982]: Queued start job for default target Main User Target.1135buildbot # [ 31.340214] systemd[982]: Created slice User Application Slice.1136buildbot # [ 31.341915] systemd[982]: Started Daily Cleanup of User's Temporary Directories.1137buildbot # [ 31.362429] systemd[982]: Reached target Paths.1138buildbot # [ 31.368322] systemd[982]: Reached target Timers.1139buildbot # [ 31.378535] systemd[982]: Starting D-Bus User Message Bus Socket...1140buildbot # [ 31.386664] systemd[982]: Starting Create User Files and Directories...1141buildbot # [ 31.419638] systemd[982]: Finished Create User Files and Directories.1142buildbot # [ 31.466774] systemd[982]: Listening on D-Bus User Message Bus Socket.1143buildbot # [ 31.470809] systemd[982]: Reached target Sockets.1144buildbot # [ 31.476959] systemd[982]: Reached target Basic System.1145buildbot # [ 31.482367] systemd[982]: Run user-specific NixOS activation skipped, unmet condition check ConditionUser=!@system1146buildbot # [ 31.484798] systemd[982]: Reached target Main User Target.1147buildbot # [ 31.486380] systemd[982]: Startup finished in 624ms.1148buildbot # [ 31.496329] systemd[1]: Started User Manager for UID 0.1149buildbot # [ 31.499989] systemd[1]: Started Session 1 of User root.1150buildbot # [ 31.543221] sshd-session[1012]: Received disconnect from ::1 port 55282:11: disconnected by user1151buildbot # [ 31.546901] sshd-session[1012]: Disconnected from user root ::1 port 552821152buildbot # [ 31.549905] sshd-session[976]: pam_unix(sshd:session): session closed for user root1153buildbot # [ 31.557251] systemd[1]: session-1.scope: Deactivated successfully.1154buildbot # [ 31.561566] systemd-logind[496]: Session 1 logged out. Waiting for processes to exit.1155buildbot # [ 31.568294] systemd-logind[496]: Removed session 1.1156buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1157buildbot # Dload Upload Total Spent Left Speed1158buildbot # 0 0 0 0 0 0 0 0 0100 266 100 266 0 0 3842 0 0100 266 100 266 0 0 3125 0 0100 266 100 266 0 0 2638 0 01159buildbot: (finished: waiting for success: curl http://localhost:8010/api/v2/projects, in 0.38 seconds)1160subtest: Wait for poller to initialize1161buildbot: waiting for success: journalctl -u buildbot-master.service | grep "gitpoller: processing changes from"1162buildbot # [ 31.775521] sshd-session[1017]: Accepted publickey for root from ::1 port 55294 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81163buildbot # [ 31.790881] sshd-session[1017]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1164buildbot # [ 31.802793] systemd-logind[496]: New session '3' of user 'root' with class 'user' and type 'tty'.1165buildbot # [ 31.805818] systemd[1]: Started Session 3 of User root.1166buildbot # [ 31.855365] sshd-session[1026]: Received disconnect from ::1 port 55294:11: disconnected by user1167buildbot # [ 31.857801] sshd-session[1026]: Disconnected from user root ::1 port 552941168buildbot # [ 31.860854] sshd-session[1017]: pam_unix(sshd:session): session closed for user root1169buildbot # [ 31.868282] systemd[1]: session-3.scope: Deactivated successfully.1170buildbot # [ 31.873210] systemd-logind[496]: Session 3 logged out. Waiting for processes to exit.1171buildbot # [ 31.876411] systemd-logind[496]: Removed session 3.1172buildbot # [ 31.898112] twistd[912]: 2026-07-05T06:00:41+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1173buildbot: (finished: waiting for success: journalctl -u buildbot-master.service | grep "gitpoller: processing changes from", in 1.10 seconds)1174Poller has started processing1175(finished: subtest: Wait for poller to initialize, in 1.10 seconds)1176subtest: Push new commit to trigger poller1177buildbot: must succeed: 1178 cd /tmp/test-flake1179 echo '# Updated at test time' >> flake.nix1180 git add flake.nix1181 git commit -m 'Test commit to trigger poller'1182 git push origin master1183 1184buildbot # Enumerating objects: 5, done.1185buildbot # 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.1186buildbot # Compressing objects: 50% (1/2)Compressing objects: 100% (2/2)Compressing objects: 100% (2/2), done.1187buildbot # Writing objects: 33% (1/3)Writing objects: 66% (2/3)Writing objects: 100% (3/3)Writing objects: 100% (3/3), 299 bytes | 149.00 KiB/s, done.1188buildbot # Total 3 (delta 1), reused 0 (delta 0), pack-reused 0 (from 0)1189buildbot # To /srv/repos/test-flake.git1190buildbot # 6941a02..bf5d229 master -> master1191buildbot: (finished: must succeed: 1192 cd /tmp/test-flake1193 echo '# Updated at test time' >> flake.nix1194 git add flake.nix1195 git commit -m 'Test commit to trigger poller'1196 git push origin master1197 , in 0.10 seconds)1198Pushed new commit to repository1199(finished: subtest: Push new commit to trigger poller, in 0.10 seconds)1200subtest: Poller triggers builds and verifies results1201buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1202buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1203buildbot # Dload Upload Total Spent Left Speed1204buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1403 0 0100 51 100 51 0 0 1105 0 0100 51 100 51 0 0 909 0 01205buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.10 seconds)1206buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1207buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1208buildbot # Dload Upload Total Spent Left Speed1209buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 875 0 0100 51 100 51 0 0 652 0 0100 51 100 51 0 0 522 0 01210buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.21 seconds)1211buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1212buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1213buildbot # Dload Upload Total Spent Left Speed1214buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1210 0 0100 51 100 51 0 0 977 0 0100 51 100 51 0 0 820 0 01215buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1216buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1217buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1218buildbot # Dload Upload Total Spent Left Speed1219buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1242 0 0100 51 100 51 0 0 992 0 0100 51 100 51 0 0 830 0 01220buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1221buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1222buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1223buildbot # Dload Upload Total Spent Left Speed1224buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1425 0 0100 51 100 51 0 0 1109 0 0100 51 100 51 0 0 912 0 01225buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.14 seconds)1226buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1227buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1228buildbot # Dload Upload Total Spent Left Speed1229buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1302 0 0100 51 100 51 0 0 1031 0 0100 51 100 51 0 0 857 0 01230buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1231buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1232buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1233buildbot # Dload Upload Total Spent Left Speed1234buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1364 0 0100 51 100 51 0 0 1075 0 0100 51 100 51 0 0 886 0 01235buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1236buildbot # [ 40.620352] sshd-session[1087]: Accepted publickey for root from ::1 port 55354 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81237buildbot # [ 40.636727] sshd-session[1087]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1238buildbot # [ 40.649302] systemd-logind[496]: New session '4' of user 'root' with class 'user' and type 'tty'.1239buildbot # [ 40.652392] systemd[1]: Started Session 4 of User root.1240buildbot # [ 40.695626] sshd-session[1091]: Received disconnect from ::1 port 55354:11: disconnected by user1241buildbot # [ 40.697948] sshd-session[1091]: Disconnected from user root ::1 port 553541242buildbot # [ 40.700509] sshd-session[1087]: pam_unix(sshd:session): session closed for user root1243buildbot # [ 40.712803] systemd[1]: session-4.scope: Deactivated successfully.1244buildbot # [ 40.716406] systemd-logind[496]: Session 4 logged out. Waiting for processes to exit.1245buildbot # [ 40.720237] systemd-logind[496]: Removed session 4.1246buildbot # [ 40.920917] sshd-session[1096]: Accepted publickey for root from ::1 port 55366 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81247buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1248buildbot # [ 40.949582] sshd-session[1096]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1249buildbot # [ 40.963753] systemd-logind[496]: New session '5' of user 'root' with class 'user' and type 'tty'.1250buildbot # [ 40.968419] systemd[1]: Started Session 5 of User root.1251buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1252buildbot # Dloa[ 41.035350] sshd-session[1102]: Received disconnect from ::1 port 55366:11: disconnected by user1253buildbot # d Uploa[ 41.039395] sshd-session[1102]: Disconnected from user root ::1 port 553661254buildbot # d[ 41.041442] sshd-session[1096]: pam_unix(sshd:session): session closed for user root1255buildbot # Total Spent Le[ 41.051520] systemd[1]: session-5.scope: Deactivated successfully.1256buildbot # ft Speed1257buildbot # [ 41.057712] systemd-logind[496]: Session 5 logged out. Waiting for processes to exit.1258buildbot # [ 41.061783] systemd-logind[496]: Removed session 5.1259buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 650 0 [ 41.095511] twistd[912]: 2026-07-05T06:00:50+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1260buildbot # 0100 51 100 51 0 0 518 0 0100 51 100 51 0 0 444 0 01261buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.20 seconds)1262buildbot # [ 41.154937] twistd[912]: 2026-07-05T06:00:50+0000 [-] gitpoller: processing 1 changes: ['bf5d2294f0f6341eabdf26b359e73311d219f894'] from "ssh://root@localhost/srv/repos/test-flake.git" branch "refs/heads/master"1263buildbot # [ 41.234375] twistd[912]: 2026-07-05T06:00:50+0000 [-] added change with revision bf5d2294f0f6341eabdf26b359e73311d219f894 to database1264buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1265buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1266buildbot # Dload Upload Total Spent Left Speed1267buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1295 0 0100 51 100 51 0 0 1029 0 0100 51 100 51 0 0 856 0 01268buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1269buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1270buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1271buildbot # Dload Upload Total Spent Left Speed1272buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1257 0 0100 51 100 51 0 0 1003 0 0100 51 100 51 0 0 838 0 01273buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1274buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1275buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1276buildbot # Dload Upload Total Spent Left Speed1277buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1274 0 0100 51 100 51 0 0 1014 0 0100 51 100 51 0 0 848 0 01278buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.14 seconds)1279buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1280buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1281buildbot # Dload Upload Total Spent Left Speed1282buildbot # 0 0 0 0 0 0 0 0 0100 51 100 51 0 0 1338 0 0100 51 100 51 0 0 1051 0 0100 51 100 51 0 0 873 0 01283buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.14 seconds)1284buildbot # [ 46.282465] twistd[912]: 2026-07-05T06:00:55+0000 [-] added buildset 1 to database1285buildbot # [ 46.438699] twistd[912]: 2026-07-05T06:00:55+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>1286buildbot # [ 46.447082] twistd[912]: 2026-07-05T06:00:55+0000 [-] <Build test-flake/nix-eval number:None results:success>.startBuild1287buildbot # [ 46.507252] twistd[912]: 2026-07-05T06:00:55+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1288buildbot # [ 46.512191] twistd[912]: 2026-07-05T06:00:55+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>1289buildbot # [ 46.517095] twistd[912]: 2026-07-05T06:00:55+0000 [-] sending ping1290buildbot # [ 46.519427] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] message from master: ping1291buildbot # [ 46.522082] twistd[912]: 2026-07-05T06:00:55+0000 [Broker,0,127.0.0.1] ping finished: success1292buildbot # [ 46.544832] twistd[912]: 2026-07-05T06:00:55+0000 [-] <RemoteShellCommand '['git', '--version']'>: RemoteCommand.run [0]1293buildbot # [ 46.548424] twistd[912]: 2026-07-05T06:00:55+0000 [-] command '['git', '--version']' in dir 'build'1294buildbot # [ 46.551706] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 0): startCommand:shell1295buildbot # [ 46.554491] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command ['git', '--version']): RunProcess._startCommand1296buildbot # [ 46.558091] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command ['git', '--version']): git --version1297buildbot # [ 46.560674] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command ['git', '--version']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1298buildbot # [ 46.564463] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command ['git', '--version']): watching logfiles {}1299buildbot # [ 46.567122] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command ['git', '--version']): argv: [b'git', b'--version']1300buildbot # [ 46.571090] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command ['git', '--version']): using PTY: False1301buildbot # [ 46.586598] twistd[913]: 2026-07-05T06:00:55+0000 [-] (command ['git', '--version']): command finished with signal None, exit code 0, elapsedTime: 0.0163871302buildbot # [ 46.589928] twistd[913]: 2026-07-05T06:00:55+0000 [-] (command 0): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf83aa50>1303buildbot # [ 46.620043] twistd[912]: 2026-07-05T06:00:55+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', '--version']'> rc=01304buildbot # [ 46.655748] twistd[912]: 2026-07-05T06:00:55+0000 [-] <RemoteCommand 'stat' at 127589393630272>: RemoteCommand.run [1]1305buildbot # [ 46.665095] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 1): startCommand:stat1306buildbot # [ 46.667236] twistd[913]: 2026-07-05T06:00:55+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'1307buildbot # [ 46.672750] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 1): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.StatFile object at 0x7037cf839400>1308buildbot # [ 46.678322] twistd[912]: 2026-07-05T06:00:55+0000 [Broker,0,127.0.0.1] <RemoteCommand 'stat' at 127589393630272> rc=21309buildbot # [ 46.691084] twistd[912]: 2026-07-05T06:00:55+0000 [-] <RemoteCommand 'mkdir' at 127589393037456>: RemoteCommand.run [2]1310buildbot # [ 46.719098] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 2): startCommand:mkdir1311buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1312buildbot # [ 46.721267] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 2): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.MakeDirectory object at 0x7037cf839160>1313buildbot # [ 46.727513] twistd[912]: 2026-07-05T06:00:55+0000 [Broker,0,127.0.0.1] <RemoteCommand 'mkdir' at 127589393037456> rc=01314buildbot # [ 46.739478] twistd[912]: 2026-07-05T06:00:55+0000 [-] <RemoteCommand 'downloadFile' at 127589393038416>: RemoteCommand.run [3]1315buildbot # [ 46.772159] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 3): startCommand:downloadFile1316buildbot # [ 46.778098] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 3): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.transfer.WorkerFileDownloadCommand object at 0x7037cf8381a0>1317buildbot # [ 46.783095] twistd[912]: 2026-07-05T06:00:55+0000 [Broker,0,127.0.0.1] <RemoteCommand 'downloadFile' at 127589393038416> rc=01318buildbot # [ 46.795265] twistd[912]: 2026-07-05T06:00:55+0000 [-] <RemoteCommand 'listdir' at 127589393038736>: RemoteCommand.run [4]1319buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1320buildbot # Dload Upload Total Spent Left Speed1321buildbot # 0 0 0 0 0 0 0 0 [ 46.830146] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 4): startCommand:listdir1322buildbot # [ 46.832312] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 4): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.ListDir object at 0x7037cf8392b0>1323buildbot # 0[ 46.840102] twistd[912]: 2026-07-05T06:00:55+0000 [Broker,0,127.0.0.1] <RemoteCommand 'listdir' at 127589393038736> rc=01324buildbot # 100 401 100 401 0 0 7431 0 [ 46.857879] twistd[912]: 2026-07-05T06:00:55+0000 [-] No git repo present, making full clone1325buildbot # [ 46.862098] twistd[912]: 2026-07-05T06:00:55+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]1326buildbot # [ 46.868389] twistd[912]: 2026-07-05T06:00:55+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'1327buildbot # 0100 401 100 401 0 0 4841 0 0100 401 100[ 46.890172] twistd[913]: 2026-07-05T06:00:55+0000 [Broker,client] (command 5): startCommand:shell1328buildbot # 401 0 [ 46.895145] twistd[913]: 2026-07-05T06:00:56+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._startCommand1329buildbot # [ 46.901467] twistd[913]: 2026-07-05T06:00:56+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 . --progress1330buildbot # 0 4312 0 01331buildbot # [ 46.932750] twistd[913]: 2026-07-05T06:00:56+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)1332buildbot # [ 46.940350] twistd[913]: 2026-07-05T06:00:56+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 {}1333buildbot # [ 46.947400] twistd[913]: 2026-07-05T06:00:56+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']1334buildbot # [ 46.958195] twistd[913]: 2026-07-05T06:00:56+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: False1335buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.25 seconds)1336buildbot # [ 47.180866] sshd-session[1137]: Accepted publickey for root from ::1 port 41548 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81337buildbot # [ 47.206366] sshd-session[1137]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1338buildbot # [ 47.217360] systemd-logind[496]: New session '6' of user 'root' with class 'user' and type 'tty'.1339buildbot # [ 47.222744] systemd[1]: Started Session 6 of User root.1340buildbot # [ 47.286948] sshd-session[1140]: Received disconnect from ::1 port 41548:11: disconnected by user1341buildbot # [ 47.289670] sshd-session[1140]: Disconnected from user root ::1 port 415481342buildbot # [ 47.291702] sshd-session[1137]: pam_unix(sshd:session): session closed for user root1343buildbot # [ 47.299748] systemd[1]: session-6.scope: Deactivated successfully.1344buildbot # [ 47.304124] systemd-logind[496]: Session 6 logged out. Waiting for processes to exit.1345buildbot # [ 47.307719] systemd-logind[496]: Removed session 6.1346buildbot # [ 47.324958] twistd[913]: 2026-07-05T06:00:56+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.4121891347buildbot # [ 47.331888] twistd[913]: 2026-07-05T06:00:56+0000 [-] (command 5): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf851090>1348buildbot # [ 47.338627] twistd[912]: 2026-07-05T06:00:56+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=01349buildbot # [ 47.366646] twistd[912]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']'>: RemoteCommand.run [6]1350buildbot # [ 47.372266] twistd[912]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']' in dir 'build'1351buildbot # [ 47.378612] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command 6): startCommand:shell1352buildbot # [ 47.381788] twistd[913]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']): RunProcess._startCommand1353buildbot # [ 47.387647] twistd[913]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']): 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 bf5d2294f0f6341eabdf26b359e73311d219f8941354buildbot # [ 47.397938] twistd[913]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1355buildbot # [ 47.404720] twistd[913]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']): watching logfiles {}1356buildbot # [ 47.410325] twistd[913]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']): 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'bf5d2294f0f6341eabdf26b359e73311d219f894']1357buildbot # [ 47.420137] twistd[913]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']): using PTY: False1358buildbot # [ 47.444856] twistd[913]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']): command finished with signal None, exit code 0, elapsedTime: 0.0477461359buildbot # [ 47.451499] twistd[913]: 2026-07-05T06:00:56+0000 [-] (command 6): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf851590>1360buildbot # [ 47.470750] twistd[912]: 2026-07-05T06:00:56+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', 'bf5d2294f0f6341eabdf26b359e73311d219f894']'> rc=01361buildbot # [ 47.498585] twistd[912]: 2026-07-05T06:00:56+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]1362buildbot # [ 47.503988] twistd[912]: 2026-07-05T06:00:56+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'1363buildbot # [ 47.510200] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command 7): startCommand:shell1364buildbot # [ 47.515082] twistd[913]: 2026-07-05T06:00:56+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._startCommand1365buildbot # [ 47.520788] twistd[913]: 2026-07-05T06:00:56+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 --recursive1366buildbot # [ 47.528744] twistd[913]: 2026-07-05T06:00:56+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)1367buildbot # [ 47.536139] twistd[913]: 2026-07-05T06:00:56+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 {}1368buildbot # [ 47.541497] twistd[913]: 2026-07-05T06:00:56+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']1369buildbot # [ 47.549993] twistd[913]: 2026-07-05T06:00:56+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: False1370buildbot # [ 47.686546] twistd[913]: 2026-07-05T06:00:56+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.1722171371buildbot # [ 47.693238] twistd[913]: 2026-07-05T06:00:56+0000 [-] (command 7): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf7f6520>1372buildbot # [ 47.699043] twistd[912]: 2026-07-05T06:00:56+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=01373buildbot # [ 47.723697] twistd[912]: 2026-07-05T06:00:56+0000 [-] <RemoteShellCommand '['git', 'rev-parse', 'HEAD']'>: RemoteCommand.run [8]1374buildbot # [ 47.726335] twistd[912]: 2026-07-05T06:00:56+0000 [-] command '['git', 'rev-parse', 'HEAD']' in dir 'build'1375buildbot # [ 47.730091] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command 8): startCommand:shell1376buildbot # [ 47.732243] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): RunProcess._startCommand1377buildbot # [ 47.735462] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): git rev-parse HEAD1378buildbot # [ 47.738054] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1379buildbot # [ 47.741831] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): watching logfiles {}1380buildbot # [ 47.746287] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): argv: [b'git', b'rev-parse', b'HEAD']1381buildbot # [ 47.749254] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['git', 'rev-parse', 'HEAD']): using PTY: False1382buildbot # [ 47.765425] twistd[913]: 2026-07-05T06:00:56+0000 [-] (command ['git', 'rev-parse', 'HEAD']): command finished with signal None, exit code 0, elapsedTime: 0.0200771383buildbot # [ 47.768973] twistd[913]: 2026-07-05T06:00:56+0000 [-] (command 8): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf7f6650>1384buildbot # [ 47.797679] twistd[912]: 2026-07-05T06:00:56+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['git', 'rev-parse', 'HEAD']'> rc=01385buildbot # [ 47.822452] twistd[912]: 2026-07-05T06:00:56+0000 [-] Got Git revision bf5d2294f0f6341eabdf26b359e73311d219f8941386buildbot # [ 47.824835] twistd[912]: 2026-07-05T06:00:56+0000 [-] <RemoteCommand 'rmdir' at 127589393727568>: RemoteCommand.run [9]1387buildbot # [ 47.842106] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command 9): startCommand:rmdir1388buildbot # [ 47.844257] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): RunProcess._startCommand1389buildbot # [ 47.848169] twistd[913]: 2026-07-05T06:00:56+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.buildbot1390buildbot # [ 47.854836] twistd[913]: 2026-07-05T06:00:56+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)1391buildbot # [ 47.859221] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): watching logfiles {}1392buildbot # [ 47.862883] twistd[913]: 2026-07-05T06:00:56+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']1393buildbot # [ 47.867827] twistd[913]: 2026-07-05T06:00:56+0000 [Broker,client] (command ['rm', '-rf', '/var/lib/buildbot-worker/worker-000/.test-flake_nix-eval.build.buildbot']): using PTY: False1394buildbot # [ 47.886307] twistd[913]: 2026-07-05T06:00:56+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.0324811395buildbot # [ 47.891066] twistd[913]: 2026-07-05T06:00:56+0000 [-] (command 9): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.fs.RemoveDirectory object at 0x7037cf83b0e0>1396buildbot # [ 47.916058] twistd[912]: 2026-07-05T06:00:57+0000 [Broker,0,127.0.0.1] <RemoteCommand 'rmdir' at 127589393727568> rc=01397buildbot # [ 47.939505] twistd[912]: 2026-07-05T06:00:57+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)): []1398buildbot # [ 47.965645] twistd[912]: 2026-07-05T06:00:57+0000 [-] step 'git' complete: success (None)1399buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1400buildbot # [ 47.976960] twistd[912]: 2026-07-05T06:00:57+0000 [-] acquireLocks(step NixEvalCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0x740ac05f8d70>, env={'CLICOLOR_FORCE': '1'}, name='Evaluate flake', nix_eval_config=NixEvalConfig(supported_systems=['x86_64-linux'], failed_build_report_limit=47, worker_count=1, max_memory_size=2048, eval_lock=<buildbot.locks.MasterLock object at 0x740ac05f92b0>, gcroots_user='buildbot-worker', cache_failed_builds=False, show_trace=False), haltOnFailure=True, locks=[<buildbot.locks.LockAccess object at 0x740ac05fa660>], 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 0x740ac05fa660>)])1401buildbot # [ 48.004165] twistd[912]: 2026-07-05T06:00:57+0000 [-] <RemoteShellCommand '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']'>: RemoteCommand.run [10]1402buildbot # [ 48.007718] twistd[912]: 2026-07-05T06:00:57+0000 [-] command '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']' in dir 'build'1403buildbot # [ 48.013884] twistd[913]: 2026-07-05T06:00:57+0000 [Broker,client] (command 10): startCommand:shell1404buildbot # [ 48.017317] twistd[913]: 2026-07-05T06:00:57+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): RunProcess._startCommand1405buildbot # [ 48.021174] twistd[913]: 2026-07-05T06:00:57+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'1406buildbot # [ 48.025656] twistd[913]: 2026-07-05T06:00:57+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)1407buildbot # [ 48.032154] twistd[913]: 2026-07-05T06:00:57+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): watching logfiles {}1408buildbot # [ 48.035695] twistd[913]: 2026-07-05T06:00:57+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']1409buildbot # [ 48.040291] twistd[913]: 2026-07-05T06:00:57+0000 [Broker,client] (command ['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']): using PTY: False1410buildbot # [ 48.065243] twistd[913]: 2026-07-05T06:00:57+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.0339671411buildbot # [ 48.069608] twistd[913]: 2026-07-05T06:00:57+0000 [-] (command 10): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cfe1be30>1412buildbot # % Total % Received % Xfer[ 48.093150] twistd[912]: 2026-07-05T06:00:57+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['sh', '-c', 'if [ -f buildbot-nix.toml ]; then cat buildbot-nix.toml; fi']'> rc=01413buildbot # d Average Speed Time Ti[ 48.102925] twistd[912]: 2026-07-05T06:00:57+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]1414buildbot # me Time [ 48.112659] twistd[912]: 2026-07-05T06:00:57+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'1415buildbot # Current1416buildbot # Dload Upload Total Spent Left Speed1417buildbot # 0 0 0 0 0 0 0 0 [ 48.139253] twistd[913]: 2026-07-05T06:00:57+0000 [Broker,client] (command 11): startCommand:shell1418buildbot # [ 48.141771] twistd[913]: 2026-07-05T06:00:57+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._startCommand1419buildbot # [ 48.152146] twistd[913]: 2026-07-05T06:00:57+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'1420buildbot # [ 48.163695] twistd[913]: 2026-07-05T06:00:57+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)1421buildbot # [ 48.172399] twistd[913]: 2026-07-05T06:00:57+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 {}1422buildbot # 0[ 48.184104] twistd[913]: 2026-07-05T06:00:57+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']1423buildbot # [ 48.196930] twistd[913]: 2026-07-05T06:00:57+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: False1424buildbot # 100 401 100 401 0 0 3244 0 0100 401 100 401 0 0 2900 0 0100 401 100 401 0 0 2633 0 01425buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.29 seconds)1426buildbot # [ 48.352343] systemd[1]: Started Nix Daemon.1427buildbot # [ 48.468400] nix-daemon[1185]: accepted connection from pid 1184, user buildbot-worker1428buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1429buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1430buildbot # Dload Upload Total Spent Left Speed1431buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 10799 0 0100 401 100 401 0 0 8510 0 0100 401 100 401 0 0 6851 0 01432buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1433buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1434buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1435buildbot # Dload Upload Total Spent Left Speed1436buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 7333 0 0100 401 100 401 0 0 6165 0 0100 401 100 401 0 0 5341 0 01437buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.16 seconds)1438buildbot # [ 50.601706] sshd-session[1202]: Accepted publickey for root from ::1 port 41560 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81439buildbot # [ 50.617401] sshd-session[1202]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1440buildbot # [ 50.627904] systemd-logind[496]: New session '7' of user 'root' with class 'user' and type 'tty'.1441buildbot # [ 50.633476] systemd[1]: Started Session 7 of User root.1442buildbot # [ 50.668662] sshd-session[1205]: Received disconnect from ::1 port 41560:11: disconnected by user1443buildbot # [ 50.671241] sshd-session[1205]: Disconnected from user root ::1 port 415601444buildbot # [ 50.674280] sshd-session[1202]: pam_unix(sshd:session): session closed for user root1445buildbot # [ 50.682745] systemd[1]: session-7.scope: Deactivated successfully.1446buildbot # [ 50.689343] systemd-logind[496]: Session 7 logged out. Waiting for processes to exit.1447buildbot # [ 50.692428] systemd-logind[496]: Removed session 7.1448buildbot # [ 50.862691] sshd-session[1210]: Accepted publickey for root from ::1 port 41568 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81449buildbot # [ 50.876196] sshd-session[1210]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1450buildbot # [ 50.887557] systemd-logind[496]: New session '8' of user 'root' with class 'user' and type 'tty'.1451buildbot # [ 50.890249] systemd[1]: Started Session 8 of User root.1452buildbot # [ 50.931646] sshd-session[1213]: Received disconnect from ::1 port 41568:11: disconnected by user1453buildbot # [ 50.935524] sshd-session[1213]: Disconnected from user root ::1 port 415681454buildbot # [ 50.938361] sshd-session[1210]: pam_unix(sshd:session): session closed for user root1455buildbot # [ 50.942758] systemd[1]: session-8.scope: Deactivated successfully.1456buildbot # [ 50.947786] systemd-logind[496]: Session 8 logged out. Waiting for processes to exit.1457buildbot # [ 50.952279] systemd-logind[496]: Removed session 8.1458buildbot # [ 50.957896] twistd[912]: 2026-07-05T06:01:00+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1459buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1460buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1461buildbot # Dload Upload Total Spent Left Speed1462buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 9701 0 0100 401 100 401 0 0 7786 0 0100 401 100 401 0 0 6534 0 01463buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.14 seconds)1464buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1465buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1466buildbot # Dload Upload Total Spent Left Speed1467buildbot # 0 0 0 0 0 0 0 0 0100 401 100 401 0 0 10304 0 0100 401 100 401 0 0 8035 0 0100 401 100 401 0 0 6689 0 01468buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1469buildbot # [ 52.892436] twistd[913]: 2026-07-05T06:01:01+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: 4.7093541470buildbot # [ 52.901220] twistd[913]: 2026-07-05T06:01:01+0000 [-] (command 11): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf858af0>1471buildbot # [ 52.908537] twistd[912]: 2026-07-05T06:01:02+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=01472buildbot # [ 52.956388] twistd[912]: 2026-07-05T06:01:02+0000 [-] releaseLocks(NixEvalCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0x740ac05f8d70>, env={'CLICOLOR_FORCE': '1'}, name='Evaluate flake', nix_eval_config=NixEvalConfig(supported_systems=['x86_64-linux'], failed_build_report_limit=47, worker_count=1, max_memory_size=2048, eval_lock=<buildbot.locks.MasterLock object at 0x740ac05f92b0>, gcroots_user='buildbot-worker', cache_failed_builds=False, show_trace=False), haltOnFailure=True, locks=[<buildbot.locks.LockAccess object at 0x740ac05fa660>], 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 0x740ac05fa660>)]1473buildbot # [ 52.976265] twistd[912]: 2026-07-05T06:01:02+0000 [-] step 'Evaluate flake' complete: success (None)1474buildbot # [ 53.079976] twistd[912]: 2026-07-05T06:01:02+0000 [-] added buildset 2 to database1475buildbot # [ 53.106635] twistd[912]: 2026-07-05T06:01:02+0000 [-] added buildset 3 to database1476buildbot # [ 53.250798] twistd[912]: 2026-07-05T06:01:02+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>1477buildbot # [ 53.255275] twistd[912]: 2026-07-05T06:01:02+0000 [-] <Build test-flake/nix-build number:None results:success>.startBuild1478buildbot # [ 53.327869] twistd[912]: 2026-07-05T06:01:02+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1479buildbot # [ 53.333520] twistd[912]: 2026-07-05T06:01:02+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>1480buildbot # [ 53.337932] twistd[912]: 2026-07-05T06:01:02+0000 [-] sending ping1481buildbot # [ 53.339927] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] message from master: ping1482buildbot # [ 53.342420] twistd[912]: 2026-07-05T06:01:02+0000 [Broker,0,127.0.0.1] ping finished: success1483buildbot # [ 53.365099] twistd[912]: 2026-07-05T06:01:02+0000 [-] <RemoteShellCommand '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']'>: RemoteCommand.run [12]1484buildbot # [ 53.371224] twistd[912]: 2026-07-05T06:01:02+0000 [-] command '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']' in dir 'build'1485buildbot # [ 53.377874] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] (command 12): startCommand:shell1486buildbot # [ 53.383072] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']): RunProcess._startCommand1487buildbot # [ 53.389178] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']): nix build -L --option keep-going true --max-silent-time 1200 --accept-flake-config --out-link result-x86_64-linux.failing-test '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*'1488buildbot # [ 53.398144] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-build/build (timeout 10800 secs)1489buildbot # [ 53.405419] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']): watching logfiles {}1490buildbot # [ 53.412150] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-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-x86_64-linux.failing-test', b'/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']1491buildbot # [ 53.422204] twistd[913]: 2026-07-05T06:01:02+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']): using PTY: False1492buildbot # [ 53.518993] nix-daemon[1185]: accepted connection from pid 1227, user buildbot-worker1493buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1494buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1495buildbot # Dload Upload Total Spent Left Speed1496buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 18976 0 0100 751 100 751 0 0 14301 0 0100 751 100 751 0 0 12008 0 01497buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1498buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1499buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1500buildbot # Dload Upload Total Spent Left Speed1501buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 20002 0 0100 751 100 751 0 0 15729 0 0100 751 100 751 0 0 13014 0 01502buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1503buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1504buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1505buildbot # Dload Upload Total Spent Left Speed1506buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 18053 0 0100 751 100 751 0 0 14479 0 0100 751 100 751 0 0 12130 0 01507buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1508buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1509buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1510buildbot # Dload Upload Total Spent Left Speed1511buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 18250 0 0100 751 100 751 0 0 14571 0 0100 751 100 751 0 0 12202 0 01512buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1513buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1514buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1515buildbot # Dload Upload Total Spent Left Speed1516buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 19626 0 0100 751 100 751 0 0 15493 0 0100 751 100 751 0 0 12842 0 01517buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1518buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1519buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1520buildbot # Dload Upload Total Spent Left Speed1521buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 18636 0 0100 751 100 751 0 0 14858 0 0100 751 100 751 0 0 12402 0 01522buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1523buildbot # [ 60.633698] sshd-session[1255]: Accepted publickey for root from ::1 port 58708 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81524buildbot # [ 60.660500] sshd-session[1255]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1525buildbot # [ 60.673105] systemd-logind[496]: New session '9' of user 'root' with class 'user' and type 'tty'.1526buildbot # [ 60.675890] systemd[1]: Started Session 9 of User root.1527buildbot # [ 60.719929] sshd-session[1258]: Received disconnect from ::1 port 58708:11: disconnected by user1528buildbot # [ 60.722220] sshd-session[1258]: Disconnected from user root ::1 port 587081529buildbot # [ 60.724342] sshd-session[1255]: pam_unix(sshd:session): session closed for user root1530buildbot # [ 60.736898] systemd[1]: session-9.scope: Deactivated successfully.1531buildbot # [ 60.742140] systemd-logind[496]: Session 9 logged out. Waiting for processes to exit.1532buildbot # [ 60.745843] systemd-logind[496]: Removed session 9.1533buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1534buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1535buildbot # Dload Upload Total Spent Left Speed1536buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 19992 0 0100 751 100 751 0 0 14195 0 0100 751 100 751 0 0 11926 0 01537buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.13 seconds)1538buildbot # [ 60.941555] sshd-session[1266]: Accepted publickey for root from ::1 port 58710 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81539buildbot # [ 60.957675] sshd-session[1266]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1540buildbot # [ 60.970097] systemd-logind[496]: New session '10' of user 'root' with class 'user' and type 'tty'.1541buildbot # [ 60.972663] systemd[1]: Started Session 10 of User root.1542buildbot # [ 61.022874] sshd-session[1269]: Received disconnect from ::1 port 58710:11: disconnected by user1543buildbot # [ 61.025950] sshd-session[1269]: Disconnected from user root ::1 port 587101544buildbot # [ 61.027949] sshd-session[1266]: pam_unix(sshd:session): session closed for user root1545buildbot # [ 61.035251] systemd[1]: session-10.scope: Deactivated successfully.1546buildbot # [ 61.040158] systemd-logind[496]: Session 10 logged out. Waiting for processes to exit.1547buildbot # [ 61.045152] systemd-logind[496]: Removed session 10.1548buildbot # [ 61.049420] twistd[912]: 2026-07-05T06:01:10+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1549buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1550buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1551buildbot # Dload Upload Total Spent Left Speed1552buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 16920 0 0100 751 100 751 0 0 13775 0 0100 751 100 751 0 0 11638 0 01553buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.16 seconds)1554buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1555buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1556buildbot # Dload Upload Total Spent Left Speed1557buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 18424 0 0100 751 100 751 0 0 14771 0 0100 751 100 751 0 0 12325 0 01558buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1559buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1560buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1561buildbot # Dload Upload Total Spent Left Speed1562buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 19102 0 0100 751 100 751 0 0 15244 0 0100 751 100 751 0 0 12658 0 01563buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1564buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1565buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1566buildbot # Dload Upload Total Spent Left Speed1567buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 19153 0 0100 751 100 751 0 0 15179 0 0100 751 100 751 0 0 12611 0 01568buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1569buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1570buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1571buildbot # Dload Upload Total Spent Left Speed1572buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 18414 0 0100 751 100 751 0 0 14839 0 0100 751 100 751 0 0 12428 0 01573buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.14 seconds)1574buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1575buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1576buildbot # Dload Upload Total Spent Left Speed1577buildbot # 0 0 0 0 0 0 0 0 0100 751 100 751 0 0 19414 0 0100 751 100 751 0 0 15401 0 0100 751 100 751 0 0 12832 0 01578buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.14 seconds)1579buildbot # [ 68.563763] twistd[913]: 2026-07-05T06:01:17+0000 [-] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']): command finished with signal None, exit code 1, elapsedTime: 15.1812721580buildbot # [ 68.570726] twistd[913]: 2026-07-05T06:01:17+0000 [-] (command 12): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf859150>1581buildbot # [ 68.576347] twistd[912]: 2026-07-05T06:01:17+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-x86_64-linux.failing-test', '/nix/store/fda1hkdim8isvx87zxb3qakl0ncsl2p8-failing-test.drv^*']'> rc=11582buildbot # [ 68.607893] twistd[912]: 2026-07-05T06:01:17+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)): []1583buildbot # [ 68.625119] twistd[912]: 2026-07-05T06:01:17+0000 [-] step 'Build flake attr' complete: failure (["'nix build ...' (failure)"])1584buildbot # [ 68.627994] twistd[912]: 2026-07-05T06:01:17+0000 [-] <Build test-flake/nix-build number:1 results:failure>: build finished1585buildbot # [ 68.642259] twistd[912]: 2026-07-05T06:01:17+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1586buildbot # [ 68.740805] twistd[912]: 2026-07-05T06:01:17+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>1587buildbot # [ 68.746216] twistd[912]: 2026-07-05T06:01:17+0000 [-] <Build test-flake/nix-build number:None results:success>.startBuild1588buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1589buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1590buildbot # [ 68.869887] twistd[912]: 2026-07-05T06:01:17+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1591buildbot # Dload Upload Total Sp[ 68.878858] twistd[912]: 2026-07-05T06:01:17+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>1592buildbot # [ 68.883227] twistd[912]: 2026-07-05T06:01:17+0000 [-] sending ping1593buildbot # ent Left [ 68.887122] twistd[913]: 2026-07-05T06:01:17+0000 [Broker,client] message from master: ping1594buildbot # [ 68.889891] twistd[912]: 2026-07-05T06:01:17+0000 [Broker,0,127.0.0.1] ping finished: success1595buildbot # Speed1596buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 17323 0 0100 1127 100 1127 0 0 14042[ 68.936299] twistd[912]: 2026-07-05T06:01:18+0000 [-] <RemoteShellCommand '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']'>: RemoteCommand.run [13]1597buildbot # [ 68.941995] twistd[912]: 2026-07-05T06:01:18+0000 [-] command '['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']' in dir 'build'1598buildbot # 0 [ 68.951102] twistd[913]: 2026-07-05T06:01:18+0000 [Broker,client] (command 13): startCommand:shell1599buildbot # [ 68.953208] twistd[913]: 2026-07-05T06:01:18+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']): RunProcess._startCommand1600buildbot # [ 68.958864] twistd[913]: 2026-07-05T06:01:18+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']): nix build -L --option keep-going true --max-silent-time 1200 --accept-flake-config --out-link result-x86_64-linux.test '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*'1601buildbot # [ 68.969143] twistd[913]: 2026-07-05T06:01:18+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-build/build (timeout 10800 secs)1602buildbot # [ 68.977959] twistd[913]: 2026-07-05T06:01:18+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']): watching logfiles {}1603buildbot # [ 68.983732] twistd[913]: 2026-07-05T06:01:18+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-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-x86_64-linux.test', b'/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']1604buildbot # [ 68.992979] twistd[913]: 2026-07-05T06:01:18+0000 [Broker,client] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']): using PTY: False1605buildbot # 0100 1127 100 1127 0 0 7369 0 01606buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.23 seconds)1607buildbot # [ 69.095255] nix-daemon[1185]: accepted connection from pid 1312, user buildbot-worker1608buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1609buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1610buildbot # Dload Upload Total Spent Left Speed1611buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 28674 0 0100 1127 100 1127 0 0 22715 0 0100 1127 100 1127 0 0 18898 0 01612buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1613buildbot # [ 70.628636] sshd-session[1327]: Accepted publickey for root from ::1 port 34408 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81614buildbot # [ 70.656305] sshd-session[1327]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1615buildbot # [ 70.668926] systemd-logind[496]: New session '11' of user 'root' with class 'user' and type 'tty'.1616buildbot # [ 70.671739] systemd[1]: Started Session 11 of User root.1617buildbot # [ 70.716231] sshd-session[1330]: Received disconnect from ::1 port 34408:11: disconnected by user1618buildbot # [ 70.718748] sshd-session[1330]: Disconnected from user root ::1 port 344081619buildbot # [ 70.720461] sshd-session[1327]: pam_unix(sshd:session): session closed for user root1620buildbot # [ 70.732535] systemd[1]: session-11.scope: Deactivated successfully.1621buildbot # [ 70.738447] systemd-logind[496]: Session 11 logged out. Waiting for processes to exit.1622buildbot # [ 70.740638] systemd-logind[496]: Removed session 11.1623buildbot # [ 70.947650] sshd-session[1335]: Accepted publickey for root from ::1 port 34414 ssh2: ED25519 SHA256:9bQN408SyytfaJe89BgemJ/ksIKTwmKJks3tp9SgYW81624buildbot # [ 70.971760] sshd-session[1335]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)1625buildbot # [ 70.984133] systemd-logind[496]: New session '12' of user 'root' with class 'user' and type 'tty'.1626buildbot # [ 70.986602] systemd[1]: Started Session 12 of User root.1627buildbot # [ 71.038752] sshd-session[1338]: Received disconnect from ::1 port 34414:11: disconnected by user1628buildbot # [ 71.040993] sshd-session[1338]: Disconnected from user root ::1 port 344141629buildbot # [ 71.043763] sshd-session[1335]: pam_unix(sshd:session): session closed for user root1630buildbot # [ 71.051102] systemd[1]: session-12.scope: Deactivated successfully.1631buildbot # [ 71.056140] systemd-logind[496]: Session 12 logged out. Waiting for processes to exit.1632buildbot # [ 71.061360] systemd-logind[496]: Removed session 12.1633buildbot # [ 71.065868] twistd[912]: 2026-07-05T06:01:20+0000 [-] gitpoller: processing changes from "ssh://root@localhost/srv/repos/test-flake.git"1634buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1635buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1636buildbot # Dload Upload Total Spent Left Speed1637buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 31179 0 0100 1127 100 1127 0 0 24381 0 0100 1127 100 1127 0 0 20063 0 01638buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.12 seconds)1639buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1640buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1641buildbot # Dload Upload Total Spent Left Speed1642buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 29300 0 0100 1127 100 1127 0 0 23131 0 0100 1127 100 1127 0 0 19199 0 01643buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1644buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1645buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1646buildbot # Dload Upload Total Spent Left Speed1647buildbot # 0 0 0 0 0 0 0 0 0100 1127 100 1127 0 0 29128 0 0100 1127 100 1127 0 0 22801 0 0100 1127 100 1127 0 0 18944 0 01648buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1649buildbot # [ 73.981384] twistd[913]: 2026-07-05T06:01:23+0000 [-] (command ['nix', 'build', '-L', '--option', 'keep-going', 'true', '--max-silent-time', '1200', '--accept-flake-config', '--out-link', 'result-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']): command finished with signal None, exit code 0, elapsedTime: 5.0041421650buildbot # [ 73.988647] twistd[913]: 2026-07-05T06:01:23+0000 [-] (command 13): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf868750>1651buildbot # [ 73.994376] twistd[912]: 2026-07-05T06:01:23+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-x86_64-linux.test', '/nix/store/qkmhm672mkkyvgcjrhmx1llqiwwr5nqs-test.drv^*']'> rc=01652buildbot # [ 74.024414] twistd[912]: 2026-07-05T06:01:23+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)): []1653buildbot # [ 74.041723] twistd[912]: 2026-07-05T06:01:23+0000 [-] step 'Build flake attr' complete: success (None)1654buildbot # [ 74.066723] twistd[912]: 2026-07-05T06:01:23+0000 [-] added buildset 4 to database1655buildbot # [ 74.206786] twistd[912]: 2026-07-05T06:01:23+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>1656buildbot # [ 74.211519] twistd[912]: 2026-07-05T06:01:23+0000 [-] <Build test-flake/nix-register-gcroot number:None results:success>.startBuild1657buildbot # [ 74.262863] twistd[912]: 2026-07-05T06:01:23+0000 [-] acquireLocks(worker <Worker 'local-worker-000'>, locks [])1658buildbot # [ 74.268432] twistd[912]: 2026-07-05T06:01:23+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>1659buildbot # [ 74.273411] twistd[912]: 2026-07-05T06:01:23+0000 [-] sending ping1660buildbot # [ 74.275478] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] message from master: ping1661buildbot # [ 74.277932] twistd[912]: 2026-07-05T06:01:23+0000 [Broker,0,127.0.0.1] ping finished: success1662buildbot # [ 74.300757] twistd[912]: 2026-07-05T06:01:23+0000 [-] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']'>: RemoteCommand.run [14]1663buildbot # [ 74.305688] twistd[912]: 2026-07-05T06:01:23+0000 [-] command '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']' in dir 'build'1664buildbot # [ 74.311158] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command 14): startCommand:shell1665buildbot # [ 74.315952] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']): RunProcess._startCommand1666buildbot # [ 74.320970] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']): nix-store --add-root /nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test -r /nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test1667buildbot # [ 74.328212] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-register-gcroot/build (timeout 1200 secs)1668buildbot # [ 74.334510] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']): watching logfiles {}1669buildbot # [ 74.340131] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']): argv: [b'nix-store', b'--add-root', b'/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', b'-r', b'/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']1670buildbot # [ 74.347757] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']): using PTY: False1671buildbot # [ 74.445992] nix-daemon[1185]: accepted connection from pid 1363, user buildbot-worker1672buildbot # [ 74.467707] twistd[913]: 2026-07-05T06:01:23+0000 [-] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']): command finished with signal None, exit code 0, elapsedTime: 0.1525991673buildbot # [ 74.473600] twistd[913]: 2026-07-05T06:01:23+0000 [-] (command 14): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf868950>1674buildbot # [ 74.480136] twistd[912]: 2026-07-05T06:01:23+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.test', '-r', '/nix/store/4896bsdcnh0yzqidnw7rid4p7dggplik-test']'> rc=01675buildbot # [ 74.514111] twistd[912]: 2026-07-05T06:01:23+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)): []1676buildbot # [ 74.530674] twistd[912]: 2026-07-05T06:01:23+0000 [-] step 'Register gcroot' complete: success (None)1677buildbot # [ 74.532851] twistd[912]: 2026-07-05T06:01:23+0000 [-] <Build test-flake/nix-register-gcroot number:1 results:success>: build finished1678buildbot # [ 74.547093] twistd[912]: 2026-07-05T06:01:23+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1679buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1680buildbot # [ 74.665437] twistd[912]: 2026-07-05T06:01:23+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 0x740ac0459bc0>, copy_properties=['out_path', 'attr'], set_properties={'report_status': False})): []1681buildbot # [ 74.682967] twistd[912]: 2026-07-05T06:01:23+0000 [-] step 'Register gcroot' complete: success (None)1682buildbot # [ 74.704276] twistd[912]: 2026-07-05T06:01:23+0000 [-] <RemoteShellCommand '['rm', '-f', 'result-x86_64-linux.test']'>: RemoteCommand.run [15]1683buildbot # [ 74.707164] twistd[912]: 2026-07-05T06:01:23+0000 [-] command '['rm', '-f', 'result-x86_64-linux.test']' in dir 'build'1684buildbot # [ 74.713112] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command 15): startCommand:shell1685buildbot # [ 74.715271] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['rm', '-f', 'result-x86_64-linux.test']): RunProcess._startCommand1686buildbot # [ 74.718184] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['rm', '-f', 'result-x86_64-linux.test']): rm -f result-x86_64-linux.test1687buildbot # [ 74.721709] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['rm', '-f', 'result-x86_64-linux.test']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-build/build (timeout 1200 secs)1688buildbot # [ 74.728149] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['rm', '-f', 'result-x86_64-linux.test']): watching logfiles {}1689buildbot # [ 74.731157] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['rm', '-f', 'result-x86_64-linux.test']): argv: [b'rm', b'-f', b'result-x86_64-linux.test']1690buildbot # [ 74.734488] twistd[913]: 2026-07-05T06:01:23+0000 [Broker,client] (command ['rm', '-f', 'result-x86_64-linux.test']): using PTY: False1691buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1692buildbot # Dload Upload Total S[ 74.765210] twistd[913]: 2026-07-05T06:01:23+0000 [-] (command ['rm', '-f', 'result-x86_64-linux.test']): command finished with signal None, exit code 0, elapsedTime: 0.0370311693buildbot # [ 74.768689] twistd[913]: 2026-07-05T06:01:23+0000 [-] (command 15): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf80dd60>1694buildbot # pent Left Speed1695buildbot # 0 0 0 0 0 0 0 0 0[ 74.787574] twistd[912]: 2026-07-05T06:01:23+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['rm', '-f', 'result-x86_64-linux.test']'> rc=01696buildbot # 100 1486 100 1486 0 0 21841 0 0100 1486 100 1486 0 0 17886 0 0100 1486 100 1486 0 0 14968 0 [ 74.839118] twistd[912]: 2026-07-05T06:01:23+0000 [-] releaseLocks(ShellCommand(name='Delete temporary gcroots', command=['rm', '-f', Interpolate('result-%(prop:attr)s')], logEnviron=False)): []1697buildbot # 01698buildbot # [ 74.853814] twistd[912]: 2026-07-05T06:01:23+0000 [-] step 'Delete temporary gcroots' complete: success (None)1699buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.27 seconds)1700buildbot # [ 74.870653] twistd[912]: 2026-07-05T06:01:23+0000 [-] releaseLocks(UpdateBuildOutput(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0x740ac05f8d70>, name='Update build output', path=PosixPath('/var/lib/buildbot-outputs'), branch_config={})): []1701buildbot # [ 74.880783] twistd[912]: 2026-07-05T06:01:23+0000 [-] step 'Update build output' complete: success (None)1702buildbot # [ 74.883244] twistd[912]: 2026-07-05T06:01:23+0000 [-] <Build test-flake/nix-build number:1 results:success>: build finished1703buildbot # [ 74.894248] twistd[912]: 2026-07-05T06:01:24+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1704buildbot # [ 75.018183] twistd[912]: 2026-07-05T06:01:24+0000 [-] releaseLocks(BuildTrigger(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0x740ac05f8d70>, 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')): []1705buildbot # [ 75.050061] twistd[912]: 2026-07-05T06:01:24+0000 [-] step 'build flake' complete: failure (['(1 success, 1 failure, 1 skipped)'])1706buildbot # [ 75.067292] twistd[912]: 2026-07-05T06:01:24+0000 [-] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']'>: RemoteCommand.run [16]1707buildbot # [ 75.072638] twistd[912]: 2026-07-05T06:01:24+0000 [-] command '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']' in dir 'build'1708buildbot # [ 75.077850] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command 16): startCommand:shell1709buildbot # [ 75.080740] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']): RunProcess._startCommand1710buildbot # [ 75.086056] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']): nix-store --add-root /nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test -r /nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test1711buildbot # [ 75.096128] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1712buildbot # [ 75.102729] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']): watching logfiles {}1713buildbot # [ 75.107981] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']): argv: [b'nix-store', b'--add-root', b'/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', b'-r', b'/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']1714buildbot # [ 75.116252] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']): using PTY: False1715buildbot # [ 75.215093] nix-daemon[1185]: accepted connection from pid 1380, user buildbot-worker1716buildbot # [ 75.235646] twistd[913]: 2026-07-05T06:01:24+0000 [-] (command ['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']): command finished with signal None, exit code 0, elapsedTime: 0.1407511717buildbot # [ 75.241854] twistd[913]: 2026-07-05T06:01:24+0000 [-] (command 16): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf80e210>1718buildbot # [ 75.249092] twistd[912]: 2026-07-05T06:01:24+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['nix-store', '--add-root', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/x86_64-linux.skippable-test', '-r', '/nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test']'> rc=01719buildbot # [ 75.283728] twistd[912]: 2026-07-05T06:01:24+0000 [-] releaseLocks(ProcessSkippedBuilds(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0x740ac05f8d70>, 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 0x740ac0458ae0>, hideStepIf=<function nix_eval_config.<locals>.<lambda> at 0x740ac0458b80>)): []1720buildbot # [ 75.298492] twistd[912]: 2026-07-05T06:01:24+0000 [-] step 'Process skipped builds' complete: success (None)1721buildbot # [ 75.314100] twistd[912]: 2026-07-05T06:01:24+0000 [-] <RemoteShellCommand '['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']'>: RemoteCommand.run [17]1722buildbot # [ 75.317914] twistd[912]: 2026-07-05T06:01:24+0000 [-] command '['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']' in dir 'build'1723buildbot # [ 75.321354] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command 17): startCommand:shell1724buildbot # [ 75.323457] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): RunProcess._startCommand1725buildbot # [ 75.328060] twistd[913]: 2026-07-05T06:01:24+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/1726buildbot # [ 75.332980] twistd[913]: 2026-07-05T06:01:24+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)1727buildbot # [ 75.337945] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): watching logfiles {}1728buildbot # [ 75.342044] twistd[913]: 2026-07-05T06:01:24+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/']1729buildbot # [ 75.348780] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['rm', '-rf', '/nix/var/nix/gcroots/per-user/buildbot-worker/test-flake/drvs/local-worker-000/']): using PTY: False1730buildbot # [ 75.366124] twistd[913]: 2026-07-05T06:01:24+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.0182611731buildbot # [ 75.370839] twistd[913]: 2026-07-05T06:01:24+0000 [-] (command 17): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf81def0>1732buildbot # [ 75.397104] twistd[912]: 2026-07-05T06:01:24+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=01733buildbot # [ 75.425922] twistd[912]: 2026-07-05T06:01:24+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)): []1734buildbot # [ 75.437629] twistd[912]: 2026-07-05T06:01:24+0000 [-] step 'Cleanup drv paths' complete: success (None)1735buildbot # [ 75.448867] twistd[912]: 2026-07-05T06:01:24+0000 [-] releaseLocks(BuildbotEffectsCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0x740ac05f8d70>, 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 0x740ac0459300>, logEnviron=False)): []1736buildbot # [ 75.461956] twistd[912]: 2026-07-05T06:01:24+0000 [-] step 'Evaluate effects' complete: skipped (None)1737buildbot # [ 75.475844] twistd[912]: 2026-07-05T06:01:24+0000 [-] <RemoteShellCommand '['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']'>: RemoteCommand.run [18]1738buildbot # [ 75.480961] twistd[912]: 2026-07-05T06:01:24+0000 [-] command '['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']' in dir 'build'1739buildbot # [ 75.488849] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command 18): startCommand:shell1740buildbot # [ 75.490952] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']): RunProcess._startCommand1741buildbot # [ 75.495492] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']): buildbot-effects list-schedules --rev bf5d2294f0f6341eabdf26b359e73311d219f894 --branch master --repo test-flake1742buildbot # [ 75.501463] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']): in dir /var/lib/buildbot-worker/worker-000/test-flake_nix-eval/build (timeout 1200 secs)1743buildbot # [ 75.508154] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']): watching logfiles {}1744buildbot # [ 75.512652] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']): argv: [b'buildbot-effects', b'list-schedules', b'--rev', b'bf5d2294f0f6341eabdf26b359e73311d219f894', b'--branch', b'master', b'--repo', b'test-flake']1745buildbot # [ 75.519476] twistd[913]: 2026-07-05T06:01:24+0000 [Broker,client] (command ['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']): using PTY: False1746buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1747buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1748buildbot # Dload Upload Total Spent Left Speed1749buildbot # 0 0 0 0 0 0 0 0 0100 1521 100 1521 0 0 [ 75.976660] nix-daemon[1185]: accepted connection from pid 1389, user buildbot-worker1750buildbot # 29265 0 0100 1521 100 1521 0 0 21006 0 0100 1521 100 1521 0 0 17100 0 01751buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.16 seconds)1752buildbot # [ 76.035118] twistd[913]: 2026-07-05T06:01:25+0000 [-] (command ['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']): command finished with signal None, exit code 0, elapsedTime: 0.5467851753buildbot # [ 76.040309] twistd[913]: 2026-07-05T06:01:25+0000 [-] (command 18): ProtocolCommandBase.command_complete (success) <buildbot_worker.commands.shell.WorkerShellCommand object at 0x7037cf81e270>1754buildbot # [ 76.046704] twistd[912]: 2026-07-05T06:01:25+0000 [Broker,0,127.0.0.1] <RemoteShellCommand '['buildbot-effects', 'list-schedules', '--rev', 'bf5d2294f0f6341eabdf26b359e73311d219f894', '--branch', 'master', '--repo', 'test-flake']'> rc=01755buildbot # [ 76.086747] twistd[912]: 2026-07-05T06:01:25+0000 [-] releaseLocks(ScheduledEffectsEvaluateCommand(project=<buildbot_nix.pull_based.project.PullBasedProject object at 0x740ac05f8d70>, 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 0x740ac04594e0>, hideStepIf=<function nix_eval_config.<locals>.<lambda> at 0x740ac0459580>, logEnviron=False)): []1756buildbot # [ 76.104871] twistd[912]: 2026-07-05T06:01:25+0000 [-] step 'Evaluate scheduled effects' complete: success (None)1757buildbot # [ 76.107473] twistd[912]: 2026-07-05T06:01:25+0000 [-] <Build test-flake/nix-eval number:1 results:failure>: build finished1758buildbot # [ 76.118977] twistd[912]: 2026-07-05T06:01:25+0000 [-] releaseLocks(<Worker 'local-worker-000'>): []1759buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds1760buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1761buildbot # Dload Upload Total Spent Left Speed1762buildbot # 0 0 0 0 0 0 0 0 0100 1530 100 1530 0 0 37546 0 0100 1530 100 1530 0 0 30140 0 0100 1530 100 1530 0 0 25166 0 01763buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds, in 0.15 seconds)1764buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/2/properties1765buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1766buildbot # Dload Upload Total Spent Left Speed1767buildbot # 0 0 0 0 0 0 0 0 0100 2168 100 2168 0 0 61590 0 0100 2168 100 2168 0 0 47928 0 0100 2168 100 2168 0 0 39220 0 01768buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/2/properties, in 0.11 seconds)1769buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/4/properties1770buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1771buildbot # Dload Upload Total Spent Left Speed1772buildbot # 0 0 0 0 0 0 0 0 0100 1547 100 1547 0 0 43125 0 0100 1547 100 1547 0 0 33565 0 0100 1547 100 1547 0 0 27535 0 01773buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/4/properties, in 0.12 seconds)1774buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/3/properties1775buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1776buildbot # Dload Upload Total Spent Left Speed1777buildbot # 0 0 0 0 0 0 0 0 0100 2128 100 2128 0 0 59163 0 0100 2128 100 2128 0 0 46048 0 0100 2128 100 2128 0 0 37901 0 01778buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/3/properties, in 0.12 seconds)1779buildbot: must succeed: curl --fail http://localhost:8010/api/v2/builds/1/properties1780buildbot # % Total % Received % Xferd Average Speed Time Time Time Current1781buildbot # Dload Upload Total Spent Left Speed1782buildbot # 0 0 0 0 0 0 0 0 0100 2482 100 2482 0 0 70263 0 0100 2482 100 2482 0 0 54472 0 0100 2482 100 2482 0 0 44661 0 01783buildbot: (finished: must succeed: curl --fail http://localhost:8010/api/v2/builds/1/properties, in 0.12 seconds)1784(finished: subtest: Poller triggers builds and verifies results, in 44.75 seconds)1785subtest: Verify output paths are written for skipped builds1786buildbot: must succeed: nix config show system1787buildbot: (finished: must succeed: nix config show system, in 0.11 seconds)1788buildbot: must succeed: 1789 nix eval --raw /tmp/test-flake#checks.x86_64-linux.skippable-test.outPath1790 1791buildbot: (finished: must succeed: 1792 nix eval --raw /tmp/test-flake#checks.x86_64-linux.skippable-test.outPath1793 , in 0.13 seconds)1794Expected output path for skippable-test: /nix/store/g7z5c3gxrlml48hiz84na7nnrsfdkx6w-skippable-test1795buildbot: waiting for success: test -f /var/lib/buildbot-outputs/unknown/test-flake/master/x86_64-linux.skippable-test1796buildbot: (finished: waiting for success: test -f /var/lib/buildbot-outputs/unknown/test-flake/master/x86_64-linux.skippable-test, in 0.03 seconds)1797buildbot: must succeed: cat /var/lib/buildbot-outputs/unknown/test-flake/master/x86_64-linux.skippable-test1798buildbot: (finished: must succeed: cat /var/lib/buildbot-outputs/unknown/test-flake/master/x86_64-linux.skippable-test, in 0.03 seconds)1799(finished: subtest: Verify output paths are written for skipped builds, in 0.29 seconds)1800(finished: run the VM test script, in 79.04 seconds)1801test script finished in 79.08s1802cleanup1803kill QemuMachine (pid 12)1804buildbot # qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/l9k0anq0z7zz81zcwy035jfwap9ga6rl-python3-3.13.13/bin/python3.13)1805(finished: cleanup, in 0.22 seconds)