these 12 derivations will be built: /nix/store/1zikjlr02as03c41fzhsiz6d6xhki92d-init-old.drv /nix/store/gbl1ip874mq2l3bm3axf3ghpvrs2r6z6-unit-postgresql.service.drv /nix/store/aifld2aq75ijy2r39s6x8gy39wvm56m7-system-units.drv /nix/store/3c8a4cnk1r39p1j27izhnkg8j2afk745-etc.drv /nix/store/mqsil0qili9krh3a59nw4ckwc0c6apq0-activate.drv /nix/store/6z2jqccpl4l9qc22xxzlp1g20silgsz6-nixos-system-server-test.drv /nix/store/5nwrafvq5i29d0vizi9k2b3ayyy79n98-closure-info.drv /nix/store/h8y8mj372miyc7vq40fbfcldhljdg0lk-drive.drv /nix/store/mi4msmxcy74r26ajrvbdjrnbj92jxs7z-run-nixos-vm.drv /nix/store/m8cqnv8vq70mc4h2sf9fd4gkkhli1c04-nixos-vm.drv /nix/store/axcly9mxmzmn5lm36jspa5k10y20y22k-nixos-test-driver-pg-upgrade-start-guard.drv /nix/store/cykcl1vz1x9v03az7hymgsig5f92ggkp-vm-test-run-pg-upgrade-start-guard.drv building '/nix/store/h8y8mj372miyc7vq40fbfcldhljdg0lk-drive.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/1zikjlr02as03c41fzhsiz6d6xhki92d-init-old.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/h8y8mj372miyc7vq40fbfcldhljdg0lk-drive.drv' building '/nix/store/1zikjlr02as03c41fzhsiz6d6xhki92d-init-old.drv' building '/nix/store/gbl1ip874mq2l3bm3axf3ghpvrs2r6z6-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/gbl1ip874mq2l3bm3axf3ghpvrs2r6z6-unit-postgresql.service.drv' building '/nix/store/aifld2aq75ijy2r39s6x8gy39wvm56m7-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/aifld2aq75ijy2r39s6x8gy39wvm56m7-system-units.drv' building '/nix/store/3c8a4cnk1r39p1j27izhnkg8j2afk745-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/3c8a4cnk1r39p1j27izhnkg8j2afk745-etc.drv' building '/nix/store/mqsil0qili9krh3a59nw4ckwc0c6apq0-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/mqsil0qili9krh3a59nw4ckwc0c6apq0-activate.drv' building '/nix/store/6z2jqccpl4l9qc22xxzlp1g20silgsz6-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/6z2jqccpl4l9qc22xxzlp1g20silgsz6-nixos-system-server-test.drv' building '/nix/store/5nwrafvq5i29d0vizi9k2b3ayyy79n98-closure-info.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/5nwrafvq5i29d0vizi9k2b3ayyy79n98-closure-info.drv' closure-info> structuredAttrs is enabled building '/nix/store/mi4msmxcy74r26ajrvbdjrnbj92jxs7z-run-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/mi4msmxcy74r26ajrvbdjrnbj92jxs7z-run-nixos-vm.drv' building '/nix/store/m8cqnv8vq70mc4h2sf9fd4gkkhli1c04-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/m8cqnv8vq70mc4h2sf9fd4gkkhli1c04-nixos-vm.drv' building '/nix/store/axcly9mxmzmn5lm36jspa5k10y20y22k-nixos-test-driver-pg-upgrade-start-guard.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/axcly9mxmzmn5lm36jspa5k10y20y22k-nixos-test-driver-pg-upgrade-start-guard.drv' nixos-test-driver-pg-upgrade-start-guard> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-pg-upgrade-start-guard> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-pg-upgrade-start-guard> Success: no issues found in 1 source file nixos-test-driver-pg-upgrade-start-guard> additionally exposed symbols: nixos-test-driver-pg-upgrade-start-guard> , nixos-test-driver-pg-upgrade-start-guard> , nixos-test-driver-pg-upgrade-start-guard> start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug nixos-test-driver-pg-upgrade-start-guard> Linting test script (enable/disable: config.skipLint) nixos-test-driver-pg-upgrade-start-guard> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint building '/nix/store/cykcl1vz1x9v03az7hymgsig5f92ggkp-vm-test-run-pg-upgrade-start-guard.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/cykcl1vz1x9v03az7hymgsig5f92ggkp-vm-test-run-pg-upgrade-start-guard.drv' vm-test-run-pg-upgrade-start-guard> Machine state will be reset. To keep it, pass --keep-vm-state vm-test-run-pg-upgrade-start-guard> start all VLans vm-test-run-pg-upgrade-start-guard> start vlan vm-test-run-pg-upgrade-start-guard> running vlan (pid 7; ctl /build/vde1.ctl) vm-test-run-pg-upgrade-start-guard> (finished: start all VLans, in 0.00 seconds) vm-test-run-pg-upgrade-start-guard> Test will time out and terminate in 3600 seconds vm-test-run-pg-upgrade-start-guard> run the VM test script vm-test-run-pg-upgrade-start-guard> additionally exposed symbols: vm-test-run-pg-upgrade-start-guard> server, vm-test-run-pg-upgrade-start-guard> vlan1, vm-test-run-pg-upgrade-start-guard> start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug vm-test-run-pg-upgrade-start-guard> start all VMs vm-test-run-pg-upgrade-start-guard> server: starting vm vm-test-run-pg-upgrade-start-guard> mke2fs 1.47.3 (8-Jul-2025) vm-test-run-pg-upgrade-start-guard> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-pg-upgrade-start-guard> qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-pg-upgrade-start-guard> qemu-system-aarch64: falling back to tcg vm-test-run-pg-upgrade-start-guard> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-pg-upgrade-start-guard> server # Formatting '/build/vm-state-server/tmp.e7Q3c6pMzp', fmt=raw size=4294967296 vm-test-run-pg-upgrade-start-guard> server # Discarding device blocks: 0/1048576 done vm-test-run-pg-upgrade-start-guard> server # Creating filesystem with 1048576 4k blocks and 262144 inodes vm-test-run-pg-upgrade-start-guard> server # Filesystem UUID: 530fc01f-9585-414d-8c3a-706a38c5f4f0 vm-test-run-pg-upgrade-start-guard> server # Superblock backups stored on blocks: vm-test-run-pg-upgrade-start-guard> server # 32768, 98304, 163840, 229376, 294912, 819200, 884736 vm-test-run-pg-upgrade-start-guard> server # vm-test-run-pg-upgrade-start-guard> server # Allocating group tables: 0/32 done vm-test-run-pg-upgrade-start-guard> server # Writing inode tables: 0/32 done vm-test-run-pg-upgrade-start-guard> server # Creating journal (16384 blocks): done vm-test-run-pg-upgrade-start-guard> server # Writing superblocks and filesystem accounting information: 0/32 done vm-test-run-pg-upgrade-start-guard> server # vm-test-run-pg-upgrade-start-guard> server # Virtualisation disk image created. vm-test-run-pg-upgrade-start-guard> server: QEMU running (pid 9) vm-test-run-pg-upgrade-start-guard> (finished: start all VMs, in 0.16 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for unit postgresql.service vm-test-run-pg-upgrade-start-guard> server: waiting for the VM to finish booting vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Linux version 6.12.63 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP Thu Dec 18 12:55:23 UTC 2025 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] KASLR enabled vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] random: crng init done vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] efi: UEFI not found. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] NODE_DATA(0) allocated [mem 0xbfa04780-0xbfa07ebf] vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Zone ranges: vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000bfffffff] vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] DMA32 empty vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Normal empty vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Device empty vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Movable zone start for each node vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Early memory node ranges vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff] vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff] vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000bb800000 on node -1 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] CPU features: detected: GIC system register CPU interface vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/i7a98zy3i337z3yn9jxl1x2l93ixw8hh-nixos-system-server-test/init regInfo=/nix/store/bhac28dc391yq48czj2c4ja1ql71yizf-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/bhac28dc391yq48czj2c4ja1ql71yizf-closure-info/registration", will be passed to user space. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 524288 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Policy zone: DMA vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] software IO TLB: area num 1. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000bb25a000-0x00000000bb45a000] (2MB) vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] allocated 4194304 bytes of page_ext vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @446c0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @446d0000 (flat, esz 8, psz 64K, shr 1) vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] GICv3: using LPI property table @0x00000000446e0000 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000000446f0000 vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt). vm-test-run-pg-upgrade-start-guard> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-pg-upgrade-start-guard> server # [ 0.000039] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-pg-upgrade-start-guard> server # [ 0.002068] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-pg-upgrade-start-guard> server # [ 0.011754] Console: colour dummy device 80x25 vm-test-run-pg-upgrade-start-guard> server # [ 0.013121] printk: legacy console [tty0] enabled vm-test-run-pg-upgrade-start-guard> server # [ 0.019072] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-pg-upgrade-start-guard> server # [ 0.019462] pid_max: default: 32768 minimum: 301 vm-test-run-pg-upgrade-start-guard> server # [ 0.021217] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-pg-upgrade-start-guard> server # [ 0.022326] landlock: Up and running. vm-test-run-pg-upgrade-start-guard> server # [ 0.022358] Yama: becoming mindful. vm-test-run-pg-upgrade-start-guard> server # [ 0.023724] LSM support for eBPF active vm-test-run-pg-upgrade-start-guard> server # [ 0.025945] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.026031] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.054261] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-pg-upgrade-start-guard> server # [ 0.066600] rcu: Hierarchical SRCU implementation. vm-test-run-pg-upgrade-start-guard> server # [ 0.066693] rcu: Max phase no-delay instances is 1000. vm-test-run-pg-upgrade-start-guard> server # [ 0.075133] fsl-mc MSI: its@8080000 domain created vm-test-run-pg-upgrade-start-guard> server # [ 0.077811] EFI services will not be available. vm-test-run-pg-upgrade-start-guard> server # [ 0.079156] smp: Bringing up secondary CPUs ... vm-test-run-pg-upgrade-start-guard> server # [ 0.079763] smp: Brought up 1 node, 1 CPU vm-test-run-pg-upgrade-start-guard> server # [ 0.079811] SMP: Total of 1 processors activated. vm-test-run-pg-upgrade-start-guard> server # [ 0.079856] CPU: All CPU(s) started at EL1 vm-test-run-pg-upgrade-start-guard> server # [ 0.080122] CPU features: detected: Branch Target Identification vm-test-run-pg-upgrade-start-guard> server # [ 0.080180] CPU features: detected: 32-bit EL0 Support vm-test-run-pg-upgrade-start-guard> server # [ 0.080212] CPU features: detected: 32-bit EL1 Support vm-test-run-pg-upgrade-start-guard> server # [ 0.080239] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-pg-upgrade-start-guard> server # [ 0.080277] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-pg-upgrade-start-guard> server # [ 0.080318] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-pg-upgrade-start-guard> server # [ 0.080391] CPU features: detected: Common not Private translations vm-test-run-pg-upgrade-start-guard> server # [ 0.080417] CPU features: detected: CRC32 instructions vm-test-run-pg-upgrade-start-guard> server # [ 0.080441] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-pg-upgrade-start-guard> server # [ 0.080475] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-pg-upgrade-start-guard> server # [ 0.080504] CPU features: detected: Data independent timing control (DIT) vm-test-run-pg-upgrade-start-guard> server # [ 0.080528] CPU features: detected: E0PD vm-test-run-pg-upgrade-start-guard> server # [ 0.080546] CPU features: detected: Enhanced Counter Virtualization vm-test-run-pg-upgrade-start-guard> server # [ 0.080569] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-pg-upgrade-start-guard> server # [ 0.080594] CPU features: detected: Enhanced Privileged Access Never vm-test-run-pg-upgrade-start-guard> server # [ 0.080617] CPU features: detected: Enhanced Virtualization Traps vm-test-run-pg-upgrade-start-guard> server # [ 0.080640] CPU features: detected: Fine Grained Traps vm-test-run-pg-upgrade-start-guard> server # [ 0.080705] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-pg-upgrade-start-guard> server # [ 0.080794] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-pg-upgrade-start-guard> server # [ 0.080838] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-pg-upgrade-start-guard> server # [ 0.080863] CPU features: detected: LSE atomic instructions vm-test-run-pg-upgrade-start-guard> server # [ 0.080885] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-pg-upgrade-start-guard> server # [ 0.080909] CPU features: detected: Privileged Access Never vm-test-run-pg-upgrade-start-guard> server # [ 0.080931] CPU features: detected: RAS Extension Support vm-test-run-pg-upgrade-start-guard> server # [ 0.080952] CPU features: detected: Random Number Generator vm-test-run-pg-upgrade-start-guard> server # [ 0.080973] CPU features: detected: Speculation barrier (SB) vm-test-run-pg-upgrade-start-guard> server # [ 0.080995] CPU features: detected: Stage-2 Force Write-Back vm-test-run-pg-upgrade-start-guard> server # [ 0.081018] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-pg-upgrade-start-guard> server # [ 0.081045] CPU features: detected: TLB range maintenance instructions vm-test-run-pg-upgrade-start-guard> server # [ 0.081068] CPU features: detected: WFx with timeout vm-test-run-pg-upgrade-start-guard> server # [ 0.081111] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-pg-upgrade-start-guard> server # [ 0.081142] CPU features: detected: Scalable Vector Extension vm-test-run-pg-upgrade-start-guard> server # [ 0.091415] alternatives: applying system-wide alternatives vm-test-run-pg-upgrade-start-guard> server # [ 0.105586] CPU features: detected: Hardware dirty bit management on CPU0 vm-test-run-pg-upgrade-start-guard> server # [ 0.106238] SVE: maximum available vector length 256 bytes per vector vm-test-run-pg-upgrade-start-guard> server # [ 0.106343] SVE: default vector length 64 bytes per vector vm-test-run-pg-upgrade-start-guard> server # [ 0.118244] Memory: 1935376K/2097152K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 118632K reserved, 32768K cma-reserved) vm-test-run-pg-upgrade-start-guard> server # [ 0.139744] devtmpfs: initialized vm-test-run-pg-upgrade-start-guard> server # [ 0.164533] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-pg-upgrade-start-guard> server # [ 0.165022] futex hash table entries: 256 (order: 2, 16384 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.168064] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-pg-upgrade-start-guard> server # [ 0.168140] 0 pages in range for non-PLT usage vm-test-run-pg-upgrade-start-guard> server # [ 0.168178] 509248 pages in range for PLT usage vm-test-run-pg-upgrade-start-guard> server # [ 0.170924] pinctrl core: initialized pinctrl subsystem vm-test-run-pg-upgrade-start-guard> server # [ 0.181713] DMI not present or invalid. vm-test-run-pg-upgrade-start-guard> server # [ 0.199131] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-pg-upgrade-start-guard> server # [ 0.210045] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations vm-test-run-pg-upgrade-start-guard> server # [ 0.212653] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-pg-upgrade-start-guard> server # [ 0.215189] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-pg-upgrade-start-guard> server # [ 0.215473] audit: initializing netlink subsys (disabled) vm-test-run-pg-upgrade-start-guard> server # [ 0.217672] audit: type=2000 audit(0.180:1): state=initialized audit_enabled=0 res=1 vm-test-run-pg-upgrade-start-guard> server # [ 0.224498] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-pg-upgrade-start-guard> server # [ 0.224557] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-pg-upgrade-start-guard> server # [ 0.224616] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-pg-upgrade-start-guard> server # [ 0.224649] thermal_sys: Registered thermal governor 'user_space' vm-test-run-pg-upgrade-start-guard> server # [ 0.224678] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-pg-upgrade-start-guard> server # [ 0.225183] cpuidle: using governor ladder vm-test-run-pg-upgrade-start-guard> server # [ 0.225350] cpuidle: using governor menu vm-test-run-pg-upgrade-start-guard> server # [ 0.227430] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-pg-upgrade-start-guard> server # [ 0.227865] ASID allocator initialised with 65536 entries vm-test-run-pg-upgrade-start-guard> server # [ 0.235315] Serial: AMBA PL011 UART driver vm-test-run-pg-upgrade-start-guard> server # [ 0.287393] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-pg-upgrade-start-guard> server # [ 0.288624] printk: legacy console [ttyAMA0] enabled vm-test-run-pg-upgrade-start-guard> server # [ 0.329232] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-pg-upgrade-start-guard> server # [ 0.329440] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-pg-upgrade-start-guard> server # [ 0.329582] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-pg-upgrade-start-guard> server # [ 0.329813] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-pg-upgrade-start-guard> server # [ 0.329956] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-pg-upgrade-start-guard> server # [ 0.330064] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-pg-upgrade-start-guard> server # [ 0.330167] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-pg-upgrade-start-guard> server # [ 0.330269] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-pg-upgrade-start-guard> server # [ 0.358064] fbcon: Taking over console vm-test-run-pg-upgrade-start-guard> server # [ 0.358445] ACPI: Interpreter disabled. vm-test-run-pg-upgrade-start-guard> server # [ 0.370449] iommu: Default domain type: Translated vm-test-run-pg-upgrade-start-guard> server # [ 0.370626] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-pg-upgrade-start-guard> server # [ 0.377752] SCSI subsystem initialized vm-test-run-pg-upgrade-start-guard> server # [ 0.380972] usbcore: registered new interface driver usbfs vm-test-run-pg-upgrade-start-guard> server # [ 0.381486] usbcore: registered new interface driver hub vm-test-run-pg-upgrade-start-guard> server # [ 0.381721] usbcore: registered new device driver usb vm-test-run-pg-upgrade-start-guard> server # [ 0.383959] pps_core: LinuxPPS API ver. 1 registered vm-test-run-pg-upgrade-start-guard> server # [ 0.384131] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-pg-upgrade-start-guard> server # [ 0.384322] PTP clock support registered vm-test-run-pg-upgrade-start-guard> server # [ 0.389943] EDAC MC: Ver: 3.0.0 vm-test-run-pg-upgrade-start-guard> server # [ 0.393694] scmi_core: SCMI protocol bus registered vm-test-run-pg-upgrade-start-guard> server # [ 0.399058] FPGA manager framework vm-test-run-pg-upgrade-start-guard> server # [ 0.400296] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-pg-upgrade-start-guard> server # [ 0.421561] vgaarb: loaded vm-test-run-pg-upgrade-start-guard> server # [ 0.434815] clocksource: Switched to clocksource arch_sys_counter vm-test-run-pg-upgrade-start-guard> server # [ 0.439245] VFS: Disk quotas dquot_6.6.0 vm-test-run-pg-upgrade-start-guard> server # [ 0.439469] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-pg-upgrade-start-guard> server # [ 0.443056] netfs: FS-Cache loaded vm-test-run-pg-upgrade-start-guard> server # [ 0.444429] pnp: PnP ACPI: disabled vm-test-run-pg-upgrade-start-guard> server # [ 0.490061] NET: Registered PF_INET protocol family vm-test-run-pg-upgrade-start-guard> server # [ 0.494399] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.627299] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.627811] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.628155] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.628773] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.630009] TCP: Hash tables configured (established 16384 bind 16384) vm-test-run-pg-upgrade-start-guard> server # [ 0.631534] MPTCP token hash table entries: 2048 (order: 4, 49152 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.632150] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.632521] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) vm-test-run-pg-upgrade-start-guard> server # [ 0.634506] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-pg-upgrade-start-guard> server # [ 0.634986] NET: Registered PF_XDP protocol family vm-test-run-pg-upgrade-start-guard> server # [ 0.635194] PCI: CLS 0 bytes, default 64 vm-test-run-pg-upgrade-start-guard> server # [ 0.638295] kvm [1]: HYP mode not available vm-test-run-pg-upgrade-start-guard> server # [ 0.642787] Trying to unpack rootfs image as initramfs... vm-test-run-pg-upgrade-start-guard> server # [ 0.828665] Initialise system trusted keyrings vm-test-run-pg-upgrade-start-guard> server # [ 0.834175] workingset: timestamp_bits=42 max_order=19 bucket_order=0 vm-test-run-pg-upgrade-start-guard> server # [ 0.843234] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-pg-upgrade-start-guard> server # [ 0.844524] 9p: Installing v9fs 9p2000 file system support vm-test-run-pg-upgrade-start-guard> server # [ 0.979941] Key type asymmetric registered vm-test-run-pg-upgrade-start-guard> server # [ 0.980349] Asymmetric key parser 'x509' registered vm-test-run-pg-upgrade-start-guard> server # [ 0.981487] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-pg-upgrade-start-guard> server # [ 0.982618] io scheduler mq-deadline registered vm-test-run-pg-upgrade-start-guard> server # [ 0.982964] io scheduler kyber registered vm-test-run-pg-upgrade-start-guard> server # [ 1.038968] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-pg-upgrade-start-guard> server # [ 1.046944] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-pg-upgrade-start-guard> server # [ 1.056423] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-pg-upgrade-start-guard> server # [ 1.057645] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-pg-upgrade-start-guard> server # [ 1.058517] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-pg-upgrade-start-guard> server # [ 1.058735] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-pg-upgrade-start-guard> server # [ 1.059318] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-pg-upgrade-start-guard> server # [ 1.060151] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-pg-upgrade-start-guard> server # [ 1.066419] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-pg-upgrade-start-guard> server # [ 1.067235] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-pg-upgrade-start-guard> server # [ 1.067507] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.067724] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.067855] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.069065] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.076467] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.077206] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pg-upgrade-start-guard> server # [ 1.077550] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.077707] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.077879] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.081410] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.081651] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pg-upgrade-start-guard> server # [ 1.081887] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.082016] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.082532] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.082830] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pg-upgrade-start-guard> server # [ 1.082946] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.083065] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.083564] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.084067] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pg-upgrade-start-guard> server # [ 1.084176] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.084646] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.090767] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.090978] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pg-upgrade-start-guard> server # [ 1.091179] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.091430] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.092103] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.092687] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-pg-upgrade-start-guard> server # [ 1.093219] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.093559] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.100803] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.101035] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pg-upgrade-start-guard> server # [ 1.101150] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.101326] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.102067] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.102662] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.102844] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.102976] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.103531] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.103842] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.103968] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.104526] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.104801] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.109865] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.110103] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pg-upgrade-start-guard> server # [ 1.110398] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.110580] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.111055] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pg-upgrade-start-guard> server # [ 1.111299] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pg-upgrade-start-guard> server # [ 1.111408] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pg-upgrade-start-guard> server # [ 1.111535] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pg-upgrade-start-guard> server # [ 1.113561] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.113826] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.114027] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.114331] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.114503] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.114720] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.114856] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.114990] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.115146] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.115295] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.116084] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.116240] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.116497] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.117406] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.117617] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.117807] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118104] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118227] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118338] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118444] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118553] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118674] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118780] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.118943] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.119172] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.119297] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.119422] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.119539] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.119899] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.120004] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.120105] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.120210] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.120380] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.120484] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-pg-upgrade-start-guard> server # [ 1.120798] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.120911] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.121658] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.132109] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-pg-upgrade-start-guard> server # [ 1.251827] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.260208] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.267148] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.268674] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.274183] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.275862] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.283129] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.284677] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-pg-upgrade-start-guard> server # [ 1.289811] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-pg-upgrade-start-guard> server # [ 1.291688] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.298209] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-pg-upgrade-start-guard> server # [ 1.328387] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-pg-upgrade-start-guard> server # [ 1.346658] msm_serial: driver initialized vm-test-run-pg-upgrade-start-guard> server # [ 1.347450] SuperH (H)SCI(F) driver initialized vm-test-run-pg-upgrade-start-guard> server # [ 1.348289] STM32 USART driver initialized vm-test-run-pg-upgrade-start-guard> server # [ 1.443638] loop: module loaded vm-test-run-pg-upgrade-start-guard> server # [ 1.444380] virtio_blk virtio5: 1/0/0 default/read/poll queues vm-test-run-pg-upgrade-start-guard> server # [ 1.452049] virtio_blk virtio5: [vda] 8388608 512-byte logical blocks (4.29 GB/4.00 GiB) vm-test-run-pg-upgrade-start-guard> server # [ 1.485845] megasas: 07.727.03.00-rc1 vm-test-run-pg-upgrade-start-guard> server # [ 1.495263] Freeing initrd memory: 10684K vm-test-run-pg-upgrade-start-guard> server # [ 1.498217] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.500734] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pg-upgrade-start-guard> server # [ 1.502145] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pg-upgrade-start-guard> server # [ 1.503179] Using buffer write method vm-test-run-pg-upgrade-start-guard> server # [ 1.503896] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-pg-upgrade-start-guard> server # [ 1.505939] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pg-upgrade-start-guard> server # [ 1.506188] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pg-upgrade-start-guard> server # [ 1.507324] Using buffer write method vm-test-run-pg-upgrade-start-guard> server # [ 1.507836] Concatenating MTD devices: vm-test-run-pg-upgrade-start-guard> server # [ 1.507972] (0): "0.flash" vm-test-run-pg-upgrade-start-guard> server # [ 1.508032] (1): "0.flash" vm-test-run-pg-upgrade-start-guard> server # [ 1.508093] into device "0.flash" vm-test-run-pg-upgrade-start-guard> server # [ 1.530592] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-pg-upgrade-start-guard> server # [ 1.542748] thunder_xcv, ver 1.0 vm-test-run-pg-upgrade-start-guard> server # [ 1.542993] thunder_bgx, ver 1.0 vm-test-run-pg-upgrade-start-guard> server # [ 1.543167] nicpf, ver 1.0 vm-test-run-pg-upgrade-start-guard> server # [ 1.546276] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-pg-upgrade-start-guard> server # [ 1.546580] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-pg-upgrade-start-guard> server # [ 1.547204] hclge is initializing vm-test-run-pg-upgrade-start-guard> server # [ 1.547430] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-pg-upgrade-start-guard> server # [ 1.547514] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-pg-upgrade-start-guard> server # [ 1.547966] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-pg-upgrade-start-guard> server # [ 1.548061] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-pg-upgrade-start-guard> server # [ 1.548379] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-pg-upgrade-start-guard> server # [ 1.548483] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-pg-upgrade-start-guard> server # [ 1.548706] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-pg-upgrade-start-guard> server # [ 1.549236] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-pg-upgrade-start-guard> server # [ 1.550018] sky2: driver version 1.30 vm-test-run-pg-upgrade-start-guard> server # [ 1.557706] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-pg-upgrade-start-guard> server # [ 1.558234] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-pg-upgrade-start-guard> server # [ 1.560730] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-pg-upgrade-start-guard> server # [ 1.564123] usbcore: registered new interface driver usb-storage vm-test-run-pg-upgrade-start-guard> server # [ 1.564771] usbcore: registered new interface driver usbserial_generic vm-test-run-pg-upgrade-start-guard> server # [ 1.566081] usbserial: USB Serial support registered for generic vm-test-run-pg-upgrade-start-guard> server # [ 1.569224] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-pg-upgrade-start-guard> server # [ 1.580757] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-pg-upgrade-start-guard> server # [ 1.582547] hub 1-0:1.0: USB hub found vm-test-run-pg-upgrade-start-guard> server # [ 1.583390] rtc-pl031 9010000.pl031: setting system clock to 2026-10-26T13:07:21 UTC (1793020041) vm-test-run-pg-upgrade-start-guard> server # [ 1.585464] hub 1-0:1.0: 6 ports detected vm-test-run-pg-upgrade-start-guard> server # [ 1.587976] i2c_dev: i2c /dev entries driver vm-test-run-pg-upgrade-start-guard> server # [ 1.603103] sdhci: Secure Digital Host Controller Interface driver vm-test-run-pg-upgrade-start-guard> server # [ 1.603289] sdhci: Copyright(c) Pierre Ossman vm-test-run-pg-upgrade-start-guard> server # [ 1.605009] Synopsys Designware Multimedia Card Interface Driver vm-test-run-pg-upgrade-start-guard> server # [ 1.607217] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-pg-upgrade-start-guard> server # [ 1.614713] hid: raw HID events driver (C) Jiri Kosina vm-test-run-pg-upgrade-start-guard> server # [ 1.616894] usbcore: registered new interface driver usbhid vm-test-run-pg-upgrade-start-guard> server # [ 1.617046] usbhid: USB HID core driver vm-test-run-pg-upgrade-start-guard> server # [ 1.626167] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-pg-upgrade-start-guard> server # [ 1.639097] drop_monitor: Initializing network drop monitor service vm-test-run-pg-upgrade-start-guard> server # [ 1.640600] NET: Registered PF_INET6 protocol family vm-test-run-pg-upgrade-start-guard> server # [ 1.651956] Segment Routing with IPv6 vm-test-run-pg-upgrade-start-guard> server # [ 1.652274] In-situ OAM (IOAM) with IPv6 vm-test-run-pg-upgrade-start-guard> server # [ 1.652834] NET: Registered PF_PACKET protocol family vm-test-run-pg-upgrade-start-guard> server # [ 1.654237] 9pnet: Installing 9P2000 support vm-test-run-pg-upgrade-start-guard> server # [ 1.658713] Key type dns_resolver registered vm-test-run-pg-upgrade-start-guard> server # [ 1.697681] registered taskstats version 1 vm-test-run-pg-upgrade-start-guard> server # [ 1.699949] Loading compiled-in X.509 certificates vm-test-run-pg-upgrade-start-guard> server # [ 1.755134] Demotion targets for Node 0: null vm-test-run-pg-upgrade-start-guard> server # [ 1.757115] Key type .fscrypt registered vm-test-run-pg-upgrade-start-guard> server # [ 1.757403] Key type fscrypt-provisioning registered vm-test-run-pg-upgrade-start-guard> server # [ 1.765259] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-pg-upgrade-start-guard> server # [ 1.807763] clk: Disabling unused clocks vm-test-run-pg-upgrade-start-guard> server # [ 1.808052] PM: genpd: Disabling unused power domains vm-test-run-pg-upgrade-start-guard> server # [ 1.808345] ALSA device list: vm-test-run-pg-upgrade-start-guard> server # [ 1.808446] No soundcards found. vm-test-run-pg-upgrade-start-guard> server # [ 1.869974] Freeing unused kernel memory: 4416K vm-test-run-pg-upgrade-start-guard> server # [ 1.870448] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-pg-upgrade-start-guard> server # [ 1.873764] Run /init as init process vm-test-run-pg-upgrade-start-guard> server # [ 2.042687] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 vm-test-run-pg-upgrade-start-guard> server # [ 2.138298] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 vm-test-run-pg-upgrade-start-guard> server # [ 2.257444] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-pg-upgrade-start-guard> server # [ 2.376511] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-pg-upgrade-start-guard> server # [ 2.432711] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 vm-test-run-pg-upgrade-start-guard> server # [ 2.438585] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 vm-test-run-pg-upgrade-start-guard> server # [ 2.519730] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-pg-upgrade-start-guard> server # [ 2.520407] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-pg-upgrade-start-guard> server # [ 2.520430] [drm] features: -context_init vm-test-run-pg-upgrade-start-guard> server # [ 2.522936] [drm] number of scanouts: 1 vm-test-run-pg-upgrade-start-guard> server # [ 2.523065] [drm] number of cap sets: 0 vm-test-run-pg-upgrade-start-guard> server # [ 2.534639] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-pg-upgrade-start-guard> server # [ 2.567980] Console: switching to colour frame buffer device 160x50 vm-test-run-pg-upgrade-start-guard> server # [ 2.580023] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-pg-upgrade-start-guard> server # [ 6.063256] EXT4-fs (vda): mounted filesystem 530fc01f-9585-414d-8c3a-706a38c5f4f0 r/w with ordered data mode. Quota mode: none. vm-test-run-pg-upgrade-start-guard> server # [ 8.117932] EXT4-fs (vda): re-mounted 530fc01f-9585-414d-8c3a-706a38c5f4f0. vm-test-run-pg-upgrade-start-guard> server # [ 8.135402] booting system configuration /nix/store/i7a98zy3i337z3yn9jxl1x2l93ixw8hh-nixos-system-server-test vm-test-run-pg-upgrade-start-guard> server # [ 19.464968] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) vm-test-run-pg-upgrade-start-guard> server # [ 19.475224] systemd[1]: Detected virtualization qemu. vm-test-run-pg-upgrade-start-guard> server # [ 19.480033] systemd[1]: Detected architecture arm64. vm-test-run-pg-upgrade-start-guard> server # [ 19.485462] systemd[1]: Detected first boot. vm-test-run-pg-upgrade-start-guard> server # [ 19.516223] systemd[1]: Initializing machine ID from random generator. vm-test-run-pg-upgrade-start-guard> server # [ 19.549900] systemd[1]: Hostname set to . vm-test-run-pg-upgrade-start-guard> server # [ 20.767069] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-pg-upgrade-start-guard> server # [ 22.492198] systemd[1]: Applying preset policy. vm-test-run-pg-upgrade-start-guard> server # [ 25.941315] systemd[1]: Populated /etc with preset unit settings. vm-test-run-pg-upgrade-start-guard> server # [ 29.216163] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-pg-upgrade-start-guard> server # [ 29.401233] systemd[1]: Created slice Slice /system/getty. vm-test-run-pg-upgrade-start-guard> server # [ 29.415314] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-pg-upgrade-start-guard> server # [ 29.431154] systemd[1]: Created slice User and Session Slice. vm-test-run-pg-upgrade-start-guard> server # [ 29.439140] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-pg-upgrade-start-guard> server # [ 29.444702] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-pg-upgrade-start-guard> server # [ 29.449691] systemd[1]: Expecting device /dev/hvc0... vm-test-run-pg-upgrade-start-guard> server # [ 29.454106] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-pg-upgrade-start-guard> server # [ 29.457895] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-pg-upgrade-start-guard> server # [ 29.462874] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-pg-upgrade-start-guard> server # [ 29.467732] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-pg-upgrade-start-guard> server # [ 29.472126] systemd[1]: Reached target Path Units. vm-test-run-pg-upgrade-start-guard> server # [ 29.476460] systemd[1]: Reached target Remote File Systems. vm-test-run-pg-upgrade-start-guard> server # [ 29.480501] systemd[1]: Reached target Slice Units. vm-test-run-pg-upgrade-start-guard> server # [ 29.484539] systemd[1]: Reached target Swaps. vm-test-run-pg-upgrade-start-guard> server # [ 29.518109] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-pg-upgrade-start-guard> server # [ 29.539290] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-pg-upgrade-start-guard> server # [ 29.550089] systemd[1]: Listening on Journal Audit Socket. vm-test-run-pg-upgrade-start-guard> server # [ 29.556641] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-pg-upgrade-start-guard> server # [ 29.563083] systemd[1]: Listening on Journal Sockets. vm-test-run-pg-upgrade-start-guard> server # [ 29.570825] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-pg-upgrade-start-guard> server # [ 29.575930] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pg-upgrade-start-guard> server # [ 29.581885] systemd[1]: Listening on udev Control Socket. vm-test-run-pg-upgrade-start-guard> server # [ 29.586816] systemd[1]: Listening on udev Kernel Socket. vm-test-run-pg-upgrade-start-guard> server # [ 29.632557] systemd[1]: Mounting Huge Pages File System... vm-test-run-pg-upgrade-start-guard> server # [ 29.694538] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-pg-upgrade-start-guard> server: Guest root shell did not produce any data yet... vm-test-run-pg-upgrade-start-guard> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-pg-upgrade-start-guard> server # [ 29.770519] systemd[1]: Mounting Kernel Debug File System... vm-test-run-pg-upgrade-start-guard> server # [ 29.878786] systemd[1]: Mounting Kernel Trace File System... vm-test-run-pg-upgrade-start-guard> server # [ 30.032314] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-pg-upgrade-start-guard> server # [ 30.187194] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-pg-upgrade-start-guard> server # [ 30.216673] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-pg-upgrade-start-guard> server # [ 30.342359] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-pg-upgrade-start-guard> server # [ 30.366461] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-pg-upgrade-start-guard> server # [ 30.396109] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-pg-upgrade-start-guard> server # [ 30.534783] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-pg-upgrade-start-guard> server # [ 30.548576] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-pg-upgrade-start-guard> server # [ 30.562987] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). vm-test-run-pg-upgrade-start-guard> server # [ 30.684684] systemd[1]: Starting Journal Service... vm-test-run-pg-upgrade-start-guard> server # [ 30.743182] systemd[1]: Starting Load Kernel Modules... vm-test-run-pg-upgrade-start-guard> server # [ 30.828677] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-pg-upgrade-start-guard> server # [ 30.902873] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-pg-upgrade-start-guard> server # [ 30.911366] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pg-upgrade-start-guard> server # [ 30.994400] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-pg-upgrade-start-guard> server # [ 31.068601] systemd[1]: Mounted Huge Pages File System. vm-test-run-pg-upgrade-start-guard> server # [ 31.082691] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-pg-upgrade-start-guard> server # [ 31.091033] systemd[1]: Mounted Kernel Debug File System. vm-test-run-pg-upgrade-start-guard> server # [ 31.099225] systemd[1]: Mounted Kernel Trace File System. vm-test-run-pg-upgrade-start-guard> server # [ 31.129774] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-pg-upgrade-start-guard> server # [ 31.148096] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-pg-upgrade-start-guard> server # [ 31.214989] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-pg-upgrade-start-guard> server # [ 32.243160] EXT4-fs (vda): re-mounted 530fc01f-9585-414d-8c3a-706a38c5f4f0. vm-test-run-pg-upgrade-start-guard> server # [ 32.337864] systemd[1]: Finished Load Kernel Modules. vm-test-run-pg-upgrade-start-guard> server # [ 32.404391] systemd[1]: Starting Firewall... vm-test-run-pg-upgrade-start-guard> server # [ 32.475956] systemd[1]: Starting Apply Kernel Variables... vm-test-run-pg-upgrade-start-guard> server # [ 32.502576] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-pg-upgrade-start-guard> server # [ 32.511160] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-pg-upgrade-start-guard> server # [ 32.596055] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-pg-upgrade-start-guard> server # [ 32.604472] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pg-upgrade-start-guard> server # [ 32.646286] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 32.661063] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-pg-upgrade-start-guard> server # [ 33.220648] fuse: init (API version 7.41) vm-test-run-pg-upgrade-start-guard> server # [ 33.327062] systemd-journald[374]: Collecting audit messages is enabled. vm-test-run-pg-upgrade-start-guard> server # [ 33.353355] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 33.370496] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-pg-upgrade-start-guard> server # [ 33.423136] systemd[1]: Mounting FUSE Control File System... vm-test-run-pg-upgrade-start-guard> server # [ 33.648685] systemd[1]: Mounted FUSE Control File System. vm-test-run-pg-upgrade-start-guard> server # [ 34.078234] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-pg-upgrade-start-guard> server # [ 34.094546] systemd[1]: Reached target First Boot Complete. vm-test-run-pg-upgrade-start-guard> server # [ 34.447267] systemd[1]: Finished Apply Kernel Variables. vm-test-run-pg-upgrade-start-guard> server # [ 34.578340] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-pg-upgrade-start-guard> server # [ 34.640725] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-pg-upgrade-start-guard> server # [ 35.431142] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-pg-upgrade-start-guard> server # [ 36.174731] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-pg-upgrade-start-guard> server # [ 36.202091] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-pg-upgrade-start-guard> server # [ 36.264696] systemd[1]: Mounting /run/wrappers... vm-test-run-pg-upgrade-start-guard> server # [ 36.313570] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-pg-upgrade-start-guard> server # [ 36.788543] systemd[1]: Mounted /run/wrappers. vm-test-run-pg-upgrade-start-guard> server # [ 36.799060] systemd[1]: Reached target Local File Systems. vm-test-run-pg-upgrade-start-guard> server # [ 36.828051] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-pg-upgrade-start-guard> server # [ 36.903325] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-pg-upgrade-start-guard> server # [ 36.915054] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-pg-upgrade-start-guard> server # [ 36.981153] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-pg-upgrade-start-guard> server # [ 37.250835] systemd[1]: Started Journal Service. vm-test-run-pg-upgrade-start-guard> server # [ 37.293925] systemd-modules-load[375]: Module 'atkbd' is built in vm-test-run-pg-upgrade-start-guard> server # [ 37.315793] systemd-modules-load[375]: Module 'loop' is built in vm-test-run-pg-upgrade-start-guard> server # [ 37.406910] systemd-oomd[376]: No swap; memory pressure usage will be degraded vm-test-run-pg-upgrade-start-guard> server # [ 37.456309] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-pg-upgrade-start-guard> server # [ 38.165191] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 38.257923] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-pg-upgrade-start-guard> server # [ 38.564160] systemd-journald[374]: Received client request to flush runtime journal. vm-test-run-pg-upgrade-start-guard> server # [ 39.108173] systemd-udevd[405]: Using default interface naming scheme 'v258'. vm-test-run-pg-upgrade-start-guard> server # [ 39.164112] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-pg-upgrade-start-guard> server # [ 39.205867] systemd[1]: Starting Create System Files and Directories... vm-test-run-pg-upgrade-start-guard> server # [ 41.946579] systemd[1]: Finished Create System Files and Directories. vm-test-run-pg-upgrade-start-guard> server # [ 42.004983] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-pg-upgrade-start-guard> server # [ 42.067322] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-pg-upgrade-start-guard> server # [ 42.581569] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-pg-upgrade-start-guard> server # [ 43.235336] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-pg-upgrade-start-guard> server # [ 44.588378] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-pg-upgrade-start-guard> server # [ 44.632985] systemd[1]: Starting Update is Completed... vm-test-run-pg-upgrade-start-guard> server # [ 45.660274] systemd[1]: Finished Update is Completed. vm-test-run-pg-upgrade-start-guard> server # [ 48.774885] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 48.801482] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-pg-upgrade-start-guard> server # [ 48.807363] systemd[1]: suid-sgid-wrappers.service: Consumed 1.689s CPU time, 5.5M memory peak. vm-test-run-pg-upgrade-start-guard> server # [ 50.766696] systemd[1]: Finished Firewall. vm-test-run-pg-upgrade-start-guard> server # [ 54.103012] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-pg-upgrade-start-guard> server # [ 54.112246] systemd[1]: Reached target System Initialization. vm-test-run-pg-upgrade-start-guard> server # [ 54.123561] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-pg-upgrade-start-guard> server # [ 54.129400] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-pg-upgrade-start-guard> server # [ 54.137609] systemd[1]: Reached target Timer Units. vm-test-run-pg-upgrade-start-guard> server # [ 54.149638] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-pg-upgrade-start-guard> server # [ 54.150875] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-pg-upgrade-start-guard> server # [ 54.151874] systemd[1]: Listening on Hostname Service Socket. vm-test-run-pg-upgrade-start-guard> server # [ 54.154888] systemd[1]: Reached target Socket Units. vm-test-run-pg-upgrade-start-guard> server # [ 54.184867] systemd[1]: Reached target Basic System. vm-test-run-pg-upgrade-start-guard> server # [ 54.190298] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-pg-upgrade-start-guard> server # [ 54.217005] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pg-upgrade-start-guard> server # [ 54.270275] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 54.330259] systemd[1]: Started Reset console on configuration changes. vm-test-run-pg-upgrade-start-guard> server # [ 54.393085] systemd[1]: Starting resolvconf update... vm-test-run-pg-upgrade-start-guard> server # [ 54.443801] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-pg-upgrade-start-guard> server # [ 55.955113] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-pg-upgrade-start-guard> server # [ 56.213486] runuser[621]: pam_unix(runuser:session): session opened for user postgres(uid=997) by (uid=0) vm-test-run-pg-upgrade-start-guard> server # [ 56.488727] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pg-upgrade-start-guard> server # [ 56.510787] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pg-upgrade-start-guard> server # [ 56.529821] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pg-upgrade-start-guard> server # [ 56.556265] nsncd[610]: Oct 26 13:08:16.409 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pg-upgrade-start-guard> server # [ 56.598989] systemd[1]: Starting User Login Management... vm-test-run-pg-upgrade-start-guard> server # [ 57.118573] dbus-daemon[614]: dbus[614]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-pg-upgrade-start-guard> server # [ 57.767570] systemd[1]: Started D-Bus System Message Bus. vm-test-run-pg-upgrade-start-guard> server # [ 58.653734] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-pg-upgrade-start-guard> server # [ 58.668152] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-pg-upgrade-start-guard> server # [ 58.716455] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-pg-upgrade-start-guard> server # [ 58.723952] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-pg-upgrade-start-guard> server # [ 58.739318] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-pg-upgrade-start-guard> server # [ 58.763865] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 58.827147] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-pg-upgrade-start-guard> server # [ 58.910775] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pg-upgrade-start-guard> server # [ 59.507280] systemd-logind[629]: New seat seat0. vm-test-run-pg-upgrade-start-guard> server # [ 59.593468] systemd[1]: Started User Login Management. vm-test-run-pg-upgrade-start-guard> server # [ 59.645892] systemd[1]: Starting linger-users.service... vm-test-run-pg-upgrade-start-guard> server: Guest root shell did not produce any data yet... vm-test-run-pg-upgrade-start-guard> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-pg-upgrade-start-guard> server # [ 59.937288] init-old[623]: The files belonging to this database system will be owned by user "postgres". vm-test-run-pg-upgrade-start-guard> server # [ 59.966428] init-old[623]: This user must also own the server process. vm-test-run-pg-upgrade-start-guard> server # [ 60.109389] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pg-upgrade-start-guard> server # [ 60.137863] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pg-upgrade-start-guard> server # [ 60.154704] init-old[623]: The database cluster will be initialized with locale "C". vm-test-run-pg-upgrade-start-guard> server # [ 60.177667] init-old[623]: The default text search configuration will be set to "english". vm-test-run-pg-upgrade-start-guard> server # [ 60.187007] init-old[623]: Data page checksums are disabled. vm-test-run-pg-upgrade-start-guard> server # [ 60.195743] init-old[623]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-pg-upgrade-start-guard> server # [ 60.245467] nsncd[674]: Oct 26 13:08:20.077 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pg-upgrade-start-guard> server # [ 60.259286] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pg-upgrade-start-guard> server # [ 60.274605] init-old[623]: creating subdirectories ... ok vm-test-run-pg-upgrade-start-guard> server # [ 60.286835] init-old[623]: selecting dynamic shared memory implementation ... posix vm-test-run-pg-upgrade-start-guard> server # [ 60.471784] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 60.483103] systemd[1]: Finished linger-users.service. vm-test-run-pg-upgrade-start-guard> server # [ 61.022003] systemd[1]: Finished resolvconf update. vm-test-run-pg-upgrade-start-guard> server # [ 61.057581] systemd[1]: Reached target Preparation for Network. vm-test-run-pg-upgrade-start-guard> server # [ 61.106650] systemd[1]: Starting DHCP Client... vm-test-run-pg-upgrade-start-guard> server # [ 61.996638] init-old[623]: selecting default max_connections ... 100 vm-test-run-pg-upgrade-start-guard> server # [ 62.967173] init-old[623]: selecting default shared_buffers ... 128MB vm-test-run-pg-upgrade-start-guard> server # [ 64.183359] dhcpcd[713]: dhcpcd-10.2.4 starting vm-test-run-pg-upgrade-start-guard> server # [ 64.486747] dhcpcd[729]: dev: loaded udev vm-test-run-pg-upgrade-start-guard> server # [ 64.936838] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-pg-upgrade-start-guard> server # [ 65.554889] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-pg-upgrade-start-guard> server # [ 65.785800] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-pg-upgrade-start-guard> server # [ 65.789936] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-pg-upgrade-start-guard> server # [ 65.814985] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-pg-upgrade-start-guard> server # [ 65.818028] cfg80211: failed to load regulatory.db vm-test-run-pg-upgrade-start-guard> server # [ 66.829880] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-pg-upgrade-start-guard> server # [ 66.783463] dhcpcd[729]: eth0: waiting for carrier vm-test-run-pg-upgrade-start-guard> server # [ 66.798648] dhcpcd[729]: eth0: carrier acquired vm-test-run-pg-upgrade-start-guard> server # [ 67.008387] dhcpcd[729]: DUID 00:01:00:01:32:72:0d:4a:52:54:00:12:34:56 vm-test-run-pg-upgrade-start-guard> server # [ 67.015411] dhcpcd[729]: eth0: IAID 00:12:34:56 vm-test-run-pg-upgrade-start-guard> server # [ 67.019372] dhcpcd[729]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-pg-upgrade-start-guard> server # [ 67.360854] dhcpcd[729]: eth0: soliciting a DHCP lease vm-test-run-pg-upgrade-start-guard> server # [ 67.409936] dhcpcd[729]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-pg-upgrade-start-guard> server # [ 67.429345] dhcpcd[729]: eth0: probing address 10.0.2.15/24 vm-test-run-pg-upgrade-start-guard> server # [ 68.597680] dhcpcd[729]: eth0: soliciting an IPv6 router vm-test-run-pg-upgrade-start-guard> server # [ 68.616676] dhcpcd[729]: eth0: Router Advertisement from fe80::2 vm-test-run-pg-upgrade-start-guard> server # [ 68.629915] dhcpcd[729]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-pg-upgrade-start-guard> server # [ 68.647900] dhcpcd[729]: eth0: adding route to fec0::/64 vm-test-run-pg-upgrade-start-guard> server # [ 68.663020] dhcpcd[729]: eth0: adding default route via fe80::2 vm-test-run-pg-upgrade-start-guard> server # [ 72.525059] dhcpcd[729]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-pg-upgrade-start-guard> server # [ 72.534708] dhcpcd[729]: eth0: adding route to 10.0.2.0/24 vm-test-run-pg-upgrade-start-guard> server # [ 72.542194] dhcpcd[729]: eth0: adding default route via 10.0.2.2 vm-test-run-pg-upgrade-start-guard> server # [ 73.584031] init-old[623]: selecting default time zone ... UTC vm-test-run-pg-upgrade-start-guard> server # [ 73.641524] init-old[623]: creating configuration files ... ok vm-test-run-pg-upgrade-start-guard> server # [ 75.598155] systemd[1]: Started DHCP Client. vm-test-run-pg-upgrade-start-guard> server # [ 78.983854] init-old[623]: running bootstrap script ... ok vm-test-run-pg-upgrade-start-guard> server # [ 81.173372] systemd[1]: Found device /dev/hvc0. vm-test-run-pg-upgrade-start-guard> server # [ 81.875931] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-pg-upgrade-start-guard> server # [ 81.929191] systemd[1]: Started backdoor.service. vm-test-run-pg-upgrade-start-guard> server # connecting to host... vm-test-run-pg-upgrade-start-guard> server # [ 82.938319] (udev-worker)[879]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pg-upgrade-start-guard> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-pg-upgrade-start-guard> server: connected to guest root shell vm-test-run-pg-upgrade-start-guard> server: (connecting took 83.42 seconds) vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for the VM to finish booting, in 83.42 seconds) vm-test-run-pg-upgrade-start-guard> server # [ 84.123978] dhcpcd[729]: eth0: IAID 00:12:34:56 vm-test-run-pg-upgrade-start-guard> server # [ 84.202914] dhcpcd[729]: eth0: soliciting an IPv6 router vm-test-run-pg-upgrade-start-guard> server # [ 85.038148] dhcpcd[729]: eth0: rebinding lease of 10.0.2.15 vm-test-run-pg-upgrade-start-guard> server # [ 85.061242] dhcpcd[729]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-pg-upgrade-start-guard> server # [ 87.405712] (udev-worker)[922]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-pg-upgrade-start-guard> server # [ 87.438656] (udev-worker)[922]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pg-upgrade-start-guard> server # [ 89.222358] systemd[1]: Found device Virtio network device. vm-test-run-pg-upgrade-start-guard> server # [ 89.248821] systemd[1]: Starting Address configuration of eth1... vm-test-run-pg-upgrade-start-guard> server # [ 90.466656] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-pg-upgrade-start-guard> server # [ 90.653907] network-addresses-eth1-start[943]: adding address 192.168.1.1/24... done vm-test-run-pg-upgrade-start-guard> server # [ 90.826912] network-addresses-eth1-start[943]: adding address 2001:db8:1::1/64... done vm-test-run-pg-upgrade-start-guard> server # [ 91.138528] systemd[1]: Finished Address configuration of eth1. vm-test-run-pg-upgrade-start-guard> server # [ 91.169053] systemd[1]: Starting Networking Setup... vm-test-run-pg-upgrade-start-guard> server # [ 94.127325] systemd[1]: Finished Networking Setup. vm-test-run-pg-upgrade-start-guard> server # [ 94.151376] systemd[1]: Reached target Network. vm-test-run-pg-upgrade-start-guard> server # [ 94.193010] systemd[1]: Starting Permit User Sessions... vm-test-run-pg-upgrade-start-guard> server # [ 95.026359] systemd[1]: Finished Permit User Sessions. vm-test-run-pg-upgrade-start-guard> server # [ 95.096380] systemd[1]: Started Getty on tty1. vm-test-run-pg-upgrade-start-guard> server # [ 95.097564] systemd[1]: Reached target Login Prompts. vm-test-run-pg-upgrade-start-guard> server # [ 101.499672] init-old[623]: performing post-bootstrap initialization ... ok vm-test-run-pg-upgrade-start-guard> server # [ 105.500131] init-old[623]: syncing data to disk ... ok vm-test-run-pg-upgrade-start-guard> server # [ 105.509678] init-old[623]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-pg-upgrade-start-guard> server # [ 105.510211] init-old[623]: 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. vm-test-run-pg-upgrade-start-guard> server # [ 105.511078] init-old[623]: Success. You can now start the database server using: vm-test-run-pg-upgrade-start-guard> server # [ 105.511534] init-old[623]: /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-pg-upgrade-start-guard> server # [ 105.547021] runuser[621]: pam_unix(runuser:session): session closed for user postgres vm-test-run-pg-upgrade-start-guard> server # [ 105.746731] systemd-logind[629]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-pg-upgrade-start-guard> server # [ 106.627208] postgres[1103]: 2026-10-26 13:09:06.603 UTC [1103] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pg-upgrade-start-guard> server # [ 106.773781] postgres[1103]: 2026-10-26 13:09:06.755 UTC [1103] LOG: listening on IPv6 address "::1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 106.785487] postgres[1103]: 2026-10-26 13:09:06.767 UTC [1103] LOG: listening on IPv4 address "127.0.0.1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 106.799548] postgres[1103]: 2026-10-26 13:09:06.781 UTC [1103] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pg-upgrade-start-guard> server # [ 106.900008] postgres[1110]: 2026-10-26 13:09:06.880 UTC [1110] LOG: database system was shut down at 2026-10-26 13:09:01 UTC vm-test-run-pg-upgrade-start-guard> server # [ 107.013814] postgres[1103]: 2026-10-26 13:09:06.996 UTC [1103] LOG: database system is ready to accept connections vm-test-run-pg-upgrade-start-guard> server # [ 107.078129] systemd[1]: Started postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 107.079534] systemd[1]: Reached target Multi-User System. vm-test-run-pg-upgrade-start-guard> server # [ 107.092133] systemd[1]: Startup finished in 18.795s (kernel) + 1min 28.277s (userspace) = 1min 47.073s. vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for unit postgresql.service, in 108.59 seconds) vm-test-run-pg-upgrade-start-guard> subtest: Seed the source cluster vm-test-run-pg-upgrade-start-guard> server: must succeed: /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/psql -h 127.0.0.1 -p 5432 -U postgres -d postgres -Atc "create table seed as select g as id, repeat(md5(g::text), 20) as pad from generate_series(1, 100000) g" vm-test-run-pg-upgrade-start-guard> server # [ 108.739243] mousedev: PS/2 mouse device common for all mice vm-test-run-pg-upgrade-start-guard> server # [ 111.603438] systemd-logind[629]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/psql -h 127.0.0.1 -p 5432 -U postgres -d postgres -Atc "create table seed as select g as id, repeat(md5(g::text), 20) as pad from generate_series(1, 100000) g", in 12.21 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/psql -h 127.0.0.1 -p 5432 -U postgres -d postgres -Atc "select count(*) || ',' || sum(id) from seed" vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/psql -h 127.0.0.1 -p 5432 -U postgres -d postgres -Atc "select count(*) || ',' || sum(id) from seed", in 1.87 seconds) vm-test-run-pg-upgrade-start-guard> (finished: subtest: Seed the source cluster, in 14.08 seconds) vm-test-run-pg-upgrade-start-guard> subtest: Control: with no guard the start loop brings the source up vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl stop postgresql vm-test-run-pg-upgrade-start-guard> server # [ 122.666970] systemd[1]: Stopping postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 122.721970] postgres[1103]: 2026-10-26 13:09:22.633 UTC [1103] LOG: received fast shutdown request vm-test-run-pg-upgrade-start-guard> server # [ 122.722899] postgres[1103]: 2026-10-26 13:09:22.670 UTC [1103] LOG: aborting any active transactions vm-test-run-pg-upgrade-start-guard> server # [ 122.853077] postgres[1103]: 2026-10-26 13:09:22.827 UTC [1103] LOG: background worker "logical replication launcher" (PID 1113) exited with exit code 1 vm-test-run-pg-upgrade-start-guard> server # [ 122.889748] postgres[1108]: 2026-10-26 13:09:22.857 UTC [1108] LOG: shutting down vm-test-run-pg-upgrade-start-guard> server # [ 122.893488] postgres[1108]: 2026-10-26 13:09:22.876 UTC [1108] LOG: checkpoint starting: shutdown immediate vm-test-run-pg-upgrade-start-guard> server # [ 123.629271] postgres[1108]: 2026-10-26 13:09:23.606 UTC [1108] LOG: checkpoint complete: wrote 2176 buffers (13.3%); 0 WAL file(s) added, 0 removed, 4 recycled; write=0.381 s, sync=0.232 s, total=0.742 s; sync files=28, longest=0.177 s, average=0.009 s; distance=69073 kB, estimate=69073 kB vm-test-run-pg-upgrade-start-guard> server # [ 123.739968] postgres[1103]: 2026-10-26 13:09:23.718 UTC [1103] LOG: database system is shut down vm-test-run-pg-upgrade-start-guard> server # [ 123.802740] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 123.821052] systemd[1]: Stopped postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 123.822413] systemd[1]: postgresql.service: Consumed 19.581s CPU time, 233.3M memory peak, 241.1M written to disk, 1.2K incoming IP traffic, 1.8K outgoing IP traffic. vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl stop postgresql, in 1.62 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemd-run --unit=salt-sim --collect /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim vm-test-run-pg-upgrade-start-guard> server # [ 124.478017] systemd[1]: Started [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim. vm-test-run-pg-upgrade-start-guard> server # Running as unit: salt-sim.service; invocation ID: 9065dfacaf5043b3a7d57b23eb102230 vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemd-run --unit=salt-sim --collect /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim, in 0.72 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for success: systemctl is-active --quiet postgresql vm-test-run-pg-upgrade-start-guard> server # [ 125.348401] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 126.493583] postgres[1241]: 2026-10-26 13:09:26.461 UTC [1241] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pg-upgrade-start-guard> server # [ 126.506302] postgres[1241]: 2026-10-26 13:09:26.486 UTC [1241] LOG: listening on IPv6 address "::1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 126.507308] postgres[1241]: 2026-10-26 13:09:26.486 UTC [1241] LOG: listening on IPv4 address "127.0.0.1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 126.525835] postgres[1241]: 2026-10-26 13:09:26.505 UTC [1241] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pg-upgrade-start-guard> server # [ 126.627289] postgres[1255]: 2026-10-26 13:09:26.605 UTC [1255] LOG: database system was shut down at 2026-10-26 13:09:23 UTC vm-test-run-pg-upgrade-start-guard> server # [ 126.800078] postgres[1241]: 2026-10-26 13:09:26.780 UTC [1241] LOG: database system is ready to accept connections vm-test-run-pg-upgrade-start-guard> server # [ 126.835307] systemd[1]: Started postgresql.service. vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for success: systemctl is-active --quiet postgresql, in 2.76 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl stop salt-sim vm-test-run-pg-upgrade-start-guard> server # [ 128.107642] systemd[1]: Stopping [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim... vm-test-run-pg-upgrade-start-guard> server # [ 128.201363] systemd[1]: salt-sim.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 128.213990] systemd[1]: Stopped [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim. vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl stop salt-sim, in 0.94 seconds) vm-test-run-pg-upgrade-start-guard> (finished: subtest: Control: with no guard the start loop brings the source up, in 6.05 seconds) vm-test-run-pg-upgrade-start-guard> subtest: A systemd start is refused while the block is held vm-test-run-pg-upgrade-start-guard> server: must succeed: systemd-run --unit=hold-block --collect /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive hold_block vm-test-run-pg-upgrade-start-guard> server # [ 128.891228] systemd[1]: Started [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive hold_block. vm-test-run-pg-upgrade-start-guard> server # Running as unit: hold-block.service; invocation ID: 9d6cdbc3f37245e584c355d9541a75df vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemd-run --unit=hold-block --collect /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive hold_block, in 0.69 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for success: systemctl show -p ExecCondition postgresql | grep -q start-guard vm-test-run-pg-upgrade-start-guard> server # [ 129.547131] systemd[1]: Stopping postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 129.599275] postgres[1241]: 2026-10-26 13:09:29.526 UTC [1241] LOG: received fast shutdown request vm-test-run-pg-upgrade-start-guard> server # [ 129.600163] postgres[1241]: 2026-10-26 13:09:29.546 UTC [1241] LOG: aborting any active transactions vm-test-run-pg-upgrade-start-guard> server # [ 129.749832] postgres[1241]: 2026-10-26 13:09:29.728 UTC [1241] LOG: background worker "logical replication launcher" (PID 1259) exited with exit code 1 vm-test-run-pg-upgrade-start-guard> server # [ 129.778045] postgres[1251]: 2026-10-26 13:09:29.735 UTC [1251] LOG: shutting down vm-test-run-pg-upgrade-start-guard> server # [ 129.779232] postgres[1251]: 2026-10-26 13:09:29.753 UTC [1251] LOG: checkpoint starting: shutdown immediate vm-test-run-pg-upgrade-start-guard> server # [ 129.869378] postgres[1251]: 2026-10-26 13:09:29.846 UTC [1251] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.025 s, sync=0.015 s, total=0.111 s; sync files=2, longest=0.011 s, average=0.008 s; distance=0 kB, estimate=0 kB vm-test-run-pg-upgrade-start-guard> server # [ 129.978287] postgres[1241]: 2026-10-26 13:09:29.954 UTC [1241] LOG: database system is shut down vm-test-run-pg-upgrade-start-guard> server # [ 130.061595] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 130.085653] systemd[1]: Stopped postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 130.512990] systemd[1]: Reload requested from client PID 1304 ('systemctl') (unit hold-block.service)... vm-test-run-pg-upgrade-start-guard> server # [ 130.514227] systemd[1]: Reloading... vm-test-run-pg-upgrade-start-guard> server # [ 135.823936] systemd[1]: Reloading finished in 5292 ms. vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for success: systemctl show -p ExecCondition postgresql | grep -q start-guard, in 7.48 seconds) vm-test-run-pg-upgrade-start-guard> server: must fail: systemctl start postgresql vm-test-run-pg-upgrade-start-guard> server # [ 137.049440] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 137.615627] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 137.629572] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 137.638132] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # Job for postgresql.service failed because the control process exited with error code. vm-test-run-pg-upgrade-start-guard> server # See "systemctl status postgresql.service" and "journalctl -xeu postgresql.service" for details. vm-test-run-pg-upgrade-start-guard> server: (finished: must fail: systemctl start postgresql, in 1.24 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: sleep 5 vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: sleep 5, in 5.48 seconds) vm-test-run-pg-upgrade-start-guard> server: must fail: systemctl is-active --quiet postgresql vm-test-run-pg-upgrade-start-guard> server: (finished: must fail: systemctl is-active --quiet postgresql, in 0.45 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl show -p Restart postgresql vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl show -p Restart postgresql, in 0.59 seconds) vm-test-run-pg-upgrade-start-guard> (finished: subtest: A systemd start is refused while the block is held, in 15.93 seconds) vm-test-run-pg-upgrade-start-guard> subtest: The block expires on its own when the holder is SIGKILLed vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl kill -s KILL hold-block vm-test-run-pg-upgrade-start-guard> server # [ 144.728135] systemd[1]: hold-block.service: Sent signal SIGKILL to main process 1278 (sleep) on client request. vm-test-run-pg-upgrade-start-guard> server # [ 144.751653] systemd[1]: hold-block.service: Killed unit cgroup '/system.slice/hold-block.service' with SIGKILL on client request. vm-test-run-pg-upgrade-start-guard> server # [ 144.766266] systemd[1]: hold-block.service: Main process exited, code=killed, status=9/KILL vm-test-run-pg-upgrade-start-guard> server # [ 144.775747] systemd[1]: hold-block.service: Failed with result 'signal'. vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl kill -s KILL hold-block, in 0.58 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for failure: systemctl is-active --quiet hold-block vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for failure: systemctl is-active --quiet hold-block, in 0.68 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl start postgresql vm-test-run-pg-upgrade-start-guard> server # [ 145.980245] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 147.967040] postgres[1420]: 2026-10-26 13:09:47.942 UTC [1420] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pg-upgrade-start-guard> server # [ 147.986967] postgres[1420]: 2026-10-26 13:09:47.969 UTC [1420] LOG: listening on IPv6 address "::1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 147.994254] postgres[1420]: 2026-10-26 13:09:47.972 UTC [1420] LOG: listening on IPv4 address "127.0.0.1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 148.003986] postgres[1420]: 2026-10-26 13:09:47.986 UTC [1420] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pg-upgrade-start-guard> server # [ 148.124118] postgres[1423]: 2026-10-26 13:09:48.097 UTC [1423] LOG: database system was shut down at 2026-10-26 13:09:29 UTC vm-test-run-pg-upgrade-start-guard> server # [ 148.238713] postgres[1420]: 2026-10-26 13:09:48.220 UTC [1420] LOG: database system is ready to accept connections vm-test-run-pg-upgrade-start-guard> server # [ 149.102592] systemd[1]: Reload requested from client PID 1435 ('systemctl') (unit postgresql.service)... vm-test-run-pg-upgrade-start-guard> server # [ 149.122054] systemd[1]: Reloading... vm-test-run-pg-upgrade-start-guard> server # [ 155.093998] systemd[1]: Reloading finished in 5967 ms. vm-test-run-pg-upgrade-start-guard> server # [ 155.434797] systemd[1]: Started postgresql.service. vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl start postgresql, in 10.08 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for unit postgresql.service vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for unit postgresql.service, in 0.90 seconds) vm-test-run-pg-upgrade-start-guard> server: must fail: test -e /run/systemd/system/postgresql.service.d/pg-upgrade-lock.conf vm-test-run-pg-upgrade-start-guard> server: (finished: must fail: test -e /run/systemd/system/postgresql.service.d/pg-upgrade-lock.conf, in 0.34 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl show -p Restart postgresql vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl show -p Restart postgresql, in 0.54 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl show -p NeedDaemonReload postgresql vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl show -p NeedDaemonReload postgresql, in 0.56 seconds) vm-test-run-pg-upgrade-start-guard> (finished: subtest: The block expires on its own when the holder is SIGKILLed, in 13.67 seconds) vm-test-run-pg-upgrade-start-guard> subtest: The pid lock stops a direct start once the new cluster is up vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl stop postgresql vm-test-run-pg-upgrade-start-guard> server # [ 158.415561] postgres[1420]: 2026-10-26 13:09:58.390 UTC [1420] LOG: received fast shutdown request vm-test-run-pg-upgrade-start-guard> server # [ 158.465799] systemd[1]: Stopping postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 158.467168] postgres[1420]: 2026-10-26 13:09:58.416 UTC [1420] LOG: aborting any active transactions vm-test-run-pg-upgrade-start-guard> server # [ 158.613412] postgres[1420]: 2026-10-26 13:09:58.593 UTC [1420] LOG: background worker "logical replication launcher" (PID 1426) exited with exit code 1 vm-test-run-pg-upgrade-start-guard> server # [ 158.637802] postgres[1421]: 2026-10-26 13:09:58.605 UTC [1421] LOG: shutting down vm-test-run-pg-upgrade-start-guard> server # [ 158.644814] postgres[1421]: 2026-10-26 13:09:58.626 UTC [1421] LOG: checkpoint starting: shutdown immediate vm-test-run-pg-upgrade-start-guard> server # [ 158.752988] postgres[1421]: 2026-10-26 13:09:58.733 UTC [1421] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.043 s, sync=0.007 s, total=0.126 s; sync files=2, longest=0.004 s, average=0.004 s; distance=0 kB, estimate=0 kB vm-test-run-pg-upgrade-start-guard> server # [ 158.817009] postgres[1420]: 2026-10-26 13:09:58.795 UTC [1420] LOG: database system is shut down vm-test-run-pg-upgrade-start-guard> server # [ 158.875207] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 158.886257] systemd[1]: Stopped postgresql.service. vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl stop postgresql, in 1.07 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemd-run --unit=hold-lock --collect /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive hold_lock vm-test-run-pg-upgrade-start-guard> server # [ 159.451546] systemd[1]: Started [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive hold_lock. vm-test-run-pg-upgrade-start-guard> server # Running as unit: hold-lock.service; invocation ID: d1076a9bcb9a42ccaf1b469c3842c035 vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemd-run --unit=hold-lock --collect /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive hold_lock, in 0.58 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for success: test -s /var/lib/postgresql/data/postmaster.pid vm-test-run-pg-upgrade-start-guard> server # [ 160.346636] runuser[1517]: pam_unix(runuser:session): session opened for user postgres(uid=997) by (uid=0) vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for success: test -s /var/lib/postgresql/data/postmaster.pid, in 2.16 seconds) vm-test-run-pg-upgrade-start-guard> server: must fail: runuser -u postgres -- /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/pg_ctl start -D /var/lib/postgresql/data -w -s -l /tmp/pg_ctl.log -t 5 vm-test-run-pg-upgrade-start-guard> server # [ 161.837388] drive[1518]: [13:10:01] Locked /var/lib/postgresql/data with pg_upgrade pid 1521 vm-test-run-pg-upgrade-start-guard> server # [ 162.187944] runuser[1536]: pam_unix(runuser:session): session opened for user postgres(uid=997) by (uid=0) vm-test-run-pg-upgrade-start-guard> server # pg_ctl: another server might be running; trying to start server anyway vm-test-run-pg-upgrade-start-guard> server # pg_ctl: could not start server vm-test-run-pg-upgrade-start-guard> server # Examine the log output. vm-test-run-pg-upgrade-start-guard> server # [ 163.400813] runuser[1536]: pam_unix(runuser:session): session closed for user postgres vm-test-run-pg-upgrade-start-guard> server: (finished: must fail: runuser -u postgres -- /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/pg_ctl start -D /var/lib/postgresql/data -w -s -l /tmp/pg_ctl.log -t 5, in 1.70 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl kill -s KILL hold-lock vm-test-run-pg-upgrade-start-guard> server # [ 163.922502] systemd[1]: hold-lock.service: Sent signal SIGKILL to main process 1511 (drive) on client request. vm-test-run-pg-upgrade-start-guard> server # [ 163.941756] systemd[1]: hold-lock.service: Killed unit cgroup '/system.slice/hold-lock.service' with SIGKILL on client request. vm-test-run-pg-upgrade-start-guard> server # [ 163.995373] systemd[1]: hold-lock.service: Main process exited, code=killed, status=9/KILL vm-test-run-pg-upgrade-start-guard> server # [ 164.037624] systemd[1]: hold-lock.service: Failed with result 'signal'. vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl kill -s KILL hold-lock, in 0.62 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for failure: systemctl is-active --quiet hold-lock vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for failure: systemctl is-active --quiet hold-lock, in 0.70 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: runuser -u postgres -- /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/pg_ctl start -D /var/lib/postgresql/data -w -s -l /tmp/pg_ctl.log vm-test-run-pg-upgrade-start-guard> server # [ 165.215526] runuser[1565]: pam_unix(runuser:session): session opened for user postgres(uid=997) by (uid=0) vm-test-run-pg-upgrade-start-guard> server # pg_ctl: another server might be running; trying to start server anyway vm-test-run-pg-upgrade-start-guard> server # [ 166.835257] runuser[1565]: pam_unix(runuser:session): session closed for user postgres vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: runuser -u postgres -- /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/pg_ctl start -D /var/lib/postgresql/data -w -s -l /tmp/pg_ctl.log , in 2.11 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: runuser -u postgres -- /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/pg_ctl stop -D /var/lib/postgresql/data -w -s vm-test-run-pg-upgrade-start-guard> server # [ 167.326658] runuser[1592]: pam_unix(runuser:session): session opened for user postgres(uid=997) by (uid=0) vm-test-run-pg-upgrade-start-guard> server # [ 167.749947] runuser[1592]: pam_unix(runuser:session): session closed for user postgres vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: runuser -u postgres -- /nix/store/pi0h02x85f8iisji4nfv287jky9c1cl5-postgresql-and-plugins-15.19/bin/pg_ctl stop -D /var/lib/postgresql/data -w -s , in 0.96 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: rm -f /run/pg-upgrade-source.pid vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: rm -f /run/pg-upgrade-source.pid, in 0.35 seconds) vm-test-run-pg-upgrade-start-guard> server: must succeed: systemctl start postgresql vm-test-run-pg-upgrade-start-guard> server # [ 168.674854] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 170.084315] postgres[1620]: 2026-10-26 13:10:10.057 UTC [1620] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pg-upgrade-start-guard> server # [ 170.105347] postgres[1620]: 2026-10-26 13:10:10.087 UTC [1620] LOG: listening on IPv6 address "::1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 170.109646] postgres[1620]: 2026-10-26 13:10:10.090 UTC [1620] LOG: listening on IPv4 address "127.0.0.1", port 5432 vm-test-run-pg-upgrade-start-guard> server # [ 170.127707] postgres[1620]: 2026-10-26 13:10:10.107 UTC [1620] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pg-upgrade-start-guard> server # [ 170.236026] postgres[1623]: 2026-10-26 13:10:10.213 UTC [1623] LOG: database system was shut down at 2026-10-26 13:10:07 UTC vm-test-run-pg-upgrade-start-guard> server # [ 170.371190] postgres[1620]: 2026-10-26 13:10:10.351 UTC [1620] LOG: database system is ready to accept connections vm-test-run-pg-upgrade-start-guard> server # [ 170.411597] systemd[1]: Started postgresql.service. vm-test-run-pg-upgrade-start-guard> server: (finished: must succeed: systemctl start postgresql, in 2.35 seconds) vm-test-run-pg-upgrade-start-guard> server: waiting for unit postgresql.service vm-test-run-pg-upgrade-start-guard> server: (finished: waiting for unit postgresql.service, in 0.73 seconds) vm-test-run-pg-upgrade-start-guard> (finished: subtest: The pid lock stops a direct start once the new cluster is up, in 13.33 seconds) vm-test-run-pg-upgrade-start-guard> subtest: A real pg_upgrade copy under a start storm vm-test-run-pg-upgrade-start-guard> server: must succeed: systemd-run --unit=fake-initiate --collect --pipe --wait /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive upgrade vm-test-run-pg-upgrade-start-guard> server # [ 171.794123] systemd[1]: Started [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive upgrade. vm-test-run-pg-upgrade-start-guard> server # Running as unit: fake-initiate.service; invocation ID: 77d4be16f60a4ac49082794d37009230 vm-test-run-pg-upgrade-start-guard> server # [ 172.464507] systemd[1]: Stopping postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 172.501774] postgres[1620]: 2026-10-26 13:10:12.441 UTC [1620] LOG: received fast shutdown request vm-test-run-pg-upgrade-start-guard> server # [ 172.502841] postgres[1620]: 2026-10-26 13:10:12.474 UTC [1620] LOG: aborting any active transactions vm-test-run-pg-upgrade-start-guard> server # [ 172.625604] postgres[1620]: 2026-10-26 13:10:12.606 UTC [1620] LOG: background worker "logical replication launcher" (PID 1626) exited with exit code 1 vm-test-run-pg-upgrade-start-guard> server # [ 172.641834] postgres[1621]: 2026-10-26 13:10:12.606 UTC [1621] LOG: shutting down vm-test-run-pg-upgrade-start-guard> server # [ 172.647511] postgres[1621]: 2026-10-26 13:10:12.626 UTC [1621] LOG: checkpoint starting: shutdown immediate vm-test-run-pg-upgrade-start-guard> server # [ 172.728588] postgres[1621]: 2026-10-26 13:10:12.711 UTC [1621] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.031 s, sync=0.008 s, total=0.105 s; sync files=2, longest=0.005 s, average=0.004 s; distance=0 kB, estimate=0 kB vm-test-run-pg-upgrade-start-guard> server # [ 172.797491] postgres[1620]: 2026-10-26 13:10:12.780 UTC [1620] LOG: database system is shut down vm-test-run-pg-upgrade-start-guard> server # [ 172.867584] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 172.876939] systemd[1]: Stopped postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 173.378242] systemd[1]: Reload requested from client PID 1658 ('systemctl') (unit fake-initiate.service)... vm-test-run-pg-upgrade-start-guard> server # [ 173.391672] systemd[1]: Reloading... vm-test-run-pg-upgrade-start-guard> server # [ 178.879250] systemd[1]: Reloading finished in 5480 ms. vm-test-run-pg-upgrade-start-guard> server # [ 179.459170] runuser[1688]: pam_unix(runuser:session): session opened for user postgres(uid=997) by (uid=0) vm-test-run-pg-upgrade-start-guard> server # [ 186.983023] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 vm-test-run-pg-upgrade-start-guard> server # [ 203.756174] systemd[1]: Starting Virtual Console Setup... vm-test-run-pg-upgrade-start-guard> server # [ 204.106640] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 204.108279] systemd[1]: Stopped Virtual Console Setup. vm-test-run-pg-upgrade-start-guard> server # [ 204.178256] systemd[1]: Starting Virtual Console Setup... vm-test-run-pg-upgrade-start-guard> server # [ 209.512890] systemd[1]: Finished Virtual Console Setup. vm-test-run-pg-upgrade-start-guard> server # [ 212.118871] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. vm-test-run-pg-upgrade-start-guard> server # [ 213.210533] systemd-logind[629]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) vm-test-run-pg-upgrade-start-guard> server # initdb: warning: enabling "trust" authentication for local connections vm-test-run-pg-upgrade-start-guard> server # 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. vm-test-run-pg-upgrade-start-guard> server # [ 215.771082] runuser[1688]: pam_unix(runuser:session): session closed for user postgres vm-test-run-pg-upgrade-start-guard> server # [ 216.011037] systemd[1]: Started [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim. vm-test-run-pg-upgrade-start-guard> server # Running as unit: salt-sim.service; invocation ID: 1d193074f6ae41ee830ca1f022face39 vm-test-run-pg-upgrade-start-guard> server # [ 216.355521] runuser[1836]: pam_unix(runuser:session): session opened for user postgres(uid=997) by (uid=0) vm-test-run-pg-upgrade-start-guard> server # [ 216.627604] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 217.103977] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 217.130152] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 217.131379] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 217.881913] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 218.419809] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 218.457585] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 218.458487] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 219.179486] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 219.743696] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 219.770361] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 219.771209] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 220.513709] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 221.042264] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 221.043876] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 221.065848] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 221.799367] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 222.346649] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 222.348047] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 222.377811] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 223.008277] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 223.015126] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 223.037855] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 223.715682] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 223.735300] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 223.756795] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 224.417890] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 224.418923] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 224.419379] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 225.171023] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 225.190139] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 225.190886] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 225.833702] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 225.835554] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 225.854200] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 226.490277] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 226.492089] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 226.505033] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 227.153288] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 227.600917] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 227.640191] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 227.650515] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 228.281150] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 228.762116] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 228.763479] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 228.798624] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 229.411102] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 229.854252] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 229.855284] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 229.881982] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 230.495117] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 230.934974] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 230.964894] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 230.965998] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 231.634364] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 232.193648] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 232.195293] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 232.217423] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 232.903099] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 232.906203] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 232.906916] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 233.554541] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 233.577921] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 233.578849] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 234.173020] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 234.183065] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 234.184068] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 234.849301] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 234.869088] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 234.870048] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 235.461022] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 235.462378] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 235.463200] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 236.131814] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 236.161207] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 236.162058] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 236.745428] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 236.746710] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 236.747540] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 237.387830] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 237.821056] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 237.826694] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 237.857631] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 238.528769] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 239.034329] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 239.035948] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 239.060177] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 239.776733] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 240.273388] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 240.279558] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 240.306546] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 241.010714] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 241.437707] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 241.439207] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 241.461931] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 242.134749] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 242.607969] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 242.616330] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 242.633807] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 243.293567] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 243.296393] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 243.310033] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 243.865673] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 243.866845] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 243.867566] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 244.501831] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 244.509317] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 244.510166] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 245.097639] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 245.098813] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 245.099526] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 245.706119] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 245.707371] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 245.708029] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 246.318140] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 246.319404] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 246.320251] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 246.920498] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 246.921597] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 246.944128] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 247.531366] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 248.130742] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 248.132140] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 248.174128] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 248.841232] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 249.300559] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 249.319294] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 249.320319] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 249.955240] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 250.380018] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 250.405869] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 250.406564] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 251.073541] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 251.622638] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 251.624005] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 251.654865] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 252.311973] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 252.846778] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 252.876144] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 252.878225] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 253.495215] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 253.501823] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 253.503476] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 254.163569] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 254.172202] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 254.190575] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 254.857321] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 254.861759] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 254.862415] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 255.575969] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 255.601818] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 255.602744] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 256.298053] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 256.309590] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 256.310375] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 256.915270] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 256.929025] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 256.930344] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 257.528359] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 257.963194] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 257.995836] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 258.002112] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 258.652023] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 259.083074] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 259.084230] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 259.113602] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 259.700776] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 260.111805] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 260.140191] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 260.143988] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 260.756176] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 261.223791] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 261.241620] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 261.255332] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 261.853054] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 262.314315] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 262.315822] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 262.341138] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 262.889624] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 262.890785] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 262.891481] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 263.530190] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 263.546493] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 263.547470] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 264.114278] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 264.115747] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 264.137646] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 264.723112] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 264.737992] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 264.739145] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 265.400777] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 265.414426] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 265.415874] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 266.095564] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 266.114462] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 266.115299] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 266.818733] systemd[1]: postgresql.service: Start request repeated too quickly. vm-test-run-pg-upgrade-start-guard> server # [ 266.822763] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 266.823584] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 267.670365] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 268.342772] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 268.357821] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 268.367699] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 269.102724] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 269.610181] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 269.611960] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 269.643776] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 270.251067] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 270.890875] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 270.898146] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 270.919860] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 271.918999] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 272.374488] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 272.386337] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 272.398711] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # [ 272.892554] runuser[1836]: pam_unix(runuser:session): session closed for user postgres vm-test-run-pg-upgrade-start-guard> server # [ 273.132933] systemd[1]: Starting postgresql.service... vm-test-run-pg-upgrade-start-guard> server # [ 273.166632] systemd[1]: Stopping [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim... vm-test-run-pg-upgrade-start-guard> server # [ 273.230709] systemd[1]: salt-sim.service: Deactivated successfully. vm-test-run-pg-upgrade-start-guard> server # [ 273.243114] systemd[1]: Stopped [systemd-run] /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive salt_sim. vm-test-run-pg-upgrade-start-guard> server # [ 273.259711] systemd[1]: salt-sim.service: Consumed 9.199s CPU time, 1.9M memory peak. vm-test-run-pg-upgrade-start-guard> server # [ 273.651964] systemd[1]: fake-initiate.service: Main process exited, code=exited, status=1/FAILURE vm-test-run-pg-upgrade-start-guard> server # [ 273.661780] systemd[1]: fake-initiate.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 273.667505] systemd[1]: fake-initiate.service: Consumed 36.154s CPU time, 148.3M memory peak, 238.2M written to disk. vm-test-run-pg-upgrade-start-guard> server # [ 273.690736] systemd[1]: postgresql.service: Condition check process exited, code=exited, status=255/EXCEPTION vm-test-run-pg-upgrade-start-guard> server # [ 273.701116] systemd[1]: postgresql.service: Failed with result 'exit-code'. vm-test-run-pg-upgrade-start-guard> server # [ 273.703832] systemd[1]: Failed to start postgresql.service. vm-test-run-pg-upgrade-start-guard> server # Finished with result: exit-code vm-test-run-pg-upgrade-start-guard> server # Main processes terminated with: code=exited, status=1/FAILURE vm-test-run-pg-upgrade-start-guard> server # Service runtime: 1min 41.878s vm-test-run-pg-upgrade-start-guard> server # CPU time consumed: 36.154s vm-test-run-pg-upgrade-start-guard> server # Memory peak: 148.3M (swap: 0B) vm-test-run-pg-upgrade-start-guard> server # IO Bytes: written 238.2M vm-test-run-pg-upgrade-start-guard> server: output: The files belonging to this database system will be owned by user "postgres". vm-test-run-pg-upgrade-start-guard> This user must also own the server process. vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> The database cluster will be initialized with locale "C". vm-test-run-pg-upgrade-start-guard> The default text search configuration will be set to "english". vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> Data page checksums are disabled. vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> creating directory /var/lib/postgresql/data-17 ... ok vm-test-run-pg-upgrade-start-guard> creating subdirectories ... ok vm-test-run-pg-upgrade-start-guard> selecting dynamic shared memory implementation ... posix vm-test-run-pg-upgrade-start-guard> selecting default "max_connections" ... 100 vm-test-run-pg-upgrade-start-guard> selecting default "shared_buffers" ... 128MB vm-test-run-pg-upgrade-start-guard> selecting default time zone ... UTC vm-test-run-pg-upgrade-start-guard> creating configuration files ... ok vm-test-run-pg-upgrade-start-guard> running bootstrap script ... ok vm-test-run-pg-upgrade-start-guard> performing post-bootstrap initialization ... ok vm-test-run-pg-upgrade-start-guard> syncing data to disk ... ok vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> Success. You can now start the database server using: vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> /nix/store/b93nnrxmkyz9mmh50hw0sn71rbmswp41-postgresql-and-plugins-17.11/bin/pg_ctl -D /var/lib/postgresql/data-17 -l logfile start vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> Performing Consistency Checks vm-test-run-pg-upgrade-start-guard> ----------------------------- vm-test-run-pg-upgrade-start-guard> Checking cluster versions ok vm-test-run-pg-upgrade-start-guard> Checking database user is the install user ok vm-test-run-pg-upgrade-start-guard> Checking database connection settings ok vm-test-run-pg-upgrade-start-guard> Checking for prepared transactions ok vm-test-run-pg-upgrade-start-guard> Checking for contrib/isn with bigint-passing mismatch ok vm-test-run-pg-upgrade-start-guard> Checking data type usage ok vm-test-run-pg-upgrade-start-guard> Checking for not-null constraint inconsistencies ok vm-test-run-pg-upgrade-start-guard> Creating dump of global objects ok vm-test-run-pg-upgrade-start-guard> Creating dump of database schemas ok vm-test-run-pg-upgrade-start-guard> [13:11:22] Locked /var/lib/postgresql/data with pg_upgrade pid 1839 vm-test-run-pg-upgrade-start-guard> Checking for presence of required libraries ok vm-test-run-pg-upgrade-start-guard> Checking database user is the install user ok vm-test-run-pg-upgrade-start-guard> Checking for prepared transactions ok vm-test-run-pg-upgrade-start-guard> Checking for new cluster tablespace directories ok vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> If pg_upgrade fails after this point, you must re-initdb the vm-test-run-pg-upgrade-start-guard> new cluster before continuing. vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> Performing Upgrade vm-test-run-pg-upgrade-start-guard> ------------------ vm-test-run-pg-upgrade-start-guard> Setting locale and encoding for new cluster ok vm-test-run-pg-upgrade-start-guard> Analyzing all rows in the new cluster ok vm-test-run-pg-upgrade-start-guard> Freezing all rows in the new cluster ok vm-test-run-pg-upgrade-start-guard> Deleting files from new pg_xact ok vm-test-run-pg-upgrade-start-guard> Copying old pg_xact to new server ok vm-test-run-pg-upgrade-start-guard> Setting oldest XID for new cluster ok vm-test-run-pg-upgrade-start-guard> Setting next transaction ID and epoch for new cluster ok vm-test-run-pg-upgrade-start-guard> Deleting files from new pg_multixact/offsets ok vm-test-run-pg-upgrade-start-guard> Copying old pg_multixact/offsets to new server ok vm-test-run-pg-upgrade-start-guard> Deleting files from new pg_multixact/members ok vm-test-run-pg-upgrade-start-guard> Copying old pg_multixact/members to new server ok vm-test-run-pg-upgrade-start-guard> Setting next multixact ID and offset for new cluster ok vm-test-run-pg-upgrade-start-guard> Resetting WAL archives ok vm-test-run-pg-upgrade-start-guard> Setting frozenxid and minmxid counters in new cluster ok vm-test-run-pg-upgrade-start-guard> Restoring global objects in the new cluster ok vm-test-run-pg-upgrade-start-guard> Restoring database schemas in the new cluster ok vm-test-run-pg-upgrade-start-guard> Copying user relation files ok vm-test-run-pg-upgrade-start-guard> Setting next OID for new cluster ok vm-test-run-pg-upgrade-start-guard> Sync data directory to disk ok vm-test-run-pg-upgrade-start-guard> Creating script to delete old cluster ok vm-test-run-pg-upgrade-start-guard> Checking for extension updates ok vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> Upgrade Complete vm-test-run-pg-upgrade-start-guard> ---------------- vm-test-run-pg-upgrade-start-guard> Optimizer statistics are not transferred by pg_upgrade. vm-test-run-pg-upgrade-start-guard> Once you start the new server, consider running: vm-test-run-pg-upgrade-start-guard> /nix/store/b93nnrxmkyz9mmh50hw0sn71rbmswp41-postgresql-and-plugins-17.11/bin/vacuumdb -U postgres --all --analyze-in-stages vm-test-run-pg-upgrade-start-guard> Running this script will delete the old cluster's data files: vm-test-run-pg-upgrade-start-guard> ./delete_old_cluster.sh vm-test-run-pg-upgrade-start-guard> [13:11:53] ERROR: postgresql.service is activating after pg_upgrade; the copied data files may be inconsistent vm-test-run-pg-upgrade-start-guard> vm-test-run-pg-upgrade-start-guard> !!! Test "A real pg_upgrade copy under a start storm" failed with error: "command `systemd-run --unit=fake-initiate --collect --pipe --wait /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive upgrade` failed (exit code 1)" vm-test-run-pg-upgrade-start-guard> !!! Traceback (most recent call last): vm-test-run-pg-upgrade-start-guard> !!! File "", line 69, in vm-test-run-pg-upgrade-start-guard> !!! server.succeed(f"systemd-run --unit=fake-initiate --collect --pipe --wait {DRIVE} upgrade") vm-test-run-pg-upgrade-start-guard> !!! vm-test-run-pg-upgrade-start-guard> !!! RequestedAssertionFailed: command `systemd-run --unit=fake-initiate --collect --pipe --wait /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive upgrade` failed (exit code 1) vm-test-run-pg-upgrade-start-guard> cleanup vm-test-run-pg-upgrade-start-guard> kill machine (pid 9) vm-test-run-pg-upgrade-start-guard> qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/y88crn50yx35fiiwcjx6xqwi6ahk7mlq-python3-3.13.11/bin/python3.13) vm-test-run-pg-upgrade-start-guard> vde_switch: EOF data port: Interrupted system call vm-test-run-pg-upgrade-start-guard> kill vlan (pid 7) vm-test-run-pg-upgrade-start-guard> (finished: cleanup, in 0.00 seconds) vm-test-run-pg-upgrade-start-guard> vde_switch: Caught signal 15, cleaning up and exiting error: build of '/nix/store/cykcl1vz1x9v03az7hymgsig5f92ggkp-vm-test-run-pg-upgrade-start-guard.drv' on 'ssh-ng://builder@mac-linux-builder' failed: Cannot build '/nix/store/cykcl1vz1x9v03az7hymgsig5f92ggkp-vm-test-run-pg-upgrade-start-guard.drv'. Reason: builder failed with exit code 1. Output paths: /nix/store/c309ygab61aj32wi0i15xk78pgf29lm2-vm-test-run-pg-upgrade-start-guard Last 25 log lines: > Creating script to delete old cluster ok > Checking for extension updates ok > > Upgrade Complete > ---------------- > Optimizer statistics are not transferred by pg_upgrade. > Once you start the new server, consider running: > /nix/store/b93nnrxmkyz9mmh50hw0sn71rbmswp41-postgresql-and-plugins-17.11/bin/vacuumdb -U postgres --all --analyze-in-stages > Running this script will delete the old cluster's data files: > ./delete_old_cluster.sh > [13:11:53] ERROR: postgresql.service is activating after pg_upgrade; the copied data files may be inconsistent > > !!! Test "A real pg_upgrade copy under a start storm" failed with error: "command `systemd-run --unit=fake-initiate --collect --pipe --wait /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive upgrade` failed (exit code 1)" > !!! Traceback (most recent call last): > !!! File "", line 69, in > !!! server.succeed(f"systemd-run --unit=fake-initiate --collect --pipe --wait {DRIVE} upgrade") > !!! > !!! RequestedAssertionFailed: command `systemd-run --unit=fake-initiate --collect --pipe --wait /nix/store/v7833w1jm7rl64gns1izka6rd90a333r-drive/bin/drive upgrade` failed (exit code 1) > cleanup > kill machine (pid 9) > qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/y88crn50yx35fiiwcjx6xqwi6ahk7mlq-python3-3.13.11/bin/python3.13) > vde_switch: EOF data port: Interrupted system call > kill vlan (pid 7) > (finished: cleanup, in 0.00 seconds) > vde_switch: Caught signal 15, cleaning up and exiting For full logs, run: nix-store -l /nix/store/cykcl1vz1x9v03az7hymgsig5f92ggkp-vm-test-run-pg-upgrade-start-guard.drv error: Cannot build '/nix/store/cykcl1vz1x9v03az7hymgsig5f92ggkp-vm-test-run-pg-upgrade-start-guard.drv'. Reason: builder failed with exit code 1. Output paths: /nix/store/c309ygab61aj32wi0i15xk78pgf29lm2-vm-test-run-pg-upgrade-start-guard