vm-test-run-amcheck-upgrade
checks.aarch64-linux.ext-amcheck-upgrade
· build #522
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 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_ssh10start all VMs11server: starting vm12server: QEMU running (pid 13)13server # Disk image does not exist, creating the virtualisation disk image...14server # Formatting '/build/vm-state-server/tmp.3XbHdZmwLW', fmt=raw size=107374182415server # mke2fs 1.47.4 (6-Mar-2025)16server # Discarding device blocks: 0/262144 done17server # Creating filesystem with 262144 4k blocks and 65536 inodes18server # Filesystem UUID: cee67440-90cc-43a0-befc-18cfcb0a894319server # 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.28server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory29server # qemu-system-aarch64: failed to initialize kvm: No such file or directory30server # qemu-system-aarch64: falling back to tcg31(finished: start all VMs, in 0.22 seconds)32server: waiting for unit supabase-db-init.service33server: waiting for the VM to finish booting34server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]35server # [ 0.000000] Linux version 6.18.59 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Oct 26 15:09:28 UTC 202636server # [ 0.000000] KASLR enabled37server # [ 0.000000] random: crng init done38server # [ 0.000000] Machine model: linux,dummy-virt39server # [ 0.000000] efi: UEFI not found.40server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT41server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]42server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f6031c0-0x13f60693f]43server # [ 0.000000] Zone ranges:44server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff]45server # [ 0.000000] DMA32 empty46server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff]47server # [ 0.000000] Device empty48server # [ 0.000000] Movable zone start for each node49server # [ 0.000000] Early memory node ranges50server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff]51server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]52server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe00000053server # [ 0.000000] psci: probing for conduit method from DT.54server # [ 0.000000] psci: PSCIv1.1 detected in firmware.55server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs56server # [ 0.000000] psci: Trusted OS migration not required57server # [ 0.000000] psci: SMC Calling Convention v1.058server # [ 0.000000] percpu: Embedded 76 pages/cpu s186968 r8192 d116136 u31129659server # [ 0.000000] Detected PIPT I-cache on CPU060server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)61server # [ 0.000000] CPU features: detected: GICv3 CPU interface62server # [ 0.000000] CPU features: detected: HCRX_EL2 register63server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE)64server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)65server # [ 0.000000] CPU features: detected: Spectre-v466server # [ 0.000000] alternatives: applying boot alternatives67server # [ 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/2wcx9j5y8qaq1948nxc7r50visnfzf24-nixos-system-server-test/init regInfo=/nix/store/bpawy7wv3pq043ykspjqdvdnwis5yypl-closure-info/registration console=ttyAMA0,115200n8 console=tty068server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/bpawy7wv3pq043ykspjqdvdnwis5yypl-closure-info/registration", will be passed to user space.69server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes70server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)71server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)72server # [ 0.000000] software IO TLB: area num 2.73server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)74server # [ 0.000000] Fallback order for Node 0: 075server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 104857676server # [ 0.000000] Policy zone: Normal77server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off78server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=179server # [ 0.000000] allocated 8388608 bytes of page_ext80server # [ 0.000000] ftrace: allocating 74992 entries in 294 pages81server # [ 0.000000] ftrace: allocated 294 pages with 4 groups82server # [ 0.000000] rcu: Hierarchical RCU implementation.83server # [ 0.000000] rcu: RCU event tracing is enabled.84server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.85server # [ 0.000000] Trampoline variant of Tasks RCU enabled.86server # [ 0.000000] Rude variant of Tasks RCU enabled.87server # [ 0.000000] Tracing variant of Tasks RCU enabled.88server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.89server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=290server # [ 0.000000] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.91server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.92server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.93server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 094server # [ 0.000000] GICv3: 256 SPIs implemented95server # [ 0.000000] GICv3: 0 Extended SPIs implemented96server # [ 0.000000] Root IRQ handler: gic_handle_irq97server # [ 0.000000] GICv3: GICv3 features: 16 PPIs98server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=099server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000100server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]101server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100b00000 (indirect, esz 8, psz 64K, shr 1)102server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100b10000 (flat, esz 8, psz 64K, shr 1)103server # [ 0.000000] GICv3: using LPI property table @0x0000000100b20000104server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b30000105server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.106server # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns107server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).108server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns109server # [ 0.000050] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns110server # [ 0.004505] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)111server # [ 0.017012] Console: colour dummy device 80x25112server # [ 0.018428] printk: legacy console [tty0] enabled113server # [ 0.024975] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)114server # [ 0.025440] pid_max: default: 32768 minimum: 301115server # [ 0.028705] LSM: initializing lsm=capability,landlock,yama,bpf,ima116server # [ 0.031611] landlock: Up and running.117server # [ 0.031693] Yama: becoming mindful.118server # [ 0.034934] LSM support for eBPF active119server # [ 0.038711] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)120server # [ 0.038896] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)121server # [ 0.083379] rcu: Hierarchical SRCU implementation.122server # [ 0.083515] rcu: Max phase no-delay instances is 1000.123server # [ 0.085184] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level124server # [ 0.093759] fsl-mc MSI: its@8080000 domain created125server # [ 0.095726] EFI services will not be available.126server # [ 0.097644] smp: Bringing up secondary CPUs ...127server # [ 0.103085] Detected PIPT I-cache on CPU1128server # [ 0.104785] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000129server # [ 0.106041] GICv3: CPU1: using allocated LPI pending table @0x0000000100b40000130server # [ 0.107579] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]131server # [ 0.110985] smp: Brought up 1 node, 2 CPUs132server # [ 0.111165] SMP: Total of 2 processors activated.133server # [ 0.111217] CPU: All CPU(s) started at EL1134server # [ 0.111416] CPU features: detected: Branch Target Identification135server # [ 0.111466] CPU features: detected: 32-bit EL0 Support136server # [ 0.111496] CPU features: detected: 32-bit EL1 Support137server # [ 0.111522] CPU features: detected: ARMv8.4 Translation Table Level138server # [ 0.111555] CPU features: detected: Instruction cache invalidation not required for I/D coherence139server # [ 0.111599] CPU features: detected: Data cache clean to the PoU not required for I/D coherence140server # [ 0.111652] CPU features: detected: Common not Private translations141server # [ 0.111679] CPU features: detected: CRC32 instructions142server # [ 0.111703] CPU features: detected: Data cache clean to Point of Deep Persistence143server # [ 0.111732] CPU features: detected: Data cache clean to Point of Persistence144server # [ 0.111759] CPU features: detected: Data independent timing control (DIT)145server # [ 0.111785] CPU features: detected: E0PD146server # [ 0.111806] CPU features: detected: Enhanced Counter Virtualization147server # [ 0.111831] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)148server # [ 0.111858] CPU features: detected: Enhanced Privileged Access Never149server # [ 0.111883] CPU features: detected: Enhanced Virtualization Traps150server # [ 0.111908] CPU features: detected: Fine Grained Traps151server # [ 0.111932] CPU features: detected: Guarded Control Stack (GCS)152server # [ 0.111989] CPU features: detected: Generic authentication (IMP DEF algorithm)153server # [ 0.112070] CPU features: detected: RCpc load-acquire (LDAPR)154server # [ 0.112129] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)155server # [ 0.112166] CPU features: detected: LSE atomic instructions156server # [ 0.112196] CPU features: detected: Memory Copy and Memory Set instructions157server # [ 0.112229] CPU features: detected: Privileged Access Never158server # [ 0.112279] CPU features: detected: PMUv3159server # [ 0.112304] CPU features: detected: SCTLR2160server # [ 0.112328] CPU features: detected: RAS Extension Support161server # [ 0.112391] CPU features: detected: RASv1p1 Extension Support162server # [ 0.112418] CPU features: detected: Random Number Generator163server # [ 0.112448] CPU features: detected: Speculation barrier (SB)164server # [ 0.112483] CPU features: detected: Stage-2 Force Write-Back165server # [ 0.112519] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality166server # [ 0.112559] CPU features: detected: TLB range maintenance instructions167server # [ 0.112595] CPU features: detected: WFx with timeout168server # [ 0.112693] CPU features: detected: Scalable Matrix Extension169server # [ 0.112724] CPU features: detected: FA64170server # [ 0.112750] CPU features: detected: SME2171server # [ 0.112775] CPU features: detected: Speculative Store Bypassing Safe (SSBS)172server # [ 0.112807] CPU features: detected: Scalable Vector Extension173server # [ 0.120671] alternatives: applying system-wide alternatives174server # [ 0.136216] CPU features: detected: Hardware dirty bit management on CPU0-1175server # [ 0.136930] SVE: maximum available vector length 256 bytes per vector176server # [ 0.137014] SVE: default vector length 64 bytes per vector177server # [ 0.138219] SME: minimum available vector length 16 bytes per vector178server # [ 0.138271] SME: maximum available vector length 256 bytes per vector179server # [ 0.138310] SME: default vector length 32 bytes per vector180server # [ 0.157912] Memory: 3904132K/4194304K available (24384K kernel code, 7094K rwdata, 26604K rodata, 4736K init, 1103K bss, 241760K reserved, 32768K cma-reserved)181server # [ 0.184753] devtmpfs: initialized182server # [ 0.221713] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear)183server # [ 0.222100] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear).184server # [ 0.225442] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL185server # [ 0.225532] 0 pages in range for non-PLT usage186server # [ 0.225586] 508240 pages in range for PLT usage187server # [ 0.228956] pinctrl core: initialized pinctrl subsystem188server # [ 0.242151] DMI not present or invalid.189server # [ 0.264135] NET: Registered PF_NETLINK/PF_ROUTE protocol family190server # [ 0.277376] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations191server # [ 0.278396] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations192server # [ 0.279614] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations193server # [ 0.279932] audit: initializing netlink subsys (disabled)194server # [ 0.282533] audit: type=2000 audit(0.204:1): state=initialized audit_enabled=0 res=1195server # [ 0.288972] thermal_sys: Registered thermal governor 'fair_share'196server # [ 0.289039] thermal_sys: Registered thermal governor 'bang_bang'197server # [ 0.289106] thermal_sys: Registered thermal governor 'step_wise'198server # [ 0.289137] thermal_sys: Registered thermal governor 'user_space'199server # [ 0.289169] thermal_sys: Registered thermal governor 'power_allocator'200server # [ 0.289911] cpuidle: using governor ladder201server # [ 0.290334] cpuidle: using governor menu202server # [ 0.292405] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.203server # [ 0.293470] ASID allocator initialised with 65536 entries204server # [ 0.300959] Serial: AMBA PL011 UART driver205server # [ 0.360648] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1206server # [ 0.362821] printk: console [ttyAMA0] enabled207server # [ 0.403894] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages208server # [ 0.404136] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page209server # [ 0.404192] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages210server # [ 0.404232] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page211server # [ 0.404368] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages212server # [ 0.404414] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page213server # [ 0.404451] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages214server # [ 0.404486] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page215server # [ 0.426843] fbcon: Taking over console216server # [ 0.427144] ACPI: Interpreter disabled.217server # [ 0.435403] iommu: Default domain type: Translated218server # [ 0.435492] iommu: DMA domain TLB invalidation policy: strict mode219server # [ 0.442379] SCSI subsystem initialized220server # [ 0.448114] usbcore: registered new interface driver usbfs221server # [ 0.448428] usbcore: registered new interface driver hub222server # [ 0.448592] usbcore: registered new device driver usb223server # [ 0.453151] pps_core: LinuxPPS API ver. 1 registered224server # [ 0.453228] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>225server # [ 0.453336] PTP clock support registered226server # [ 0.453679] EDAC MC: Ver: 3.0.0227server # [ 0.458507] scmi_core: SCMI protocol bus registered228server # [ 0.462160] FPGA manager framework229server # [ 0.472770] vgaarb: loaded230server # [ 0.476654] clocksource: Switched to clocksource arch_sys_counter231server # [ 0.518531] VFS: Disk quotas dquot_6.6.0232server # [ 0.518742] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)233server # [ 0.523108] netfs: FS-Cache loaded234server # [ 0.524360] pnp: PnP ACPI: disabled235server # [ 0.552702] NET: Registered PF_INET protocol family236server # [ 0.555274] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)237server # [ 0.665747] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)238server # [ 0.666085] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)239server # [ 0.666244] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)240server # [ 0.666687] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)241server # [ 0.667035] TCP: Hash tables configured (established 32768 bind 32768)242server # [ 0.668317] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)243server # [ 0.668691] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)244server # [ 0.668940] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)245server # [ 0.671929] NET: Registered PF_UNIX/PF_LOCAL protocol family246server # [ 0.672382] NET: Registered PF_XDP protocol family247server # [ 0.672822] PCI: CLS 0 bytes, default 64248server # [ 0.677388] kvm [1]: HYP mode not available249server # [ 0.677710] Trying to unpack rootfs image as initramfs...250server # [ 0.908682] Initialise system trusted keyrings251server # [ 0.911578] workingset: timestamp_bits=42 max_order=20 bucket_order=0252server # [ 0.922961] squashfs: version 4.0 (2009/01/31) Phillip Lougher253server # [ 0.923685] 9p: Installing v9fs 9p2000 file system support254server # [ 1.006805] Key type asymmetric registered255server # [ 1.006968] Asymmetric key parser 'x509' registered256server # [ 1.007362] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)257server # [ 1.010396] io scheduler mq-deadline registered258server # [ 1.010517] io scheduler kyber registered259server # [ 1.056181] pl061_gpio 9030000.pl061: PL061 GPIO chip registered260server # [ 1.065453] ledtrig-cpu: registered to indicate activity on CPUs261server # [ 1.071116] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:262server # [ 1.071724] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000263server # [ 1.072435] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000264server # [ 1.072556] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000265server # [ 1.074125] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits266server # [ 1.075168] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]267server # [ 1.079089] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00268server # [ 1.079448] pci_bus 0000:00: root bus resource [bus 00-ff]269server # [ 1.079581] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]270server # [ 1.079646] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]271server # [ 1.079688] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]272server # [ 1.080873] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint273server # [ 1.087248] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint274server # [ 1.087507] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]275server # [ 1.087581] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]276server # [ 1.087641] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]277server # [ 1.087733] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]278server # [ 1.088250] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint279server # [ 1.088344] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]280server # [ 1.088384] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]281server # [ 1.088425] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282server # [ 1.089373] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint283server # [ 1.090100] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]284server # [ 1.090247] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]285server # [ 1.090923] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]286server # [ 1.093625] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287server # [ 1.093739] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]288server # [ 1.093907] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]289server # [ 1.094225] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290server # [ 1.097780] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291server # [ 1.098057] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]292server # [ 1.098105] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]293server # [ 1.098147] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]294server # [ 1.098655] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint295server # [ 1.098748] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]296server # [ 1.098795] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]297server # [ 1.098844] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]298server # [ 1.099335] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint299server # [ 1.099432] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]300server # [ 1.099470] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]301server # [ 1.099511] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302server # [ 1.099551] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]303server # [ 1.100015] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint304server # [ 1.100100] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]305server # [ 1.100141] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]306server # [ 1.100581] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint307server # [ 1.100676] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]308server # [ 1.100724] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]309server # [ 1.102893] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint310server # [ 1.103526] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]311server # [ 1.105404] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint312server # [ 1.106365] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]313server # [ 1.106425] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]314server # [ 1.106470] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]315server # [ 1.107021] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint316server # [ 1.107134] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]317server # [ 1.107181] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]318server # [ 1.107234] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]319server # [ 1.111712] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned320server # [ 1.111946] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned321server # [ 1.112085] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned322server # [ 1.112506] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned323server # [ 1.113500] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned324server # [ 1.113572] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned325server # [ 1.113635] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned326server # [ 1.113690] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned327server # [ 1.113744] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned328server # [ 1.113899] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned329server # [ 1.113965] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned330server # [ 1.114023] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned331server # [ 1.114308] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned332server # [ 1.114754] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned333server # [ 1.115460] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned334server # [ 1.115525] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned335server # [ 1.115590] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned336server # [ 1.115643] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned337server # [ 1.115688] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned338server # [ 1.115734] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned339server # [ 1.115783] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned340server # [ 1.115825] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned341server # [ 1.115871] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned342server # [ 1.115922] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned343server # [ 1.115975] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned344server # [ 1.116405] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned345server # [ 1.119233] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned346server # [ 1.119309] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned347server # [ 1.119356] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned348server # [ 1.119962] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned349server # [ 1.120041] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned350server # [ 1.120089] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned351server # [ 1.120143] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned352server # [ 1.120193] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned353server # [ 1.121392] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]354server # [ 1.121480] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]355server # [ 1.121520] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]356server # [ 1.125423] pci 0000:00:0a.0: enabling device (0000 -> 0002)357server # [ 1.225745] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)358server # [ 1.230727] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)359server # [ 1.233744] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)360server # [ 1.236315] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)361server # [ 1.243267] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)362server # [ 1.247456] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)363server # [ 1.253940] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)364server # [ 1.258587] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)365server # [ 1.263896] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)366server # [ 1.268331] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)367server # [ 1.271160] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)368server # [ 1.292652] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled369server # [ 1.305251] msm_serial: driver initialized370server # [ 1.307425] SuperH (H)SCI(F) driver initialized371server # [ 1.307905] STM32 USART driver initialized372server # [ 1.387710] loop: module loaded373server # [ 1.388349] virtio_blk virtio5: 2/0/0 default/read/poll queues374server # [ 1.393425] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)375server # [ 1.407742] megasas: 07.734.00.00-rc1376server # [ 1.414398] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]377server # [ 1.417316] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000378server # [ 1.417735] Intel/Sharp Extended Query Table at 0x0031379server # [ 1.419765] Using buffer write method380server # [ 1.420410] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]381server # [ 1.421516] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000382server # [ 1.422459] Intel/Sharp Extended Query Table at 0x0031383server # [ 1.424685] Using buffer write method384server # [ 1.425642] Concatenating MTD devices:385server # [ 1.425769] (0): "0.flash"386server # [ 1.425966] (1): "0.flash"387server # [ 1.426122] into device "0.flash"388server # [ 1.778158] Freeing initrd memory: 25348K389server # [ 1.900465] tun: Universal TUN/TAP device driver, 1.6390server # [ 1.915511] thunder_xcv, ver 1.0391server # [ 1.915735] thunder_bgx, ver 1.0392server # [ 1.915865] nicpf, ver 1.0393server # [ 1.920136] e1000: Intel(R) PRO/1000 Network Driver394server # [ 1.920207] e1000: Copyright (c) 1999-2006 Intel Corporation.395server # [ 1.920363] e1000e: Intel(R) PRO/1000 Network Driver396server # [ 1.920400] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.397server # [ 1.920558] igb: Intel(R) Gigabit Ethernet Network Driver398server # [ 1.920597] igb: Copyright (c) 2007-2014 Intel Corporation.399server # [ 1.920732] igbvf: Intel(R) Gigabit Virtual Function Network Driver400server # [ 1.920770] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.401server # [ 1.923047] sky2: driver version 1.30402server # [ 1.932637] ehci-pci 0000:00:0a.0: EHCI Host Controller403server # [ 1.934212] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1404server # [ 1.935200] usbcore: registered new interface driver usb-storage405server # [ 1.935756] usbcore: registered new interface driver usbserial_generic406server # [ 1.935956] usbserial: USB Serial support registered for generic407server # [ 1.936253] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000408server # [ 1.940665] hv_vmbus: registering driver hyperv_keyboard409server # [ 1.945461] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00410server # [ 1.947651] rtc-pl031 9010000.pl031: registered as rtc0411server # [ 1.948174] rtc-pl031 9010000.pl031: setting system clock to 2026-10-31T19:36:54 UTC (1793475414)412server # [ 1.950096] i2c_dev: i2c /dev entries driver413server # [ 1.961311] hub 1-0:1.0: USB hub found414server # [ 1.961968] hub 1-0:1.0: 6 ports detected415server # [ 1.971009] sdhci: Secure Digital Host Controller Interface driver416server # [ 1.971081] sdhci: Copyright(c) Pierre Ossman417server # [ 1.972625] Synopsys Designware Multimedia Card Interface Driver418server # [ 1.975102] sdhci-pltfm: SDHCI platform and OF driver helper419server # [ 1.985057] hid: raw HID events driver (C) Jiri Kosina420server # [ 1.986808] usbcore: registered new interface driver usbhid421server # [ 1.986871] usbhid: USB HID core driver422server # [ 1.998038] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available423server # [ 2.009004] drop_monitor: Initializing network drop monitor service424server # [ 2.010581] NET: Registered PF_INET6 protocol family425server # [ 2.019664] Segment Routing with IPv6426server # [ 2.019906] In-situ OAM (IOAM) with IPv6427server # [ 2.020436] NET: Registered PF_PACKET protocol family428server # [ 2.021441] 9pnet: Installing 9P2000 support429server # [ 2.028064] Key type dns_resolver registered430server # [ 2.072775] registered taskstats version 1431server # [ 2.076380] Loading compiled-in X.509 certificates432server # [ 2.144747] Demotion targets for Node 0: null433server # [ 2.145954] Key type .fscrypt registered434server # [ 2.146009] Key type fscrypt-provisioning registered435server # [ 2.148450] ima: No TPM chip found, activating TPM-bypass!436server # [ 2.148549] ima: Allocated hash algorithm: sha1437server # [ 2.150270] ima: No architecture policies found438server # [ 2.159780] input: gpio-keys as /devices/platform/gpio-keys/input/input0439server # [ 2.219840] usb 1-1: new high-speed USB device number 2 using ehci-pci440server # [ 2.238027] clk: Disabling unused clocks441server # [ 2.238295] PM: genpd: Disabling unused power domains442server # [ 2.444589] 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/input1443server # [ 2.514428] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0444server # [ 2.574848] Freeing unused kernel memory: 4736K445server # [ 2.576037] Run /init as init process446server # [ 2.640875] usb 1-2: new high-speed USB device number 3 using ehci-pci447server # [ 2.727724] systemd[1]: Successfully made /usr/ read-only.448server # [ 2.829400] 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/input2449server # [ 2.830271] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0450server # [ 3.079747] systemd[1]: systemd 260.5 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)451server # [ 3.079996] systemd[1]: Detected virtualization qemu.452server # [ 3.080403] systemd[1]: Detected architecture arm64.453server # [ 3.080475] systemd[1]: Running in initrd.454server # [ 3.090585] systemd[1]: Initializing machine ID from random generator.455server # [ 3.093377] systemd[1]: Hostname set to <server>.456server # [ 3.748660] systemd[1]: Queued start job for default target Initrd Default Target.457server # [ 3.798197] systemd[1]: Created slice Slice /system/modprobe.458server # [ 3.801461] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459server # [ 3.802194] systemd[1]: Expecting device /dev/disk/by-label/nixos...460server # [ 3.802550] systemd[1]: Reached target Path Units.461server # [ 3.802702] systemd[1]: Reached target Slice Units.462server # [ 3.802822] systemd[1]: Reached target Swaps.463server # [ 3.802955] systemd[1]: Reached target Timer Units.464server # [ 3.805685] systemd[1]: Listening on D-Bus System Message Bus Socket.465server # [ 3.808135] systemd[1]: Listening on Journal Socket (/dev/log).466server # [ 3.812038] systemd[1]: Listening on Journal Sockets.467server # [ 3.813309] systemd[1]: Listening on udev Control Socket.468server # [ 3.814021] systemd[1]: Listening on udev Kernel Socket.469server # [ 3.814233] systemd[1]: Reached target Socket Units.470server # [ 3.834479] systemd[1]: Starting Create List of Static Device Nodes...471server # [ 3.848879] systemd[1]: Starting Load Kernel Module 9pnet_virtio...472server # [ 3.849838] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs473server # [ 3.882688] systemd[1]: Mounting Kernel Configuration File System...474server # [ 3.962141] systemd[1]: Starting Journal Service...475server # [ 3.983224] systemd[1]: Starting Load Kernel Modules...476server # [ 3.984632] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki477server # [ 4.017241] systemd[1]: Starting Coldplug All udev Devices...478server # [ 4.090406] systemd[1]: Finished Create List of Static Device Nodes.479server # [ 4.110642] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480server # [ 4.113552] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481server # [ 4.125023] systemd[1]: Mounted Kernel Configuration File System.482server # [ 4.162603] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...483server # [ 4.267947] systemd-journald[81]: Collecting audit messages is disabled.484server # [ 4.404555] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.485server # [ 4.430861] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev486server # [ 4.484626] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0487server # [ 4.487349] [drm] features: -virgl +edid -resource_blob -host_visible488server # [ 4.487493] [drm] features: -context_init489server # [ 4.566303] [drm] number of scanouts: 1490server # [ 4.566627] [drm] number of cap sets: 0491server # [ 4.586618] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.492server # [ 4.607987] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic493server # [ 4.608180] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0494server # [ 4.690491] systemd[1]: Starting Create Static Device Nodes in /dev...495server # [ 4.741055] Console: switching to colour frame buffer device 160x50496server # [ 4.764010] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device497server # [ 4.827584] systemd[1]: Finished Load Kernel Modules.498server # [ 4.885924] systemd[1]: Starting Apply Kernel Variables...499server # [ 4.979177] systemd[1]: Finished Create Static Device Nodes in /dev.500server # [ 4.983685] systemd[1]: Reached target Preparation for Local File Systems.501server # [ 4.991746] systemd[1]: Reached target Local File Systems.502server # [ 5.055590] systemd[1]: Starting Rule-based Manager for Device Events and Files...503server # [ 5.199521] systemd[1]: Finished Apply Kernel Variables.504server # [ 5.424206] systemd[1]: Started Journal Service.505server # [ 5.315922] systemd-modules-load[82]: Using 2 probe threads506server # [ 5.339491] systemd-modules-load[82]: Module 'virtio_balloon' is built in507server # [ 5.372473] systemd-modules-load[82]: Module 'virtio_console' is built in508server # [ 5.380499] systemd-modules-load[82]: Inserted module 'dm_mod'509server # [ 5.388089] systemd-modules-load[82]: Module 'virtio_rng' is built in510server # [ 5.395852] systemd-modules-load[82]: Inserted module 'virtio_gpu'511server # [ 5.417933] systemd-udevd[89]: Using default interface naming scheme 'v260'.512server # [ 5.434638] systemd[1]: Starting Create System Files and Directories...513server # [ 5.619884] systemd[1]: Finished Create System Files and Directories.514server # [ 5.784440] systemd[1]: Started Rule-based Manager for Device Events and Files.515server # [ 6.253951] systemd[1]: Starting Virtual Console Setup...516server # [ 6.551887] systemd-vconsole-setup[111]: Configuration of first virtual console was skipped, ignoring remaining ones.517server # [ 6.583987] systemd[1]: Finished Virtual Console Setup.518server # [ 10.237336] systemd[1]: Finished Coldplug All udev Devices.519server # [ 10.246826] systemd[1]: Reached target System Initialization.520server # [ 10.254882] systemd[1]: Reached target Basic System.521server # [ 12.540083] (udev-worker)[118]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.522server # [ 12.554393] (udev-worker)[118]: Network interface NamePolicy= disabled on kernel command line.523server # [ 12.609774] (udev-worker)[119]: Network interface NamePolicy= disabled on kernel command line.524server # [ 12.967135] systemd[1]: Found device /dev/disk/by-label/nixos.525server # [ 13.024166] systemd[1]: Reached target Initrd Root Device.526server # [ 13.041551] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...527server # [ 13.315274] systemd-fsck[139]: nixos: clean, 12/65536 files, 13019/262144 blocks528server # [ 13.361468] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.529server # [ 13.407039] systemd[1]: Mounting /sysroot...530server # [ 13.773035] EXT4-fs (vda): mounted filesystem cee67440-90cc-43a0-befc-18cfcb0a8943 r/w with ordered data mode. Quota mode: none.531server # [ 13.713160] systemd[1]: Mounted /sysroot.532server # [ 13.728165] systemd[1]: Reached target Initrd Root File System.533server # [ 13.754573] systemd[1]: Starting Mountpoints Configured in the Real Root...534server # [ 13.907648] systemd-sysroot-fstab-check[147]: /sysroot should be mounted in the initrd, will request daemon-reload.535server # [ 13.938646] systemd[1]: Reload requested from client PID 147 ('systemd-sysroot') (unit initrd-parse-etc.service)...536server # [ 13.946614] systemd[1]: Reloading...537server # [ 15.103061] systemd[1]: Reloading finished in 1150 ms.538server # [ 15.155457] systemd-sysroot-fstab-check[147]: Requesting initrd-fs.target/start/replace...539server # [ 15.167998] systemd-sysroot-fstab-check[147]: Requesting swap.target/start/replace...540server # [ 15.199690] systemd[1]: Mounting /sysroot/nix/.rw-store...541server # [ 15.225565] systemd[1]: Mounting /sysroot/run...542server # [ 15.258311] systemd[1]: Starting Load Kernel Module 9pnet_virtio...543server # [ 15.278101] systemd[1]: initrd-parse-etc.service: Deactivated successfully.544server # [ 15.284197] systemd[1]: Finished Mountpoints Configured in the Real Root.545server # [ 15.287401] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.546server # [ 15.339579] systemd[1]: Mounted /sysroot/nix/.rw-store.547server # [ 15.383816] systemd[1]: Mounted /sysroot/run.548server # [ 15.387804] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.549server # [ 15.391410] systemd[1]: Finished Load Kernel Module 9pnet_virtio.550server # [ 15.432005] systemd[1]: Mounting /sysroot/nix/.ro-store...551server # [ 15.455312] systemd[1]: Mounting /sysroot/tmp/shared...552server # [ 15.493445] systemd[1]: Mounting /sysroot/tmp/xchg...553server # [ 15.520800] systemd[1]: Starting rw-sysroot-nix-store.service...554server # [ 15.649789] systemd[1]: Mounted /sysroot/nix/.ro-store.555server # [ 15.725969] systemd[1]: Mounted /sysroot/tmp/shared.556server # [ 15.728975] systemd[1]: Mounted /sysroot/tmp/xchg.557server # [ 15.732543] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.558server # [ 15.734763] systemd[1]: Finished rw-sysroot-nix-store.service.559server # [ 16.227588] systemd[1]: Mounting /sysroot/nix/store...560server # [ 16.601936] systemd[1]: Mounted /sysroot/nix/store.561server # [ 16.618015] systemd[1]: Reached target Initrd File Systems.562server # [ 16.641944] systemd[1]: Starting Find NixOS closure...563server # [ 16.672841] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...564server # [ 16.962931] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.565server # [ 16.995782] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.566server # [ 17.199404] systemd[1]: Finished Find NixOS closure.567server # [ 17.225905] systemd[1]: Reached target Initrd Default Target.568server # [ 17.247635] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...569server # [ 17.471779] systemd[1]: initrd-cleanup.service: Deactivated successfully.570server # [ 17.474658] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.571server # [ 17.491699] systemd[1]: Stopped target Initrd Default Target.572server # [ 17.497705] systemd[1]: Stopped target Basic System.573server # [ 17.503651] systemd[1]: Stopped target Initrd Root Device.574server # [ 17.510906] systemd[1]: Stopped target Path Units.575server # [ 17.517863] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.576server # [ 17.521565] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.577server # [ 17.525554] systemd[1]: Stopped target Slice Units.578server # [ 17.527639] systemd[1]: Stopped target Socket Units.579server # [ 17.529880] systemd[1]: Stopped target System Initialization.580server # [ 17.531577] systemd[1]: Stopped target Swaps.581server # [ 17.533633] systemd[1]: Stopped target Timer Units.582server # [ 17.535246] systemd[1]: dbus.socket: Deactivated successfully.583server # [ 17.539740] systemd[1]: Closed D-Bus System Message Bus Socket.584server # [ 17.543150] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.585server # [ 17.545697] systemd[1]: Stopped Find NixOS closure.586server # [ 17.548286] systemd[1]: Starting Load Kernel Module 9pnet_virtio...587server # [ 17.568998] systemd[1]: Starting rw-sysroot-nix-store.service...588server # [ 17.573944] systemd[1]: systemd-sysctl.service: Deactivated successfully.589server # [ 17.574953] systemd[1]: Stopped Apply Kernel Variables.590server # [ 17.577517] systemd[1]: systemd-modules-load.service: Deactivated successfully.591server # [ 17.579133] systemd[1]: Stopped Load Kernel Modules.592server # [ 17.588021] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.593server # [ 17.591639] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.594server # [ 17.595657] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.595server # [ 17.599693] systemd[1]: Stopped Create System Files and Directories.596server # [ 17.602406] systemd[1]: Stopped target Local File Systems.597server # [ 17.603751] systemd[1]: Stopped target Preparation for Local File Systems.598server # [ 17.607454] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.599server # [ 17.610172] systemd[1]: Stopped Coldplug All udev Devices.600server # [ 17.615343] systemd[1]: systemd-udev-trigger.service: Consumed 5.173s CPU time over 13.702s wall clock time, 9.5M memory peak.601server # [ 17.618842] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.602server # [ 17.622928] systemd[1]: Stopped Virtual Console Setup.603server # [ 17.733946] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.604server # [ 17.737846] systemd[1]: Finished rw-sysroot-nix-store.service.605server # [ 17.767465] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.606server # [ 17.777047] systemd[1]: Finished Load Kernel Module 9pnet_virtio.607server # [ 18.043055] systemd[1]: systemd-udevd.service: Deactivated successfully.608server # [ 18.048634] systemd[1]: Stopped Rule-based Manager for Device Events and Files.609server # [ 18.051604] systemd[1]: systemd-udevd.service: Consumed 9.242s CPU time over 13.112s wall clock time, 23.2M memory peak.610server # [ 18.061875] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.611server # [ 18.066477] systemd[1]: Closed udev Control Socket.612server # [ 18.084933] systemd[1]: Starting Cleanup udev Database...613server # [ 18.086614] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.614server # [ 18.088043] systemd[1]: Stopped Create Static Device Nodes in /dev.615server # [ 18.093108] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.616server # [ 18.095021] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.617server # [ 18.099318] systemd[1]: kmod-static-nodes.service: Deactivated successfully.618server # [ 18.102581] systemd[1]: Stopped Create List of Static Device Nodes.619server # [ 18.259347] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.620server # [ 18.262851] systemd[1]: Finished Cleanup udev Database.621server # [ 18.273817] systemd[1]: Reached target Switch Root.622server # [ 18.289147] systemd[1]: Starting NixOS Activation...623server # [ 20.107673] initrd-nixos-activation-start[219]: booting system configuration /nix/store/2wcx9j5y8qaq1948nxc7r50visnfzf24-nixos-system-server-test624server # [ 20.765989] initrd-nixos-activation-start[219]: running activation script...625server # [ 25.062304] initrd-nixos-activation-start[242]: setting up /etc...626server # [ 27.507091] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.627server # [ 27.510693] systemd[1]: Finished NixOS Activation.628server # [ 27.512810] systemd[1]: initrd-nixos-activation.service: Consumed 4.694s CPU time over 9.215s wall clock time, 22.6M memory peak.629server # [ 27.525070] systemd[1]: Starting Switch Root...630server # [ 27.656759] systemd[1]: Switching root.631server # [ 27.953924] systemd-journald[81]: Received SIGTERM from PID 1 (systemd).632server: Guest root shell did not produce any data yet...633server: To debug, enter the VM and run 'systemctl status backdoor.service'.634server # [ 30.178627] systemd[1]: systemd 260.5 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)635server # [ 30.179069] systemd[1]: Detected virtualization qemu.636server # [ 30.179412] systemd[1]: Detected architecture arm64.637server # [ 30.180085] systemd[1]: Detected first boot.638server # [ 30.211452] systemd[1]: Initializing machine ID from random generator.639server # [ 31.428904] systemd[1]: bpf-restrict-fs: LSM BPF program attached640server # [ 32.803400] systemd[1]: Applying preset policy.641server # [ 37.207780] systemd[1]: Populated /etc with preset unit settings.642server # [ 42.107001] systemd[1]: initrd-switch-root.service: Deactivated successfully.643server # [ 42.115123] systemd[1]: Stopped initrd-switch-root.service.644server # [ 42.139667] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.645server # [ 42.156120] systemd[1]: Created slice Slice /system/getty.646server # [ 42.171376] systemd[1]: Created slice User and Session Slice.647server # [ 42.178343] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.648server # [ 42.183771] systemd[1]: Started Forward Password Requests to Wall Directory Watch.649server # [ 42.188867] systemd[1]: Expecting device /dev/hvc0...650server # [ 42.191943] systemd[1]: Expecting device /dev/ttyAMA0...651server # [ 42.196850] systemd[1]: Reached target Local Encrypted Volumes.652server # [ 42.200360] systemd[1]: Stopped target initrd-fs.target.653server # [ 42.204678] systemd[1]: Stopped target initrd-root-fs.target.654server # [ 42.209303] systemd[1]: Stopped target initrd-switch-root.target.655server # [ 42.212968] systemd[1]: Reached target Virtual Machines and Containers.656server # [ 42.216958] systemd[1]: Reached target Path Units.657server # [ 42.219920] systemd[1]: Reached target Remote File Systems.658server # [ 42.225741] systemd[1]: Reached target Slice Units.659server # [ 42.228783] systemd[1]: Reached target Swaps.660server # [ 42.261955] systemd[1]: Listening on Process Core Dump Socket.661server # [ 42.290442] systemd[1]: Listening on Credential Encryption/Decryption.662server # [ 42.335903] systemd[1]: Starting Journal Log Access Socket...663server # [ 42.351615] systemd[1]: Listening on Journal Audit Socket.664server # [ 42.366205] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.665server # [ 42.373622] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki666server # [ 42.382192] systemd[1]: Listening on udev Control Socket.667server # [ 42.418392] systemd[1]: Mounting Huge Pages File System...668server # [ 42.473202] systemd[1]: Mounting POSIX Message Queue File System...669server # [ 42.542211] systemd[1]: Mounting Kernel Debug File System...670server # [ 42.671422] systemd[1]: Mounting Kernel Trace File System...671server # [ 42.739700] systemd[1]: Starting Create List of Static Device Nodes...672server # [ 42.802950] systemd[1]: Starting Load Kernel Module 9pnet_virtio...673server # [ 42.847232] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs674server # [ 43.038604] systemd[1]: Mounting Kernel Configuration File System...675server # [ 43.099875] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm676server # [ 43.121992] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore677server # [ 43.363864] systemd[1]: Starting Load Kernel Module fuse...678server # [ 43.373447] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67679server # [ 43.504495] systemd[1]: Starting Journal Service...680server # [ 43.671565] systemd[1]: Starting Load Kernel Modules...681server # [ 43.881272] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...682server # [ 44.103453] systemd[1]: Starting Remount Root and Kernel File Systems...683server # [ 44.138607] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki684server # [ 44.408282] systemd[1]: Starting Coldplug All udev Devices...685server # [ 44.647394] fuse: init (API version 7.45)686server # [ 44.712211] systemd-journald[313]: Collecting audit messages is enabled.687server # [ 44.803781] systemd[1]: Listening on Journal Log Access Socket.688server # [ 44.854911] systemd[1]: Mounted Huge Pages File System.689server # [ 44.874114] systemd[1]: Mounted POSIX Message Queue File System.690server # [ 44.887406] systemd[1]: Mounted Kernel Debug File System.691server # [ 44.922185] systemd[1]: Mounted Kernel Trace File System.692server # [ 44.986817] systemd[1]: Finished Create List of Static Device Nodes.693server # [ 45.051363] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.694server # [ 44.910085] systemd[1]: Queued start job for default target Multi-User System.695server # [ 44.939485] systemd[1]: systemd-journald.service: Deactivated successfully.696server # [ 45.066815] systemd[1]: Finished Load Kernel Module 9pnet_virtio.697server # [ 44.945763] systemd[1]: systemd-journald.service: Consumed 1.274s CPU time over 38.065s wall clock time, 9.9M memory peak.698server # [ 45.076085] systemd[1]: Started Journal Service.699server # [ 44.959126] systemd-modules-load[314]: Using 2 probe threads700server # [ 44.967407] systemd-modules-load[314]: Module 'atkbd' is built in701server # [ 44.976378] systemd-modules-load[314]: Module 'loop' is built in702server # [ 45.065789] systemd[1]: Mounted Kernel Configuration File System.703server # [ 45.080982] systemd[1]: modprobe@fuse.service: Deactivated successfully.704server # [ 45.086761] systemd[1]: Finished Load Kernel Module fuse.705server # [ 45.102198] systemd[1]: Finished Load Kernel Modules.706server # [ 45.349293] EXT4-fs (vda): re-mounted cee67440-90cc-43a0-befc-18cfcb0a8943.707server # [ 45.316032] systemd[1]: Finished Remount Root and Kernel File Systems.708server # [ 45.521293] systemd[1]: Mounting FUSE Control File System...709server # [ 45.609051] systemd[1]: Starting Firewall...710server # [ 45.727616] systemd[1]: Starting Flush Journal to Persistent Storage...711server # [ 45.736070] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore712server # [ 45.794606] systemd-oomd[316]: No swap; memory pressure usage will be degraded713server # [ 45.904764] systemd[1]: Starting Load/Save OS Random Seed...714server # [ 46.085244] systemd[1]: Starting Apply Kernel Variables...715server # [ 46.321481] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...716server # [ 46.332053] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki717server # [ 46.457276] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.718server # [ 46.632551] systemd-journald[313]: Received client request to flush runtime journal.719server # [ 47.445561] systemd[1]: Mounted FUSE Control File System.720server # [ 47.486952] systemd[1]: Finished Load/Save OS Random Seed.721server # [ 47.512369] systemd[1]: Reached target First Boot Complete.722server # [ 47.544064] systemd[1]: Finished Flush Journal to Persistent Storage.723server # [ 48.027349] systemd[1]: Finished Apply Kernel Variables.724server # [ 48.511968] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.725server # [ 48.605700] systemd[1]: Starting Create Static Device Nodes in /dev...726server # [ 49.514341] systemd[1]: Finished Create Static Device Nodes in /dev.727server # [ 49.529184] systemd[1]: Reached target Preparation for Local File Systems.728server # [ 49.584039] systemd[1]: Mounting /run/wrappers...729server # [ 49.669021] systemd[1]: Starting Rule-based Manager for Device Events and Files...730server # [ 50.236977] systemd[1]: Mounted /run/wrappers.731server # [ 50.288331] systemd[1]: Reached target Local File Systems.732server # [ 50.361945] systemd[1]: Listening on Boot Loader Control Service Socket.733server # [ 50.439732] systemd[1]: Starting register-nix-paths.service...734server # [ 50.545088] systemd[1]: Starting Create SUID/SGID Wrappers...735server # [ 50.550921] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.736server # [ 50.742415] systemd[1]: Starting Save Transient machine-id to Disk...737server # [ 51.001911] systemd[1]: Starting Create System Files and Directories...738server # [ 52.318765] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.739server # [ 52.373852] systemd[1]: Finished Save Transient machine-id to Disk.740server # [ 54.378705] systemd[1]: Finished Create System Files and Directories.741server # [ 54.610606] systemd[1]: Starting Rebuild Journal Catalog...742server # [ 54.863456] systemd[1]: Starting Record System Boot/Shutdown in UTMP...743server # [ 56.385768] systemd[1]: Finished Record System Boot/Shutdown in UTMP.744server # [ 57.983932] systemd[1]: Finished Rebuild Journal Catalog.745server # [ 58.142268] systemd[1]: Starting Update is Completed...746server: Guest root shell did not produce any data yet...747server: To debug, enter the VM and run 'systemctl status backdoor.service'.748server # [ 59.087982] systemd[1]: Finished Update is Completed.749server # [ 63.411385] systemd-udevd[363]: Using default interface naming scheme 'v260'.750server # [ 64.029957] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.751server # [ 64.065005] systemd[1]: Finished Create SUID/SGID Wrappers.752server # [ 64.085408] systemd[1]: suid-sgid-wrappers.service: Consumed 3.290s CPU time over 13.509s wall clock time, 5.3M memory peak.753server # [ 65.624021] systemd[1]: Started Rule-based Manager for Device Events and Files.754server # [ 67.490706] systemd[1]: Finished Firewall.755server # [ 67.941048] systemd[1]: Finished register-nix-paths.service.756server # [ 70.634274] systemd[1]: Finished Coldplug All udev Devices.757server # [ 70.641086] systemd[1]: Reached target System Initialization.758server # [ 70.653251] systemd[1]: Started Discard unused filesystem blocks once a week.759server # [ 70.657689] systemd[1]: Started Daily Cleanup of Temporary Directories.760server # [ 70.662076] systemd[1]: Reached target Timer Units.761server # [ 70.669965] systemd[1]: Listening on D-Bus System Message Bus Socket.762server # [ 70.673930] systemd[1]: Listening on Nix Daemon Socket.763server # [ 70.683193] systemd[1]: Listening on Hostname Service Socket.764server # [ 70.691123] systemd[1]: Reached target Socket Units.765server # [ 70.698479] systemd[1]: Reached target Basic System.766server # [ 70.711063] systemd[1]: Starting Import lastlog data into lastlog2 database...767server # [ 70.791994] systemd[1]: Starting Name Service Cache Daemon (nsncd)...768server # [ 70.892527] systemd[1]: Starting Post-Boot Actions...769server # [ 71.133947] systemd[1]: Started Reset console on configuration changes.770server # [ 71.351261] systemd[1]: Starting resolvconf update...771server # [ 72.002884] systemd[1]: Finished Post-Boot Actions.772server # [ 72.005838] systemd[1]: Started Name Service Cache Daemon (nsncd).773server # [ 72.060145] nsncd[570]: Oct 31 19:38:04.668 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"774server # [ 72.169756] systemd[1]: Reached target Host and Network Name Lookups.775server # [ 72.193876] systemd[1]: Reached target User and Group Name Lookups.776server # [ 72.383229] systemd[1]: Starting D-Bus System Message Bus...777server # [ 72.685652] systemd[1]: Starting User Login Management...778server # [ 72.836089] systemd[1]: Finished Import lastlog data into lastlog2 database.779server # [ 74.170221] dbus-broker-launch[588]: Looking up NSS user entry for 'systemd-timesync'...780server # [ 74.537948] dbus-broker-launch[588]: NSS returned no entry for 'systemd-timesync'781server # [ 74.553265] dbus-broker-launch[588]: Invalid user-name in /nix/store/vwdpn7pp7wk0r49xcqdysw2qqzsvv94q-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"782server # [ 74.703858] systemd-logind[590]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)783server # [ 74.731373] systemd-logind[590]: New seat seat0.784server # [ 74.809826] systemd[1]: Started User Login Management.785server # [ 74.866315] systemd[1]: Starting linger-users.service...786server # [ 74.966109] systemd[1]: Started D-Bus System Message Bus.787server # [ 75.717123] systemd[1]: linger-users.service: Deactivated successfully.788server # [ 75.772974] dbus-broker-launch[588]: Ready789server # [ 75.781983] systemd[1]: Finished linger-users.service.790server # [ 76.047018] systemd[1]: Stopped target Host and Network Name Lookups.791server # [ 76.073005] systemd[1]: Stopping Host and Network Name Lookups...792server # [ 76.081990] systemd[1]: Stopped target User and Group Name Lookups.793server # [ 76.092751] systemd[1]: Stopping User and Group Name Lookups...794server # [ 76.108226] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...795server # [ 76.134858] systemd[1]: nscd.service: Deactivated successfully.796server # [ 76.159318] systemd[1]: Stopped Name Service Cache Daemon (nsncd).797server # [ 76.392259] systemd[1]: Starting Name Service Cache Daemon (nsncd)...798server # [ 77.206319] systemd[1]: Started Name Service Cache Daemon (nsncd).799server # [ 77.218892] nsncd[651]: Oct 31 19:38:09.862 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"800server # [ 77.239200] systemd[1]: Reached target Host and Network Name Lookups.801server # [ 77.259739] systemd[1]: Reached target User and Group Name Lookups.802server # [ 77.611695] systemd[1]: Finished resolvconf update.803server # [ 77.668094] systemd[1]: Reached target Preparation for Network.804server # [ 77.754822] systemd[1]: Starting DHCP Client...805server # [ 77.821054] systemd[1]: Starting Extra networking commands....806server # [ 80.545082] dhcpcd[695]: dhcpcd-10.3.1 starting807server # [ 80.785354] dhcpcd[748]: dev: loaded udev808server # [ 80.927236] dhcpcd[748]: no valid interfaces found809server # [ 80.935414] dhcpcd[748]: no valid interfaces found810server # [ 80.939157] dhcpcd[748]: libudev: received NULL device811server # [ 80.942787] dhcpcd[748]: libudev: received NULL device812server # [ 81.351447] systemd[1]: Finished Extra networking commands..813server # [ 81.363880] systemd[1]: Reached target Network.814server # [ 81.393578] systemd[1]: Starting PostgreSQL Database Server...815server # [ 81.438353] systemd[1]: Starting Permit User Sessions...816server # [ 81.971512] systemd[1]: Finished Permit User Sessions.817server # [ 82.028849] systemd[1]: Started Getty on tty1.818server # [ 82.044328] systemd[1]: Reached target Login Prompts.819server # [ 82.842949] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[771]: Initializing database at /var/lib/postgresql/data820server # [ 84.385795] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: The files belonging to this database system will be owned by user "postgres".821server # [ 84.394581] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: This user must also own the server process.822server # [ 84.467904] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: The database cluster will be initialized with locale "en_US.UTF-8".823server # [ 84.472792] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: The default database encoding has accordingly been set to "UTF8".824server # [ 84.482087] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: The default text search configuration will be set to "english".825server # [ 84.486517] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: Data page checksums are enabled.826server # [ 84.497171] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: fixing permissions on existing directory /var/lib/postgresql/data ... ok827server # [ 84.502654] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: creating subdirectories ... ok828server # [ 84.511787] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: selecting dynamic shared memory implementation ... posix829server # [ 85.774984] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: selecting default max_connections ... 100830server # [ 86.393139] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: selecting default shared_buffers ... 128MB831server: Guest root shell did not produce any data yet...832server: To debug, enter the VM and run 'systemctl status backdoor.service'.833server # [ 97.180187] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: selecting default time zone ... UTC834server # [ 97.245703] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: creating configuration files ... ok835server # [ 101.226951] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: running bootstrap script ... ok836server # [ 110.979385] dhcpcd[748]: timed out837server # [ 110.991710] dhcpcd[748]: timed out838server # [ 111.057853] systemd[1]: Started DHCP Client.839server # [ 111.075010] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: performing post-bootstrap initialization ... ok840server # [ 114.178619] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: syncing data to disk ... ok841server # [ 114.191127] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: initdb: warning: enabling "trust" authentication for local connections842server # [ 114.195431] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: 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.843server # [ 114.204312] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: Success. You can now start the database server using:844server # [ 114.209256] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[787]: /nix/store/as2nds8lpb4qfzhl3yshr0z6dkjwy99z-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start845server # [ 115.614248] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[771]: Validating PostgreSQL configuration...846server # [ 116.250635] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[1019]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault847server # [ 117.260507] postgres[1030]: 2026-10-31 19:38:49.924 UTC [1030] LOG: pgaudit extension initialized848server # [ 117.872079] postgres[1030]: 2026-10-31 19:38:50.547 UTC [1030] LOG: pgsodium primary server secret key loaded849server # [ 118.410164] postgres[1030]: 2026-10-31 19:38:51.078 UTC [1030] LOG: vault primary server secret key loaded850server: Guest root shell did not produce any data yet...851server: To debug, enter the VM and run 'systemctl status backdoor.service'.852server # [ 118.996667] postgres[1030]: 2026-10-31 19:38:51.668 UTC [1030] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.3.0, 64-bit853server # [ 119.017045] postgres[1030]: 2026-10-31 19:38:51.690 UTC [1030] LOG: listening on IPv4 address "0.0.0.0", port 5432854server # [ 119.022022] postgres[1030]: 2026-10-31 19:38:51.691 UTC [1030] LOG: listening on IPv6 address "::", port 5432855server # [ 119.043584] postgres[1030]: 2026-10-31 19:38:51.719 UTC [1030] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"856server # [ 119.167425] postgres[1058]: 2026-10-31 19:38:51.832 UTC [1058] LOG: database system was shut down at 2026-10-31 19:38:43 UTC857server # [ 119.448869] postgres[1061]: 2026-10-31 19:38:52.098 UTC [1061] LOG: TimescaleDB background worker launcher connected to shared catalogs858server # [ 119.489653] postgres[1030]: 2026-10-31 19:38:52.100 UTC [1030] LOG: database system is ready to accept connections859server # [ 119.523103] postgres[1062]: 2026-10-31 19:38:52.119 UTC [1062] FATAL: role "postgres" does not exist860server # [ 119.543368] systemd[1]: Started PostgreSQL Database Server.861server # [ 119.555932] postgres[1030]: 2026-10-31 19:38:52.226 UTC [1030] LOG: background worker "pg_net 0.20.4 worker" (PID 1062) exited with exit code 1862server # [ 119.629571] systemd[1]: Starting Supabase Database Initialization...863server # [ 119.669202] postgres[1063]: 2026-10-31 19:38:52.334 UTC [1063] LOG: pg_cron scheduler started864server # [ 120.027757] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Waiting for PostgreSQL to be ready...865server # [ 120.606077] postgres[1069]: ::1 2026-10-31 19:38:53.253 UTC [1069] root@root FATAL: role "root" does not exist866server # [ 120.616117] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: PostgreSQL is ready867server # [ 120.638058] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Creating postgres role...868server # [ 120.685627] postgres[1070]: 2026-10-31 19:38:53.303 UTC [1070] FATAL: role "postgres" does not exist869server # [ 120.710502] postgres[1030]: 2026-10-31 19:38:53.381 UTC [1030] LOG: background worker "pg_net 0.20.4 worker" (PID 1070) exited with exit code 1870server # [ 121.085275] postgres[1072]: ::1 2026-10-31 19:38:53.739 UTC [1072] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN;871server # [ 121.110210] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1071]: CREATE ROLE872server # [ 121.505974] postgres[1074]: ::1 2026-10-31 19:38:54.145 UTC [1074] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres;873server # [ 121.544486] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1073]: ALTER DATABASE874server # [ 121.629877] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000000-initial-schema.sql875server # [ 122.079236] postgres[1086]: ::1 2026-10-31 19:38:54.746 UTC [1086] postgres@postgres LOG: statement: create publication supabase_realtime;876server # [ 122.112119] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE PUBLICATION877server # [ 122.121834] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER ROLE878server # [ 122.123242] postgres[1086]: ::1 2026-10-31 19:38:54.786 UTC [1086] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls;879server # [ 122.130701] postgres[1086]: ::1 2026-10-31 19:38:54.798 UTC [1086] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication;880server # [ 122.138324] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE ROLE881server # [ 122.151950] postgres[1086]: ::1 2026-10-31 19:38:54.817 UTC [1086] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls;882server # [ 122.182584] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE ROLE883server # [ 122.193120] postgres[1086]: ::1 2026-10-31 19:38:54.868 UTC [1086] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin;884server # [ 122.209998] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT ROLE885server # [ 122.214823] postgres[1086]: ::1 2026-10-31 19:38:54.890 UTC [1086] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin;886server # [ 122.225355] postgres[1086]: ::1 2026-10-31 19:38:54.901 UTC [1086] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls;887server # [ 122.227796] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT888server # [ 122.239897] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE ROLE889server # [ 122.245885] postgres[1086]: ::1 2026-10-31 19:38:54.916 UTC [1086] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user;890server # [ 122.256872] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT ROLE891server # [ 122.266306] postgres[1086]: ::1 2026-10-31 19:38:54.938 UTC [1086] postgres@postgres LOG: statement: create schema if not exists extensions;892server # [ 122.283449] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE SCHEMA893server # [ 122.293184] postgres[1086]: ::1 2026-10-31 19:38:54.968 UTC [1086] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions;894server # [ 124.987631] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE EXTENSION895server # [ 125.003602] postgres[1086]: ::1 2026-10-31 19:38:57.659 UTC [1086] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions;896server # [ 126.161585] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE EXTENSION897server # [ 126.174958] postgres[1086]: ::1 2026-10-31 19:38:58.818 UTC [1086] postgres@postgres LOG: statement: create role anon nologin noinherit;898server # [ 126.185588] postgres[1086]: ::1 2026-10-31 19:38:58.827 UTC [1086] postgres@postgres LOG: statement: create role authenticated nologin noinherit;899server # [ 126.190486] postgres[1086]: ::1 2026-10-31 19:38:58.837 UTC [1086] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls;900server # [ 126.197010] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE ROLE901server # [ 126.199785] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE ROLE902server # [ 126.201756] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE ROLE903server # [ 126.202894] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: CREATE ROLE904server # [ 126.205156] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT ROLE905server # [ 126.207415] postgres[1086]: ::1 2026-10-31 19:38:58.848 UTC [1086] postgres@postgres LOG: statement: create user authenticator noinherit;906server # [ 126.210273] postgres[1086]: ::1 2026-10-31 19:38:58.859 UTC [1086] postgres@postgres LOG: statement: grant anon to authenticator;907server # [ 126.212236] postgres[1086]: ::1 2026-10-31 19:38:58.867 UTC [1086] postgres@postgres LOG: statement: grant authenticated to authenticator;908server # [ 126.214560] postgres[1086]: ::1 2026-10-31 19:38:58.876 UTC [1086] postgres@postgres LOG: statement: grant service_role to authenticator;909server # [ 126.216174] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT ROLE910server # [ 126.217818] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT ROLE911server # [ 126.219846] postgres[1086]: ::1 2026-10-31 19:38:58.888 UTC [1086] postgres@postgres LOG: statement: grant supabase_admin to authenticator;912server # [ 126.224259] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT ROLE913server # [ 126.226501] postgres[1086]: ::1 2026-10-31 19:38:58.900 UTC [1086] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role;914server # [ 126.234977] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT915server # [ 126.243532] postgres[1086]: ::1 2026-10-31 19:38:58.913 UTC [1086] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;916server # [ 126.258774] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER DEFAULT PRIVILEGES917server # [ 126.267115] postgres[1086]: ::1 2026-10-31 19:38:58.939 UTC [1086] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;918server # [ 126.275792] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER DEFAULT PRIVILEGES919server # [ 126.282064] postgres[1086]: ::1 2026-10-31 19:38:58.955 UTC [1086] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;920server # [ 126.294074] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER DEFAULT PRIVILEGES921server # [ 126.299289] postgres[1086]: ::1 2026-10-31 19:38:58.968 UTC [1086] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role;922server # [ 126.304798] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: GRANT923server # [ 126.314422] postgres[1086]: ::1 2026-10-31 19:38:58.985 UTC [1086] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions;924server # [ 126.320852] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER ROLE925server # [ 126.322297] postgres[1086]: ::1 2026-10-31 19:38:58.996 UTC [1086] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all926server # [ 126.326760] postgres[1086]: on sequences to postgres, anon, authenticated, service_role;927server # [ 126.334699] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER DEFAULT PRIVILEGES928server # [ 126.336340] postgres[1086]: ::1 2026-10-31 19:38:59.011 UTC [1086] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all929server # [ 126.339421] postgres[1086]: on tables to postgres, anon, authenticated, service_role;930server # [ 126.345207] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER DEFAULT PRIVILEGES931server # [ 126.347688] postgres[1086]: ::1 2026-10-31 19:38:59.023 UTC [1086] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all932server # [ 126.350431] postgres[1086]: on functions to postgres, anon, authenticated, service_role;933server # [ 126.355382] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER DEFAULT PRIVILEGES934server # [ 126.361582] postgres[1086]: ::1 2026-10-31 19:38:59.035 UTC [1086] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s';935server # [ 126.367499] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER ROLE936server # [ 126.373033] postgres[1086]: ::1 2026-10-31 19:38:59.045 UTC [1086] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s';937server # [ 126.378882] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1079]: ALTER ROLE938server # [ 126.440056] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000001-auth-schema.sql939server # [ 126.709953] postgres[1119]: ::1 2026-10-31 19:38:59.375 UTC [1119] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;940server # [ 126.727935] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE SCHEMA941server # [ 126.734005] postgres[1119]: ::1 2026-10-31 19:38:59.401 UTC [1119] postgres@postgres LOG: statement: CREATE TABLE auth.users (942server # [ 126.735991] postgres[1119]: instance_id uuid NULL,943server # [ 126.739376] postgres[1119]: id uuid NOT NULL UNIQUE,944server # [ 126.742115] postgres[1119]: aud varchar(255) NULL,945server # [ 126.743269] postgres[1119]: "role" varchar(255) NULL,946server # [ 126.745334] postgres[1119]: email varchar(255) NULL UNIQUE,947server # [ 126.747521] postgres[1119]: encrypted_password varchar(255) NULL,948server # [ 126.750817] postgres[1119]: confirmed_at timestamptz NULL,949server # [ 126.753978] postgres[1119]: invited_at timestamptz NULL,950server # [ 126.759306] postgres[1119]: confirmation_token varchar(255) NULL,951server # [ 126.761877] postgres[1119]: confirmation_sent_at timestamptz NULL,952server # [ 126.764945] postgres[1119]: recovery_token varchar(255) NULL,953server # [ 126.766699] postgres[1119]: recovery_sent_at timestamptz NULL,954server # [ 126.770189] postgres[1119]: email_change_token varchar(255) NULL,955server # [ 126.773852] postgres[1119]: email_change varchar(255) NULL,956server # [ 126.775037] postgres[1119]: email_change_sent_at timestamptz NULL,957server # [ 126.775918] postgres[1119]: last_sign_in_at timestamptz NULL,958server # [ 126.777913] postgres[1119]: raw_app_meta_data jsonb NULL,959server # [ 126.779769] postgres[1119]: raw_user_meta_data jsonb NULL,960server # [ 126.782041] postgres[1119]: is_super_admin bool NULL,961server # [ 126.782969] postgres[1119]: created_at timestamptz NULL,962server # [ 126.783832] postgres[1119]: updated_at timestamptz NULL,963server # [ 126.785948] postgres[1119]: CONSTRAINT users_pkey PRIMARY KEY (id)964server # [ 126.787292] postgres[1119]: );965server # [ 126.887415] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE TABLE966server # [ 126.894783] postgres[1119]: ::1 2026-10-31 19:38:59.565 UTC [1119] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);967server # [ 126.921093] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE INDEX968server # [ 126.926042] postgres[1119]: ::1 2026-10-31 19:38:59.597 UTC [1119] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);969server # [ 126.946787] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE INDEX970server # [ 126.953630] postgres[1119]: ::1 2026-10-31 19:38:59.623 UTC [1119] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';971server # [ 126.966304] postgres[1119]: ::1 2026-10-31 19:38:59.637 UTC [1119] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens (972server # [ 126.969573] postgres[1119]: instance_id uuid NULL,973server # [ 126.970914] postgres[1119]: id bigserial NOT NULL,974server # [ 126.971753] postgres[1119]: "token" varchar(255) NULL,975server # [ 126.974054] postgres[1119]: user_id varchar(255) NULL,976server # [ 126.976011] postgres[1119]: revoked bool NULL,977server # [ 126.978216] postgres[1119]: created_at timestamptz NULL,978server # [ 126.979643] postgres[1119]: updated_at timestamptz NULL,979server # [ 126.981949] postgres[1119]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)980server # [ 126.983962] postgres[1119]: );981server # [ 126.986215] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: COMMENT982server # [ 127.083188] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE TABLE983server # [ 127.090006] postgres[1119]: ::1 2026-10-31 19:38:59.760 UTC [1119] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);984server # [ 127.112921] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE INDEX985server # [ 127.129743] postgres[1119]: ::1 2026-10-31 19:38:59.792 UTC [1119] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);986server # [ 127.161562] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE INDEX987server # [ 127.163547] postgres[1119]: ::1 2026-10-31 19:38:59.839 UTC [1119] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);988server # [ 127.217728] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE INDEX989server # [ 127.219895] postgres[1119]: ::1 2026-10-31 19:38:59.893 UTC [1119] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';990server # [ 127.227996] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: COMMENT991server # [ 127.230184] postgres[1119]: ::1 2026-10-31 19:38:59.905 UTC [1119] postgres@postgres LOG: statement: CREATE TABLE auth.instances (992server # [ 127.237344] postgres[1119]: id uuid NOT NULL,993server # [ 127.238852] postgres[1119]: uuid uuid NULL,994server # [ 127.239638] postgres[1119]: raw_base_config text NULL,995server # [ 127.240550] postgres[1119]: created_at timestamptz NULL,996server # [ 127.246511] postgres[1119]: updated_at timestamptz NULL,997server # [ 127.254226] postgres[1119]: CONSTRAINT instances_pkey PRIMARY KEY (id)998server # [ 127.261686] postgres[1119]: );999server # [ 127.315047] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE TABLE1000server # [ 127.329696] postgres[1119]: ::1 2026-10-31 19:39:00.003 UTC [1119] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';1001server # [ 127.365354] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: COMMENT1002server # [ 127.377716] postgres[1119]: ::1 2026-10-31 19:39:00.039 UTC [1119] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries (1003server # [ 127.378967] postgres[1119]: instance_id uuid NULL,1004server # [ 127.384722] postgres[1119]: id uuid NOT NULL,1005server # [ 127.387303] postgres[1119]: payload json NULL,1006server # [ 127.393383] postgres[1119]: created_at timestamptz NULL,1007server # [ 127.401251] postgres[1119]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)1008server # [ 127.402884] postgres[1119]: );1009server # [ 127.498872] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE TABLE1010server # [ 127.506932] postgres[1119]: ::1 2026-10-31 19:39:00.170 UTC [1119] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);1011server # [ 127.549075] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE INDEX1012server # [ 127.568961] postgres[1119]: ::1 2026-10-31 19:39:00.239 UTC [1119] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';1013server # [ 127.592679] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: COMMENT1014server # [ 127.601575] postgres[1119]: ::1 2026-10-31 19:39:00.275 UTC [1119] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations (1015server # [ 127.613327] postgres[1119]: "version" varchar(255) NOT NULL,1016server # [ 127.624082] postgres[1119]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")1017server # [ 127.639908] postgres[1119]: );1018server # [ 127.695034] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE TABLE1019server # [ 127.714244] postgres[1119]: ::1 2026-10-31 19:39:00.376 UTC [1119] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';1020server # [ 127.749012] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: COMMENT1021server # [ 127.772887] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: INSERT 0 71022server # [ 127.785002] postgres[1119]: ::1 2026-10-31 19:39:00.457 UTC [1119] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$1023server # [ 127.789371] postgres[1119]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;1024server # [ 127.793381] postgres[1119]: $$ language sql stable;1025server # [ 127.794949] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE FUNCTION1026server # [ 127.796368] postgres[1119]: ::1 2026-10-31 19:39:00.472 UTC [1119] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$1027server # [ 127.797970] postgres[1119]: select nullif(current_setting('request.jwt.claim.role', true), '')::text;1028server # [ 127.798810] postgres[1119]: $$ language sql stable;1029server # [ 127.818455] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE FUNCTION1030server # [ 127.820806] postgres[1119]: ::1 2026-10-31 19:39:00.492 UTC [1119] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$1031server # [ 127.822427] postgres[1119]: select nullif(current_setting('request.jwt.claim.email', true), '')::text;1032server # [ 127.829121] postgres[1119]: $$ language sql stable;1033server # [ 127.830500] postgres[1119]: ::1 2026-10-31 19:39:00.506 UTC [1119] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;1034server # [ 127.833785] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE FUNCTION1035server # [ 127.838767] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: GRANT1036server # [ 127.840356] postgres[1119]: ::1 2026-10-31 19:39:00.515 UTC [1119] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1037server # [ 127.847188] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: CREATE ROLE1038server # [ 127.848005] postgres[1119]: ::1 2026-10-31 19:39:00.524 UTC [1119] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1039server # [ 127.859163] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: GRANT1040server # [ 127.866027] postgres[1119]: ::1 2026-10-31 19:39:00.535 UTC [1119] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1041server # [ 127.873277] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: GRANT1042server # [ 127.881094] postgres[1119]: ::1 2026-10-31 19:39:00.548 UTC [1119] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1043server # [ 127.883197] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: GRANT1044server # [ 127.887842] postgres[1119]: ::1 2026-10-31 19:39:00.557 UTC [1119] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth";1045server # [ 127.898745] postgres[1119]: ::1 2026-10-31 19:39:00.569 UTC [1119] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin;1046server # [ 127.901693] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: ALTER ROLE1047server # [ 127.918792] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: ALTER TABLE1048server # [ 127.920048] postgres[1119]: ::1 2026-10-31 19:39:00.593 UTC [1119] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;1049server # [ 127.939751] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: ALTER TABLE1050server # [ 127.946849] postgres[1119]: ::1 2026-10-31 19:39:00.622 UTC [1119] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;1051server # [ 127.960696] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: ALTER TABLE1052server # [ 127.962733] postgres[1119]: ::1 2026-10-31 19:39:00.634 UTC [1119] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;1053server # [ 127.970142] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: ALTER TABLE1054server # [ 127.977893] postgres[1119]: ::1 2026-10-31 19:39:00.650 UTC [1119] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;1055server # [ 128.005964] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1118]: ALTER TABLE1056server # [ 128.078325] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000002-storage-schema.sql1057server # [ 128.421930] postgres[1141]: ::1 2026-10-31 19:39:01.088 UTC [1141] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;1058server # [ 128.441518] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1140]: CREATE SCHEMA1059server # [ 128.443100] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1140]: CREATE ROLE1060server # [ 128.445631] postgres[1141]: ::1 2026-10-31 19:39:01.105 UTC [1141] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1061server # [ 128.450594] postgres[1141]: ::1 2026-10-31 19:39:01.114 UTC [1141] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage";1062server # [ 128.456407] postgres[1141]: ::1 2026-10-31 19:39:01.124 UTC [1141] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;1063server # [ 128.462692] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1140]: ALTER ROLE1064server # [ 128.467221] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1140]: GRANT1065server # [ 128.498622] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1140]: DO1066server # [ 128.545500] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000003-post-setup.sql1067server # [ 128.827545] postgres[1143]: ::1 2026-10-31 19:39:01.493 UTC [1143] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;1068server # [ 128.844217] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: ALTER ROLE1069server # [ 128.854616] postgres[1143]: ::1 2026-10-31 19:39:01.522 UTC [1143] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;1070server # [ 128.861786] postgres[1143]: ::1 2026-10-31 19:39:01.537 UTC [1143] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()1071server # [ 128.863144] postgres[1143]: RETURNS event_trigger1072server # [ 128.868714] postgres[1143]: LANGUAGE plpgsql1073server # [ 128.870970] postgres[1143]: AS $$1074server # [ 128.871913] postgres[1143]: DECLARE1075server # [ 128.878407] postgres[1143]: schema_is_cron bool;1076server # [ 128.881567] postgres[1143]: BEGIN1077server # [ 128.885899] postgres[1143]: schema_is_cron = (1078server # [ 128.889483] postgres[1143]: SELECT n.nspname = 'cron'1079server # [ 128.893964] postgres[1143]: FROM pg_event_trigger_ddl_commands() AS ev1080server # [ 128.899160] postgres[1143]: LEFT JOIN pg_catalog.pg_namespace AS n1081server # [ 128.904233] postgres[1143]: ON ev.objid = n.oid1082server # [ 128.910035] postgres[1143]: );1083server # [ 128.917851] postgres[1143]:1084server # [ 128.921789] postgres[1143]: IF schema_is_cron1085server # [ 128.923069] postgres[1143]: THEN1086server # [ 128.930949] postgres[1143]: grant usage on schema cron to postgres with grant option;1087server # [ 128.934605] postgres[1143]:1088server # [ 128.938933] postgres[1143]: alter default privileges in schema cron grant all on tables to postgres with grant option;1089server # [ 128.944608] postgres[1143]: alter default privileges in schema cron grant all on functions to postgres with grant option;1090server # [ 128.950973] postgres[1143]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1091server # [ 128.957185] postgres[1143]:1092server # [ 128.958410] postgres[1143]: alter default privileges for user supabase_admin in schema cron grant all1093server # [ 128.959995] postgres[1143]: on sequences to postgres with grant option;1094server # [ 128.965920] postgres[1143]: alter default privileges for user supabase_admin in schema cron grant all1095server # [ 128.966953] postgres[1143]: on tables to postgres with grant option;1096server # [ 128.967911] postgres[1143]: alter default privileges for user supabase_admin in schema cron grant all1097server # [ 128.969821] postgres[1143]: on functions to postgres with grant option;1098server # [ 128.974222] postgres[1143]:1099server # [ 128.978623] postgres[1143]: grant all privileges on all tables in schema cron to postgres with grant option;1100server # [ 128.981646] postgres[1143]:1101server # [ 128.985320] postgres[1143]: END IF;1102server # [ 128.986523] postgres[1143]:1103server # [ 128.990313] postgres[1143]: END;1104server # [ 128.996830] postgres[1143]: $$;1105server # [ 128.998967] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: ALTER ROLE1106server # [ 128.999933] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: CREATE FUNCTION1107server # [ 129.002007] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: CREATE EVENT TRIGGER1108server # [ 129.010658] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: COMMENT1109server # [ 129.012121] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: CREATE FUNCTION1110server # [ 129.013368] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: COMMENT1111server # [ 129.014849] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: DO1112server # [ 129.020676] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: CREATE ROLE1113server # [ 129.022423] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1114server # [ 129.023555] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1115server # [ 129.025843] postgres[1143]: ::1 2026-10-31 19:39:01.563 UTC [1143] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')1116server # [ 129.029389] postgres[1143]: EXECUTE PROCEDURE extensions.grant_pg_cron_access();1117server # [ 129.035212] postgres[1143]: ::1 2026-10-31 19:39:01.579 UTC [1143] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';1118server # [ 129.040981] postgres[1143]: ::1 2026-10-31 19:39:01.597 UTC [1143] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()1119server # [ 129.042368] postgres[1143]: RETURNS event_trigger1120server # [ 129.045007] postgres[1143]: LANGUAGE plpgsql1121server # [ 129.050748] postgres[1143]: AS $$1122server # [ 129.058520] postgres[1143]: BEGIN1123server # [ 129.060134] postgres[1143]: IF EXISTS (1124server # [ 129.062419] postgres[1143]: SELECT 11125server # [ 129.063787] postgres[1143]: FROM pg_event_trigger_ddl_commands() AS ev1126server # [ 129.068763] postgres[1143]: JOIN pg_extension AS ext1127server # [ 129.073167] postgres[1143]: ON ev.objid = ext.oid1128server # [ 129.077175] postgres[1143]: WHERE ext.extname = 'pg_net'1129server # [ 129.081586] postgres[1143]: )1130server # [ 129.086448] postgres[1143]: THEN1131server # [ 129.093745] postgres[1143]: IF NOT EXISTS (1132server # [ 129.095309] postgres[1143]: SELECT 11133server # [ 129.096019] postgres[1143]: FROM pg_roles1134server # [ 129.099380] postgres[1143]: WHERE rolname = 'supabase_functions_admin'1135server # [ 129.107044] postgres[1143]: )1136server # [ 129.110117] postgres[1143]: THEN1137server # [ 129.114193] postgres[1143]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1138server # [ 129.118749] postgres[1143]: END IF;1139server # [ 129.120662] postgres[1143]:1140server # [ 129.126242] postgres[1143]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;1141server # [ 129.128418] postgres[1143]:1142server # [ 129.137872] postgres[1143]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1143server # [ 129.145607] postgres[1143]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1144server # [ 129.153936] postgres[1143]:1145server # [ 129.163983] postgres[1143]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1146server # [ 129.171211] postgres[1143]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1147server # [ 129.186661] postgres[1143]:1148server # [ 129.194404] postgres[1143]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1149server # [ 129.202406] postgres[1143]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1150server # [ 129.203573] postgres[1143]:1151server # [ 129.204280] postgres[1143]: GRANT EXECUTE ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) TO supabase_functions_admin, postgres, anon, authenticated, service_role;1152server # [ 129.210661] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1153server # [ 129.217628] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1154server # [ 129.220037] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1155server # [ 129.222359] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1156server # [ 129.229729] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1157server # [ 129.231084] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1158server # [ 129.233880] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: GRANT1159server # [ 129.237263] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1142]: DO1160server # [ 129.238708] postgres[1143]: GRANT EXECUTE ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) TO supabase_functions_admin, postgres, anon, authenticated, service_role;1161server # [ 129.246038] postgres[1143]: END IF;1162server # [ 129.248947] postgres[1143]: END;1163server # [ 129.250458] postgres[1143]: $$;1164server # [ 129.257743] postgres[1143]: ::1 2026-10-31 19:39:01.610 UTC [1143] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1165server # [ 129.259633] postgres[1143]: ::1 2026-10-31 19:39:01.643 UTC [1143] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1166server # [ 129.270408] postgres[1143]: ::1 2026-10-31 19:39:01.653 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1167server # [ 129.273635] postgres[1143]: ::1 2026-10-31 19:39:01.662 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1168server # [ 129.275249] postgres[1143]: ::1 2026-10-31 19:39:01.670 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1169server # [ 129.278050] postgres[1143]: ::1 2026-10-31 19:39:01.687 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1170server # [ 129.286578] postgres[1143]: ::1 2026-10-31 19:39:01.703 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1171server # [ 129.290164] postgres[1143]: ::1 2026-10-31 19:39:01.707 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1172server # [ 129.298930] postgres[1143]: ::1 2026-10-31 19:39:01.717 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1173server # [ 129.301720] postgres[1143]: ::1 2026-10-31 19:39:01.721 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1174server # [ 129.303645] postgres[1143]: ::1 2026-10-31 19:39:01.745 UTC [1143] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1175server # [ 129.317269] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running pgbouncer auth schema...1176server # [ 129.530019] postgres[1145]: ::1 2026-10-31 19:39:02.180 UTC [1145] postgres@postgres LOG: statement: CREATE USER pgbouncer;1177server # [ 129.539461] postgres[1145]: ::1 2026-10-31 19:39:02.203 UTC [1145] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1178server # [ 129.547812] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1144]: CREATE ROLE1179server # [ 129.557679] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1144]: REVOKE1180server # [ 129.561557] postgres[1145]: ::1 2026-10-31 19:39:02.230 UTC [1145] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1181server # [ 129.602291] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1144]: CREATE SCHEMA1182server # [ 129.609037] postgres[1145]: ::1 2026-10-31 19:39:02.282 UTC [1145] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1183server # [ 129.611127] postgres[1145]: RETURNS TABLE(username TEXT, password TEXT) AS1184server # [ 129.617202] postgres[1145]: $$1185server # [ 129.619032] postgres[1145]: BEGIN1186server # [ 129.625396] postgres[1145]: RAISE WARNING 'PgBouncer auth request: %', p_usename;1187server # [ 129.627507] postgres[1145]:1188server # [ 129.632001] postgres[1145]: RETURN QUERY1189server # [ 129.635200] postgres[1145]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1190server # [ 129.642253] postgres[1145]: WHERE usename = p_usename;1191server # [ 129.643885] postgres[1145]: END;1192server # [ 129.649884] postgres[1145]: $$ LANGUAGE plpgsql1193server # [ 129.655371] postgres[1145]: SET search_path = ''1194server # [ 129.658116] postgres[1145]: SECURITY DEFINER;1195server # [ 129.659316] postgres[1145]: ::1 2026-10-31 19:39:02.315 UTC [1145] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1196server # [ 129.660936] postgres[1145]: ::1 2026-10-31 19:39:02.334 UTC [1145] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1197server # [ 129.663740] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1144]: CREATE FUNCTION1198server # [ 129.666859] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1144]: REVOKE1199server # [ 129.674298] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1144]: GRANT1200server # [ 129.724808] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running stat extension...1201server # [ 130.043279] postgres[1149]: ::1 2026-10-31 19:39:02.715 UTC [1149] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions;1202server # [ 130.063867] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1148]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping1203server # [ 130.104987] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1148]: CREATE SCHEMA1204server # [ 130.118712] postgres[1149]: ::1 2026-10-31 19:39:02.785 UTC [1149] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1205server # [ 131.203496] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1148]: CREATE EXTENSION1206server # [ 131.295670] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/10000000000000_demote-postgres.sql1207server # [ 131.635877] postgres[1151]: ::1 2026-10-31 19:39:04.296 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres;1208server # [ 131.651580] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1209server # [ 131.658135] postgres[1151]: ::1 2026-10-31 19:39:04.324 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres;1210server # [ 131.667469] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1211server # [ 131.674036] postgres[1151]: ::1 2026-10-31 19:39:04.348 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres;1212server # [ 131.686909] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1213server # [ 131.699559] postgres[1151]: ::1 2026-10-31 19:39:04.370 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1214server # [ 131.723321] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1215server # [ 131.725762] postgres[1151]: ::1 2026-10-31 19:39:04.399 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1216server # [ 131.733987] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1217server # [ 131.735849] postgres[1151]: ::1 2026-10-31 19:39:04.411 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1218server # [ 131.743952] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1219server # [ 131.745876] postgres[1151]: ::1 2026-10-31 19:39:04.420 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1220server # [ 131.751679] postgres[1151]: ::1 2026-10-31 19:39:04.424 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1221server # [ 131.755387] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1222server # [ 131.769177] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1223server # [ 131.770497] postgres[1151]: ::1 2026-10-31 19:39:04.445 UTC [1151] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1224server # [ 131.841991] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: GRANT1225server # [ 131.915649] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: DO1226server # [ 131.917698] postgres[1151]: ::1 2026-10-31 19:39:04.591 UTC [1151] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1227server # [ 131.927821] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1150]: ALTER ROLE1228server # [ 131.985165] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211115181400_update-auth-permissions.sql1229server # [ 132.483998] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31230server # [ 132.486466] postgres[1166]: ::1 2026-10-31 19:39:05.135 UTC [1166] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1231server # [ 132.502344] postgres[1166]: ::1 2026-10-31 19:39:05.174 UTC [1166] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1232server # [ 132.521539] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: GRANT1233server # [ 132.533787] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: GRANT1234server # [ 132.538579] postgres[1166]: ::1 2026-10-31 19:39:05.211 UTC [1166] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1235server # [ 132.552487] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: GRANT1236server # [ 132.558005] postgres[1166]: ::1 2026-10-31 19:39:05.232 UTC [1166] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1237server # [ 132.573975] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER TABLE1238server # [ 132.582805] postgres[1166]: ::1 2026-10-31 19:39:05.254 UTC [1166] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1239server # [ 132.597206] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER TABLE1240server # [ 132.606348] postgres[1166]: ::1 2026-10-31 19:39:05.271 UTC [1166] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1241server # [ 132.610104] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER TABLE1242server # [ 132.617811] postgres[1166]: ::1 2026-10-31 19:39:05.287 UTC [1166] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1243server # [ 132.620987] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER TABLE1244server # [ 132.625643] postgres[1166]: ::1 2026-10-31 19:39:05.297 UTC [1166] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1245server # [ 132.632767] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER TABLE1246server # [ 132.634331] postgres[1166]: ::1 2026-10-31 19:39:05.310 UTC [1166] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres;1247server # [ 132.649257] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: GRANT1248server # [ 132.658583] postgres[1166]: ::1 2026-10-31 19:39:05.323 UTC [1166] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1249server # [ 132.665599] postgres[1166]: ::1 2026-10-31 19:39:05.340 UTC [1166] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1250server # [ 132.675996] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: GRANT1251server # [ 132.694943] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: GRANT1252server # [ 132.696007] postgres[1166]: ::1 2026-10-31 19:39:05.365 UTC [1166] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1253server # [ 132.721317] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: GRANT1254server # [ 132.723005] postgres[1166]: ::1 2026-10-31 19:39:05.397 UTC [1166] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1255server # [ 132.740614] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER DEFAULT PRIVILEGES1256server # [ 132.749279] postgres[1166]: ::1 2026-10-31 19:39:05.420 UTC [1166] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1257server # [ 132.766366] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER DEFAULT PRIVILEGES1258server # [ 132.774129] postgres[1166]: ::1 2026-10-31 19:39:05.449 UTC [1166] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1259server # [ 132.796562] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1161]: ALTER DEFAULT PRIVILEGES1260server # [ 132.888680] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211118015519_create-realtime-schema.sql1261server # [ 133.227286] postgres[1175]: ::1 2026-10-31 19:39:05.896 UTC [1175] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime;1262server # [ 133.246884] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1174]: CREATE SCHEMA1263server # [ 133.297495] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1264server # [ 133.617333] postgres[1177]: ::1 2026-10-31 19:39:06.285 UTC [1177] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres;1265server # [ 133.639709] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1176]: GRANT1266server # [ 133.645526] postgres[1177]: ::1 2026-10-31 19:39:06.312 UTC [1177] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1267server # [ 133.652951] postgres[1177]: ::1 2026-10-31 19:39:06.317 UTC [1177] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1268server # [ 133.655397] postgres[1177]: ::1 2026-10-31 19:39:06.319 UTC [1177] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1269server # [ 133.670026] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1176]: GRANT1270server # [ 133.671630] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1176]: GRANT1271server # [ 133.677834] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1176]: GRANT1272server # [ 133.700897] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211124212715_update-auth-owner.sql1273server # [ 134.062863] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1178]: DO1274server # [ 134.080769] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1178]: DO1275server # [ 134.087329] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1178]: DO1276server # [ 134.110706] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1277server # [ 134.362086] postgres[1181]: ::1 2026-10-31 19:39:07.024 UTC [1181] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user;1278server # [ 134.377725] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1180]: ALTER DEFAULT PRIVILEGES1279server # [ 134.382264] postgres[1181]: ::1 2026-10-31 19:39:07.043 UTC [1181] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1280server # [ 134.385908] postgres[1181]: ::1 2026-10-31 19:39:07.053 UTC [1181] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user;1281server # [ 134.389966] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1180]: ALTER DEFAULT PRIVILEGES1282server # [ 134.391188] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1180]: ALTER DEFAULT PRIVILEGES1283server # [ 134.428846] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1284server # [ 134.715667] postgres[1183]: ::1 2026-10-31 19:39:07.379 UTC [1183] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1285server # [ 134.734212] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1182]: ALTER ROLE1286server # [ 134.775627] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1287server # [ 135.097321] postgres[1185]: ::1 2026-10-31 19:39:07.764 UTC [1185] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1288server # [ 135.105611] postgres[1185]: ::1 2026-10-31 19:39:07.781 UTC [1185] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1289server # [ 135.115135] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1290server # [ 135.122128] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: DROP EVENT TRIGGER1291server # [ 135.129764] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1292server # [ 135.132260] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: DROP FUNCTION1293server # [ 135.138065] postgres[1185]: ::1 2026-10-31 19:39:07.792 UTC [1185] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1294server # [ 135.146526] postgres[1185]: DECLARE1295server # [ 135.153676] postgres[1185]: cmd record;1296server # [ 135.156011] postgres[1185]: BEGIN1297server # [ 135.162073] postgres[1185]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1298server # [ 135.163650] postgres[1185]: LOOP1299server # [ 135.169754] postgres[1185]: IF cmd.command_tag IN (1300server # [ 135.171586] postgres[1185]: 'CREATE SCHEMA', 'ALTER SCHEMA'1301server # [ 135.174199] postgres[1185]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1302server # [ 135.177330] postgres[1185]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1303server # [ 135.182686] postgres[1185]: , 'CREATE VIEW', 'ALTER VIEW'1304server # [ 135.185764] postgres[1185]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1305server # [ 135.193018] postgres[1185]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1306server # [ 135.197570] postgres[1185]: , 'CREATE TRIGGER'1307server # [ 135.198679] postgres[1185]: , 'CREATE TYPE'1308server # [ 135.205547] postgres[1185]: , 'CREATE RULE'1309server # [ 135.210125] postgres[1185]: , 'COMMENT'1310server # [ 135.214005] postgres[1185]: )1311server # [ 135.215588] postgres[1185]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1312server # [ 135.230569] postgres[1185]: AND cmd.schema_name is distinct from 'pg_temp'1313server # [ 135.238881] postgres[1185]: THEN1314server # [ 135.242810] postgres[1185]: NOTIFY pgrst, 'reload schema';1315server # [ 135.258246] postgres[1185]: END IF;1316server # [ 135.263190] postgres[1185]: END LOOP;1317server # [ 135.271791] postgres[1185]: END; $$ LANGUAGE plpgsql;1318server # [ 135.280805] postgres[1185]: ::1 2026-10-31 19:39:07.825 UTC [1185] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1319server # [ 135.286238] postgres[1185]: DECLARE1320server # [ 135.287417] postgres[1185]: obj record;1321server # [ 135.289027] postgres[1185]: BEGIN1322server # [ 135.297154] postgres[1185]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1323server # [ 135.305864] postgres[1185]: LOOP1324server # [ 135.314092] postgres[1185]: IF obj.object_type IN (1325server # [ 135.317035] postgres[1185]: 'schema'1326server # [ 135.325380] postgres[1185]: , 'table'1327server # [ 135.333183] postgres[1185]: , 'foreign table'1328server # [ 135.335053] postgres[1185]: , 'view'1329server # [ 135.337216] postgres[1185]: , 'materialized view'1330server # [ 135.340892] postgres[1185]: , 'function'1331server # [ 135.343938] postgres[1185]: , 'trigger'1332server # [ 135.350261] postgres[1185]: , 'type'1333server # [ 135.357039] postgres[1185]: , 'rule'1334server # [ 135.359738] postgres[1185]: )1335server # [ 135.362462] postgres[1185]: AND obj.is_temporary IS false -- no pg_temp objects1336server # [ 135.365391] postgres[1185]: THEN1337server # [ 135.375742] postgres[1185]: NOTIFY pgrst, 'reload schema';1338server # [ 135.380762] postgres[1185]: END IF;1339server # [ 135.385632] postgres[1185]: END LOOP;1340server # [ 135.390345] postgres[1185]: END; $$ LANGUAGE plpgsql;1341server # [ 135.398813] postgres[1185]: ::1 2026-10-31 19:39:07.840 UTC [1185] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1342server # [ 135.401825] postgres[1185]: ::1 2026-10-31 19:39:07.850 UTC [1185] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1343server # [ 135.404150] postgres[1185]: ON ddl_command_end1344server # [ 135.413470] postgres[1185]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1345server # [ 135.416167] postgres[1185]: ::1 2026-10-31 19:39:07.867 UTC [1185] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1346server # [ 135.418320] postgres[1185]: ::1 2026-10-31 19:39:07.872 UTC [1185] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1347server # [ 135.421049] postgres[1185]: ON sql_drop1348server # [ 135.423347] postgres[1185]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1349server # [ 135.426852] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: CREATE FUNCTION1350server # [ 135.433955] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: CREATE FUNCTION1351server # [ 135.435216] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping1352server # [ 135.437530] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: DROP EVENT TRIGGER1353server # [ 135.438816] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: CREATE EVENT TRIGGER1354server # [ 135.442663] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping1355server # [ 135.444857] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: DROP EVENT TRIGGER1356server # [ 135.447199] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1184]: CREATE EVENT TRIGGER1357server # [ 135.457888] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1358server # [ 136.418759] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1192]: DO1359server # [ 136.449151] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql1360server # [ 136.797013] postgres[1209]: ::1 2026-10-31 19:39:09.463 UTC [1209] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public;1361server # [ 136.844871] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: CREATE SCHEMA1362server # [ 136.849747] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping1363server # [ 136.852095] postgres[1209]: ::1 2026-10-31 19:39:09.517 UTC [1209] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb);1364server # [ 136.857367] postgres[1209]: ::1 2026-10-31 19:39:09.531 UTC [1209] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1365server # [ 136.865301] postgres[1209]: "operationName" text default null,1366server # [ 136.869971] postgres[1209]: query text default null,1367server # [ 136.874225] postgres[1209]: variables jsonb default null,1368server # [ 136.881731] postgres[1209]: extensions jsonb default null1369server # [ 136.883334] postgres[1209]: )1370server # [ 136.886169] postgres[1209]: returns jsonb1371server # [ 136.886947] postgres[1209]: language plpgsql1372server # [ 136.887377] postgres[1209]: as $$1373server # [ 136.887964] postgres[1209]: DECLARE1374server # [ 136.888319] postgres[1209]: server_version float;1375server # [ 136.893220] postgres[1209]: BEGIN1376server # [ 136.897304] postgres[1209]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1377server # [ 136.898439] postgres[1209]:1378server # [ 136.901907] postgres[1209]: IF server_version >= 14 THEN1379server # [ 136.910011] postgres[1209]: RETURN jsonb_build_object(1380server # [ 136.911881] postgres[1209]: 'data', null::jsonb,1381server # [ 136.915083] postgres[1209]: 'errors', array['pg_graphql extension is not enabled.']1382server # [ 136.923572] postgres[1209]: );1383server # [ 136.925027] postgres[1209]: ELSE1384server # [ 136.927159] postgres[1209]: RETURN jsonb_build_object(1385server # [ 136.934783] postgres[1209]: 'data', null::jsonb,1386server # [ 136.938178] postgres[1209]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1387server # [ 136.940706] postgres[1209]: );1388server # [ 136.941782] postgres[1209]: END IF;1389server # [ 136.942947] postgres[1209]: END;1390server # [ 136.943794] postgres[1209]: $$;1391server # [ 136.945236] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: DROP FUNCTION1392server # [ 136.947043] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: CREATE FUNCTION1393server # [ 136.948960] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: GRANT1394server # [ 136.949946] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: ALTER DEFAULT PRIVILEGES1395server # [ 136.952303] postgres[1209]: ::1 2026-10-31 19:39:09.570 UTC [1209] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1396server # [ 136.954249] postgres[1209]: ::1 2026-10-31 19:39:09.594 UTC [1209] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1397server # [ 136.955142] postgres[1209]: ::1 2026-10-31 19:39:09.621 UTC [1209] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1398server # [ 136.959044] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: ALTER DEFAULT PRIVILEGES1399server # [ 136.960307] postgres[1209]: ::1 2026-10-31 19:39:09.635 UTC [1209] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1400server # [ 136.977195] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: ALTER DEFAULT PRIVILEGES1401server # [ 136.978484] postgres[1209]: ::1 2026-10-31 19:39:09.650 UTC [1209] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1402server # [ 136.979110] postgres[1209]: on sequences to postgres, anon, authenticated, service_role;1403server # [ 136.993097] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: ALTER DEFAULT PRIVILEGES1404server # [ 136.995958] postgres[1209]: ::1 2026-10-31 19:39:09.668 UTC [1209] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1405server # [ 136.999188] postgres[1209]: on tables to postgres, anon, authenticated, service_role;1406server # [ 137.007269] postgres[1209]: ::1 2026-10-31 19:39:09.677 UTC [1209] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1407server # [ 137.010265] postgres[1209]: on functions to postgres, anon, authenticated, service_role;1408server # [ 137.011685] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: ALTER DEFAULT PRIVILEGES1409server # [ 137.014737] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: ALTER DEFAULT PRIVILEGES1410server # [ 137.019846] postgres[1209]: ::1 2026-10-31 19:39:09.693 UTC [1209] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1411server # [ 137.021969] postgres[1209]: RETURNS event_trigger1412server # [ 137.025275] postgres[1209]: LANGUAGE plpgsql1413server # [ 137.027412] postgres[1209]: AS $func$1414server # [ 137.034049] postgres[1209]: DECLARE1415server # [ 137.035200] postgres[1209]: func_is_graphql_resolve bool;1416server # [ 137.035967] postgres[1209]: BEGIN1417server # [ 137.042429] postgres[1209]: func_is_graphql_resolve = (1418server # [ 137.043376] postgres[1209]: SELECT n.proname = 'resolve'1419server # [ 137.045912] postgres[1209]: FROM pg_event_trigger_ddl_commands() AS ev1420server # [ 137.049215] postgres[1209]: LEFT JOIN pg_catalog.pg_proc AS n1421server # [ 137.050577] postgres[1209]: ON ev.objid = n.oid1422server # [ 137.054374] postgres[1209]: );1423server # [ 137.059007] postgres[1209]:1424server # [ 137.060039] postgres[1209]: IF func_is_graphql_resolve1425server # [ 137.062116] postgres[1209]: THEN1426server # [ 137.064824] postgres[1209]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1427server # [ 137.066260] postgres[1209]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1428server # [ 137.067014] postgres[1209]:1429server # [ 137.073059] postgres[1209]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1430server # [ 137.074696] postgres[1209]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1431server # [ 137.075368] postgres[1209]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1432server # [ 137.077736] postgres[1209]:1433server # [ 137.079105] postgres[1209]: DROP FUNCTION IF EXISTS graphql_public.graphql;1434server # [ 137.085594] postgres[1209]: create or replace function graphql_public.graphql(1435server # [ 137.087779] postgres[1209]: "operationName" text default null,1436server # [ 137.089792] postgres[1209]: query text default null,1437server # [ 137.093475] postgres[1209]: variables jsonb default null,1438server # [ 137.094840] postgres[1209]: extensions jsonb default null1439server # [ 137.095668] postgres[1209]: )1440server # [ 137.100918] postgres[1209]: returns jsonb1441server # [ 137.102226] postgres[1209]: language sql1442server # [ 137.104863] postgres[1209]: as $$1443server # [ 137.109169] postgres[1209]: SELECT graphql.resolve(query, coalesce(variables, '{}'));1444server # [ 137.110330] postgres[1209]: $$;1445server # [ 137.111074] postgres[1209]:1446server # [ 137.113781] postgres[1209]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1447server # [ 137.115261] postgres[1209]: END IF;1448server # [ 137.121333] postgres[1209]:1449server # [ 137.122809] postgres[1209]: END;1450server # [ 137.125300] postgres[1209]: $func$;1451server # [ 137.127272] postgres[1209]: ::1 2026-10-31 19:39:09.708 UTC [1209] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1452server # [ 137.135515] postgres[1209]: ::1 2026-10-31 19:39:09.713 UTC [1209] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1453server # [ 137.142183] postgres[1209]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1454server # [ 137.142996] postgres[1209]: ::1 2026-10-31 19:39:09.724 UTC [1209] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1455server # [ 137.143792] postgres[1209]: ::1 2026-10-31 19:39:09.738 UTC [1209] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1456server # [ 137.150445] postgres[1209]: RETURNS event_trigger1457server # [ 137.161703] postgres[1209]: LANGUAGE plpgsql1458server # [ 137.163259] postgres[1209]: AS $func$1459server # [ 137.171826] postgres[1209]: DECLARE1460server # [ 137.177860] postgres[1209]: graphql_is_dropped bool;1461server # [ 137.178783] postgres[1209]: BEGIN1462server # [ 137.179312] postgres[1209]: graphql_is_dropped = (1463server # [ 137.179992] postgres[1209]: SELECT ev.schema_name = 'graphql_public'1464server # [ 137.184434] postgres[1209]: FROM pg_event_trigger_dropped_objects() AS ev1465server # [ 137.199232] postgres[1209]: WHERE ev.schema_name = 'graphql_public'1466server # [ 137.202106] postgres[1209]: );1467server # [ 137.205382] postgres[1209]:1468server # [ 137.206450] postgres[1209]: IF graphql_is_dropped1469server # [ 137.207349] postgres[1209]: THEN1470server # [ 137.208009] postgres[1209]: create or replace function graphql_public.graphql(1471server # [ 137.209628] postgres[1209]: "operationName" text default null,1472server # [ 137.211815] postgres[1209]: query text default null,1473server # [ 137.213247] postgres[1209]: variables jsonb default null,1474server # [ 137.214669] postgres[1209]: extensions jsonb default null1475server # [ 137.216050] postgres[1209]: )1476server # [ 137.218789] postgres[1209]: returns jsonb1477server # [ 137.219810] postgres[1209]: language plpgsql1478server # [ 137.222614] postgres[1209]: as $$1479server # [ 137.232841] postgres[1209]: DECLARE1480server # [ 137.245884] postgres[1209]: server_version float;1481server # [ 137.256754] postgres[1209]: BEGIN1482server # [ 137.261756] postgres[1209]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1483server # [ 137.273789] postgres[1209]:1484server # [ 137.276357] postgres[1209]: IF server_version >= 14 THEN1485server # [ 137.280786] postgres[1209]: RETURN jsonb_build_object(1486server # [ 137.283629] postgres[1209]: 'data', null::jsonb,1487server # [ 137.286460] postgres[1209]: 'errors', array['pg_graphql extension is not enabled.']1488server # [ 137.288490] postgres[1209]: );1489server # [ 137.290580] postgres[1209]: ELSE1490server # [ 137.293218] postgres[1209]: RETURN jsonb_build_object(1491server # [ 137.296194] postgres[1209]: 'data', null::jsonb,1492server # [ 137.298586] postgres[1209]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1493server # [ 137.301267] postgres[1209]: );1494server # [ 137.304384] postgres[1209]: END IF;1495server # [ 137.306948] postgres[1209]: END;1496server # [ 137.311086] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: CREATE FUNCTION1497server # [ 137.312588] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping1498server # [ 137.314586] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: DROP EVENT TRIGGER1499server # [ 137.317283] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: CREATE EVENT TRIGGER1500server # [ 137.320006] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: COMMENT1501server # [ 137.322612] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: CREATE FUNCTION1502server # [ 137.323598] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping1503server # [ 137.326994] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: DROP EVENT TRIGGER1504server # [ 137.330095] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: CREATE EVENT TRIGGER1505server # [ 137.332987] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1207]: COMMENT1506server # [ 137.334259] postgres[1209]: $$;1507server # [ 137.336310] postgres[1209]: END IF;1508server # [ 137.338373] postgres[1209]:1509server # [ 137.341523] postgres[1209]: END;1510server # [ 137.344011] postgres[1209]: $func$;1511server # [ 137.346696] postgres[1209]: ::1 2026-10-31 19:39:09.786 UTC [1209] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1512server # [ 137.350603] postgres[1209]: ::1 2026-10-31 19:39:09.789 UTC [1209] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1513server # [ 137.355251] postgres[1209]: EXECUTE PROCEDURE extensions.set_graphql_placeholder();1514server # [ 137.357369] postgres[1209]: ::1 2026-10-31 19:39:09.801 UTC [1209] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1515server # [ 137.366232] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1516server # [ 137.526640] postgres[1211]: ::1 2026-10-31 19:39:10.194 UTC [1211] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1517server # [ 137.533725] postgres[1211]: ::1 2026-10-31 19:39:10.207 UTC [1211] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1518server # [ 137.554780] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1519server # [ 137.562448] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: DROP EVENT TRIGGER1520server # [ 137.571993] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1521server # [ 137.581749] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: DROP FUNCTION1522server # [ 137.583956] postgres[1211]: ::1 2026-10-31 19:39:10.253 UTC [1211] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1523server # [ 137.593290] postgres[1211]: DECLARE1524server # [ 137.594767] postgres[1211]: cmd record;1525server # [ 137.595944] postgres[1211]: BEGIN1526server # [ 137.602022] postgres[1211]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1527server # [ 137.603833] postgres[1211]: LOOP1528server # [ 137.611616] postgres[1211]: IF cmd.command_tag IN (1529server # [ 137.618030] postgres[1211]: 'CREATE SCHEMA', 'ALTER SCHEMA'1530server # [ 137.622589] postgres[1211]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1531server # [ 137.627796] postgres[1211]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1532server # [ 137.631406] postgres[1211]: , 'CREATE VIEW', 'ALTER VIEW'1533server # [ 137.636039] postgres[1211]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1534server # [ 137.642879] postgres[1211]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1535server # [ 137.654711] postgres[1211]: , 'CREATE TRIGGER'1536server # [ 137.660951] postgres[1211]: , 'CREATE TYPE', 'ALTER TYPE'1537server # [ 137.664057] postgres[1211]: , 'CREATE RULE'1538server # [ 137.671494] postgres[1211]: , 'COMMENT'1539server # [ 137.674670] postgres[1211]: )1540server # [ 137.676871] postgres[1211]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1541server # [ 137.678580] postgres[1211]: AND cmd.schema_name is distinct from 'pg_temp'1542server # [ 137.680934] postgres[1211]: THEN1543server # [ 137.683696] postgres[1211]: NOTIFY pgrst, 'reload schema';1544server # [ 137.686478] postgres[1211]: END IF;1545server # [ 137.688446] postgres[1211]: END LOOP;1546server # [ 137.689795] postgres[1211]: END; $$ LANGUAGE plpgsql;1547server # [ 137.691035] postgres[1211]: ::1 2026-10-31 19:39:10.314 UTC [1211] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1548server # [ 137.693563] postgres[1211]: DECLARE1549server # [ 137.698030] postgres[1211]: obj record;1550server # [ 137.699570] postgres[1211]: BEGIN1551server # [ 137.709987] postgres[1211]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1552server # [ 137.713914] postgres[1211]: LOOP1553server # [ 137.731241] postgres[1211]: IF obj.object_type IN (1554server # [ 137.747580] postgres[1211]: 'schema'1555server # [ 137.763755] postgres[1211]: , 'table'1556server # [ 137.782094] postgres[1211]: , 'foreign table'1557server # [ 137.786916] postgres[1211]: , 'view'1558server # [ 137.799454] postgres[1211]: , 'materialized view'1559server # [ 137.803922] postgres[1211]: , 'function'1560server # [ 137.806884] postgres[1211]: , 'trigger'1561server # [ 137.813672] postgres[1211]: , 'type'1562server # [ 137.815121] postgres[1211]: , 'rule'1563server # [ 137.817459] postgres[1211]: )1564server # [ 137.823492] postgres[1211]: AND obj.is_temporary IS false -- no pg_temp objects1565server # [ 137.825911] postgres[1211]: THEN1566server # [ 137.827800] postgres[1211]: NOTIFY pgrst, 'reload schema';1567server # [ 137.834158] postgres[1211]: END IF;1568server # [ 137.838927] postgres[1211]: END LOOP;1569server # [ 137.846978] postgres[1211]: END; $$ LANGUAGE plpgsql;1570server # [ 137.857266] postgres[1211]: ::1 2026-10-31 19:39:10.339 UTC [1211] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1571server # [ 137.861020] postgres[1211]: ::1 2026-10-31 19:39:10.352 UTC [1211] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1572server # [ 137.867673] postgres[1211]: ON ddl_command_end1573server # [ 137.878544] postgres[1211]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1574server # [ 137.880246] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: CREATE FUNCTION1575server # [ 137.889816] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: CREATE FUNCTION1576server # [ 137.890793] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: DROP EVENT TRIGGER1577server # [ 137.900142] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: CREATE EVENT TRIGGER1578server # [ 137.907429] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: DROP EVENT TRIGGER1579server # [ 137.914374] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1210]: CREATE EVENT TRIGGER1580server # [ 137.922067] postgres[1211]: ::1 2026-10-31 19:39:10.368 UTC [1211] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1581server # [ 137.930305] postgres[1211]: ::1 2026-10-31 19:39:10.383 UTC [1211] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1582server # [ 137.931306] postgres[1211]: ON sql_drop1583server # [ 137.931740] postgres[1211]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1584server # [ 137.934325] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1585server # [ 138.075623] postgres[1213]: ::1 2026-10-31 19:39:10.747 UTC [1213] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1586server # [ 138.105176] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1212]: ALTER ROLE1587server # [ 138.145321] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1588server # [ 138.446496] postgres[1215]: ::1 2026-10-31 19:39:11.104 UTC [1215] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1589server # [ 138.453463] postgres[1215]: returns event_trigger1590server # [ 138.457593] postgres[1215]: language plpgsql1591server # [ 138.458671] postgres[1215]: AS $func$1592server # [ 138.461730] postgres[1215]: DECLARE1593server # [ 138.465177] postgres[1215]: func_is_graphql_resolve bool;1594server # [ 138.468958] postgres[1215]: BEGIN1595server # [ 138.474946] postgres[1215]: func_is_graphql_resolve = (1596server # [ 138.479101] postgres[1215]: SELECT n.proname = 'resolve'1597server # [ 138.485057] postgres[1215]: FROM pg_event_trigger_ddl_commands() AS ev1598server # [ 138.494674] postgres[1215]: LEFT JOIN pg_catalog.pg_proc AS n1599server # [ 138.498508] postgres[1215]: ON ev.objid = n.oid1600server # [ 138.503382] postgres[1215]: );1601server # [ 138.505816] postgres[1215]:1602server # [ 138.508928] postgres[1215]: IF func_is_graphql_resolve1603server # [ 138.510328] postgres[1215]: THEN1604server # [ 138.511534] postgres[1215]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1605server # [ 138.513939] postgres[1215]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1606server # [ 138.514964] postgres[1215]:1607server # [ 138.515647] postgres[1215]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1608server # [ 138.518204] postgres[1215]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1609server # [ 138.519649] postgres[1215]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1610server # [ 138.522206] postgres[1215]:1611server # [ 138.523740] postgres[1215]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1612server # [ 138.525961] postgres[1215]: DROP FUNCTION IF EXISTS graphql_public.graphql;1613server # [ 138.528833] postgres[1215]: create or replace function graphql_public.graphql(1614server # [ 138.530952] postgres[1215]: "operationName" text default null,1615server # [ 138.533870] postgres[1215]: query text default null,1616server # [ 138.537151] postgres[1215]: variables jsonb default null,1617server # [ 138.538458] postgres[1215]: extensions jsonb default null1618server # [ 138.539436] postgres[1215]: )1619server # [ 138.541491] postgres[1215]: returns jsonb1620server # [ 138.542669] postgres[1215]: language sql1621server # [ 138.543395] postgres[1215]: as $$1622server # [ 138.545057] postgres[1215]: -- This changed1623server # [ 138.547336] postgres[1215]: select graphql.resolve(1624server # [ 138.550478] postgres[1215]: query := query,1625server # [ 138.553910] postgres[1215]: variables := coalesce(variables, '{}'),1626server # [ 138.555864] postgres[1215]: "operationName" := "operationName",1627server # [ 138.559062] postgres[1215]: extensions := extensions1628server # [ 138.563027] postgres[1215]: );1629server # [ 138.564144] postgres[1215]: $$;1630server # [ 138.565391] postgres[1215]:1631server # [ 138.567305] postgres[1215]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1632server # [ 138.569618] postgres[1215]: END IF;1633server # [ 138.571476] postgres[1215]:1634server # [ 138.574631] postgres[1215]: END;1635server # [ 138.577058] postgres[1215]: $func$;1636server # [ 138.581917] postgres[1215]: ::1 2026-10-31 19:39:11.183 UTC [1215] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1637server # [ 138.589074] postgres[1215]: RETURNS event_trigger1638server # [ 138.590525] postgres[1215]: LANGUAGE plpgsql1639server # [ 138.593695] postgres[1215]: AS $func$1640server # [ 138.597969] postgres[1215]: DECLARE1641server # [ 138.605549] postgres[1215]: graphql_is_dropped bool;1642server # [ 138.607780] postgres[1215]: BEGIN1643server # [ 138.613707] postgres[1215]: graphql_is_dropped = (1644server # [ 138.618373] postgres[1215]: SELECT ev.schema_name = 'graphql_public'1645server # [ 138.623383] postgres[1215]: FROM pg_event_trigger_dropped_objects() AS ev1646server # [ 138.627572] postgres[1215]: WHERE ev.schema_name = 'graphql_public'1647server # [ 138.631823] postgres[1215]: );1648server # [ 138.636180] postgres[1215]:1649server # [ 138.637867] postgres[1215]: IF graphql_is_dropped1650server # [ 138.641804] postgres[1215]: THEN1651server # [ 138.646926] postgres[1215]: create or replace function graphql_public.graphql(1652server # [ 138.649919] postgres[1215]: "operationName" text default null,1653server # [ 138.654100] postgres[1215]: query text default null,1654server # [ 138.656040] postgres[1215]: variables jsonb default null,1655server # [ 138.658104] postgres[1215]: extensions jsonb default null1656server # [ 138.659762] postgres[1215]: )1657server # [ 138.662287] postgres[1215]: returns jsonb1658server # [ 138.665174] postgres[1215]: language plpgsql1659server # [ 138.667149] postgres[1215]: as $$1660server # [ 138.669161] postgres[1215]: DECLARE1661server # [ 138.671223] postgres[1215]: server_version float;1662server # [ 138.674984] postgres[1215]: BEGIN1663server # [ 138.678491] postgres[1215]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1664server # [ 138.681240] postgres[1215]:1665server # [ 138.683574] postgres[1215]: IF server_version >= 14 THEN1666server # [ 138.688858] postgres[1215]: RETURN jsonb_build_object(1667server # [ 138.690209] postgres[1215]: 'errors', jsonb_build_array(1668server # [ 138.695618] postgres[1215]: jsonb_build_object(1669server # [ 138.698382] postgres[1215]: 'message', 'pg_graphql extension is not enabled.'1670server # [ 138.701680] postgres[1215]: )1671server # [ 138.706256] postgres[1215]: )1672server # [ 138.711154] postgres[1215]: );1673server # [ 138.715174] postgres[1215]: ELSE1674server # [ 138.719000] postgres[1215]: RETURN jsonb_build_object(1675server # [ 138.724532] postgres[1215]: 'errors', jsonb_build_array(1676server # [ 138.728961] postgres[1215]: jsonb_build_object(1677server # [ 138.730877] postgres[1215]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1678server # [ 138.734845] postgres[1215]: )1679server # [ 138.738582] postgres[1215]: )1680server # [ 138.743397] postgres[1215]: );1681server # [ 138.747888] postgres[1215]: END IF;1682server # [ 138.749651] postgres[1215]: END;1683server # [ 138.751301] postgres[1215]: $$;1684server # [ 138.754646] postgres[1215]: END IF;1685server # [ 138.756944] postgres[1215]:1686server # [ 138.760013] postgres[1215]: END;1687server # [ 138.766656] postgres[1215]: $func$;1688server # [ 138.770749] postgres[1215]: ::1 2026-10-31 19:39:11.218 UTC [1215] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1689server # [ 138.773866] postgres[1215]: "operationName" text default null,1690server # [ 138.778575] postgres[1215]: query text default null,1691server # [ 138.781270] postgres[1215]: variables jsonb default null,1692server # [ 138.799418] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1214]: CREATE FUNCTION1693server # [ 138.803142] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1214]: CREATE FUNCTION1694server # [ 138.806364] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1214]: CREATE FUNCTION1695server # [ 138.810392] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1214]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping1696server # [ 138.813115] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1214]: DROP EXTENSION1697server # [ 138.815423] postgres[1215]: extensions jsonb default null1698server # [ 138.818668] postgres[1215]: )1699server # [ 138.819932] postgres[1215]: returns jsonb1700server # [ 138.822371] postgres[1215]: language plpgsql1701server # [ 138.826039] postgres[1215]: as $$1702server # [ 138.827453] postgres[1215]: DECLARE1703server # [ 138.830538] postgres[1215]: server_version float;1704server # [ 138.832806] postgres[1215]: BEGIN1705server # [ 138.835659] postgres[1215]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1706server # [ 138.845482] postgres[1215]:1707server # [ 138.847952] postgres[1215]: IF server_version >= 14 THEN1708server # [ 138.861648] postgres[1215]: RETURN jsonb_build_object(1709server # [ 138.866811] postgres[1215]: 'errors', jsonb_build_array(1710server # [ 138.879948] postgres[1215]: jsonb_build_object(1711server # [ 138.891672] postgres[1215]: 'message', 'pg_graphql extension is not enabled.'1712server # [ 138.898592] postgres[1215]: )1713server # [ 138.908170] postgres[1215]: )1714server # [ 138.913585] postgres[1215]: );1715server # [ 138.926981] postgres[1215]: ELSE1716server # [ 138.940043] postgres[1215]: RETURN jsonb_build_object(1717server # [ 138.950803] postgres[1215]: 'errors', jsonb_build_array(1718server # [ 138.961927] postgres[1215]: jsonb_build_object(1719server # [ 138.973575] postgres[1215]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1720server # [ 138.984934] postgres[1215]: )1721server # [ 138.995876] postgres[1215]: )1722server # [ 139.003344] postgres[1215]: );1723server # [ 139.019143] postgres[1215]: END IF;1724server # [ 139.030406] postgres[1215]: END;1725server # [ 139.040077] postgres[1215]: $$;1726server # [ 139.055580] postgres[1215]: ::1 2026-10-31 19:39:11.265 UTC [1215] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1727server # [ 140.660093] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1214]: DO1728server # [ 140.747444] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1729server # [ 140.992075] postgres[1237]: ::1 2026-10-31 19:39:13.659 UTC [1237] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1730server # [ 141.017189] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1236]: GRANT ROLE1731server # [ 141.049242] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1732server # [ 141.353973] postgres[1239]: ::1 2026-10-31 19:39:14.020 UTC [1239] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1733server # [ 141.357810] postgres[1239]: returns event_trigger1734server # [ 141.358975] postgres[1239]: language plpgsql1735server # [ 141.362715] postgres[1239]: AS $func$1736server # [ 141.363820] postgres[1239]: DECLARE1737server # [ 141.367867] postgres[1239]: func_is_graphql_resolve bool;1738server # [ 141.372959] postgres[1239]: BEGIN1739server # [ 141.382206] postgres[1239]: func_is_graphql_resolve = (1740server # [ 141.383631] postgres[1239]: SELECT n.proname = 'resolve'1741server # [ 141.387510] postgres[1239]: FROM pg_event_trigger_ddl_commands() AS ev1742server # [ 141.390270] postgres[1239]: LEFT JOIN pg_catalog.pg_proc AS n1743server # [ 141.392741] postgres[1239]: ON ev.objid = n.oid1744server # [ 141.394448] postgres[1239]: );1745server # [ 141.395333] postgres[1239]:1746server # [ 141.398486] postgres[1239]: IF func_is_graphql_resolve1747server # [ 141.402190] postgres[1239]: THEN1748server # [ 141.403379] postgres[1239]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1749server # [ 141.406045] postgres[1239]: DROP FUNCTION IF EXISTS graphql_public.graphql;1750server # [ 141.408038] postgres[1239]: create or replace function graphql_public.graphql(1751server # [ 141.409922] postgres[1239]: "operationName" text default null,1752server # [ 141.413995] postgres[1239]: query text default null,1753server # [ 141.416402] postgres[1239]: variables jsonb default null,1754server # [ 141.421925] postgres[1239]: extensions jsonb default null1755server # [ 141.424121] postgres[1239]: )1756server # [ 141.426760] postgres[1239]: returns jsonb1757server # [ 141.430369] postgres[1239]: language sql1758server # [ 141.431318] postgres[1239]: as $$1759server # [ 141.433888] postgres[1239]: select graphql.resolve(1760server # [ 141.435509] postgres[1239]: query := query,1761server # [ 141.436625] postgres[1239]: variables := coalesce(variables, '{}'),1762server # [ 141.439873] postgres[1239]: "operationName" := "operationName",1763server # [ 141.444176] postgres[1239]: extensions := extensions1764server # [ 141.447169] postgres[1239]: );1765server # [ 141.449376] postgres[1239]: $$;1766server # [ 141.450889] postgres[1239]:1767server # [ 141.451754] postgres[1239]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1768server # [ 141.454590] postgres[1239]: -- function in the extension so we need to grant permissions on existing entities AND1769server # [ 141.457171] postgres[1239]: -- update default permissions to any others that are created after `graphql.resolve`1770server # [ 141.459322] postgres[1239]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1771server # [ 141.461229] postgres[1239]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1772server # [ 141.462596] postgres[1239]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1773server # [ 141.465659] postgres[1239]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1774server # [ 141.467524] postgres[1239]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1775server # [ 141.473370] postgres[1239]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1776server # [ 141.475892] postgres[1239]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1777server # [ 141.480106] postgres[1239]: END IF;1778server # [ 141.483225] postgres[1239]:1779server # [ 141.484418] postgres[1239]: END;1780server # [ 141.485993] postgres[1239]: $func$;1781server # [ 141.494102] postgres[1239]: ::1 2026-10-31 19:39:14.107 UTC [1239] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1782server # [ 141.507930] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1238]: CREATE FUNCTION1783server # [ 141.513824] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1238]: DROP EXTENSION1784server # [ 143.272041] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1238]: DO1785server # [ 143.356258] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql1786server # [ 144.508579] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1256]: DO1787server # [ 145.161779] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1256]: DO1788server # [ 145.220393] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1789server # [ 145.522539] postgres[1263]: ::1 2026-10-31 19:39:18.183 UTC [1263] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s';1790server # [ 145.549687] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1262]: ALTER ROLE1791server # [ 145.589625] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221103090837_revoke_admin.sql1792server # [ 146.067459] postgres[1273]: ::1 2026-10-31 19:39:18.710 UTC [1273] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator;1793server # [ 146.095928] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1267]: REVOKE ROLE1794server # [ 146.107593] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql1795server # [ 148.214040] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1277]: DO1796server # [ 148.276767] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql1797server # [ 148.691072] postgres[1294]: ::1 2026-10-31 19:39:21.342 UTC [1294] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres;1798server # [ 148.739800] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1288]: GRANT ROLE1799server # [ 148.758483] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql1800server: Guest root shell did not produce any data yet...1801server: To debug, enter the VM and run 'systemctl status backdoor.service'.1802server # [ 149.240012] postgres[1307]: ::1 2026-10-31 19:39:21.905 UTC [1307] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option;1803server # [ 149.293100] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1299]: GRANT1804server # [ 149.295351] postgres[1307]: ::1 2026-10-31 19:39:21.966 UTC [1307] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option;1805server # [ 149.331224] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1299]: GRANT1806server # [ 149.337203] postgres[1307]: ::1 2026-10-31 19:39:22.013 UTC [1307] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option;1807server # [ 149.343833] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1299]: GRANT1808server # [ 149.349261] postgres[1307]: ::1 2026-10-31 19:39:22.023 UTC [1307] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option;1809server # [ 149.374592] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1299]: ALTER DEFAULT PRIVILEGES1810server # [ 149.375942] postgres[1307]: ::1 2026-10-31 19:39:22.044 UTC [1307] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option;1811server # [ 149.383109] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1299]: ALTER DEFAULT PRIVILEGES1812server # [ 149.390187] postgres[1307]: ::1 2026-10-31 19:39:22.062 UTC [1307] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option;1813server # [ 149.402962] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1299]: ALTER DEFAULT PRIVILEGES1814server # [ 149.494821] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql1815server # [ 149.823558] postgres[1314]: ::1 2026-10-31 19:39:22.495 UTC [1314] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres;1816server # [ 149.862479] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1312]: GRANT ROLE1817server # [ 149.885678] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql1818server # [ 150.231342] postgres[1318]: ::1 2026-10-31 19:39:22.894 UTC [1318] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin;1819server # [ 150.269594] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1316]: GRANT ROLE1820server # [ 150.287637] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql1821server # [ 150.631354] postgres[1320]: ::1 2026-10-31 19:39:23.291 UTC [1320] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit;1822server # [ 150.642901] postgres[1320]: ::1 2026-10-31 19:39:23.304 UTC [1320] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit;1823server # [ 150.671055] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1319]: ALTER ROLE1824server # [ 150.682555] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1319]: ALTER ROLE1825server # [ 150.686276] postgres[1320]: ::1 2026-10-31 19:39:23.319 UTC [1320] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit;1826server # [ 150.699796] systemd[1]: Starting Virtual Console Setup...1827server # [ 150.715348] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1319]: ALTER ROLE1828server # [ 150.730351] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1319]: DO1829server # [ 150.777769] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql1830server # [ 150.999181] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1831server # [ 151.007919] systemd[1]: Stopped Virtual Console Setup.1832server # [ 151.076032] systemd[1]: Starting Virtual Console Setup...1833server # [ 151.168500] postgres[1324]: ::1 2026-10-31 19:39:23.836 UTC [1324] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin;1834server # [ 151.199424] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1322]: GRANT ROLE1835server # [ 151.210711] postgres[1324]: ::1 2026-10-31 19:39:23.860 UTC [1324] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin;1836server # [ 151.214418] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1322]: REVOKE ROLE1837server # [ 151.248843] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql1838server # [ 151.680371] postgres[1327]: ::1 2026-10-31 19:39:24.343 UTC [1327] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1839server # [ 151.686346] postgres[1327]: returns event_trigger1840server # [ 151.690366] postgres[1327]: language plpgsql1841server # [ 151.706138] postgres[1327]: AS $func$1842server # [ 151.714230] postgres[1327]: DECLARE1843server # [ 151.717563] postgres[1327]: func_is_graphql_resolve bool;1844server # [ 151.721638] postgres[1327]: BEGIN1845server # [ 151.735161] postgres[1327]: func_is_graphql_resolve = (1846server # [ 151.738309] postgres[1327]: SELECT n.proname = 'resolve'1847server # [ 151.747369] postgres[1327]: FROM pg_event_trigger_ddl_commands() AS ev1848server # [ 151.752215] postgres[1327]: LEFT JOIN pg_catalog.pg_proc AS n1849server # [ 151.758073] postgres[1327]: ON ev.objid = n.oid1850server # [ 151.766072] postgres[1327]: );1851server # [ 151.774203] postgres[1327]:1852server # [ 151.779705] postgres[1327]: IF func_is_graphql_resolve1853server # [ 151.789695] postgres[1327]: THEN1854server # [ 151.791805] postgres[1327]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1855server # [ 151.800237] postgres[1327]: DROP FUNCTION IF EXISTS graphql_public.graphql;1856server # [ 151.818292] postgres[1327]: create or replace function graphql_public.graphql(1857server # [ 151.830798] postgres[1327]: "operationName" text default null,1858server # [ 151.842632] postgres[1327]: query text default null,1859server # [ 151.851274] postgres[1327]: variables jsonb default null,1860server # [ 151.855674] postgres[1327]: extensions jsonb default null1861server # [ 151.871784] postgres[1327]: )1862server # [ 151.874764] postgres[1327]: returns jsonb1863server # [ 151.877810] postgres[1327]: language sql1864server # [ 151.879279] postgres[1327]: as $$1865server # [ 151.885678] postgres[1327]: select graphql.resolve(1866server # [ 151.907099] postgres[1327]: query := query,1867server # [ 151.913604] postgres[1327]: variables := coalesce(variables, '{}'),1868server # [ 151.923656] postgres[1327]: "operationName" := "operationName",1869server # [ 151.931633] postgres[1327]: extensions := extensions1870server # [ 151.938213] postgres[1327]: );1871server # [ 151.947667] postgres[1327]: $$;1872server # [ 151.951780] postgres[1327]:1873server # [ 151.959165] postgres[1327]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1874server # [ 151.969884] postgres[1327]: -- function in the extension so we need to grant permissions on existing entities AND1875server # [ 151.978620] postgres[1327]: -- update default permissions to any others that are created after `graphql.resolve`1876server # [ 151.982593] postgres[1327]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1877server # [ 151.985160] postgres[1327]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1878server # [ 151.986759] postgres[1327]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1879server # [ 151.999061] postgres[1327]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1880server # [ 152.008893] postgres[1327]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1881server # [ 152.018107] postgres[1327]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1882server # [ 152.019833] postgres[1327]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1883server # [ 152.029808] postgres[1327]:1884server # [ 152.038445] postgres[1327]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1885server # [ 152.052949] postgres[1327]: grant usage on schema graphql_public to postgres with grant option;1886server # [ 152.054772] postgres[1327]: grant usage on schema graphql to postgres with grant option;1887server # [ 152.055744] postgres[1327]: END IF;1888server # [ 152.057858] postgres[1327]:1889server # [ 152.059385] postgres[1327]: END;1890server # [ 152.067894] postgres[1327]: $func$;1891server # [ 152.077223] postgres[1327]: ::1 2026-10-31 19:39:24.544 UTC [1327] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1892server # [ 152.097914] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1326]: CREATE FUNCTION1893server # [ 152.103026] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1326]: DROP EXTENSION1894server # [ 154.395966] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1326]: DO1895server # [ 154.478009] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql1896server # [ 155.038664] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1348]: DO1897server # [ 155.070131] postgres[1349]: ::1 2026-10-31 19:39:27.703 UTC [1349] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger1898server # [ 155.078275] postgres[1349]: LANGUAGE plpgsql1899server # [ 155.085215] postgres[1349]: AS $$1900server # [ 155.087443] postgres[1349]: BEGIN1901server # [ 155.094014] postgres[1349]: IF EXISTS (1902server # [ 155.102908] postgres[1349]: SELECT1903server # [ 155.104686] postgres[1349]: FROM pg_event_trigger_ddl_commands() AS ev1904server # [ 155.109676] postgres[1349]: JOIN pg_extension AS ext1905server # [ 155.117855] postgres[1349]: ON ev.objid = ext.oid1906server # [ 155.126061] postgres[1349]: WHERE ext.extname = 'pg_cron'1907server # [ 155.127716] postgres[1349]: )1908server # [ 155.134470] postgres[1349]: THEN1909server # [ 155.137983] postgres[1349]: grant usage on schema cron to postgres with grant option;1910server # [ 155.146225] postgres[1349]:1911server # [ 155.149090] postgres[1349]: alter default privileges in schema cron grant all on tables to postgres with grant option;1912server # [ 155.158331] postgres[1349]: alter default privileges in schema cron grant all on functions to postgres with grant option;1913server # [ 155.164803] postgres[1349]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1914server # [ 155.173013] postgres[1349]:1915server # [ 155.180430] postgres[1349]: alter default privileges for user supabase_admin in schema cron grant all1916server # [ 155.191170] postgres[1349]: on sequences to postgres with grant option;1917server # [ 155.194413] postgres[1349]: alter default privileges for user supabase_admin in schema cron grant all1918server # [ 155.195387] postgres[1349]: on tables to postgres with grant option;1919server # [ 155.200744] postgres[1349]: alter default privileges for user supabase_admin in schema cron grant all1920server # [ 155.209918] postgres[1349]: on functions to postgres with grant option;1921server # [ 155.212827] postgres[1349]:1922server # [ 155.217617] postgres[1349]: grant all privileges on all tables in schema cron to postgres with grant option;1923server # [ 155.226343] postgres[1349]: revoke all on table cron.job from postgres;1924server # [ 155.233590] postgres[1349]: grant select on table cron.job to postgres with grant option;1925server # [ 155.247191] postgres[1349]: END IF;1926server # [ 155.254944] postgres[1349]: END;1927server # [ 155.257943] postgres[1349]: $$;1928server # [ 155.261908] postgres[1349]: ::1 2026-10-31 19:39:27.859 UTC [1349] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access;1929server # [ 155.270116] postgres[1349]: ::1 2026-10-31 19:39:27.880 UTC [1349] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin;1930server # [ 155.271296] postgres[1349]: ::1 2026-10-31 19:39:27.914 UTC [1349] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end1931server # [ 155.272066] postgres[1349]: WHEN TAG IN ('CREATE EXTENSION')1932server # [ 155.281223] postgres[1349]: EXECUTE FUNCTION extensions.grant_pg_cron_access();1933server # [ 155.285407] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1348]: CREATE FUNCTION1934server # [ 155.292975] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1348]: DROP EVENT TRIGGER1935server # [ 155.298830] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1348]: ALTER FUNCTION1936server # [ 155.305085] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1348]: CREATE EVENT TRIGGER1937server # [ 155.321316] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql1938server # [ 156.041619] postgres[1351]: ::1 2026-10-31 19:39:28.696 UTC [1351] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s';1939server # [ 156.077272] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1350]: ALTER ROLE1940server # [ 156.198856] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql1941server # [ 156.598055] postgres[1353]: ::1 2026-10-31 19:39:29.248 UTC [1353] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin;1942server # [ 156.650457] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1352]: ALTER FUNCTION1943server # [ 156.661353] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1352]: ALTER FUNCTION1944server # [ 156.673646] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1352]: ALTER FUNCTION1945server # [ 156.687264] postgres[1353]: ::1 2026-10-31 19:39:29.314 UTC [1353] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin;1946server # [ 156.705655] postgres[1353]: ::1 2026-10-31 19:39:29.319 UTC [1353] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin;1947server # [ 156.719426] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql1948server # [ 156.971379] systemd-vconsole-setup[1325]: Configuration of first virtual console was skipped, ignoring remaining ones.1949server # [ 157.048415] systemd[1]: Finished Virtual Console Setup.1950server # [ 157.211573] postgres[1356]: ::1 2026-10-31 19:39:29.863 UTC [1356] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres;1951server # [ 157.281570] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1354]: GRANT ROLE1952server # [ 157.311849] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241031003909_create_orioledb.sql1953server # [ 158.550832] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1954server # [ 158.623731] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1362]: DO1955server # [ 158.687213] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1956server # [ 159.042116] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1379]: DO1957server # [ 159.054687] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1379]: DO1958server # [ 159.110673] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql1959server # [ 159.403339] postgres[1382]: ::1 2026-10-31 19:39:32.069 UTC [1382] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none;1960server # [ 159.418365] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1381]: ALTER ROLE1961server # [ 159.423764] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1381]: ALTER ROLE1962server # [ 159.427254] postgres[1382]: ::1 2026-10-31 19:39:32.089 UTC [1382] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none;1963server # [ 159.429094] postgres[1382]: ::1 2026-10-31 19:39:32.098 UTC [1382] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none;1964server # [ 159.435885] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1381]: ALTER ROLE1965server # [ 159.478037] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql1966server # [ 159.836940] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1383]: DO1967server # [ 159.891187] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1968server # [ 160.407048] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1385]: DO1969server # [ 160.429785] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql1970server # [ 160.960773] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1397]: CREATE FUNCTION1971server # [ 160.967914] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1397]: DO1972server # [ 161.008242] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1973server # [ 161.235719] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1409]: ALTER FUNCTION1974server # [ 161.250176] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1409]: GRANT1975server # [ 161.294295] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql1976server # [ 161.524562] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1411]: DROP EVENT TRIGGER1977server # [ 161.566458] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1411]: ALTER FUNCTION1978server # [ 161.575195] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1411]: CREATE EVENT TRIGGER1979server # [ 161.612503] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql1980server # [ 161.837801] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1413]: DO1981server # [ 161.886238] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql1982server # [ 162.183168] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1415]: CREATE FUNCTION1983server # [ 162.194553] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1415]: GRANT1984server # [ 162.228985] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql1985server # [ 162.509537] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1417]: REVOKE ROLE1986server # [ 162.552462] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1417]: DO1987server # [ 162.598036] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1417]: DO1988server # [ 162.599461] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1417]: REVOKE ROLE1989server # [ 162.628816] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1417]: REVOKE1990server # [ 162.671672] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1417]: DO1991server # [ 162.724677] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql1992server # [ 163.143303] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1425]: DO1993server # [ 163.204794] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql1994server # [ 163.635142] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1436]: DO1995server # [ 163.674378] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql1996server # [ 164.015646] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1443]: DO1997server # [ 164.058359] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql1998server # [ 164.388028] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1445]: ALTER ROLE1999server # [ 164.451452] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251001204436_predefined_role_grants.sql2000server # [ 164.703951] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1447]: GRANT ROLE2001server # [ 164.725454] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1447]: DO2002server # [ 164.769703] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql2003server # [ 165.010714] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1449]: GRANT2004server # [ 165.038529] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql2005server # [ 165.324361] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1451]: CREATE FUNCTION2006server # [ 165.333958] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1451]: REVOKE2007server # [ 165.344107] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1451]: REVOKE2008server # [ 165.357598] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1451]: GRANT2009server # [ 165.398319] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260211120934_supabase_privileged_role.sql2010server # [ 165.687145] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1453]: DO2011server # [ 165.715613] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql2012server # [ 166.123351] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1460]: ALTER ROLE2013server # [ 166.179319] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql2014server # [ 166.551565] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1471]: DROP EXTENSION2015server # [ 166.586394] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql2016server # [ 166.926700] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2017server # [ 167.135214] 8021q: 802.1Q VLAN Support v1.82018server # [ 167.080173] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1480]: CREATE FUNCTION2019server # [ 167.101087] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1480]: DROP EVENT TRIGGER2020server # [ 167.118769] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1480]: CREATE EVENT TRIGGER2021server # [ 167.198302] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql2022server # [ 167.629527] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1484]: DO2023server # [ 167.659574] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1484]: CREATE FUNCTION2024server # [ 167.717033] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql2025server # [ 168.112494] cfg80211: Loading compiled-in X.509 certificates for regulatory database2026server # [ 168.468070] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2027server # [ 168.469010] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2028server # [ 168.386303] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1486]: CREATE FUNCTION2029server # [ 168.516504] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22030server # [ 168.517080] cfg80211: failed to load regulatory.db2031server # [ 168.414142] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1486]: CREATE FUNCTION2032server # [ 168.495833] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20261016000001_search_path_for_supa_functions.sql2033server # [ 168.804567] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1492]: CREATE FUNCTION2034server # [ 168.818676] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1492]: CREATE FUNCTION2035server # [ 168.832247] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1492]: CREATE FUNCTION2036server # [ 168.848050] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1492]: CREATE FUNCTION2037server # [ 168.861403] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1492]: CREATE FUNCTION2038server # [ 168.872922] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1492]: CREATE FUNCTION2039server # [ 168.920924] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Running postgresql schema...2040server # [ 169.188745] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER DATABASE2041server # [ 169.198446] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER DATABASE2042server # [ 169.266804] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2043server # [ 169.327493] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2044server # [ 169.395650] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2045server # [ 169.462455] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2046server # [ 169.533652] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2047server # [ 169.623273] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2048server # [ 169.730122] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2049server # [ 169.835075] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2050server # [ 169.942301] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2051server # [ 169.961009] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1494]: ALTER ROLE2052server # [ 170.032021] 3j17f06sldnfpj8jnk47zvn4s91dkk7h-supabase-db-init[1066]: Database initialization complete2053server # [ 170.082939] systemd[1]: Finished Supabase Database Initialization.2054server # [ 170.169306] (udev-worker)[1498]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.2055server # [ 170.186929] (udev-worker)[1498]: Network interface NamePolicy= disabled on kernel command line.2056server # [ 170.341520] (udev-worker)[1497]: Network interface NamePolicy= disabled on kernel command line.2057server # [ 170.838300] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2058server # [ 170.932885] systemd[1]: Started backdoor.service.2059server # [ 170.942631] systemd[1]: Reached target Multi-User System.2060server # [ 170.986823] systemd[1]: Startup finished in 2.480s (kernel) + 26.246s (initrd) + 2min 22.247s (userspace) = 2min 50.974s.2061server # [ 171.299305] systemd[1]: Condition check resulted in Virtio network device being skipped.2062server # [ 171.419206] systemd[1]: Starting Address configuration of eth1...2063server # connecting to host...2064server: Guest shell says: b'Spawning backdoor root shell...\n'2065server: connected to guest root shell2066server: (connecting took 173.53 seconds)2067server: (finished: waiting for the VM to finish booting, in 173.53 seconds)2068server # [ 172.635786] 8021q: adding VLAN 0 to HW filter on device eth12069server # [ 172.854577] network-addresses-eth1-start[1522]: adding address 192.168.1.1/24... done2070server # [ 173.091097] network-addresses-eth1-start[1522]: adding address 2001:db8:1::1/64... done2071server # [ 173.231215] 8021q: adding VLAN 0 to HW filter on device eth02072server # [ 173.140067] dhcpcd[748]: eth0: waiting for carrier2073server # [ 173.323573] systemd[1]: Finished Address configuration of eth1.2074server # [ 173.549052] dhcpcd[748]: eth0: carrier acquired2075server # [ 173.763533] dhcpcd[748]: DUID 00:01:00:01:32:79:00:82:52:54:00:12:34:562076server # [ 173.770340] dhcpcd[748]: eth0: IAID 00:12:34:562077server # [ 173.774544] dhcpcd[748]: eth0: adding address fe80::5054:ff:fe12:34562078server: (finished: waiting for unit supabase-db-init.service, in 175.09 seconds)2079subtest: Preconditions2080server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select rolsuper from pg_roles where rolname = 'postgres'"2081server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select rolsuper from pg_roles where rolname = 'postgres'", in 0.51 seconds)2082server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select current_user"2083server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select current_user", in 0.49 seconds)2084(finished: subtest: Preconditions, in 0.99 seconds)2085subtest: A non-superuser installs amcheck on PG 15, pinned to pg_catalog2086server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension amcheck"2087server # [ 175.355309] dhcpcd[748]: eth0: soliciting a DHCP lease2088server # [ 175.376642] dhcpcd[748]: eth0: offered 10.0.2.15 from 10.0.2.22089server # [ 175.395672] dhcpcd[748]: eth0: probing address 10.0.2.15/242090server # [ 176.285707] dhcpcd[748]: eth0: soliciting an IPv6 router2091server # [ 176.305660] dhcpcd[748]: eth0: Router Advertisement from fe80::22092server # [ 176.307793] dhcpcd[748]: eth0: adding address fec0::5054:ff:fe12:3456/642093server # [ 176.323945] dhcpcd[748]: eth0: adding route to fec0::/642094server # [ 176.330758] dhcpcd[748]: eth0: adding default route via fe80::22095server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension amcheck", in 1.72 seconds)2096server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'"2097server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'", in 0.45 seconds)2098PG 15 amcheck: 1.3,pg_catalog2099(finished: subtest: A non-superuser installs amcheck on PG 15, pinned to pg_catalog, in 2.17 seconds)2100subtest: PG 15 grants reach postgres only2101server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname"2102server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname", in 0.43 seconds)2103(finished: subtest: PG 15 grants reach postgres only, in 0.43 seconds)2104subtest: amcheck dropped before upgrade, mirroring EXTENSIONS_TO_DISABLE2105server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "drop extension if exists amcheck cascade"2106server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "drop extension if exists amcheck cascade", in 0.38 seconds)2107(finished: subtest: amcheck dropped before upgrade, mirroring EXTENSIONS_TO_DISABLE, in 0.38 seconds)2108subtest: switch to postgresql 172109server # Checking switch inhibitors... done2110server # [ 180.534542] dhcpcd[748]: eth0: leased 10.0.2.15 for 86400 seconds2111server # [ 180.540959] dhcpcd[748]: eth0: adding route to 10.0.2.0/242112server # [ 180.542466] dhcpcd[748]: eth0: adding default route via 10.0.2.22113server # [ 184.499846] systemd-logind[590]: Watching system buttons on /dev/input/event0 (gpio-keys)2114server # stopping the following units: postgresql.service, supabase-db-init.service2115server # [ 186.683428] nixos[1603]: switching to system configuration /nix/store/0fijgca9xv0lg145j2mb8cmjiicqp59y-nixos-system-server-test2116server # [ 186.731338] systemd[1]: supabase-db-init.service: Deactivated successfully.2117server # [ 186.743907] systemd[1]: Stopped Supabase Database Initialization.2118server # [ 186.763100] postgres[1030]: 2026-10-31 19:39:59.411 UTC [1030] LOG: received fast shutdown request2119server # [ 186.808363] systemd[1]: supabase-db-init.service: Consumed 8.269s CPU time over 1min 7.090s wall clock time, 3.9M memory peak, 90.1K incoming IP traffic, 131K outgoing IP traffic.2120server # [ 186.852493] postgres[1030]: 2026-10-31 19:39:59.445 UTC [1030] LOG: aborting any active transactions2121server # [ 186.856549] systemd[1]: Stopping PostgreSQL Database Server...2122server # [ 186.890644] postgres[1063]: 2026-10-31 19:39:59.447 UTC [1063] LOG: pg_cron scheduler shutting down2123server # [ 186.939268] systemd[1]: Stopped target Local File Systems.2124server # [ 186.971565] postgres[1061]: 2026-10-31 19:39:59.447 UTC [1061] FATAL: terminating background worker "TimescaleDB Background Worker Launcher" due to administrator command2125server # [ 186.985165] postgres[1030]: 2026-10-31 19:39:59.658 UTC [1030] LOG: background worker "TimescaleDB Background Worker Launcher" (PID 1061) exited with exit code 12126server # [ 186.987108] postgres[1030]: 2026-10-31 19:39:59.659 UTC [1030] LOG: background worker "pg_cron launcher" (PID 1063) exited with exit code 12127server # [ 187.004765] systemd[1]: Stopped target Remote File Systems.2128server # [ 187.007276] postgres[1030]: 2026-10-31 19:39:59.675 UTC [1030] LOG: background worker "logical replication launcher" (PID 1064) exited with exit code 12129server # [ 187.025683] postgres[1030]: 2026-10-31 19:39:59.699 UTC [1030] LOG: background worker "pg_net 0.20.4 worker" (PID 1082) exited with exit code 12130server # [ 187.047420] postgres[1056]: 2026-10-31 19:39:59.707 UTC [1056] LOG: shutting down2131server # [ 187.057545] postgres[1056]: 2026-10-31 19:39:59.730 UTC [1056] LOG: checkpoint starting: shutdown immediate2132server # [ 187.525838] postgres[1056]: 2026-10-31 19:40:00.196 UTC [1056] LOG: checkpoint complete: wrote 256 buffers (1.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.166 s, sync=0.200 s, total=0.481 s; sync files=132, longest=0.015 s, average=0.002 s; distance=1891 kB, estimate=1891 kB2133server # [ 187.722353] postgres[1030]: 2026-10-31 19:40:00.396 UTC [1030] LOG: database system is shut down2134server # [ 187.835916] systemd[1]: postgresql.service: Deactivated successfully.2135server # [ 187.840777] systemd[1]: Stopped PostgreSQL Database Server.2136server # [ 187.845984] systemd[1]: postgresql.service: Consumed 37.613s CPU time over 1min 46.441s wall clock time, 126.5M memory peak, 45.1M written to disk, 135.4K incoming IP traffic, 94.6K outgoing IP traffic.2137server # activating the configuration...2138server # [ 190.515638] mousedev: PS/2 mouse device common for all mice2139server # setting up /etc...2140server # [ 193.578403] systemd-logind[590]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2141server # [ 195.723877] systemd[1]: Reload requested from client PID 1603 ('.switch-to-conf') (unit backdoor.service)...2142server # [ 195.734116] systemd[1]: Reloading...2143server # [ 201.999919] systemd[1]: Reloading finished in 6243 ms.2144server # restarting sysinit-reactivation.target2145server # [ 202.400787] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....2146server # [ 203.958700] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..2147server # [ 204.002370] systemd[1]: Reached target Reactivate sysinit units.2148server # reloading the following units: dbus-broker.service2149server # [ 204.047522] dbus-broker-launch[588]: Caught SIGHUP, trigger reload.2150server # [ 204.061222] systemd[1]: Reloading D-Bus System Message Bus...2151server # [ 204.327629] dbus-broker-launch[588]: Looking up NSS user entry for 'systemd-timesync'...2152server # [ 204.353566] dbus-broker-launch[588]: NSS returned no entry for 'systemd-timesync'2153server # [ 204.355683] dbus-broker-launch[588]: Invalid user-name in /nix/store/3sy3r4gcahgjcdbrqqgy512vr53vsq3v-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2154server # [ 204.649219] systemd[1]: Reloaded D-Bus System Message Bus.2155server # starting the following units: postgresql.service, supabase-db-init.service2156server # [ 204.680034] systemd[1]: Reached target Remote File Systems.2157server # [ 204.735504] systemd[1]: Reached target Local File Systems.2158server # [ 204.739002] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2159server # [ 204.779320] systemd[1]: Starting Create SUID/SGID Wrappers...2160server # [ 204.784525] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2161server # [ 204.790716] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672162server # [ 204.799220] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2163server # [ 204.806428] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki2164server # [ 204.807884] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki2165server # [ 205.069226] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2166server # [ 208.292706] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2167server # [ 208.302123] systemd[1]: Finished Create SUID/SGID Wrappers.2168server # [ 208.303688] systemd[1]: suid-sgid-wrappers.service: Consumed 1.559s CPU time over 3.508s wall clock time, 3.2M memory peak.2169server # [ 208.346712] systemd[1]: Starting linger-users.service...2170server # [ 208.399848] systemd[1]: Starting PostgreSQL Major Version Migration...2171server # [ 208.704966] systemd[1]: linger-users.service: Deactivated successfully.2172server # [ 208.724000] systemd[1]: Finished linger-users.service.2173server # [ 208.753012] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1951]: Starting pg_upgrade from 15 to 172174server # [ 209.452140] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: The files belonging to this database system will be owned by user "postgres".2175server # [ 209.458706] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: This user must also own the server process.2176server # [ 209.506349] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: The database cluster will be initialized with locale "en_US.UTF-8".2177server # [ 209.507317] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: The default database encoding has accordingly been set to "UTF8".2178server # [ 209.509216] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: The default text search configuration will be set to "english".2179server # [ 209.509784] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: Data page checksums are enabled.2180server # [ 209.510570] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: fixing permissions on existing directory /var/lib/postgresql/data-17 ... ok2181server # [ 209.511650] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: creating subdirectories ... ok2182server # [ 209.517056] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: selecting dynamic shared memory implementation ... posix2183server # [ 210.150024] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: selecting default "max_connections" ... 1002184server # [ 210.718586] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: selecting default "shared_buffers" ... 128MB2185server # [ 220.721159] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: selecting default time zone ... UTC2186server # [ 220.773153] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: creating configuration files ... ok2187server # [ 224.571105] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: running bootstrap script ... ok2188server # [ 234.313913] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: performing post-bootstrap initialization ... ok2189server # [ 237.213918] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: syncing data to disk ... ok2190server # [ 237.217885] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: initdb: warning: enabling "trust" authentication for local connections2191server # [ 237.218899] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: 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.2192server # [ 237.220111] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: Success. You can now start the database server using:2193server # [ 237.222310] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1955]: /nix/store/dk435l1mppbbmm8idkycp5q7p6p1fs68-postgresql-and-plugins-17.11/bin/pg_ctl -D /var/lib/postgresql/data-17 -l logfile start2194server # [ 243.791632] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Performing Consistency Checks2195server # [ 243.908629] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: -----------------------------2196server # [ 243.933347] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking cluster versions ok2197server # [ 249.358412] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking database user is the install user ok2198server # [ 249.453015] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking database connection settings ok2199server # [ 249.561043] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking for prepared transactions ok2200server # [ 249.562608] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking for contrib/isn with bigint-passing mismatch ok2201server # [ 250.677525] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking data type usage ok2202server # [ 251.237686] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking for not-null constraint inconsistencies ok2203server # [ 252.158544] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Creating dump of global objects ok2204server # [ 256.088065] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Creating dump of database schemas ok2205server # [ 261.705363] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking for presence of required libraries ok2206server # [ 261.828898] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking database user is the install user ok2207server # [ 261.967969] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking for prepared transactions ok2208server # [ 261.970178] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking for new cluster tablespace directories ok2209server # [ 261.992076] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: If pg_upgrade fails after this point, you must re-initdb the2210server # [ 262.006355] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: new cluster before continuing.2211server # [ 262.017020] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Performing Upgrade2212server # [ 262.019676] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: ------------------2213server # [ 262.105181] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Setting locale and encoding for new cluster ok2214server # [ 266.163952] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Analyzing all rows in the new cluster ok2215server # [ 267.937208] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Freezing all rows in the new cluster ok2216server # [ 268.270617] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2217server # [ 268.306340] systemd[1]: Stopped Virtual Console Setup.2218server # [ 268.317005] systemd[1]: systemd-vconsole-setup.service: Consumed 1.431s CPU time over 1min 57.207s wall clock time, 2.7M memory peak.2219server # [ 268.320233] systemd[1]: Stopping Virtual Console Setup...2220server # [ 268.371854] systemd[1]: Starting Virtual Console Setup...2221server # [ 268.679818] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Deleting files from new pg_xact ok2222server # [ 269.063967] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Copying old pg_xact to new server ok2223server # [ 269.892842] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Setting oldest XID for new cluster ok2224server # [ 272.342156] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Setting next transaction ID and epoch for new cluster ok2225server # [ 272.349978] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Deleting files from new pg_multixact/offsets ok2226server # [ 272.553258] systemd-vconsole-setup[2453]: Configuration of first virtual console was skipped, ignoring remaining ones.2227server # [ 272.617836] systemd[1]: Finished Virtual Console Setup.2228server # [ 272.682298] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Copying old pg_multixact/offsets to new server ok2229server # [ 272.689131] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Deleting files from new pg_multixact/members ok2230server # [ 272.982792] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Copying old pg_multixact/members to new server ok2231server # [ 273.434157] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Setting next multixact ID and offset for new cluster ok2232server # [ 273.805904] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Resetting WAL archives ok2233server # [ 275.797474] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2234server # [ 276.126058] systemd-logind[590]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2235server # [ 277.861843] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Setting frozenxid and minmxid counters in new cluster ok2236server # [ 278.876813] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Restoring global objects in the new cluster ok2237server # [ 282.709368] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Restoring database schemas in the new cluster ok2238server # [ 283.625391] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Copying user relation files ok2239server # [ 283.930869] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Setting next OID for new cluster ok2240server # [ 285.740582] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Sync data directory to disk ok2241server # [ 285.743771] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Creating script to delete old cluster ok2242server # [ 290.121739] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Checking for extension updates notice2243server # [ 290.126707] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Your installation contains extensions that should be updated2244server # [ 290.129499] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: with the ALTER EXTENSION command. The file2245server # [ 290.130725] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: update_extensions.sql2246server # [ 290.133259] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: when executed by psql by the database superuser will update2247server # [ 290.135662] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: these extensions.2248server # [ 290.472609] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Upgrade Complete2249server # [ 290.475961] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: ----------------2250server # [ 290.479364] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Optimizer statistics are not transferred by pg_upgrade.2251server # [ 290.483581] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Once you start the new server, consider running:2252server # [ 290.487257] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: /nix/store/dk435l1mppbbmm8idkycp5q7p6p1fs68-postgresql-and-plugins-17.11/bin/vacuumdb -U supabase_admin --all --analyze-in-stages2253server # [ 290.491189] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: Running this script will delete the old cluster's data files:2254server # [ 290.493614] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[2165]: ./delete_old_cluster.sh2255server # [ 290.525863] 3rg9dj71plfqlxivxhnr14jlmflmvi8p-postgresql-migrate[1951]: pg_upgrade completed successfully2256server # [ 290.550895] systemd[1]: Finished PostgreSQL Major Version Migration.2257server # [ 290.582378] systemd[1]: Starting PostgreSQL Database Server...2258server # [ 292.079595] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[2569]: Validating PostgreSQL configuration...2259server # [ 292.428619] qd2lxz6xfbyr9hdf968bwnx0xdpmj37j-postgresql-init[2581]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault2260server # [ 293.101944] postgres[2583]: 2026-10-31 19:41:45.770 UTC [2583] LOG: pgaudit extension initialized2261server # [ 293.403379] postgres[2583]: 2026-10-31 19:41:46.078 UTC [2583] LOG: pgsodium primary server secret key loaded2262server # [ 293.608741] postgres[2583]: 2026-10-31 19:41:46.284 UTC [2583] LOG: vault primary server secret key loaded2263server # [ 293.854794] postgres[2583]: 2026-10-31 19:41:46.530 UTC [2583] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.3.0, 64-bit2264server # [ 293.865126] postgres[2583]: 2026-10-31 19:41:46.540 UTC [2583] LOG: listening on IPv4 address "0.0.0.0", port 54322265server # [ 293.870045] postgres[2583]: 2026-10-31 19:41:46.541 UTC [2583] LOG: listening on IPv6 address "::", port 54322266server # [ 293.886902] postgres[2583]: 2026-10-31 19:41:46.562 UTC [2583] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2267server # [ 293.970144] postgres[2588]: 2026-10-31 19:41:46.638 UTC [2588] LOG: database system was shut down at 2026-10-31 19:41:42 UTC2268server # [ 294.099769] postgres[2583]: 2026-10-31 19:41:46.766 UTC [2583] LOG: database system is ready to accept connections2269server # [ 294.140031] systemd[1]: Started PostgreSQL Database Server.2270server # [ 294.194034] postgres[2592]: 2026-10-31 19:41:46.847 UTC [2592] LOG: pg_cron scheduler started2271server # [ 294.224950] systemd[1]: Starting Supabase Database Initialization...2272server # [ 294.498596] systemd[1]: Finished Supabase Database Initialization.2273server # the following new units were started: postgresql-migrate.service, sys-devices-virtual-input-mice.device, sys-devices-virtual-misc-rfkill.device, sysinit-reactivation.target, systemd-rfkill.socket, systemd-tmpfiles-resetup.service2274server # [ 294.800653] nixos[1603]: finished switching to system configuration /nix/store/0fijgca9xv0lg145j2mb8cmjiicqp59y-nixos-system-server-test2275server: waiting for unit postgresql.service2276server: (finished: waiting for unit postgresql.service, in 0.55 seconds)2277(finished: subtest: switch to postgresql 17, in 117.61 seconds)2278subtest: amcheck recreated fresh on PG 17, mirroring the EXTENSIONS_TO_DISABLE fix2279server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension if not exists amcheck cascade"2280server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension if not exists amcheck cascade", in 1.27 seconds)2281server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select default_version from pg_available_extensions where name = 'amcheck'"2282server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select default_version from pg_available_extensions where name = 'amcheck'", in 0.89 seconds)2283server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'"2284server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'", in 0.42 seconds)2285server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname"2286server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname", in 0.45 seconds)2287(finished: subtest: amcheck recreated fresh on PG 17, mirroring the EXTENSIONS_TO_DISABLE fix, in 3.03 seconds)2288subtest: The support workflow still works after the upgrade2289server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create table amcheck_heap(i int primary key) using heap; insert into amcheck_heap select generate_series(1, 100)"2290server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create table amcheck_heap(i int primary key) using heap; insert into amcheck_heap select generate_series(1, 100)", in 0.49 seconds)2291server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass)"2292server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass)", in 0.43 seconds)2293server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass, false, false)"2294server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass, false, false)", in 0.41 seconds)2295server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('auth.users_pkey'::regclass)"2296server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('auth.users_pkey'::regclass)", in 0.41 seconds)2297(finished: subtest: The support workflow still works after the upgrade, in 1.74 seconds)2298(finished: run the VM test script, in 301.66 seconds)2299test script finished in 302.06s2300cleanup2301kill QemuMachine (pid 13)2302server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/vilynzly1pyhawy04ljmrp7n9ffr24qy-python3-3.13.17/bin/python3.13)2303(finished: cleanup, in 0.07 seconds)