vm-test-run-pgtap
checks.aarch64-darwin.ext-pgtap
· build #38
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.01 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 server,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10Running tests for extension: pgtap11start all VMs12server: starting vm13server # Disk image does not exist, creating the virtualisation disk image...14server: QEMU running (pid 91078)15server # Formatting '/nix/var/nix/builds/nix-91058-65619379/vm-state-server/tmp.jkwV6jrcEU', fmt=raw size=107374182416server # mke2fs 1.47.4 (6-Mar-2025)17server # Creating filesystem with 262144 4k blocks and 65536 inodes18server # Filesystem UUID: 6b2cd26d-f061-48a2-ba6c-1d784e97dff419server # Superblock backups stored on blocks:20server # 32768, 98304, 163840, 22937621server # 22server # Allocating group tables: 0/8 done23server # Writing inode tables: 0/8 done24server # Creating journal (8192 blocks): done25server # Writing superblocks and filesystem accounting information: 0/8 done26server # 27server # Virtualisation disk image created.28(finished: start all VMs, in 0.56 seconds)29server: waiting for unit multi-user.target30server: waiting for the VM to finish booting31server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x610f0000]32server # [ 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 202633server # [ 0.000000] KASLR enabled34server # [ 0.000000] random: crng init done35server # [ 0.000000] Machine model: linux,dummy-virt36server # [ 0.000000] efi: UEFI not found.37server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT38server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]39server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f604280-0x13f6079ff]40server # [ 0.000000] Zone ranges:41server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff]42server # [ 0.000000] DMA32 empty43server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff]44server # [ 0.000000] Device empty45server # [ 0.000000] Movable zone start for each node46server # [ 0.000000] Early memory node ranges47server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff]48server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]49server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe00000050server # [ 0.000000] psci: probing for conduit method from DT.51server # [ 0.000000] psci: PSCIv1.1 detected in firmware.52server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs53server # [ 0.000000] psci: Trusted OS migration not required54server # [ 0.000000] psci: SMC Calling Convention v1.055server # [ 0.000000] percpu: Embedded 76 pages/cpu s186520 r8192 d116584 u31129656server # [ 0.000000] Detected PIPT I-cache on CPU057server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)58server # [ 0.000000] CPU features: detected: Spectre-v459server # [ 0.000000] CPU features: detected: Spectre-BHB60server # [ 0.000000] alternatives: applying boot alternatives61server # [ 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=tty062server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/690i1ykcsyz2b28w9n85szn02gxg5fl3-closure-info/registration", will be passed to user space.63server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes64server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)65server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)66server # [ 0.000000] software IO TLB: area num 2.67server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)68server # [ 0.000000] Fallback order for Node 0: 069server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 104857670server # [ 0.000000] Policy zone: Normal71server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off72server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=173server # [ 0.000000] allocated 8388608 bytes of page_ext74server # [ 0.000000] ftrace: allocating 74832 entries in 294 pages75server # [ 0.000000] ftrace: allocated 294 pages with 4 groups76server # [ 0.000000] rcu: Hierarchical RCU implementation.77server # [ 0.000000] rcu: RCU event tracing is enabled.78server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.79server # [ 0.000000] Trampoline variant of Tasks RCU enabled.80server # [ 0.000000] Rude variant of Tasks RCU enabled.81server # [ 0.000000] Tracing variant of Tasks RCU enabled.82server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.83server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=284server # [ 0.000000] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.85server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.86server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.87server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 088server # [ 0.000000] Root IRQ handler: gic_handle_irq89server # [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143]90server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.91server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns92server # [ 0.000000] arch_timer: cp15 timer running at 24.00MHz (virt).93server # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns94server # [ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns95server # [ 0.000203] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)96server # [ 0.000263] Console: colour dummy device 80x2597server # [ 0.000265] printk: legacy console [tty0] enabled98server # [ 0.000335] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)99server # [ 0.000339] pid_max: default: 32768 minimum: 301100server # [ 0.000367] LSM: initializing lsm=capability,landlock,yama,bpf,ima101server # [ 0.000426] landlock: Up and running.102server # [ 0.000427] Yama: becoming mindful.103server # [ 0.000612] LSM support for eBPF active104server # [ 0.000656] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)105server # [ 0.000666] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)106server # [ 0.001137] rcu: Hierarchical SRCU implementation.107server # [ 0.001139] rcu: Max phase no-delay instances is 1000.108server # [ 0.001173] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level109server # [ 0.001566] EFI services will not be available.110server # [ 0.001613] smp: Bringing up secondary CPUs ...111server # [ 0.001885] Detected PIPT I-cache on CPU1112server # [ 0.001954] CPU1: Booted secondary processor 0x0000000001 [0x610f0000]113server # [ 0.002138] smp: Brought up 1 node, 2 CPUs114server # [ 0.002142] SMP: Total of 2 processors activated.115server # [ 0.002143] CPU: All CPU(s) started at EL1116server # [ 0.002146] CPU features: detected: ARMv8.4 Translation Table Level117server # [ 0.002147] CPU features: detected: Data cache clean to the PoU not required for I/D coherence118server # [ 0.002149] CPU features: detected: Common not Private translations119server # [ 0.002149] CPU features: detected: CRC32 instructions120server # [ 0.002150] CPU features: detected: Data cache clean to Point of Deep Persistence121server # [ 0.002151] CPU features: detected: Data cache clean to Point of Persistence122server # [ 0.002152] CPU features: detected: Data independent timing control (DIT)123server # [ 0.002152] CPU features: detected: E0PD124server # [ 0.002153] CPU features: detected: Enhanced Privileged Access Never125server # [ 0.002154] CPU features: detected: Fine Grained Traps126server # [ 0.002155] CPU features: detected: Generic authentication (IMP DEF algorithm)127server # [ 0.002156] CPU features: detected: RCpc load-acquire (LDAPR)128server # [ 0.002157] CPU features: detected: LSE atomic instructions129server # [ 0.002158] CPU features: detected: Privileged Access Never130server # [ 0.002159] CPU features: detected: RAS Extension Support131server # [ 0.002159] CPU features: detected: Speculation barrier (SB)132server # [ 0.002160] CPU features: detected: TLB range maintenance instructions133server # [ 0.002213] alternatives: applying system-wide alternatives134server # [ 0.003004] Memory: 3906376K/4194304K available (24320K kernel code, 7090K rwdata, 26572K rodata, 4736K init, 1103K bss, 239844K reserved, 32768K cma-reserved)135server # [ 0.005755] devtmpfs: initialized136server # [ 0.006698] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear)137server # [ 0.006712] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear).138server # [ 0.006812] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL139server # [ 0.006814] 0 pages in range for non-PLT usage140server # [ 0.006815] 508272 pages in range for PLT usage141server # [ 0.006884] pinctrl core: initialized pinctrl subsystem142server # [ 0.007236] DMI not present or invalid.143server # [ 0.008300] NET: Registered PF_NETLINK/PF_ROUTE protocol family144server # [ 0.008907] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations145server # [ 0.008941] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations146server # [ 0.008983] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations147server # [ 0.009007] audit: initializing netlink subsys (disabled)148server # [ 0.009173] audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1149server # [ 0.009402] thermal_sys: Registered thermal governor 'fair_share'150server # [ 0.009402] thermal_sys: Registered thermal governor 'bang_bang'151server # [ 0.009404] thermal_sys: Registered thermal governor 'step_wise'152server # [ 0.009405] thermal_sys: Registered thermal governor 'user_space'153server # [ 0.009406] thermal_sys: Registered thermal governor 'power_allocator'154server # [ 0.009415] cpuidle: using governor ladder155server # [ 0.009418] cpuidle: using governor menu156server # [ 0.009457] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.157server # [ 0.009540] ASID allocator initialised with 65536 entries158server # [ 0.009778] Serial: AMBA PL011 UART driver159server # [ 0.011060] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1160server # [ 0.011242] printk: console [ttyAMA0] enabled161server # [ 0.024379] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages162server # [ 0.024395] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page163server # [ 0.024398] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages164server # [ 0.024400] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page165server # [ 0.024401] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages166server # [ 0.024403] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page167server # [ 0.024417] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages168server # [ 0.024420] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page169server # [ 0.037568] fbcon: Taking over console170server # [ 0.037582] ACPI: Interpreter disabled.171server # [ 0.040206] iommu: Default domain type: Translated172server # [ 0.040221] iommu: DMA domain TLB invalidation policy: strict mode173server # [ 0.044771] SCSI subsystem initialized174server # [ 0.044966] usbcore: registered new interface driver usbfs175server # [ 0.044973] usbcore: registered new interface driver hub176server # [ 0.044977] usbcore: registered new device driver usb177server # [ 0.045085] pps_core: LinuxPPS API ver. 1 registered178server # [ 0.045087] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>179server # [ 0.045089] PTP clock support registered180server # [ 0.045099] EDAC MC: Ver: 3.0.0181server # [ 0.045145] scmi_core: SCMI protocol bus registered182server # [ 0.053448] FPGA manager framework183server # [ 0.053937] vgaarb: loaded184server # [ 0.056898] clocksource: Switched to clocksource arch_sys_counter185server # [ 0.057082] VFS: Disk quotas dquot_6.6.0186server # [ 0.057088] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)187server # [ 0.057275] netfs: FS-Cache loaded188server # [ 0.057311] pnp: PnP ACPI: disabled189server # [ 0.058173] NET: Registered PF_INET protocol family190server # [ 0.058253] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)191server # [ 0.070908] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)192server # [ 0.070922] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)193server # [ 0.070935] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)194server # [ 0.070964] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)195server # [ 0.071005] TCP: Hash tables configured (established 32768 bind 32768)196server # [ 0.071040] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)197server # [ 0.071052] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)198server # [ 0.071060] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)199server # [ 0.071094] NET: Registered PF_UNIX/PF_LOCAL protocol family200server # [ 0.071101] NET: Registered PF_XDP protocol family201server # [ 0.071107] PCI: CLS 0 bytes, default 64202server # [ 0.071238] Trying to unpack rootfs image as initramfs...203server # [ 0.073003] kvm [1]: HYP mode not available204server # [ 0.092743] Initialise system trusted keyrings205server # [ 0.092807] workingset: timestamp_bits=42 max_order=20 bucket_order=0206server # [ 0.093566] squashfs: version 4.0 (2009/01/31) Phillip Lougher207server # [ 0.093595] 9p: Installing v9fs 9p2000 file system support208server # [ 0.102053] Key type asymmetric registered209server # [ 0.102059] Asymmetric key parser 'x509' registered210server # [ 0.102101] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)211server # [ 0.105529] io scheduler mq-deadline registered212server # [ 0.105532] io scheduler kyber registered213server # [ 0.110407] pl061_gpio 9030000.pl061: PL061 GPIO chip registered214server # [ 0.113215] ledtrig-cpu: registered to indicate activity on CPUs215server # [ 0.113357] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:216server # [ 0.113363] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000217server # [ 0.113368] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000218server # [ 0.113370] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000219server # [ 0.113379] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits220server # [ 0.113387] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]221server # [ 0.113414] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00222server # [ 0.113416] pci_bus 0000:00: root bus resource [bus 00-ff]223server # [ 0.113417] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]224server # [ 0.113419] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]225server # [ 0.113420] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]226server # [ 0.113439] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint227server # [ 0.113553] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint228server # [ 0.113608] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]229server # [ 0.113610] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]230server # [ 0.113615] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]231server # [ 0.113617] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]232server # [ 0.113710] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint233server # [ 0.113748] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]234server # [ 0.113750] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]235server # [ 0.113755] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]236server # [ 0.113856] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint237server # [ 0.113894] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]238server # [ 0.113896] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]239server # [ 0.113900] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]240server # [ 0.113993] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint241server # [ 0.114030] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]242server # [ 0.114032] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]243server # [ 0.114036] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]244server # [ 0.114131] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint245server # [ 0.114173] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]246server # [ 0.114175] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]247server # [ 0.114180] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]248server # [ 0.114269] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint249server # [ 0.114308] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]250server # [ 0.114310] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]251server # [ 0.114316] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]252server # [ 0.114423] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint253server # [ 0.114468] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]254server # [ 0.114470] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]255server # [ 0.114476] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]256server # [ 0.114479] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]257server # [ 0.114580] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint258server # [ 0.114618] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]259server # [ 0.114623] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]260server # [ 0.114715] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint261server # [ 0.114753] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]262server # [ 0.114758] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263server # [ 0.114838] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint264server # [ 0.114876] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]265server # [ 0.114927] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint266server # [ 0.115129] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]267server # [ 0.115137] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]268server # [ 0.115145] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]269server # [ 0.115294] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint270server # [ 0.115337] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]271server # [ 0.115340] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]272server # [ 0.115345] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]273server # [ 0.115493] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned274server # [ 0.115495] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned275server # [ 0.115497] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned276server # [ 0.115506] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned277server # [ 0.115515] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned278server # [ 0.115524] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned279server # [ 0.115532] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned280server # [ 0.115541] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned281server # [ 0.115550] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned282server # [ 0.115560] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned283server # [ 0.115568] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned284server # [ 0.115577] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned285server # [ 0.115624] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned286server # [ 0.115675] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned287server # [ 0.115687] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned288server # [ 0.115694] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned289server # [ 0.115700] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned290server # [ 0.115705] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned291server # [ 0.115710] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned292server # [ 0.115715] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned293server # [ 0.115720] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned294server # [ 0.115726] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned295server # [ 0.115731] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned296server # [ 0.115736] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned297server # [ 0.115742] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned298server # [ 0.115747] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned299server # [ 0.115752] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned300server # [ 0.115756] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned301server # [ 0.115761] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned302server # [ 0.115766] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned303server # [ 0.115770] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned304server # [ 0.115775] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned305server # [ 0.115780] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned306server # [ 0.115784] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned307server # [ 0.115793] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]308server # [ 0.115795] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]309server # [ 0.115797] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]310server # [ 0.116217] pci 0000:00:0a.0: enabling device (0000 -> 0002)311server # [ 0.124354] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)312server # [ 0.133703] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)313server # [ 0.134907] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)314server # [ 0.136260] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)315server # [ 0.137649] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)316server # [ 0.138617] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)317server # [ 0.140824] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)318server # [ 0.149910] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)319server # [ 0.151231] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)320server # [ 0.154440] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)321server # [ 0.155956] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)322server # [ 0.166428] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled323server # [ 0.166905] msm_serial: driver initialized324server # [ 0.166964] SuperH (H)SCI(F) driver initialized325server # [ 0.166986] STM32 USART driver initialized326server # [ 0.181670] loop: module loaded327server # [ 0.181982] virtio_blk virtio5: 2/0/0 default/read/poll queues328server # [ 0.182390] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)329server # [ 0.185038] megasas: 07.734.00.00-rc1330server # [ 0.185324] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]331server # [ 0.186398] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000332server # [ 0.186404] Intel/Sharp Extended Query Table at 0x0031333server # [ 0.187681] Using buffer write method334server # [ 0.187696] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]335server # [ 0.188432] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000336server # [ 0.188437] Intel/Sharp Extended Query Table at 0x0031337server # [ 0.189159] Using buffer write method338server # [ 0.189164] Concatenating MTD devices:339server # [ 0.189166] (0): "0.flash"340server # [ 0.189167] (1): "0.flash"341server # [ 0.189167] into device "0.flash"342server # [ 0.220495] Freeing initrd memory: 25488K343server # [ 0.226869] tun: Universal TUN/TAP device driver, 1.6344server # [ 0.229416] thunder_xcv, ver 1.0345server # [ 0.229424] thunder_bgx, ver 1.0346server # [ 0.229429] nicpf, ver 1.0347server # [ 0.229621] e1000: Intel(R) PRO/1000 Network Driver348server # [ 0.229623] e1000: Copyright (c) 1999-2006 Intel Corporation.349server # [ 0.229629] e1000e: Intel(R) PRO/1000 Network Driver350server # [ 0.229630] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.351server # [ 0.229640] igb: Intel(R) Gigabit Ethernet Network Driver352server # [ 0.229641] igb: Copyright (c) 2007-2014 Intel Corporation.353server # [ 0.229646] igbvf: Intel(R) Gigabit Virtual Function Network Driver354server # [ 0.229647] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.355server # [ 0.229690] sky2: driver version 1.30356server # [ 0.230313] usbcore: registered new interface driver usb-storage357server # [ 0.230336] usbcore: registered new interface driver usbserial_generic358server # [ 0.230339] usbserial: USB Serial support registered for generic359server # [ 0.230546] hv_vmbus: registering driver hyperv_keyboard360server # [ 0.230564] ehci-pci 0000:00:0a.0: EHCI Host Controller361server # [ 0.230584] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1362server # [ 0.230676] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000363server # [ 0.231023] rtc-pl031 9010000.pl031: registered as rtc0364server # [ 0.231206] rtc-pl031 9010000.pl031: setting system clock to 2026-10-05T11:01:04 UTC (1791198064)365server # [ 0.231414] i2c_dev: i2c /dev entries driver366server # [ 0.232807] sdhci: Secure Digital Host Controller Interface driver367server # [ 0.232813] sdhci: Copyright(c) Pierre Ossman368server # [ 0.234947] Synopsys Designware Multimedia Card Interface Driver369server # [ 0.235197] sdhci-pltfm: SDHCI platform and OF driver helper370server # [ 0.236009] hid: raw HID events driver (C) Jiri Kosina371server # [ 0.236115] usbcore: registered new interface driver usbhid372server # [ 0.236117] usbhid: USB HID core driver373server # [ 0.236645] armv8-pmu pmu: hw perfevents: failed to probe PMU!374server # [ 0.237415] drop_monitor: Initializing network drop monitor service375server # [ 0.237501] NET: Registered PF_INET6 protocol family376server # [ 0.243192] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00377server # [ 0.243316] hub 1-0:1.0: USB hub found378server # [ 0.243322] hub 1-0:1.0: 6 ports detected379server # [ 0.243514] Segment Routing with IPv6380server # [ 0.243518] In-situ OAM (IOAM) with IPv6381server # [ 0.243529] NET: Registered PF_PACKET protocol family382server # [ 0.244059] 9pnet: Installing 9P2000 support383server # [ 0.246330] Key type dns_resolver registered384server # [ 0.247611] registered taskstats version 1385server # [ 0.247681] Loading compiled-in X.509 certificates386server # [ 0.249835] Demotion targets for Node 0: null387server # [ 0.249871] Key type .fscrypt registered388server # [ 0.249872] Key type fscrypt-provisioning registered389server # [ 0.249907] ima: No TPM chip found, activating TPM-bypass!390server # [ 0.249914] ima: Allocated hash algorithm: sha1391server # [ 0.249921] ima: No architecture policies found392server # [ 0.250159] input: gpio-keys as /devices/platform/gpio-keys/input/input0393server # [ 0.254451] clk: Disabling unused clocks394server # [ 0.254456] PM: genpd: Disabling unused power domains395server # [ 0.256266] Freeing unused kernel memory: 4736K396server # [ 0.256330] Run /init as init process397server # [ 0.295193] systemd[1]: Successfully made /usr/ read-only.398server # [ 0.509761] usb 1-1: new high-speed USB device number 2 using ehci-pci399server # [ 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)400server # [ 0.638208] systemd[1]: Detected virtualization qemu.401server # [ 0.638232] systemd[1]: Detected architecture arm64.402server # [ 0.638234] systemd[1]: Running in initrd.403server # [ 0.638476] systemd[1]: Initializing machine ID from random generator.404server # [ 0.638525] systemd[1]: Hostname set to <server>.405server # [ 0.672175] systemd[1]: Queued start job for default target Initrd Default Target.406server # [ 0.673613] systemd[1]: Created slice Slice /system/modprobe.407server # [ 0.673794] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.408server # [ 0.673809] systemd[1]: Expecting device /dev/disk/by-label/nixos...409server # [ 0.673819] systemd[1]: Reached target Path Units.410server # [ 0.673826] systemd[1]: Reached target Slice Units.411server # [ 0.673833] systemd[1]: Reached target Swaps.412server # [ 0.673839] systemd[1]: Reached target Timer Units.413server # [ 0.673882] systemd[1]: Listening on D-Bus System Message Bus Socket.414server # [ 0.673930] systemd[1]: Listening on Journal Socket (/dev/log).415server # [ 0.673963] systemd[1]: Listening on Journal Sockets.416server # [ 0.673991] systemd[1]: Listening on udev Control Socket.417server # [ 0.674009] systemd[1]: Listening on udev Kernel Socket.418server # [ 0.674015] systemd[1]: Reached target Socket Units.419server # [ 0.674622] systemd[1]: Starting Create List of Static Device Nodes...420server # [ 0.675104] systemd[1]: Starting Load Kernel Module 9pnet_virtio...421server # [ 0.675131] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs422server # [ 0.678246] systemd[1]: Mounting Kernel Configuration File System...423server # [ 0.683577] systemd[1]: Starting Journal Service...424server # [ 0.684002] systemd[1]: Starting Load Kernel Modules...425server # [ 0.684017] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki426server # [ 0.684886] systemd[1]: Starting Coldplug All udev Devices...427server # [ 0.686250] systemd[1]: Finished Create List of Static Device Nodes.428server # [ 0.686577] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.429server # [ 0.686651] systemd[1]: Finished Load Kernel Module 9pnet_virtio.430server # [ 0.686715] systemd[1]: Mounted Kernel Configuration File System.431server # [ 0.688467] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...432server # [ 0.695086] systemd-journald[80]: Collecting audit messages is disabled.433server # [ 0.697152] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.434server # [ 0.697559] systemd[1]: Starting Create Static Device Nodes in /dev...435server # [ 0.700864] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0436server # [ 0.700975] [drm] features: -virgl +edid -resource_blob -host_visible437server # [ 0.700976] [drm] features: -context_init438server # [ 0.701182] [drm] number of scanouts: 1439server # [ 0.701185] [drm] number of cap sets: 0440server # [ 0.705253] systemd[1]: Finished Create Static Device Nodes in /dev.441server # [ 0.705317] systemd[1]: Reached target Preparation for Local File Systems.442server # [ 0.705327] systemd[1]: Reached target Local File Systems.443server # [ 0.705779] systemd[1]: Starting Rule-based Manager for Device Events and Files...444server # [ 0.705951] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.445server # [ 0.710565] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic446server # [ 0.710569] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0447server # [ 0.711394] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev448server # [ 0.711971] Console: switching to colour frame buffer device 160x50449server # [ 0.712171] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device450server # [ 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/input1451server # [ 0.719896] systemd-modules-load[81]: Using 2 probe threads452server # [ 0.720971] systemd-modules-load[81]: Module 'virtio_balloon' is built in453server # [ 0.721314] systemd-modules-load[81]: Module 'virtio_console' is built in454server # [ 0.721629] systemd-modules-load[81]: Inserted module 'dm_mod'455server # [ 0.721890] systemd-modules-load[81]: Module 'virtio_rng' is built in456server # [ 0.722218] systemd-modules-load[81]: Inserted module 'virtio_gpu'457server # [ 0.722628] systemd[1]: Finished Load Kernel Modules.458server # [ 0.725199] systemd[1]: Started Journal Service.459server # [ 0.728139] systemd[1]: Starting Apply Kernel Variables...460server # [ 0.728409] systemd[1]: Starting Create System Files and Directories...461server # [ 0.731789] systemd-udevd[87]: Using default interface naming scheme 'v260'.462server # [ 0.732532] systemd[1]: Finished Apply Kernel Variables.463server # [ 0.738952] systemd[1]: Finished Create System Files and Directories.464server # [ 0.742284] systemd[1]: Started Rule-based Manager for Device Events and Files.465server # [ 0.752323] systemd[1]: Starting Virtual Console Setup...466server # [ 0.777471] systemd-vconsole-setup[113]: Configuration of first virtual console was skipped, ignoring remaining ones.467server # [ 0.778458] systemd[1]: Finished Virtual Console Setup.468server # [ 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/input0469server # [ 0.864302] systemd[1]: Finished Coldplug All udev Devices.470server # [ 0.864571] systemd[1]: Reached target System Initialization.471server # [ 0.864812] systemd[1]: Reached target Basic System.472server # [ 0.904901] (udev-worker)[106]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.473server # [ 0.906609] (udev-worker)[106]: Network interface NamePolicy= disabled on kernel command line.474server # [ 0.923028] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line.475server # [ 0.929753] systemd[1]: Found device /dev/disk/by-label/nixos.476server # [ 0.930024] systemd[1]: Reached target Initrd Root Device.477server # [ 0.930357] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...478server # [ 0.944923] usb 1-2: new high-speed USB device number 3 using ehci-pci479server # [ 0.955147] systemd-fsck[132]: nixos: clean, 12/65536 files, 13019/262144 blocks480server # [ 0.960201] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.481server # [ 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 directory482server # [ 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 directory483server # [ 1.149098] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.484server # [ 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/input2485server # [ 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/input0486server # [ 1.152420] systemd[1]: Stopped Virtual Console Setup.487server # [ 1.153470] systemd[1]: Stopping Virtual Console Setup...488server # [ 1.153834] systemd[1]: Starting Virtual Console Setup...489server # [ 1.180677] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.490server # [ 1.181696] systemd[1]: Finished Virtual Console Setup.491server # [ 1.681983] systemd[1]: Mounting /sysroot...492server # [ 1.722743] systemd[1]: Mounted /sysroot.493server # [ 1.723187] systemd[1]: Reached target Initrd Root File System.494server # [ 1.726237] EXT4-fs (vda): mounted filesystem 6b2cd26d-f061-48a2-ba6c-1d784e97dff4 r/w with ordered data mode. Quota mode: none.495server # [ 1.724596] systemd[1]: Mounting /sysroot/nix/.ro-store...496server # [ 1.726849] systemd[1]: Mounting /sysroot/nix/.rw-store...497server # [ 1.728131] systemd[1]: Mounting /sysroot/run...498server # [ 1.728794] systemd[1]: Mounting /sysroot/tmp/shared...499server # [ 1.733246] systemd[1]: Mounting /sysroot/tmp/xchg...500server # [ 1.736457] systemd[1]: Starting Mountpoints Configured in the Real Root...501server # [ 1.738911] systemd[1]: Mounted /sysroot/nix/.rw-store.502server # [ 1.739227] systemd[1]: Mounted /sysroot/run.503server # [ 1.742832] systemd-sysroot-fstab-check[151]: /sysroot should be mounted in the initrd, will request daemon-reload.504server # [ 1.744295] systemd[1]: Starting rw-sysroot-nix-store.service...505server # [ 1.744860] systemd[1]: Mounted /sysroot/nix/.ro-store.506server # [ 1.745077] systemd[1]: Mounted /sysroot/tmp/shared.507server # [ 1.745395] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.508server # [ 1.746340] systemd[1]: Finished rw-sysroot-nix-store.service.509server # [ 1.747363] systemd[1]: Mounted /sysroot/tmp/xchg.510server # [ 1.748455] systemd[1]: Reload requested from client PID 151 ('systemd-sysroot') (unit initrd-parse-etc.service)...511server # [ 1.748914] systemd[1]: Reloading...512server # [ 1.790086] systemd[1]: Reloading finished in 40 ms.513server # [ 1.838553] systemd-sysroot-fstab-check[151]: Requesting initrd-fs.target/start/replace...514server # [ 1.839069] systemd-sysroot-fstab-check[151]: Requesting swap.target/start/replace...515server # [ 1.839447] systemd[1]: Starting rw-sysroot-nix-store.service...516server # [ 1.840253] systemd[1]: initrd-parse-etc.service: Deactivated successfully.517server # [ 1.841599] systemd[1]: Finished Mountpoints Configured in the Real Root.518server # [ 1.842002] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.519server # [ 1.862240] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.520server # [ 1.862956] systemd[1]: Finished rw-sysroot-nix-store.service.521server # [ 2.708364] systemd[1]: Mounting /sysroot/nix/store...522server # [ 2.763450] systemd[1]: Mounted /sysroot/nix/store.523server # [ 2.763767] systemd[1]: Reached target Initrd File Systems.524server # [ 2.764799] systemd[1]: Starting Find NixOS closure...525server # [ 2.765280] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...526server # [ 2.773886] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.527server # [ 2.774442] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.528server # [ 2.776314] systemd[1]: Finished Find NixOS closure.529server # [ 2.776612] systemd[1]: Reached target Initrd Default Target.530server # [ 2.777057] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...531server # [ 2.785367] systemd[1]: Stopped target Initrd Default Target.532server # [ 2.786329] systemd[1]: Stopped target Basic System.533server # [ 2.786679] systemd[1]: Stopped target Initrd Root Device.534server # [ 2.786916] systemd[1]: Stopped target Path Units.535server # [ 2.787148] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.536server # [ 2.789251] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.537server # [ 2.789553] systemd[1]: Stopped target Slice Units.538server # [ 2.791869] systemd[1]: Stopped target Socket Units.539server # [ 2.792321] systemd[1]: Stopped target System Initialization.540server # [ 2.792722] systemd[1]: Stopped target Swaps.541server # [ 2.792967] systemd[1]: Stopped target Timer Units.542server # [ 2.793172] systemd[1]: dbus.socket: Deactivated successfully.543server # [ 2.793999] systemd[1]: Closed D-Bus System Message Bus Socket.544server # [ 2.794990] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.545server # [ 2.795677] systemd[1]: Stopped Find NixOS closure.546server # [ 2.796298] systemd[1]: Starting Load Kernel Module 9pnet_virtio...547server # [ 2.796653] systemd[1]: Starting rw-sysroot-nix-store.service...548server # [ 2.796989] systemd[1]: systemd-sysctl.service: Deactivated successfully.549server # [ 2.797394] systemd[1]: Stopped Apply Kernel Variables.550server # [ 2.797762] systemd[1]: systemd-modules-load.service: Deactivated successfully.551server # [ 2.798071] systemd[1]: Stopped Load Kernel Modules.552server # [ 2.798260] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.553server # [ 2.798688] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.554server # [ 2.798998] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.555server # [ 2.799287] systemd[1]: Stopped Create System Files and Directories.556server # [ 2.799575] systemd[1]: Stopped target Local File Systems.557server # [ 2.799802] systemd[1]: Stopped target Preparation for Local File Systems.558server # [ 2.801262] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.559server # [ 2.801552] systemd[1]: Stopped Coldplug All udev Devices.560server # [ 2.801878] systemd[1]: Stopping Rule-based Manager for Device Events and Files...561server # [ 2.802337] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.562server # [ 2.802785] systemd[1]: Stopped Virtual Console Setup.563server # [ 2.802981] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.564server # [ 2.803253] systemd[1]: Finished rw-sysroot-nix-store.service.565server # [ 2.803477] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.566server # [ 2.803904] systemd[1]: Finished Load Kernel Module 9pnet_virtio.567server # [ 2.804425] systemd[1]: initrd-cleanup.service: Deactivated successfully.568server # [ 2.804678] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.569server # [ 2.804921] systemd[1]: systemd-udevd.service: Deactivated successfully.570server # [ 2.805148] systemd[1]: Stopped Rule-based Manager for Device Events and Files.571server # [ 2.805402] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.572server # [ 2.805879] systemd[1]: Closed udev Control Socket.573server # [ 2.806764] systemd[1]: Starting Cleanup udev Database...574server # [ 2.806959] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.575server # [ 2.807630] systemd[1]: Stopped Create Static Device Nodes in /dev.576server # [ 2.808262] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.577server # [ 2.808599] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.578server # [ 2.808870] systemd[1]: kmod-static-nodes.service: Deactivated successfully.579server # [ 2.809261] systemd[1]: Stopped Create List of Static Device Nodes.580server # [ 2.854339] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.581server # [ 2.854671] systemd[1]: Finished Cleanup udev Database.582server # [ 2.854864] systemd[1]: Reached target Switch Root.583server # [ 2.856112] systemd[1]: Starting NixOS Activation...584server # [ 3.110016] initrd-nixos-activation-start[198]: booting system configuration /nix/store/fsy7kxx1rlqwh9rxnzdwddnxqw3f4l5b-nixos-system-server-test585server # [ 3.207101] initrd-nixos-activation-start[198]: running activation script...586server # [ 3.899674] initrd-nixos-activation-start[221]: setting up /etc...587server # [ 4.346293] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.588server # [ 4.346748] systemd[1]: Finished NixOS Activation.589server # [ 4.347019] systemd[1]: Starting Switch Root...590server # [ 4.404210] systemd[1]: Switching root.591server # [ 4.638458] systemd-journald[80]: Received SIGTERM from PID 1 (systemd).592server # [ 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)593server # [ 5.467790] systemd[1]: Detected virtualization qemu.594server # [ 5.467815] systemd[1]: Detected architecture arm64.595server # [ 5.467861] systemd[1]: Detected first boot.596server # [ 5.475908] systemd[1]: Initializing machine ID from random generator.597server # [ 5.633422] systemd[1]: bpf-restrict-fs: LSM BPF program attached598server # [ 5.764511] NET: Registered PF_VSOCK protocol family599server # [ 5.770291] Guest personality initialized and is inactive600server # [ 5.770707] VMCI host device registered (name=vmci, major=10, minor=261)601server # [ 5.770724] Initialized host personality602server # [ 5.944352] systemd[1]: Applying preset policy.603server # [ 6.685444] systemd[1]: Populated /etc with preset unit settings.604server # [ 7.543734] systemd[1]: initrd-switch-root.service: Deactivated successfully.605server # [ 7.544165] systemd[1]: Stopped initrd-switch-root.service.606server # [ 7.545343] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.607server # [ 7.546456] systemd[1]: Created slice Slice /system/getty.608server # [ 7.547268] systemd[1]: Created slice User and Session Slice.609server # [ 7.547712] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.610server # [ 7.548010] systemd[1]: Started Forward Password Requests to Wall Directory Watch.611server # [ 7.548263] systemd[1]: Expecting device /dev/hvc0...612server # [ 7.548648] systemd[1]: Expecting device /dev/ttyAMA0...613server # [ 7.548819] systemd[1]: Reached target Local Encrypted Volumes.614server # [ 7.549597] systemd[1]: Stopped target initrd-fs.target.615server # [ 7.549777] systemd[1]: Stopped target initrd-root-fs.target.616server # [ 7.550387] systemd[1]: Stopped target initrd-switch-root.target.617server # [ 7.550783] systemd[1]: Reached target Virtual Machines and Containers.618server # [ 7.551301] systemd[1]: Reached target Path Units.619server # [ 7.551578] systemd[1]: Reached target Remote File Systems.620server # [ 7.551790] systemd[1]: Reached target Slice Units.621server # [ 7.552325] systemd[1]: Reached target Swaps.622server # [ 7.557758] systemd[1]: Listening on Process Core Dump Socket.623server # [ 7.561192] systemd[1]: Listening on Credential Encryption/Decryption.624server # [ 7.564588] systemd[1]: Starting Journal Log Access Socket...625server # [ 7.565586] systemd[1]: Listening on Journal Audit Socket.626server # [ 7.565885] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.627server # [ 7.566356] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki628server # [ 7.566698] systemd[1]: Listening on udev Control Socket.629server # [ 7.600846] systemd[1]: Mounting Huge Pages File System...630server # [ 7.604288] systemd[1]: Mounting POSIX Message Queue File System...631server # [ 7.607855] systemd[1]: Mounting Kernel Debug File System...632server # [ 7.612181] systemd[1]: Mounting Kernel Trace File System...633server # [ 7.617742] systemd[1]: Starting Create List of Static Device Nodes...634server # [ 7.650705] systemd[1]: Starting Load Kernel Module 9pnet_virtio...635server # [ 7.651027] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs636server # [ 7.660881] systemd[1]: Mounting Kernel Configuration File System...637server # [ 7.662339] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm638server # [ 7.665398] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore639server # [ 7.677060] systemd[1]: Starting Load Kernel Module fuse...640server # [ 7.677377] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67641server # [ 7.681020] systemd[1]: Starting Journal Service...642server # [ 7.689914] systemd[1]: Starting Load Kernel Modules...643server # [ 7.695374] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...644server # [ 7.704687] systemd[1]: Starting Remount Root and Kernel File Systems...645server # [ 7.706247] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki646server # [ 7.716522] systemd[1]: Starting Coldplug All udev Devices...647server # [ 7.718806] systemd[1]: Listening on Journal Log Access Socket.648server # [ 7.730682] systemd[1]: Finished Create List of Static Device Nodes.649server # [ 7.736400] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...650server # [ 7.739642] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.651server # [ 7.740023] systemd[1]: Finished Load Kernel Module 9pnet_virtio.652server # [ 7.780201] systemd[1]: Mounted Kernel Debug File System.653server # [ 7.786796] systemd[1]: Mounted POSIX Message Queue File System.654server # [ 7.791121] systemd[1]: Mounted Huge Pages File System.655server # [ 7.791451] systemd[1]: Mounted Kernel Trace File System.656server # [ 7.791834] systemd[1]: Mounted Kernel Configuration File System.657server # [ 7.846032] systemd-journald[292]: Collecting audit messages is enabled.658server # [ 7.851765] fuse: init (API version 7.45)659server # [ 7.852535] systemd[1]: Queued start job for default target Multi-User System.660server # [ 7.854228] systemd[1]: systemd-journald.service: Deactivated successfully.661server # [ 7.858388] systemd[1]: Started Journal Service.662server # [ 7.857178] systemd-modules-load[293]: Using 2 probe threads663server # [ 7.858639] systemd-oomd[294]: No swap; memory pressure usage will be degraded664server # [ 7.860547] systemd[1]: modprobe@fuse.service: Deactivated successfully.665server # [ 7.863821] systemd[1]: Finished Load Kernel Module fuse.666server # [ 7.864617] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.667server # [ 7.877293] EXT4-fs (vda): re-mounted 6b2cd26d-f061-48a2-ba6c-1d784e97dff4.668server # [ 7.877032] systemd-modules-load[293]: Module 'atkbd' is built in669server # [ 7.877825] systemd-modules-load[293]: Module 'loop' is built in670server # [ 7.882575] systemd[1]: Finished Remount Root and Kernel File Systems.671server # [ 7.884312] systemd[1]: Finished Load Kernel Modules.672server # [ 7.890439] systemd[1]: Starting Firewall...673server # [ 7.895161] systemd[1]: Starting Flush Journal to Persistent Storage...674server # [ 7.896539] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore675server # [ 7.905912] systemd[1]: Starting Load/Save OS Random Seed...676server # [ 7.925535] systemd[1]: Starting Apply Kernel Variables...677server # [ 7.926596] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki678server # [ 7.975938] systemd-journald[292]: Received client request to flush runtime journal.679server # [ 7.988753] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.680server # [ 7.991272] systemd[1]: Finished Load/Save OS Random Seed.681server # [ 7.991953] systemd[1]: Reached target First Boot Complete.682server # [ 7.992951] systemd[1]: Starting Create Static Device Nodes in /dev...683server # [ 7.996562] systemd[1]: Finished Flush Journal to Persistent Storage.684server # [ 8.032118] systemd[1]: Finished Apply Kernel Variables.685server # [ 8.048052] systemd[1]: Finished Coldplug All udev Devices.686server # [ 8.071254] systemd[1]: Finished Create Static Device Nodes in /dev.687server # [ 8.071721] systemd[1]: Reached target Preparation for Local File Systems.688server # [ 8.074322] systemd[1]: Starting Rule-based Manager for Device Events and Files...689server # [ 8.176916] systemd-udevd[329]: Using default interface naming scheme 'v260'.690server # [ 8.346057] systemd[1]: Started Rule-based Manager for Device Events and Files.691server # [ 8.430497] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse692server # [ 8.436299] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs693server # [ 8.448301] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse694server # [ 8.470411] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.695server # [ 8.499124] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.696server # [ 8.541747] systemd[1]: Mounting /run/wrappers...697server # [ 8.546879] systemd[1]: Mounting FUSE Control File System...698server # [ 8.589915] (udev-worker)[360]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.699server # [ 8.595621] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line.700server # [ 8.597348] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line.701server # [ 8.602259] systemd[1]: Mounted /run/wrappers.702server # [ 8.603509] systemd[1]: Mounted FUSE Control File System.703server # [ 8.604199] systemd[1]: Reached target Local File Systems.704server # [ 8.607936] systemd[1]: Listening on Boot Loader Control Service Socket.705server # [ 8.610475] systemd[1]: Starting register-nix-paths.service...706server # [ 8.616523] systemd[1]: Starting Create SUID/SGID Wrappers...707server # [ 8.617035] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.708server # [ 8.620494] systemd[1]: Starting Save Transient machine-id to Disk...709server # [ 8.635385] systemd[1]: Starting Create System Files and Directories...710server # [ 8.646915] mousedev: PS/2 mouse device common for all mice711server # [ 8.687748] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.712server # [ 8.693637] systemd[1]: Finished Save Transient machine-id to Disk.713server # [ 8.717499] systemd[1]: Condition check resulted in Virtio network device being skipped.714server # [ 8.720655] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore715server # [ 8.724146] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.716server # [ 8.725499] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67717server # [ 8.730638] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore718server # [ 8.735222] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki719server # [ 8.740633] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki720server # [ 8.771522] systemd[1]: Finished Create System Files and Directories.721server # [ 8.776442] systemd[1]: Starting Rebuild Journal Catalog...722server # [ 8.782392] systemd[1]: Starting Record System Boot/Shutdown in UTMP...723server # [ 8.869631] systemd[1]: Finished Record System Boot/Shutdown in UTMP.724server # [ 9.017743] systemd[1]: Finished Rebuild Journal Catalog.725server # [ 9.061932] systemd[1]: Starting Update is Completed...726server # [ 9.075820] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3727server # [ 9.126069] systemd[1]: Starting Virtual Console Setup...728server # [ 9.148616] systemd[1]: Finished Update is Completed.729server # [ 9.484165] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.730server # [ 9.485723] systemd[1]: Finished Create SUID/SGID Wrappers.731server # [ 9.658476] systemd[1]: Finished register-nix-paths.service.732server # [ 9.662729] systemd[1]: Finished Firewall.733server # [ 10.004457] systemd-vconsole-setup[471]: Configuration of first virtual console was skipped, ignoring remaining ones.734server # [ 10.006859] systemd[1]: Finished Virtual Console Setup.735server # [ 10.007226] systemd[1]: Reached target System Initialization.736server # [ 10.007549] systemd[1]: Started Discard unused filesystem blocks once a week.737server # [ 10.008440] systemd[1]: Started Daily Cleanup of Temporary Directories.738server # [ 10.008903] systemd[1]: Reached target Timer Units.739server # [ 10.009448] systemd[1]: Listening on D-Bus System Message Bus Socket.740server # [ 10.010112] systemd[1]: Listening on Nix Daemon Socket.741server # [ 10.011745] systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).742server # [ 10.012374] systemd[1]: Listening on Hostname Service Socket.743server # [ 10.012937] systemd[1]: Reached target Socket Units.744server # [ 10.013489] systemd[1]: Reached target Basic System.745server # [ 10.014612] systemd[1]: Started backdoor.service.746server # [ 10.017126] systemd[1]: Starting Import lastlog data into lastlog2 database...747server # [ 10.020339] systemd[1]: Starting Name Service Cache Daemon (nsncd)...748server # [ 10.024060] systemd[1]: Starting Post-Boot Actions...749server # [ 10.068263] systemd[1]: Started Reset console on configuration changes.750server # [ 10.071445] systemd[1]: Starting resolvconf update...751server # [ 10.075778] systemd[1]: Starting SSH Host Keys Generation...752server # [ 10.090748] systemd[1]: Starting D-Bus System Message Bus...753server # [ 10.131781] systemd[1]: Finished Post-Boot Actions.754server # [ 10.157095] systemd[1]: Started Name Service Cache Daemon (nsncd).755server # [ 10.159822] systemd[1]: Reached target Host and Network Name Lookups.756server # connecting to host...757server # [ 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"758server # [ 10.165068] systemd[1]: Reached target User and Group Name Lookups.759server # [ 10.166637] systemd[1]: Starting User Login Management...760server # [ 10.196316] systemd[1]: Finished Import lastlog data into lastlog2 database.761server: Guest shell says: b'Spawning backdoor root shell...\n'762server: connected to guest root shell763server: (connecting took 10.34 seconds)764server: (finished: waiting for the VM to finish booting, in 10.34 seconds)765server # [ 10.267034] systemd-logind[572]: New seat seat0.766server # [ 10.271643] systemd-logind[572]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)767server # [ 10.272466] systemd-logind[572]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)768server # [ 10.272965] systemd-logind[572]: Watching system buttons on /dev/input/event0 (gpio-keys)769server # [ 10.277070] systemd[1]: Started User Login Management.770server # [ 10.282253] systemd[1]: Starting linger-users.service...771server # [ 10.285142] dbus-broker-launch[552]: Looking up NSS user entry for 'systemd-timesync'...772server # [ 10.300954] dbus-broker-launch[552]: NSS returned no entry for 'systemd-timesync'773server # [ 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"774server # [ 10.382010] dbus-broker-launch[552]: Ready775server # [ 10.401386] systemd[1]: Started D-Bus System Message Bus.776server # [ 10.401799] systemd[1]: linger-users.service: Deactivated successfully.777server # [ 10.403444] systemd[1]: Finished linger-users.service.778server # [ 10.411653] systemd[1]: Stopped target Host and Network Name Lookups.779server # [ 10.413570] systemd[1]: Stopping Host and Network Name Lookups...780server # [ 10.414204] systemd[1]: Stopped target User and Group Name Lookups.781server # [ 10.414468] systemd[1]: Stopping User and Group Name Lookups...782server # [ 10.414686] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...783server # [ 10.417127] systemd[1]: nscd.service: Deactivated successfully.784server # [ 10.418481] systemd[1]: Stopped Name Service Cache Daemon (nsncd).785server # [ 10.423834] systemd[1]: Starting Name Service Cache Daemon (nsncd)...786server # [ 10.489226] systemd[1]: Started Name Service Cache Daemon (nsncd).787server # [ 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"788server # [ 10.493039] systemd[1]: Reached target Host and Network Name Lookups.789server # [ 10.493618] systemd[1]: Reached target User and Group Name Lookups.790server # [ 10.496519] sshd-keygen-start[587]: Generating public/private rsa key pair.791server # [ 10.497195] sshd-keygen-start[587]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key792server # [ 10.497816] sshd-keygen-start[587]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub793server # [ 10.498411] sshd-keygen-start[587]: The key fingerprint is:794server # [ 10.499090] sshd-keygen-start[587]: SHA256:yPf7ahQ1F9fB267R6J+kXDht5U84XGCoP5GUkJQ4j5c root@server795server # [ 10.499980] sshd-keygen-start[587]: The key's randomart image is:796server # [ 10.500902] sshd-keygen-start[587]: +---[RSA 4096]----+797server # [ 10.501387] sshd-keygen-start[587]: | ooo oo+|798server # [ 10.501941] sshd-keygen-start[587]: | o o.oo.o.|799server # [ 10.502670] sshd-keygen-start[587]: | + o+oo o|800server # [ 10.503377] sshd-keygen-start[587]: | . .. Eo o o.|801server # [ 10.504100] sshd-keygen-start[587]: | o S...o +o|802server # [ 10.504570] sshd-keygen-start[587]: | . ... o=++|803server # [ 10.505220] sshd-keygen-start[587]: | .. o++Bo|804server # [ 10.505730] sshd-keygen-start[587]: | ....Ooo|805server # [ 10.506327] sshd-keygen-start[587]: | .oo.o oo|806server # [ 10.507066] sshd-keygen-start[587]: +----[SHA256]-----+807server # [ 10.535704] systemd[1]: Finished resolvconf update.808server # [ 10.536261] systemd[1]: Reached target Preparation for Network.809server # [ 10.539665] systemd[1]: Starting DHCP Client...810server # [ 10.542877] systemd[1]: Starting Address configuration of eth1...811server # [ 10.547618] systemd[1]: Starting Extra networking commands....812server # [ 10.562798] sshd-keygen-start[669]: Generating public/private ed25519 key pair.813server # [ 10.563508] sshd-keygen-start[669]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key814server # [ 10.565034] sshd-keygen-start[669]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub815server # [ 10.566287] sshd-keygen-start[669]: The key fingerprint is:816server # [ 10.567589] sshd-keygen-start[669]: SHA256:crayT3y8mSMYOrnCxBU1oaLb/lGF68F3bZjxMV9g2UU root@server817server # [ 10.569140] sshd-keygen-start[669]: The key's randomart image is:818server # [ 10.569443] sshd-keygen-start[669]: +--[ED25519 256]--+819server # [ 10.570081] sshd-keygen-start[669]: | .+. o+E|820server # [ 10.570974] sshd-keygen-start[669]: | .. o ....|821server # [ 10.571807] sshd-keygen-start[669]: | . ... . . o .|822server # [ 10.572416] sshd-keygen-start[669]: | . ... o * + . |823server # [ 10.573114] sshd-keygen-start[669]: |.. . * S + + . |824server # [ 10.573724] sshd-keygen-start[669]: | oo o.B + . |825server # [ 10.574250] sshd-keygen-start[669]: |.o. .ooo+ o |826server # [ 10.575213] sshd-keygen-start[669]: | .o +..+...+ |827server # [ 10.575975] sshd-keygen-start[669]: | .ooo....+. |828server # [ 10.576497] sshd-keygen-start[669]: +----[SHA256]-----+829server # [ 10.577667] systemd[1]: sshd-keygen.service: Deactivated successfully.830server # [ 10.578171] systemd[1]: Finished SSH Host Keys Generation.831server # [ 10.668380] network-addresses-eth1-start[667]: adding address 192.168.1.1/24... done832server # [ 10.682485] dhcpcd[676]: dhcpcd-10.3.1 starting833server # [ 10.684975] network-addresses-eth1-start[667]: adding address 2001:db8:1::1/64... done834server # [ 10.697552] dhcpcd[710]: dev: loaded udev835server # [ 10.706034] systemd[1]: Finished Address configuration of eth1.836server # [ 10.742571] systemd[1]: Finished Extra networking commands..837server # [ 10.744914] systemd[1]: Reached target Network.838server # [ 10.750551] 8021q: 802.1Q VLAN Support v1.8839server # [ 10.750767] 8021q: adding VLAN 0 to HW filter on device eth1840server # [ 10.748845] systemd[1]: Starting PostgreSQL Server...841server # [ 10.752513] systemd[1]: Starting SSH Daemon...842server # [ 10.756555] systemd[1]: Starting Permit User Sessions...843server # [ 10.792985] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.844server # [ 10.813623] systemd[1]: Finished Permit User Sessions.845server # [ 10.818519] systemd[1]: Started Getty on tty1.846server # [ 10.819109] systemd[1]: Reached target Login Prompts.847server # [ 10.831429] cfg80211: Loading compiled-in X.509 certificates for regulatory database848server # [ 10.845683] sshd[738]: Server listening on 0.0.0.0 port 22.849server # [ 10.846238] sshd[738]: Server listening on :: port 22.850server # [ 10.846657] systemd[1]: Started SSH Daemon.851server # [ 10.879776] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'852server # [ 10.880286] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'853server # [ 10.885545] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2854server # [ 10.885862] cfg80211: failed to load regulatory.db855server # [ 10.963304] dhcpcd[710]: eth0: waiting for carrier[ 10.967802] 8021q: adding VLAN 0 to HW filter on device eth0856server # 857server # [ 10.964700] dhcpcd[710]: eth0: carrier acquired858server # [ 10.996335] dhcpcd[710]: DUID 00:01:00:01:32:56:3f:fb:52:54:00:12:34:56859server # [ 10.997906] dhcpcd[710]: eth0: IAID 00:12:34:56860server # [ 10.999370] dhcpcd[710]: eth0: adding address fe80::5054:ff:fe12:3456861server # [ 11.245002] postgresql-pre-start[749]: The files belonging to this database system will be owned by user "postgres".862server # [ 11.246099] postgresql-pre-start[749]: This user must also own the server process.863server # [ 11.260361] postgresql-pre-start[749]: The database cluster will be initialized with locale "en_US.UTF-8".864server # [ 11.261099] postgresql-pre-start[749]: The default database encoding has accordingly been set to "UTF8".865server # [ 11.262088] postgresql-pre-start[749]: The default text search configuration will be set to "english".866server # [ 11.262961] postgresql-pre-start[749]: Data page checksums are disabled.867server # [ 11.264162] postgresql-pre-start[749]: fixing permissions on existing directory /var/lib/postgresql/15 ... ok868server # [ 11.265306] postgresql-pre-start[749]: creating subdirectories ... ok869server # [ 11.265970] postgresql-pre-start[749]: selecting dynamic shared memory implementation ... posix870server # [ 11.422086] postgresql-pre-start[749]: selecting default max_connections ... 100871server # [ 11.495568] postgresql-pre-start[749]: selecting default shared_buffers ... 128MB872server # [ 12.376375] dhcpcd[710]: eth0: soliciting a DHCP lease873server # [ 12.384574] dhcpcd[710]: eth0: offered 10.0.2.15 from 10.0.2.2874server # [ 12.396395] dhcpcd[710]: eth0: probing address 10.0.2.15/24875server # [ 12.862299] dhcpcd[710]: eth0: soliciting an IPv6 router876server # [ 12.862807] dhcpcd[710]: eth0: Router Advertisement from fe80::2877server # [ 12.863535] dhcpcd[710]: eth0: adding address fec0::5054:ff:fe12:3456/64878server # [ 12.864265] dhcpcd[710]: eth0: adding route to fec0::/64879server # [ 12.864862] dhcpcd[710]: eth0: adding default route via fe80::2880server # [ 13.150589] postgresql-pre-start[749]: selecting default time zone ... UTC881server # [ 13.155643] postgresql-pre-start[749]: creating configuration files ... ok882server # [ 13.330456] postgresql-pre-start[749]: running bootstrap script ... ok883server # [ 13.724578] postgresql-pre-start[749]: performing post-bootstrap initialization ... ok884server # [ 13.889770] postgresql-pre-start[749]: syncing data to disk ... ok885server # [ 13.891173] postgresql-pre-start[749]: initdb: warning: enabling "trust" authentication for local connections886server # [ 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.887server # [ 13.893246] postgresql-pre-start[749]: Success. You can now start the database server using:888server # [ 13.895238] postgresql-pre-start[749]: /nix/store/9yrimycrlm3q2a0yc9hv4q2bm3ff9mib-postgresql-15-pgtap/bin/pg_ctl -D /var/lib/postgresql/15 -l logfile start889server # [ 14.022149] postgres[798]: [798] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit890server # [ 14.023909] postgres[798]: [798] LOG: listening on IPv4 address "0.0.0.0", port 5432891server # [ 14.024679] postgres[798]: [798] LOG: listening on IPv6 address "::", port 5432892server # [ 14.026036] postgres[798]: [798] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"893server # [ 14.032685] postgres[804]: [804] LOG: database system was shut down at 2026-10-05 11:01:17 GMT894server # [ 14.035748] postgres[798]: [798] LOG: database system is ready to accept connections895server # [ 14.040934] systemd[1]: Started PostgreSQL Server.896server # [ 14.046537] systemd[1]: Starting PostgreSQL Setup Scripts...897server # [ 14.246883] postgresql-setup-start[818]: CREATE ROLE898server # [ 14.273010] postgresql-setup-start[823]: ALTER ROLE899server # [ 14.276681] systemd[1]: Finished PostgreSQL Setup Scripts.900server # [ 14.277293] systemd[1]: Reached target PostgreSQL.901server # [ 17.059670] dhcpcd[710]: eth0: leased 10.0.2.15 for 86400 seconds902server # [ 17.059825] dhcpcd[710]: eth0: adding route to 10.0.2.0/24903server # [ 17.059975] dhcpcd[710]: eth0: adding default route via 10.0.2.2904server # [ 17.175750] systemd[1]: Started DHCP Client.905server # [ 17.176126] systemd[1]: Reached target Multi-User System.906server # [ 17.176581] systemd[1]: Startup finished in 253ms (kernel) + 4.482s (initrd) + 12.440s (userspace) = 17.176s.907server: (finished: waiting for unit multi-user.target, in 17.62 seconds)908server: waiting for unit postgresql.service909server: (finished: waiting for unit postgresql.service, in 0.14 seconds)910server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" 911server # NOTICE: schema "public" already exists, skipping912server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.06 seconds)913subtest: Check upgrade path with postgresql 15914server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" 915server # NOTICE: extension "pgtap" does not exist, skipping916server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.05 seconds)917server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.2.0' CASCADE;" 918server: (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)919server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 920server: (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)921server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.1';" 922server: (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)923server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 924server: (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)925server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" 926server: (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)927server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 928server: (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)929(finished: subtest: Check upgrade path with postgresql 15, in 0.51 seconds)930subtest: Check pg_regress with postgresql 15 after extension upgrade931server: 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" 932server # [ 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 pgtap933server # [ 18.354866] sudo[940]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)934server # pg_regress: could not set core size: disallowed by hard limit935server # [ 18.462603] sudo[940]: pam_unix(sudo:session): session closed for user postgres936server: (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)937(using postmaster on Unix socket, default port)938============== running regression test queries ==============939test pgtap ... ok 59 ms940941=====================942 All 1 tests passed. 943=====================944945946(finished: subtest: Check pg_regress with postgresql 15 after extension upgrade, in 0.27 seconds)947subtest: Check the install of the last version of the extension948server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" 949server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.07 seconds)950server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.3' CASCADE;" 951server: (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)952server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 953server: (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)954(finished: subtest: Check the install of the last version of the extension, in 0.22 seconds)955subtest: switch to postgresql 17956server # Checking switch inhibitors... done957server # stopping the following units: postgresql-setup.service, postgresql.service958server # [ 19.873841] nixos[966]: switching to system configuration /nix/store/m8zh4r4y5qw00p2ny2srqpihw6q3caqp-nixos-system-server-test959server # [ 19.875403] systemd[1]: Stopped target Local File Systems.960server # [ 19.876748] systemd[1]: Stopped target Remote File Systems.961server # [ 19.878717] systemd[1]: Stopped target PostgreSQL.962server # [ 19.879428] postgres[798]: [798] LOG: received fast shutdown request963server # [ 19.880341] systemd[1]: postgresql-setup.service: Deactivated successfully.964server # [ 19.881017] systemd[1]: Stopped PostgreSQL Setup Scripts.965server # [ 19.881848] systemd[1]: Stopping PostgreSQL Server...966server # [ 19.882035] postgres[798]: [798] LOG: aborting any active transactions967server # [ 19.882839] postgres[798]: [798] LOG: background worker "logical replication launcher" (PID 807) exited with exit code 1968server # [ 19.884337] postgres[802]: [802] LOG: shutting down969server # [ 19.885656] postgres[802]: [802] LOG: checkpoint starting: shutdown immediate970server # [ 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 kB971server # [ 19.911502] postgres[798]: [798] LOG: database system is shut down972server # [ 19.916528] systemd[1]: postgresql.service: Deactivated successfully.973server # [ 19.916822] systemd[1]: Stopped PostgreSQL Server.974server # [ 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.975server # activating the configuration...976server # setting up /etc...977server # [ 20.742449] systemd[1]: Reload requested from client PID 966 ('.switch-to-conf') (unit backdoor.service)...978server # [ 20.743854] systemd[1]: Reloading...979server # [ 21.674878] systemd[1]: Reloading finished in 929 ms.980server # restarting sysinit-reactivation.target981server # [ 21.714608] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....982server # [ 21.860268] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..983server # [ 21.860979] systemd[1]: Reached target Reactivate sysinit units.984server # reloading the following units: dbus-broker.service985server # [ 21.862586] systemd[1]: Reloading D-Bus System Message Bus...986server # [ 21.862982] dbus-broker-launch[552]: Caught SIGHUP, trigger reload.987server # [ 21.896097] dbus-broker-launch[552]: Looking up NSS user entry for 'systemd-timesync'...988server # [ 21.897093] dbus-broker-launch[552]: NSS returned no entry for 'systemd-timesync'989server # [ 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"990server # [ 21.951541] systemd[1]: Reloaded D-Bus System Message Bus.991server # starting the following units: postgresql-setup.service, postgresql.service992server # [ 21.955270] systemd[1]: Reached target Local File Systems.993server # [ 21.960096] systemd[1]: Starting postgresql-migrate.service...994server # [ 21.967233] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore995server # [ 21.969593] systemd[1]: Starting Create SUID/SGID Wrappers...996server # [ 21.970021] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.997server # [ 21.970470] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67998server # [ 21.971637] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore999server # [ 21.973102] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1000server # [ 21.974397] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1001server # [ 21.975495] systemd[1]: Reached target Remote File Systems.1002server # [ 21.977627] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1003server # [ 22.197512] postgresql-migrate-start[1043]: The files belonging to this database system will be owned by user "postgres".1004server # [ 22.198251] postgresql-migrate-start[1043]: This user must also own the server process.1005server # [ 22.218092] postgresql-migrate-start[1043]: The database cluster will be initialized with locale "en_US.UTF-8".1006server # [ 22.218933] postgresql-migrate-start[1043]: The default database encoding has accordingly been set to "UTF8".1007server # [ 22.219985] postgresql-migrate-start[1043]: The default text search configuration will be set to "english".1008server # [ 22.220764] postgresql-migrate-start[1043]: Data page checksums are disabled.1009server # [ 22.221348] postgresql-migrate-start[1043]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok1010server # [ 22.222041] postgresql-migrate-start[1043]: creating subdirectories ... ok1011server # [ 22.223169] postgresql-migrate-start[1043]: selecting dynamic shared memory implementation ... posix1012server # [ 22.360379] postgresql-migrate-start[1043]: selecting default "max_connections" ... 1001013server # [ 22.449535] postgresql-migrate-start[1043]: selecting default "shared_buffers" ... 128MB1014server # [ 22.649097] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1015server # [ 22.649424] systemd[1]: Finished Create SUID/SGID Wrappers.1016server # [ 22.652778] systemd[1]: Starting linger-users.service...1017server # [ 22.653390] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.1018server # [ 22.707010] systemd[1]: linger-users.service: Deactivated successfully.1019server # [ 22.707535] systemd[1]: Finished linger-users.service.1020server # [ 24.289127] postgresql-migrate-start[1043]: selecting default time zone ... UTC1021server # [ 24.295274] postgresql-migrate-start[1043]: creating configuration files ... ok1022server # [ 24.471090] postgresql-migrate-start[1043]: running bootstrap script ... ok1023server # [ 24.834484] postgresql-migrate-start[1043]: performing post-bootstrap initialization ... ok1024server # [ 24.974822] postgresql-migrate-start[1043]: syncing data to disk ... ok1025server # [ 24.975753] postgresql-migrate-start[1043]: initdb: warning: enabling "trust" authentication for local connections1026server # [ 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.1027server # [ 24.979812] postgresql-migrate-start[1043]: Success. You can now start the database server using:1028server # [ 24.981007] postgresql-migrate-start[1043]: /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start1029server # [ 25.674716] postgresql-migrate-start[1119]: Performing Consistency Checks1030server # [ 25.674788] postgresql-migrate-start[1119]: -----------------------------1031server # [ 25.674802] postgresql-migrate-start[1119]: Checking cluster versions ok1032server # [ 25.965789] postgresql-migrate-start[1119]: Checking database user is the install user ok1033server # [ 25.968244] postgresql-migrate-start[1119]: Checking database connection settings ok1034server # [ 25.970354] postgresql-migrate-start[1119]: Checking for prepared transactions ok1035server # [ 25.970778] postgresql-migrate-start[1119]: Checking for contrib/isn with bigint-passing mismatch ok1036server # [ 25.995877] postgresql-migrate-start[1119]: Checking data type usage ok1037server # [ 26.004765] postgresql-migrate-start[1119]: Checking for not-null constraint inconsistencies ok1038server # [ 26.096244] postgresql-migrate-start[1119]: Creating dump of global objects ok1039server # [ 26.433410] postgresql-migrate-start[1119]: Creating dump of database schemas ok1040server # [ 26.804549] postgresql-migrate-start[1119]: Checking for presence of required libraries ok1041server # [ 26.806757] postgresql-migrate-start[1119]: Checking database user is the install user ok1042server # [ 26.809331] postgresql-migrate-start[1119]: Checking for prepared transactions ok1043server # [ 26.810034] postgresql-migrate-start[1119]: Checking for new cluster tablespace directories ok1044server # [ 26.810490] postgresql-migrate-start[1119]: If pg_upgrade fails after this point, you must re-initdb the1045server # [ 26.810888] postgresql-migrate-start[1119]: new cluster before continuing.1046server # [ 26.811145] postgresql-migrate-start[1119]: Performing Upgrade1047server # [ 26.811403] postgresql-migrate-start[1119]: ------------------1048server # [ 26.815213] postgresql-migrate-start[1119]: Setting locale and encoding for new cluster ok1049server # [ 26.958883] postgresql-migrate-start[1119]: Analyzing all rows in the new cluster ok1050server # [ 27.035662] postgresql-migrate-start[1119]: Freezing all rows in the new cluster ok1051server # [ 27.168079] postgresql-migrate-start[1119]: Deleting files from new pg_xact ok1052server # [ 27.192516] postgresql-migrate-start[1119]: Copying old pg_xact to new server ok1053server # [ 27.226004] postgresql-migrate-start[1119]: Setting oldest XID for new cluster ok1054server # [ 27.318420] postgresql-migrate-start[1119]: Setting next transaction ID and epoch for new cluster ok1055server # [ 27.319807] postgresql-migrate-start[1119]: Deleting files from new pg_multixact/offsets ok1056server # [ 27.343246] postgresql-migrate-start[1119]: Copying old pg_multixact/offsets to new server ok1057server # [ 27.344434] postgresql-migrate-start[1119]: Deleting files from new pg_multixact/members ok1058server # [ 27.372164] postgresql-migrate-start[1119]: Copying old pg_multixact/members to new server ok1059server # [ 27.406561] postgresql-migrate-start[1119]: Setting next multixact ID and offset for new cluster ok1060server # [ 27.440318] postgresql-migrate-start[1119]: Resetting WAL archives ok1061server # [ 27.659022] postgresql-migrate-start[1119]: Setting frozenxid and minmxid counters in new cluster ok1062server # [ 27.713248] postgresql-migrate-start[1119]: Restoring global objects in the new cluster ok1063server # [ 28.189719] postgresql-migrate-start[1119]: Restoring database schemas in the new cluster ok1064server # [ 28.336293] postgresql-migrate-start[1119]: Copying user relation files ok1065server # [ 28.368720] postgresql-migrate-start[1119]: Setting next OID for new cluster ok1066server # [ 28.543178] postgresql-migrate-start[1119]: Sync data directory to disk ok1067server # [ 28.544811] postgresql-migrate-start[1119]: Creating script to delete old cluster ok1068server # [ 28.980586] postgresql-migrate-start[1119]: Checking for extension updates ok1069server # [ 29.114679] postgresql-migrate-start[1119]: Upgrade Complete1070server # [ 29.115033] postgresql-migrate-start[1119]: ----------------1071server # [ 29.115702] postgresql-migrate-start[1119]: Optimizer statistics are not transferred by pg_upgrade.1072server # [ 29.116581] postgresql-migrate-start[1119]: Once you start the new server, consider running:1073server # [ 29.117071] postgresql-migrate-start[1119]: /nix/store/ls8nyhdsv111cl34ga22xp0a2xnywvqq-postgresql-17-pgtap/bin/vacuumdb --all --analyze-in-stages1074server # [ 29.118093] postgresql-migrate-start[1119]: Running this script will delete the old cluster's data files:1075server # [ 29.119147] postgresql-migrate-start[1119]: ./delete_old_cluster.sh1076server # [ 29.124191] systemd[1]: Finished postgresql-migrate.service.1077server # [ 29.126732] systemd[1]: Starting PostgreSQL Server...1078server # [ 29.367023] postgres[1280]: [1280] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit1079server # [ 29.369392] postgres[1280]: [1280] LOG: listening on IPv4 address "0.0.0.0", port 54321080server # [ 29.370432] postgres[1280]: [1280] LOG: listening on IPv6 address "::", port 54321081server # [ 29.371542] postgres[1280]: [1280] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1082server # [ 29.376196] postgres[1286]: [1286] LOG: database system was shut down at 2026-10-05 11:01:33 GMT1083server # [ 29.379127] postgres[1280]: [1280] LOG: database system is ready to accept connections1084server # [ 29.381352] systemd[1]: Started PostgreSQL Server.1085server # [ 29.384409] systemd[1]: Starting PostgreSQL Setup Scripts...1086server # [ 29.516538] postgresql-setup-start[1296]: ALTER ROLE1087server # [ 29.520384] systemd[1]: Finished PostgreSQL Setup Scripts.1088server # [ 29.520878] systemd[1]: Reached target PostgreSQL.1089server # the following new units were started: postgresql-migrate.service, sysinit-reactivation.target, systemd-tmpfiles-resetup.service1090server # [ 29.797764] nixos[966]: finished switching to system configuration /nix/store/m8zh4r4y5qw00p2ny2srqpihw6q3caqp-nixos-system-server-test1091server: waiting for unit postgresql.service1092server: (finished: waiting for unit postgresql.service, in 0.13 seconds)1093server: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no'1094server: (finished: must succeed: test -f /var/lib/postgresql/update_extensions.sql && echo 'yes' || echo 'no', in 0.02 seconds)1095(finished: subtest: switch to postgresql 17, in 11.28 seconds)1096subtest: Check last version of the extension after postgresql upgrade1097server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 1098server: (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)1099(finished: subtest: Check last version of the extension after postgresql upgrade, in 0.05 seconds)1100subtest: Check upgrade path with postgresql 171101server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" 1102server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.07 seconds)1103server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.1' CASCADE;" 1104server: (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)1105server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 1106server: (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)1107server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" 1108server: (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)1109server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 1110server: (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)1111(finished: subtest: Check upgrade path with postgresql 17, in 0.36 seconds)1112subtest: Check pg_regress with postgresql 17 after extension upgrade1113server: 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" 1114server # [ 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 pgtap1115server # [ 30.449448] sudo[1330]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)1116server # # could not set core size: disallowed by hard limit1117server # [ 30.549895] sudo[1330]: pam_unix(sudo:session): session closed for user postgres1118server: (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)1119# using postmaster on Unix socket, default port1120ok 1 - pgtap 51 ms11211..11122# All 1 tests passed.11231124(finished: subtest: Check pg_regress with postgresql 17 after extension upgrade, in 0.18 seconds)1125subtest: Check the install of the last version of the extension1126server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" 1127server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.06 seconds)1128server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.3' CASCADE;" 1129server: (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)1130server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 1131server: (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)1132(finished: subtest: Check the install of the last version of the extension, in 0.25 seconds)1133subtest: Check pg_regress with postgresql 17 after installing the last version1134server: 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" 1135server # [ 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 pgtap1136server # [ 30.877710] sudo[1353]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)1137server # # could not set core size: disallowed by hard limit1138server # [ 30.985881] sudo[1353]: pam_unix(sudo:session): session closed for user postgres1139server: (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)1140# using postmaster on Unix socket, default port1141ok 1 - pgtap 61 ms11421..11143# All 1 tests passed.11441145(finished: subtest: Check pg_regress with postgresql 17 after installing the last version, in 0.18 seconds)1146subtest: switch to orioledb 171147server # Checking switch inhibitors... done1148server # stopping the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service1149server # [ 32.102904] nixos[1367]: switching to system configuration /nix/store/h2g4i7nzbh1z0jp3jhbvkb3aw9jl17a8-nixos-system-server-test1150server # [ 32.104962] systemd[1]: Stopped target PostgreSQL.1151server # [ 32.105297] systemd[1]: postgresql-setup.service: Deactivated successfully.1152server # [ 32.105593] systemd[1]: Stopped PostgreSQL Setup Scripts.1153server # [ 32.105856] systemd[1]: Stopping PostgreSQL Server...1154server # [ 32.106686] postgres[1280]: [1280] LOG: received fast shutdown request1155server # [ 32.109279] systemd[1]: Stopped target Remote File Systems.1156server # [ 32.109532] postgres[1280]: [1280] LOG: aborting any active transactions1157server # [ 32.112097] systemd[1]: Stopped target Local File Systems.1158server # [ 32.112623] postgres[1280]: [1280] LOG: background worker "logical replication launcher" (PID 1289) exited with exit code 11159server # [ 32.113393] postgres[1284]: [1284] LOG: shutting down1160server # [ 32.114193] postgres[1284]: [1284] LOG: checkpoint starting: shutdown immediate1161server # [ 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/8519DF01162server # [ 32.140055] postgres[1280]: [1280] LOG: database system is shut down1163server # [ 32.145702] systemd[1]: postgresql.service: Deactivated successfully.1164server # [ 32.146072] systemd[1]: Stopped PostgreSQL Server.1165server # [ 32.147386] systemd[1]: postgresql-migrate.service: Deactivated successfully.1166server # [ 32.147822] systemd[1]: Stopped postgresql-migrate.service.1167server # [ 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.1168server # activating the configuration...1169server # setting up /etc...1170server # [ 32.849847] systemd[1]: Reload requested from client PID 1367 ('.switch-to-conf') (unit backdoor.service)...1171server # [ 32.850689] systemd[1]: Reloading...1172server # [ 33.728953] systemd[1]: Reloading finished in 875 ms.1173server # restarting sysinit-reactivation.target1174server # [ 33.760723] systemd[1]: Stopped target Reactivate sysinit units.1175server # [ 33.761095] systemd[1]: Stopping Reactivate sysinit units...1176server # [ 33.761384] systemd[1]: Reached target Reactivate sysinit units.1177server # reloading the following units: dbus-broker.service1178server # [ 33.762848] systemd[1]: Reloading D-Bus System Message Bus...1179server # [ 33.763893] dbus-broker-launch[552]: Caught SIGHUP, trigger reload.1180server # [ 33.800633] dbus-broker-launch[552]: Looking up NSS user entry for 'systemd-timesync'...1181server # [ 33.801822] dbus-broker-launch[552]: NSS returned no entry for 'systemd-timesync'1182server # [ 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"1183server # [ 33.858007] systemd[1]: Reloaded D-Bus System Message Bus.1184server # starting the following units: postgresql-migrate.service, postgresql-setup.service, postgresql.service1185server # [ 33.861176] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1186server # [ 33.866170] systemd[1]: Starting postgresql-migrate.service...1187server # [ 33.871583] systemd[1]: Reached target Remote File Systems.1188server # [ 33.875927] systemd[1]: Starting linger-users.service...1189server # [ 33.876400] systemd[1]: SSH Host Keys Generation skipped, no trigger condition checks were met.1190server # [ 33.886919] systemd[1]: Reached target Local File Systems.1191server # [ 33.889305] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1192server # [ 33.892968] systemd[1]: Starting Create SUID/SGID Wrappers...1193server # [ 33.893622] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1194server # [ 33.894250] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671195server # [ 33.895526] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1196server # [ 33.897544] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1197server # [ 33.898774] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1198server # [ 33.961245] systemd[1]: linger-users.service: Deactivated successfully.1199server # [ 33.962044] systemd[1]: Finished linger-users.service.1200server # [ 33.971280] systemd[1]: Finished postgresql-migrate.service.1201server # [ 34.521536] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1202server # [ 34.521957] systemd[1]: Finished Create SUID/SGID Wrappers.1203server # [ 34.525279] systemd[1]: Starting PostgreSQL Server...1204server # [ 34.744796] postgresql-pre-start[1513]: The files belonging to this database system will be owned by user "postgres".1205server # [ 34.745360] postgresql-pre-start[1513]: This user must also own the server process.1206server # [ 34.761857] postgresql-pre-start[1513]: Using language tag "en-US" for ICU locale "en_US.UTF-8".1207server # [ 34.762361] postgresql-pre-start[1513]: The database cluster will be initialized with this locale configuration:1208server # [ 34.763341] postgresql-pre-start[1513]: locale provider: icu1209server # [ 34.764342] postgresql-pre-start[1513]: default collation: en-US1210server # [ 34.764890] postgresql-pre-start[1513]: LC_COLLATE: en_US.UTF-81211server # [ 34.765353] postgresql-pre-start[1513]: LC_CTYPE: en_US.UTF-81212server # [ 34.765934] postgresql-pre-start[1513]: LC_MESSAGES: en_US.UTF-81213server # [ 34.766714] postgresql-pre-start[1513]: LC_MONETARY: en_US.UTF-81214server # [ 34.767518] postgresql-pre-start[1513]: LC_NUMERIC: en_US.UTF-81215server # [ 34.768255] postgresql-pre-start[1513]: LC_TIME: en_US.UTF-81216server # [ 34.768729] postgresql-pre-start[1513]: The default text search configuration will be set to "english".1217server # [ 34.769345] postgresql-pre-start[1513]: Data page checksums are disabled.1218server # [ 34.769937] postgresql-pre-start[1513]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok1219server # [ 34.771038] postgresql-pre-start[1513]: creating subdirectories ... ok1220server # [ 34.771790] postgresql-pre-start[1513]: selecting dynamic shared memory implementation ... posix1221server # [ 34.870808] postgresql-pre-start[1513]: selecting default "max_connections" ... 1001222server # [ 34.935011] postgresql-pre-start[1513]: selecting default "shared_buffers" ... 128MB1223server # [ 36.660269] postgresql-pre-start[1513]: selecting default time zone ... UTC1224server # [ 36.665876] postgresql-pre-start[1513]: creating configuration files ... ok1225server # [ 36.826450] postgresql-pre-start[1513]: running bootstrap script ... ok1226server # [ 37.165555] postgresql-pre-start[1513]: performing post-bootstrap initialization ... ok1227server # [ 37.307579] postgresql-pre-start[1513]: syncing data to disk ... ok1228server # [ 37.307919] postgresql-pre-start[1513]: initdb: warning: enabling "trust" authentication for local connections1229server # [ 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.1230server # [ 37.309767] postgresql-pre-start[1513]: Success. You can now start the database server using:1231server # [ 37.310460] postgresql-pre-start[1513]: /nix/store/h2l361nalwd77g43mlzfssfhfhsa1vnd-postgresql-orioledb-17-pgtap/bin/pg_ctl -D /var/lib/postgresql/17 -l logfile start1232server # [ 37.540269] postgres[1526]: [1526] LOG: registered custom resource manager "OrioleDB resource manager" with ID 1291233server # [ 37.560156] postgres[1526]: [1526] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit1234server # [ 37.575650] postgres[1526]: [1526] LOG: listening on IPv6 address "::1", port 54321235server # [ 37.576883] postgres[1526]: [1526] LOG: listening on IPv4 address "127.0.0.1", port 54321236server # [ 37.577740] postgres[1526]: [1526] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1237server # [ 37.580818] postgres[1533]: [1533] LOG: Cleanup of old files at startup. Checkpoint 01238server # [ 37.581168] postgres[1533]: [1533] LOG: database system was shut down at 2026-10-05 11:01:41 GMT1239server # [ 37.582354] postgres[1534]: [1534] LOG: orioledb background writer 0 started1240server # [ 37.594441] postgres[1526]: [1526] LOG: database system is ready to accept connections1241server # [ 37.596918] systemd[1]: Started PostgreSQL Server.1242server # [ 37.598871] systemd[1]: Starting PostgreSQL Setup Scripts...1243server # [ 37.845260] postgresql-setup-start[1541]: CREATE EXTENSION1244server # [ 37.906820] postgresql-setup-start[1547]: CREATE ROLE1245server # [ 37.935264] postgresql-setup-start[1549]: ALTER ROLE1246server # [ 37.939566] systemd[1]: Finished PostgreSQL Setup Scripts.1247server # [ 37.939963] systemd[1]: Reached target PostgreSQL.1248server # [ 38.194058] nixos[1367]: finished switching to system configuration /nix/store/h2g4i7nzbh1z0jp3jhbvkb3aw9jl17a8-nixos-system-server-test1249server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extname FROM pg_extension WHERE extname = 'orioledb';" 1250server: (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)1251server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" 1252server # NOTICE: schema "public" already exists, skipping1253server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE SCHEMA IF NOT EXISTS public;" , in 0.05 seconds)1254(finished: subtest: switch to orioledb 17, in 7.32 seconds)1255subtest: Check upgrade path with orioledb 171256server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" 1257server # NOTICE: extension "pgtap" does not exist, skipping1258server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgtap;" , in 0.05 seconds)1259server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgtap WITH VERSION '1.3.1' CASCADE;" 1260server: (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)1261server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 1262server: (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)1263server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgtap UPDATE TO '1.3.3';" 1264server: (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)1265server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgtap';" 1266server: (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)1267(finished: subtest: Check upgrade path with orioledb 17, in 0.36 seconds)1268subtest: Check pg_regress with orioledb 17 after installing the last version1269server: 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" 1270server # [ 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 pgtap1271server # [ 38.749219] sudo[1580]: pam_unix(sudo:session): session opened for user postgres(uid=71) by (uid=0)1272server # # could not set core size: disallowed by hard limit1273server # [ 38.864067] sudo[1580]: pam_unix(sudo:session): session closed for user postgres1274server: (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)1275# using postmaster on Unix socket, default port1276ok 1 - pgtap 62 ms12771..11278# All 1 tests passed.12791280(finished: subtest: Check pg_regress with orioledb 17 after installing the last version, in 0.20 seconds)1281(finished: run the VM test script, in 39.56 seconds)1282test script finished in 39.62s1283cleanup1284kill QemuMachine (pid 91078)1285server # qemu-system-aarch64: terminating on signal 15 from pid 91071 (<unknown process>)1286(finished: cleanup, in 0.01 seconds)