these 4 derivations will be built: /nix/store/f1qysz7l0fnw9zn72rbccca1da33qssw-test-script.drv /nix/store/hy47mh3n1bxvyf48lp26gnkgww1s077q-driverConfiguration.json.drv /nix/store/9m85x4bi9qj7kwkc9c1r33yznysyxfsj-nixos-test-driver-pgtap.drv /nix/store/na7mkbkada1cqq7097jrmcd0cwlx76f5-vm-test-run-pgtap.drv building '/nix/store/f1qysz7l0fnw9zn72rbccca1da33qssw-test-script.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/f1qysz7l0fnw9zn72rbccca1da33qssw-test-script.drv' building '/nix/store/hy47mh3n1bxvyf48lp26gnkgww1s077q-driverConfiguration.json.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/hy47mh3n1bxvyf48lp26gnkgww1s077q-driverConfiguration.json.drv' driverConfiguration.json> structuredAttrs is enabled building '/nix/store/9m85x4bi9qj7kwkc9c1r33yznysyxfsj-nixos-test-driver-pgtap.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/9m85x4bi9qj7kwkc9c1r33yznysyxfsj-nixos-test-driver-pgtap.drv' nixos-test-driver-pgtap> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-pgtap> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-pgtap> All checks passed! nixos-test-driver-pgtap> Linting test script (enable/disable: config.skipLint) nixos-test-driver-pgtap> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint nixos-test-driver-pgtap> All checks passed! building '/nix/store/na7mkbkada1cqq7097jrmcd0cwlx76f5-vm-test-run-pgtap.drv' on 'ssh-ng://nixbot-remote-build@62.210.193.142' building '/nix/store/na7mkbkada1cqq7097jrmcd0cwlx76f5-vm-test-run-pgtap.drv' vm-test-run-pgtap> Machine state will be reset. To keep it, pass --keep-machine-state vm-test-run-pgtap> start all VLans vm-test-run-pgtap> (finished: start all VLans, in 0.01 seconds) vm-test-run-pgtap> Test will time out and terminate in 3600 seconds vm-test-run-pgtap> run the VM test script vm-test-run-pgtap> additionally exposed symbols: vm-test-run-pgtap> server, vm-test-run-pgtap> vlan1, vm-test-run-pgtap> start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh vm-test-run-pgtap> Running tests for extension: pgtap vm-test-run-pgtap> start all VMs vm-test-run-pgtap> server: starting vm vm-test-run-pgtap> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-pgtap> server: QEMU running (pid 91078) vm-test-run-pgtap> server # Formatting '/nix/var/nix/builds/nix-91058-65619379/vm-state-server/tmp.jkwV6jrcEU', fmt=raw size=1073741824 vm-test-run-pgtap> server # mke2fs 1.47.4 (6-Mar-2025) vm-test-run-pgtap> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-pgtap> server # Filesystem UUID: 6b2cd26d-f061-48a2-ba6c-1d784e97dff4 vm-test-run-pgtap> server # Superblock backups stored on blocks: vm-test-run-pgtap> server # 32768, 98304, 163840, 229376 vm-test-run-pgtap> server # vm-test-run-pgtap> server # Allocating group tables: 0/8 done vm-test-run-pgtap> server # Writing inode tables: 0/8 done vm-test-run-pgtap> server # Creating journal (8192 blocks): done vm-test-run-pgtap> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-pgtap> server # vm-test-run-pgtap> server # Virtualisation disk image created. vm-test-run-pgtap> (finished: start all VMs, in 0.56 seconds) vm-test-run-pgtap> server: waiting for unit multi-user.target vm-test-run-pgtap> server: waiting for the VM to finish booting vm-test-run-pgtap> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x610f0000] vm-test-run-pgtap> server # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 vm-test-run-pgtap> server # [ 0.000000] KASLR enabled vm-test-run-pgtap> server # [ 0.000000] random: crng init done vm-test-run-pgtap> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-pgtap> server # [ 0.000000] efi: UEFI not found. vm-test-run-pgtap> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-pgtap> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgtap> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f604280-0x13f6079ff] vm-test-run-pgtap> server # [ 0.000000] Zone ranges: vm-test-run-pgtap> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] vm-test-run-pgtap> server # [ 0.000000] DMA32 empty vm-test-run-pgtap> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-pgtap> server # [ 0.000000] Device empty vm-test-run-pgtap> server # [ 0.000000] Movable zone start for each node vm-test-run-pgtap> server # [ 0.000000] Early memory node ranges vm-test-run-pgtap> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgtap> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgtap> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 vm-test-run-pgtap> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-pgtap> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-pgtap> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-pgtap> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-pgtap> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-pgtap> server # [ 0.000000] percpu: Embedded 76 pages/cpu s186520 r8192 d116584 u311296 vm-test-run-pgtap> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-pgtap> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-pgtap> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-pgtap> server # [ 0.000000] CPU features: detected: Spectre-BHB vm-test-run-pgtap> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-pgtap> server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/fsy7kxx1rlqwh9rxnzdwddnxqw3f4l5b-nixos-system-server-test/init regInfo=/nix/store/690i1ykcsyz2b28w9n85szn02gxg5fl3-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-pgtap> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/690i1ykcsyz2b28w9n85szn02gxg5fl3-closure-info/registration", will be passed to user space. vm-test-run-pgtap> server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes vm-test-run-pgtap> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-pgtap> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-pgtap> server # [ 0.000000] software IO TLB: area num 2. vm-test-run-pgtap> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) vm-test-run-pgtap> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-pgtap> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 vm-test-run-pgtap> server # [ 0.000000] Policy zone: Normal vm-test-run-pgtap> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-pgtap> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm-test-run-pgtap> server # [ 0.000000] allocated 8388608 bytes of page_ext vm-test-run-pgtap> server # [ 0.000000] ftrace: allocating 74832 entries in 294 pages vm-test-run-pgtap> server # [ 0.000000] ftrace: allocated 294 pages with 4 groups vm-test-run-pgtap> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-pgtap> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-pgtap> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2. vm-test-run-pgtap> server # [ 0.000000] Trampoline variant of Tasks RCU enabled. vm-test-run-pgtap> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-pgtap> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-pgtap> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-pgtap> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm-test-run-pgtap> server # [ 0.000000] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-pgtap> server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-pgtap> server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-pgtap> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-pgtap> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-pgtap> server # [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] vm-test-run-pgtap> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-pgtap> server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-pgtap> server # [ 0.000000] arch_timer: cp15 timer running at 24.00MHz (virt). vm-test-run-pgtap> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns vm-test-run-pgtap> server # [ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns vm-test-run-pgtap> server # [ 0.000203] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-pgtap> server # [ 0.000263] Console: colour dummy device 80x25 vm-test-run-pgtap> server # [ 0.000265] printk: legacy console [tty0] enabled vm-test-run-pgtap> server # [ 0.000335] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) vm-test-run-pgtap> server # [ 0.000339] pid_max: default: 32768 minimum: 301 vm-test-run-pgtap> server # [ 0.000367] LSM: initializing lsm=capability,landlock,yama,bpf,ima vm-test-run-pgtap> server # [ 0.000426] landlock: Up and running. vm-test-run-pgtap> server # [ 0.000427] Yama: becoming mindful. vm-test-run-pgtap> server # [ 0.000612] LSM support for eBPF active vm-test-run-pgtap> server # [ 0.000656] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-pgtap> server # [ 0.000666] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-pgtap> server # [ 0.001137] rcu: Hierarchical SRCU implementation. vm-test-run-pgtap> server # [ 0.001139] rcu: Max phase no-delay instances is 1000. vm-test-run-pgtap> server # [ 0.001173] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-pgtap> server # [ 0.001566] EFI services will not be available. vm-test-run-pgtap> server # [ 0.001613] smp: Bringing up secondary CPUs ... vm-test-run-pgtap> server # [ 0.001885] Detected PIPT I-cache on CPU1 vm-test-run-pgtap> server # [ 0.001954] CPU1: Booted secondary processor 0x0000000001 [0x610f0000] vm-test-run-pgtap> server # [ 0.002138] smp: Brought up 1 node, 2 CPUs vm-test-run-pgtap> server # [ 0.002142] SMP: Total of 2 processors activated. vm-test-run-pgtap> server # [ 0.002143] CPU: All CPU(s) started at EL1 vm-test-run-pgtap> server # [ 0.002146] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-pgtap> server # [ 0.002147] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-pgtap> server # [ 0.002149] CPU features: detected: Common not Private translations vm-test-run-pgtap> server # [ 0.002149] CPU features: detected: CRC32 instructions vm-test-run-pgtap> server # [ 0.002150] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-pgtap> server # [ 0.002151] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-pgtap> server # [ 0.002152] CPU features: detected: Data independent timing control (DIT) vm-test-run-pgtap> server # [ 0.002152] CPU features: detected: E0PD vm-test-run-pgtap> server # [ 0.002153] CPU features: detected: Enhanced Privileged Access Never vm-test-run-pgtap> server # [ 0.002154] CPU features: detected: Fine Grained Traps vm-test-run-pgtap> server # [ 0.002155] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-pgtap> server # [ 0.002156] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-pgtap> server # [ 0.002157] CPU features: detected: LSE atomic instructions vm-test-run-pgtap> server # [ 0.002158] CPU features: detected: Privileged Access Never vm-test-run-pgtap> server # [ 0.002159] CPU features: detected: RAS Extension Support vm-test-run-pgtap> server # [ 0.002159] CPU features: detected: Speculation barrier (SB) vm-test-run-pgtap> server # [ 0.002160] CPU features: detected: TLB range maintenance instructions vm-test-run-pgtap> server # [ 0.002213] alternatives: applying system-wide alternatives vm-test-run-pgtap> server # [ 0.003004] Memory: 3906376K/4194304K available (24320K kernel code, 7090K rwdata, 26572K rodata, 4736K init, 1103K bss, 239844K reserved, 32768K cma-reserved) vm-test-run-pgtap> server # [ 0.005755] devtmpfs: initialized vm-test-run-pgtap> server # [ 0.006698] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear) vm-test-run-pgtap> server # [ 0.006712] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear). vm-test-run-pgtap> server # [ 0.006812] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-pgtap> server # [ 0.006814] 0 pages in range for non-PLT usage vm-test-run-pgtap> server # [ 0.006815] 508272 pages in range for PLT usage vm-test-run-pgtap> server # [ 0.006884] pinctrl core: initialized pinctrl subsystem vm-test-run-pgtap> server # [ 0.007236] DMI not present or invalid. vm-test-run-pgtap> server # [ 0.008300] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-pgtap> server # [ 0.008907] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-pgtap> server # [ 0.008941] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-pgtap> server # [ 0.008983] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-pgtap> server # [ 0.009007] audit: initializing netlink subsys (disabled) vm-test-run-pgtap> server # [ 0.009173] audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 vm-test-run-pgtap> server # [ 0.009402] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-pgtap> server # [ 0.009402] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-pgtap> server # [ 0.009404] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-pgtap> server # [ 0.009405] thermal_sys: Registered thermal governor 'user_space' vm-test-run-pgtap> server # [ 0.009406] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-pgtap> server # [ 0.009415] cpuidle: using governor ladder vm-test-run-pgtap> server # [ 0.009418] cpuidle: using governor menu vm-test-run-pgtap> server # [ 0.009457] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-pgtap> server # [ 0.009540] ASID allocator initialised with 65536 entries vm-test-run-pgtap> server # [ 0.009778] Serial: AMBA PL011 UART driver vm-test-run-pgtap> server # [ 0.011060] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-pgtap> server # [ 0.011242] printk: console [ttyAMA0] enabled vm-test-run-pgtap> server # [ 0.024379] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-pgtap> server # [ 0.024395] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-pgtap> server # [ 0.024398] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-pgtap> server # [ 0.024400] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-pgtap> server # [ 0.024401] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-pgtap> server # [ 0.024403] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-pgtap> server # [ 0.024417] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-pgtap> server # [ 0.024420] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-pgtap> server # [ 0.037568] fbcon: Taking over console vm-test-run-pgtap> server # [ 0.037582] ACPI: Interpreter disabled. vm-test-run-pgtap> server # [ 0.040206] iommu: Default domain type: Translated vm-test-run-pgtap> server # [ 0.040221] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-pgtap> server # [ 0.044771] SCSI subsystem initialized vm-test-run-pgtap> server # [ 0.044966] usbcore: registered new interface driver usbfs vm-test-run-pgtap> server # [ 0.044973] usbcore: registered new interface driver hub vm-test-run-pgtap> server # [ 0.044977] usbcore: registered new device driver usb vm-test-run-pgtap> server # [ 0.045085] pps_core: LinuxPPS API ver. 1 registered vm-test-run-pgtap> server # [ 0.045087] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-pgtap> server # [ 0.045089] PTP clock support registered vm-test-run-pgtap> server # [ 0.045099] EDAC MC: Ver: 3.0.0 vm-test-run-pgtap> server # [ 0.045145] scmi_core: SCMI protocol bus registered vm-test-run-pgtap> server # [ 0.053448] FPGA manager framework vm-test-run-pgtap> server # [ 0.053937] vgaarb: loaded vm-test-run-pgtap> server # [ 0.056898] clocksource: Switched to clocksource arch_sys_counter vm-test-run-pgtap> server # [ 0.057082] VFS: Disk quotas dquot_6.6.0 vm-test-run-pgtap> server # [ 0.057088] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-pgtap> server # [ 0.057275] netfs: FS-Cache loaded vm-test-run-pgtap> server # [ 0.057311] pnp: PnP ACPI: disabled vm-test-run-pgtap> server # [ 0.058173] NET: Registered PF_INET protocol family vm-test-run-pgtap> server # [ 0.058253] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-pgtap> server # [ 0.070908] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-pgtap> server # [ 0.070922] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-pgtap> server # [ 0.070935] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-pgtap> server # [ 0.070964] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-pgtap> server # [ 0.071005] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-pgtap> server # [ 0.071040] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-pgtap> server # [ 0.071052] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear) vm-test-run-pgtap> server # [ 0.071060] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear) vm-test-run-pgtap> server # [ 0.071094] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-pgtap> server # [ 0.071101] NET: Registered PF_XDP protocol family vm-test-run-pgtap> server # [ 0.071107] PCI: CLS 0 bytes, default 64 vm-test-run-pgtap> server # [ 0.071238] Trying to unpack rootfs image as initramfs... vm-test-run-pgtap> server # [ 0.073003] kvm [1]: HYP mode not available vm-test-run-pgtap> server # [ 0.092743] Initialise system trusted keyrings vm-test-run-pgtap> server # [ 0.092807] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-pgtap> server # [ 0.093566] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-pgtap> server # [ 0.093595] 9p: Installing v9fs 9p2000 file system support vm-test-run-pgtap> server # [ 0.102053] Key type asymmetric registered vm-test-run-pgtap> server # [ 0.102059] Asymmetric key parser 'x509' registered vm-test-run-pgtap> server # [ 0.102101] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) vm-test-run-pgtap> server # [ 0.105529] io scheduler mq-deadline registered vm-test-run-pgtap> server # [ 0.105532] io scheduler kyber registered vm-test-run-pgtap> server # [ 0.110407] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-pgtap> server # [ 0.113215] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-pgtap> server # [ 0.113357] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-pgtap> server # [ 0.113363] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-pgtap> server # [ 0.113368] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-pgtap> server # [ 0.113370] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-pgtap> server # [ 0.113379] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-pgtap> server # [ 0.113387] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-pgtap> server # [ 0.113414] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-pgtap> server # [ 0.113416] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-pgtap> server # [ 0.113417] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-pgtap> server # [ 0.113419] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-pgtap> server # [ 0.113420] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-pgtap> server # [ 0.113439] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.113553] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.113608] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgtap> server # [ 0.113610] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.113615] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.113617] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pgtap> server # [ 0.113710] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.113748] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgtap> server # [ 0.113750] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.113755] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.113856] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.113894] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pgtap> server # [ 0.113896] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.113900] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.113993] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.114030] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgtap> server # [ 0.114032] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.114036] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.114131] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.114173] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgtap> server # [ 0.114175] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.114180] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.114269] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.114308] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-pgtap> server # [ 0.114310] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.114316] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.114423] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.114468] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgtap> server # [ 0.114470] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.114476] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.114479] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pgtap> server # [ 0.114580] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.114618] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.114623] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.114715] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.114753] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.114758] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.114838] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.114876] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.114927] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.115129] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pgtap> server # [ 0.115137] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.115145] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.115294] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pgtap> server # [ 0.115337] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgtap> server # [ 0.115340] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgtap> server # [ 0.115345] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgtap> server # [ 0.115493] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-pgtap> server # [ 0.115495] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-pgtap> server # [ 0.115497] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115506] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115515] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115524] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115532] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115541] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115550] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115560] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115568] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115577] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115624] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-pgtap> server # [ 0.115675] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-pgtap> server # [ 0.115687] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-pgtap> server # [ 0.115694] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-pgtap> server # [ 0.115700] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-pgtap> server # [ 0.115705] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-pgtap> server # [ 0.115710] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-pgtap> server # [ 0.115715] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-pgtap> server # [ 0.115720] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-pgtap> server # [ 0.115726] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-pgtap> server # [ 0.115731] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-pgtap> server # [ 0.115736] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-pgtap> server # [ 0.115742] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-pgtap> server # [ 0.115747] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-pgtap> server # [ 0.115752] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-pgtap> server # [ 0.115756] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-pgtap> server # [ 0.115761] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-pgtap> server # [ 0.115766] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-pgtap> server # [ 0.115770] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-pgtap> server # [ 0.115775] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-pgtap> server # [ 0.115780] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-pgtap> server # [ 0.115784] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-pgtap> server # [ 0.115793] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-pgtap> server # [ 0.115795] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-pgtap> server # [ 0.115797] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-pgtap> server # [ 0.116217] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-pgtap> server # [ 0.124354] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.133703] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.134907] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.136260] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.137649] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.138617] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.140824] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.149910] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-pgtap> server # [ 0.151231] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-pgtap> server # [ 0.154440] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.155956] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-pgtap> server # [ 0.166428] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-pgtap> server # [ 0.166905] msm_serial: driver initialized vm-test-run-pgtap> server # [ 0.166964] SuperH (H)SCI(F) driver initialized vm-test-run-pgtap> server # [ 0.166986] STM32 USART driver initialized vm-test-run-pgtap> server # [ 0.181670] loop: module loaded vm-test-run-pgtap> server # [ 0.181982] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-pgtap> server # [ 0.182390] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-pgtap> server # [ 0.185038] megasas: 07.734.00.00-rc1 vm-test-run-pgtap> server # [ 0.185324] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-pgtap> server # [ 0.186398] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pgtap> server # [ 0.186404] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pgtap> server # [ 0.187681] Using buffer write method vm-test-run-pgtap> server # [ 0.187696] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-pgtap> server # [ 0.188432] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pgtap> server # [ 0.188437] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pgtap> server # [ 0.189159] Using buffer write method vm-test-run-pgtap> server # [ 0.189164] Concatenating MTD devices: vm-test-run-pgtap> server # [ 0.189166] (0): "0.flash" vm-test-run-pgtap> server # [ 0.189167] (1): "0.flash" vm-test-run-pgtap> server # [ 0.189167] into device "0.flash" vm-test-run-pgtap> server # [ 0.220495] Freeing initrd memory: 25488K vm-test-run-pgtap> server # [ 0.226869] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-pgtap> server # [ 0.229416] thunder_xcv, ver 1.0 vm-test-run-pgtap> server # [ 0.229424] thunder_bgx, ver 1.0 vm-test-run-pgtap> server # [ 0.229429] nicpf, ver 1.0 vm-test-run-pgtap> server # [ 0.229621] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-pgtap> server # [ 0.229623] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-pgtap> server # [ 0.229629] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-pgtap> server # [ 0.229630] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-pgtap> server # [ 0.229640] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-pgtap> server # [ 0.229641] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-pgtap> server # [ 0.229646] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-pgtap> server # [ 0.229647] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-pgtap> server # [ 0.229690] sky2: driver version 1.30 vm-test-run-pgtap> server # [ 0.230313] usbcore: registered new interface driver usb-storage vm-test-run-pgtap> server # [ 0.230336] usbcore: registered new interface driver usbserial_generic vm-test-run-pgtap> server # [ 0.230339] usbserial: USB Serial support registered for generic vm-test-run-pgtap> server # [ 0.230546] hv_vmbus: registering driver hyperv_keyboard vm-test-run-pgtap> server # [ 0.230564] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-pgtap> server # [ 0.230584] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-pgtap> server # [ 0.230676] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-pgtap> server # [ 0.231023] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-pgtap> server # [ 0.231206] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T11:01:04 UTC (1791198064) vm-test-run-pgtap> server # [ 0.231414] i2c_dev: i2c /dev entries driver vm-test-run-pgtap> server # [ 0.232807] sdhci: Secure Digital Host Controller Interface driver vm-test-run-pgtap> server # [ 0.232813] sdhci: Copyright(c) Pierre Ossman vm-test-run-pgtap> server # [ 0.234947] Synopsys Designware Multimedia Card Interface Driver vm-test-run-pgtap> server # [ 0.235197] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-pgtap> server # [ 0.236009] hid: raw HID events driver (C) Jiri Kosina vm-test-run-pgtap> server # [ 0.236115] usbcore: registered new interface driver usbhid vm-test-run-pgtap> server # [ 0.236117] usbhid: USB HID core driver vm-test-run-pgtap> server # [ 0.236645] armv8-pmu pmu: hw perfevents: failed to probe PMU! vm-test-run-pgtap> server # [ 0.237415] drop_monitor: Initializing network drop monitor service vm-test-run-pgtap> server # [ 0.237501] NET: Registered PF_INET6 protocol family vm-test-run-pgtap> server # [ 0.243192] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-pgtap> server # [ 0.243316] hub 1-0:1.0: USB hub found vm-test-run-pgtap> server # [ 0.243322] hub 1-0:1.0: 6 ports detected vm-test-run-pgtap> server # [ 0.243514] Segment Routing with IPv6 vm-test-run-pgtap> server # [ 0.243518] In-situ OAM (IOAM) with IPv6 vm-test-run-pgtap> server # [ 0.243529] NET: Registered PF_PACKET protocol family vm-test-run-pgtap> server # [ 0.244059] 9pnet: Installing 9P2000 support vm-test-run-pgtap> server # [ 0.246330] Key type dns_resolver registered vm-test-run-pgtap> server # [ 0.247611] registered taskstats version 1 vm-test-run-pgtap> server # [ 0.247681] Loading compiled-in X.509 certificates vm-test-run-pgtap> server # [ 0.249835] Demotion targets for Node 0: null vm-test-run-pgtap> server # [ 0.249871] Key type .fscrypt registered vm-test-run-pgtap> server # [ 0.249872] Key type fscrypt-provisioning registered vm-test-run-pgtap> server # [ 0.249907] ima: No TPM chip found, activating TPM-bypass! vm-test-run-pgtap> server # [ 0.249914] ima: Allocated hash algorithm: sha1 vm-test-run-pgtap> server # [ 0.249921] ima: No architecture policies found vm-test-run-pgtap> server # [ 0.250159] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-pgtap> server # [ 0.254451] clk: Disabling unused clocks vm-test-run-pgtap> server # [ 0.254456] PM: genpd: Disabling unused power domains vm-test-run-pgtap> server # [ 0.256266] Freeing unused kernel memory: 4736K vm-test-run-pgtap> server # [ 0.256330] Run /init as init process vm-test-run-pgtap> server # [ 0.295193] systemd[1]: Successfully made /usr/ read-only. vm-test-run-pgtap> server # [ 0.509761] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-pgtap> server # [ 0.638196] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) vm-test-run-pgtap> server # [ 0.638208] systemd[1]: Detected virtualization qemu. vm-test-run-pgtap> server # [ 0.638232] systemd[1]: Detected architecture arm64. vm-test-run-pgtap> server # [ 0.638234] systemd[1]: Running in initrd. vm-test-run-pgtap> server # [ 0.638476] systemd[1]: Initializing machine ID from random generator. vm-test-run-pgtap> server # [ 0.638525] systemd[1]: Hostname set to . vm-test-run-pgtap> server # [ 0.672175] systemd[1]: Queued start job for default target Initrd Default Target. vm-test-run-pgtap> server # [ 0.673613] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-pgtap> server # [ 0.673794] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-pgtap> server # [ 0.673809] systemd[1]: Expecting device /dev/disk/by-label/nixos... vm-test-run-pgtap> server # [ 0.673819] systemd[1]: Reached target Path Units. vm-test-run-pgtap> server # [ 0.673826] systemd[1]: Reached target Slice Units. vm-test-run-pgtap> server # [ 0.673833] systemd[1]: Reached target Swaps. vm-test-run-pgtap> server # [ 0.673839] systemd[1]: Reached target Timer Units. vm-test-run-pgtap> server # [ 0.673882] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-pgtap> server # [ 0.673930] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-pgtap> server # [ 0.673963] systemd[1]: Listening on Journal Sockets. vm-test-run-pgtap> server # [ 0.673991] systemd[1]: Listening on udev Control Socket. vm-test-run-pgtap> server # [ 0.674009] systemd[1]: Listening on udev Kernel Socket. vm-test-run-pgtap> server # [ 0.674015] systemd[1]: Reached target Socket Units. vm-test-run-pgtap> server # [ 0.674622] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-pgtap> server # [ 0.675104] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-pgtap> server # [ 0.675131] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-pgtap> server # [ 0.678246] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-pgtap> server # [ 0.683577] systemd[1]: Starting Journal Service... vm-test-run-pgtap> server # [ 0.684002] systemd[1]: Starting Load Kernel Modules... vm-test-run-pgtap> server # [ 0.684017] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 0.684886] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-pgtap> server # [ 0.686250] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-pgtap> server # [ 0.686577] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-pgtap> server # [ 0.686651] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-pgtap> server # [ 0.686715] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-pgtap> server # [ 0.688467] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-pgtap> server # [ 0.695086] systemd-journald[80]: Collecting audit messages is disabled. vm-test-run-pgtap> server # [ 0.697152] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-pgtap> server # [ 0.697559] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-pgtap> server # [ 0.700864] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-pgtap> server # [ 0.700975] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-pgtap> server # [ 0.700976] [drm] features: -context_init vm-test-run-pgtap> server # [ 0.701182] [drm] number of scanouts: 1 vm-test-run-pgtap> server # [ 0.701185] [drm] number of cap sets: 0 vm-test-run-pgtap> server # [ 0.705253] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-pgtap> server # [ 0.705317] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-pgtap> server # [ 0.705327] systemd[1]: Reached target Local File Systems. vm-test-run-pgtap> server # [ 0.705779] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-pgtap> server # [ 0.705951] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. vm-test-run-pgtap> server # [ 0.710565] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic vm-test-run-pgtap> server # [ 0.710569] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-pgtap> server # [ 0.711394] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev vm-test-run-pgtap> server # [ 0.711971] Console: switching to colour frame buffer device 160x50 vm-test-run-pgtap> server # [ 0.712171] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-pgtap> server # [ 0.720577] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 vm-test-run-pgtap> server # [ 0.719896] systemd-modules-load[81]: Using 2 probe threads vm-test-run-pgtap> server # [ 0.720971] systemd-modules-load[81]: Module 'virtio_balloon' is built in vm-test-run-pgtap> server # [ 0.721314] systemd-modules-load[81]: Module 'virtio_console' is built in vm-test-run-pgtap> server # [ 0.721629] systemd-modules-load[81]: Inserted module 'dm_mod' vm-test-run-pgtap> server # [ 0.721890] systemd-modules-load[81]: Module 'virtio_rng' is built in vm-test-run-pgtap> server # [ 0.722218] systemd-modules-load[81]: Inserted module 'virtio_gpu' vm-test-run-pgtap> server # [ 0.722628] systemd[1]: Finished Load Kernel Modules. vm-test-run-pgtap> server # [ 0.725199] systemd[1]: Started Journal Service. vm-test-run-pgtap> server # [ 0.728139] systemd[1]: Starting Apply Kernel Variables... vm-test-run-pgtap> server # [ 0.728409] systemd[1]: Starting Create System Files and Directories... vm-test-run-pgtap> server # [ 0.731789] systemd-udevd[87]: Using default interface naming scheme 'v260'. vm-test-run-pgtap> server # [ 0.732532] systemd[1]: Finished Apply Kernel Variables. vm-test-run-pgtap> server # [ 0.738952] systemd[1]: Finished Create System Files and Directories. vm-test-run-pgtap> server # [ 0.742284] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-pgtap> server # [ 0.752323] systemd[1]: Starting Virtual Console Setup... vm-test-run-pgtap> server # [ 0.777471] systemd-vconsole-setup[113]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-pgtap> server # [ 0.778458] systemd[1]: Finished Virtual Console Setup. vm-test-run-pgtap> server # [ 0.826243] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 vm-test-run-pgtap> server # [ 0.864302] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-pgtap> server # [ 0.864571] systemd[1]: Reached target System Initialization. vm-test-run-pgtap> server # [ 0.864812] systemd[1]: Reached target Basic System. vm-test-run-pgtap> server # [ 0.904901] (udev-worker)[106]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-pgtap> server # [ 0.906609] (udev-worker)[106]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgtap> server # [ 0.923028] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgtap> server # [ 0.929753] systemd[1]: Found device /dev/disk/by-label/nixos. vm-test-run-pgtap> server # [ 0.930024] systemd[1]: Reached target Initrd Root Device. vm-test-run-pgtap> server # [ 0.930357] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... vm-test-run-pgtap> server # [ 0.944923] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-pgtap> server # [ 0.955147] systemd-fsck[132]: nixos: clean, 12/65536 files, 13019/262144 blocks vm-test-run-pgtap> server # [ 0.960201] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. vm-test-run-pgtap> server # [ 1.139829] (udev-worker)[112]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory vm-test-run-pgtap> server # [ 1.144427] (udev-worker)[112]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory vm-test-run-pgtap> server # [ 1.149098] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-pgtap> server # [ 1.155033] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 vm-test-run-pgtap> server # [ 1.155120] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 vm-test-run-pgtap> server # [ 1.152420] systemd[1]: Stopped Virtual Console Setup. vm-test-run-pgtap> server # [ 1.153470] systemd[1]: Stopping Virtual Console Setup... vm-test-run-pgtap> server # [ 1.153834] systemd[1]: Starting Virtual Console Setup... vm-test-run-pgtap> server # [ 1.180677] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-pgtap> server # [ 1.181696] systemd[1]: Finished Virtual Console Setup. vm-test-run-pgtap> server # [ 1.681983] systemd[1]: Mounting /sysroot... vm-test-run-pgtap> server # [ 1.722743] systemd[1]: Mounted /sysroot. vm-test-run-pgtap> server # [ 1.723187] systemd[1]: Reached target Initrd Root File System. vm-test-run-pgtap> server # [ 1.726237] EXT4-fs (vda): mounted filesystem 6b2cd26d-f061-48a2-ba6c-1d784e97dff4 r/w with ordered data mode. Quota mode: none. vm-test-run-pgtap> server # [ 1.724596] systemd[1]: Mounting /sysroot/nix/.ro-store... vm-test-run-pgtap> server # [ 1.726849] systemd[1]: Mounting /sysroot/nix/.rw-store... vm-test-run-pgtap> server # [ 1.728131] systemd[1]: Mounting /sysroot/run... vm-test-run-pgtap> server # [ 1.728794] systemd[1]: Mounting /sysroot/tmp/shared... vm-test-run-pgtap> server # [ 1.733246] systemd[1]: Mounting /sysroot/tmp/xchg... vm-test-run-pgtap> server # [ 1.736457] systemd[1]: Starting Mountpoints Configured in the Real Root... vm-test-run-pgtap> server # [ 1.738911] systemd[1]: Mounted /sysroot/nix/.rw-store. vm-test-run-pgtap> server # [ 1.739227] systemd[1]: Mounted /sysroot/run. vm-test-run-pgtap> server # [ 1.742832] systemd-sysroot-fstab-check[151]: /sysroot should be mounted in the initrd, will request daemon-reload. vm-test-run-pgtap> server # [ 1.744295] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-pgtap> server # [ 1.744860] systemd[1]: Mounted /sysroot/nix/.ro-store. vm-test-run-pgtap> server # [ 1.745077] systemd[1]: Mounted /sysroot/tmp/shared. vm-test-run-pgtap> server # [ 1.745395] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-pgtap> server # [ 1.746340] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-pgtap> server # [ 1.747363] systemd[1]: Mounted /sysroot/tmp/xchg. vm-test-run-pgtap> server # [ 1.748455] systemd[1]: Reload requested from client PID 151 ('systemd-sysroot') (unit initrd-parse-etc.service)... vm-test-run-pgtap> server # [ 1.748914] systemd[1]: Reloading... vm-test-run-pgtap> server # [ 1.790086] systemd[1]: Reloading finished in 40 ms. vm-test-run-pgtap> server # [ 1.838553] systemd-sysroot-fstab-check[151]: Requesting initrd-fs.target/start/replace... vm-test-run-pgtap> server # [ 1.839069] systemd-sysroot-fstab-check[151]: Requesting swap.target/start/replace... vm-test-run-pgtap> server # [ 1.839447] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-pgtap> server # [ 1.840253] systemd[1]: initrd-parse-etc.service: Deactivated successfully. vm-test-run-pgtap> server # [ 1.841599] systemd[1]: Finished Mountpoints Configured in the Real Root. vm-test-run-pgtap> server # [ 1.842002] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. vm-test-run-pgtap> server # [ 1.862240] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-pgtap> server # [ 1.862956] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-pgtap> server # [ 2.708364] systemd[1]: Mounting /sysroot/nix/store... vm-test-run-pgtap> server # [ 2.763450] systemd[1]: Mounted /sysroot/nix/store. vm-test-run-pgtap> server # [ 2.763767] systemd[1]: Reached target Initrd File Systems. vm-test-run-pgtap> server # [ 2.764799] systemd[1]: Starting Find NixOS closure... vm-test-run-pgtap> server # [ 2.765280] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... vm-test-run-pgtap> server # [ 2.773886] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. vm-test-run-pgtap> server # [ 2.774442] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. vm-test-run-pgtap> server # [ 2.776314] systemd[1]: Finished Find NixOS closure. vm-test-run-pgtap> server # [ 2.776612] systemd[1]: Reached target Initrd Default Target. vm-test-run-pgtap> server # [ 2.777057] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... vm-test-run-pgtap> server # [ 2.785367] systemd[1]: Stopped target Initrd Default Target. vm-test-run-pgtap> server # [ 2.786329] systemd[1]: Stopped target Basic System. vm-test-run-pgtap> server # [ 2.786679] systemd[1]: Stopped target Initrd Root Device. vm-test-run-pgtap> server # [ 2.786916] systemd[1]: Stopped target Path Units. vm-test-run-pgtap> server # [ 2.787148] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. vm-test-run-pgtap> server # [ 2.789251] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. vm-test-run-pgtap> server # [ 2.789553] systemd[1]: Stopped target Slice Units. vm-test-run-pgtap> server # [ 2.791869] systemd[1]: Stopped target Socket Units. vm-test-run-pgtap> server # [ 2.792321] systemd[1]: Stopped target System Initialization. vm-test-run-pgtap> server # [ 2.792722] systemd[1]: Stopped target Swaps. vm-test-run-pgtap> server # [ 2.792967] systemd[1]: Stopped target Timer Units. vm-test-run-pgtap> server # [ 2.793172] systemd[1]: dbus.socket: Deactivated successfully. vm-test-run-pgtap> server # [ 2.793999] systemd[1]: Closed D-Bus System Message Bus Socket. vm-test-run-pgtap> server # [ 2.794990] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.795677] systemd[1]: Stopped Find NixOS closure. vm-test-run-pgtap> server # [ 2.796298] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-pgtap> server # [ 2.796653] systemd[1]: Starting rw-sysroot-nix-store.service... vm-test-run-pgtap> server # [ 2.796989] systemd[1]: systemd-sysctl.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.797394] systemd[1]: Stopped Apply Kernel Variables. vm-test-run-pgtap> server # [ 2.797762] systemd[1]: systemd-modules-load.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.798071] systemd[1]: Stopped Load Kernel Modules. vm-test-run-pgtap> server # [ 2.798260] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.798688] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. vm-test-run-pgtap> server # [ 2.798998] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.799287] systemd[1]: Stopped Create System Files and Directories. vm-test-run-pgtap> server # [ 2.799575] systemd[1]: Stopped target Local File Systems. vm-test-run-pgtap> server # [ 2.799802] systemd[1]: Stopped target Preparation for Local File Systems. vm-test-run-pgtap> server # [ 2.801262] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.801552] systemd[1]: Stopped Coldplug All udev Devices. vm-test-run-pgtap> server # [ 2.801878] systemd[1]: Stopping Rule-based Manager for Device Events and Files... vm-test-run-pgtap> server # [ 2.802337] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.802785] systemd[1]: Stopped Virtual Console Setup. vm-test-run-pgtap> server # [ 2.802981] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.803253] systemd[1]: Finished rw-sysroot-nix-store.service. vm-test-run-pgtap> server # [ 2.803477] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.803904] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-pgtap> server # [ 2.804425] systemd[1]: initrd-cleanup.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.804678] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. vm-test-run-pgtap> server # [ 2.804921] systemd[1]: systemd-udevd.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.805148] systemd[1]: Stopped Rule-based Manager for Device Events and Files. vm-test-run-pgtap> server # [ 2.805402] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. vm-test-run-pgtap> server # [ 2.805879] systemd[1]: Closed udev Control Socket. vm-test-run-pgtap> server # [ 2.806764] systemd[1]: Starting Cleanup udev Database... vm-test-run-pgtap> server # [ 2.806959] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.807630] systemd[1]: Stopped Create Static Device Nodes in /dev. vm-test-run-pgtap> server # [ 2.808262] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.808599] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. vm-test-run-pgtap> server # [ 2.808870] systemd[1]: kmod-static-nodes.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.809261] systemd[1]: Stopped Create List of Static Device Nodes. vm-test-run-pgtap> server # [ 2.854339] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. vm-test-run-pgtap> server # [ 2.854671] systemd[1]: Finished Cleanup udev Database. vm-test-run-pgtap> server # [ 2.854864] systemd[1]: Reached target Switch Root. vm-test-run-pgtap> server # [ 2.856112] systemd[1]: Starting NixOS Activation... vm-test-run-pgtap> server # [ 3.110016] initrd-nixos-activation-start[198]: booting system configuration /nix/store/fsy7kxx1rlqwh9rxnzdwddnxqw3f4l5b-nixos-system-server-test vm-test-run-pgtap> server # [ 3.207101] initrd-nixos-activation-start[198]: running activation script... vm-test-run-pgtap> server # [ 3.899674] initrd-nixos-activation-start[221]: setting up /etc... vm-test-run-pgtap> server # [ 4.346293] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. vm-test-run-pgtap> server # [ 4.346748] systemd[1]: Finished NixOS Activation. vm-test-run-pgtap> server # [ 4.347019] systemd[1]: Starting Switch Root... vm-test-run-pgtap> server # [ 4.404210] systemd[1]: Switching root. vm-test-run-pgtap> server # [ 4.638458] systemd-journald[80]: Received SIGTERM from PID 1 (systemd). vm-test-run-pgtap> server # [ 5.467758] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) vm-test-run-pgtap> server # [ 5.467790] systemd[1]: Detected virtualization qemu. vm-test-run-pgtap> server # [ 5.467815] systemd[1]: Detected architecture arm64. vm-test-run-pgtap> server # [ 5.467861] systemd[1]: Detected first boot. vm-test-run-pgtap> server # [ 5.475908] systemd[1]: Initializing machine ID from random generator. vm-test-run-pgtap> server # [ 5.633422] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-pgtap> server # [ 5.764511] NET: Registered PF_VSOCK protocol family vm-test-run-pgtap> server # [ 5.770291] Guest personality initialized and is inactive vm-test-run-pgtap> server # [ 5.770707] VMCI host device registered (name=vmci, major=10, minor=261) vm-test-run-pgtap> server # [ 5.770724] Initialized host personality vm-test-run-pgtap> server # [ 5.944352] systemd[1]: Applying preset policy. vm-test-run-pgtap> server # [ 6.685444] systemd[1]: Populated /etc with preset unit settings. vm-test-run-pgtap> server # [ 7.543734] systemd[1]: initrd-switch-root.service: Deactivated successfully. vm-test-run-pgtap> server # [ 7.544165] systemd[1]: Stopped initrd-switch-root.service. vm-test-run-pgtap> server # [ 7.545343] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. vm-test-run-pgtap> server # [ 7.546456] systemd[1]: Created slice Slice /system/getty. vm-test-run-pgtap> server # [ 7.547268] systemd[1]: Created slice User and Session Slice. vm-test-run-pgtap> server # [ 7.547712] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-pgtap> server # [ 7.548010] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-pgtap> server # [ 7.548263] systemd[1]: Expecting device /dev/hvc0... vm-test-run-pgtap> server # [ 7.548648] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-pgtap> server # [ 7.548819] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-pgtap> server # [ 7.549597] systemd[1]: Stopped target initrd-fs.target. vm-test-run-pgtap> server # [ 7.549777] systemd[1]: Stopped target initrd-root-fs.target. vm-test-run-pgtap> server # [ 7.550387] systemd[1]: Stopped target initrd-switch-root.target. vm-test-run-pgtap> server # [ 7.550783] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-pgtap> server # [ 7.551301] systemd[1]: Reached target Path Units. vm-test-run-pgtap> server # [ 7.551578] systemd[1]: Reached target Remote File Systems. vm-test-run-pgtap> server # [ 7.551790] systemd[1]: Reached target Slice Units. vm-test-run-pgtap> server # [ 7.552325] systemd[1]: Reached target Swaps. vm-test-run-pgtap> server # [ 7.557758] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-pgtap> server # [ 7.561192] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-pgtap> server # [ 7.564588] systemd[1]: Starting Journal Log Access Socket... vm-test-run-pgtap> server # [ 7.565586] systemd[1]: Listening on Journal Audit Socket. vm-test-run-pgtap> server # [ 7.565885] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-pgtap> server # [ 7.566356] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 7.566698] systemd[1]: Listening on udev Control Socket. vm-test-run-pgtap> server # [ 7.600846] systemd[1]: Mounting Huge Pages File System... vm-test-run-pgtap> server # [ 7.604288] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-pgtap> server # [ 7.607855] systemd[1]: Mounting Kernel Debug File System... vm-test-run-pgtap> server # [ 7.612181] systemd[1]: Mounting Kernel Trace File System... vm-test-run-pgtap> server # [ 7.617742] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-pgtap> server # [ 7.650705] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-pgtap> server # [ 7.651027] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-pgtap> server # [ 7.660881] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-pgtap> server # [ 7.662339] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm vm-test-run-pgtap> server # [ 7.665398] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-pgtap> server # [ 7.677060] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-pgtap> server # [ 7.677377] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 vm-test-run-pgtap> server # [ 7.681020] systemd[1]: Starting Journal Service... vm-test-run-pgtap> server # [ 7.689914] systemd[1]: Starting Load Kernel Modules... vm-test-run-pgtap> server # [ 7.695374] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-pgtap> server # [ 7.704687] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-pgtap> server # [ 7.706247] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 7.716522] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-pgtap> server # [ 7.718806] systemd[1]: Listening on Journal Log Access Socket. vm-test-run-pgtap> server # [ 7.730682] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-pgtap> server # [ 7.736400] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-pgtap> server # [ 7.739642] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-pgtap> server # [ 7.740023] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-pgtap> server # [ 7.780201] systemd[1]: Mounted Kernel Debug File System. vm-test-run-pgtap> server # [ 7.786796] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-pgtap> server # [ 7.791121] systemd[1]: Mounted Huge Pages File System. vm-test-run-pgtap> server # [ 7.791451] systemd[1]: Mounted Kernel Trace File System. vm-test-run-pgtap> server # [ 7.791834] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-pgtap> server # [ 7.846032] systemd-journald[292]: Collecting audit messages is enabled. vm-test-run-pgtap> server # [ 7.851765] fuse: init (API version 7.45) vm-test-run-pgtap> server # [ 7.852535] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-pgtap> server # [ 7.854228] systemd[1]: systemd-journald.service: Deactivated successfully. vm-test-run-pgtap> server # [ 7.858388] systemd[1]: Started Journal Service. vm-test-run-pgtap> server # [ 7.857178] systemd-modules-load[293]: Using 2 probe threads vm-test-run-pgtap> server # [ 7.858639] systemd-oomd[294]: No swap; memory pressure usage will be degraded vm-test-run-pgtap> server # [ 7.860547] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-pgtap> server # [ 7.863821] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-pgtap> server # [ 7.864617] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-pgtap> server # [ 7.877293] EXT4-fs (vda): re-mounted 6b2cd26d-f061-48a2-ba6c-1d784e97dff4. vm-test-run-pgtap> server # [ 7.877032] systemd-modules-load[293]: Module 'atkbd' is built in vm-test-run-pgtap> server # [ 7.877825] systemd-modules-load[293]: Module 'loop' is built in vm-test-run-pgtap> server # [ 7.882575] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-pgtap> server # [ 7.884312] systemd[1]: Finished Load Kernel Modules. vm-test-run-pgtap> server # [ 7.890439] systemd[1]: Starting Firewall... vm-test-run-pgtap> server # [ 7.895161] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-pgtap> server # [ 7.896539] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-pgtap> server # [ 7.905912] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-pgtap> server # [ 7.925535] systemd[1]: Starting Apply Kernel Variables... vm-test-run-pgtap> server # [ 7.926596] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 7.975938] systemd-journald[292]: Received client request to flush runtime journal. vm-test-run-pgtap> server # [ 7.988753] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-pgtap> server # [ 7.991272] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-pgtap> server # [ 7.991953] systemd[1]: Reached target First Boot Complete. vm-test-run-pgtap> server # [ 7.992951] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-pgtap> server # [ 7.996562] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-pgtap> server # [ 8.032118] systemd[1]: Finished Apply Kernel Variables. vm-test-run-pgtap> server # [ 8.048052] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-pgtap> server # [ 8.071254] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-pgtap> server # [ 8.071721] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-pgtap> server # [ 8.074322] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-pgtap> server # [ 8.176916] systemd-udevd[329]: Using default interface naming scheme 'v260'. vm-test-run-pgtap> server # [ 8.346057] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-pgtap> server # [ 8.430497] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse vm-test-run-pgtap> server # [ 8.436299] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs vm-test-run-pgtap> server # [ 8.448301] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse vm-test-run-pgtap> server # [ 8.470411] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. vm-test-run-pgtap> server # [ 8.499124] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. vm-test-run-pgtap> server # [ 8.541747] systemd[1]: Mounting /run/wrappers... vm-test-run-pgtap> server # [ 8.546879] systemd[1]: Mounting FUSE Control File System... vm-test-run-pgtap> server # [ 8.589915] (udev-worker)[360]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-pgtap> server # [ 8.595621] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgtap> server # [ 8.597348] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgtap> server # [ 8.602259] systemd[1]: Mounted /run/wrappers. vm-test-run-pgtap> server # [ 8.603509] systemd[1]: Mounted FUSE Control File System. vm-test-run-pgtap> server # [ 8.604199] systemd[1]: Reached target Local File Systems. vm-test-run-pgtap> server # [ 8.607936] systemd[1]: Listening on Boot Loader Control Service Socket. vm-test-run-pgtap> server # [ 8.610475] systemd[1]: Starting register-nix-paths.service... vm-test-run-pgtap> server # [ 8.616523] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-pgtap> server # [ 8.617035] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-pgtap> server # [ 8.620494] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-pgtap> server # [ 8.635385] systemd[1]: Starting Create System Files and Directories... vm-test-run-pgtap> server # [ 8.646915] mousedev: PS/2 mouse device common for all mice vm-test-run-pgtap> server # [ 8.687748] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-pgtap> server # [ 8.693637] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-pgtap> server # [ 8.717499] systemd[1]: Condition check resulted in Virtio network device being skipped. vm-test-run-pgtap> server # [ 8.720655] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-pgtap> server # [ 8.724146] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-pgtap> server # [ 8.725499] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 vm-test-run-pgtap> server # [ 8.730638] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-pgtap> server # [ 8.735222] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 8.740633] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 8.771522] systemd[1]: Finished Create System Files and Directories. vm-test-run-pgtap> server # [ 8.776442] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-pgtap> server # [ 8.782392] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-pgtap> server # [ 8.869631] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-pgtap> server # [ 9.017743] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-pgtap> server # [ 9.061932] systemd[1]: Starting Update is Completed... vm-test-run-pgtap> server # [ 9.075820] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 vm-test-run-pgtap> server # [ 9.126069] systemd[1]: Starting Virtual Console Setup... vm-test-run-pgtap> server # [ 9.148616] systemd[1]: Finished Update is Completed. vm-test-run-pgtap> server # [ 9.484165] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-pgtap> server # [ 9.485723] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-pgtap> server # [ 9.658476] systemd[1]: Finished register-nix-paths.service. vm-test-run-pgtap> server # [ 9.662729] systemd[1]: Finished Firewall. vm-test-run-pgtap> server # [ 10.004457] systemd-vconsole-setup[471]: Configuration of first virtual console was skipped, ignoring remaining ones. vm-test-run-pgtap> server # [ 10.006859] systemd[1]: Finished Virtual Console Setup. vm-test-run-pgtap> server # [ 10.007226] systemd[1]: Reached target System Initialization. vm-test-run-pgtap> server # [ 10.007549] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-pgtap> server # [ 10.008440] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-pgtap> server # [ 10.008903] systemd[1]: Reached target Timer Units. vm-test-run-pgtap> server # [ 10.009448] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-pgtap> server # [ 10.010112] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-pgtap> server # [ 10.011745] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). vm-test-run-pgtap> server # [ 10.012374] systemd[1]: Listening on Hostname Service Socket. vm-test-run-pgtap> server # [ 10.012937] systemd[1]: Reached target Socket Units. vm-test-run-pgtap> server # [ 10.013489] systemd[1]: Reached target Basic System. vm-test-run-pgtap> server # [ 10.014612] systemd[1]: Started backdoor.service. vm-test-run-pgtap> server # [ 10.017126] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-pgtap> server # [ 10.020339] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pgtap> server # [ 10.024060] systemd[1]: Starting Post-Boot Actions... vm-test-run-pgtap> server # [ 10.068263] systemd[1]: Started Reset console on configuration changes. vm-test-run-pgtap> server # [ 10.071445] systemd[1]: Starting resolvconf update... vm-test-run-pgtap> server # [ 10.075778] systemd[1]: Starting SSH Host Keys Generation... vm-test-run-pgtap> server # [ 10.090748] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-pgtap> server # [ 10.131781] systemd[1]: Finished Post-Boot Actions. vm-test-run-pgtap> server # [ 10.157095] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pgtap> server # [ 10.159822] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pgtap> server # connecting to host... vm-test-run-pgtap> server # [ 10.161884] nsncd[546]: Oct 05 11:01:14.432 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pgtap> server # [ 10.165068] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pgtap> server # [ 10.166637] systemd[1]: Starting User Login Management... vm-test-run-pgtap> server # [ 10.196316] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-pgtap> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-pgtap> server: connected to guest root shell vm-test-run-pgtap> server: (connecting took 10.34 seconds) vm-test-run-pgtap> server: (finished: waiting for the VM to finish booting, in 10.34 seconds) vm-test-run-pgtap> server # [ 10.267034] systemd-logind[572]: New seat seat0. vm-test-run-pgtap> server # [ 10.271643] systemd-logind[572]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) vm-test-run-pgtap> server # [ 10.272466] systemd-logind[572]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-pgtap> server # [ 10.272965] systemd-logind[572]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-pgtap> server # [ 10.277070] systemd[1]: Started User Login Management. vm-test-run-pgtap> server # [ 10.282253] systemd[1]: Starting linger-users.service... vm-test-run-pgtap> server # [ 10.285142] dbus-broker-launch[552]: Looking up NSS user entry for 'systemd-timesync'... vm-test-run-pgtap> server # [ 10.300954] dbus-broker-launch[552]: NSS returned no entry for 'systemd-timesync' vm-test-run-pgtap> server # [ 10.301641] dbus-broker-launch[552]: Invalid user-name in /nix/store/izpx6jikmg1inp319z5am7n6xksnf7l2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" vm-test-run-pgtap> server # [ 10.382010] dbus-broker-launch[552]: Ready vm-test-run-pgtap> server # [ 10.401386] systemd[1]: Started D-Bus System Message Bus. vm-test-run-pgtap> server # [ 10.401799] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-pgtap> server # [ 10.403444] systemd[1]: Finished linger-users.service. vm-test-run-pgtap> server # [ 10.411653] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-pgtap> server # [ 10.413570] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-pgtap> server # [ 10.414204] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-pgtap> server # [ 10.414468] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-pgtap> server # [ 10.414686] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-pgtap> server # [ 10.417127] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-pgtap> server # [ 10.418481] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-pgtap> server # [ 10.423834] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pgtap> server # [ 10.489226] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pgtap> server # [ 10.490853] nsncd[633]: Oct 05 11:01:14.763 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pgtap> server # [ 10.493039] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pgtap> server # [ 10.493618] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pgtap> server # [ 10.496519] sshd-keygen-start[587]: Generating public/private rsa key pair. vm-test-run-pgtap> server # [ 10.497195] sshd-keygen-start[587]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key vm-test-run-pgtap> server # [ 10.497816] sshd-keygen-start[587]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub vm-test-run-pgtap> server # [ 10.498411] sshd-keygen-start[587]: The key fingerprint is: vm-test-run-pgtap> server # [ 10.499090] sshd-keygen-start[587]: SHA256:yPf7ahQ1F9fB267R6J+kXDht5U84XGCoP5GUkJQ4j5c root@server vm-test-run-pgtap> server # [ 10.499980] sshd-keygen-start[587]: The key's randomart image is: vm-test-run-pgtap> server # [ 10.500902] sshd-keygen-start[587]: +---[RSA 4096]----+ vm-test-run-pgtap> server # [ 10.501387] sshd-keygen-start[587]: | ooo oo+| vm-test-run-pgtap> server # [ 10.501941] sshd-keygen-start[587]: | o o.oo.o.| vm-test-run-pgtap> server # [ 10.502670] sshd-keygen-start[587]: | + o+oo o| vm-test-run-pgtap> server # [ 10.503377] sshd-keygen-start[587]: | . .. Eo o o.| vm-test-run-pgtap> server # [ 10.504100] sshd-keygen-start[587]: | o S...o +o| vm-test-run-pgtap> server # [ 10.504570] sshd-keygen-start[587]: | . ... o=++| vm-test-run-pgtap> server # [ 10.505220] sshd-keygen-start[587]: | .. o++Bo| vm-test-run-pgtap> server # [ 10.505730] sshd-keygen-start[587]: | ....Ooo| vm-test-run-pgtap> server # [ 10.506327] sshd-keygen-start[587]: | .oo.o oo| vm-test-run-pgtap> server # [ 10.507066] sshd-keygen-start[587]: +----[SHA256]-----+ vm-test-run-pgtap> server # [ 10.535704] systemd[1]: Finished resolvconf update. vm-test-run-pgtap> server # [ 10.536261] systemd[1]: Reached target Preparation for Network. vm-test-run-pgtap> server # [ 10.539665] systemd[1]: Starting DHCP Client... vm-test-run-pgtap> server # [ 10.542877] systemd[1]: Starting Address configuration of eth1... vm-test-run-pgtap> server # [ 10.547618] systemd[1]: Starting Extra networking commands.... vm-test-run-pgtap> server # [ 10.562798] sshd-keygen-start[669]: Generating public/private ed25519 key pair. vm-test-run-pgtap> server # [ 10.563508] sshd-keygen-start[669]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key vm-test-run-pgtap> server # [ 10.565034] sshd-keygen-start[669]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub vm-test-run-pgtap> server # [ 10.566287] sshd-keygen-start[669]: The key fingerprint is: vm-test-run-pgtap> server # [ 10.567589] sshd-keygen-start[669]: SHA256:crayT3y8mSMYOrnCxBU1oaLb/lGF68F3bZjxMV9g2UU root@server vm-test-run-pgtap> server # [ 10.569140] sshd-keygen-start[669]: The key's randomart image is: vm-test-run-pgtap> server # [ 10.569443] sshd-keygen-start[669]: +--[ED25519 256]--+ vm-test-run-pgtap> server # [ 10.570081] sshd-keygen-start[669]: | .+. o+E| vm-test-run-pgtap> server # [ 10.570974] sshd-keygen-start[669]: | .. o ....| vm-test-run-pgtap> server # [ 10.571807] sshd-keygen-start[669]: | . ... . . o .| vm-test-run-pgtap> server # [ 10.572416] sshd-keygen-start[669]: | . ... o * + . | vm-test-run-pgtap> server # [ 10.573114] sshd-keygen-start[669]: |.. . * S + + . | vm-test-run-pgtap> server # [ 10.573724] sshd-keygen-start[669]: | oo o.B + . | vm-test-run-pgtap> server # [ 10.574250] sshd-keygen-start[669]: |.o. .ooo+ o | vm-test-run-pgtap> server # [ 10.575213] sshd-keygen-start[669]: | .o +..+...+ | vm-test-run-pgtap> server # [ 10.575975] sshd-keygen-start[669]: | .ooo....+. | vm-test-run-pgtap> server # [ 10.576497] sshd-keygen-start[669]: +----[SHA256]-----+ vm-test-run-pgtap> server # [ 10.577667] systemd[1]: sshd-keygen.service: Deactivated successfully. vm-test-run-pgtap> server # [ 10.578171] systemd[1]: Finished SSH Host Keys Generation. vm-test-run-pgtap> server # [ 10.668380] network-addresses-eth1-start[667]: adding address 192.168.1.1/24... done vm-test-run-pgtap> server # [ 10.682485] dhcpcd[676]: dhcpcd-10.3.1 starting vm-test-run-pgtap> server # [ 10.684975] network-addresses-eth1-start[667]: adding address 2001:db8:1::1/64... done vm-test-run-pgtap> server # [ 10.697552] dhcpcd[710]: dev: loaded udev vm-test-run-pgtap> server # [ 10.706034] systemd[1]: Finished Address configuration of eth1. vm-test-run-pgtap> server # [ 10.742571] systemd[1]: Finished Extra networking commands.. vm-test-run-pgtap> server # [ 10.744914] systemd[1]: Reached target Network. vm-test-run-pgtap> server # [ 10.750551] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-pgtap> server # [ 10.750767] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-pgtap> server # [ 10.748845] systemd[1]: Starting PostgreSQL Server... vm-test-run-pgtap> server # [ 10.752513] systemd[1]: Starting SSH Daemon... vm-test-run-pgtap> server # [ 10.756555] systemd[1]: Starting Permit User Sessions... vm-test-run-pgtap> server # [ 10.792985] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. vm-test-run-pgtap> server # [ 10.813623] systemd[1]: Finished Permit User Sessions. vm-test-run-pgtap> server # [ 10.818519] systemd[1]: Started Getty on tty1. vm-test-run-pgtap> server # [ 10.819109] systemd[1]: Reached target Login Prompts. vm-test-run-pgtap> server # [ 10.831429] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-pgtap> server # [ 10.845683] sshd[738]: Server listening on 0.0.0.0 port 22. vm-test-run-pgtap> server # [ 10.846238] sshd[738]: Server listening on :: port 22. vm-test-run-pgtap> server # [ 10.846657] systemd[1]: Started SSH Daemon. vm-test-run-pgtap> server # [ 10.879776] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-pgtap> server # [ 10.880286] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-pgtap> server # [ 10.885545] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2 vm-test-run-pgtap> server # [ 10.885862] cfg80211: failed to load regulatory.db vm-test-run-pgtap> server # [ 10.963304] dhcpcd[710]: eth0: waiting for carrier[ 10.967802] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-pgtap> server # vm-test-run-pgtap> server # [ 10.964700] dhcpcd[710]: eth0: carrier acquired vm-test-run-pgtap> server # [ 10.996335] dhcpcd[710]: DUID 00:01:00:01:32:56:3f:fb:52:54:00:12:34:56 vm-test-run-pgtap> server # [ 10.997906] dhcpcd[710]: eth0: IAID 00:12:34:56 vm-test-run-pgtap> server # [ 10.999370] dhcpcd[710]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-pgtap> server # [ 11.245002] postgresql-pre-start[749]: The files belonging to this database system will be owned by user "postgres". vm-test-run-pgtap> server # [ 11.246099] postgresql-pre-start[749]: This user must also own the server process. vm-test-run-pgtap> server # [ 11.260361] postgresql-pre-start[749]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-pgtap> server # [ 11.261099] postgresql-pre-start[749]: The default database encoding has accordingly been set to "UTF8". vm-test-run-pgtap> server # [ 11.262088] postgresql-pre-start[749]: The default text search configuration will be set to "english". vm-test-run-pgtap> server # [ 11.262961] postgresql-pre-start[749]: Data page checksums are disabled. vm-test-run-pgtap> server # [ 11.264162] postgresql-pre-start[749]: fixing permissions on existing directory /var/lib/postgresql/15 ... ok vm-test-run-pgtap> server # [ 11.265306] postgresql-pre-start[749]: creating subdirectories ... ok vm-test-run-pgtap> server # [ 11.265970] postgresql-pre-start[749]: selecting dynamic shared memory implementation ... posix vm-test-run-pgtap> server # [ 11.422086] postgresql-pre-start[749]: selecting default max_connections ... 100 vm-test-run-pgtap> server # [ 11.495568] postgresql-pre-start[749]: selecting default shared_buffers ... 128MB vm-test-run-pgtap> server # [ 12.376375] dhcpcd[710]: eth0: soliciting a DHCP lease vm-test-run-pgtap> server # [ 12.384574] dhcpcd[710]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-pgtap> server # [ 12.396395] dhcpcd[710]: eth0: probing address 10.0.2.15/24 vm-test-run-pgtap> server # [ 12.862299] dhcpcd[710]: eth0: soliciting an IPv6 router vm-test-run-pgtap> server # [ 12.862807] dhcpcd[710]: eth0: Router Advertisement from fe80::2 vm-test-run-pgtap> server # [ 12.863535] dhcpcd[710]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-pgtap> server # [ 12.864265] dhcpcd[710]: eth0: adding route to fec0::/64 vm-test-run-pgtap> server # [ 12.864862] dhcpcd[710]: eth0: adding default route via fe80::2 vm-test-run-pgtap> server # [ 13.150589] postgresql-pre-start[749]: selecting default time zone ... UTC vm-test-run-pgtap> server # [ 13.155643] postgresql-pre-start[749]: creating configuration files ... ok vm-test-run-pgtap> server # [ 13.330456] postgresql-pre-start[749]: running bootstrap script ... ok vm-test-run-pgtap> server # [ 13.724578] postgresql-pre-start[749]: performing post-bootstrap initialization ... ok vm-test-run-pgtap> server # [ 13.889770] postgresql-pre-start[749]: syncing data to disk ... ok vm-test-run-pgtap> server # [ 13.891173] postgresql-pre-start[749]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-pgtap> server # [ 13.892349] postgresql-pre-start[749]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb. vm-test-run-pgtap> server # [ 13.893246] postgresql-pre-start[749]: Success. You can now start the database server using: vm-test-run-pgtap> server # [ 13.895238] postgresql-pre-start[749]: /nix/store/9yrimycrlm3q2a0yc9hv4q2bm3ff9mib-postgresql-15-pgtap/bin/pg_ctl -D /var/lib/postgresql/15 -l logfile start vm-test-run-pgtap> server # [ 14.022149] postgres[798]: [798] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pgtap> server # [ 14.023909] postgres[798]: [798] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-pgtap> server # [ 14.024679] postgres[798]: [798] LOG: listening on IPv6 address "::", port 5432 vm-test-run-pgtap> server # [ 14.026036] postgres[798]: [798] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pgtap> server # [ 14.032685] postgres[804]: [804] LOG: database system was shut down at 2026-10-05 11:01:17 GMT vm-test-run-pgtap> server # [ 14.035748] postgres[798]: [798] LOG: database system is ready to accept connections vm-test-run-pgtap> server # [ 14.040934] systemd[1]: Started PostgreSQL Server. vm-test-run-pgtap> server # [ 14.046537] systemd[1]: Starting PostgreSQL Setup Scripts... vm-test-run-pgtap> server # [ 14.246883] postgresql-setup-start[818]: CREATE ROLE vm-test-run-pgtap> server # [ 14.273010] postgresql-setup-start[823]: ALTER ROLE vm-test-run-pgtap> server # [ 14.276681] systemd[1]: Finished PostgreSQL Setup Scripts. vm-test-run-pgtap> server # [ 14.277293] systemd[1]: Reached target PostgreSQL. vm-test-run-pgtap> server # [ 17.059670] dhcpcd[710]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-pgtap> server # [ 17.059825] dhcpcd[710]: eth0: adding route to 10.0.2.0/24 vm-test-run-pgtap> server # [ 17.059975] dhcpcd[710]: eth0: adding default route via 10.0.2.2 vm-test-run-pgtap> server # [ 17.175750] systemd[1]: Started DHCP Client. vm-test-run-pgtap> server # [ 17.176126] systemd[1]: Reached target Multi-User System. vm-test-run-pgtap> server # [ 17.176581] systemd[1]: Startup finished in 253ms (kernel) + 4.482s (initrd) + 12.440s (userspace) = 17.176s. vm-test-run-pgtap> server: (finished: waiting for unit multi-user.target, in 17.62 seconds) vm-test-run-pgtap> server: waiting for unit postgresql.service vm-test-run-pgtap> server: (finished: waiting for unit postgresql.service, in 0.14 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" vm-test-run-pgtap> server # NOTICE: schema "public" already exists, skipping vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.06 seconds) vm-test-run-pgtap> subtest: Check upgrade path with postgresql 15 vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" vm-test-run-pgtap> server # NOTICE: extension "pgtap" does not exist, skipping vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.05 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.2.0' CASCADE;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.2.0' CASCADE;" , in 0.11 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.06 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.1';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.1';" , in 0.10 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.05 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" , in 0.08 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.06 seconds) vm-test-run-pgtap> (finished: subtest: Check upgrade path with postgresql 15, in 0.51 seconds) vm-test-run-pgtap> subtest: Check pg_regress with postgresql 15 after extension upgrade vm-test-run-pgtap> server: must succeed: sudo -u postgres /nix/store/9yrimycrlm3q2a0yc9hv4q2bm3ff9mib-postgresql-15-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 "pgtap" vm-test-run-pgtap> server # [ 18.335609] sudo[940]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/9yrimycrlm3q2a0yc9hv4q2bm3ff9mib-postgresql-15-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 pgtap vm-test-run-pgtap> server # [ 18.354866] sudo[940]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0) vm-test-run-pgtap> server # pg_regress: could not set core size: disallowed by hard limit vm-test-run-pgtap> server # [ 18.462603] sudo[940]: pam_unix(sudo:session): session closed for user postgres vm-test-run-pgtap> server: (finished: must succeed: sudo -u postgres /nix/store/9yrimycrlm3q2a0yc9hv4q2bm3ff9mib-postgresql-15-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_15 "pgtap" , in 0.27 seconds) vm-test-run-pgtap> (using postmaster on Unix socket, default port) vm-test-run-pgtap> ============== running regression test queries ============== vm-test-run-pgtap> test pgtap ... ok 59 ms vm-test-run-pgtap> vm-test-run-pgtap> ===================== vm-test-run-pgtap> All 1 tests passed. vm-test-run-pgtap> ===================== vm-test-run-pgtap> vm-test-run-pgtap> vm-test-run-pgtap> (finished: subtest: Check pg_regress with postgresql 15 after extension upgrade, in 0.27 seconds) vm-test-run-pgtap> subtest: Check the install of the last version of the extension vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.07 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.3' CASCADE;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.3' CASCADE;" , in 0.09 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.05 seconds) vm-test-run-pgtap> (finished: subtest: Check the install of the last version of the extension, in 0.22 seconds) vm-test-run-pgtap> subtest: switch to postgresql 17 vm-test-run-pgtap> server # Checking switch inhibitors... done vm-test-run-pgtap> server # stopping the following units: postgresql-setup.service, postgresql.service vm-test-run-pgtap> server # [ 19.873841] nixos[966]: switching to system configuration /nix/store/m8zh4r4y5qw00p2ny2srqpihw6q3caqp-nixos-system-server-test vm-test-run-pgtap> server # [ 19.875403] systemd[1]: Stopped target Local File Systems. vm-test-run-pgtap> server # [ 19.876748] systemd[1]: Stopped target Remote File Systems. vm-test-run-pgtap> server # [ 19.878717] systemd[1]: Stopped target PostgreSQL. vm-test-run-pgtap> server # [ 19.879428] postgres[798]: [798] LOG: received fast shutdown request vm-test-run-pgtap> server # [ 19.880341] systemd[1]: postgresql-setup.service: Deactivated successfully. vm-test-run-pgtap> server # [ 19.881017] systemd[1]: Stopped PostgreSQL Setup Scripts. vm-test-run-pgtap> server # [ 19.881848] systemd[1]: Stopping PostgreSQL Server... vm-test-run-pgtap> server # [ 19.882035] postgres[798]: [798] LOG: aborting any active transactions vm-test-run-pgtap> server # [ 19.882839] postgres[798]: [798] LOG: background worker "logical replication launcher" (PID 807) exited with exit code 1 vm-test-run-pgtap> server # [ 19.884337] postgres[802]: [802] LOG: shutting down vm-test-run-pgtap> server # [ 19.885656] postgres[802]: [802] LOG: checkpoint starting: shutdown immediate vm-test-run-pgtap> server # [ 19.909389] postgres[802]: [802] LOG: checkpoint complete: wrote 389 buffers (2.4%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.008 s, sync=0.013 s, total=0.026 s; sync files=65, longest=0.005 s, average=0.001 s; distance=4297 kB, estimate=4297 kB vm-test-run-pgtap> server # [ 19.911502] postgres[798]: [798] LOG: database system is shut down vm-test-run-pgtap> server # [ 19.916528] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-pgtap> server # [ 19.916822] systemd[1]: Stopped PostgreSQL Server. vm-test-run-pgtap> server # [ 19.917076] systemd[1]: postgresql.service: Consumed 800ms CPU time over 9.167s wall clock time, 105.3M memory peak, 45.4M written to disk. vm-test-run-pgtap> server # activating the configuration... vm-test-run-pgtap> server # setting up /etc... vm-test-run-pgtap> server # [ 20.742449] systemd[1]: Reload requested from client PID 966 ('.switch-to-conf') (unit backdoor.service)... vm-test-run-pgtap> server # [ 20.743854] systemd[1]: Reloading... vm-test-run-pgtap> server # [ 21.674878] systemd[1]: Reloading finished in 929 ms. vm-test-run-pgtap> server # restarting sysinit-reactivation.target vm-test-run-pgtap> server # [ 21.714608] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... vm-test-run-pgtap> server # [ 21.860268] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. vm-test-run-pgtap> server # [ 21.860979] systemd[1]: Reached target Reactivate sysinit units. vm-test-run-pgtap> server # reloading the following units: dbus-broker.service vm-test-run-pgtap> server # [ 21.862586] systemd[1]: Reloading D-Bus System Message Bus... vm-test-run-pgtap> server # [ 21.862982] dbus-broker-launch[552]: Caught SIGHUP, trigger reload. vm-test-run-pgtap> server # [ 21.896097] dbus-broker-launch[552]: Looking up NSS user entry for 'systemd-timesync'... vm-test-run-pgtap> server # [ 21.897093] dbus-broker-launch[552]: NSS returned no entry for 'systemd-timesync' vm-test-run-pgtap> server # [ 21.897669] dbus-broker-launch[552]: Invalid user-name in /nix/store/hp8dbr6xbgsyajpysp5r6aii9pha9d0d-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" vm-test-run-pgtap> server # [ 21.951541] systemd[1]: Reloaded D-Bus System Message Bus. vm-test-run-pgtap> server # starting the following units: postgresql-setup.service, postgresql.service vm-test-run-pgtap> server # [ 21.955270] systemd[1]: Reached target Local File Systems. vm-test-run-pgtap> server # [ 21.960096] systemd[1]: Starting postgresql-migrate.service... vm-test-run-pgtap> server # [ 21.967233] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-pgtap> server # [ 21.969593] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-pgtap> server # [ 21.970021] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-pgtap> server # [ 21.970470] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 vm-test-run-pgtap> server # [ 21.971637] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-pgtap> server # [ 21.973102] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 21.974397] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 21.975495] systemd[1]: Reached target Remote File Systems. vm-test-run-pgtap> server # [ 21.977627] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 22.197512] postgresql-migrate-start[1043]: The files belonging to this database system will be owned by user "postgres". vm-test-run-pgtap> server # [ 22.198251] postgresql-migrate-start[1043]: This user must also own the server process. vm-test-run-pgtap> server # [ 22.218092] postgresql-migrate-start[1043]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-pgtap> server # [ 22.218933] postgresql-migrate-start[1043]: The default database encoding has accordingly been set to "UTF8". vm-test-run-pgtap> server # [ 22.219985] postgresql-migrate-start[1043]: The default text search configuration will be set to "english". vm-test-run-pgtap> server # [ 22.220764] postgresql-migrate-start[1043]: Data page checksums are disabled. vm-test-run-pgtap> server # [ 22.221348] postgresql-migrate-start[1043]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok vm-test-run-pgtap> server # [ 22.222041] postgresql-migrate-start[1043]: creating subdirectories ... ok vm-test-run-pgtap> server # [ 22.223169] postgresql-migrate-start[1043]: selecting dynamic shared memory implementation ... posix vm-test-run-pgtap> server # [ 22.360379] postgresql-migrate-start[1043]: selecting default "max_connections" ... 100 vm-test-run-pgtap> server # [ 22.449535] postgresql-migrate-start[1043]: selecting default "shared_buffers" ... 128MB vm-test-run-pgtap> server # [ 22.649097] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-pgtap> server # [ 22.649424] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-pgtap> server # [ 22.652778] systemd[1]: Starting linger-users.service... vm-test-run-pgtap> server # [ 22.653390] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met. vm-test-run-pgtap> server # [ 22.707010] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-pgtap> server # [ 22.707535] systemd[1]: Finished linger-users.service. vm-test-run-pgtap> server # [ 24.289127] postgresql-migrate-start[1043]: selecting default time zone ... UTC vm-test-run-pgtap> server # [ 24.295274] postgresql-migrate-start[1043]: creating configuration files ... ok vm-test-run-pgtap> server # [ 24.471090] postgresql-migrate-start[1043]: running bootstrap script ... ok vm-test-run-pgtap> server # [ 24.834484] postgresql-migrate-start[1043]: performing post-bootstrap initialization ... ok vm-test-run-pgtap> server # [ 24.974822] postgresql-migrate-start[1043]: syncing data to disk ... ok vm-test-run-pgtap> server # [ 24.975753] postgresql-migrate-start[1043]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-pgtap> server # [ 24.977974] postgresql-migrate-start[1043]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb. vm-test-run-pgtap> server # [ 24.979812] postgresql-migrate-start[1043]: Success. You can now start the database server using: vm-test-run-pgtap> server # [ 24.981007] postgresql-migrate-start[1043]: /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start vm-test-run-pgtap> server # [ 25.674716] postgresql-migrate-start[1119]: Performing Consistency Checks vm-test-run-pgtap> server # [ 25.674788] postgresql-migrate-start[1119]: ----------------------------- vm-test-run-pgtap> server # [ 25.674802] postgresql-migrate-start[1119]: Checking cluster versions ok vm-test-run-pgtap> server # [ 25.965789] postgresql-migrate-start[1119]: Checking database user is the install user ok vm-test-run-pgtap> server # [ 25.968244] postgresql-migrate-start[1119]: Checking database connection settings ok vm-test-run-pgtap> server # [ 25.970354] postgresql-migrate-start[1119]: Checking for prepared transactions ok vm-test-run-pgtap> server # [ 25.970778] postgresql-migrate-start[1119]: Checking for contrib/isn with bigint-passing mismatch ok vm-test-run-pgtap> server # [ 25.995877] postgresql-migrate-start[1119]: Checking data type usage ok vm-test-run-pgtap> server # [ 26.004765] postgresql-migrate-start[1119]: Checking for not-null constraint inconsistencies ok vm-test-run-pgtap> server # [ 26.096244] postgresql-migrate-start[1119]: Creating dump of global objects ok vm-test-run-pgtap> server # [ 26.433410] postgresql-migrate-start[1119]: Creating dump of database schemas ok vm-test-run-pgtap> server # [ 26.804549] postgresql-migrate-start[1119]: Checking for presence of required libraries ok vm-test-run-pgtap> server # [ 26.806757] postgresql-migrate-start[1119]: Checking database user is the install user ok vm-test-run-pgtap> server # [ 26.809331] postgresql-migrate-start[1119]: Checking for prepared transactions ok vm-test-run-pgtap> server # [ 26.810034] postgresql-migrate-start[1119]: Checking for new cluster tablespace directories ok vm-test-run-pgtap> server # [ 26.810490] postgresql-migrate-start[1119]: If pg_upgrade fails after this point, you must re-initdb the vm-test-run-pgtap> server # [ 26.810888] postgresql-migrate-start[1119]: new cluster before continuing. vm-test-run-pgtap> server # [ 26.811145] postgresql-migrate-start[1119]: Performing Upgrade vm-test-run-pgtap> server # [ 26.811403] postgresql-migrate-start[1119]: ------------------ vm-test-run-pgtap> server # [ 26.815213] postgresql-migrate-start[1119]: Setting locale and encoding for new cluster ok vm-test-run-pgtap> server # [ 26.958883] postgresql-migrate-start[1119]: Analyzing all rows in the new cluster ok vm-test-run-pgtap> server # [ 27.035662] postgresql-migrate-start[1119]: Freezing all rows in the new cluster ok vm-test-run-pgtap> server # [ 27.168079] postgresql-migrate-start[1119]: Deleting files from new pg_xact ok vm-test-run-pgtap> server # [ 27.192516] postgresql-migrate-start[1119]: Copying old pg_xact to new server ok vm-test-run-pgtap> server # [ 27.226004] postgresql-migrate-start[1119]: Setting oldest XID for new cluster ok vm-test-run-pgtap> server # [ 27.318420] postgresql-migrate-start[1119]: Setting next transaction ID and epoch for new cluster ok vm-test-run-pgtap> server # [ 27.319807] postgresql-migrate-start[1119]: Deleting files from new pg_multixact/offsets ok vm-test-run-pgtap> server # [ 27.343246] postgresql-migrate-start[1119]: Copying old pg_multixact/offsets to new server ok vm-test-run-pgtap> server # [ 27.344434] postgresql-migrate-start[1119]: Deleting files from new pg_multixact/members ok vm-test-run-pgtap> server # [ 27.372164] postgresql-migrate-start[1119]: Copying old pg_multixact/members to new server ok vm-test-run-pgtap> server # [ 27.406561] postgresql-migrate-start[1119]: Setting next multixact ID and offset for new cluster ok vm-test-run-pgtap> server # [ 27.440318] postgresql-migrate-start[1119]: Resetting WAL archives ok vm-test-run-pgtap> server # [ 27.659022] postgresql-migrate-start[1119]: Setting frozenxid and minmxid counters in new cluster ok vm-test-run-pgtap> server # [ 27.713248] postgresql-migrate-start[1119]: Restoring global objects in the new cluster ok vm-test-run-pgtap> server # [ 28.189719] postgresql-migrate-start[1119]: Restoring database schemas in the new cluster ok vm-test-run-pgtap> server # [ 28.336293] postgresql-migrate-start[1119]: Copying user relation files ok vm-test-run-pgtap> server # [ 28.368720] postgresql-migrate-start[1119]: Setting next OID for new cluster ok vm-test-run-pgtap> server # [ 28.543178] postgresql-migrate-start[1119]: Sync data directory to disk ok vm-test-run-pgtap> server # [ 28.544811] postgresql-migrate-start[1119]: Creating script to delete old cluster ok vm-test-run-pgtap> server # [ 28.980586] postgresql-migrate-start[1119]: Checking for extension updates ok vm-test-run-pgtap> server # [ 29.114679] postgresql-migrate-start[1119]: Upgrade Complete vm-test-run-pgtap> server # [ 29.115033] postgresql-migrate-start[1119]: ---------------- vm-test-run-pgtap> server # [ 29.115702] postgresql-migrate-start[1119]: Optimizer statistics are not transferred by pg_upgrade. vm-test-run-pgtap> server # [ 29.116581] postgresql-migrate-start[1119]: Once you start the new server, consider running: vm-test-run-pgtap> server # [ 29.117071] postgresql-migrate-start[1119]: /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/bin/vacuumdb --all --analyze-in-stages vm-test-run-pgtap> server # [ 29.118093] postgresql-migrate-start[1119]: Running this script will delete the old cluster's data files: vm-test-run-pgtap> server # [ 29.119147] postgresql-migrate-start[1119]: ./delete_old_cluster.sh vm-test-run-pgtap> server # [ 29.124191] systemd[1]: Finished postgresql-migrate.service. vm-test-run-pgtap> server # [ 29.126732] systemd[1]: Starting PostgreSQL Server... vm-test-run-pgtap> server # [ 29.367023] postgres[1280]: [1280] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pgtap> server # [ 29.369392] postgres[1280]: [1280] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-pgtap> server # [ 29.370432] postgres[1280]: [1280] LOG: listening on IPv6 address "::", port 5432 vm-test-run-pgtap> server # [ 29.371542] postgres[1280]: [1280] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pgtap> server # [ 29.376196] postgres[1286]: [1286] LOG: database system was shut down at 2026-10-05 11:01:33 GMT vm-test-run-pgtap> server # [ 29.379127] postgres[1280]: [1280] LOG: database system is ready to accept connections vm-test-run-pgtap> server # [ 29.381352] systemd[1]: Started PostgreSQL Server. vm-test-run-pgtap> server # [ 29.384409] systemd[1]: Starting PostgreSQL Setup Scripts... vm-test-run-pgtap> server # [ 29.516538] postgresql-setup-start[1296]: ALTER ROLE vm-test-run-pgtap> server # [ 29.520384] systemd[1]: Finished PostgreSQL Setup Scripts. vm-test-run-pgtap> server # [ 29.520878] systemd[1]: Reached target PostgreSQL. vm-test-run-pgtap> server # the following new units were started: postgresql-migrate.service, sysinit-reactivation.target, systemd-tmpfiles-resetup.service vm-test-run-pgtap> server # [ 29.797764] nixos[966]: finished switching to system configuration /nix/store/m8zh4r4y5qw00p2ny2srqpihw6q3caqp-nixos-system-server-test vm-test-run-pgtap> server: waiting for unit postgresql.service vm-test-run-pgtap> server: (finished: waiting for unit postgresql.service, in 0.13 seconds) vm-test-run-pgtap> server: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no' vm-test-run-pgtap> server: (finished: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no', in 0.02 seconds) vm-test-run-pgtap> (finished: subtest: switch to postgresql 17, in 11.28 seconds) vm-test-run-pgtap> subtest: Check last version of the extension after postgresql upgrade vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.05 seconds) vm-test-run-pgtap> (finished: subtest: Check last version of the extension after postgresql upgrade, in 0.05 seconds) vm-test-run-pgtap> subtest: Check upgrade path with postgresql 17 vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.07 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.1' CASCADE;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.1' CASCADE;" , in 0.10 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.05 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" , in 0.08 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.05 seconds) vm-test-run-pgtap> (finished: subtest: Check upgrade path with postgresql 17, in 0.36 seconds) vm-test-run-pgtap> subtest: Check pg_regress with postgresql 17 after extension upgrade vm-test-run-pgtap> server: must succeed: sudo -u postgres /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pgtap" vm-test-run-pgtap> server # [ 30.432248] sudo[1330]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 pgtap vm-test-run-pgtap> server # [ 30.449448] sudo[1330]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0) vm-test-run-pgtap> server # # could not set core size: disallowed by hard limit vm-test-run-pgtap> server # [ 30.549895] sudo[1330]: pam_unix(sudo:session): session closed for user postgres vm-test-run-pgtap> server: (finished: must succeed: sudo -u postgres /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pgtap" , in 0.18 seconds) vm-test-run-pgtap> # using postmaster on Unix socket, default port vm-test-run-pgtap> ok 1 - pgtap 51 ms vm-test-run-pgtap> 1..1 vm-test-run-pgtap> # All 1 tests passed. vm-test-run-pgtap> vm-test-run-pgtap> (finished: subtest: Check pg_regress with postgresql 17 after extension upgrade, in 0.18 seconds) vm-test-run-pgtap> subtest: Check the install of the last version of the extension vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.06 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.3' CASCADE;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.3' CASCADE;" , in 0.11 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.08 seconds) vm-test-run-pgtap> (finished: subtest: Check the install of the last version of the extension, in 0.25 seconds) vm-test-run-pgtap> subtest: Check pg_regress with postgresql 17 after installing the last version vm-test-run-pgtap> server: must succeed: sudo -u postgres /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pgtap" vm-test-run-pgtap> server # [ 30.860559] sudo[1353]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 pgtap vm-test-run-pgtap> server # [ 30.877710] sudo[1353]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0) vm-test-run-pgtap> server # # could not set core size: disallowed by hard limit vm-test-run-pgtap> server # [ 30.985881] sudo[1353]: pam_unix(sudo:session): session closed for user postgres vm-test-run-pgtap> server: (finished: must succeed: sudo -u postgres /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_17 "pgtap" , in 0.18 seconds) vm-test-run-pgtap> # using postmaster on Unix socket, default port vm-test-run-pgtap> ok 1 - pgtap 61 ms vm-test-run-pgtap> 1..1 vm-test-run-pgtap> # All 1 tests passed. vm-test-run-pgtap> vm-test-run-pgtap> (finished: subtest: Check pg_regress with postgresql 17 after installing the last version, in 0.18 seconds) vm-test-run-pgtap> subtest: switch to orioledb 17 vm-test-run-pgtap> server # Checking switch inhibitors... done vm-test-run-pgtap> server # stopping the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service vm-test-run-pgtap> server # [ 32.102904] nixos[1367]: switching to system configuration /nix/store/h2g4i7nzbh1z0jp3jhbvkb3aw9jl17a8-nixos-system-server-test vm-test-run-pgtap> server # [ 32.104962] systemd[1]: Stopped target PostgreSQL. vm-test-run-pgtap> server # [ 32.105297] systemd[1]: postgresql-setup.service: Deactivated successfully. vm-test-run-pgtap> server # [ 32.105593] systemd[1]: Stopped PostgreSQL Setup Scripts. vm-test-run-pgtap> server # [ 32.105856] systemd[1]: Stopping PostgreSQL Server... vm-test-run-pgtap> server # [ 32.106686] postgres[1280]: [1280] LOG: received fast shutdown request vm-test-run-pgtap> server # [ 32.109279] systemd[1]: Stopped target Remote File Systems. vm-test-run-pgtap> server # [ 32.109532] postgres[1280]: [1280] LOG: aborting any active transactions vm-test-run-pgtap> server # [ 32.112097] systemd[1]: Stopped target Local File Systems. vm-test-run-pgtap> server # [ 32.112623] postgres[1280]: [1280] LOG: background worker "logical replication launcher" (PID 1289) exited with exit code 1 vm-test-run-pgtap> server # [ 32.113393] postgres[1284]: [1284] LOG: shutting down vm-test-run-pgtap> server # [ 32.114193] postgres[1284]: [1284] LOG: checkpoint starting: shutdown immediate vm-test-run-pgtap> server # [ 32.138248] postgres[1284]: [1284] LOG: checkpoint complete: wrote 485 buffers (3.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.008 s, sync=0.011 s, total=0.026 s; sync files=53, longest=0.005 s, average=0.001 s; distance=5223 kB, estimate=5223 kB; lsn=0/8519DF0, redo lsn=0/8519DF0 vm-test-run-pgtap> server # [ 32.140055] postgres[1280]: [1280] LOG: database system is shut down vm-test-run-pgtap> server # [ 32.145702] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-pgtap> server # [ 32.146072] systemd[1]: Stopped PostgreSQL Server. vm-test-run-pgtap> server # [ 32.147386] systemd[1]: postgresql-migrate.service: Deactivated successfully. vm-test-run-pgtap> server # [ 32.147822] systemd[1]: Stopped postgresql-migrate.service. vm-test-run-pgtap> server # [ 32.148423] systemd[1]: postgresql-migrate.service: Consumed 1.758s CPU time over 10.187s wall clock time, 104M memory peak, 168.2M written to disk. vm-test-run-pgtap> server # activating the configuration... vm-test-run-pgtap> server # setting up /etc... vm-test-run-pgtap> server # [ 32.849847] systemd[1]: Reload requested from client PID 1367 ('.switch-to-conf') (unit backdoor.service)... vm-test-run-pgtap> server # [ 32.850689] systemd[1]: Reloading... vm-test-run-pgtap> server # [ 33.728953] systemd[1]: Reloading finished in 875 ms. vm-test-run-pgtap> server # restarting sysinit-reactivation.target vm-test-run-pgtap> server # [ 33.760723] systemd[1]: Stopped target Reactivate sysinit units. vm-test-run-pgtap> server # [ 33.761095] systemd[1]: Stopping Reactivate sysinit units... vm-test-run-pgtap> server # [ 33.761384] systemd[1]: Reached target Reactivate sysinit units. vm-test-run-pgtap> server # reloading the following units: dbus-broker.service vm-test-run-pgtap> server # [ 33.762848] systemd[1]: Reloading D-Bus System Message Bus... vm-test-run-pgtap> server # [ 33.763893] dbus-broker-launch[552]: Caught SIGHUP, trigger reload. vm-test-run-pgtap> server # [ 33.800633] dbus-broker-launch[552]: Looking up NSS user entry for 'systemd-timesync'... vm-test-run-pgtap> server # [ 33.801822] dbus-broker-launch[552]: NSS returned no entry for 'systemd-timesync' vm-test-run-pgtap> server # [ 33.802503] dbus-broker-launch[552]: Invalid user-name in /nix/store/w32jay92a5gpmh3grlrdfkxi30vkv8kc-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" vm-test-run-pgtap> server # [ 33.858007] systemd[1]: Reloaded D-Bus System Message Bus. vm-test-run-pgtap> server # starting the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service vm-test-run-pgtap> server # [ 33.861176] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 33.866170] systemd[1]: Starting postgresql-migrate.service... vm-test-run-pgtap> server # [ 33.871583] systemd[1]: Reached target Remote File Systems. vm-test-run-pgtap> server # [ 33.875927] systemd[1]: Starting linger-users.service... vm-test-run-pgtap> server # [ 33.876400] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met. vm-test-run-pgtap> server # [ 33.886919] systemd[1]: Reached target Local File Systems. vm-test-run-pgtap> server # [ 33.889305] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore vm-test-run-pgtap> server # [ 33.892968] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-pgtap> server # [ 33.893622] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. vm-test-run-pgtap> server # [ 33.894250] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 vm-test-run-pgtap> server # [ 33.895526] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore vm-test-run-pgtap> server # [ 33.897544] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 33.898774] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki vm-test-run-pgtap> server # [ 33.961245] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-pgtap> server # [ 33.962044] systemd[1]: Finished linger-users.service. vm-test-run-pgtap> server # [ 33.971280] systemd[1]: Finished postgresql-migrate.service. vm-test-run-pgtap> server # [ 34.521536] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-pgtap> server # [ 34.521957] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-pgtap> server # [ 34.525279] systemd[1]: Starting PostgreSQL Server... vm-test-run-pgtap> server # [ 34.744796] postgresql-pre-start[1513]: The files belonging to this database system will be owned by user "postgres". vm-test-run-pgtap> server # [ 34.745360] postgresql-pre-start[1513]: This user must also own the server process. vm-test-run-pgtap> server # [ 34.761857] postgresql-pre-start[1513]: Using language tag "en-US" for ICU locale "en_US.UTF-8". vm-test-run-pgtap> server # [ 34.762361] postgresql-pre-start[1513]: The database cluster will be initialized with this locale configuration: vm-test-run-pgtap> server # [ 34.763341] postgresql-pre-start[1513]: locale provider: icu vm-test-run-pgtap> server # [ 34.764342] postgresql-pre-start[1513]: default collation: en-US vm-test-run-pgtap> server # [ 34.764890] postgresql-pre-start[1513]: LC_COLLATE: en_US.UTF-8 vm-test-run-pgtap> server # [ 34.765353] postgresql-pre-start[1513]: LC_CTYPE: en_US.UTF-8 vm-test-run-pgtap> server # [ 34.765934] postgresql-pre-start[1513]: LC_MESSAGES: en_US.UTF-8 vm-test-run-pgtap> server # [ 34.766714] postgresql-pre-start[1513]: LC_MONETARY: en_US.UTF-8 vm-test-run-pgtap> server # [ 34.767518] postgresql-pre-start[1513]: LC_NUMERIC: en_US.UTF-8 vm-test-run-pgtap> server # [ 34.768255] postgresql-pre-start[1513]: LC_TIME: en_US.UTF-8 vm-test-run-pgtap> server # [ 34.768729] postgresql-pre-start[1513]: The default text search configuration will be set to "english". vm-test-run-pgtap> server # [ 34.769345] postgresql-pre-start[1513]: Data page checksums are disabled. vm-test-run-pgtap> server # [ 34.769937] postgresql-pre-start[1513]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok vm-test-run-pgtap> server # [ 34.771038] postgresql-pre-start[1513]: creating subdirectories ... ok vm-test-run-pgtap> server # [ 34.771790] postgresql-pre-start[1513]: selecting dynamic shared memory implementation ... posix vm-test-run-pgtap> server # [ 34.870808] postgresql-pre-start[1513]: selecting default "max_connections" ... 100 vm-test-run-pgtap> server # [ 34.935011] postgresql-pre-start[1513]: selecting default "shared_buffers" ... 128MB vm-test-run-pgtap> server # [ 36.660269] postgresql-pre-start[1513]: selecting default time zone ... UTC vm-test-run-pgtap> server # [ 36.665876] postgresql-pre-start[1513]: creating configuration files ... ok vm-test-run-pgtap> server # [ 36.826450] postgresql-pre-start[1513]: running bootstrap script ... ok vm-test-run-pgtap> server # [ 37.165555] postgresql-pre-start[1513]: performing post-bootstrap initialization ... ok vm-test-run-pgtap> server # [ 37.307579] postgresql-pre-start[1513]: syncing data to disk ... ok vm-test-run-pgtap> server # [ 37.307919] postgresql-pre-start[1513]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-pgtap> server # [ 37.308940] postgresql-pre-start[1513]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb. vm-test-run-pgtap> server # [ 37.309767] postgresql-pre-start[1513]: Success. You can now start the database server using: vm-test-run-pgtap> server # [ 37.310460] postgresql-pre-start[1513]: /nix/store/h2l361nalwd77g43mlzfssfhfhsa1vnd-postgresql-orioledb-17-pgtap/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start vm-test-run-pgtap> server # [ 37.540269] postgres[1526]: [1526] LOG: registered custom resource manager "OrioleDB resource manager" with ID 129 vm-test-run-pgtap> server # [ 37.560156] postgres[1526]: [1526] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pgtap> server # [ 37.575650] postgres[1526]: [1526] LOG: listening on IPv6 address "::1", port 5432 vm-test-run-pgtap> server # [ 37.576883] postgres[1526]: [1526] LOG: listening on IPv4 address "127.0.0.1", port 5432 vm-test-run-pgtap> server # [ 37.577740] postgres[1526]: [1526] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pgtap> server # [ 37.580818] postgres[1533]: [1533] LOG: Cleanup of old files at startup. Checkpoint 0 vm-test-run-pgtap> server # [ 37.581168] postgres[1533]: [1533] LOG: database system was shut down at 2026-10-05 11:01:41 GMT vm-test-run-pgtap> server # [ 37.582354] postgres[1534]: [1534] LOG: orioledb background writer 0 started vm-test-run-pgtap> server # [ 37.594441] postgres[1526]: [1526] LOG: database system is ready to accept connections vm-test-run-pgtap> server # [ 37.596918] systemd[1]: Started PostgreSQL Server. vm-test-run-pgtap> server # [ 37.598871] systemd[1]: Starting PostgreSQL Setup Scripts... vm-test-run-pgtap> server # [ 37.845260] postgresql-setup-start[1541]: CREATE EXTENSION vm-test-run-pgtap> server # [ 37.906820] postgresql-setup-start[1547]: CREATE ROLE vm-test-run-pgtap> server # [ 37.935264] postgresql-setup-start[1549]: ALTER ROLE vm-test-run-pgtap> server # [ 37.939566] systemd[1]: Finished PostgreSQL Setup Scripts. vm-test-run-pgtap> server # [ 37.939963] systemd[1]: Reached target PostgreSQL. vm-test-run-pgtap> server # [ 38.194058] nixos[1367]: finished switching to system configuration /nix/store/h2g4i7nzbh1z0jp3jhbvkb3aw9jl17a8-nixos-system-server-test vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extname FROM pg_extension WHERE extname = 'orioledb';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extname FROM pg_extension WHERE extname = 'orioledb';" , in 0.06 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" vm-test-run-pgtap> server # NOTICE: schema "public" already exists, skipping vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.05 seconds) vm-test-run-pgtap> (finished: subtest: switch to orioledb 17, in 7.32 seconds) vm-test-run-pgtap> subtest: Check upgrade path with orioledb 17 vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" vm-test-run-pgtap> server # NOTICE: extension "pgtap" does not exist, skipping vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.05 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.1' CASCADE;" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.1' CASCADE;" , in 0.11 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.06 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" , in 0.09 seconds) vm-test-run-pgtap> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" vm-test-run-pgtap> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" , in 0.06 seconds) vm-test-run-pgtap> (finished: subtest: Check upgrade path with orioledb 17, in 0.36 seconds) vm-test-run-pgtap> subtest: Check pg_regress with orioledb 17 after installing the last version vm-test-run-pgtap> server: must succeed: sudo -u postgres /nix/store/h2l361nalwd77g43mlzfssfhfhsa1vnd-postgresql-orioledb-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 "pgtap" vm-test-run-pgtap> server # [ 38.729044] sudo[1580]: root : TTY=hvc0 ; PWD=/tmp ; USER=postgres ; COMMAND=/nix/store/h2l361nalwd77g43mlzfssfhfhsa1vnd-postgresql-orioledb-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 pgtap vm-test-run-pgtap> server # [ 38.749219] sudo[1580]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0) vm-test-run-pgtap> server # # could not set core size: disallowed by hard limit vm-test-run-pgtap> server # [ 38.864067] sudo[1580]: pam_unix(sudo:session): session closed for user postgres vm-test-run-pgtap> server: (finished: must succeed: sudo -u postgres /nix/store/h2l361nalwd77g43mlzfssfhfhsa1vnd-postgresql-orioledb-17-pgtap/lib/pgxs/src/test/regress/pg_regress --inputdir=/nix/store/dhark3dgw0v3npv8vaskx9j6xg48qr7i-tests --debug --use-existing --dbname=postgres --outputdir=/tmp/regression_output_orioledb-17 "pgtap" , in 0.20 seconds) vm-test-run-pgtap> # using postmaster on Unix socket, default port vm-test-run-pgtap> ok 1 - pgtap 62 ms vm-test-run-pgtap> 1..1 vm-test-run-pgtap> # All 1 tests passed. vm-test-run-pgtap> vm-test-run-pgtap> (finished: subtest: Check pg_regress with orioledb 17 after installing the last version, in 0.20 seconds) vm-test-run-pgtap> (finished: run the VM test script, in 39.56 seconds) vm-test-run-pgtap> test script finished in 39.62s vm-test-run-pgtap> cleanup vm-test-run-pgtap> kill QemuMachine (pid 91078) vm-test-run-pgtap> server # qemu-system-aarch64: terminating on signal 15 from pid 91071 () vm-test-run-pgtap> (finished: cleanup, in 0.01 seconds)