vm-test-run-gitea-mq-integration-gitea
checks.x86_64-linux.nixos-test
· build #86
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit postgresql.service8machine: waiting for the VM to finish booting9machine: starting vm10machine: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.ZplK1q6mUY', fmt=raw size=107374182413machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: f8a2453b-7599-412b-aa07-8bd01a07fd4217machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)27machine # 28machine # 29machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0030machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...31machine # 32machine # 33machine # 34machine # 35machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0036machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)...37machine # 38machine # 39machine # Booting from ROM...40machine # Probing EDD (edd=off to disable)... ok41machine # [ 0.000000] Linux version 6.18.45 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Aug 19 16:18:21 UTC 202642machine # [ 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/7g9hzcz71g43f6pa86w1gvw7ys8h11jb-nixos-system-machine-test/init regInfo=/nix/store/7b7jdij4av4wvd6s272wl6812fzpr7xj-closure-info/registration console=ttyS0,115200n8 console=tty043machine # [ 0.000000] BIOS-provided physical RAM map:44machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable45machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved46machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved47machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable48machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved49machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved50machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved51machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved52machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved53machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved54machine # [ 0.000000] NX (Execute Disable) protection: active55machine # [ 0.000000] APIC: Static calls initialized56machine # [ 0.000000] SMBIOS 2.8 present.57machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201458machine # [ 0.000000] DMI: Memory slots populated: 1/159machine # [ 0.000000] Hypervisor detected: KVM60machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000061machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0062machine # [ 0.000000] kvm-clock: using sched offset of 550605557 cycles63machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns64machine # [ 0.000005] tsc: Detected 2400.010 MHz processor65machine # [ 0.000809] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000066machine # [ 0.000835] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs67machine # [ 0.000838] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT68machine # [ 0.002732] found SMP MP-table at [mem 0x000f5450-0x000f545f]69machine # [ 0.002743] Using GB pages for direct mapping70machine # [ 0.002795] RAMDISK: [mem 0x3e34d000-0x3ffcffff]71machine # [ 0.002802] ACPI: Early table checksum verification disabled72machine # [ 0.002805] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )73machine # [ 0.002808] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)74machine # [ 0.002812] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)75machine # [ 0.002819] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)76machine # [ 0.002821] ACPI: FACS 0x000000003FFE0000 00004077machine # [ 0.002822] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002824] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002825] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002827] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)81machine # [ 0.002828] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]82machine # [ 0.002829] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]83machine # [ 0.002830] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]84machine # [ 0.002830] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]85machine # [ 0.002831] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]86machine # [ 0.002831] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]87machine # [ 0.002832] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]88machine # [ 0.003049] No NUMA configuration found89machine # [ 0.003050] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]90machine # [ 0.003052] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]91machine # [ 0.005298] Zone ranges:92machine # [ 0.005298] DMA [mem 0x0000000000001000-0x0000000000ffffff]93machine # [ 0.005300] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]94machine # [ 0.005301] Normal empty95machine # [ 0.005302] Device empty96machine # [ 0.005302] Movable zone start for each node97machine # [ 0.005303] Early memory node ranges98machine # [ 0.005303] node 0: [mem 0x0000000000001000-0x000000000009efff]99machine # [ 0.005304] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]100machine # [ 0.005305] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]101machine # [ 0.005324] On node 0, zone DMA: 1 pages in unavailable ranges102machine # [ 0.005592] On node 0, zone DMA: 97 pages in unavailable ranges103machine # [ 0.023317] On node 0, zone DMA32: 40 pages in unavailable ranges104machine # [ 0.023768] ACPI: PM-Timer IO Port: 0x608105machine # [ 0.023779] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])106machine # [ 0.023805] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23107machine # [ 0.023807] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)108machine # [ 0.023809] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)109machine # [ 0.023810] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)110machine # [ 0.023811] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)111machine # [ 0.023812] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)112machine # [ 0.023814] ACPI: Using ACPI (MADT) for SMP configuration information113machine # [ 0.023815] ACPI: HPET id: 0x8086a201 base: 0xfed00000114machine # [ 0.023818] TSC deadline timer available115machine # [ 0.023822] CPU topo: Max. logical packages: 1116machine # [ 0.023823] CPU topo: Max. logical dies: 1117machine # [ 0.023823] CPU topo: Max. dies per package: 1118machine # [ 0.023826] CPU topo: Max. threads per core: 1119machine # [ 0.023827] CPU topo: Num. cores per package: 1120machine # [ 0.023827] CPU topo: Num. threads per package: 1121machine # [ 0.023828] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs122machine # [ 0.023844] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()123machine # [ 0.023874] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]124machine # [ 0.023875] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]125machine # [ 0.023876] [mem 0x40000000-0xafffffff] available for PCI devices126machine # [ 0.023877] Booting paravirtualized kernel on KVM127machine # [ 0.023880] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns128machine # [ 0.028313] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1129machine # [ 0.030460] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152130machine # [ 0.030502] kvm-guest: PV spinlocks disabled, single CPU131machine # [ 0.030504] 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/7g9hzcz71g43f6pa86w1gvw7ys8h11jb-nixos-system-machine-test/init regInfo=/nix/store/7b7jdij4av4wvd6s272wl6812fzpr7xj-closure-info/registration console=ttyS0,115200n8 console=tty0132machine # [ 0.030604] Unknown kernel command line parameters "regInfo=/nix/store/7b7jdij4av4wvd6s272wl6812fzpr7xj-closure-info/registration", will be passed to user space.133machine # [ 0.030616] random: crng init done134machine # [ 0.030617] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes135machine # [ 0.031916] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)136machine # [ 0.031928] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)137machine # [ 0.031956] Fallback order for Node 0: 0138machine # [ 0.031959] Built 1 zonelists, mobility grouping on. Total pages: 262006139machine # [ 0.031960] Policy zone: DMA32140machine # [ 0.034090] mem auto-init: stack:all(zero), heap alloc:on, heap free:off141machine # [ 0.036467] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1142machine # [ 0.038795] allocated 2097152 bytes of page_ext143machine # [ 0.048548] ftrace: allocating 48728 entries in 192 pages144machine # [ 0.048550] ftrace: allocated 192 pages with 2 groups145machine # [ 0.049367] Dynamic Preempt: lazy146machine # [ 0.049535] rcu: Preemptible hierarchical RCU implementation.147machine # [ 0.049536] rcu: RCU event tracing is enabled.148machine # [ 0.049536] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.149machine # [ 0.049538] Trampoline variant of Tasks RCU enabled.150machine # [ 0.049538] Rude variant of Tasks RCU enabled.151machine # [ 0.049539] Tracing variant of Tasks RCU enabled.152machine # [ 0.049539] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.153machine # [ 0.049540] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1154machine # [ 0.049560] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155machine # [ 0.049562] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156machine # [ 0.049563] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.157machine # [ 0.053905] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16158machine # [ 0.054186] rcu: srcu_init: Setting srcu_struct sizes based on contention.159machine # [ 0.054192] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns160machine # [ 0.054291] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)161machine # [ 0.057795] Console: colour VGA+ 80x25162machine # [ 0.057798] printk: legacy console [tty0] enabled163machine # [ 0.086721] printk: legacy console [ttyS0] enabled164machine # [ 0.194035] ACPI: Core revision 20250807165machine # [ 0.194923] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns166machine # [ 0.196526] APIC: Switch to symmetric I/O mode setup167machine # [ 0.197559] x2apic enabled168machine # [ 0.198325] APIC: Switched APIC routing to: physical x2apic169machine # [ 0.200177] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1170machine # [ 0.201236] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns171machine # [ 0.202977] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)172machine # [ 0.204060] x86/cpu: User Mode Instruction Prevention (UMIP) activated173machine # [ 0.206156] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127174machine # [ 0.206973] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0175machine # [ 0.207978] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto176machine # [ 0.208975] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl177machine # [ 0.209975] Transient Scheduler Attacks: Vulnerable: No microcode178machine # [ 0.210974] Spectre V2 : Mitigation: Enhanced / Automatic IBRS179machine # [ 0.211975] Speculative Return Stack Overflow: Mitigation: Safe RET180machine # [ 0.212974] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization181machine # [ 0.214981] Spectre V2 : Enabling IBPB for BPF182machine # [ 0.215975] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier183machine # [ 0.216975] active return thunk: srso_alias_return_thunk184machine # [ 0.217896] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'185machine # [ 0.218974] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'186machine # [ 0.219975] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'187machine # [ 0.220975] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'188machine # [ 0.221974] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'189machine # [ 0.222974] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'190machine # [ 0.223974] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'191machine # [ 0.225973] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256192machine # [ 0.226787] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64193machine # [ 0.227974] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512194machine # [ 0.228973] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024195machine # [ 0.229974] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8196machine # [ 0.230974] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.197machine # [ 0.265764] Freeing SMP alternatives memory: 44K198machine # [ 0.266571] pid_max: default: 32768 minimum: 301199machine # [ 0.267064] LSM: initializing lsm=capability,landlock,yama,bpf,ima200machine # [ 0.268075] landlock: Up and running.201machine # [ 0.268975] Yama: becoming mindful.202machine # [ 0.270187] LSM support for eBPF active203machine # [ 0.270933] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)204machine # [ 0.271995] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)205machine # [ 0.274548] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)206machine # [ 0.275489] Performance Events: Fam17h+ core perfctr, AMD PMU driver.207machine # [ 0.275979] ... version: 2208machine # [ 0.276747] ... bit width: 48209machine # [ 0.276977] ... generic counters: 6210machine # [ 0.277760] ... generic bitmap: 000000000000003f211machine # [ 0.278025] ... fixed-purpose counters: 0212machine # [ 0.278752] ... fixed-purpose bitmap: 0000000000000000213machine # [ 0.279683] ... value mask: 0000ffffffffffff214machine # [ 0.279977] ... max period: 00007fffffffffff215machine # [ 0.280922] ... global_ctrl mask: 000000000000003f216machine # [ 0.281871] signal: max sigframe size: 3376217machine # [ 0.282920] rcu: Hierarchical SRCU implementation.218machine # [ 0.283582] rcu: Max phase no-delay instances is 400.219machine # [ 0.288465] smp: Bringing up secondary CPUs ...220machine # [ 0.288991] smp: Brought up 1 node, 1 CPU221machine # [ 0.289685] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)222machine # [ 0.290134] Memory: 940924K/1048024K available (17215K kernel code, 2726K rwdata, 13580K rodata, 3644K init, 2988K bss, 99720K reserved, 0K cma-reserved)223machine # [ 0.291192] devtmpfs: initialized224machine # [ 0.292005] x86/mm: Memory block size: 128MB225machine # [ 0.293655] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)226machine # [ 0.294931] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).227machine # [ 0.296054] pinctrl core: initialized pinctrl subsystem228machine # [ 0.297245] PM: RTC time: 03:42:57, date: 2026-09-03229machine # [ 0.300696] NET: Registered PF_NETLINK/PF_ROUTE protocol family230machine # [ 0.301338] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations231machine # [ 0.301994] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations232machine # [ 0.303126] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations233machine # [ 0.303986] audit: initializing netlink subsys (disabled)234machine # [ 0.304979] audit: type=2000 audit(1788406977.857:1): state=initialized audit_enabled=0 res=1235machine # [ 0.306189] thermal_sys: Registered thermal governor 'fair_share'236machine # [ 0.306191] thermal_sys: Registered thermal governor 'bang_bang'237machine # [ 0.306978] thermal_sys: Registered thermal governor 'step_wise'238machine # [ 0.307978] thermal_sys: Registered thermal governor 'user_space'239machine # [ 0.308977] thermal_sys: Registered thermal governor 'power_allocator'240machine # [ 0.309990] cpuidle: using governor menu241machine # [ 0.312231] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5242machine # [ 0.313216] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]243machine # [ 0.313980] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry244machine # [ 0.314988] PCI: Using configuration type 1 for base access245machine # [ 0.316135] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.246machine # [ 0.321264] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages247machine # [ 0.321979] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page248machine # [ 0.326978] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages249machine # [ 0.327977] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page250machine # [ 0.338389] ACPI: Added _OSI(Module Device)251machine # [ 0.338979] ACPI: Added _OSI(Processor Device)252machine # [ 0.339717] ACPI: Added _OSI(Processor Aggregator Device)253machine # [ 0.348535] ACPI: 1 ACPI AML tables successfully acquired and loaded254machine # [ 0.352377] ACPI: Interpreter enabled255machine # [ 0.352993] ACPI: PM: (supports S0 S3 S4 S5)256machine # [ 0.353741] ACPI: Using IOAPIC for interrupt routing257machine # [ 0.354051] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug258machine # [ 0.354977] PCI: Using E820 reservations for host bridge windows259machine # [ 0.358114] ACPI: Enabled 2 GPEs in block 00 to 3F260machine # [ 0.366698] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])261machine # [ 0.366984] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]262machine # [ 0.368858] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]263machine # [ 0.370041] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]264machine # [ 0.371335] PCI host bridge to bus 0000:00265machine # [ 0.371982] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]266machine # [ 0.372977] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]267machine # [ 0.373977] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]268machine # [ 0.374977] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]269machine # [ 0.375977] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]270machine # [ 0.376977] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]271machine # [ 0.377977] pci_bus 0000:00: root bus resource [bus 00-ff]272machine # [ 0.378953] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint273machine # [ 0.380414] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint274machine # [ 0.383078] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]275machine # [ 0.383989] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]276machine # [ 0.385001] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]277machine # [ 0.386282] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]278machine # [ 0.387666] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint279machine # [ 0.390010] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]280machine # [ 0.390903] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]281machine # [ 0.391763] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]282machine # [ 0.392850] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]283machine # [ 0.394520] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint284machine # [ 0.396008] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]285machine # [ 0.396946] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]286machine # [ 0.397769] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]287machine # [ 0.399434] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint288machine # [ 0.402010] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]289machine # [ 0.402902] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]290machine # [ 0.403779] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]291machine # [ 0.405462] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint292machine # [ 0.407826] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]293machine # [ 0.408661] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]294machine # [ 0.408999] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]295machine # [ 0.410536] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint296machine # [ 0.411984] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]297machine # [ 0.412897] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]298machine # [ 0.413788] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]299machine # [ 0.415466] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint300machine # [ 0.418014] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]301machine # [ 0.418929] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]302machine # [ 0.419777] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]303machine # [ 0.421506] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint304machine # [ 0.422985] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]305machine # [ 0.423871] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]306machine # [ 0.424780] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]307machine # [ 0.425983] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]308machine # [ 0.427539] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint309machine # [ 0.428990] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]310machine # [ 0.429976] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]311machine # [ 0.431539] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint312machine # [ 0.433910] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]313machine # [ 0.434677] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]314machine # [ 0.434999] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]315machine # [ 0.436546] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint316machine # [ 0.438009] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]317machine # [ 0.438943] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]318machine # [ 0.439786] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]319machine # [ 0.442090] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint320machine # [ 0.443692] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]321machine # [ 0.444173] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint322machine # [ 0.445571] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]323machine # [ 0.446173] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint324machine # [ 0.448642] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]325machine # [ 0.449249] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint326machine # [ 0.451556] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]327machine # [ 0.452257] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint328machine # [ 0.453267] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO329machine # [ 0.454231] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint330machine # [ 0.456866] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]331machine # [ 0.457684] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]332machine # [ 0.458315] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint333machine # [ 0.459768] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]334machine # [ 0.462994] ACPI: PCI: Interrupt link LNKA configured for IRQ 10335machine # [ 0.464084] ACPI: PCI: Interrupt link LNKB configured for IRQ 10336machine # [ 0.465077] ACPI: PCI: Interrupt link LNKC configured for IRQ 11337machine # [ 0.466077] ACPI: PCI: Interrupt link LNKD configured for IRQ 11338machine # [ 0.467074] ACPI: PCI: Interrupt link LNKE configured for IRQ 10339machine # [ 0.468075] ACPI: PCI: Interrupt link LNKF configured for IRQ 10340machine # [ 0.469083] ACPI: PCI: Interrupt link LNKG configured for IRQ 11341machine # [ 0.470092] ACPI: PCI: Interrupt link LNKH configured for IRQ 11342machine # [ 0.471015] ACPI: PCI: Interrupt link GSIA configured for IRQ 16343machine # [ 0.471990] ACPI: PCI: Interrupt link GSIB configured for IRQ 17344machine # [ 0.472993] ACPI: PCI: Interrupt link GSIC configured for IRQ 18345machine # [ 0.473991] ACPI: PCI: Interrupt link GSID configured for IRQ 19346machine # [ 0.474989] ACPI: PCI: Interrupt link GSIE configured for IRQ 20347machine # [ 0.475992] ACPI: PCI: Interrupt link GSIF configured for IRQ 21348machine # [ 0.477001] ACPI: PCI: Interrupt link GSIG configured for IRQ 22349machine # [ 0.477989] ACPI: PCI: Interrupt link GSIH configured for IRQ 23350machine # [ 0.480018] iommu: Default domain type: Translated351machine # [ 0.480879] iommu: DMA domain TLB invalidation policy: lazy mode352machine # [ 0.482049] ACPI: bus type USB registered353machine # [ 0.482838] usbcore: registered new interface driver usbfs354machine # [ 0.483724] usbcore: registered new interface driver hub355machine # [ 0.483994] usbcore: registered new device driver usb356machine # [ 0.485940] NetLabel: Initializing357machine # [ 0.486492] NetLabel: domain hash size = 128358machine # [ 0.486977] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO359machine # [ 0.487995] NetLabel: unlabeled traffic allowed by default360machine # [ 0.488914] PCI: Using ACPI for IRQ routing361machine # [ 0.532481] pci 0000:00:01.0: vgaarb: setting as boot VGA device362machine # [ 0.532973] pci 0000:00:01.0: vgaarb: bridge control possible363machine # [ 0.532973] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none364machine # [ 0.532981] vgaarb: loaded365machine # [ 0.533700] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0366machine # [ 0.533978] hpet0: 3 comparators, 64-bit 100.000000 MHz counter367machine # [ 0.538058] clocksource: Switched to clocksource kvm-clock368machine # [ 0.539760] VFS: Disk quotas dquot_6.6.0369machine # [ 0.542298] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)370machine # [ 0.543708] pnp: PnP ACPI init371machine # [ 0.544540] ACPI: IRQ 4 override to edge(!), high(!)372machine # [ 0.545557] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved373machine # [ 0.547182] pnp: PnP ACPI: found 6 devices374machine # [ 0.554818] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns375machine # [ 0.556340] clocksource: Switched to clocksource acpi_pm376machine # [ 0.557346] NET: Registered PF_INET protocol family377machine # [ 0.558412] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)378machine # [ 0.575226] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)379machine # [ 0.576768] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)380machine # [ 0.578175] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)381machine # [ 0.579523] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)382machine # [ 0.580811] TCP: Hash tables configured (established 8192 bind 8192)383machine # [ 0.581940] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)384machine # [ 0.583254] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)385machine # [ 0.584403] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)386machine # [ 0.585650] NET: Registered PF_UNIX/PF_LOCAL protocol family387machine # [ 0.586668] NET: Registered PF_XDP protocol family388machine # [ 0.587560] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]389machine # [ 0.588615] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]390machine # [ 0.589659] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]391machine # [ 0.590821] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]392machine # [ 0.591976] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]393machine # [ 0.593101] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]394machine # [ 0.594993] ACPI: \_SB_.GSIA: Enabled at IRQ 16395machine # [ 0.597016] ACPI: \_SB_.GSIB: Enabled at IRQ 17396machine # [ 0.599013] ACPI: \_SB_.GSIC: Enabled at IRQ 18397machine # [ 0.601023] ACPI: \_SB_.GSID: Enabled at IRQ 19398machine # [ 0.602707] PCI: CLS 0 bytes, default 64399machine # [ 0.603670] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns400machine # [ 0.605510] Trying to unpack rootfs image as initramfs...401machine # [ 0.650536] Initialise system trusted keyrings402machine # [ 0.651509] workingset: timestamp_bits=40 max_order=18 bucket_order=0403machine # [ 0.675283] Key type asymmetric registered404machine # [ 0.676022] Asymmetric key parser 'x509' registered405machine # [ 0.678849] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)406machine # [ 0.682840] io scheduler mq-deadline registered407machine # [ 0.683654] io scheduler kyber registered408machine # [ 0.686959] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled409machine # [ 0.688285] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A410machine # [ 0.693564] Linux agpgart interface v0.103411machine # [ 0.694392] ACPI: bus type drm_connector registered412machine # [ 0.696946] usbcore: registered new interface driver usbserial_generic413machine # [ 0.698056] usbserial: USB Serial support registered for generic414machine # [ 0.700816] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.415machine # [ 0.700816] Please enable it if your BIOS has the CPPC option.416machine # [ 0.703077] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled417machine # [ 0.707940] drop_monitor: Initializing network drop monitor service418machine # [ 0.709202] NET: Registered PF_INET6 protocol family419machine # [ 0.713308] Segment Routing with IPv6420machine # [ 0.713995] In-situ OAM (IOAM) with IPv6421machine # [ 0.717088] IPI shorthand broadcast: enabled422machine # [ 0.725323] sched_clock: Marking stable (581014540, 143781240)->(804186389, -79390609)423machine # [ 0.728891] registered taskstats version 1424machine # [ 0.729888] Loading compiled-in X.509 certificates425machine # [ 0.747991] Demotion targets for Node 0: null426machine # [ 0.748910] Key type .fscrypt registered427machine # [ 0.749622] Key type fscrypt-provisioning registered428machine # [ 0.752906] ima: No TPM chip found, activating TPM-bypass!429machine # [ 0.753862] ima: Allocated hash algorithm: sha1430machine # [ 0.754685] ima: No architecture policies found431machine # [ 0.756987] PM: Magic number: 10:243:714432machine # [ 0.758600] RAS: Correctable Errors collector initialized.433machine # [ 0.766452] clk: Disabling unused clocks434machine # [ 0.770807] PM: genpd: Disabling unused power domains435machine # [ 0.930591] Freeing initrd memory: 29196K436machine # [ 0.933749] Freeing unused decrypted memory: 2028K437machine # [ 0.936404] Freeing unused kernel image (initmem) memory: 3644K438machine # [ 0.937512] Write protecting the kernel read-only data: 32768k439machine # [ 0.939423] Freeing unused kernel image (text/rodata gap) memory: 1216K440machine # [ 0.941007] Freeing unused kernel image (rodata/data gap) memory: 756K441machine # [ 0.991952] x86/mm: Checked W+X mappings: passed, no W+X pages found.442machine # [ 0.993049] Run /init as init process443machine # [ 1.003136] systemd[1]: Inserted module 'autofs4'444machine # [ 1.019468] fuse: init (API version 7.45)445machine # [ 1.025303] ACPI: \_SB_.GSIG: Enabled at IRQ 22446machine # [ 1.027682] ACPI: \_SB_.GSIH: Enabled at IRQ 23447machine # [ 1.030626] ACPI: \_SB_.GSIE: Enabled at IRQ 20448machine # [ 1.032912] ACPI: \_SB_.GSIF: Enabled at IRQ 21449machine # [ 1.057442] systemd[1]: Successfully made /usr/ read-only.450machine # [ 1.392920] systemd[1]: systemd 261.1 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)451machine # [ 1.398030] systemd[1]: Detected virtualization kvm.452machine # [ 1.398883] systemd[1]: Detected architecture x86-64.453machine # [ 1.399774] systemd[1]: Running in initrd.454machine # [ 1.400916] systemd[1]: Initializing machine ID from random generator.455machine # [ 1.402135] systemd[1]: Hostname set to <machine>.456machine # [ 1.606250] systemd[1]: bpf-restrict-fs: LSM BPF program attached457machine # [ 1.657951] systemd[1]: Queued start job for default target Initrd Default Target.458machine # [ 1.661065] systemd[1]: Created slice Slice /system/modprobe.459machine # [ 1.662235] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.460machine # [ 1.663656] systemd[1]: Expecting device /dev/disk/by-label/nixos...461machine # [ 1.664808] systemd[1]: Reached target Path Units.462machine # [ 1.665703] systemd[1]: Reached target Slice Units.463machine # [ 1.666607] systemd[1]: Reached target Swaps.464machine # [ 1.667470] systemd[1]: Reached target Timer Units.465machine # [ 1.668465] systemd[1]: Listening on D-Bus System Message Bus Socket.466machine # [ 1.669717] systemd[1]: Listening on Journal Socket (/dev/log).467machine # [ 1.670928] systemd[1]: Listening on Journal Sockets.468machine # [ 1.671953] systemd[1]: Listening on udev Control Socket.469machine # [ 1.672997] systemd[1]: Listening on udev Kernel Socket.470machine # [ 1.673950] systemd[1]: Reached target Socket Units.471machine # [ 1.675712] systemd[1]: Starting Create List of Static Device Nodes...472machine # [ 1.679597] systemd[1]: Starting Load Kernel Module 9pnet_virtio...473machine # [ 1.686980] systemd[1]: Starting Load Kernel Module configfs...474machine # [ 1.699440] systemd[1]: Starting Journal Service...475machine # [ 1.710936] systemd[1]: Starting Load Kernel Modules...476machine # [ 1.718875] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os477machine # [ 1.736417] netfs: FS-Cache loaded478machine # [ 1.741094] 9pnet: Installing 9P2000 support479machine # [ 1.749573] systemd[1]: Starting Coldplug All udev Devices...480machine # [ 1.763333] systemd[1]: Finished Create List of Static Device Nodes.481machine # [ 1.769403] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.482machine # [ 1.776866] systemd[1]: Finished Load Kernel Module 9pnet_virtio.483machine # [ 1.783393] systemd-journald[67]: Collecting audit messages is disabled.484machine # [ 1.785021] systemd[1]: modprobe@configfs.service: Deactivated successfully.485machine # [ 1.790224] systemd[1]: Finished Load Kernel Module configfs.486machine # [ 1.796252] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config487machine # [ 1.798542] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.488machine # [ 1.807879] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...489machine # [ 1.809373] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev490machine # [ 1.838353] systemd[1]: Finished Load Kernel Modules.491machine # [ 1.843659] systemd[1]: Starting Apply Kernel Variables...492machine # [ 1.853356] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.493machine # [ 1.864472] systemd[1]: Starting Create Static Device Nodes in /dev...494machine # [ 1.883100] systemd[1]: Finished Apply Kernel Variables.495machine # [ 1.897944] systemd[1]: Started Journal Service.496machine # [ 1.755066] systemd-modules-load[68]: Inserted module 'dm_mod'497machine # [ 1.757288] systemd-modules-load[68]: Inserted module 'virtio_balloon'498machine # [ 1.758383] systemd-modules-load[68]: Inserted module 'virtio_gpu'499machine # [ 1.760261] systemd[1]: Finished Create Static Device Nodes in /dev.500machine # [ 1.763396] systemd[1]: Reached target Preparation for Local File Systems.501machine # [ 1.764519] systemd[1]: Reached target Local File Systems.502machine # [ 1.769089] systemd[1]: Starting Create System Files and Directories...503machine # [ 1.775784] systemd[1]: Starting Rule-based Manager for Device Events and Files...504machine # [ 1.802190] systemd[1]: Finished Create System Files and Directories.505machine # [ 1.828238] systemd-udevd[78]: Using default interface naming scheme 'v261'.506machine # [ 1.851555] systemd[1]: Started Rule-based Manager for Device Events and Files.507machine # [ 1.896140] systemd[1]: Finished Coldplug All udev Devices.508machine # [ 1.897063] systemd[1]: Reached target System Initialization.509machine # [ 1.897845] systemd[1]: Reached target Basic System.510machine # [ 2.224309] virtio_blk virtio5: 1/0/0 default/read/poll queues511machine # [ 2.228344] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12512machine # [ 2.249595] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)513machine # [ 2.252388] serio: i8042 KBD port at 0x60,0x64 irq 1514machine # [ 2.253072] serio: i8042 AUX port at 0x60,0x64 irq 12515machine # [ 2.272337] ehci-pci 0000:00:1d.7: EHCI Host Controller516machine # [ 2.273191] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1517machine # [ 2.275566] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000518machine # [ 2.281869] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00519machine # [ 2.284228] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18520machine # [ 2.285365] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1521machine # [ 2.288804] usb usb1: Product: EHCI Host Controller522machine # [ 2.289485] usb usb1: Manufacturer: Linux 6.18.45 ehci_hcd523machine # [ 2.291814] usb usb1: SerialNumber: 0000:00:1d.7524machine # [ 2.293825] hub 1-0:1.0: USB hub found525machine # [ 2.295302] hub 1-0:1.0: 6 ports detected526machine # [ 2.300880] uhci_hcd 0000:00:1d.0: UHCI Host Controller527machine # [ 2.301610] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2528machine # [ 2.307362] uhci_hcd 0000:00:1d.0: detected 2 ports529machine # [ 2.313918] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200530machine # [ 2.321944] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18531machine # [ 2.323071] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1532machine # [ 2.338825] usb usb2: Product: UHCI Host Controller533machine # [ 2.339529] usb usb2: Manufacturer: Linux 6.18.45 uhci_hcd534machine # [ 2.201871] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.535machine # [ 2.205448] systemd[1]: Starting Virtual Console Setup...536machine # [ 2.356539] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0537machine # [ 2.218508] (udev-worker)[88]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.538machine # [ 2.220979] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.539machine # [ 2.366312] SCSI subsystem initialized540machine # [ 2.371811] usb usb2: SerialNumber: 0000:00:1d.0541machine # [ 2.376898] hub 2-0:1.0: USB hub found542machine # [ 2.383937] hub 2-0:1.0: 2 ports detected543machine # [ 2.244375] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.544machine # [ 2.247940] systemd[1]: Finished Virtual Console Setup.545machine # [ 2.410321] uhci_hcd 0000:00:1d.1: UHCI Host Controller546machine # [ 2.411054] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3547machine # [ 2.417615] uhci_hcd 0000:00:1d.1: detected 2 ports548machine # [ 2.418708] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220549machine # [ 2.421360] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18550machine # [ 2.424062] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1551machine # [ 2.428874] usb usb3: Product: UHCI Host Controller552machine # [ 2.429568] usb usb3: Manufacturer: Linux 6.18.45 uhci_hcd553machine # [ 2.290940] systemd[1]: Found device /dev/disk/by-label/nixos.554machine # [ 2.292036] systemd[1]: Reached target Initrd Root Device.555machine # [ 2.437285] usb usb3: SerialNumber: 0000:00:1d.1556machine # [ 2.294508] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...557machine # [ 2.439992] hub 3-0:1.0: USB hub found558machine # [ 2.445108] hub 3-0:1.0: 2 ports detected559machine # [ 2.453918] uhci_hcd 0000:00:1d.2: UHCI Host Controller560machine # [ 2.454660] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4561machine # [ 2.458839] uhci_hcd 0000:00:1d.2: detected 2 ports562machine # [ 2.459628] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240563machine # [ 2.463875] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18564machine # [ 2.470961] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1565machine # [ 2.330363] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks566machine # [ 2.475241] usb usb4: Product: UHCI Host Controller567machine # [ 2.476840] usb usb4: Manufacturer: Linux 6.18.45 uhci_hcd568machine # [ 2.477632] usb usb4: SerialNumber: 0000:00:1d.2569machine # [ 2.337637] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.570machine # [ 2.485489] hub 4-0:1.0: USB hub found571machine # [ 2.486751] hub 4-0:1.0: 2 ports detected572machine # [ 2.490549] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode573machine # [ 2.496980] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)574machine # [ 2.503650] ahci 0000:00:1f.2: flags: 64bit ncq only575machine # [ 2.511360] scsi host0: ahci576machine # [ 2.516400] scsi host1: ahci577machine # [ 2.520480] scsi host2: ahci578machine # [ 2.524842] scsi host3: ahci579machine # [ 2.526546] scsi host4: ahci580machine # [ 2.530172] scsi host5: ahci581machine # [ 2.530751] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1582machine # [ 2.533644] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1583machine # [ 2.535813] usb 1-1: new high-speed USB device number 2 using ehci-pci584machine # [ 2.538854] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1585machine # [ 2.540995] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1586machine # [ 2.542186] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1587machine # [ 2.543369] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1588machine # [ 2.665127] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00589machine # [ 2.667965] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10590machine # [ 2.671075] usb 1-1: Product: QEMU USB Tablet591machine # [ 2.672945] usb 1-1: Manufacturer: QEMU592machine # [ 2.674551] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1593machine # [ 2.702926] hid: raw HID events driver (C) Jiri Kosina594machine # [ 2.615422] systemd[1]: Mounting /sysroot...595machine # [ 2.854608] ata1: SATA link down (SStatus 0 SControl 300)596machine # [ 2.857089] ata4: SATA link down (SStatus 0 SControl 300)597machine # [ 2.859565] ata5: SATA link down (SStatus 0 SControl 300)598machine # [ 2.861857] ata6: SATA link down (SStatus 0 SControl 300)599machine # [ 2.864070] ata2: SATA link down (SStatus 0 SControl 300)600machine # [ 2.866230] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)601machine # [ 2.868838] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100602machine # [ 2.870747] ata3.00: applying bridge limits603machine # [ 2.872653] ata3.00: configured for UDMA/100604machine # [ 2.875090] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5605machine # [ 2.940549] usbcore: registered new interface driver usbhid606machine # [ 2.944531] usbhid: USB HID core driver607machine # [ 2.966293] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2608machine # [ 2.973876] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0609machine # [ 2.978159] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray610machine # [ 2.988009] cdrom: Uniform CD-ROM driver Revision: 3.20611machine # [ 2.989865] EXT4-fs (vda): mounted filesystem f8a2453b-7599-412b-aa07-8bd01a07fd42 r/w with ordered data mode. Quota mode: none.612machine # [ 2.851192] systemd[1]: Mounted /sysroot.613machine # [ 2.853723] systemd[1]: Reached target Initrd Root File System.614machine # [ 2.857300] systemd[1]: Starting Mountpoints Configured in the Real Root...615machine # [ 2.870878] systemd-sysroot-fstab-check[140]: /sysroot should be mounted in the initrd, will request daemon-reload.616machine # [ 2.873626] systemd[1]: Reload requested from client PID 140 ('systemd-sysroot') (unit initrd-parse-etc.service)...617machine # [ 2.875961] systemd[1]: Reloading...618machine # [ 2.959231] systemd[1]: Reloading finished in 83 ms.619machine # [ 2.966636] systemd-sysroot-fstab-check[140]: Requesting initrd-fs.target/start/replace...620machine # [ 2.970200] systemd-sysroot-fstab-check[140]: Requesting swap.target/start/replace...621machine # [ 2.973359] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio622machine # [ 2.975354] systemd[1]: initrd-parse-etc.service: Deactivated successfully.623machine # [ 2.976665] systemd[1]: Finished Mountpoints Configured in the Real Root.624machine # [ 2.977695] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.625machine # [ 3.617112] systemd[1]: Mounting /sysroot/nix/.ro-store...626machine # [ 3.624180] systemd[1]: Mounting /sysroot/nix/.rw-store...627machine # [ 3.632311] systemd[1]: Mounting /sysroot/run...628machine # [ 3.641178] systemd[1]: Mounting /sysroot/tmp/shared...629machine # [ 3.652609] systemd[1]: Mounting /sysroot/tmp/xchg...630machine # [ 3.689672] systemd[1]: Mounted /sysroot/nix/.rw-store.631machine # [ 3.839916] 9p: Installing v9fs 9p2000 file system support632machine # [ 3.701656] systemd[1]: Mounted /sysroot/run.633machine # [ 3.704788] systemd[1]: Mounted /sysroot/nix/.ro-store.634machine # [ 3.705791] systemd[1]: Mounted /sysroot/tmp/shared.635machine # [ 3.706705] systemd[1]: Mounted /sysroot/tmp/xchg.636machine # [ 3.710616] systemd[1]: Starting rw-sysroot-nix-store.service...637machine # [ 3.720240] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.638machine # [ 3.721669] systemd[1]: Finished rw-sysroot-nix-store.service.639machine # [ 4.616702] systemd[1]: Mounting /sysroot/nix/store...640machine # [ 4.662680] systemd[1]: Mounted /sysroot/nix/store.641machine # [ 4.665604] systemd[1]: Reached target Initrd File Systems.642machine # [ 4.669548] systemd[1]: Starting Find NixOS closure...643machine # [ 4.675063] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...644machine # [ 4.697946] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.645machine # [ 4.699719] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.646machine # [ 4.707985] systemd[1]: Finished Find NixOS closure.647machine # [ 4.710122] systemd[1]: Reached target Initrd Default Target.648machine # [ 4.711178] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...649machine # [ 4.724039] systemd[1]: Stopped target Initrd Default Target.650machine # [ 4.725471] systemd[1]: Stopped target Basic System.651machine # [ 4.726353] systemd[1]: Stopped target Initrd Root Device.652machine # [ 4.728108] systemd[1]: Stopped target Path Units.653machine # [ 4.728859] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.654machine # [ 4.729881] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.655machine # [ 4.731083] systemd[1]: Stopped target Slice Units.656machine # [ 4.731819] systemd[1]: Stopped target Socket Units.657machine # [ 4.732520] systemd[1]: Stopped target System Initialization.658machine # [ 4.733661] systemd[1]: Stopped target Swaps.659machine # [ 4.734500] systemd[1]: Stopped target Timer Units.660machine # [ 4.735508] systemd[1]: dbus.socket: Deactivated successfully.661machine # [ 4.737075] systemd[1]: Closed D-Bus System Message Bus Socket.662machine # [ 4.737969] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.663machine # [ 4.738976] systemd[1]: Stopped Find NixOS closure.664machine # [ 4.739743] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio665machine # [ 4.741864] systemd[1]: Starting rw-sysroot-nix-store.service...666machine # [ 4.743128] systemd[1]: systemd-sysctl.service: Deactivated successfully.667machine # [ 4.744190] systemd[1]: Stopped Apply Kernel Variables.668machine # [ 4.745283] systemd[1]: systemd-modules-load.service: Deactivated successfully.669machine # [ 4.746411] systemd[1]: Stopped Load Kernel Modules.670machine # [ 4.748306] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.671machine # [ 4.749662] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.672machine # [ 4.750929] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.673machine # [ 4.752113] systemd[1]: Stopped Create System Files and Directories.674machine # [ 4.753251] systemd[1]: Stopped target Local File Systems.675machine # [ 4.754181] systemd[1]: Stopped target Preparation for Local File Systems.676machine # [ 4.756111] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.677machine # [ 4.757124] systemd[1]: Stopped Coldplug All udev Devices.678machine # [ 4.758166] systemd[1]: Stopping Rule-based Manager for Device Events and Files...679machine # [ 4.760104] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.680machine # [ 4.761152] systemd[1]: Stopped Virtual Console Setup.681machine # [ 4.769421] systemd[1]: initrd-cleanup.service: Deactivated successfully.682machine # [ 4.771971] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.683machine # [ 4.778096] systemd[1]: systemd-udevd.service: Deactivated successfully.684machine # [ 4.780086] systemd[1]: Stopped Rule-based Manager for Device Events and Files.685machine # [ 4.782149] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.686machine # [ 4.783588] systemd[1]: Finished rw-sysroot-nix-store.service.687machine # [ 4.784865] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.688machine # [ 4.786286] systemd[1]: Closed udev Control Socket.689machine # [ 4.787854] systemd[1]: Starting Cleanup udev Database...690machine # [ 4.789121] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.691machine # [ 4.790251] systemd[1]: Stopped Create Static Device Nodes in /dev.692machine # [ 4.791128] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.693machine # [ 4.793123] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.694machine # [ 4.794089] systemd[1]: kmod-static-nodes.service: Deactivated successfully.695machine # [ 4.795051] systemd[1]: Stopped Create List of Static Device Nodes.696machine # [ 4.808224] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.697machine # [ 4.809718] systemd[1]: Finished Cleanup udev Database.698machine # [ 4.811052] systemd[1]: Reached target Switch Root.699machine # [ 4.812811] systemd[1]: Starting NixOS Activation...700machine # [ 4.973386] initrd-nixos-activation-start[193]: booting system configuration /nix/store/7g9hzcz71g43f6pa86w1gvw7ys8h11jb-nixos-system-machine-test701machine # [ 5.037855] initrd-nixos-activation-start[193]: running activation script...702machine # [ 5.451379] initrd-nixos-activation-start[216]: setting up /etc...703machine # [ 5.702318] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.704machine # [ 5.704065] systemd[1]: Finished NixOS Activation.705machine # [ 5.705649] systemd[1]: Starting Switch Root...706machine # [ 5.719092] systemd[1]: Switching root.707machine # [ 5.990611] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).708machine # [ 6.150232] NET: Registered PF_VSOCK protocol family709machine # [ 6.535993] systemd[1]: systemd 261.1 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)710machine # [ 6.545487] systemd[1]: Detected virtualization kvm.711machine # [ 6.547304] systemd[1]: Detected architecture x86-64.712machine # [ 6.549180] systemd[1]: Detected first boot.713machine # [ 6.556427] systemd[1]: Initializing machine ID from random generator.714machine # [ 6.815359] systemd[1]: bpf-restrict-fs: LSM BPF program attached715machine # [ 6.949665] systemd[1]: Applying preset policy.716machine # [ 7.403095] systemd[1]: Populated /etc with preset unit settings.717machine # [ 7.872706] systemd[1]: initrd-switch-root.service: Deactivated successfully.718machine # [ 7.874247] systemd[1]: Stopped initrd-switch-root.service.719machine # [ 7.876741] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.720machine # [ 7.878809] systemd[1]: Created slice Slice /system/getty.721machine # [ 7.880124] systemd[1]: Created slice User and Session Slice.722machine # [ 7.881036] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.723machine # [ 7.882200] systemd[1]: Started Forward Password Requests to Wall Directory Watch.724machine # [ 7.883319] systemd[1]: Expecting device /dev/hvc0...725machine # [ 7.884020] systemd[1]: Expecting device /dev/ttyS0...726machine # [ 7.884730] systemd[1]: Reached target Local Encrypted Volumes.727machine # [ 7.885584] systemd[1]: Stopped target initrd-fs.target.728machine # [ 7.886348] systemd[1]: Stopped target initrd-root-fs.target.729machine # [ 7.887145] systemd[1]: Stopped target initrd-switch-root.target.730machine # [ 7.887972] systemd[1]: Reached target Virtual Machines and Containers.731machine # [ 7.888856] systemd[1]: Reached target Path Units.732machine # [ 7.889571] systemd[1]: Reached target Remote File Systems.733machine # [ 7.890909] systemd[1]: Reached target Slice Units.734machine # [ 7.892881] systemd[1]: Reached target Swaps.735machine # [ 7.908966] systemd[1]: Listening on Query the User Interactively for a Password.736machine # [ 7.913420] systemd[1]: Listening on Process Core Dump Socket.737machine # [ 7.916925] systemd[1]: Listening on Credential Encryption/Decryption.738machine # [ 7.920199] systemd[1]: Listening on Factory Reset Management.739machine # [ 7.921099] systemd[1]: Listening on Hostname Service Socket.740machine # [ 7.926035] systemd[1]: Starting Journal Log Access Socket...741machine # [ 7.927811] systemd[1]: Listening on Journal Audit Socket.742machine # [ 7.931184] systemd[1]: Listening on Console Output Muting Service Socket.743machine # [ 7.932337] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.744machine # [ 7.934322] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os745machine # [ 7.935670] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki746machine # [ 7.947612] systemd[1]: Listening on Disk Repartitioning Service Socket.747machine # [ 7.948679] systemd[1]: Listening on udev Control Socket.748machine # [ 7.949613] systemd[1]: Listening on udev Varlink Socket.749machine # [ 7.954384] systemd[1]: Mounting Huge Pages File System...750machine # [ 7.958011] systemd[1]: Mounting POSIX Message Queue File System...751machine # [ 7.963383] systemd[1]: Mounting Kernel Debug File System...752machine # [ 7.969831] systemd[1]: Mounting Kernel Trace File System...753machine # [ 7.975730] systemd[1]: Starting Create List of Static Device Nodes...754machine # [ 7.977745] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio755machine # [ 7.989587] systemd[1]: Starting Load Kernel Module configfs...756machine # [ 7.990684] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm757machine # [ 7.992796] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore758machine # [ 7.994963] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse759machine # [ 8.003895] systemd[1]: Mounting FUSE Control File System...760machine # [ 8.005221] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67761machine # [ 8.033204] systemd[1]: Starting Journal Service...762machine # [ 8.053353] systemd[1]: Starting Load Kernel Modules...763machine # [ 8.072008] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...764machine # [ 8.090834] systemd[1]: Starting Remount Root and Kernel File Systems...765machine # [ 8.095482] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os766machine # [ 8.110153] systemd[1]: Starting Coldplug All udev Devices...767machine # [ 8.114413] systemd-journald[286]: Collecting audit messages is enabled.768machine # [ 8.133873] systemd[1]: Listening on Journal Log Access Socket.769machine # [ 8.139736] systemd[1]: Mounted Huge Pages File System.770machine # [ 8.145979] systemd[1]: Started Journal Service.771machine # [ 8.005100] systemd[1]: Queued start job for default target Multi-User System.772machine # [ 8.006616] systemd[1]: systemd-journald.service: Deactivated successfully.773machine # [ 8.013073] systemd[1]: Mounted POSIX Message Queue File System.774machine # [ 8.014227] systemd[1]: Mounted Kernel Debug File System.775machine # [ 8.159298] loop: module loaded776machine # [ 8.017384] systemd[1]: Mounted Kernel Trace File System.777machine # [ 8.022403] systemd[1]: Finished Create List of Static Device Nodes.778machine # [ 8.025135] systemd[1]: modprobe@configfs.service: Deactivated successfully.779machine # [ 8.169899] EXT4-fs (vda): re-mounted f8a2453b-7599-412b-aa07-8bd01a07fd42.780machine # [ 8.029296] systemd-modules-load[287]: Inserted module 'loop'781machine # [ 8.030408] systemd[1]: Finished Load Kernel Module configfs.782machine # [ 8.031252] systemd[1]: Mounted FUSE Control File System.783machine # [ 8.037394] systemd[1]: Finished Load Kernel Modules.784machine # [ 8.041277] systemd[1]: Finished Remount Root and Kernel File Systems.785machine # [ 8.051125] systemd[1]: Listening on Disk Image Download Service Socket.786machine # [ 8.060069] systemd[1]: Mounting Kernel Configuration File System...787machine # [ 8.066157] systemd[1]: Starting Firewall...788machine # [ 8.077485] systemd[1]: Starting Flush Journal to Persistent Storage...789machine # [ 8.079973] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore790machine # [ 8.091989] systemd-oomd[289]: No swap; memory pressure usage will be degraded791machine # [ 8.098090] systemd[1]: Starting Load/Save OS Random Seed...792machine # [ 8.105952] systemd[1]: Starting Apply Kernel Variables...793machine # [ 8.114086] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...794machine # [ 8.115196] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os795machine # [ 8.116459] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.796machine # [ 8.294329] systemd-journald[286]: Received client request to flush runtime journal.797machine # [ 8.290727] systemd[1]: Mounted Kernel Configuration File System.798machine # [ 8.291788] systemd[1]: Finished Load/Save OS Random Seed.799machine # [ 8.294408] systemd[1]: Reached target First Boot Complete.800machine # [ 8.295260] systemd[1]: Finished Apply Kernel Variables.801machine # [ 8.297866] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.802machine # [ 8.301350] systemd[1]: Finished Flush Journal to Persistent Storage.803machine # [ 8.307074] systemd[1]: Starting Create Static Device Nodes in /dev...804machine # [ 8.380794] systemd[1]: Finished Create Static Device Nodes in /dev.805machine # [ 8.384078] systemd[1]: Reached target Preparation for Local File Systems.806machine # [ 8.387990] systemd[1]: Starting Rule-based Manager for Device Events and Files...807machine # [ 8.455970] systemd[1]: Finished Coldplug All udev Devices.808machine # [ 8.478414] systemd-udevd[329]: Using default interface naming scheme 'v261'.809machine # [ 8.596666] systemd[1]: Started Rule-based Manager for Device Events and Files.810machine # [ 8.719413] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse811machine # [ 8.734531] systemd[1]: Mounting /run/wrappers...812machine # [ 8.769700] systemd[1]: Mounted /run/wrappers.813machine # [ 8.772449] systemd[1]: Reached target Local File Systems.814machine # [ 8.776396] systemd[1]: Listening on Boot Loader Control Service Socket.815machine # [ 8.781192] systemd[1]: Starting register-nix-paths.service...816machine # [ 8.785949] systemd[1]: Starting Create SUID/SGID Wrappers...817machine # [ 8.787309] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.818machine # [ 8.800557] systemd[1]: Starting Save Transient machine-id to Disk...819machine # [ 8.814656] systemd[1]: Starting Create System Files and Directories...820machine # [ 8.861796] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.821machine # [ 8.902453] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.822machine # [ 8.909710] systemd[1]: Finished Save Transient machine-id to Disk.823machine # [ 8.939969] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.824machine # [ 8.987197] systemd[1]: Finished Create System Files and Directories.825machine # [ 8.997167] systemd[1]: Starting Rebuild Journal Catalog...826machine # [ 9.007089] systemd[1]: Starting Record System Boot/Shutdown in UTMP...827machine # [ 9.021492] (udev-worker)[362]: Network interface NamePolicy= disabled on kernel command line.828machine # [ 9.047305] (udev-worker)[364]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.829machine # [ 9.055408] (udev-worker)[364]: Network interface NamePolicy= disabled on kernel command line.830machine # [ 9.101974] systemd[1]: Finished Record System Boot/Shutdown in UTMP.831machine # [ 9.140620] systemd[1]: Finished Rebuild Journal Catalog.832machine # [ 9.149517] systemd[1]: Starting Update is Completed...833machine # [ 9.204694] systemd[1]: Finished Update is Completed.834machine # [ 9.282453] systemd[1]: Condition check resulted in Virtio network device being skipped.835machine # [ 9.285075] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore836machine # [ 9.286932] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.837machine # [ 9.289421] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67838machine # [ 9.292195] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore839machine # [ 9.295095] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os840machine # [ 9.297288] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os841machine # [ 9.485212] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.842machine # [ 9.486722] systemd[1]: Finished Create SUID/SGID Wrappers.843machine # [ 9.638335] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console844machine # [ 9.651948] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3845machine # [ 9.526605] systemd[1]: Finished Firewall.846machine # [ 9.678277] mousedev: PS/2 mouse device common for all mice847machine # [ 9.687387] ACPI: button: Power Button [PWRF]848machine # [ 9.737089] rtc_cmos 00:04: RTC can wake from S4849machine # [ 9.754012] rtc_cmos 00:04: registered as rtc0850machine # [ 9.754082] rtc_cmos 00:04: setting system clock to 2026-09-03T03:43:07 UTC (1788406987)851machine # [ 9.754167] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs852machine # [ 9.762626] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4853machine # [ 9.774224] parport_pc 00:02: reported by Plug and Play ACPI854machine # [ 9.774303] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]855machine # [ 9.786904] Console: switching to colour dummy device 80x25856machine # [ 9.801130] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized857machine # [ 9.888203] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt858machine # [ 9.888308] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD859machine # [ 9.892485] [drm] Found bochs VGA, ID 0xb0c5.860machine # [ 9.892486] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.861machine # [ 9.914385] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic862machine # [ 9.915102] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0863machine # [ 9.940447] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6864machine # [ 9.941633] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5865machine # [ 9.812991] systemd[1]: Finished register-nix-paths.service.866machine # [ 9.814305] systemd[1]: Reached target System Initialization.867machine # [ 9.817190] systemd[1]: Started Discard unused filesystem blocks once a week.868machine # [ 9.818239] systemd[1]: Started Daily Cleanup of Temporary Directories.869machine # [ 9.819413] systemd[1]: Reached target Timer Units.870machine # [ 9.821163] systemd[1]: Listening on D-Bus System Message Bus Socket.871machine # [ 9.826088] systemd[1]: Listening on Nix Daemon Socket.872machine # [ 9.826960] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.873machine # [ 9.828156] systemd[1]: Reached target Socket Units.874machine # [ 9.828882] systemd[1]: Reached target Basic System.875machine # [ 9.832165] systemd[1]: Started backdoor.service.876machine # [ 9.835576] systemd[1]: Starting Import lastlog data into lastlog2 database...877machine # [ 9.843461] systemd[1]: Starting Name Service Cache Daemon (nsncd)...878machine # [ 9.857311] systemd[1]: Starting Post-Boot Actions...879machine # [ 9.872166] systemd[1]: Started Reset console on configuration changes.880machine # [ 9.883663] systemd[1]: Starting resolvconf update...881machine # [ 9.938450] systemd[1]: Finished Post-Boot Actions.882machine # connecting to host...883machine # [ 9.968469] systemd[1]: Started Name Service Cache Daemon (nsncd).884machine # [ 9.971774] nsncd[512]: Sep 03 03:43:07.855 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"885machine # [ 9.981977] systemd[1]: Reached target Host and Network Name Lookups.886machine # [ 9.982956] systemd[1]: Reached target User and Group Name Lookups.887machine: Guest shell says: b'Spawning backdoor root shell...\n'888machine: connected to guest root shell889machine # [ 9.992174] systemd[1]: Starting D-Bus System Message Bus...890machine: (connecting took 10.75 seconds)891machine: (finished: waiting for the VM to finish booting, in 11.04 seconds)892machine # [ 10.014065] systemd[1]: Starting User Login Management...893machine # [ 10.023064] systemd[1]: Starting Virtual Console Setup...894machine # [ 10.141505] Console: switching to colour frame buffer device 160x50895machine # [ 10.169390] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device896machine # [ 10.030721] systemd[1]: Finished Import lastlog data into lastlog2 database.897machine # [ 10.182539] dbus-broker-launch[537]: Looking up NSS user entry for 'systemd-timesync'...898machine # [ 10.209811] dbus-broker-launch[537]: NSS returned no entry for 'systemd-timesync'899machine # [ 10.210927] dbus-broker-launch[537]: Invalid user-name in /nix/store/3znbjn7nyzb0qbmbbainbgfckwwpsl5z-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"900machine # [ 10.215507] systemd[1]: Stopped target Host and Network Name Lookups.901machine # [ 10.217116] systemd[1]: Stopping Host and Network Name Lookups...902machine # [ 10.220113] systemd[1]: Stopped target User and Group Name Lookups.903machine # [ 10.222204] systemd[1]: Stopping User and Group Name Lookups...904machine # [ 10.224233] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...905machine # [ 10.232593] systemd[1]: nscd.service: Deactivated successfully.906machine # [ 10.236880] systemd[1]: Stopped Name Service Cache Daemon (nsncd).907machine # [ 10.244807] systemd-logind[541]: Watching system buttons on /dev/input/event2 (Power Button)908machine # [ 10.247899] systemd-logind[541]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)909machine # [ 10.251905] systemd-logind[541]: New seat seat0.910machine # [ 10.254252] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.911machine # [ 10.259861] systemd[1]: Stopped Virtual Console Setup.912machine # [ 10.263984] systemd[1]: Started User Login Management.913machine # [ 10.271865] systemd[1]: Starting linger-users.service...914machine # [ 10.418935] ppdev: user-space parallel port driver915machine # [ 10.284238] systemd[1]: Starting Name Service Cache Daemon (nsncd)...916machine # [ 10.295962] systemd[1]: Starting Virtual Console Setup...917machine # [ 10.299138] systemd[1]: Started D-Bus System Message Bus.918machine # [ 10.324920] systemd-logind[541]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)919machine # [ 10.331265] dbus-broker-launch[537]: Ready920machine # [ 10.367222] systemd[1]: Finished resolvconf update.921machine # [ 10.371544] systemd[1]: linger-users.service: Deactivated successfully.922machine # [ 10.374134] systemd[1]: Finished linger-users.service.923machine # [ 10.377393] systemd[1]: Reached target Preparation for Network.924machine # [ 10.387943] systemd[1]: Starting DHCP Client...925machine # [ 10.394460] nsncd[604]: Sep 03 03:43:08.282 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"926machine # [ 10.400263] systemd[1]: Starting Address configuration of eth1...927machine # [ 10.411068] systemd[1]: Starting Extra networking commands....928machine # [ 10.558743] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)929machine # [ 10.418465] systemd[1]: Started Name Service Cache Daemon (nsncd).930machine # [ 10.427859] systemd[1]: Reached target Host and Network Name Lookups.931machine # [ 10.429335] systemd[1]: Reached target User and Group Name Lookups.932machine # [ 10.466063] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.933machine # [ 10.468960] systemd[1]: Stopped Virtual Console Setup.934machine # [ 10.621914] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)935machine # [ 10.491886] systemd[1]: Starting Virtual Console Setup...936machine # [ 10.555798] network-addresses-eth1-start[624]: adding address 192.168.1.1/24... done937machine # [ 10.577516] network-addresses-eth1-start[624]: adding address 2001:db8:1::1/64... done938machine # [ 10.607931] systemd[1]: Finished Address configuration of eth1.939machine # [ 10.708551] systemd[1]: Finished Extra networking commands..940machine # [ 10.711669] systemd[1]: Reached target Network.941machine # [ 10.715486] dhcpcd[640]: dhcpcd-10.3.2 starting942machine # [ 10.721662] systemd[1]: Starting PostgreSQL Server...943machine # [ 10.727206] dhcpcd[697]: dev: loaded udev944machine # [ 10.732032] systemd[1]: Starting Permit User Sessions...945machine # [ 10.880666] kvm_amd: TSC scaling supported946machine # [ 10.882234] kvm_amd: Nested Virtualization enabled947machine # [ 10.884110] kvm_amd: Nested Paging enabled948machine # [ 10.884606] kvm_amd: LBR virtualization supported949machine # [ 10.886891] kvm_amd: Virtual VMLOAD VMSAVE supported950machine # [ 10.887450] kvm_amd: Virtual GIF supported951machine # [ 10.889334] kvm_amd: Virtual NMI enabled952machine # [ 10.905230] 8021q: 802.1Q VLAN Support v1.8953machine # [ 10.905663] 8021q: adding VLAN 0 to HW filter on device eth1954machine # [ 10.795161] systemd[1]: Finished Permit User Sessions.955machine # [ 10.806602] systemd[1]: Started Getty on tty1.956machine # [ 10.809598] systemd[1]: Reached target Login Prompts.957machine # [ 10.839201] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.958machine # [ 10.986329] EDAC MC: Ver: 3.0.0959machine # [ 11.055633] cfg80211: Loading compiled-in X.509 certificates for regulatory database960machine # [ 11.085798] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'961machine # [ 11.086617] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'962machine # [ 11.091055] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2963machine # [ 11.091993] cfg80211: failed to load regulatory.db964machine # [ 11.149399] 8021q: adding VLAN 0 to HW filter on device eth0965machine # [ 11.007469] dhcpcd[697]: eth0: waiting for carrier966machine # [ 11.008797] dhcpcd[697]: eth0: carrier acquired967machine # [ 11.020098] dhcpcd[697]: DUID 00:01:00:01:32:2b:a9:4c:52:54:00:12:34:56968machine # [ 11.021080] dhcpcd[697]: eth0: IAID 00:12:34:56969machine # [ 11.022215] dhcpcd[697]: eth0: adding address fe80::5054:ff:fe12:3456970machine # [ 11.037243] postgresql-pre-start[710]: The files belonging to this database system will be owned by user "postgres".971machine # [ 11.038769] postgresql-pre-start[710]: This user must also own the server process.972machine # [ 11.047772] postgresql-pre-start[710]: The database cluster will be initialized with locale "en_US.UTF-8".973machine # [ 11.049731] postgresql-pre-start[710]: The default database encoding has accordingly been set to "UTF8".974machine # [ 11.050966] postgresql-pre-start[710]: The default text search configuration will be set to "english".975machine # [ 11.052123] postgresql-pre-start[710]: Data page checksums are enabled.976machine # [ 11.052970] postgresql-pre-start[710]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok977machine # [ 11.054200] postgresql-pre-start[710]: creating subdirectories ... ok978machine # [ 11.055592] postgresql-pre-start[710]: selecting dynamic shared memory implementation ... posix979machine # [ 11.111027] systemd-vconsole-setup[637]: Configuration of first virtual console was skipped, ignoring remaining ones.980machine # [ 11.115345] systemd[1]: Finished Virtual Console Setup.981machine # [ 11.136175] postgresql-pre-start[710]: selecting default "max_connections" ... 100982machine # [ 11.205922] postgresql-pre-start[710]: selecting default "shared_buffers" ... 128MB983machine # [ 12.421424] dhcpcd[697]: eth0: soliciting a DHCP lease984machine # [ 12.585235] NET: Registered PF_PACKET protocol family985machine # [ 12.445564] dhcpcd[697]: eth0: offered 10.0.2.15 from 10.0.2.2986machine # [ 12.449399] dhcpcd[697]: eth0: probing address 10.0.2.15/24987machine # [ 12.925792] dhcpcd[697]: eth0: soliciting an IPv6 router988machine # [ 12.927087] dhcpcd[697]: eth0: Router Advertisement from fe80::2989machine # [ 12.927995] dhcpcd[697]: eth0: adding address fec0::5054:ff:fe12:3456/64990machine # [ 12.929338] dhcpcd[697]: eth0: adding route to fec0::/64991machine # [ 12.930255] dhcpcd[697]: eth0: adding default route via fe80::2992machine # [ 13.624144] postgresql-pre-start[710]: selecting default time zone ... UTC993machine # [ 13.627581] postgresql-pre-start[710]: creating configuration files ... ok994machine # [ 13.831668] postgresql-pre-start[710]: running bootstrap script ... ok995machine # [ 14.395086] postgresql-pre-start[710]: performing post-bootstrap initialization ... ok996machine # [ 14.558230] postgresql-pre-start[710]: syncing data to disk ... ok997machine # [ 14.559927] postgresql-pre-start[710]: initdb: warning: enabling "trust" authentication for local connections998machine # [ 14.561136] postgresql-pre-start[710]: 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.999machine # [ 14.563042] postgresql-pre-start[710]: Success. You can now start the database server using:1000machine # [ 14.564061] postgresql-pre-start[710]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1001machine # [ 14.672986] postgres[757]: [757] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit1002machine # [ 14.683418] postgres[757]: [757] LOG: listening on IPv6 address "::1", port 54321003machine # [ 14.684486] postgres[757]: [757] LOG: listening on IPv4 address "127.0.0.1", port 54321004machine # [ 14.689038] postgres[757]: [757] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1005machine # [ 14.701260] postgres[766]: [766] LOG: database system was shut down at 2026-09-03 03:43:12 GMT1006machine # [ 14.706779] postgres[757]: [757] LOG: database system is ready to accept connections1007machine # [ 14.711498] systemd[1]: Started PostgreSQL Server.1008machine # [ 14.717929] systemd[1]: Starting PostgreSQL Setup Scripts...1009machine # [ 14.901656] postgresql-setup-start[777]: CREATE DATABASE1010machine # [ 14.995290] postgresql-setup-start[782]: CREATE DATABASE1011machine # [ 15.059677] postgresql-setup-start[790]: CREATE ROLE1012machine # [ 15.079885] postgresql-setup-start[792]: ALTER DATABASE1013machine # [ 15.119703] postgresql-setup-start[797]: CREATE ROLE1014machine # [ 15.139702] postgresql-setup-start[799]: ALTER DATABASE1015machine # [ 15.180554] postgresql-setup-start[804]: ALTER DATABASE1016machine # [ 15.184516] systemd[1]: Finished PostgreSQL Setup Scripts.1017machine # [ 15.185979] systemd[1]: Reached target PostgreSQL.1018machine # [ 15.190258] systemd[1]: Starting gitea...1019machine: (finished: waiting for unit postgresql.service, in 16.60 seconds)1020machine: waiting for unit gitea.service1021machine # [ 16.838576] dhcpcd[697]: eth0: leased 10.0.2.15 for 86400 seconds1022machine # [ 16.841081] dhcpcd[697]: eth0: adding route to 10.0.2.0/241023machine # [ 16.841877] dhcpcd[697]: eth0: adding default route via 10.0.2.21024machine # [ 16.971401] systemd[1]: Started DHCP Client.1025machine # [ 17.160939] gitea-pre-start[900]: 2026/09/03 03:43:15 .../v3@v3.10.0/command_run.go:95:(*Command).Run() [I] PING DATABASE postgres1026machine # [ 17.162511] gitea-pre-start[900]: 2026/09/03 03:43:15 cmd/migrate.go:31:runMigrate() [I] AppPath: /nix/store/hmnprw81idl7jcr8n06p0qsdwz6dhbsb-gitea-1.27.2/bin/gitea1027machine # [ 17.164235] gitea-pre-start[900]: 2026/09/03 03:43:15 cmd/migrate.go:32:runMigrate() [I] AppWorkPath: /var/lib/gitea1028machine # [ 17.166766] gitea-pre-start[900]: 2026/09/03 03:43:15 cmd/migrate.go:33:runMigrate() [I] Custom path: /var/lib/gitea/custom1029machine # [ 17.168122] gitea-pre-start[900]: 2026/09/03 03:43:15 cmd/migrate.go:34:runMigrate() [I] Log path: /var/lib/gitea/log1030machine # [ 17.169439] gitea-pre-start[900]: 2026/09/03 03:43:15 cmd/migrate.go:35:runMigrate() [I] Configuration file: /var/lib/gitea/custom/conf/app.ini1031machine # [ 18.367041] gitea[929]: 2026/09/03 03:43:16 cmd/web.go:263:runWeb() [I] Starting Gitea on PID: 9291032machine # [ 19.410182] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:115:showWebStartupMessage() [I] Gitea version: 1.27.2 built with go1.26.5 : sqlite, sqlite_unlock_notify1033machine # [ 19.412219] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:116:showWebStartupMessage() [I] * RunMode: prod1034machine # [ 19.413394] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:117:showWebStartupMessage() [I] * AppPath: /nix/store/hmnprw81idl7jcr8n06p0qsdwz6dhbsb-gitea-1.27.2/bin/gitea1035machine # [ 19.415156] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:118:showWebStartupMessage() [I] * WorkPath: /var/lib/gitea1036machine # [ 19.416565] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:119:showWebStartupMessage() [I] * CustomPath: /var/lib/gitea/custom1037machine # [ 19.417920] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:120:showWebStartupMessage() [I] * ConfigFile: /var/lib/gitea/custom/conf/app.ini1038machine # [ 19.419380] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:121:showWebStartupMessage() [I] Prepare to run web server1039machine # [ 19.637770] gitea[929]: 2026/09/03 03:43:17 routers/init.go:117:InitWebInstalled() [I] Git version: 2.55.0 (home: /var/lib/gitea/data/home)1040machine # [ 19.937774] gitea[929]: 2026/09/03 03:43:17 modules/setting/webhook.go:38:loadWebhookFrom() [E] Deprecation: config option `[webhook].ALLOWED_HOST_LIST` present, please use `[security].ALLOWED_HOST_LIST` instead because this fallback will be/has been removed in v28.0.01041machine # [ 19.940785] gitea[929]: 2026/09/03 03:43:17 modules/storage/storage.go:227:initAttachments() [I] Initialising Attachment storage with type: local1042machine # [ 19.943079] gitea[929]: 2026/09/03 03:43:17 modules/storage/local.go:48:NewLocalStorage() [I] Creating new Local Storage at /var/lib/gitea/data/attachments1043machine # [ 19.944758] gitea[929]: 2026/09/03 03:43:17 modules/storage/storage.go:217:initAvatars() [I] Initialising Avatar storage with type: local1044machine # [ 19.946183] gitea[929]: 2026/09/03 03:43:17 modules/storage/local.go:48:NewLocalStorage() [I] Creating new Local Storage at /var/lib/gitea/data/avatars1045machine # [ 19.947751] gitea[929]: 2026/09/03 03:43:17 modules/storage/storage.go:243:initRepoAvatars() [I] Initialising Repository Avatar storage with type: local1046machine # [ 19.949256] gitea[929]: 2026/09/03 03:43:17 modules/storage/local.go:48:NewLocalStorage() [I] Creating new Local Storage at /var/lib/gitea/data/repo-avatars1047machine # [ 19.950877] gitea[929]: 2026/09/03 03:43:17 modules/storage/storage.go:249:initRepoArchives() [I] Initialising Repository Archive storage with type: local1048machine # [ 19.952493] gitea[929]: 2026/09/03 03:43:17 modules/storage/local.go:48:NewLocalStorage() [I] Creating new Local Storage at /var/lib/gitea/data/repo-archive1049machine # [ 19.954086] gitea[929]: 2026/09/03 03:43:17 modules/storage/storage.go:259:initPackages() [I] Initialising Packages storage with type: local1050machine # [ 19.955599] gitea[929]: 2026/09/03 03:43:17 modules/storage/local.go:48:NewLocalStorage() [I] Creating new Local Storage at /var/lib/gitea/data/packages1051machine # [ 19.957189] gitea[929]: 2026/09/03 03:43:17 modules/storage/storage.go:270:initActions() [I] Initialising Actions storage with type: local1052machine # [ 19.958695] gitea[929]: 2026/09/03 03:43:17 modules/storage/local.go:48:NewLocalStorage() [I] Creating new Local Storage at /var/lib/gitea/data/actions_log1053machine # [ 19.960214] gitea[929]: 2026/09/03 03:43:17 modules/storage/storage.go:274:initActions() [I] Initialising ActionsArtifacts storage with type: local1054machine # [ 19.962655] gitea[929]: 2026/09/03 03:43:17 modules/storage/local.go:48:NewLocalStorage() [I] Creating new Local Storage at /var/lib/gitea/data/actions_artifacts1055machine # [ 19.986776] gitea[929]: 2026/09/03 03:43:17 routers/common/db.go:22:InitDBEngine() [I] Beginning ORM engine initialization.1056machine # [ 19.988167] gitea[929]: 2026/09/03 03:43:17 routers/common/db.go:29:InitDBEngine() [I] ORM engine initialization attempt #1/10...1057machine # [ 19.990074] gitea[929]: 2026/09/03 03:43:17 cmd/web.go:205:serveInstalled() [I] PING DATABASE postgres1058machine # [ 20.589354] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table oauth2_application Column confidential_client db default is true, struct default is TRUE1059machine # [ 20.592056] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table oauth2_application Column skip_secondary_authorization db default is false, struct default is FALSE1060machine # [ 20.655686] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table system_setting Column version db default is , struct default is 11061machine # [ 20.712070] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table user Column max_repo_creation db default is '-1', struct default is -11062machine # [ 20.875598] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table action_run_attempt Column concurrency_cancel db default is false, struct default is FALSE1063machine # [ 20.893875] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table action_run_job Column concurrency_cancel db default is false, struct default is FALSE1064machine # [ 20.895719] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table action_run_job Column is_reusable_caller db default is false, struct default is FALSE1065machine # [ 20.897548] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table action_run_job Column is_expanded db default is false, struct default is FALSE1066machine # [ 20.899257] gitea[929]: 2026/09/03 03:43:18 cmd/web.go:205:serveInstalled() [W] Table action_run_job Column continue_on_error db default is false, struct default is FALSE1067machine # [ 21.152128] gitea[929]: 2026/09/03 03:43:19 cmd/web.go:205:serveInstalled() [W] Table label Column archived_unix db default is , struct default is NULL1068machine # [ 21.218503] gitea[929]: 2026/09/03 03:43:19 routers/init.go:138:InitWebInstalled() [I] ORM engine initialization successful!1069machine # [ 22.046893] gitea[929]: 2026/09/03 03:43:19 .../indexer/issues/indexer.go:78:InitIssueIndexer.1() [I] PID 929: Initializing Issue Indexer: bleve1070machine # [ 22.048583] gitea[929]: 2026/09/03 03:43:19 .../indexer/stats/indexer.go:41:populateRepoIndexer() [I] Populating the repo stats indexer with existing repositories1071machine # [ 22.062028] gitea[929]: 2026/09/03 03:43:19 .../indexer/issues/indexer.go:155:InitIssueIndexer.2() [I] Issue Indexer Initialization took 42.990659ms1072machine # [ 22.072949] gitea[929]: 2026/09/03 03:43:19 routers/init.go:86:syncAppConfForGit() [I] AppPath changed from '' to '/nix/store/hmnprw81idl7jcr8n06p0qsdwz6dhbsb-gitea-1.27.2/bin/gitea'1073machine # [ 22.074870] gitea[929]: 2026/09/03 03:43:19 routers/init.go:91:syncAppConfForGit() [I] CustomConf changed from '' to '/var/lib/gitea/custom/conf/app.ini'1074machine # [ 22.076543] gitea[929]: 2026/09/03 03:43:19 routers/init.go:97:syncAppConfForGit() [I] re-sync repository hooks ...1075machine # [ 22.081058] gitea[929]: 2026/09/03 03:43:19 routers/init.go:100:syncAppConfForGit() [I] re-write ssh public keys ...1076machine # [ 23.124913] gitea[929]: 2026/09/03 03:43:21 cmd/web.go:329:listen() [I] Listen: http://0.0.0.0:30001077machine # [ 23.126909] gitea[929]: 2026/09/03 03:43:21 cmd/web.go:333:listen() [I] AppURL(ROOT_URL): http://localhost:3000/1078machine # [ 23.128172] gitea[929]: 2026/09/03 03:43:21 modules/graceful/server.go:52:NewServer() [I] Starting new Web server: tcp:0.0.0.0:3000 on PID: 9291079machine # [ 23.133237] systemd[1]: Started gitea.1080machine # [ 23.133861] systemd[1]: Reached target Multi-User System.1081machine # [ 23.134654] systemd[1]: Startup finished in 852ms (kernel) + 5.092s (initrd) + 17.187s (userspace) = 23.132s.1082machine: (finished: waiting for unit gitea.service, in 8.51 seconds)1083machine: waiting for TCP port 3000 on localhost1084machine # Connection to localhost (::1) 3000 port [tcp/hbci] succeeded!1085machine: (finished: waiting for TCP port 3000 on localhost, in 0.08 seconds)1086machine: must succeed: su -l gitea -c 'GITEA_WORK_DIR=/var/lib/gitea gitea admin user create --admin --username testuser --password testpass123 --email test@test.com'1087machine # [ 24.226554] su[1010]: Successful su for gitea by root1088machine # [ 24.238174] su[1010]: pam_unix(su:session): session opened for user gitea(uid=998) by (uid=0)1089machine # [ 24.412437] su[1010]: pam_unix(su:session): session closed for user gitea1090machine: (finished: must succeed: su -l gitea -c 'GITEA_WORK_DIR=/var/lib/gitea gitea admin user create --admin --username testuser --password testpass123 --email test@test.com', in 0.26 seconds)1091machine: must succeed: curl -sf -X POST http://localhost:3000/api/v1/users/testuser/tokens -u testuser:testpass123 -H 'Content-Type: application/json' -d '{"name": "mq-token", "scopes": ["all"]}'1092machine # [ 24.534660] gitea[929]: 2026/09/03 03:43:22 HTTPRequest [I] router: completed POST /api/v1/users/testuser/tokens for [::1]:37192, 201 Created in 34.8ms @ user/app.go:75(user.CreateAccessToken)1093machine: (finished: must succeed: curl -sf -X POST http://localhost:3000/api/v1/users/testuser/tokens -u testuser:testpass123 -H 'Content-Type: application/json' -d '{"name": "mq-token", "scopes": ["all"]}', in 0.13 seconds)1094machine: must succeed: curl -sf -X POST http://localhost:3000/api/v1/user/repos -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"name": "testrepo", "auto_init": true, "default_branch": "main", "private": true}'1095machine # [ 25.331324] gitea[929]: 2026/09/03 03:43:23 .../actions/notifier_helper.go:166:notify() [W] Ref of event "repository" is empty, will fall back to the default branch1096machine # [ 25.352868] gitea[929]: 2026/09/03 03:43:23 HTTPRequest [I] router: completed POST /api/v1/user/repos for [::1]:37208, 201 Created in 760.8ms @ repo/repo.go:280(repo.Create)1097machine: (finished: must succeed: curl -sf -X POST http://localhost:3000/api/v1/user/repos -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"name": "testrepo", "auto_init": true, "default_branch": "main", "private": true}', in 0.82 seconds)1098machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/big.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"content": "bGluZSAxCmxpbmUgMgpsaW5lIDMKbGluZSA0CmxpbmUgNQpsaW5lIDYKbGluZSA3CmxpbmUgOApsaW5lIDkKbGluZSAxMApsaW5lIDExCmxpbmUgMTIKbGluZSAxMwpsaW5lIDE0CmxpbmUgMTUKbGluZSAxNgpsaW5lIDE3CmxpbmUgMTgKbGluZSAxOQpsaW5lIDIwCmxpbmUgMjEKbGluZSAyMgpsaW5lIDIzCmxpbmUgMjQKbGluZSAyNQpsaW5lIDI2CmxpbmUgMjcKbGluZSAyOApsaW5lIDI5CmxpbmUgMzAKbGluZSAzMQpsaW5lIDMyCmxpbmUgMzMKbGluZSAzNApsaW5lIDM1CmxpbmUgMzYKbGluZSAzNwpsaW5lIDM4CmxpbmUgMzkKbGluZSA0MApsaW5lIDQxCmxpbmUgNDIKbGluZSA0MwpsaW5lIDQ0CmxpbmUgNDUKbGluZSA0NgpsaW5lIDQ3CmxpbmUgNDgKbGluZSA0OQpsaW5lIDUwCmxpbmUgNTEKbGluZSA1MgpsaW5lIDUzCmxpbmUgNTQKbGluZSA1NQpsaW5lIDU2CmxpbmUgNTcKbGluZSA1OApsaW5lIDU5CmxpbmUgNjAKbGluZSA2MQpsaW5lIDYyCmxpbmUgNjMKbGluZSA2NApsaW5lIDY1CmxpbmUgNjYKbGluZSA2NwpsaW5lIDY4CmxpbmUgNjkKbGluZSA3MApsaW5lIDcxCmxpbmUgNzIKbGluZSA3MwpsaW5lIDc0CmxpbmUgNzUKbGluZSA3NgpsaW5lIDc3CmxpbmUgNzgKbGluZSA3OQpsaW5lIDgwCmxpbmUgODEKbGluZSA4MgpsaW5lIDgzCmxpbmUgODQKbGluZSA4NQpsaW5lIDg2CmxpbmUgODcKbGluZSA4OApsaW5lIDg5CmxpbmUgOTAKbGluZSA5MQpsaW5lIDkyCmxpbmUgOTMKbGluZSA5NApsaW5lIDk1CmxpbmUgOTYKbGluZSA5NwpsaW5lIDk4CmxpbmUgOTkKbGluZSAxMDA=", "message": "add big file"}'1099machine # [ 25.735450] gitea[929]: 2026/09/03 03:43:23 HTTPRequest [I] router: completed POST /api/internal/hook/pre-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 5.4ms @ private/hook_pre_receive.go:110(private.HookPreReceive)1100machine # [ 26.031808] gitea[929]: 2026/09/03 03:43:23 HTTPRequest [I] router: completed POST /api/internal/hook/post-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 33.3ms @ private/hook_post_receive.go:101(private.HookPostReceive)1101machine # [ 26.082096] gitea[929]: 2026/09/03 03:43:23 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/contents/big.txt for [::1]:37212, 201 Created in 675.6ms @ repo/file.go:435(repo.CreateFile)1102machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/big.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"content": "bGluZSAxCmxpbmUgMgpsaW5lIDMKbGluZSA0CmxpbmUgNQpsaW5lIDYKbGluZSA3CmxpbmUgOApsaW5lIDkKbGluZSAxMApsaW5lIDExCmxpbmUgMTIKbGluZSAxMwpsaW5lIDE0CmxpbmUgMTUKbGluZSAxNgpsaW5lIDE3CmxpbmUgMTgKbGluZSAxOQpsaW5lIDIwCmxpbmUgMjEKbGluZSAyMgpsaW5lIDIzCmxpbmUgMjQKbGluZSAyNQpsaW5lIDI2CmxpbmUgMjcKbGluZSAyOApsaW5lIDI5CmxpbmUgMzAKbGluZSAzMQpsaW5lIDMyCmxpbmUgMzMKbGluZSAzNApsaW5lIDM1CmxpbmUgMzYKbGluZSAzNwpsaW5lIDM4CmxpbmUgMzkKbGluZSA0MApsaW5lIDQxCmxpbmUgNDIKbGluZSA0MwpsaW5lIDQ0CmxpbmUgNDUKbGluZSA0NgpsaW5lIDQ3CmxpbmUgNDgKbGluZSA0OQpsaW5lIDUwCmxpbmUgNTEKbGluZSA1MgpsaW5lIDUzCmxpbmUgNTQKbGluZSA1NQpsaW5lIDU2CmxpbmUgNTcKbGluZSA1OApsaW5lIDU5CmxpbmUgNjAKbGluZSA2MQpsaW5lIDYyCmxpbmUgNjMKbGluZSA2NApsaW5lIDY1CmxpbmUgNjYKbGluZSA2NwpsaW5lIDY4CmxpbmUgNjkKbGluZSA3MApsaW5lIDcxCmxpbmUgNzIKbGluZSA3MwpsaW5lIDc0CmxpbmUgNzUKbGluZSA3NgpsaW5lIDc3CmxpbmUgNzgKbGluZSA3OQpsaW5lIDgwCmxpbmUgODEKbGluZSA4MgpsaW5lIDgzCmxpbmUgODQKbGluZSA4NQpsaW5lIDg2CmxpbmUgODcKbGluZSA4OApsaW5lIDg5CmxpbmUgOTAKbGluZSA5MQpsaW5lIDkyCmxpbmUgOTMKbGluZSA5NApsaW5lIDk1CmxpbmUgOTYKbGluZSA5NwpsaW5lIDk4CmxpbmUgOTkKbGluZSAxMDA=", "message": "add big file"}', in 0.73 seconds)1103machine: must succeed: curl -sf -X POST http://localhost:3000/api/v1/repos/testuser/testrepo/branch_protections -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"branch_name": "main", "enable_status_check": true, "status_check_contexts": ["ci/build"]}'1104machine # [ 26.154897] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/branch_protections for [::1]:37232, 201 Created in 17.0ms @ repo/branch.go:623(repo.CreateBranchProtection)1105machine: (finished: must succeed: curl -sf -X POST http://localhost:3000/api/v1/repos/testuser/testrepo/branch_protections -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"branch_name": "main", "enable_status_check": true, "status_check_contexts": ["ci/build"]}', in 0.07 seconds)1106machine: must succeed: echo -n '01aa4efccff126328d7bc4f9ccafec41c3f38f51' > /run/gitea-mq/token1107machine: (finished: must succeed: echo -n '01aa4efccff126328d7bc4f9ccafec41c3f38f51' > /run/gitea-mq/token, in 0.02 seconds)1108machine: must succeed: echo -n 'test-webhook-secret' > /run/gitea-mq/secret1109machine: (finished: must succeed: echo -n 'test-webhook-secret' > /run/gitea-mq/secret, in 0.02 seconds)1110machine # [ 26.239785] systemd[1]: Started gitea-mq merge queue for Gitea.1111machine: waiting for TCP port 8080 on localhost1112machine # [ 26.435617] gitea-mq-start[1176]: time=2026-09-03T03:43:24.319Z level=INFO msg="starting gitea-mq" listen=:8080 repos=[gitea:testuser/testrepo] gitea=true github=false poll_interval=5s idle_poll_interval=15m0s check_timeout=5m0s batch_max=11113machine # [ 26.440429] gitea-mq-start[1176]: time=2026-09-03T03:43:24.330Z level=DEBUG msg="connecting to database" connection_string="postgres:///gitea-mq?host=/run/postgresql"1114machine # [ 26.446864] gitea-mq-start[1176]: time=2026-09-03T03:43:24.336Z level=DEBUG msg="migrating database"1115machine # [ 26.490637] postgres[1188]: [1188] ERROR: relation "goose_db_version" does not exist at character 361116machine # [ 26.491895] postgres[1188]: [1188] STATEMENT: SELECT version_id, is_applied from goose_db_version ORDER BY id DESC1117machine # [ 26.524861] gitea-mq-start[1176]: time=2026-09-03T03:43:24.414Z level=INFO msg="OK 001_initial.sql (10.92ms)"1118machine # [ 26.529417] gitea-mq-start[1176]: time=2026-09-03T03:43:24.418Z level=INFO msg="OK 002_check_status_target_url.sql (3.06ms)"1119machine # [ 26.534287] gitea-mq-start[1176]: time=2026-09-03T03:43:24.423Z level=INFO msg="OK 003_add_forge.sql (4.84ms)"1120machine # [ 26.541408] gitea-mq-start[1176]: time=2026-09-03T03:43:24.430Z level=INFO msg="OK 004_batches.sql (7.08ms)"1121machine # [ 26.542766] gitea-mq-start[1176]: time=2026-09-03T03:43:24.430Z level=INFO msg="goose: successfully migrated database to version: 4"1122machine # [ 26.546307] gitea-mq-start[1176]: time=2026-09-03T03:43:24.435Z level=INFO msg="discovery: adding repo" repo=gitea:testuser/testrepo1123machine # [ 26.584411] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branch_protections?page=1&limit=50 for [::1]:37238, 200 OK in 12.3ms @ repo/branch.go:587(repo.ListBranchProtections)1124machine # [ 26.607435] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed PATCH /api/v1/repos/testuser/testrepo/branch_protections/main for [::1]:37238, 200 OK in 14.5ms @ repo/branch.go:841(repo.EditBranchProtection)1125machine # [ 26.609944] gitea-mq-start[1176]: time=2026-09-03T03:43:24.496Z level=INFO msg="added gitea-mq to required status checks" owner=testuser repo=testrepo rule=main1126machine # [ 26.622165] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/hooks?page=1&limit=50 for [::1]:37238, 200 OK in 9.7ms @ repo/hook.go:26(repo.ListHooks)1127machine # [ 26.636067] gitea-mq-start[1176]: time=2026-09-03T03:43:24.525Z level=INFO msg="created webhook" owner=testuser repo=testrepo url=http://localhost:8080/webhook/gitea1128machine # [ 26.638209] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/hooks for [::1]:37238, 201 Created in 10.6ms @ repo/hook.go:200(repo.CreateHook)1129machine # [ 26.675165] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branches?page=1&limit=50 for [::1]:37238, 200 OK in 30.0ms @ repo/branch.go:284(repo.ListBranches)1130machine # [ 26.677928] gitea-mq-start[1176]: time=2026-09-03T03:43:24.567Z level=INFO msg="startup merge branch cleanup" owner=testuser repo=testrepo active_branches=0 stale_deleted=01131machine # [ 26.681913] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed GET /api/forgejo/v1/version for [::1]:37238, 404 Not Found in 1.1ms @ <autogenerated>:1(WebNotFound)1132machine # [ 26.696251] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed GET /api/v1/version for [::1]:37248, 200 OK in 7.6ms @ misc/version.go:15(misc.Version)1133machine # [ 26.698871] gitea-mq-start[1176]: time=2026-09-03T03:43:24.588Z level=INFO msg="gitea: server capabilities detected" version=1.27.2 status_webhook=true1134machine # [ 26.703434] gitea-mq-start[1176]: time=2026-09-03T03:43:24.592Z level=INFO msg="poller started" owner=testuser repo=testrepo interval=5s idle_interval=15m0s idle_gating=true1135machine # [ 26.720425] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=1&limit=50 for [::1]:37248, 200 OK in 18.5ms @ repo/repo.go:47(repo.Search)1136machine # [ 26.725052] gitea-mq-start[1176]: time=2026-09-03T03:43:24.614Z level=INFO msg="discovery: reconciled" forge=gitea managed=01137machine # [ 26.729942] gitea-mq-start[1176]: time=2026-09-03T03:43:24.619Z level=INFO msg="HTTP server listening" addr=:80801138machine # [ 26.731613] gitea-mq-start[1176]: time=2026-09-03T03:43:24.620Z level=INFO msg="discovery loop started" interval=5s sources=11139machine # [ 26.745320] gitea[929]: 2026/09/03 03:43:24 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37252, 200 OK in 33.8ms @ repo/pull.go:51(repo.ListPullRequests)1140machine # Connection to localhost (::1) 8080 port [tcp/http-alt] succeeded!1141machine: (finished: waiting for TCP port 8080 on localhost, in 1.12 seconds)1142machine: must succeed: curl -sf http://localhost:8080/healthz1143machine: (finished: must succeed: curl -sf http://localhost:8080/healthz, in 0.06 seconds)1144??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1145 File "/nix/store/ri05iv8i720xw2qylqi7msjin73k6v9y-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391146machine: waiting for success: curl -sf http://localhost:3000/api/v1/repos/testuser/testrepo/branch_protections/main -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | grep -q gitea-mq1147??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1148 File "/nix/store/ri05iv8i720xw2qylqi7msjin73k6v9y-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391149machine # [ 27.492690] gitea[929]: 2026/09/03 03:43:25 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branch_protections/main for [::1]:37268, 200 OK in 11.9ms @ repo/branch.go:543(repo.GetBranchProtection)1150machine: (finished: waiting for success: curl -sf http://localhost:3000/api/v1/repos/testuser/testrepo/branch_protections/main -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | grep -q gitea-mq, in 0.08 seconds)1151machine: waiting for success: curl -sf http://localhost:3000/api/v1/repos/testuser/testrepo/hooks -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | grep -q localhost:80801152machine # [ 27.570173] gitea[929]: 2026/09/03 03:43:25 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/hooks for [::1]:37278, 200 OK in 9.7ms @ repo/hook.go:26(repo.ListHooks)1153machine: (finished: waiting for success: curl -sf http://localhost:3000/api/v1/repos/testuser/testrepo/hooks -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | grep -q localhost:8080, in 0.08 seconds)1154machine: must succeed: curl -sf http://localhost:8080/1155machine: (finished: must succeed: curl -sf http://localhost:8080/, in 0.06 seconds)1156machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/test.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"content": "dGVzdA==", "message": "add test file", "new_branch": "feature-1"}'1157machine # [ 28.007045] gitea[929]: 2026/09/03 03:43:25 HTTPRequest [I] router: completed POST /api/internal/hook/pre-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 4.2ms @ private/hook_pre_receive.go:110(private.HookPreReceive)1158machine # [ 28.278610] gitea[929]: 2026/09/03 03:43:26 HTTPRequest [I] router: completed POST /api/internal/hook/post-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 22.7ms @ private/hook_post_receive.go:101(private.HookPostReceive)1159machine # [ 28.325844] gitea[929]: 2026/09/03 03:43:26 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/contents/test.txt for [::1]:37288, 201 Created in 643.2ms @ repo/file.go:435(repo.CreateFile)1160machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/test.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"content": "dGVzdA==", "message": "add test file", "new_branch": "feature-1"}', in 0.70 seconds)1161machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"title": "Test PR", "head": "feature-1", "base": "main"}'1162machine # [ 29.096209] gitea[929]: 2026/09/03 03:43:26 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/pulls for [::1]:37304, 201 Created in 718.3ms @ repo/pull.go:371(repo.CreatePullRequest)1163machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"title": "Test PR", "head": "feature-1", "base": "main"}', in 0.77 seconds)1164machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/1/merge' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"Do": "merge", "merge_when_checks_succeed": true}'1165machine # [ 29.197920] gitea[929]: 2026/09/03 03:43:27 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/pulls/1/merge for [::1]:37312, 201 Created in 48.3ms @ repo/pull.go:885(repo.MergePullRequest)1166machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/1/merge' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"Do": "merge", "merge_when_checks_succeed": true}', in 0.10 seconds)1167machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}'1168machine # [ 29.317714] gitea[929]: 2026/09/03 03:43:27 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:37328, 201 Created in 63.7ms @ repo/status.go:21(repo.NewCommitStatus)1169machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}', in 0.12 seconds)1170machine: waiting for success: curl -sf http://localhost:8080/repo/testuser/testrepo | grep -q 'PR #1'1171machine # [ 29.386531] gitea[929]: 2026/09/03 03:43:27 .../automerge/automerge.go:237:handlePullRequestAutoMerge() [I] Scheduled auto merge <PullRequest [1]testuser/testrepo#1[main...feature-1]> has unsuccessful status checks1172machine # [ 29.472758] gitea[929]: 2026/09/03 03:43:27 .../automerge/automerge.go:237:handlePullRequestAutoMerge() [I] Scheduled auto merge <PullRequest [1]testuser/testrepo#1[main...feature-1]> has unsuccessful status checks1173machine # [ 31.372126] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37252, 200 OK in 53.3ms @ repo/pull.go:51(repo.ListPullRequests)1174machine # [ 31.392355] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=1&limit=50 for [::1]:37252, 200 OK in 14.4ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1175machine # [ 31.404416] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=2&limit=50 for [::1]:37252, 200 OK in 8.8ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1176machine # [ 31.417788] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branch_protections/main for [::1]:37252, 200 OK in 9.7ms @ repo/branch.go:543(repo.GetBranchProtection)1177machine # [ 31.458468] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96/status?page=1&limit=50 for [::1]:37252, 200 OK in 37.2ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1178machine # [ 31.465925] gitea-mq-start[1176]: time=2026-09-03T03:43:29.355Z level=INFO msg="enqueued PR" pr=1 position=11179machine # [ 31.514095] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:37252, 201 Created in 45.8ms @ repo/status.go:21(repo.NewCommitStatus)1180machine # [ 31.516927] gitea-mq-start[1176]: time=2026-09-03T03:43:29.403Z level=DEBUG msg="created commit status" owner=testuser repo=testrepo sha=1c8be034 context=gitea-mq state=pending1181machine # [ 31.519115] gitea-mq-start[1176]: time=2026-09-03T03:43:29.403Z level=INFO msg="enqueued PR from automerge detection" pr=1 position=11182machine: (finished: waiting for success: curl -sf http://localhost:8080/repo/testuser/testrepo | grep -q 'PR #1', in 2.25 seconds)1183machine: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/branches/gitea-mq/1' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.commit.id'1184machine # [ 31.640829] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branches/gitea-mq/1 for [::1]:37344, 404 Not Found in 12.8ms @ repo/branch.go:32(repo.GetBranch)1185machine # [ 31.671849] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /testuser/testrepo.git/info/refs?service=git-upload-pack for [::1]:37356, 200 OK in 17.6ms @ repo/githttp.go:455(repo.GetInfoRefs)1186machine # [ 31.693203] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed POST /testuser/testrepo.git/git-upload-pack for [::1]:37356, 200 OK in 17.4ms @ repo/githttp.go:433(repo.ServiceUploadPack)1187machine # [ 31.724550] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed POST /testuser/testrepo.git/git-upload-pack for [::1]:37356, 200 OK in 27.0ms @ repo/githttp.go:433(repo.ServiceUploadPack)1188machine # [ 31.744952] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=1&limit=50 for [::1]:37252, 200 OK in 10.3ms @ repo/repo.go:47(repo.Search)1189machine # [ 31.747121] gitea-mq-start[1176]: time=2026-09-03T03:43:29.634Z level=INFO msg="discovery: reconciled" forge=gitea managed=01190machine # [ 31.849546] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed GET /testuser/testrepo.git/info/refs?service=git-receive-pack for [::1]:37366, 200 OK in 18.7ms @ repo/githttp.go:455(repo.GetInfoRefs)1191machine # [ 32.056690] gitea[929]: 2026/09/03 03:43:29 HTTPRequest [I] router: completed POST /api/internal/hook/pre-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 3.6ms @ private/hook_pre_receive.go:110(private.HookPreReceive)1192machine # [ 32.342253] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed POST /api/internal/hook/post-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 21.9ms @ private/hook_post_receive.go:101(private.HookPostReceive)1193machine # [ 32.360794] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed POST /testuser/testrepo.git/git-receive-pack for [::1]:37366, 200 OK in 487.0ms @ repo/githttp.go:438(repo.ServiceReceivePack)1194machine # [ 32.382222] gitea-mq-start[1176]: time=2026-09-03T03:43:30.271Z level=DEBUG msg="created merge branch" branch=gitea-mq/1 sha=d5457c6e1195machine # [ 32.426613] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96/status?page=1&limit=50 for [::1]:37252, 200 OK in 37.2ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1196machine # [ 32.472405] gitea-mq-start[1176]: time=2026-09-03T03:43:30.361Z level=DEBUG msg="created commit status" owner=testuser repo=testrepo sha=1c8be034 context=gitea-mq state=pending1197machine # [ 32.474961] gitea-mq-start[1176]: time=2026-09-03T03:43:30.363Z level=INFO msg="started testing" pr=1 branch=gitea-mq/1 sha=d5457c6e5b264c00e21ac0eb7a69a650b949040e1198machine # [ 32.477078] gitea-mq-start[1176]: time=2026-09-03T03:43:30.363Z level=INFO msg="started testing for head-of-queue" pr=1 branch=gitea-mq/11199machine # [ 32.478802] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:37252, 201 Created in 45.9ms @ repo/status.go:21(repo.NewCommitStatus)1200machine # [ 32.517390] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/d5457c6e5b264c00e21ac0eb7a69a650b949040e/status?page=1&limit=50 for [::1]:37252, 200 OK in 39.6ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1201machine # [ 32.547089] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37252, 200 OK in 25.4ms @ repo/pull.go:51(repo.ListPullRequests)1202machine # [ 32.560418] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=1&limit=50 for [::1]:37252, 200 OK in 12.7ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1203machine # [ 32.571966] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=2&limit=50 for [::1]:37252, 200 OK in 11.4ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1204machine # [ 32.610813] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/d5457c6e5b264c00e21ac0eb7a69a650b949040e/status?page=1&limit=50 for [::1]:37252, 200 OK in 34.8ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1205machine # [ 32.734419] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branches/gitea-mq/1 for [::1]:37390, 200 OK in 26.7ms @ repo/branch.go:32(repo.GetBranch)1206machine: (finished: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/branches/gitea-mq/1' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.commit.id', in 1.17 seconds)1207machine: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/branches/gitea-mq/1' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51'1208machine # [ 32.820250] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branches/gitea-mq/1 for [::1]:59430, 200 OK in 27.6ms @ repo/branch.go:32(repo.GetBranch)1209machine: (finished: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/branches/gitea-mq/1' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51', in 0.08 seconds)1210machine: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.[] | select(.context == "gitea-mq" and .status == "pending")'1211machine # [ 32.897501] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:59432, 200 OK in 13.5ms @ repo/status.go:76(repo.GetCommitStatuses)1212machine: (finished: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.[] | select(.context == "gitea-mq" and .status == "pending")', in 0.08 seconds)1213machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/d5457c6e5b264c00e21ac0eb7a69a650b949040e' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}'1214machine # [ 33.004439] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/d5457c6e5b264c00e21ac0eb7a69a650b949040e for [::1]:59434, 201 Created in 50.4ms @ repo/status.go:21(repo.NewCommitStatus)1215machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/d5457c6e5b264c00e21ac0eb7a69a650b949040e' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}', in 0.10 seconds)1216machine: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.[] | select(.context == "gitea-mq" and .status == "success")'1217machine # [ 33.080949] gitea[929]: 2026/09/03 03:43:30 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:59448, 200 OK in 13.8ms @ repo/status.go:76(repo.GetCommitStatuses)1218machine # [ 33.292669] gitea-mq-start[1176]: time=2026-09-03T03:43:31.181Z level=DEBUG msg="created commit status" owner=testuser repo=testrepo sha=1c8be034 context=gitea-mq/ci/build state=success1219machine # [ 33.295781] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:37252, 201 Created in 73.1ms @ repo/status.go:21(repo.NewCommitStatus)1220machine # [ 33.313194] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branch_protections/main for [::1]:37252, 200 OK in 13.1ms @ repo/branch.go:543(repo.GetBranchProtection)1221machine # [ 33.316391] gitea-mq-start[1176]: time=2026-09-03T03:43:31.206Z level=INFO msg="all checks passed" pr=11222machine # [ 33.384652] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:37252, 201 Created in 66.0ms @ repo/status.go:21(repo.NewCommitStatus)1223machine # [ 33.387809] gitea-mq-start[1176]: time=2026-09-03T03:43:31.274Z level=DEBUG msg="created commit status" owner=testuser repo=testrepo sha=1c8be034 context=gitea-mq state=success1224machine # [ 33.436368] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96/status?page=1&limit=50 for [::1]:37252, 200 OK in 42.8ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1225machine # [ 33.520305] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed DELETE /api/v1/repos/testuser/testrepo/branches/gitea-mq/1 for [::1]:37252, 204 No Content in 84.0ms @ repo/branch.go:93(repo.DeleteBranch)1226machine # [ 33.561975] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37252, 200 OK in 30.5ms @ repo/pull.go:51(repo.ListPullRequests)1227machine # [ 33.569203] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=1&limit=50 for [::1]:37252, 200 OK in 12.5ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1228machine # [ 33.581562] gitea[929]: 2026/09/03 03:43:31 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=2&limit=50 for [::1]:37252, 200 OK in 9.3ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1229machine # [ 34.080088] gitea[929]: 2026/09/03 03:43:31 .../actions/notifier_helper.go:115:(*notifyInput).Notify() [E] an error occurred while executing the CreateRef actions method: gitRepo.GetRefCommitID: object does not exist [id: refs/heads/gitea-mq/1, rel_path: ]1230machine # [ 34.137625] gitea[929]: 2026/09/03 03:43:32 .../actions/notifier_helper.go:115:(*notifyInput).Notify() [E] an error occurred while executing the PushCommits actions method: gitRepo.GetRefCommitID: object does not exist [id: refs/heads/gitea-mq/1, rel_path: ]1231machine # [ 34.141694] gitea[929]: 2026/09/03 03:43:32 services/repository/push.go:210:pushQueueHandleUpdates() [E] repo_module.CacheRef %!s(int64=1)/gitea-mq/1 failed: object does not exist [id: refs/heads/gitea-mq/1, rel_path: ]1232machine # [ 34.186310] gitea[929]: 2026/09/03 03:43:32 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:59452, 200 OK in 22.0ms @ repo/status.go:76(repo.GetCommitStatuses)1233machine: (finished: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.[] | select(.context == "gitea-mq" and .status == "success")', in 1.19 seconds)1234machine: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.[] | select(.context == "gitea-mq/ci/build" and .status == "success" and .description == "build passed")'1235machine # [ 34.266160] gitea[929]: 2026/09/03 03:43:32 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:59460, 200 OK in 15.2ms @ repo/status.go:76(repo.GetCommitStatuses)1236machine: (finished: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.[] | select(.context == "gitea-mq/ci/build" and .status == "success" and .description == "build passed")', in 0.08 seconds)1237machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}'1238machine # [ 34.374484] gitea[929]: 2026/09/03 03:43:32 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96 for [::1]:59470, 201 Created in 50.8ms @ repo/status.go:21(repo.NewCommitStatus)1239machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/1c8be034bd2dea7b14eb85a0eaa7b57d2d043b96' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}', in 0.11 seconds)1240machine: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/1' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.merged == true'1241machine # [ 34.491389] gitea[929]: 2026/09/03 03:43:32 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls/1 for [::1]:59480, 200 OK in 57.1ms @ repo/pull.go:164(repo.GetPullRequest)1242machine # [ 35.147128] gitea[929]: 2026/09/03 03:43:33 HTTPRequest [I] router: completed POST /api/internal/hook/pre-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 16.6ms @ private/hook_pre_receive.go:110(private.HookPreReceive)1243machine # [ 35.155270] gitea[929]: 2026/09/03 03:43:33 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37252, 200 OK in 41.2ms @ repo/pull.go:51(repo.ListPullRequests)1244machine # [ 35.170719] gitea[929]: 2026/09/03 03:43:33 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=1&limit=50 for [::1]:37252, 200 OK in 12.5ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1245machine # [ 35.183113] gitea[929]: 2026/09/03 03:43:33 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=2&limit=50 for [::1]:37252, 200 OK in 9.3ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1246machine # [ 35.452858] gitea[929]: 2026/09/03 03:43:33 HTTPRequest [I] router: completed POST /api/internal/hook/post-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 35.6ms @ private/hook_post_receive.go:101(private.HookPostReceive)1247machine # [ 35.625428] gitea[929]: 2026/09/03 03:43:33 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls/1 for [::1]:59514, 200 OK in 64.6ms @ repo/pull.go:164(repo.GetPullRequest)1248machine: (finished: waiting for success: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/1' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' | jq -e '.merged == true', in 1.25 seconds)1249machine: waiting for success: ! curl -sf http://localhost:8080/repo/testuser/testrepo | grep -q 'PR #1'1250machine # [ 36.765542] gitea[929]: 2026/09/03 03:43:34 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=1&limit=50 for [::1]:37252, 200 OK in 30.1ms @ repo/repo.go:47(repo.Search)1251machine # [ 36.768578] gitea-mq-start[1176]: time=2026-09-03T03:43:34.654Z level=INFO msg="discovery: reconciled" forge=gitea managed=01252machine # [ 36.775714] gitea[929]: 2026/09/03 03:43:34 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37248, 200 OK in 20.8ms @ repo/pull.go:51(repo.ListPullRequests)1253machine # [ 36.844480] gitea[929]: 2026/09/03 03:43:34 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls/1 for [::1]:37248, 200 OK in 65.5ms @ repo/pull.go:164(repo.GetPullRequest)1254machine # [ 36.857283] gitea[929]: 2026/09/03 03:43:34 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=1&limit=50 for [::1]:37248, 200 OK in 9.9ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1255machine # [ 36.869223] gitea[929]: 2026/09/03 03:43:34 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/1/timeline?page=2&limit=50 for [::1]:37248, 200 OK in 9.1ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1256machine # [ 36.874429] gitea-mq-start[1176]: time=2026-09-03T03:43:34.764Z level=INFO msg="dequeued PR" pr=1 was_head=true1257machine # [ 36.902963] gitea-mq-start[1176]: time=2026-09-03T03:43:34.792Z level=DEBUG msg="branch already deleted" owner=testuser repo=testrepo branch=gitea-mq/11258machine # [ 36.904611] gitea-mq-start[1176]: time=2026-09-03T03:43:34.792Z level=INFO msg="removed merged PR from queue" pr=11259machine # [ 36.905852] gitea[929]: 2026/09/03 03:43:34 HTTPRequest [I] router: completed DELETE /api/v1/repos/testuser/testrepo/branches/gitea-mq/1 for [::1]:37248, 404 Not Found in 26.4ms @ repo/branch.go:93(repo.DeleteBranch)1260machine: (finished: waiting for success: ! curl -sf http://localhost:8080/repo/testuser/testrepo | grep -q 'PR #1', in 2.26 seconds)1261machine: must succeed: su -l gitea -s /bin/sh -c 'export HOME=/var/lib/gitea GIT_CONFIG_NOSYSTEM=1; /nix/store/8wxs6573l730vxkqd6wp58kvxa19csll-git-2.55.0/bin/git config --global --get uploadpack.hideRefs | grep -q refs/heads/gitea-mq/'1262machine # [ 37.938381] su[1778]: Successful su for gitea by root1263machine # [ 37.950077] su[1778]: pam_unix(su:session): session opened for user gitea(uid=998) by (uid=0)1264machine # [ 37.981604] su[1778]: pam_unix(su:session): session closed for user gitea1265machine: (finished: must succeed: su -l gitea -s /bin/sh -c 'export HOME=/var/lib/gitea GIT_CONFIG_NOSYSTEM=1; /nix/store/8wxs6573l730vxkqd6wp58kvxa19csll-git-2.55.0/bin/git config --global --get uploadpack.hideRefs | grep -q refs/heads/gitea-mq/', in 0.09 seconds)1266machine: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/big.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51'1267machine # [ 38.068164] gitea[929]: 2026/09/03 03:43:35 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/contents/big.txt for [::1]:59516, 200 OK in 35.2ms @ repo/file.go:774(repo.GetContents)1268machine: (finished: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/big.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51', in 0.09 seconds)1269machine: must succeed: curl -sf -X PUT 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/big.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"content": "bW9kaWZpZWQgbGluZSAxCm1vZGlmaWVkIGxpbmUgMgptb2RpZmllZCBsaW5lIDMKbW9kaWZpZWQgbGluZSA0Cm1vZGlmaWVkIGxpbmUgNQptb2RpZmllZCBsaW5lIDYKbW9kaWZpZWQgbGluZSA3Cm1vZGlmaWVkIGxpbmUgOAptb2RpZmllZCBsaW5lIDkKbW9kaWZpZWQgbGluZSAxMAptb2RpZmllZCBsaW5lIDExCm1vZGlmaWVkIGxpbmUgMTIKbW9kaWZpZWQgbGluZSAxMwptb2RpZmllZCBsaW5lIDE0Cm1vZGlmaWVkIGxpbmUgMTUKbW9kaWZpZWQgbGluZSAxNgptb2RpZmllZCBsaW5lIDE3Cm1vZGlmaWVkIGxpbmUgMTgKbW9kaWZpZWQgbGluZSAxOQptb2RpZmllZCBsaW5lIDIwCm1vZGlmaWVkIGxpbmUgMjEKbW9kaWZpZWQgbGluZSAyMgptb2RpZmllZCBsaW5lIDIzCm1vZGlmaWVkIGxpbmUgMjQKbW9kaWZpZWQgbGluZSAyNQptb2RpZmllZCBsaW5lIDI2Cm1vZGlmaWVkIGxpbmUgMjcKbW9kaWZpZWQgbGluZSAyOAptb2RpZmllZCBsaW5lIDI5Cm1vZGlmaWVkIGxpbmUgMzAKbW9kaWZpZWQgbGluZSAzMQptb2RpZmllZCBsaW5lIDMyCm1vZGlmaWVkIGxpbmUgMzMKbW9kaWZpZWQgbGluZSAzNAptb2RpZmllZCBsaW5lIDM1Cm1vZGlmaWVkIGxpbmUgMzYKbW9kaWZpZWQgbGluZSAzNwptb2RpZmllZCBsaW5lIDM4Cm1vZGlmaWVkIGxpbmUgMzkKbW9kaWZpZWQgbGluZSA0MAptb2RpZmllZCBsaW5lIDQxCm1vZGlmaWVkIGxpbmUgNDIKbW9kaWZpZWQgbGluZSA0Mwptb2RpZmllZCBsaW5lIDQ0Cm1vZGlmaWVkIGxpbmUgNDUKbW9kaWZpZWQgbGluZSA0Ngptb2RpZmllZCBsaW5lIDQ3Cm1vZGlmaWVkIGxpbmUgNDgKbW9kaWZpZWQgbGluZSA0OQptb2RpZmllZCBsaW5lIDUwCm1vZGlmaWVkIGxpbmUgNTEKbW9kaWZpZWQgbGluZSA1Mgptb2RpZmllZCBsaW5lIDUzCm1vZGlmaWVkIGxpbmUgNTQKbW9kaWZpZWQgbGluZSA1NQptb2RpZmllZCBsaW5lIDU2Cm1vZGlmaWVkIGxpbmUgNTcKbW9kaWZpZWQgbGluZSA1OAptb2RpZmllZCBsaW5lIDU5Cm1vZGlmaWVkIGxpbmUgNjAKbW9kaWZpZWQgbGluZSA2MQptb2RpZmllZCBsaW5lIDYyCm1vZGlmaWVkIGxpbmUgNjMKbW9kaWZpZWQgbGluZSA2NAptb2RpZmllZCBsaW5lIDY1Cm1vZGlmaWVkIGxpbmUgNjYKbW9kaWZpZWQgbGluZSA2Nwptb2RpZmllZCBsaW5lIDY4Cm1vZGlmaWVkIGxpbmUgNjkKbW9kaWZpZWQgbGluZSA3MAptb2RpZmllZCBsaW5lIDcxCm1vZGlmaWVkIGxpbmUgNzIKbW9kaWZpZWQgbGluZSA3Mwptb2RpZmllZCBsaW5lIDc0Cm1vZGlmaWVkIGxpbmUgNzUKbW9kaWZpZWQgbGluZSA3Ngptb2RpZmllZCBsaW5lIDc3Cm1vZGlmaWVkIGxpbmUgNzgKbW9kaWZpZWQgbGluZSA3OQptb2RpZmllZCBsaW5lIDgwCm1vZGlmaWVkIGxpbmUgODEKbW9kaWZpZWQgbGluZSA4Mgptb2RpZmllZCBsaW5lIDgzCm1vZGlmaWVkIGxpbmUgODQKbW9kaWZpZWQgbGluZSA4NQptb2RpZmllZCBsaW5lIDg2Cm1vZGlmaWVkIGxpbmUgODcKbW9kaWZpZWQgbGluZSA4OAptb2RpZmllZCBsaW5lIDg5Cm1vZGlmaWVkIGxpbmUgOTAKbW9kaWZpZWQgbGluZSA5MQptb2RpZmllZCBsaW5lIDkyCm1vZGlmaWVkIGxpbmUgOTMKbW9kaWZpZWQgbGluZSA5NAptb2RpZmllZCBsaW5lIDk1Cm1vZGlmaWVkIGxpbmUgOTYKbW9kaWZpZWQgbGluZSA5Nwptb2RpZmllZCBsaW5lIDk4Cm1vZGlmaWVkIGxpbmUgOTkKbW9kaWZpZWQgbGluZSAxMDA=", "message": "modify big file", "sha": "9240480b2bc35daf96c845dec28132f0cd67acaa", "new_branch": "long-timeline"}'1270machine # [ 38.446772] gitea[929]: 2026/09/03 03:43:36 HTTPRequest [I] router: completed POST /api/internal/hook/pre-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 3.2ms @ private/hook_pre_receive.go:110(private.HookPreReceive)1271machine # [ 38.735184] gitea[929]: 2026/09/03 03:43:36 HTTPRequest [I] router: completed POST /api/internal/hook/post-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 24.5ms @ private/hook_post_receive.go:101(private.HookPostReceive)1272machine # [ 38.785446] gitea[929]: 2026/09/03 03:43:36 HTTPRequest [I] router: completed PUT /api/v1/repos/testuser/testrepo/contents/big.txt for [::1]:59518, 200 OK in 659.0ms @ repo/file.go:504(repo.UpdateFile)1273machine: (finished: must succeed: curl -sf -X PUT 'http://localhost:3000/api/v1/repos/testuser/testrepo/contents/big.txt' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"content": "bW9kaWZpZWQgbGluZSAxCm1vZGlmaWVkIGxpbmUgMgptb2RpZmllZCBsaW5lIDMKbW9kaWZpZWQgbGluZSA0Cm1vZGlmaWVkIGxpbmUgNQptb2RpZmllZCBsaW5lIDYKbW9kaWZpZWQgbGluZSA3Cm1vZGlmaWVkIGxpbmUgOAptb2RpZmllZCBsaW5lIDkKbW9kaWZpZWQgbGluZSAxMAptb2RpZmllZCBsaW5lIDExCm1vZGlmaWVkIGxpbmUgMTIKbW9kaWZpZWQgbGluZSAxMwptb2RpZmllZCBsaW5lIDE0Cm1vZGlmaWVkIGxpbmUgMTUKbW9kaWZpZWQgbGluZSAxNgptb2RpZmllZCBsaW5lIDE3Cm1vZGlmaWVkIGxpbmUgMTgKbW9kaWZpZWQgbGluZSAxOQptb2RpZmllZCBsaW5lIDIwCm1vZGlmaWVkIGxpbmUgMjEKbW9kaWZpZWQgbGluZSAyMgptb2RpZmllZCBsaW5lIDIzCm1vZGlmaWVkIGxpbmUgMjQKbW9kaWZpZWQgbGluZSAyNQptb2RpZmllZCBsaW5lIDI2Cm1vZGlmaWVkIGxpbmUgMjcKbW9kaWZpZWQgbGluZSAyOAptb2RpZmllZCBsaW5lIDI5Cm1vZGlmaWVkIGxpbmUgMzAKbW9kaWZpZWQgbGluZSAzMQptb2RpZmllZCBsaW5lIDMyCm1vZGlmaWVkIGxpbmUgMzMKbW9kaWZpZWQgbGluZSAzNAptb2RpZmllZCBsaW5lIDM1Cm1vZGlmaWVkIGxpbmUgMzYKbW9kaWZpZWQgbGluZSAzNwptb2RpZmllZCBsaW5lIDM4Cm1vZGlmaWVkIGxpbmUgMzkKbW9kaWZpZWQgbGluZSA0MAptb2RpZmllZCBsaW5lIDQxCm1vZGlmaWVkIGxpbmUgNDIKbW9kaWZpZWQgbGluZSA0Mwptb2RpZmllZCBsaW5lIDQ0Cm1vZGlmaWVkIGxpbmUgNDUKbW9kaWZpZWQgbGluZSA0Ngptb2RpZmllZCBsaW5lIDQ3Cm1vZGlmaWVkIGxpbmUgNDgKbW9kaWZpZWQgbGluZSA0OQptb2RpZmllZCBsaW5lIDUwCm1vZGlmaWVkIGxpbmUgNTEKbW9kaWZpZWQgbGluZSA1Mgptb2RpZmllZCBsaW5lIDUzCm1vZGlmaWVkIGxpbmUgNTQKbW9kaWZpZWQgbGluZSA1NQptb2RpZmllZCBsaW5lIDU2Cm1vZGlmaWVkIGxpbmUgNTcKbW9kaWZpZWQgbGluZSA1OAptb2RpZmllZCBsaW5lIDU5Cm1vZGlmaWVkIGxpbmUgNjAKbW9kaWZpZWQgbGluZSA2MQptb2RpZmllZCBsaW5lIDYyCm1vZGlmaWVkIGxpbmUgNjMKbW9kaWZpZWQgbGluZSA2NAptb2RpZmllZCBsaW5lIDY1Cm1vZGlmaWVkIGxpbmUgNjYKbW9kaWZpZWQgbGluZSA2Nwptb2RpZmllZCBsaW5lIDY4Cm1vZGlmaWVkIGxpbmUgNjkKbW9kaWZpZWQgbGluZSA3MAptb2RpZmllZCBsaW5lIDcxCm1vZGlmaWVkIGxpbmUgNzIKbW9kaWZpZWQgbGluZSA3Mwptb2RpZmllZCBsaW5lIDc0Cm1vZGlmaWVkIGxpbmUgNzUKbW9kaWZpZWQgbGluZSA3Ngptb2RpZmllZCBsaW5lIDc3Cm1vZGlmaWVkIGxpbmUgNzgKbW9kaWZpZWQgbGluZSA3OQptb2RpZmllZCBsaW5lIDgwCm1vZGlmaWVkIGxpbmUgODEKbW9kaWZpZWQgbGluZSA4Mgptb2RpZmllZCBsaW5lIDgzCm1vZGlmaWVkIGxpbmUgODQKbW9kaWZpZWQgbGluZSA4NQptb2RpZmllZCBsaW5lIDg2Cm1vZGlmaWVkIGxpbmUgODcKbW9kaWZpZWQgbGluZSA4OAptb2RpZmllZCBsaW5lIDg5Cm1vZGlmaWVkIGxpbmUgOTAKbW9kaWZpZWQgbGluZSA5MQptb2RpZmllZCBsaW5lIDkyCm1vZGlmaWVkIGxpbmUgOTMKbW9kaWZpZWQgbGluZSA5NAptb2RpZmllZCBsaW5lIDk1Cm1vZGlmaWVkIGxpbmUgOTYKbW9kaWZpZWQgbGluZSA5Nwptb2RpZmllZCBsaW5lIDk4Cm1vZGlmaWVkIGxpbmUgOTkKbW9kaWZpZWQgbGluZSAxMDA=", "message": "modify big file", "sha": "9240480b2bc35daf96c845dec28132f0cd67acaa", "new_branch": "long-timeline"}', in 0.72 seconds)1274machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"title": "Long timeline PR", "head": "long-timeline", "base": "main"}'1275machine # [ 39.552635] gitea[929]: 2026/09/03 03:43:37 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/pulls for [::1]:59540, 201 Created in 712.0ms @ repo/pull.go:371(repo.CreatePullRequest)1276machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"title": "Long timeline PR", "head": "long-timeline", "base": "main"}', in 0.77 seconds)1277machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/2/reviews' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"event": "COMMENT", "body": "Code review with many inline comments", "commit_id": "e35d3c9362587675ef312894359102f887827552", "comments": [{"path": "big.txt", "body": "review comment on line 1", "new_position": 1, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 2", "new_position": 2, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 3", "new_position": 3, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 4", "new_position": 4, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 5", "new_position": 5, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 6", "new_position": 6, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 7", "new_position": 7, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 8", "new_position": 8, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 9", "new_position": 9, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 10", "new_position": 10, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 11", "new_position": 11, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 12", "new_position": 12, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 13", "new_position": 13, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 14", "new_position": 14, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 15", "new_position": 15, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 16", "new_position": 16, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 17", "new_position": 17, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 18", "new_position": 18, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 19", "new_position": 19, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 20", "new_position": 20, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 21", "new_position": 21, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 22", "new_position": 22, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 23", "new_position": 23, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 24", "new_position": 24, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 25", "new_position": 25, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 26", "new_position": 26, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 27", "new_position": 27, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 28", "new_position": 28, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 29", "new_position": 29, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 30", "new_position": 30, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 31", "new_position": 31, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 32", "new_position": 32, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 33", "new_position": 33, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 34", "new_position": 34, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 35", "new_position": 35, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 36", "new_position": 36, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 37", "new_position": 37, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 38", "new_position": 38, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 39", "new_position": 39, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 40", "new_position": 40, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 41", "new_position": 41, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 42", "new_position": 42, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 43", "new_position": 43, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 44", "new_position": 44, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 45", "new_position": 45, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 46", "new_position": 46, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 47", "new_position": 47, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 48", "new_position": 48, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 49", "new_position": 49, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 50", "new_position": 50, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 51", "new_position": 51, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 52", "new_position": 52, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 53", "new_position": 53, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 54", "new_position": 54, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 55", "new_position": 55, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 56", "new_position": 56, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 57", "new_position": 57, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 58", "new_position": 58, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 59", "new_position": 59, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 60", "new_position": 60, "old_position": 0}]}'1278machine # [ 41.194120] gitea[929]: 2026/09/03 03:43:39 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/pulls/2/reviews for [::1]:59550, 200 OK in 1576.4ms @ repo/pull_review.go:466(repo.CreatePullReview)1279machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/2/reviews' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"event": "COMMENT", "body": "Code review with many inline comments", "commit_id": "e35d3c9362587675ef312894359102f887827552", "comments": [{"path": "big.txt", "body": "review comment on line 1", "new_position": 1, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 2", "new_position": 2, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 3", "new_position": 3, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 4", "new_position": 4, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 5", "new_position": 5, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 6", "new_position": 6, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 7", "new_position": 7, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 8", "new_position": 8, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 9", "new_position": 9, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 10", "new_position": 10, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 11", "new_position": 11, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 12", "new_position": 12, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 13", "new_position": 13, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 14", "new_position": 14, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 15", "new_position": 15, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 16", "new_position": 16, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 17", "new_position": 17, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 18", "new_position": 18, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 19", "new_position": 19, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 20", "new_position": 20, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 21", "new_position": 21, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 22", "new_position": 22, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 23", "new_position": 23, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 24", "new_position": 24, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 25", "new_position": 25, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 26", "new_position": 26, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 27", "new_position": 27, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 28", "new_position": 28, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 29", "new_position": 29, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 30", "new_position": 30, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 31", "new_position": 31, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 32", "new_position": 32, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 33", "new_position": 33, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 34", "new_position": 34, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 35", "new_position": 35, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 36", "new_position": 36, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 37", "new_position": 37, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 38", "new_position": 38, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 39", "new_position": 39, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 40", "new_position": 40, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 41", "new_position": 41, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 42", "new_position": 42, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 43", "new_position": 43, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 44", "new_position": 44, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 45", "new_position": 45, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 46", "new_position": 46, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 47", "new_position": 47, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 48", "new_position": 48, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 49", "new_position": 49, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 50", "new_position": 50, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 51", "new_position": 51, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 52", "new_position": 52, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 53", "new_position": 53, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 54", "new_position": 54, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 55", "new_position": 55, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 56", "new_position": 56, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 57", "new_position": 57, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 58", "new_position": 58, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 59", "new_position": 59, "old_position": 0}, {"path": "big.txt", "body": "review comment on line 60", "new_position": 60, "old_position": 0}]}', in 1.64 seconds)1280machine: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/issues/2/timeline' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51'1281machine # [ 41.261575] gitea[929]: 2026/09/03 03:43:39 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline for [::1]:59552, 200 OK in 11.1ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1282machine: (finished: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/issues/2/timeline' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51', in 0.07 seconds)1283machine: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/issues/2/timeline?page=1&limit=50' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51'1284machine # [ 41.328220] gitea[929]: 2026/09/03 03:43:39 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=1&limit=50 for [::1]:59562, 200 OK in 11.4ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1285machine: (finished: must succeed: curl -sf 'http://localhost:3000/api/v1/repos/testuser/testrepo/issues/2/timeline?page=1&limit=50' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51', in 0.07 seconds)1286machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/2/merge' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"Do": "merge", "merge_when_checks_succeed": true}'1287machine # [ 41.426519] gitea[929]: 2026/09/03 03:43:39 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/pulls/2/merge for [::1]:59570, 201 Created in 45.3ms @ repo/pull.go:885(repo.MergePullRequest)1288machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/pulls/2/merge' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"Do": "merge", "merge_when_checks_succeed": true}', in 0.10 seconds)1289machine: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/e35d3c9362587675ef312894359102f887827552' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}'1290machine # [ 41.531389] gitea[929]: 2026/09/03 03:43:39 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/e35d3c9362587675ef312894359102f887827552 for [::1]:59580, 201 Created in 52.2ms @ repo/status.go:21(repo.NewCommitStatus)1291machine: (finished: must succeed: curl -sf -X POST 'http://localhost:3000/api/v1/repos/testuser/testrepo/statuses/e35d3c9362587675ef312894359102f887827552' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"context": "ci/build", "state": "success", "description": "build passed"}', in 0.10 seconds)1292machine: waiting for success: curl -sf http://localhost:8080/repo/testuser/testrepo | grep -q 'PR #2'1293machine # [ 41.760872] gitea-mq-start[1176]: time=2026-09-03T03:43:39.649Z level=INFO msg="discovery: reconciled" forge=gitea managed=01294machine # [ 41.765426] gitea[929]: 2026/09/03 03:43:39 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=1&limit=50 for [::1]:37248, 200 OK in 23.5ms @ repo/repo.go:47(repo.Search)1295machine # [ 41.802958] gitea[929]: 2026/09/03 03:43:39 .../automerge/automerge.go:237:handlePullRequestAutoMerge() [I] Scheduled auto merge <PullRequest [2]testuser/testrepo#2[main...long-timeline]> has unsuccessful status checks1296machine # [ 42.340312] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37248, 200 OK in 54.9ms @ repo/pull.go:51(repo.ListPullRequests)1297machine # [ 42.356981] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=1&limit=50 for [::1]:37248, 200 OK in 20.0ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1298machine # [ 42.369882] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=2&limit=50 for [::1]:37248, 200 OK in 9.5ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1299machine # [ 42.382053] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=3&limit=50 for [::1]:37248, 200 OK in 11.7ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1300machine # [ 42.395085] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/branch_protections/main for [::1]:37248, 200 OK in 9.9ms @ repo/branch.go:543(repo.GetBranchProtection)1301machine # [ 42.436160] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/e35d3c9362587675ef312894359102f887827552/status?page=1&limit=50 for [::1]:37248, 200 OK in 38.0ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1302machine # [ 42.441463] gitea-mq-start[1176]: time=2026-09-03T03:43:40.330Z level=INFO msg="enqueued PR" pr=2 position=11303machine # [ 42.485265] gitea-mq-start[1176]: time=2026-09-03T03:43:40.374Z level=DEBUG msg="created commit status" owner=testuser repo=testrepo sha=e35d3c93 context=gitea-mq state=pending1304machine # [ 42.487655] gitea-mq-start[1176]: time=2026-09-03T03:43:40.374Z level=INFO msg="enqueued PR from automerge detection" pr=2 position=11305machine # [ 42.489535] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/e35d3c9362587675ef312894359102f887827552 for [::1]:37248, 201 Created in 41.9ms @ repo/status.go:21(repo.NewCommitStatus)1306machine # [ 42.567746] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /testuser/testrepo.git/info/refs?service=git-upload-pack for [::1]:59596, 200 OK in 17.9ms @ repo/githttp.go:455(repo.GetInfoRefs)1307machine # [ 42.590117] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed POST /testuser/testrepo.git/git-upload-pack for [::1]:59596, 200 OK in 17.9ms @ repo/githttp.go:433(repo.ServiceUploadPack)1308machine # [ 42.623027] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed POST /testuser/testrepo.git/git-upload-pack for [::1]:59596, 200 OK in 27.3ms @ repo/githttp.go:433(repo.ServiceUploadPack)1309machine: (finished: waiting for success: curl -sf http://localhost:8080/repo/testuser/testrepo | grep -q 'PR #2', in 1.14 seconds)1310machine: must succeed: curl -sf -X POST http://localhost:3000/api/v1/user/repos -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"name": "discovered-repo", "auto_init": true, "default_branch": "main"}'1311machine # [ 42.740725] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed GET /testuser/testrepo.git/info/refs?service=git-receive-pack for [::1]:59614, 200 OK in 22.1ms @ repo/githttp.go:455(repo.GetInfoRefs)1312machine # [ 43.043443] gitea[929]: 2026/09/03 03:43:40 HTTPRequest [I] router: completed POST /api/internal/hook/pre-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 7.9ms @ private/hook_pre_receive.go:110(private.HookPreReceive)1313machine # [ 43.471515] gitea[929]: 2026/09/03 03:43:41 HTTPRequest [I] router: completed POST /api/internal/hook/post-receive/testuser/testrepo for 127.0.0.1:0, 200 OK in 31.5ms @ private/hook_post_receive.go:101(private.HookPostReceive)1314machine # [ 43.498737] gitea[929]: 2026/09/03 03:43:41 HTTPRequest [I] router: completed POST /testuser/testrepo.git/git-receive-pack for [::1]:59614, 200 OK in 733.5ms @ repo/githttp.go:438(repo.ServiceReceivePack)1315machine # [ 43.520533] gitea-mq-start[1176]: time=2026-09-03T03:43:41.409Z level=DEBUG msg="created merge branch" branch=gitea-mq/2 sha=2bcd7aa81316machine # [ 43.572608] gitea[929]: 2026/09/03 03:43:41 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/e35d3c9362587675ef312894359102f887827552/status?page=1&limit=50 for [::1]:37248, 200 OK in 46.0ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1317machine # [ 43.628583] gitea[929]: 2026/09/03 03:43:41 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/testrepo/statuses/e35d3c9362587675ef312894359102f887827552 for [::1]:37248, 201 Created in 51.8ms @ repo/status.go:21(repo.NewCommitStatus)1318machine # [ 43.631535] gitea-mq-start[1176]: time=2026-09-03T03:43:41.518Z level=DEBUG msg="created commit status" owner=testuser repo=testrepo sha=e35d3c93 context=gitea-mq state=pending1319machine # [ 43.634470] gitea-mq-start[1176]: time=2026-09-03T03:43:41.518Z level=INFO msg="started testing" pr=2 branch=gitea-mq/2 sha=2bcd7aa86d37d3fdfca163cb2d577385ee3f77c61320machine # [ 43.636509] gitea-mq-start[1176]: time=2026-09-03T03:43:41.518Z level=INFO msg="started testing for head-of-queue" pr=2 branch=gitea-mq/21321machine # [ 43.653173] gitea[929]: 2026/09/03 03:43:41 .../actions/notifier_helper.go:166:notify() [W] Ref of event "repository" is empty, will fall back to the default branch1322machine # [ 43.674490] gitea[929]: 2026/09/03 03:43:41 HTTPRequest [I] router: completed POST /api/v1/user/repos for [::1]:59598, 201 Created in 957.2ms @ repo/repo.go:280(repo.Create)1323machine # [ 43.682170] gitea[929]: 2026/09/03 03:43:41 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/2bcd7aa86d37d3fdfca163cb2d577385ee3f77c6/status?page=1&limit=50 for [::1]:37248, 200 OK in 43.5ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1324machine: (finished: must succeed: curl -sf -X POST http://localhost:3000/api/v1/user/repos -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"name": "discovered-repo", "auto_init": true, "default_branch": "main"}', in 1.01 seconds)1325machine: must succeed: curl -sf -X PUT 'http://localhost:3000/api/v1/repos/testuser/discovered-repo/topics/merge-queue' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51'1326machine # [ 43.744134] gitea[929]: 2026/09/03 03:43:41 HTTPRequest [I] router: completed PUT /api/v1/repos/testuser/discovered-repo/topics/merge-queue for [::1]:42438, 204 No Content in 13.5ms @ repo/topic.go:135(repo.AddTopic)1327machine: (finished: must succeed: curl -sf -X PUT 'http://localhost:3000/api/v1/repos/testuser/discovered-repo/topics/merge-queue' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51', in 0.06 seconds)1328machine: waiting for success: curl -sf http://localhost:8080/ | grep -q 'testuser/discovered-repo'1329machine # [ 46.790207] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=1&limit=50 for [::1]:37248, 200 OK in 48.8ms @ repo/repo.go:47(repo.Search)1330machine # [ 46.797201] gitea-mq-start[1176]: time=2026-09-03T03:43:44.686Z level=INFO msg="discovery: adding repo" repo=gitea:testuser/discovered-repo1331machine # [ 46.799328] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=2&limit=50 for [::1]:37248, 200 OK in 12.0ms @ repo/repo.go:47(repo.Search)1332machine # [ 46.813996] gitea-mq-start[1176]: time=2026-09-03T03:43:44.703Z level=WARN msg="no branch protection rules found, gitea-mq requires branch protection with status checks" owner=testuser repo=discovered-repo1333machine # [ 46.816388] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/discovered-repo/branch_protections?page=1&limit=50 for [::1]:37248, 200 OK in 16.9ms @ repo/branch.go:587(repo.ListBranchProtections)1334machine # [ 46.827256] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37252, 200 OK in 75.1ms @ repo/pull.go:51(repo.ListPullRequests)1335machine # [ 46.840190] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/discovered-repo/hooks?page=1&limit=50 for [::1]:37248, 200 OK in 20.1ms @ repo/hook.go:26(repo.ListHooks)1336machine # [ 46.850706] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=1&limit=50 for [::1]:37252, 200 OK in 19.2ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1337machine # [ 46.863046] gitea-mq-start[1176]: time=2026-09-03T03:43:44.752Z level=INFO msg="created webhook" owner=testuser repo=discovered-repo url=http://localhost:8080/webhook/gitea1338machine # [ 46.864880] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed POST /api/v1/repos/testuser/discovered-repo/hooks for [::1]:37248, 201 Created in 22.7ms @ repo/hook.go:200(repo.CreateHook)1339machine # [ 46.876511] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=2&limit=50 for [::1]:37252, 200 OK in 16.3ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1340machine # [ 46.906178] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=3&limit=50 for [::1]:37252, 200 OK in 35.1ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1341machine # [ 46.924847] gitea-mq-start[1176]: time=2026-09-03T03:43:44.814Z level=INFO msg="startup merge branch cleanup" owner=testuser repo=discovered-repo active_branches=0 stale_deleted=01342machine # [ 46.926807] gitea-mq-start[1176]: time=2026-09-03T03:43:44.814Z level=INFO msg="discovery: reconciled" forge=gitea managed=11343machine # [ 46.928167] gitea-mq-start[1176]: time=2026-09-03T03:43:44.814Z level=INFO msg="poller started" owner=testuser repo=discovered-repo interval=5s idle_interval=15m0s idle_gating=true1344machine # [ 46.930750] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/discovered-repo/branches?page=1&limit=50 for [::1]:37248, 200 OK in 55.3ms @ repo/branch.go:284(repo.ListBranches)1345machine # [ 46.948955] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/discovered-repo/pulls?state=open&page=1&limit=50 for [::1]:37248, 200 OK in 23.8ms @ repo/pull.go:51(repo.ListPullRequests)1346machine # [ 46.963485] gitea[929]: 2026/09/03 03:43:44 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/2bcd7aa86d37d3fdfca163cb2d577385ee3f77c6/status?page=1&limit=50 for [::1]:37252, 200 OK in 53.1ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1347machine: (finished: waiting for success: curl -sf http://localhost:8080/ | grep -q 'testuser/discovered-repo', in 3.32 seconds)1348machine: must succeed: su -l gitea -c 'GITEA_WORK_DIR=/var/lib/gitea gitea admin user create --username otheruser --password otherpass123 --email other@test.com'1349machine # [ 47.112108] su[2329]: Successful su for gitea by root1350machine # [ 47.123738] su[2329]: pam_unix(su:session): session opened for user gitea(uid=998) by (uid=0)1351machine # [ 47.262178] su[2329]: pam_unix(su:session): session closed for user gitea1352machine: (finished: must succeed: su -l gitea -c 'GITEA_WORK_DIR=/var/lib/gitea gitea admin user create --username otheruser --password otherpass123 --email other@test.com', in 0.20 seconds)1353machine: must succeed: curl -sf -X POST http://localhost:3000/api/v1/admin/users/otheruser/repos -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"name": "other-repo", "auto_init": true, "default_branch": "main"}'1354machine # [ 47.953556] gitea[929]: 2026/09/03 03:43:45 .../actions/notifier_helper.go:166:notify() [W] Ref of event "repository" is empty, will fall back to the default branch1355machine # [ 47.973118] gitea[929]: 2026/09/03 03:43:45 HTTPRequest [I] router: completed POST /api/v1/admin/users/otheruser/repos for [::1]:42446, 201 Created in 656.6ms @ admin/repo.go:14(admin.CreateRepo)1356machine: (finished: must succeed: curl -sf -X POST http://localhost:3000/api/v1/admin/users/otheruser/repos -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51' -H 'Content-Type: application/json' -d '{"name": "other-repo", "auto_init": true, "default_branch": "main"}', in 0.71 seconds)1357machine: must succeed: curl -sf -X PUT 'http://localhost:3000/api/v1/repos/otheruser/other-repo/topics/merge-queue' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51'1358machine # [ 48.042148] gitea[929]: 2026/09/03 03:43:45 HTTPRequest [I] router: completed PUT /api/v1/repos/otheruser/other-repo/topics/merge-queue for [::1]:42454, 204 No Content in 13.2ms @ repo/topic.go:135(repo.AddTopic)1359machine: (finished: must succeed: curl -sf -X PUT 'http://localhost:3000/api/v1/repos/otheruser/other-repo/topics/merge-queue' -H 'Authorization: token 01aa4efccff126328d7bc4f9ccafec41c3f38f51', in 0.07 seconds)1360machine: waiting for success: curl -sf http://localhost:8080/ | grep -q 'otheruser/other-repo'1361machine # [ 51.808469] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=1&limit=50 for [::1]:37252, 200 OK in 69.0ms @ repo/repo.go:47(repo.Search)1362machine # [ 51.830158] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/pulls?state=open&page=1&limit=50 for [::1]:37248, 200 OK in 70.0ms @ repo/pull.go:51(repo.ListPullRequests)1363machine # [ 51.841231] gitea-mq-start[1176]: time=2026-09-03T03:43:49.730Z level=INFO msg="discovery: adding repo" repo=gitea:otheruser/other-repo1364machine # [ 51.843145] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/search?q=merge-queue&topic=true&page=2&limit=50 for [::1]:37252, 200 OK in 32.6ms @ repo/repo.go:47(repo.Search)1365machine # [ 51.852043] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=1&limit=50 for [::1]:37248, 200 OK in 21.7ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1366machine # [ 51.860673] gitea-mq-start[1176]: time=2026-09-03T03:43:49.750Z level=WARN msg="no branch protection rules found, gitea-mq requires branch protection with status checks" owner=otheruser repo=other-repo1367machine # [ 51.863728] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/otheruser/other-repo/branch_protections?page=1&limit=50 for [::1]:37252, 200 OK in 19.6ms @ repo/branch.go:587(repo.ListBranchProtections)1368machine # [ 51.872788] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=2&limit=50 for [::1]:37248, 200 OK in 20.4ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1369machine # [ 51.883233] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/otheruser/other-repo/hooks?page=1&limit=50 for [::1]:37252, 200 OK in 19.9ms @ repo/hook.go:26(repo.ListHooks)1370machine # [ 51.891498] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/issues/2/timeline?page=3&limit=50 for [::1]:37248, 200 OK in 18.0ms @ repo/issue_comment.go:123(repo.ListIssueCommentsAndTimeline)1371machine # [ 51.902839] gitea-mq-start[1176]: time=2026-09-03T03:43:49.792Z level=INFO msg="created webhook" owner=otheruser repo=other-repo url=http://localhost:8080/webhook/gitea1372machine # [ 51.905336] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed POST /api/v1/repos/otheruser/other-repo/hooks for [::1]:37252, 201 Created in 16.9ms @ repo/hook.go:200(repo.CreateHook)1373machine # [ 51.947660] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/testuser/testrepo/commits/2bcd7aa86d37d3fdfca163cb2d577385ee3f77c6/status?page=1&limit=50 for [::1]:37248, 200 OK in 52.5ms @ repo/status.go:216(repo.GetCombinedCommitStatusByRef)1374machine # [ 51.966766] gitea-mq-start[1176]: time=2026-09-03T03:43:49.856Z level=INFO msg="startup merge branch cleanup" owner=otheruser repo=other-repo active_branches=0 stale_deleted=01375machine # [ 51.969099] gitea-mq-start[1176]: time=2026-09-03T03:43:49.856Z level=INFO msg="discovery: reconciled" forge=gitea managed=21376machine # [ 51.970437] gitea-mq-start[1176]: time=2026-09-03T03:43:49.856Z level=INFO msg="poller started" owner=otheruser repo=other-repo interval=5s idle_interval=15m0s idle_gating=true1377machine # [ 51.972275] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/otheruser/other-repo/branches?page=1&limit=50 for [::1]:37252, 200 OK in 37.3ms @ repo/branch.go:284(repo.ListBranches)1378machine # [ 51.987920] gitea[929]: 2026/09/03 03:43:49 HTTPRequest [I] router: completed GET /api/v1/repos/otheruser/other-repo/pulls?state=open&page=1&limit=50 for [::1]:37252, 200 OK in 20.2ms @ repo/pull.go:51(repo.ListPullRequests)1379machine: (finished: waiting for success: curl -sf http://localhost:8080/ | grep -q 'otheruser/other-repo', in 4.46 seconds)1380(finished: run the VM test script, in 53.55 seconds)1381test script finished in 53.61s1382cleanup1383kill QemuMachine (pid 45)1384machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/3n4qphl9s728sz8frmpqqrv9b1m87g68-python3-3.14.7/bin/python3.14)1385(finished: cleanup, in 0.24 seconds)1386additionally exposed symbols:1387 machine,1388 vlan1,1389 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_ssh