nixbot

1these 2 derivations will be built:2  /nix/store/870vjjvf5fvqz89vmg8kb25a6p1vqr70-nixos-test-driver-hypopg.drv3  /nix/store/4cm1khjll2hh66jrpra6wgy3zx9n11wh-vm-test-run-hypopg.drv4building '/nix/store/870vjjvf5fvqz89vmg8kb25a6p1vqr70-nixos-test-driver-hypopg.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'5building '/nix/store/870vjjvf5fvqz89vmg8kb25a6p1vqr70-nixos-test-driver-hypopg.drv'6nixos-test-driver-hypopg> Running type check (enable/disable: config.skipTypeCheck)7nixos-test-driver-hypopg> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck8nixos-test-driver-hypopg> Success: no issues found in 1 source file9nixos-test-driver-hypopg> additionally exposed symbols:10nixos-test-driver-hypopg>     ,11nixos-test-driver-hypopg>     ,12nixos-test-driver-hypopg>     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, debug13nixos-test-driver-hypopg> Linting test script (enable/disable: config.skipLint)14nixos-test-driver-hypopg> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint15building '/nix/store/4cm1khjll2hh66jrpra6wgy3zx9n11wh-vm-test-run-hypopg.drv' on 'ssh-ng://builder@mac-linux-builder'16building '/nix/store/4cm1khjll2hh66jrpra6wgy3zx9n11wh-vm-test-run-hypopg.drv'17vm-test-run-hypopg> Machine state will be reset. To keep it, pass --keep-vm-state18vm-test-run-hypopg> start all VLans19vm-test-run-hypopg> start vlan20vm-test-run-hypopg> running vlan (pid 7; ctl /build/vde1.ctl)21vm-test-run-hypopg> (finished: start all VLans, in 0.00 seconds)22vm-test-run-hypopg> Test will time out and terminate in 3600 seconds23vm-test-run-hypopg> run the VM test script24vm-test-run-hypopg> additionally exposed symbols:25vm-test-run-hypopg>     server,26vm-test-run-hypopg>     vlan1,27vm-test-run-hypopg>     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, debug28vm-test-run-hypopg> Running tests for extension: hypopg29vm-test-run-hypopg> start all VMs30vm-test-run-hypopg> server: starting vm31vm-test-run-hypopg> mke2fs 1.47.3 (8-Jul-2025)32vm-test-run-hypopg> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory33vm-test-run-hypopg> qemu-system-aarch64: failed to initialize kvm: No such file or directory34vm-test-run-hypopg> qemu-system-aarch64: falling back to tcg35vm-test-run-hypopg> server # Disk image does not exist, creating the virtualisation disk image...36vm-test-run-hypopg> server # Formatting '/build/vm-state-server/tmp.2Qsz8zoxft', fmt=raw size=107374182437vm-test-run-hypopg> server # Discarding device blocks:      0/262144             done38vm-test-run-hypopg> server # Creating filesystem with 262144 4k blocks and 65536 inodes39vm-test-run-hypopg> server # Filesystem UUID: 9cd6287e-3394-4f66-b242-700013192e7440vm-test-run-hypopg> server # Superblock backups stored on blocks:41vm-test-run-hypopg> server # 	32768, 98304, 163840, 22937642vm-test-run-hypopg> server # 43vm-test-run-hypopg> server # Allocating group tables: 0/8   done44vm-test-run-hypopg> server # Writing inode tables: 0/8   done45vm-test-run-hypopg> server # Creating journal (8192 blocks): done46vm-test-run-hypopg> server # Writing superblocks and filesystem accounting information: 0/8   done47vm-test-run-hypopg> server # 48vm-test-run-hypopg> server # Virtualisation disk image created.49vm-test-run-hypopg> server: QEMU running (pid 9)50vm-test-run-hypopg> (finished: start all VMs, in 0.12 seconds)51vm-test-run-hypopg> server: waiting for unit multi-user.target52vm-test-run-hypopg> server: waiting for the VM to finish booting53vm-test-run-hypopg> server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]54vm-test-run-hypopg> 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 202555vm-test-run-hypopg> server # [    0.000000] KASLR enabled56vm-test-run-hypopg> server # [    0.000000] random: crng init done57vm-test-run-hypopg> server # [    0.000000] Machine model: linux,dummy-virt58vm-test-run-hypopg> server # [    0.000000] efi: UEFI not found.59vm-test-run-hypopg> server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT60vm-test-run-hypopg> server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]61vm-test-run-hypopg> server # [    0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f]62vm-test-run-hypopg> server # [    0.000000] Zone ranges:63vm-test-run-hypopg> server # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]64vm-test-run-hypopg> server # [    0.000000]   DMA32    empty65vm-test-run-hypopg> server # [    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]66vm-test-run-hypopg> server # [    0.000000]   Device   empty67vm-test-run-hypopg> server # [    0.000000] Movable zone start for each node68vm-test-run-hypopg> server # [    0.000000] Early memory node ranges69vm-test-run-hypopg> server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]70vm-test-run-hypopg> server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]71vm-test-run-hypopg> server # [    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -172vm-test-run-hypopg> server # [    0.000000] psci: probing for conduit method from DT.73vm-test-run-hypopg> server # [    0.000000] psci: PSCIv1.1 detected in firmware.74vm-test-run-hypopg> server # [    0.000000] psci: Using standard PSCI v0.2 function IDs75vm-test-run-hypopg> server # [    0.000000] psci: Trusted OS migration not required76vm-test-run-hypopg> server # [    0.000000] psci: SMC Calling Convention v1.077vm-test-run-hypopg> server # [    0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u36044878vm-test-run-hypopg> server # [    0.000000] Detected PIPT I-cache on CPU079vm-test-run-hypopg> server # [    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)80vm-test-run-hypopg> server # [    0.000000] CPU features: detected: GIC system register CPU interface81vm-test-run-hypopg> server # [    0.000000] CPU features: detected: HCRX_EL2 register82vm-test-run-hypopg> server # [    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)83vm-test-run-hypopg> server # [    0.000000] CPU features: detected: Spectre-v484vm-test-run-hypopg> server # [    0.000000] alternatives: applying boot alternatives85vm-test-run-hypopg> 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/ymnravnyg8gvx0dd0sixdijxsn1p7vh7-nixos-system-server-test/init regInfo=/nix/store/5hn7xydnzjk94xwx8yv1q7b8pp9lw15k-closure-info/registration console=ttyAMA0,115200n8 console=tty086vm-test-run-hypopg> server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/5hn7xydnzjk94xwx8yv1q7b8pp9lw15k-closure-info/registration", will be passed to user space.87vm-test-run-hypopg> server # [    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)88vm-test-run-hypopg> server # [    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)89vm-test-run-hypopg> server # [    0.000000] Fallback order for Node 0: 090vm-test-run-hypopg> server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 104857691vm-test-run-hypopg> server # [    0.000000] Policy zone: Normal92vm-test-run-hypopg> server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off93vm-test-run-hypopg> server # [    0.000000] software IO TLB: area num 2.94vm-test-run-hypopg> server # [    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)95vm-test-run-hypopg> server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=196vm-test-run-hypopg> server # [    0.000000] allocated 8388608 bytes of page_ext97vm-test-run-hypopg> server # [    0.000000] ftrace: allocating 72257 entries in 283 pages98vm-test-run-hypopg> server # [    0.000000] ftrace: allocated 283 pages with 5 groups99vm-test-run-hypopg> server # [    0.000000] rcu: Hierarchical RCU implementation.100vm-test-run-hypopg> server # [    0.000000] rcu: 	RCU event tracing is enabled.101vm-test-run-hypopg> server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.102vm-test-run-hypopg> server # [    0.000000] 	Rude variant of Tasks RCU enabled.103vm-test-run-hypopg> server # [    0.000000] 	Tracing variant of Tasks RCU enabled.104vm-test-run-hypopg> server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.105vm-test-run-hypopg> server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2106vm-test-run-hypopg> server # [    0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.107vm-test-run-hypopg> server # [    0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.108vm-test-run-hypopg> server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0109vm-test-run-hypopg> server # [    0.000000] GICv3: 256 SPIs implemented110vm-test-run-hypopg> server # [    0.000000] GICv3: 0 Extended SPIs implemented111vm-test-run-hypopg> server # [    0.000000] Root IRQ handler: gic_handle_irq112vm-test-run-hypopg> server # [    0.000000] GICv3: GICv3 features: 16 PPIs113vm-test-run-hypopg> server # [    0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0114vm-test-run-hypopg> server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000115vm-test-run-hypopg> server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]116vm-test-run-hypopg> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ae0000 (indirect, esz 8, psz 64K, shr 1)117vm-test-run-hypopg> server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100af0000 (flat, esz 8, psz 64K, shr 1)118vm-test-run-hypopg> server # [    0.000000] GICv3: using LPI property table @0x0000000100b00000119vm-test-run-hypopg> server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000120vm-test-run-hypopg> server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.121vm-test-run-hypopg> server # [    0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).122vm-test-run-hypopg> server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns123vm-test-run-hypopg> server # [    0.000030] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns124vm-test-run-hypopg> server # [    0.004665] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)125vm-test-run-hypopg> server # [    0.014953] Console: colour dummy device 80x25126vm-test-run-hypopg> server # [    0.016163] printk: legacy console [tty0] enabled127vm-test-run-hypopg> server # [    0.020665] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)128vm-test-run-hypopg> server # [    0.020981] pid_max: default: 32768 minimum: 301129vm-test-run-hypopg> server # [    0.023642] LSM: initializing lsm=capability,landlock,yama,bpf130vm-test-run-hypopg> server # [    0.026037] landlock: Up and running.131vm-test-run-hypopg> server # [    0.026089] Yama: becoming mindful.132vm-test-run-hypopg> server # [    0.028599] LSM support for eBPF active133vm-test-run-hypopg> server # [    0.031671] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)134vm-test-run-hypopg> server # [    0.031838] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)135vm-test-run-hypopg> server # [    0.060983] cacheinfo: Unable to detect cache hierarchy for CPU 0136vm-test-run-hypopg> server # [    0.072280] rcu: Hierarchical SRCU implementation.137vm-test-run-hypopg> server # [    0.072380] rcu: 	Max phase no-delay instances is 1000.138vm-test-run-hypopg> server # [    0.073545] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level139vm-test-run-hypopg> server # [    0.079777] fsl-mc MSI: its@8080000 domain created140vm-test-run-hypopg> server # [    0.082084] EFI services will not be available.141vm-test-run-hypopg> server # [    0.083971] smp: Bringing up secondary CPUs ...142vm-test-run-hypopg> server # [    0.089568] Detected PIPT I-cache on CPU1143vm-test-run-hypopg> server # [    0.090731] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000144vm-test-run-hypopg> server # [    0.091320] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000145vm-test-run-hypopg> server # [    0.092685] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]146vm-test-run-hypopg> server # [    0.095776] smp: Brought up 1 node, 2 CPUs147vm-test-run-hypopg> server # [    0.095902] SMP: Total of 2 processors activated.148vm-test-run-hypopg> server # [    0.095939] CPU: All CPU(s) started at EL1149vm-test-run-hypopg> server # [    0.096065] CPU features: detected: Branch Target Identification150vm-test-run-hypopg> server # [    0.096108] CPU features: detected: 32-bit EL0 Support151vm-test-run-hypopg> server # [    0.096134] CPU features: detected: 32-bit EL1 Support152vm-test-run-hypopg> server # [    0.096156] CPU features: detected: ARMv8.4 Translation Table Level153vm-test-run-hypopg> server # [    0.096185] CPU features: detected: Instruction cache invalidation not required for I/D coherence154vm-test-run-hypopg> server # [    0.096216] CPU features: detected: Data cache clean to the PoU not required for I/D coherence155vm-test-run-hypopg> server # [    0.096269] CPU features: detected: Common not Private translations156vm-test-run-hypopg> server # [    0.096289] CPU features: detected: CRC32 instructions157vm-test-run-hypopg> server # [    0.096307] CPU features: detected: Data cache clean to Point of Deep Persistence158vm-test-run-hypopg> server # [    0.096330] CPU features: detected: Data cache clean to Point of Persistence159vm-test-run-hypopg> server # [    0.096351] CPU features: detected: Data independent timing control (DIT)160vm-test-run-hypopg> server # [    0.096371] CPU features: detected: E0PD161vm-test-run-hypopg> server # [    0.096387] CPU features: detected: Enhanced Counter Virtualization162vm-test-run-hypopg> server # [    0.096406] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)163vm-test-run-hypopg> server # [    0.096427] CPU features: detected: Enhanced Privileged Access Never164vm-test-run-hypopg> server # [    0.096446] CPU features: detected: Enhanced Virtualization Traps165vm-test-run-hypopg> server # [    0.096467] CPU features: detected: Fine Grained Traps166vm-test-run-hypopg> server # [    0.096516] CPU features: detected: Generic authentication (IMP DEF algorithm)167vm-test-run-hypopg> server # [    0.096588] CPU features: detected: RCpc load-acquire (LDAPR)168vm-test-run-hypopg> server # [    0.096625] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)169vm-test-run-hypopg> server # [    0.096646] CPU features: detected: LSE atomic instructions170vm-test-run-hypopg> server # [    0.096665] CPU features: detected: Memory Copy and Memory Set instructions171vm-test-run-hypopg> server # [    0.096685] CPU features: detected: Privileged Access Never172vm-test-run-hypopg> server # [    0.096704] CPU features: detected: RAS Extension Support173vm-test-run-hypopg> server # [    0.096722] CPU features: detected: Random Number Generator174vm-test-run-hypopg> server # [    0.096740] CPU features: detected: Speculation barrier (SB)175vm-test-run-hypopg> server # [    0.096758] CPU features: detected: Stage-2 Force Write-Back176vm-test-run-hypopg> server # [    0.096777] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality177vm-test-run-hypopg> server # [    0.096804] CPU features: detected: TLB range maintenance instructions178vm-test-run-hypopg> server # [    0.096824] CPU features: detected: WFx with timeout179vm-test-run-hypopg> server # [    0.096859] CPU features: detected: Speculative Store Bypassing Safe (SSBS)180vm-test-run-hypopg> server # [    0.096884] CPU features: detected: Scalable Vector Extension181vm-test-run-hypopg> server # [    0.105568] alternatives: applying system-wide alternatives182vm-test-run-hypopg> server # [    0.121830] CPU features: detected: Hardware dirty bit management on CPU0-1183vm-test-run-hypopg> server # [    0.122814] SVE: maximum available vector length 256 bytes per vector184vm-test-run-hypopg> server # [    0.123168] SVE: default vector length 64 bytes per vector185vm-test-run-hypopg> server # [    0.134180] Memory: 3922792K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved)186vm-test-run-hypopg> server # [    0.154946] devtmpfs: initialized187vm-test-run-hypopg> server # [    0.177795] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns188vm-test-run-hypopg> server # [    0.178284] futex hash table entries: 512 (order: 3, 32768 bytes, linear)189vm-test-run-hypopg> server # [    0.180504] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL190vm-test-run-hypopg> server # [    0.180574] 0 pages in range for non-PLT usage191vm-test-run-hypopg> server # [    0.180611] 509248 pages in range for PLT usage192vm-test-run-hypopg> server # [    0.183253] pinctrl core: initialized pinctrl subsystem193vm-test-run-hypopg> server # [    0.192460] DMI not present or invalid.194vm-test-run-hypopg> server # [    0.211435] NET: Registered PF_NETLINK/PF_ROUTE protocol family195vm-test-run-hypopg> server # [    0.221361] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations196vm-test-run-hypopg> server # [    0.222282] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations197vm-test-run-hypopg> server # [    0.223137] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations198vm-test-run-hypopg> server # [    0.223346] audit: initializing netlink subsys (disabled)199vm-test-run-hypopg> server # [    0.225050] audit: type=2000 audit(0.152:1): state=initialized audit_enabled=0 res=1200vm-test-run-hypopg> server # [    0.229825] thermal_sys: Registered thermal governor 'fair_share'201vm-test-run-hypopg> server # [    0.229871] thermal_sys: Registered thermal governor 'bang_bang'202vm-test-run-hypopg> server # [    0.229962] thermal_sys: Registered thermal governor 'step_wise'203vm-test-run-hypopg> server # [    0.229987] thermal_sys: Registered thermal governor 'user_space'204vm-test-run-hypopg> server # [    0.230008] thermal_sys: Registered thermal governor 'power_allocator'205vm-test-run-hypopg> server # [    0.230665] cpuidle: using governor ladder206vm-test-run-hypopg> server # [    0.231154] cpuidle: using governor menu207vm-test-run-hypopg> server # [    0.233117] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.208vm-test-run-hypopg> server # [    0.234092] ASID allocator initialised with 65536 entries209vm-test-run-hypopg> server # [    0.239946] Serial: AMBA PL011 UART driver210vm-test-run-hypopg> server # [    0.277208] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1211vm-test-run-hypopg> server # [    0.278078] printk: legacy console [ttyAMA0] enabled212vm-test-run-hypopg> server # [    0.307327] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages213vm-test-run-hypopg> server # [    0.307481] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page214vm-test-run-hypopg> server # [    0.308044] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages215vm-test-run-hypopg> server # [    0.308142] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page216vm-test-run-hypopg> server # [    0.308293] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages217vm-test-run-hypopg> server # [    0.308375] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page218vm-test-run-hypopg> server # [    0.308458] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages219vm-test-run-hypopg> server # [    0.308542] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page220vm-test-run-hypopg> server # [    0.317456] fbcon: Taking over console221vm-test-run-hypopg> server # [    0.319220] ACPI: Interpreter disabled.222vm-test-run-hypopg> server # [    0.324716] iommu: Default domain type: Translated223vm-test-run-hypopg> server # [    0.325013] iommu: DMA domain TLB invalidation policy: strict mode224vm-test-run-hypopg> server # [    0.328848] SCSI subsystem initialized225vm-test-run-hypopg> server # [    0.331422] usbcore: registered new interface driver usbfs226vm-test-run-hypopg> server # [    0.331773] usbcore: registered new interface driver hub227vm-test-run-hypopg> server # [    0.332097] usbcore: registered new device driver usb228vm-test-run-hypopg> server # [    0.334069] pps_core: LinuxPPS API ver. 1 registered229vm-test-run-hypopg> server # [    0.334169] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>230vm-test-run-hypopg> server # [    0.334354] PTP clock support registered231vm-test-run-hypopg> server # [    0.334715] EDAC MC: Ver: 3.0.0232vm-test-run-hypopg> server # [    0.336619] scmi_core: SCMI protocol bus registered233vm-test-run-hypopg> server # [    0.339528] FPGA manager framework234vm-test-run-hypopg> server # [    0.340657] Advanced Linux Sound Architecture Driver Initialized.235vm-test-run-hypopg> server # [    0.348321] vgaarb: loaded236vm-test-run-hypopg> server # [    0.353290] clocksource: Switched to clocksource arch_sys_counter237vm-test-run-hypopg> server # [    0.355575] VFS: Disk quotas dquot_6.6.0238vm-test-run-hypopg> server # [    0.356659] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)239vm-test-run-hypopg> server # [    0.362576] netfs: FS-Cache loaded240vm-test-run-hypopg> server # [    0.363544] pnp: PnP ACPI: disabled241vm-test-run-hypopg> server # [    0.380965] NET: Registered PF_INET protocol family242vm-test-run-hypopg> server # [    0.382831] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)243vm-test-run-hypopg> server # [    0.474947] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)244vm-test-run-hypopg> server # [    0.475263] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)245vm-test-run-hypopg> server # [    0.476193] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)246vm-test-run-hypopg> server # [    0.477161] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)247vm-test-run-hypopg> server # [    0.478627] TCP: Hash tables configured (established 32768 bind 32768)248vm-test-run-hypopg> server # [    0.480070] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)249vm-test-run-hypopg> server # [    0.480517] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)250vm-test-run-hypopg> server # [    0.480960] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)251vm-test-run-hypopg> server # [    0.482693] NET: Registered PF_UNIX/PF_LOCAL protocol family252vm-test-run-hypopg> server # [    0.483137] NET: Registered PF_XDP protocol family253vm-test-run-hypopg> server # [    0.483369] PCI: CLS 0 bytes, default 64254vm-test-run-hypopg> server # [    0.486471] kvm [1]: HYP mode not available255vm-test-run-hypopg> server # [    0.486931] Trying to unpack rootfs image as initramfs...256vm-test-run-hypopg> server # [    0.577441] Initialise system trusted keyrings257vm-test-run-hypopg> server # [    0.579330] workingset: timestamp_bits=42 max_order=20 bucket_order=0258vm-test-run-hypopg> server # [    0.582883] squashfs: version 4.0 (2009/01/31) Phillip Lougher259vm-test-run-hypopg> server # [    0.583471] 9p: Installing v9fs 9p2000 file system support260vm-test-run-hypopg> server # [    0.637722] Key type asymmetric registered261vm-test-run-hypopg> server # [    0.637963] Asymmetric key parser 'x509' registered262vm-test-run-hypopg> server # [    0.638420] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)263vm-test-run-hypopg> server # [    0.640639] io scheduler mq-deadline registered264vm-test-run-hypopg> server # [    0.640818] io scheduler kyber registered265vm-test-run-hypopg> server # [    0.663334] pl061_gpio 9030000.pl061: PL061 GPIO chip registered266vm-test-run-hypopg> server # [    0.668803] ledtrig-cpu: registered to indicate activity on CPUs267vm-test-run-hypopg> server # [    0.672684] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:268vm-test-run-hypopg> server # [    0.673854] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000269vm-test-run-hypopg> server # [    0.674501] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000270vm-test-run-hypopg> server # [    0.674882] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000271vm-test-run-hypopg> server # [    0.675743] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits272vm-test-run-hypopg> server # [    0.676422] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]273vm-test-run-hypopg> server # [    0.678936] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00274vm-test-run-hypopg> server # [    0.679307] pci_bus 0000:00: root bus resource [bus 00-ff]275vm-test-run-hypopg> server # [    0.679462] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]276vm-test-run-hypopg> server # [    0.679768] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]277vm-test-run-hypopg> server # [    0.679878] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]278vm-test-run-hypopg> server # [    0.681226] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint279vm-test-run-hypopg> server # [    0.685110] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint280vm-test-run-hypopg> server # [    0.685814] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]281vm-test-run-hypopg> server # [    0.686579] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]282vm-test-run-hypopg> server # [    0.686937] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]283vm-test-run-hypopg> server # [    0.687110] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]284vm-test-run-hypopg> server # [    0.687864] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint285vm-test-run-hypopg> server # [    0.688233] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]286vm-test-run-hypopg> server # [    0.688568] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]287vm-test-run-hypopg> server # [    0.688816] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288vm-test-run-hypopg> server # [    0.689501] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint289vm-test-run-hypopg> server # [    0.689661] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]290vm-test-run-hypopg> server # [    0.689765] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]291vm-test-run-hypopg> server # [    0.689866] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292vm-test-run-hypopg> server # [    0.690600] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint293vm-test-run-hypopg> server # [    0.690884] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]294vm-test-run-hypopg> server # [    0.691038] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]295vm-test-run-hypopg> server # [    0.691143] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]296vm-test-run-hypopg> server # [    0.691600] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint297vm-test-run-hypopg> server # [    0.691980] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]298vm-test-run-hypopg> server # [    0.692072] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]299vm-test-run-hypopg> server # [    0.692283] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]300vm-test-run-hypopg> server # [    0.692778] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint301vm-test-run-hypopg> server # [    0.693709] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]302vm-test-run-hypopg> server # [    0.693818] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]303vm-test-run-hypopg> server # [    0.694104] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]304vm-test-run-hypopg> server # [    0.694583] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint305vm-test-run-hypopg> server # [    0.694846] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]306vm-test-run-hypopg> server # [    0.694934] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]307vm-test-run-hypopg> server # [    0.695129] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]308vm-test-run-hypopg> server # [    0.695245] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]309vm-test-run-hypopg> server # [    0.695714] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint310vm-test-run-hypopg> server # [    0.695979] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]311vm-test-run-hypopg> server # [    0.696096] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]312vm-test-run-hypopg> server # [    0.696547] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint313vm-test-run-hypopg> server # [    0.696704] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]314vm-test-run-hypopg> server # [    0.696805] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]315vm-test-run-hypopg> server # [    0.698449] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint316vm-test-run-hypopg> server # [    0.698607] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]317vm-test-run-hypopg> server # [    0.698996] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint318vm-test-run-hypopg> server # [    0.699159] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]319vm-test-run-hypopg> server # [    0.699243] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]320vm-test-run-hypopg> server # [    0.699341] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]321vm-test-run-hypopg> server # [    0.699710] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint322vm-test-run-hypopg> server # [    0.700050] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]323vm-test-run-hypopg> server # [    0.700432] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]324vm-test-run-hypopg> server # [    0.700551] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]325vm-test-run-hypopg> server # [    0.702831] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned326vm-test-run-hypopg> server # [    0.703057] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned327vm-test-run-hypopg> server # [    0.703309] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned328vm-test-run-hypopg> server # [    0.703583] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned329vm-test-run-hypopg> server # [    0.703724] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned330vm-test-run-hypopg> server # [    0.703841] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned331vm-test-run-hypopg> server # [    0.703957] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned332vm-test-run-hypopg> server # [    0.704081] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned333vm-test-run-hypopg> server # [    0.704200] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned334vm-test-run-hypopg> server # [    0.704314] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned335vm-test-run-hypopg> server # [    0.704430] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned336vm-test-run-hypopg> server # [    0.704547] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned337vm-test-run-hypopg> server # [    0.705743] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned338vm-test-run-hypopg> server # [    0.706024] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned339vm-test-run-hypopg> server # [    0.706348] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned340vm-test-run-hypopg> server # [    0.706492] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned341vm-test-run-hypopg> server # [    0.706598] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned342vm-test-run-hypopg> server # [    0.706932] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned343vm-test-run-hypopg> server # [    0.707037] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned344vm-test-run-hypopg> server # [    0.707139] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned345vm-test-run-hypopg> server # [    0.707232] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned346vm-test-run-hypopg> server # [    0.707324] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned347vm-test-run-hypopg> server # [    0.707419] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned348vm-test-run-hypopg> server # [    0.707691] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned349vm-test-run-hypopg> server # [    0.707850] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned350vm-test-run-hypopg> server # [    0.707965] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned351vm-test-run-hypopg> server # [    0.708072] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned352vm-test-run-hypopg> server # [    0.708163] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned353vm-test-run-hypopg> server # [    0.708321] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned354vm-test-run-hypopg> server # [    0.708658] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned355vm-test-run-hypopg> server # [    0.708751] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned356vm-test-run-hypopg> server # [    0.708838] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned357vm-test-run-hypopg> server # [    0.708926] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned358vm-test-run-hypopg> server # [    0.709013] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned359vm-test-run-hypopg> server # [    0.709276] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]360vm-test-run-hypopg> server # [    0.709375] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]361vm-test-run-hypopg> server # [    0.709462] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]362vm-test-run-hypopg> server # [    0.710727] pci 0000:00:0a.0: enabling device (0000 -> 0002)363vm-test-run-hypopg> server # [    0.762126] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)364vm-test-run-hypopg> server # [    0.766653] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)365vm-test-run-hypopg> server # [    0.768663] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)366vm-test-run-hypopg> server # [    0.771083] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)367vm-test-run-hypopg> server # [    0.772597] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)368vm-test-run-hypopg> server # [    0.775369] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)369vm-test-run-hypopg> server # [    0.778791] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)370vm-test-run-hypopg> server # [    0.780589] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)371vm-test-run-hypopg> server # [    0.783326] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)372vm-test-run-hypopg> server # [    0.785024] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)373vm-test-run-hypopg> server # [    0.786497] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)374vm-test-run-hypopg> server # [    0.801858] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled375vm-test-run-hypopg> server # [    0.810831] msm_serial: driver initialized376vm-test-run-hypopg> server # [    0.811767] SuperH (H)SCI(F) driver initialized377vm-test-run-hypopg> server # [    0.812130] STM32 USART driver initialized378vm-test-run-hypopg> server # [    0.858106] loop: module loaded379vm-test-run-hypopg> server # [    0.859845] virtio_blk virtio5: 2/0/0 default/read/poll queues380vm-test-run-hypopg> server # [    0.864611] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)381vm-test-run-hypopg> server # [    0.878589] megasas: 07.727.03.00-rc1382vm-test-run-hypopg> server # [    0.884053] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]383vm-test-run-hypopg> server # [    0.887589] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000384vm-test-run-hypopg> server # [    0.888140] Intel/Sharp Extended Query Table at 0x0031385vm-test-run-hypopg> server # [    0.889871] Using buffer write method386vm-test-run-hypopg> server # [    0.890681] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]387vm-test-run-hypopg> server # [    0.892050] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000388vm-test-run-hypopg> server # [    0.892333] Intel/Sharp Extended Query Table at 0x0031389vm-test-run-hypopg> server # [    0.893660] Using buffer write method390vm-test-run-hypopg> server # [    0.894506] Concatenating MTD devices:391vm-test-run-hypopg> server # [    0.894614] (0): "0.flash"392vm-test-run-hypopg> server # [    0.894674] (1): "0.flash"393vm-test-run-hypopg> server # [    0.894729] into device "0.flash"394vm-test-run-hypopg> server # [    0.901846] Freeing initrd memory: 10684K395vm-test-run-hypopg> server # [    0.920503] tun: Universal TUN/TAP device driver, 1.6396vm-test-run-hypopg> server # [    0.931543] thunder_xcv, ver 1.0397vm-test-run-hypopg> server # [    0.931751] thunder_bgx, ver 1.0398vm-test-run-hypopg> server # [    0.931904] nicpf, ver 1.0399vm-test-run-hypopg> server # [    0.934493] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version400vm-test-run-hypopg> server # [    0.934709] hns3: Copyright (c) 2017 Huawei Corporation.401vm-test-run-hypopg> server # [    0.935105] hclge is initializing402vm-test-run-hypopg> server # [    0.935298] e1000: Intel(R) PRO/1000 Network Driver403vm-test-run-hypopg> server # [    0.935373] e1000: Copyright (c) 1999-2006 Intel Corporation.404vm-test-run-hypopg> server # [    0.935580] e1000e: Intel(R) PRO/1000 Network Driver405vm-test-run-hypopg> server # [    0.935699] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.406vm-test-run-hypopg> server # [    0.935887] igb: Intel(R) Gigabit Ethernet Network Driver407vm-test-run-hypopg> server # [    0.936097] igb: Copyright (c) 2007-2014 Intel Corporation.408vm-test-run-hypopg> server # [    0.936295] igbvf: Intel(R) Gigabit Virtual Function Network Driver409vm-test-run-hypopg> server # [    0.936408] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.410vm-test-run-hypopg> server # [    0.937001] sky2: driver version 1.30411vm-test-run-hypopg> server # [    0.944857] ehci-pci 0000:00:0a.0: EHCI Host Controller412vm-test-run-hypopg> server # [    0.946336] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1413vm-test-run-hypopg> server # [    0.946986] usbcore: registered new interface driver usb-storage414vm-test-run-hypopg> server # [    0.947860] usbcore: registered new interface driver usbserial_generic415vm-test-run-hypopg> server # [    0.948908] usbserial: USB Serial support registered for generic416vm-test-run-hypopg> server # [    0.950169] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000417vm-test-run-hypopg> server # [    0.959500] rtc-pl031 9010000.pl031: registered as rtc0418vm-test-run-hypopg> server # [    0.960089] rtc-pl031 9010000.pl031: setting system clock to 2026-10-26T11:30:54 UTC (1793014254)419vm-test-run-hypopg> server # [    0.962083] i2c_dev: i2c /dev entries driver420vm-test-run-hypopg> server # [    0.963495] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00421vm-test-run-hypopg> server # [    0.970126] hub 1-0:1.0: USB hub found422vm-test-run-hypopg> server # [    0.971602] hub 1-0:1.0: 6 ports detected423vm-test-run-hypopg> server # [    0.977289] sdhci: Secure Digital Host Controller Interface driver424vm-test-run-hypopg> server # [    0.977462] sdhci: Copyright(c) Pierre Ossman425vm-test-run-hypopg> server # [    0.979634] Synopsys Designware Multimedia Card Interface Driver426vm-test-run-hypopg> server # [    0.981255] sdhci-pltfm: SDHCI platform and OF driver helper427vm-test-run-hypopg> server # [    0.987150] hid: raw HID events driver (C) Jiri Kosina428vm-test-run-hypopg> server # [    0.988782] usbcore: registered new interface driver usbhid429vm-test-run-hypopg> server # [    0.988901] usbhid: USB HID core driver430vm-test-run-hypopg> server # [    0.996570] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available431vm-test-run-hypopg> server # [    1.009245] drop_monitor: Initializing network drop monitor service432vm-test-run-hypopg> server # [    1.011364] NET: Registered PF_INET6 protocol family433vm-test-run-hypopg> server # [    1.026757] Segment Routing with IPv6434vm-test-run-hypopg> server # [    1.027493] In-situ OAM (IOAM) with IPv6435vm-test-run-hypopg> server # [    1.028133] NET: Registered PF_PACKET protocol family436vm-test-run-hypopg> server # [    1.029885] 9pnet: Installing 9P2000 support437vm-test-run-hypopg> server # [    1.035333] Key type dns_resolver registered438vm-test-run-hypopg> server # [    1.068648] registered taskstats version 1439vm-test-run-hypopg> server # [    1.071444] Loading compiled-in X.509 certificates440vm-test-run-hypopg> server # [    1.123764] Demotion targets for Node 0: null441vm-test-run-hypopg> server # [    1.126148] Key type .fscrypt registered442vm-test-run-hypopg> server # [    1.126281] Key type fscrypt-provisioning registered443vm-test-run-hypopg> server # [    1.134741] input: gpio-keys as /devices/platform/gpio-keys/input/input0444vm-test-run-hypopg> server # [    1.174247] clk: Disabling unused clocks445vm-test-run-hypopg> server # [    1.174508] PM: genpd: Disabling unused power domains446vm-test-run-hypopg> server # [    1.175345] ALSA device list:447vm-test-run-hypopg> server # [    1.175578]   No soundcards found.448vm-test-run-hypopg> server # [    1.222123] usb 1-1: new high-speed USB device number 2 using ehci-pci449vm-test-run-hypopg> server # [    1.249747] Freeing unused kernel memory: 4416K450vm-test-run-hypopg> server # [    1.252160] Run /init as init process451vm-test-run-hypopg> server # [    1.459131] 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/input1452vm-test-run-hypopg> server # [    1.653019] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0453vm-test-run-hypopg> server # [    1.738686] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev454vm-test-run-hypopg> server # [    1.783140] usb 1-2: new high-speed USB device number 3 using ehci-pci455vm-test-run-hypopg> server # [    1.836281] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0456vm-test-run-hypopg> server # [    1.836896] [drm] features: -virgl +edid -resource_blob -host_visible457vm-test-run-hypopg> server # [    1.836915] [drm] features: -context_init458vm-test-run-hypopg> server # [    1.839774] [drm] number of scanouts: 1459vm-test-run-hypopg> server # [    1.839911] [drm] number of cap sets: 0460vm-test-run-hypopg> server # [    1.848040] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0461vm-test-run-hypopg> server # [    1.876570] Console: switching to colour frame buffer device 160x50462vm-test-run-hypopg> server # [    1.885068] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device463vm-test-run-hypopg> server # [    2.016701] 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/input2464vm-test-run-hypopg> server # [    2.019063] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0465vm-test-run-hypopg> server # [    4.713536] EXT4-fs (vda): mounted filesystem 9cd6287e-3394-4f66-b242-700013192e74 r/w with ordered data mode. Quota mode: none.466vm-test-run-hypopg> server # [    6.999308] EXT4-fs (vda): re-mounted 9cd6287e-3394-4f66-b242-700013192e74.467vm-test-run-hypopg> server # [    7.016067] booting system configuration /nix/store/ymnravnyg8gvx0dd0sixdijxsn1p7vh7-nixos-system-server-test468vm-test-run-hypopg> server # [   17.437660] 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)469vm-test-run-hypopg> server # [   17.447399] systemd[1]: Detected virtualization qemu.470vm-test-run-hypopg> server # [   17.451601] systemd[1]: Detected architecture arm64.471vm-test-run-hypopg> server # [   17.455878] systemd[1]: Detected first boot.472vm-test-run-hypopg> server # [   17.477218] systemd[1]: Initializing machine ID from random generator.473vm-test-run-hypopg> server # [   17.503237] systemd[1]: Hostname set to <server>.474vm-test-run-hypopg> server # [   18.730105] systemd[1]: bpf-restrict-fs: LSM BPF program attached475vm-test-run-hypopg> server # [   20.085952] NET: Registered PF_VSOCK protocol family476vm-test-run-hypopg> server # [   20.136966] Guest personality initialized and is inactive477vm-test-run-hypopg> server # [   20.145663] VMCI host device registered (name=vmci, major=10, minor=261)478vm-test-run-hypopg> server # [   20.148471] Initialized host personality479vm-test-run-hypopg> server # [   20.350793] systemd[1]: Applying preset policy.480vm-test-run-hypopg> server # [   23.396454] systemd[1]: Populated /etc with preset unit settings.481vm-test-run-hypopg> server # [   26.357723] systemd[1]: Queued start job for default target Multi-User System.482vm-test-run-hypopg> server # [   26.594225] systemd[1]: Created slice Slice /system/getty.483vm-test-run-hypopg> server # [   26.611398] systemd[1]: Created slice Slice /system/modprobe.484vm-test-run-hypopg> server # [   26.623276] systemd[1]: Created slice User and Session Slice.485vm-test-run-hypopg> server # [   26.632232] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.486vm-test-run-hypopg> server # [   26.638728] systemd[1]: Started Forward Password Requests to Wall Directory Watch.487vm-test-run-hypopg> server # [   26.644406] systemd[1]: Expecting device /dev/hvc0...488vm-test-run-hypopg> server # [   26.650384] systemd[1]: Expecting device /dev/ttyAMA0...489vm-test-run-hypopg> server # [   26.655314] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...490vm-test-run-hypopg> server # [   26.660629] systemd[1]: Reached target Local Encrypted Volumes.491vm-test-run-hypopg> server # [   26.665694] systemd[1]: Reached target Virtual Machines and Containers.492vm-test-run-hypopg> server # [   26.670850] systemd[1]: Reached target Path Units.493vm-test-run-hypopg> server # [   26.675164] systemd[1]: Reached target Remote File Systems.494vm-test-run-hypopg> server # [   26.680082] systemd[1]: Reached target Slice Units.495vm-test-run-hypopg> server # [   26.684804] systemd[1]: Reached target Swaps.496vm-test-run-hypopg> server # [   26.723285] systemd[1]: Listening on Process Core Dump Socket.497vm-test-run-hypopg> server # [   26.750810] systemd[1]: Listening on Credential Encryption/Decryption.498vm-test-run-hypopg> server # [   26.763322] systemd[1]: Listening on Journal Audit Socket.499vm-test-run-hypopg> server # [   26.770660] systemd[1]: Listening on Journal Socket (/dev/log).500vm-test-run-hypopg> server # [   26.776457] systemd[1]: Listening on Journal Sockets.501vm-test-run-hypopg> server # [   26.785101] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.502vm-test-run-hypopg> server # [   26.791031] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).503vm-test-run-hypopg> server # [   26.798495] systemd[1]: Listening on udev Control Socket.504vm-test-run-hypopg> server # [   26.803576] systemd[1]: Listening on udev Kernel Socket.505vm-test-run-hypopg> server # [   26.855392] systemd[1]: Mounting Huge Pages File System...506vm-test-run-hypopg> server # [   26.891005] systemd[1]: Mounting POSIX Message Queue File System...507vm-test-run-hypopg> server # [   26.950588] systemd[1]: Mounting Kernel Debug File System...508vm-test-run-hypopg> server # [   27.004336] systemd[1]: Mounting Kernel Trace File System...509vm-test-run-hypopg> server # [   27.176028] systemd[1]: Starting Create List of Static Device Nodes...510vm-test-run-hypopg> server # [   27.288199] systemd[1]: Starting Load Kernel Module 9pnet_virtio...511vm-test-run-hypopg> server # [   27.301761] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).512vm-test-run-hypopg> server # [   27.400499] systemd[1]: Mounting Kernel Configuration File System...513vm-test-run-hypopg> server # [   27.418166] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).514vm-test-run-hypopg> server # [   27.423954] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).515vm-test-run-hypopg> server # [   27.603716] systemd[1]: Starting Load Kernel Module fuse...516vm-test-run-hypopg> server # [   27.620419] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).517vm-test-run-hypopg> server # [   27.649759] 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).518vm-test-run-hypopg> server # [   27.780852] systemd[1]: Starting Journal Service...519vm-test-run-hypopg> server # [   27.881501] systemd[1]: Starting Load Kernel Modules...520vm-test-run-hypopg> server # [   27.972189] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...521vm-test-run-hypopg> server # [   28.062380] systemd[1]: Starting Remount Root and Kernel File Systems...522vm-test-run-hypopg> server # [   28.068896] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).523vm-test-run-hypopg> server # [   28.124484] systemd[1]: Starting Coldplug All udev Devices...524vm-test-run-hypopg> server # [   28.215221] systemd[1]: Mounted Huge Pages File System.525vm-test-run-hypopg> server # [   28.230152] systemd[1]: Mounted POSIX Message Queue File System.526vm-test-run-hypopg> server # [   28.243708] systemd[1]: Mounted Kernel Debug File System.527vm-test-run-hypopg> server # [   28.255488] systemd[1]: Mounted Kernel Trace File System.528vm-test-run-hypopg> server # [   28.283773] systemd[1]: Finished Create List of Static Device Nodes.529vm-test-run-hypopg> server # [   28.308498] systemd[1]: Mounted Kernel Configuration File System.530vm-test-run-hypopg> server # [   28.379888] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...531vm-test-run-hypopg> server # [   29.027193] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.532vm-test-run-hypopg> server # [   29.051845] systemd[1]: Finished Load Kernel Module 9pnet_virtio.533vm-test-run-hypopg> server: Guest root shell did not produce any data yet...534vm-test-run-hypopg> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.535vm-test-run-hypopg> server # [   29.352577] systemd[1]: Finished Load Kernel Modules.536vm-test-run-hypopg> server # [   29.420641] systemd[1]: Starting Firewall...537vm-test-run-hypopg> server # [   29.502139] fuse: init (API version 7.41)538vm-test-run-hypopg> server # [   29.511357] systemd[1]: Starting Apply Kernel Variables...539vm-test-run-hypopg> server # [   29.530295] EXT4-fs (vda): re-mounted 9cd6287e-3394-4f66-b242-700013192e74.540vm-test-run-hypopg> server # [   29.602237] systemd[1]: modprobe@fuse.service: Deactivated successfully.541vm-test-run-hypopg> server # [   29.626920] systemd[1]: Finished Load Kernel Module fuse.542vm-test-run-hypopg> server # [   29.678442] systemd[1]: Mounting FUSE Control File System...543vm-test-run-hypopg> server # [   29.728385] systemd[1]: Finished Remount Root and Kernel File Systems.544vm-test-run-hypopg> server # [   29.745243] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).545vm-test-run-hypopg> server # [   29.840738] systemd[1]: Starting Load/Save OS Random Seed...546vm-test-run-hypopg> server # [   29.853445] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).547vm-test-run-hypopg> server # [   30.037728] systemd[1]: Mounted FUSE Control File System.548vm-test-run-hypopg> server # [   30.136196] systemd-journald[400]: Collecting audit messages is enabled.549vm-test-run-hypopg> server # [   31.101028] systemd[1]: Finished Load/Save OS Random Seed.550vm-test-run-hypopg> server # [   31.140900] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.551vm-test-run-hypopg> server # [   31.181353] systemd[1]: Reached target First Boot Complete.552vm-test-run-hypopg> server # [   31.291197] systemd[1]: Starting Create Static Device Nodes in /dev...553vm-test-run-hypopg> server # [   31.687016] systemd[1]: Finished Apply Kernel Variables.554vm-test-run-hypopg> server # [   31.788652] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.555vm-test-run-hypopg> server # [   32.670739] systemd[1]: Finished Create Static Device Nodes in /dev.556vm-test-run-hypopg> server # [   32.700259] systemd[1]: Reached target Preparation for Local File Systems.557vm-test-run-hypopg> server # [   32.763500] systemd[1]: Mounting /run/wrappers...558vm-test-run-hypopg> server # [   32.836477] systemd[1]: Starting Rule-based Manager for Device Events and Files...559vm-test-run-hypopg> server # [   33.277108] systemd[1]: Mounted /run/wrappers.560vm-test-run-hypopg> server # [   33.288496] systemd[1]: Reached target Local File Systems.561vm-test-run-hypopg> server # [   33.320895] systemd[1]: Listening on Boot Entries Service Socket.562vm-test-run-hypopg> server # [   33.381843] systemd[1]: Starting Create SUID/SGID Wrappers...563vm-test-run-hypopg> server # [   33.274731] systemd-modules-load[401]: Module 'atkbd' is built in[   33.387009] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.564vm-test-run-hypopg> server # 565vm-test-run-hypopg> server # [   33.311852] systemd-modules-load[401]: Module 'loop' is built in566vm-test-run-hypopg> server # [   33.450652] systemd[1]: Starting Save Transient machine-id to Disk...567vm-test-run-hypopg> server # [   33.464761] systemd[1]: Started Journal Service.568vm-test-run-hypopg> server # [   33.406608] systemd-oomd[402]: No swap; memory pressure usage will be degraded569vm-test-run-hypopg> server # [   33.490488] systemd[1]: Starting Flush Journal to Persistent Storage...570vm-test-run-hypopg> server # [   34.593571] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.571vm-test-run-hypopg> server # [   34.633883] systemd[1]: Finished Save Transient machine-id to Disk.572vm-test-run-hypopg> server # [   34.695666] systemd-udevd[431]: Using default interface naming scheme 'v258'.573vm-test-run-hypopg> server # [   34.891227] systemd-journald[400]: Received client request to flush runtime journal.574vm-test-run-hypopg> server # [   35.486732] systemd[1]: Finished Flush Journal to Persistent Storage.575vm-test-run-hypopg> server # [   35.560306] systemd[1]: Starting Create System Files and Directories...576vm-test-run-hypopg> server # [   37.828580] systemd[1]: Started Rule-based Manager for Device Events and Files.577vm-test-run-hypopg> server # [   38.025896] systemd[1]: Finished Create System Files and Directories.578vm-test-run-hypopg> server # [   38.127757] systemd[1]: Starting Rebuild Journal Catalog...579vm-test-run-hypopg> server # [   38.175079] systemd[1]: Starting Record System Boot/Shutdown in UTMP...580vm-test-run-hypopg> server # [   39.331862] systemd[1]: Finished Record System Boot/Shutdown in UTMP.581vm-test-run-hypopg> server # [   40.621235] systemd[1]: Finished Rebuild Journal Catalog.582vm-test-run-hypopg> server # [   40.649846] systemd[1]: Starting Update is Completed...583vm-test-run-hypopg> server # [   41.406621] systemd[1]: Finished Update is Completed.584vm-test-run-hypopg> server # [   44.854850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.585vm-test-run-hypopg> server # [   44.874954] systemd[1]: Finished Create SUID/SGID Wrappers.586vm-test-run-hypopg> server # [   44.882115] systemd[1]: suid-sgid-wrappers.service: Consumed 3.158s CPU time, 5.6M memory peak.587vm-test-run-hypopg> server # [   47.675590] systemd[1]: Finished Firewall.588vm-test-run-hypopg> server # [   49.450226] systemd[1]: Finished Coldplug All udev Devices.589vm-test-run-hypopg> server # [   49.453990] systemd[1]: Reached target System Initialization.590vm-test-run-hypopg> server # [   49.456193] systemd[1]: Started Discard unused filesystem blocks once a week.591vm-test-run-hypopg> server # [   49.464088] systemd[1]: Started Daily Cleanup of Temporary Directories.592vm-test-run-hypopg> server # [   49.470188] systemd[1]: Reached target Timer Units.593vm-test-run-hypopg> server # [   49.475095] systemd[1]: Listening on D-Bus System Message Bus Socket.594vm-test-run-hypopg> server # [   49.489985] systemd[1]: Listening on Nix Daemon Socket.595vm-test-run-hypopg> server # [   49.504250] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).596vm-test-run-hypopg> server # [   49.554922] systemd[1]: Starting OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK)...597vm-test-run-hypopg> server # [   49.569873] systemd[1]: Listening on Hostname Service Socket.598vm-test-run-hypopg> server # [   49.740722] (sh-issue)[635]: sshd-vsock.socket: Unable to locate executable '/usr/lib/systemd/systemd-ssh-issue': No such file or directory599vm-test-run-hypopg> server # [   49.789732] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).600vm-test-run-hypopg> server # [   49.798375] systemd[1]: Reached target Socket Units.601vm-test-run-hypopg> server # [   49.804632] systemd[1]: Reached target Basic System.602vm-test-run-hypopg> server # [   49.840200] systemd[1]: Starting D-Bus System Message Bus...603vm-test-run-hypopg> server # [   49.909603] systemd[1]: Starting Import lastlog data into lastlog2 database...604vm-test-run-hypopg> server # [   49.959459] systemd[1]: Starting Name Service Cache Daemon (nsncd)...605vm-test-run-hypopg> server # [   50.020741] systemd[1]: Started Reset console on configuration changes.606vm-test-run-hypopg> server # [   50.074398] systemd[1]: Starting resolvconf update...607vm-test-run-hypopg> server # [   50.140766] systemd[1]: Starting SSH Host Keys Generation...608vm-test-run-hypopg> server # [   51.767360] systemd[1]: Finished Import lastlog data into lastlog2 database.609vm-test-run-hypopg> server # [   51.837065] nsncd[639]: Oct 26 11:31:45.437 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"610vm-test-run-hypopg> server # [   51.865818] systemd[1]: Started Name Service Cache Daemon (nsncd).611vm-test-run-hypopg> server # [   51.871016] systemd[1]: Reached target Host and Network Name Lookups.612vm-test-run-hypopg> server # [   51.879790] systemd[1]: Reached target User and Group Name Lookups.613vm-test-run-hypopg> server # [   51.947048] systemd[1]: Starting User Login Management...614vm-test-run-hypopg> server # [   51.985764] dbus-daemon[637]: dbus[637]: Unknown username "systemd-timesync" in message bus configuration file615vm-test-run-hypopg> server # [   52.685304] systemd[1]: Started D-Bus System Message Bus.616vm-test-run-hypopg> server # [   53.995335] systemd-logind[662]: New seat seat0.617vm-test-run-hypopg> server # [   54.036770] systemd[1]: Started User Login Management.618vm-test-run-hypopg> server # [   54.086968] systemd[1]: Starting linger-users.service...619vm-test-run-hypopg> server # [   54.305198] systemd[1]: Stopped target Host and Network Name Lookups.620vm-test-run-hypopg> server # [   54.341536] systemd[1]: Stopping Host and Network Name Lookups...621vm-test-run-hypopg> server # [   54.375125] systemd[1]: Stopped target User and Group Name Lookups.622vm-test-run-hypopg> server # [   54.412132] systemd[1]: Stopping User and Group Name Lookups...623vm-test-run-hypopg> server # [   54.427419] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...624vm-test-run-hypopg> server # [   54.433973] systemd[1]: nscd.service: Deactivated successfully.625vm-test-run-hypopg> server # [   54.447860] systemd[1]: Stopped Name Service Cache Daemon (nsncd).626vm-test-run-hypopg> server # [   54.598314] systemd[1]: Starting Name Service Cache Daemon (nsncd)...627vm-test-run-hypopg> server # [   54.941860] systemd[1]: linger-users.service: Deactivated successfully.628vm-test-run-hypopg> server # [   54.956096] systemd[1]: Finished linger-users.service.629vm-test-run-hypopg> server # [   55.459794] systemd[1]: Started Name Service Cache Daemon (nsncd).630vm-test-run-hypopg> server # [   55.481837] nsncd[713]: Oct 26 11:31:49.099 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"631vm-test-run-hypopg> server # [   55.491061] systemd[1]: Reached target Host and Network Name Lookups.632vm-test-run-hypopg> server # [   55.502326] systemd[1]: Reached target User and Group Name Lookups.633vm-test-run-hypopg> server # [   56.228459] systemd[1]: Finished resolvconf update.634vm-test-run-hypopg> server # [   56.239649] systemd[1]: Reached target Preparation for Network.635vm-test-run-hypopg> server # [   56.305262] systemd[1]: Starting DHCP Client...636vm-test-run-hypopg> server # [   58.243465] dhcpcd[756]: dhcpcd-10.2.4 starting637vm-test-run-hypopg> server # [   58.504981] dhcpcd[776]: dev: loaded udev638vm-test-run-hypopg> server # [   59.034579] 8021q: 802.1Q VLAN Support v1.8639vm-test-run-hypopg> server: Guest root shell did not produce any data yet...640vm-test-run-hypopg> server:   To debug, enter the VM and run 'systemctl status backdoor.service'.641vm-test-run-hypopg> server # [   59.089736] sshd-keygen-start[666]: Generating public/private rsa key pair.642vm-test-run-hypopg> server # [   59.105632] sshd-keygen-start[666]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key643vm-test-run-hypopg> server # [   59.111775] sshd-keygen-start[666]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub644vm-test-run-hypopg> server # [   59.115892] sshd-keygen-start[666]: The key fingerprint is:645vm-test-run-hypopg> server # [   59.127652] sshd-keygen-start[666]: SHA256:HuooeBPwXQ1T9HWfOI2QK2eiFpG06aIu/zEp8SKWsTk root@server646vm-test-run-hypopg> server # [   59.135615] sshd-keygen-start[666]: The key's randomart image is:647vm-test-run-hypopg> server # [   59.143457] sshd-keygen-start[666]: +---[RSA 4096]----+648vm-test-run-hypopg> server # [   59.149658] sshd-keygen-start[666]: |      .++  .o .  |649vm-test-run-hypopg> server # [   59.158986] sshd-keygen-start[666]: |      ooo. o..+..|650vm-test-run-hypopg> server # [   59.164069] sshd-keygen-start[666]: |       *. . .+ o.|651vm-test-run-hypopg> server # [   59.171268] sshd-keygen-start[666]: |.     o..o +  .  |652vm-test-run-hypopg> server # [   59.180078] sshd-keygen-start[666]: | + o o .S =      |653vm-test-run-hypopg> server # [   59.186064] sshd-keygen-start[666]: |  B = o= .       |654vm-test-run-hypopg> server # [   59.189523] sshd-keygen-start[666]: | E = =o .        |655vm-test-run-hypopg> server # [   59.194348] sshd-keygen-start[666]: |o.B ooo          |656vm-test-run-hypopg> server # [   59.195467] sshd-keygen-start[666]: | .o=o..          |657vm-test-run-hypopg> server # [   59.197527] sshd-keygen-start[666]: +----[SHA256]-----+658vm-test-run-hypopg> server # [   59.660584] cfg80211: Loading compiled-in X.509 certificates for regulatory database659vm-test-run-hypopg> server # [   60.003265] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'660vm-test-run-hypopg> server # [   60.012930] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'661vm-test-run-hypopg> server # [   60.037195] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2662vm-test-run-hypopg> server # [   60.044013] cfg80211: failed to load regulatory.db663vm-test-run-hypopg> server # [   59.996954] sshd-keygen-start[790]: Generating public/private ed25519 key pair.664vm-test-run-hypopg> server # [   60.006496] sshd-keygen-start[790]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key665vm-test-run-hypopg> server # [   60.009538] sshd-keygen-start[790]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub666vm-test-run-hypopg> server # [   60.011122] sshd-keygen-start[790]: The key fingerprint is:667vm-test-run-hypopg> server # [   60.014659] sshd-keygen-start[790]: SHA256:iwCjvtlJ99jXvjeUKtt663sU5uw5AO+JHGbswyRqkAI root@server668vm-test-run-hypopg> server # [   60.021909] sshd-keygen-start[790]: The key's randomart image is:669vm-test-run-hypopg> server # [   60.023099] sshd-keygen-start[790]: +--[ED25519 256]--+670vm-test-run-hypopg> server # [   60.023868] sshd-keygen-start[790]: |                 |671vm-test-run-hypopg> server # [   60.026201] sshd-keygen-start[790]: |                 |672vm-test-run-hypopg> server # [   60.028955] sshd-keygen-start[790]: |  o              |673vm-test-run-hypopg> server # [   60.037282] sshd-keygen-start[790]: |E. o     .   o   |674vm-test-run-hypopg> server # [   60.041955] sshd-keygen-start[790]: |o   o   S o + o  |675vm-test-run-hypopg> server # [   60.047612] sshd-keygen-start[790]: |.. o . o B o *   |676vm-test-run-hypopg> server # [   60.052131] sshd-keygen-start[790]: | .....o X = B .  |677vm-test-run-hypopg> server # [   60.053145] sshd-keygen-start[790]: |  = oo+  O.* B   |678vm-test-run-hypopg> server # [   60.056710] sshd-keygen-start[790]: | o o.. o.oOB* o  |679vm-test-run-hypopg> server # [   60.061126] sshd-keygen-start[790]: +----[SHA256]-----+680vm-test-run-hypopg> server # [   60.078192] systemd[1]: sshd-keygen.service: Deactivated successfully.681vm-test-run-hypopg> server # [   60.079488] systemd[1]: Finished SSH Host Keys Generation.682vm-test-run-hypopg> server # [   60.085244] systemd[1]: sshd-keygen.service: Consumed 4.297s CPU time, 2.9M memory peak.683vm-test-run-hypopg> server # [   60.876876] 8021q: adding VLAN 0 to HW filter on device eth0684vm-test-run-hypopg> server # [   60.795590] dhcpcd[776]: eth0: waiting for carrier685vm-test-run-hypopg> server # [   60.809633] dhcpcd[776]: libudev: received NULL device686vm-test-run-hypopg> server # [   60.821513] dhcpcd[776]: libudev: received NULL device687vm-test-run-hypopg> server # [   60.824803] dhcpcd[776]: eth0: carrier acquired688vm-test-run-hypopg> server # [   60.993973] dhcpcd[776]: DUID 00:01:00:01:32:71:f6:aa:52:54:00:12:34:56689vm-test-run-hypopg> server # [   61.001732] dhcpcd[776]: eth0: IAID 00:12:34:56690vm-test-run-hypopg> server # [   61.003735] dhcpcd[776]: eth0: adding address fe80::5054:ff:fe12:3456691vm-test-run-hypopg> server # [   61.323038] dhcpcd[776]: eth0: soliciting a DHCP lease692vm-test-run-hypopg> server # [   61.354737] dhcpcd[776]: eth0: offered 10.0.2.15 from 10.0.2.2693vm-test-run-hypopg> server # [   61.378071] dhcpcd[776]: eth0: probing address 10.0.2.15/24694vm-test-run-hypopg> server # [   61.779408] systemd[1]: Found device /dev/hvc0.695vm-test-run-hypopg> server # [   63.269956] dhcpcd[776]: eth0: soliciting an IPv6 router696vm-test-run-hypopg> server # [   63.278711] dhcpcd[776]: eth0: Router Advertisement from fe80::2697vm-test-run-hypopg> server # [   63.281803] dhcpcd[776]: eth0: adding address fec0::5054:ff:fe12:3456/64698vm-test-run-hypopg> server # [   63.287102] dhcpcd[776]: eth0: adding route to fec0::/64699vm-test-run-hypopg> server # [   63.290809] dhcpcd[776]: eth0: adding default route via fe80::2700vm-test-run-hypopg> server # [   63.696090] systemd[1]: Found device /dev/ttyAMA0.701vm-test-run-hypopg> server # [   63.734365] systemd[1]: Started backdoor.service.702vm-test-run-hypopg> server # connecting to host...703vm-test-run-hypopg> server # [   64.387219] (udev-worker)[829]: Network interface NamePolicy= disabled on kernel command line.704vm-test-run-hypopg> server # [   64.493968] (udev-worker)[837]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.705vm-test-run-hypopg> server # [   64.496542] (udev-worker)[837]: Network interface NamePolicy= disabled on kernel command line.706vm-test-run-hypopg> server: Guest shell says: b'Spawning backdoor root shell...\n'707vm-test-run-hypopg> server: connected to guest root shell708vm-test-run-hypopg> server: (connecting took 65.67 seconds)709vm-test-run-hypopg> server: (finished: waiting for the VM to finish booting, in 65.67 seconds)710vm-test-run-hypopg> server # [   66.283174] dhcpcd[776]: eth0: IAID 00:12:34:56711vm-test-run-hypopg> server # [   66.318754] dhcpcd[776]: eth0: leased 10.0.2.15 for 86400 seconds712vm-test-run-hypopg> server # [   66.329802] dhcpcd[776]: eth0: adding route to 10.0.2.0/24713vm-test-run-hypopg> server # [   66.331863] dhcpcd[776]: eth0: adding default route via 10.0.2.2714vm-test-run-hypopg> server # [   66.448086] systemd[1]: Found device Virtio network device.715vm-test-run-hypopg> server # [   66.570555] systemd[1]: Starting Address configuration of eth1...716vm-test-run-hypopg> server # [   67.820562] 8021q: adding VLAN 0 to HW filter on device eth1717vm-test-run-hypopg> server # [   68.021845] network-addresses-eth1-start[866]: adding address 192.168.1.1/24... done718vm-test-run-hypopg> server # [   68.267098] network-addresses-eth1-start[866]: adding address 2001:db8:1::1/64... done719vm-test-run-hypopg> server # [   68.546632] systemd[1]: Finished Address configuration of eth1.720vm-test-run-hypopg> server # [   68.583405] systemd[1]: Starting Networking Setup...721vm-test-run-hypopg> server # [   69.905070] dhcpcd[776]: eth0: rebinding lease of 10.0.2.15722vm-test-run-hypopg> server # [   69.950553] systemd[1]: Started DHCP Client.723vm-test-run-hypopg> server # [   69.957115] dhcpcd[776]: eth0: soliciting an IPv6 router724vm-test-run-hypopg> server # [   69.969464] dhcpcd[776]: eth0: leased 10.0.2.15 for 86400 seconds725vm-test-run-hypopg> server # [   72.302081] systemd[1]: Finished Networking Setup.726vm-test-run-hypopg> server # [   72.328212] systemd[1]: Reached target Network.727vm-test-run-hypopg> server # [   72.399830] systemd[1]: Starting PostgreSQL Server...728vm-test-run-hypopg> server # [   72.463652] systemd[1]: Starting SSH Daemon...729vm-test-run-hypopg> server # [   72.522489] systemd[1]: Starting Permit User Sessions...730vm-test-run-hypopg> server # [   73.399830] systemd[1]: Finished Permit User Sessions.731vm-test-run-hypopg> server # [   73.445222] systemd[1]: Started Getty on tty1.732vm-test-run-hypopg> server # [   73.458661] systemd[1]: Reached target Login Prompts.733vm-test-run-hypopg> server # [   73.611376] sshd[1013]: Server listening on 0.0.0.0 port 22.734vm-test-run-hypopg> server # [   73.623333] sshd[1013]: Server listening on :: port 22.735vm-test-run-hypopg> server # [   73.631159] systemd[1]: Started SSH Daemon.736vm-test-run-hypopg> server # [   76.168664] postgresql-pre-start[1027]: The files belonging to this database system will be owned by user "postgres".737vm-test-run-hypopg> server # [   76.186463] postgresql-pre-start[1027]: This user must also own the server process.738vm-test-run-hypopg> server # [   76.279922] postgresql-pre-start[1027]: The database cluster will be initialized with locale "en_US.UTF-8".739vm-test-run-hypopg> server # [   76.285466] postgresql-pre-start[1027]: The default database encoding has accordingly been set to "UTF8".740vm-test-run-hypopg> server # [   76.293732] postgresql-pre-start[1027]: The default text search configuration will be set to "english".741vm-test-run-hypopg> server # [   76.294368] postgresql-pre-start[1027]: Data page checksums are disabled.742vm-test-run-hypopg> server # [   76.295719] postgresql-pre-start[1027]: fixing permissions on existing directory /var/lib/postgresql/15 ... ok743vm-test-run-hypopg> server # [   76.315652] postgresql-pre-start[1027]: creating subdirectories ... ok744vm-test-run-hypopg> server # [   76.337254] postgresql-pre-start[1027]: selecting dynamic shared memory implementation ... posix745vm-test-run-hypopg> server # [   77.429558] postgresql-pre-start[1027]: selecting default max_connections ... 100746vm-test-run-hypopg> server # [   78.285325] postgresql-pre-start[1027]: selecting default shared_buffers ... 128MB747vm-test-run-hypopg> server # [   80.055797] systemd-logind[662]: Watching system buttons on /dev/input/event0 (gpio-keys)748vm-test-run-hypopg> server # [   83.499173] mousedev: PS/2 mouse device common for all mice749vm-test-run-hypopg> server # [   86.519518] systemd-logind[662]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)750vm-test-run-hypopg> server # [   90.877450] postgresql-pre-start[1027]: selecting default time zone ... UTC751vm-test-run-hypopg> server # [   90.945150] postgresql-pre-start[1027]: creating configuration files ... ok752vm-test-run-hypopg> server # [   96.425263] postgresql-pre-start[1027]: running bootstrap script ... ok753vm-test-run-hypopg> server # [  110.526418] postgresql-pre-start[1027]: performing post-bootstrap initialization ... ok754vm-test-run-hypopg> server # [  114.229132] postgresql-pre-start[1027]: syncing data to disk ... ok755vm-test-run-hypopg> server # [  114.265768] postgresql-pre-start[1027]: initdb: warning: enabling "trust" authentication for local connections756vm-test-run-hypopg> server # [  114.267058] postgresql-pre-start[1027]: 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.757vm-test-run-hypopg> server # [  114.267741] postgresql-pre-start[1027]: Success. You can now start the database server using:758vm-test-run-hypopg> server # [  114.285910] postgresql-pre-start[1027]:     /nix/store/507yvzvz9njml7vh0gvn3m6ir3gffzqi-postgresql-15-hypopg/bin/pg_ctl -D /var/lib/postgresql/15 -l logfile start759vm-test-run-hypopg> server # [  116.263234] postgres[1429]: [1429] LOG:  starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit760vm-test-run-hypopg> server # [  116.270693] postgres[1429]: [1429] LOG:  listening on IPv4 address "0.0.0.0", port 5432761vm-test-run-hypopg> server # [  116.281086] postgres[1429]: [1429] LOG:  listening on IPv6 address "::", port 5432762vm-test-run-hypopg> server # [  116.282992] postgres[1429]: [1429] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"763vm-test-run-hypopg> server # [  116.409787] postgres[1442]: [1442] LOG:  database system was shut down at 2026-10-26 11:32:43 GMT764vm-test-run-hypopg> server # [  116.558915] postgres[1429]: [1429] LOG:  database system is ready to accept connections765vm-test-run-hypopg> server # [  116.616560] systemd[1]: Started PostgreSQL Server.766vm-test-run-hypopg> server # [  116.731793] systemd[1]: Starting PostgreSQL Setup Scripts...767vm-test-run-hypopg> server # [  119.028345] postgresql-setup-start[1482]: CREATE ROLE768vm-test-run-hypopg> server # [  119.251297] postgresql-setup-start[1484]: ALTER ROLE769vm-test-run-hypopg> server # [  119.319488] systemd[1]: Finished PostgreSQL Setup Scripts.770vm-test-run-hypopg> server # [  119.329123] systemd[1]: Reached target PostgreSQL.771vm-test-run-hypopg> server # [  119.330638] systemd[1]: Reached target Multi-User System.772vm-test-run-hypopg> server # [  119.335744] systemd[1]: Startup finished in 16.739s (kernel) + 1min 42.586s (userspace) = 1min 59.326s.773vm-test-run-hypopg> server: (finished: waiting for unit multi-user.target, in 121.75 seconds)774vm-test-run-hypopg> server: waiting for unit postgresql.service775vm-test-run-hypopg> server: (finished: waiting for unit postgresql.service, in 1.10 seconds)776vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" 777vm-test-run-hypopg> server # NOTICE:  schema "public" already exists, skipping778vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.50 seconds)779vm-test-run-hypopg> subtest: Check upgrade path with postgresql 15780vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" 781vm-test-run-hypopg> server # NOTICE:  extension "hypopg" does not exist, skipping782vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" , in 0.54 seconds)783vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.3.1' CASCADE;" 784vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.3.1' CASCADE;" , in 1.16 seconds)785vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" 786vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" , in 0.51 seconds)787vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION hypopg UPDATE TO '1.4.1';" 788vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION hypopg UPDATE TO '1.4.1';" , in 0.65 seconds)789vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" 790vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" , in 0.50 seconds)791vm-test-run-hypopg> (finished: subtest: Check upgrade path with postgresql 15, in 3.36 seconds)792vm-test-run-hypopg> subtest: Check pg_regress with postgresql 15 after extension upgrade793vm-test-run-hypopg> server: must succeed: sudo -u postgres /nix/store/507yvzvz9njml7vh0gvn3m6ir3gffzqi-postgresql-15-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 "hypopg" 794vm-test-run-hypopg> server # [  126.798597] sudo[1559]:     root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/507yvzvz9njml7vh0gvn3m6ir3gffzqi-postgresql-15-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 hypopg795vm-test-run-hypopg> server # [  127.175320] sudo[1559]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)796vm-test-run-hypopg> server # pg_regress: could not set core size: disallowed by hard limit797vm-test-run-hypopg> server # [  128.066684] sudo[1559]: pam_unix(sudo:session): session closed for user postgres798vm-test-run-hypopg> server: (finished: must succeed: sudo -u postgres /nix/store/507yvzvz9njml7vh0gvn3m6ir3gffzqi-postgresql-15-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 "hypopg" , in 2.50 seconds)799vm-test-run-hypopg> (using postmaster on Unix socket, default port)800vm-test-run-hypopg> ============== running regression test queries        ==============801vm-test-run-hypopg> test hypopg                       ... ok          495 ms802vm-test-run-hypopg> 803vm-test-run-hypopg> =====================804vm-test-run-hypopg>  All 1 tests passed. 805vm-test-run-hypopg> =====================806vm-test-run-hypopg> 807vm-test-run-hypopg> 808vm-test-run-hypopg> (finished: subtest: Check pg_regress with postgresql 15 after extension upgrade, in 2.50 seconds)809vm-test-run-hypopg> subtest: Check the install of the last version of the extension810vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" 811vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" , in 0.53 seconds)812vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" 813vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" , in 1.12 seconds)814vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" 815vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" , in 0.67 seconds)816vm-test-run-hypopg> (finished: subtest: Check the install of the last version of the extension, in 2.32 seconds)817vm-test-run-hypopg> subtest: switch to postgresql 17818vm-test-run-hypopg> server # Checking switch inhibitors... done819vm-test-run-hypopg> server # stopping the following units: postgresql-setup.service, postgresql.service820vm-test-run-hypopg> server # [  139.768047] nixos[1625]: switching to system configuration /nix/store/akrsz66rpjlmmh7y1d04kh2h3nkfxwzx-nixos-system-server-test821vm-test-run-hypopg> server # [  139.797718] systemd[1]: Stopped target Remote File Systems.822vm-test-run-hypopg> server # [  139.825206] systemd[1]: Stopped target Local File Systems.823vm-test-run-hypopg> server # [  139.844954] systemd[1]: Stopped target PostgreSQL.824vm-test-run-hypopg> server # [  139.851712] systemd[1]: postgresql-setup.service: Deactivated successfully.825vm-test-run-hypopg> server # [  139.853979] systemd[1]: Stopped PostgreSQL Setup Scripts.826vm-test-run-hypopg> server # [  139.855093] systemd[1]: Stopping PostgreSQL Server...827vm-test-run-hypopg> server # [  139.875729] postgres[1429]: [1429] LOG:  received fast shutdown request828vm-test-run-hypopg> server # [  139.881446] postgres[1429]: [1429] LOG:  aborting any active transactions829vm-test-run-hypopg> server # [  139.993537] postgres[1429]: [1429] LOG:  background worker "logical replication launcher" (PID 1448) exited with exit code 1830vm-test-run-hypopg> server # [  140.004734] postgres[1440]: [1440] LOG:  shutting down831vm-test-run-hypopg> server # [  140.022546] postgres[1440]: [1440] LOG:  checkpoint starting: shutdown immediate832vm-test-run-hypopg> server # [  140.225726] postgres[1440]: [1440] LOG:  checkpoint complete: wrote 92 buffers (0.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.068 s, sync=0.087 s, total=0.220 s; sync files=53, longest=0.019 s, average=0.002 s; distance=397 kB, estimate=397 kB833vm-test-run-hypopg> server # [  140.274571] postgres[1429]: [1429] LOG:  database system is shut down834vm-test-run-hypopg> server # [  140.335716] systemd[1]: postgresql.service: Deactivated successfully.835vm-test-run-hypopg> server # [  140.344172] systemd[1]: Stopped PostgreSQL Server.836vm-test-run-hypopg> server # [  140.351230] systemd[1]: postgresql.service: Consumed 23.170s CPU time, 91.1M memory peak, 46.7M written to disk.837vm-test-run-hypopg> server # activating the configuration...838vm-test-run-hypopg> server # setting up /etc...839vm-test-run-hypopg> server # [  148.415696] systemd[1]: Reload requested from client PID 1625 ('.switch-to-conf') (unit backdoor.service)...840vm-test-run-hypopg> server # [  148.433019] systemd[1]: Reloading...841vm-test-run-hypopg> server # [  154.522851] systemd[1]: Reloading finished in 6106 ms.842vm-test-run-hypopg> server # restarting sysinit-reactivation.target843vm-test-run-hypopg> server # [  154.960358] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....844vm-test-run-hypopg> server # [  156.367803] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..845vm-test-run-hypopg> server # [  156.406294] systemd[1]: Reached target Reactivate sysinit units.846vm-test-run-hypopg> server # reloading the following units: dbus.service847vm-test-run-hypopg> server # [  156.470490] systemd[1]: Reloading D-Bus System Message Bus...848vm-test-run-hypopg> server # [  157.012085] dbus-daemon[637]: Unknown username "systemd-timesync" in message bus configuration file849vm-test-run-hypopg> server # [  157.282100] dbus-daemon[637]: [system] Reloaded configuration850vm-test-run-hypopg> server # [  157.307332] dbus-send[1824]: method return time=1793014410.944897 sender=org.freedesktop.DBus -> destination=:1.5 serial=3 reply_serial=2851vm-test-run-hypopg> server # [  157.510699] dbus-daemon[637]: Unknown username "systemd-timesync" in message bus configuration file852vm-test-run-hypopg> server # [  157.779730] dbus-daemon[637]: [system] Reloaded configuration853vm-test-run-hypopg> server # [  157.811373] systemd[1]: Reloaded D-Bus System Message Bus.854vm-test-run-hypopg> server # starting the following units: postgresql-setup.service, postgresql.service855vm-test-run-hypopg> server # [  157.884789] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).856vm-test-run-hypopg> server # [  157.888685] systemd[1]: Reached target Local File Systems.857vm-test-run-hypopg> server # [  158.001622] systemd[1]: Starting postgresql-migrate.service...858vm-test-run-hypopg> server # [  158.179265] systemd[1]: Reached target Remote File Systems.859vm-test-run-hypopg> server # [  158.250070] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).860vm-test-run-hypopg> server # [  158.373318] systemd[1]: Starting linger-users.service...861vm-test-run-hypopg> server # [  158.386869] systemd[1]: SSH Host Keys Generation was skipped because no trigger condition checks were met.862vm-test-run-hypopg> server # [  158.615870] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).863vm-test-run-hypopg> server # [  158.669977] systemd[1]: Starting Create SUID/SGID Wrappers...864vm-test-run-hypopg> server # [  158.678690] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.865vm-test-run-hypopg> server # [  158.680316] 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).866vm-test-run-hypopg> server # [  158.710451] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).867vm-test-run-hypopg> server # [  158.713705] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).868vm-test-run-hypopg> server # [  158.756595] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).869vm-test-run-hypopg> server # [  159.062432] systemd[1]: linger-users.service: Deactivated successfully.870vm-test-run-hypopg> server # [  159.086145] systemd[1]: Finished linger-users.service.871vm-test-run-hypopg> server # [  160.316004] postgresql-migrate-start[1852]: The files belonging to this database system will be owned by user "postgres".872vm-test-run-hypopg> server # [  160.322451] postgresql-migrate-start[1852]: This user must also own the server process.873vm-test-run-hypopg> server # [  160.434892] postgresql-migrate-start[1852]: The database cluster will be initialized with locale "en_US.UTF-8".874vm-test-run-hypopg> server # [  160.435688] postgresql-migrate-start[1852]: The default database encoding has accordingly been set to "UTF8".875vm-test-run-hypopg> server # [  160.441053] postgresql-migrate-start[1852]: The default text search configuration will be set to "english".876vm-test-run-hypopg> server # [  160.444597] postgresql-migrate-start[1852]: Data page checksums are disabled.877vm-test-run-hypopg> server # [  160.446908] postgresql-migrate-start[1852]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok878vm-test-run-hypopg> server # [  160.460783] postgresql-migrate-start[1852]: creating subdirectories ... ok879vm-test-run-hypopg> server # [  160.467340] postgresql-migrate-start[1852]: selecting dynamic shared memory implementation ... posix880vm-test-run-hypopg> server # [  161.974848] postgresql-migrate-start[1852]: selecting default "max_connections" ... 100881vm-test-run-hypopg> server # [  163.298113] postgresql-migrate-start[1852]: selecting default "shared_buffers" ... 128MB882vm-test-run-hypopg> server # [  166.991928] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.883vm-test-run-hypopg> server # [  167.005043] systemd[1]: Finished Create SUID/SGID Wrappers.884vm-test-run-hypopg> server # [  167.017079] systemd[1]: suid-sgid-wrappers.service: Consumed 3.213s CPU time, 3.3M memory peak.885vm-test-run-hypopg> server # [  174.335499] postgresql-migrate-start[1852]: selecting default time zone ... UTC886vm-test-run-hypopg> server # [  174.381478] postgresql-migrate-start[1852]: creating configuration files ... ok887vm-test-run-hypopg> server # [  178.327157] postgresql-migrate-start[1852]: running bootstrap script ... ok888vm-test-run-hypopg> server # [  179.678778] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3889vm-test-run-hypopg> server # [  188.181199] postgresql-migrate-start[1852]: performing post-bootstrap initialization ... ok890vm-test-run-hypopg> server # [  189.159673] systemd[1]: Starting Virtual Console Setup...891vm-test-run-hypopg> server # [  189.503248] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.892vm-test-run-hypopg> server # [  189.506114] systemd[1]: Stopped Virtual Console Setup.893vm-test-run-hypopg> server # [  189.574256] systemd[1]: Starting Virtual Console Setup...894vm-test-run-hypopg> server # [  191.380033] postgresql-migrate-start[1852]: syncing data to disk ... ok895vm-test-run-hypopg> server # [  191.383555] postgresql-migrate-start[1852]: initdb: warning: enabling "trust" authentication for local connections896vm-test-run-hypopg> server # [  191.385344] postgresql-migrate-start[1852]: 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.897vm-test-run-hypopg> server # [  191.385803] postgresql-migrate-start[1852]: Success. You can now start the database server using:898vm-test-run-hypopg> server # [  191.386112] postgresql-migrate-start[1852]:     /nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start899vm-test-run-hypopg> server # [  194.027719] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.900vm-test-run-hypopg> server # [  194.854858] systemd[1]: Finished Virtual Console Setup.901vm-test-run-hypopg> server # [  197.312143] systemd-logind[662]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)902vm-test-run-hypopg> server # [  197.975760] postgresql-migrate-start[2122]: Performing Consistency Checks903vm-test-run-hypopg> server # [  197.985119] postgresql-migrate-start[2122]: -----------------------------904vm-test-run-hypopg> server # [  197.986434] postgresql-migrate-start[2122]: Checking cluster versions                                     ok905vm-test-run-hypopg> server # [  200.497918] postgresql-migrate-start[2122]: Checking database user is the install user                    ok906vm-test-run-hypopg> server # [  200.536353] postgresql-migrate-start[2122]: Checking database connection settings                         ok907vm-test-run-hypopg> server # [  200.605624] postgresql-migrate-start[2122]: Checking for prepared transactions                            ok908vm-test-run-hypopg> server # [  200.606510] postgresql-migrate-start[2122]: Checking for contrib/isn with bigint-passing mismatch         ok909vm-test-run-hypopg> server # [  201.209320] postgresql-migrate-start[2122]: Checking data type usage                                      ok910vm-test-run-hypopg> server # [  201.376939] postgresql-migrate-start[2122]: Checking for not-null constraint inconsistencies              ok911vm-test-run-hypopg> server # [  201.955187] postgresql-migrate-start[2122]: Creating dump of global objects                               ok912vm-test-run-hypopg> server # [  203.902301] postgresql-migrate-start[2122]: Creating dump of database schemas                             ok913vm-test-run-hypopg> server # [  206.058387] postgresql-migrate-start[2122]: Checking for presence of required libraries                   ok914vm-test-run-hypopg> server # [  206.114018] postgresql-migrate-start[2122]: Checking database user is the install user                    ok915vm-test-run-hypopg> server # [  206.181137] postgresql-migrate-start[2122]: Checking for prepared transactions                            ok916vm-test-run-hypopg> server # [  206.182520] postgresql-migrate-start[2122]: Checking for new cluster tablespace directories               ok917vm-test-run-hypopg> server # [  206.183168] postgresql-migrate-start[2122]: If pg_upgrade fails after this point, you must re-initdb the918vm-test-run-hypopg> server # [  206.183780] postgresql-migrate-start[2122]: new cluster before continuing.919vm-test-run-hypopg> server # [  206.190373] postgresql-migrate-start[2122]: Performing Upgrade920vm-test-run-hypopg> server # [  206.193483] postgresql-migrate-start[2122]: ------------------921vm-test-run-hypopg> server # [  206.239637] postgresql-migrate-start[2122]: Setting locale and encoding for new cluster                   ok922vm-test-run-hypopg> server # [  208.243642] postgresql-migrate-start[2122]: Analyzing all rows in the new cluster                         ok923vm-test-run-hypopg> server # [  209.048438] postgresql-migrate-start[2122]: Freezing all rows in the new cluster                          ok924vm-test-run-hypopg> server # [  209.342923] postgresql-migrate-start[2122]: Deleting files from new pg_xact                               ok925vm-test-run-hypopg> server # [  209.473379] postgresql-migrate-start[2122]: Copying old pg_xact to new server                             ok926vm-test-run-hypopg> server # [  209.774743] postgresql-migrate-start[2122]: Setting oldest XID for new cluster                            ok927vm-test-run-hypopg> server # [  210.683796] postgresql-migrate-start[2122]: Setting next transaction ID and epoch for new cluster         ok928vm-test-run-hypopg> server # [  210.688630] postgresql-migrate-start[2122]: Deleting files from new pg_multixact/offsets                  ok929vm-test-run-hypopg> server # [  210.819383] postgresql-migrate-start[2122]: Copying old pg_multixact/offsets to new server                ok930vm-test-run-hypopg> server # [  210.822329] postgresql-migrate-start[2122]: Deleting files from new pg_multixact/members                  ok931vm-test-run-hypopg> server # [  210.952141] postgresql-migrate-start[2122]: Copying old pg_multixact/members to new server                ok932vm-test-run-hypopg> server # [  211.247107] postgresql-migrate-start[2122]: Setting next multixact ID and offset for new cluster          ok933vm-test-run-hypopg> server # [  211.549618] postgresql-migrate-start[2122]: Resetting WAL archives                                        ok934vm-test-run-hypopg> server # [  213.014399] postgresql-migrate-start[2122]: Setting frozenxid and minmxid counters in new cluster         ok935vm-test-run-hypopg> server # [  213.333240] postgresql-migrate-start[2122]: Restoring global objects in the new cluster                   ok936vm-test-run-hypopg> server # [  215.290767] postgresql-migrate-start[2122]: Restoring database schemas in the new cluster                 ok937vm-test-run-hypopg> server # [  215.918237] postgresql-migrate-start[2122]: Copying user relation files                                   ok938vm-test-run-hypopg> server # [  216.221035] postgresql-migrate-start[2122]: Setting next OID for new cluster                              ok939vm-test-run-hypopg> server # [  217.674690] postgresql-migrate-start[2122]: Sync data directory to disk                                   ok940vm-test-run-hypopg> server # [  217.678963] postgresql-migrate-start[2122]: Creating script to delete old cluster                         ok941vm-test-run-hypopg> server # [  219.406029] postgresql-migrate-start[2122]: Checking for extension updates                                ok942vm-test-run-hypopg> server # [  219.652552] postgresql-migrate-start[2122]: Upgrade Complete943vm-test-run-hypopg> server # [  219.654562] postgresql-migrate-start[2122]: ----------------944vm-test-run-hypopg> server # [  219.655894] postgresql-migrate-start[2122]: Optimizer statistics are not transferred by pg_upgrade.945vm-test-run-hypopg> server # [  219.657590] postgresql-migrate-start[2122]: Once you start the new server, consider running:946vm-test-run-hypopg> server # [  219.658228] postgresql-migrate-start[2122]:     /nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/bin/vacuumdb --all --analyze-in-stages947vm-test-run-hypopg> server # [  219.658668] postgresql-migrate-start[2122]: Running this script will delete the old cluster's data files:948vm-test-run-hypopg> server # [  219.659665] postgresql-migrate-start[2122]:     ./delete_old_cluster.sh949vm-test-run-hypopg> server # [  219.698548] systemd[1]: Finished postgresql-migrate.service.950vm-test-run-hypopg> server # [  219.735235] systemd[1]: Starting PostgreSQL Server...951vm-test-run-hypopg> server # [  220.978876] postgres[2320]: [2320] LOG:  starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit952vm-test-run-hypopg> server # [  220.983175] postgres[2320]: [2320] LOG:  listening on IPv4 address "0.0.0.0", port 5432953vm-test-run-hypopg> server # [  220.984782] postgres[2320]: [2320] LOG:  listening on IPv6 address "::", port 5432954vm-test-run-hypopg> server # [  220.993994] postgres[2320]: [2320] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"955vm-test-run-hypopg> server # [  221.050722] postgres[2326]: [2326] LOG:  database system was shut down at 2026-10-26 11:34:33 GMT956vm-test-run-hypopg> server # [  221.095910] postgres[2320]: [2320] LOG:  database system is ready to accept connections957vm-test-run-hypopg> server # [  221.127655] systemd[1]: Started PostgreSQL Server.958vm-test-run-hypopg> server # [  221.150713] systemd[1]: Starting PostgreSQL Setup Scripts...959vm-test-run-hypopg> server # [  221.866483] postgresql-setup-start[2336]: ALTER ROLE960vm-test-run-hypopg> server # [  221.915906] systemd[1]: Finished PostgreSQL Setup Scripts.961vm-test-run-hypopg> server # [  221.925153] systemd[1]: Reached target PostgreSQL.962vm-test-run-hypopg> server # the following new units were started: postgresql-migrate.service, sys-devices-platform-4010000000.pcie-pci0000:00-0000:00:0b.0-virtio9-virtio\x2dports-vport9p0.device, sys-devices-virtual-input-mice.device, sys-devices-virtual-misc-rfkill.device, sys-devices-virtual-misc-tun.device, sys-devices-virtual-mtd-mtd0ro.device, sysinit-reactivation.target, systemd-rfkill.socket, systemd-tmpfiles-resetup.service, systemd-vconsole-setup.service963vm-test-run-hypopg> server # [  222.223857] nixos[1625]: finished switching to system configuration /nix/store/akrsz66rpjlmmh7y1d04kh2h3nkfxwzx-nixos-system-server-test964vm-test-run-hypopg> server: waiting for unit postgresql.service965vm-test-run-hypopg> server: (finished: waiting for unit postgresql.service, in 0.59 seconds)966vm-test-run-hypopg> server: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no'967vm-test-run-hypopg> server: (finished: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no', in 0.13 seconds)968vm-test-run-hypopg> (finished: subtest: switch to postgresql 17, in 92.54 seconds)969vm-test-run-hypopg> subtest: Check last version of the extension after postgresql upgrade970vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" 971vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" , in 0.32 seconds)972vm-test-run-hypopg> (finished: subtest: Check last version of the extension after postgresql upgrade, in 0.32 seconds)973vm-test-run-hypopg> subtest: Check upgrade path with postgresql 17974vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" 975vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" , in 0.30 seconds)976vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" 977vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" , in 0.45 seconds)978vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" 979vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" , in 0.30 seconds)980vm-test-run-hypopg> (finished: subtest: Check upgrade path with postgresql 17, in 1.05 seconds)981vm-test-run-hypopg> subtest: Check pg_regress with postgresql 17 after extension upgrade982vm-test-run-hypopg> server: must succeed: sudo -u postgres /nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "hypopg" 983vm-test-run-hypopg> server # [  224.669422] sudo[2362]:     root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 hypopg984vm-test-run-hypopg> server # [  224.774484] sudo[2362]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)985vm-test-run-hypopg> server # # could not set core size: disallowed by hard limit986vm-test-run-hypopg> server # [  225.312112] sudo[2362]: pam_unix(sudo:session): session closed for user postgres987vm-test-run-hypopg> server: (finished: must succeed: sudo -u postgres /nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "hypopg" , in 0.98 seconds)988vm-test-run-hypopg> # using postmaster on Unix socket, default port989vm-test-run-hypopg> ok 1         - hypopg                                    287 ms990vm-test-run-hypopg> 1..1991vm-test-run-hypopg> # All 1 tests passed.992vm-test-run-hypopg> 993vm-test-run-hypopg> (finished: subtest: Check pg_regress with postgresql 17 after extension upgrade, in 0.98 seconds)994vm-test-run-hypopg> subtest: Check the install of the last version of the extension995vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" 996vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" , in 0.31 seconds)997vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" 998vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" , in 0.42 seconds)999vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" 1000vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" , in 0.28 seconds)1001vm-test-run-hypopg> (finished: subtest: Check the install of the last version of the extension, in 1.02 seconds)1002vm-test-run-hypopg> subtest: Check pg_regress with postgresql 17 after installing the last version1003vm-test-run-hypopg> server: must succeed: sudo -u postgres /nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "hypopg" 1004vm-test-run-hypopg> server # [  226.632674] sudo[2384]:     root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 hypopg1005vm-test-run-hypopg> server # [  226.743306] sudo[2384]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)1006vm-test-run-hypopg> server # # could not set core size: disallowed by hard limit1007vm-test-run-hypopg> server # [  227.200050] sudo[2384]: pam_unix(sudo:session): session closed for user postgres1008vm-test-run-hypopg> server: (finished: must succeed: sudo -u postgres /nix/store/q5dcnn23r76xqzbkzrw7abppr6zr3r3z-postgresql-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "hypopg" , in 0.88 seconds)1009vm-test-run-hypopg> # using postmaster on Unix socket, default port1010vm-test-run-hypopg> ok 1         - hypopg                                    253 ms1011vm-test-run-hypopg> 1..11012vm-test-run-hypopg> # All 1 tests passed.1013vm-test-run-hypopg> 1014vm-test-run-hypopg> (finished: subtest: Check pg_regress with postgresql 17 after installing the last version, in 0.88 seconds)1015vm-test-run-hypopg> subtest: switch to orioledb 171016vm-test-run-hypopg> server # Checking switch inhibitors... done1017vm-test-run-hypopg> server # stopping the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service1018vm-test-run-hypopg> server # [  232.219304] nixos[2398]: switching to system configuration /nix/store/cwqp3cpia83si11nkihqinwyp25x4xlp-nixos-system-server-test1019vm-test-run-hypopg> server # [  232.231332] systemd[1]: Stopped target Remote File Systems.1020vm-test-run-hypopg> server # [  232.246901] systemd[1]: Stopped target PostgreSQL.1021vm-test-run-hypopg> server # [  232.248172] systemd[1]: postgresql-setup.service: Deactivated successfully.1022vm-test-run-hypopg> server # [  232.254225] systemd[1]: Stopped PostgreSQL Setup Scripts.1023vm-test-run-hypopg> server # [  232.255324] systemd[1]: Stopping PostgreSQL Server...1024vm-test-run-hypopg> server # [  232.263899] postgres[2320]: [2320] LOG:  received fast shutdown request1025vm-test-run-hypopg> server # [  232.275275] postgres[2320]: [2320] LOG:  aborting any active transactions1026vm-test-run-hypopg> server # [  232.327668] postgres[2320]: [2320] LOG:  background worker "logical replication launcher" (PID 2329) exited with exit code 11027vm-test-run-hypopg> server # [  232.342654] systemd[1]: Stopped target Local File Systems.1028vm-test-run-hypopg> server # [  232.358780] postgres[2324]: [2324] LOG:  shutting down1029vm-test-run-hypopg> server # [  232.363107] postgres[2324]: [2324] LOG:  checkpoint starting: shutdown immediate1030vm-test-run-hypopg> server # [  232.472947] postgres[2324]: [2324] LOG:  checkpoint complete: wrote 97 buffers (0.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.034 s, sync=0.039 s, total=0.119 s; sync files=47, longest=0.012 s, average=0.001 s; distance=568 kB, estimate=568 kB; lsn=0/808E408, redo lsn=0/808E4081031vm-test-run-hypopg> server # [  232.510012] postgres[2320]: [2320] LOG:  database system is shut down1032vm-test-run-hypopg> server # [  232.559277] systemd[1]: postgresql.service: Deactivated successfully.1033vm-test-run-hypopg> server # [  232.566272] systemd[1]: Stopped PostgreSQL Server.1034vm-test-run-hypopg> server # [  232.571923] systemd[1]: postgresql.service: Consumed 2.073s CPU time, 22.9M memory peak, 224K written to disk.1035vm-test-run-hypopg> server # [  232.597237] systemd[1]: postgresql-migrate.service: Deactivated successfully.1036vm-test-run-hypopg> server # [  232.604197] systemd[1]: Stopped postgresql-migrate.service.1037vm-test-run-hypopg> server # [  232.608691] systemd[1]: postgresql-migrate.service: Consumed 35.109s CPU time, 97.5M memory peak, 173.1M written to disk.1038vm-test-run-hypopg> server # activating the configuration...1039vm-test-run-hypopg> server # setting up /etc...1040vm-test-run-hypopg> server # [  236.471451] systemd[1]: Reload requested from client PID 2398 ('.switch-to-conf') (unit backdoor.service)...1041vm-test-run-hypopg> server # [  236.476946] systemd[1]: Reloading...1042vm-test-run-hypopg> server # [  241.819164] systemd[1]: Reloading finished in 5330 ms.1043vm-test-run-hypopg> server # restarting sysinit-reactivation.target1044vm-test-run-hypopg> server # [  242.121788] systemd[1]: Stopped target Reactivate sysinit units.1045vm-test-run-hypopg> server # [  242.126795] systemd[1]: Stopping Reactivate sysinit units...1046vm-test-run-hypopg> server # [  242.127887] systemd[1]: Reached target Reactivate sysinit units.1047vm-test-run-hypopg> server # reloading the following units: dbus.service1048vm-test-run-hypopg> server # [  242.161495] systemd[1]: Reloading D-Bus System Message Bus...1049vm-test-run-hypopg> server # [  242.412314] dbus-daemon[637]: Unknown username "systemd-timesync" in message bus configuration file1050vm-test-run-hypopg> server # [  242.521451] dbus-daemon[637]: [system] Reloaded configuration1051vm-test-run-hypopg> server # [  242.539072] dbus-send[2479]: method return time=1793014496.174426 sender=org.freedesktop.DBus -> destination=:1.7 serial=3 reply_serial=21052vm-test-run-hypopg> server # [  242.551606] systemd[1]: Reloaded D-Bus System Message Bus.1053vm-test-run-hypopg> server # [  242.614900] dbus-daemon[637]: Unknown username "systemd-timesync" in message bus configuration file1054vm-test-run-hypopg> server # [  242.728096] dbus-daemon[637]: [system] Reloaded configuration1055vm-test-run-hypopg> server # starting the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service1056vm-test-run-hypopg> server # [  242.798674] systemd[1]: Reached target Remote File Systems.1057vm-test-run-hypopg> server # [  242.879663] systemd[1]: Starting linger-users.service...1058vm-test-run-hypopg> server # [  242.931889] systemd[1]: Starting postgresql-migrate.service...1059vm-test-run-hypopg> server # [  242.937935] systemd[1]: SSH Host Keys Generation was skipped because no trigger condition checks were met.1060vm-test-run-hypopg> server # [  243.005600] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1061vm-test-run-hypopg> server # [  243.149512] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).1062vm-test-run-hypopg> server # [  243.155387] systemd[1]: Reached target Local File Systems.1063vm-test-run-hypopg> server # [  243.201345] systemd[1]: linger-users.service: Deactivated successfully.1064vm-test-run-hypopg> server # [  243.211623] systemd[1]: Finished linger-users.service.1065vm-test-run-hypopg> server # [  243.253597] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).1066vm-test-run-hypopg> server # [  243.286995] systemd[1]: Starting Create SUID/SGID Wrappers...1067vm-test-run-hypopg> server # [  243.299180] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.1068vm-test-run-hypopg> server # [  243.300118] 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).1069vm-test-run-hypopg> server # [  243.307085] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).1070vm-test-run-hypopg> server # [  243.310268] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1071vm-test-run-hypopg> server # [  243.313770] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).1072vm-test-run-hypopg> server # [  244.037303] systemd[1]: Finished postgresql-migrate.service.1073vm-test-run-hypopg> server # [  246.678025] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1074vm-test-run-hypopg> server # [  246.682358] systemd[1]: Finished Create SUID/SGID Wrappers.1075vm-test-run-hypopg> server # [  246.687015] systemd[1]: suid-sgid-wrappers.service: Consumed 1.658s CPU time, 3.3M memory peak.1076vm-test-run-hypopg> server # [  246.728726] systemd[1]: Starting PostgreSQL Server...1077vm-test-run-hypopg> server # [  247.855632] postgresql-pre-start[2562]: The files belonging to this database system will be owned by user "postgres".1078vm-test-run-hypopg> server # [  247.863059] postgresql-pre-start[2562]: This user must also own the server process.1079vm-test-run-hypopg> server # [  247.928200] postgresql-pre-start[2562]: Using language tag "en-US" for ICU locale "en_US.UTF-8".1080vm-test-run-hypopg> server # [  247.930938] postgresql-pre-start[2562]: The database cluster will be initialized with this locale configuration:1081vm-test-run-hypopg> server # [  247.931656] postgresql-pre-start[2562]:   locale provider:   icu1082vm-test-run-hypopg> server # [  247.932064] postgresql-pre-start[2562]:   default collation: en-US1083vm-test-run-hypopg> server # [  247.932333] postgresql-pre-start[2562]:   LC_COLLATE:  en_US.UTF-81084vm-test-run-hypopg> server # [  247.932577] postgresql-pre-start[2562]:   LC_CTYPE:    en_US.UTF-81085vm-test-run-hypopg> server # [  247.933549] postgresql-pre-start[2562]:   LC_MESSAGES: en_US.UTF-81086vm-test-run-hypopg> server # [  247.934210] postgresql-pre-start[2562]:   LC_MONETARY: en_US.UTF-81087vm-test-run-hypopg> server # [  247.934660] postgresql-pre-start[2562]:   LC_NUMERIC:  en_US.UTF-81088vm-test-run-hypopg> server # [  247.935147] postgresql-pre-start[2562]:   LC_TIME:     en_US.UTF-81089vm-test-run-hypopg> server # [  247.935408] postgresql-pre-start[2562]: The default text search configuration will be set to "english".1090vm-test-run-hypopg> server # [  247.937579] postgresql-pre-start[2562]: Data page checksums are disabled.1091vm-test-run-hypopg> server # [  247.938567] postgresql-pre-start[2562]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok1092vm-test-run-hypopg> server # [  247.945106] postgresql-pre-start[2562]: creating subdirectories ... ok1093vm-test-run-hypopg> server # [  247.947957] postgresql-pre-start[2562]: selecting dynamic shared memory implementation ... posix1094vm-test-run-hypopg> server # [  248.470711] postgresql-pre-start[2562]: selecting default "max_connections" ... 1001095vm-test-run-hypopg> server # [  248.830542] postgresql-pre-start[2562]: selecting default "shared_buffers" ... 128MB1096vm-test-run-hypopg> server # [  254.844085] postgresql-pre-start[2562]: selecting default time zone ... UTC1097vm-test-run-hypopg> server # [  254.862646] postgresql-pre-start[2562]: creating configuration files ... ok1098vm-test-run-hypopg> server # [  256.845692] postgresql-pre-start[2562]: running bootstrap script ... ok1099vm-test-run-hypopg> server # [  261.655132] postgresql-pre-start[2562]: performing post-bootstrap initialization ... ok1100vm-test-run-hypopg> server # [  263.036988] postgresql-pre-start[2562]: syncing data to disk ... ok1101vm-test-run-hypopg> server # [  263.038152] postgresql-pre-start[2562]: initdb: warning: enabling "trust" authentication for local connections1102vm-test-run-hypopg> server # [  263.039229] postgresql-pre-start[2562]: 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.1103vm-test-run-hypopg> server # [  263.039722] postgresql-pre-start[2562]: Success. You can now start the database server using:1104vm-test-run-hypopg> server # [  263.040570] postgresql-pre-start[2562]:     /nix/store/c8xiw2z6f106xw6dy0ljq2lk308b4f8c-postgresql-orioledb-17-hypopg/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start1105vm-test-run-hypopg> server # [  263.989814] postgres[2575]: [2575] LOG:  registered custom resource manager "OrioleDB resource manager" with ID 1291106vm-test-run-hypopg> server # [  264.308331] postgres[2575]: [2575] LOG:  starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit1107vm-test-run-hypopg> server # [  264.363432] postgres[2575]: [2575] LOG:  listening on IPv6 address "::1", port 54321108vm-test-run-hypopg> server # [  264.367239] postgres[2575]: [2575] LOG:  listening on IPv4 address "127.0.0.1", port 54321109vm-test-run-hypopg> server # [  264.374737] postgres[2575]: [2575] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1110vm-test-run-hypopg> server # [  264.443874] postgres[2581]: [2581] LOG:  Cleanup of old files at startup. Checkpoint 01111vm-test-run-hypopg> server # [  264.451896] postgres[2582]: [2582] LOG:  orioledb background writer 0 started1112vm-test-run-hypopg> server # [  264.454268] postgres[2581]: [2581] LOG:  database system was shut down at 2026-10-26 11:35:15 GMT1113vm-test-run-hypopg> server # [  264.514407] postgres[2575]: [2575] LOG:  database system is ready to accept connections1114vm-test-run-hypopg> server # [  264.547607] systemd[1]: Started PostgreSQL Server.1115vm-test-run-hypopg> server # [  264.573169] systemd[1]: Starting PostgreSQL Setup Scripts...1116vm-test-run-hypopg> server # [  265.544599] postgresql-setup-start[2589]: CREATE EXTENSION1117vm-test-run-hypopg> server # [  265.937796] postgresql-setup-start[2595]: CREATE ROLE1118vm-test-run-hypopg> server # [  266.084758] postgresql-setup-start[2597]: ALTER ROLE1119vm-test-run-hypopg> server # [  266.131222] systemd[1]: Finished PostgreSQL Setup Scripts.1120vm-test-run-hypopg> server # [  266.141691] systemd[1]: Reached target PostgreSQL.1121vm-test-run-hypopg> server # [  266.434745] nixos[2398]: finished switching to system configuration /nix/store/cwqp3cpia83si11nkihqinwyp25x4xlp-nixos-system-server-test1122vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extname FROM pg_extension WHERE extname = 'orioledb';" 1123vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extname FROM pg_extension WHERE extname = 'orioledb';" , in 0.31 seconds)1124vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" 1125vm-test-run-hypopg> server # NOTICE:  schema "public" already exists, skipping1126vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.29 seconds)1127vm-test-run-hypopg> (finished: subtest: switch to orioledb 17, in 39.84 seconds)1128vm-test-run-hypopg> subtest: Check upgrade path with orioledb 171129vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" 1130vm-test-run-hypopg> server # NOTICE:  extension "hypopg" does not exist, skipping1131vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS hypopg;" , in 0.21 seconds)1132vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" 1133vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION hypopg WITH VERSION '1.4.1' CASCADE;" , in 0.50 seconds)1134vm-test-run-hypopg> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" 1135vm-test-run-hypopg> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'hypopg';" , in 0.34 seconds)1136vm-test-run-hypopg> (finished: subtest: Check upgrade path with orioledb 17, in 1.06 seconds)1137vm-test-run-hypopg> subtest: Check pg_regress with orioledb 17 after installing the last version1138vm-test-run-hypopg> server: must succeed: sudo -u postgres /nix/store/c8xiw2z6f106xw6dy0ljq2lk308b4f8c-postgresql-orioledb-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 "hypopg" 1139vm-test-run-hypopg> server # [  268.495799] sudo[2620]:     root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/c8xiw2z6f106xw6dy0ljq2lk308b4f8c-postgresql-orioledb-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 hypopg1140vm-test-run-hypopg> server # [  268.609199] sudo[2620]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)1141vm-test-run-hypopg> server # # could not set core size: disallowed by hard limit1142vm-test-run-hypopg> server # [  269.206280] sudo[2620]: pam_unix(sudo:session): session closed for user postgres1143vm-test-run-hypopg> server: (finished: must succeed: sudo -u postgres /nix/store/c8xiw2z6f106xw6dy0ljq2lk308b4f8c-postgresql-orioledb-17-hypopg/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/cc1sqswgspxkbsnx1dyf3rx7h9s1m1s7-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 "hypopg" , in 1.10 seconds)1144vm-test-run-hypopg> # using postmaster on Unix socket, default port1145vm-test-run-hypopg> ok 1         - hypopg                                    353 ms1146vm-test-run-hypopg> 1..11147vm-test-run-hypopg> # All 1 tests passed.1148vm-test-run-hypopg> 1149vm-test-run-hypopg> (finished: subtest: Check pg_regress with orioledb 17 after installing the last version, in 1.10 seconds)1150vm-test-run-hypopg> (finished: run the VM test script, in 270.42 seconds)1151vm-test-run-hypopg> test script finished in 270.71s1152vm-test-run-hypopg> cleanup1153vm-test-run-hypopg> kill machine (pid 9)1154vm-test-run-hypopg> qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/y88crn50yx35fiiwcjx6xqwi6ahk7mlq-python3-3.13.11/bin/python3.13)1155vm-test-run-hypopg> vde_switch: EOF data port: Interrupted system call1156vm-test-run-hypopg> kill vlan (pid 7)1157vm-test-run-hypopg> (finished: cleanup, in 0.01 seconds)1158vm-test-run-hypopg> vde_switch: Caught signal 15, cleaning up and exiting