vm-test-run-wrappers-regression-PSQL-1387
checks.aarch64-linux.ext-wrappers-regression-PSQL-1387
· build #474
· 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 # Disk image does not exist, creating the virtualisation disk image...13server # Formatting '/build/vm-state-server/tmp.ejNa20Dq3H', fmt=raw size=107374182414server: QEMU running (pid 13)15server # 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: ee42a145-88f3-45b7-b79e-91c6984c273019server # 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.31 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.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 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 s186520 r8192 d116584 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/98k940gqfarnmsxiry7yqi1w40453j1m-nixos-system-server-test/init regInfo=/nix/store/zri54rd94whqs6rhkh4bxy69132dqgkr-closure-info/registration console=ttyAMA0,115200n8 console=tty068server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/zri54rd94whqs6rhkh4bxy69132dqgkr-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 74832 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 @100b10000 (indirect, esz 8, psz 64K, shr 1)102server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100b20000 (flat, esz 8, psz 64K, shr 1)103server # [ 0.000000] GICv3: using LPI property table @0x0000000100b30000104server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b40000105server # [ 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.000048] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns110server # [ 0.005239] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)111server # [ 0.018476] Console: colour dummy device 80x25112server # [ 0.020819] printk: legacy console [tty0] enabled113server # [ 0.027955] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)114server # [ 0.029263] pid_max: default: 32768 minimum: 301115server # [ 0.032541] LSM: initializing lsm=capability,landlock,yama,bpf,ima116server # [ 0.035481] landlock: Up and running.117server # [ 0.035573] Yama: becoming mindful.118server # [ 0.039632] LSM support for eBPF active119server # [ 0.043374] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)120server # [ 0.043881] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)121server # [ 0.095241] rcu: Hierarchical SRCU implementation.122server # [ 0.095350] rcu: Max phase no-delay instances is 1000.123server # [ 0.096792] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level124server # [ 0.105653] fsl-mc MSI: its@8080000 domain created125server # [ 0.108248] EFI services will not be available.126server # [ 0.110350] smp: Bringing up secondary CPUs ...127server # [ 0.117784] Detected PIPT I-cache on CPU1128server # [ 0.119488] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000129server # [ 0.120173] GICv3: CPU1: using allocated LPI pending table @0x0000000100b50000130server # [ 0.122061] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]131server # [ 0.126717] smp: Brought up 1 node, 2 CPUs132server # [ 0.126904] SMP: Total of 2 processors activated.133server # [ 0.126953] CPU: All CPU(s) started at EL1134server # [ 0.127157] CPU features: detected: Branch Target Identification135server # [ 0.127215] CPU features: detected: 32-bit EL0 Support136server # [ 0.127250] CPU features: detected: 32-bit EL1 Support137server # [ 0.127283] CPU features: detected: ARMv8.4 Translation Table Level138server # [ 0.127327] CPU features: detected: Instruction cache invalidation not required for I/D coherence139server # [ 0.127375] CPU features: detected: Data cache clean to the PoU not required for I/D coherence140server # [ 0.127439] CPU features: detected: Common not Private translations141server # [ 0.127473] CPU features: detected: CRC32 instructions142server # [ 0.127498] CPU features: detected: Data cache clean to Point of Deep Persistence143server # [ 0.127534] CPU features: detected: Data cache clean to Point of Persistence144server # [ 0.127568] CPU features: detected: Data independent timing control (DIT)145server # [ 0.127598] CPU features: detected: E0PD146server # [ 0.127625] CPU features: detected: Enhanced Counter Virtualization147server # [ 0.127656] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)148server # [ 0.127690] CPU features: detected: Enhanced Privileged Access Never149server # [ 0.127722] CPU features: detected: Enhanced Virtualization Traps150server # [ 0.127754] CPU features: detected: Fine Grained Traps151server # [ 0.127785] CPU features: detected: Guarded Control Stack (GCS)152server # [ 0.127857] CPU features: detected: Generic authentication (IMP DEF algorithm)153server # [ 0.127952] CPU features: detected: RCpc load-acquire (LDAPR)154server # [ 0.128011] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)155server # [ 0.128045] CPU features: detected: LSE atomic instructions156server # [ 0.128073] CPU features: detected: Memory Copy and Memory Set instructions157server # [ 0.128107] CPU features: detected: Privileged Access Never158server # [ 0.128158] CPU features: detected: PMUv3159server # [ 0.128185] CPU features: detected: SCTLR2160server # [ 0.128209] CPU features: detected: RAS Extension Support161server # [ 0.128279] CPU features: detected: RASv1p1 Extension Support162server # [ 0.128311] CPU features: detected: Random Number Generator163server # [ 0.128338] CPU features: detected: Speculation barrier (SB)164server # [ 0.128363] CPU features: detected: Stage-2 Force Write-Back165server # [ 0.128388] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality166server # [ 0.128418] CPU features: detected: TLB range maintenance instructions167server # [ 0.128445] CPU features: detected: WFx with timeout168server # [ 0.128521] CPU features: detected: Scalable Matrix Extension169server # [ 0.128559] CPU features: detected: FA64170server # [ 0.128582] CPU features: detected: SME2171server # [ 0.128604] CPU features: detected: Speculative Store Bypassing Safe (SSBS)172server # [ 0.128632] CPU features: detected: Scalable Vector Extension173server # [ 0.136282] alternatives: applying system-wide alternatives174server # [ 0.153576] CPU features: detected: Hardware dirty bit management on CPU0-1175server # [ 0.154354] SVE: maximum available vector length 256 bytes per vector176server # [ 0.154450] SVE: default vector length 64 bytes per vector177server # [ 0.155732] SME: minimum available vector length 16 bytes per vector178server # [ 0.156621] SME: maximum available vector length 256 bytes per vector179server # [ 0.156672] SME: default vector length 32 bytes per vector180server # [ 0.173683] Memory: 3904032K/4194304K available (24320K kernel code, 7090K rwdata, 26572K rodata, 4736K init, 1103K bss, 241772K reserved, 32768K cma-reserved)181server # [ 0.200814] devtmpfs: initialized182server # [ 0.238113] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear)183server # [ 0.238451] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear).184server # [ 0.241787] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL185server # [ 0.241887] 0 pages in range for non-PLT usage186server # [ 0.241946] 508272 pages in range for PLT usage187server # [ 0.244836] pinctrl core: initialized pinctrl subsystem188server # [ 0.256781] DMI not present or invalid.189server # [ 0.279343] NET: Registered PF_NETLINK/PF_ROUTE protocol family190server # [ 0.294451] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations191server # [ 0.296364] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations192server # [ 0.297687] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations193server # [ 0.297998] audit: initializing netlink subsys (disabled)194server # [ 0.300239] audit: type=2000 audit(0.236:1): state=initialized audit_enabled=0 res=1195server # [ 0.309250] thermal_sys: Registered thermal governor 'fair_share'196server # [ 0.309327] thermal_sys: Registered thermal governor 'bang_bang'197server # [ 0.309404] thermal_sys: Registered thermal governor 'step_wise'198server # [ 0.309442] thermal_sys: Registered thermal governor 'user_space'199server # [ 0.309477] thermal_sys: Registered thermal governor 'power_allocator'200server # [ 0.310432] cpuidle: using governor ladder201server # [ 0.311173] cpuidle: using governor menu202server # [ 0.313651] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.203server # [ 0.314603] ASID allocator initialised with 65536 entries204server # [ 0.324038] Serial: AMBA PL011 UART driver205server # [ 0.395917] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1206server # [ 0.399390] printk: console [ttyAMA0] enabled207server # [ 0.476333] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages208server # [ 0.476822] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page209server # [ 0.476908] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages210server # [ 0.476946] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page211server # [ 0.477098] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages212server # [ 0.477205] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page213server # [ 0.477278] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages214server # [ 0.477384] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page215server # [ 0.495577] fbcon: Taking over console216server # [ 0.495876] ACPI: Interpreter disabled.217server # [ 0.507792] iommu: Default domain type: Translated218server # [ 0.507895] iommu: DMA domain TLB invalidation policy: strict mode219server # [ 0.520862] SCSI subsystem initialized220server # [ 0.523978] usbcore: registered new interface driver usbfs221server # [ 0.524244] usbcore: registered new interface driver hub222server # [ 0.524430] usbcore: registered new device driver usb223server # [ 0.527393] pps_core: LinuxPPS API ver. 1 registered224server # [ 0.527464] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>225server # [ 0.527575] PTP clock support registered226server # [ 0.527906] EDAC MC: Ver: 3.0.0227server # [ 0.534314] scmi_core: SCMI protocol bus registered228server # [ 0.538257] FPGA manager framework229server # [ 0.554904] vgaarb: loaded230server # [ 0.562496] clocksource: Switched to clocksource arch_sys_counter231server # [ 0.591018] VFS: Disk quotas dquot_6.6.0232server # [ 0.591258] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)233server # [ 0.593876] netfs: FS-Cache loaded234server # [ 0.595694] pnp: PnP ACPI: disabled235server # [ 0.675212] NET: Registered PF_INET protocol family236server # [ 0.676501] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)237server # [ 0.803481] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)238server # [ 0.803715] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)239server # [ 0.803872] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)240server # [ 0.804340] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)241server # [ 0.804729] TCP: Hash tables configured (established 32768 bind 32768)242server # [ 0.809593] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)243server # [ 0.811962] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)244server # [ 0.812369] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)245server # [ 0.814881] NET: Registered PF_UNIX/PF_LOCAL protocol family246server # [ 0.815455] NET: Registered PF_XDP protocol family247server # [ 0.815665] PCI: CLS 0 bytes, default 64248server # [ 0.818476] kvm [1]: HYP mode not available249server # [ 0.823012] Trying to unpack rootfs image as initramfs...250server # [ 0.996622] Initialise system trusted keyrings251server # [ 1.000739] workingset: timestamp_bits=42 max_order=20 bucket_order=0252server # [ 1.012186] squashfs: version 4.0 (2009/01/31) Phillip Lougher253server # [ 1.014761] 9p: Installing v9fs 9p2000 file system support254server # [ 1.085969] Key type asymmetric registered255server # [ 1.087154] Asymmetric key parser 'x509' registered256server # [ 1.087650] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)257server # [ 1.088679] io scheduler mq-deadline registered258server # [ 1.088770] io scheduler kyber registered259server # [ 1.139347] pl061_gpio 9030000.pl061: PL061 GPIO chip registered260server # [ 1.149747] ledtrig-cpu: registered to indicate activity on CPUs261server # [ 1.157142] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:262server # [ 1.157760] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000263server # [ 1.160368] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000264server # [ 1.160510] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000265server # [ 1.161120] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits266server # [ 1.161636] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]267server # [ 1.167921] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00268server # [ 1.168283] pci_bus 0000:00: root bus resource [bus 00-ff]269server # [ 1.168389] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]270server # [ 1.168465] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]271server # [ 1.168507] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]272server # [ 1.169784] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint273server # [ 1.176762] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint274server # [ 1.177142] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]275server # [ 1.177248] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]276server # [ 1.177319] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]277server # [ 1.177424] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]278server # [ 1.180455] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint279server # [ 1.180584] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]280server # [ 1.180630] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]281server # [ 1.180673] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282server # [ 1.181393] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint283server # [ 1.181505] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]284server # [ 1.181551] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]285server # [ 1.181597] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]286server # [ 1.182114] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287server # [ 1.182203] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]288server # [ 1.182242] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]289server # [ 1.182283] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290server # [ 1.182760] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291server # [ 1.189338] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]292server # [ 1.189443] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]293server # [ 1.189491] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]294server # [ 1.190220] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint295server # [ 1.190328] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]296server # [ 1.190372] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]297server # [ 1.190416] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]298server # [ 1.193974] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint299server # [ 1.194130] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]300server # [ 1.194180] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]301server # [ 1.194223] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302server # [ 1.194264] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]303server # [ 1.197447] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint304server # [ 1.197586] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]305server # [ 1.197635] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]306server # [ 1.199936] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint307server # [ 1.200074] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]308server # [ 1.200134] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]309server # [ 1.200825] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint310server # [ 1.200916] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]311server # [ 1.201722] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint312server # [ 1.202046] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]313server # [ 1.202175] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]314server # [ 1.202256] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]315server # [ 1.206309] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint316server # [ 1.206464] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]317server # [ 1.206513] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]318server # [ 1.206556] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]319server # [ 1.208701] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned320server # [ 1.208880] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned321server # [ 1.212464] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned322server # [ 1.212674] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned323server # [ 1.212765] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned324server # [ 1.212829] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned325server # [ 1.212897] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned326server # [ 1.212953] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned327server # [ 1.213144] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned328server # [ 1.213215] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned329server # [ 1.213282] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned330server # [ 1.213341] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned331server # [ 1.213424] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned332server # [ 1.213491] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned333server # [ 1.213545] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned334server # [ 1.213593] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned335server # [ 1.213634] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned336server # [ 1.213675] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned337server # [ 1.213715] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned338server # [ 1.213756] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned339server # [ 1.213796] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned340server # [ 1.213836] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned341server # [ 1.213877] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned342server # [ 1.213918] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned343server # [ 1.213959] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned344server # [ 1.214008] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned345server # [ 1.214063] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned346server # [ 1.231250] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned347server # [ 1.231350] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned348server # [ 1.231403] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned349server # [ 1.231459] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned350server # [ 1.232066] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned351server # [ 1.232128] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned352server # [ 1.232454] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned353server # [ 1.232826] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]354server # [ 1.232888] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]355server # [ 1.232929] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]356server # [ 1.242670] pci 0000:00:0a.0: enabling device (0000 -> 0002)357server # [ 1.330922] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)358server # [ 1.336833] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)359server # [ 1.340958] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)360server # [ 1.345655] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)361server # [ 1.356299] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)362server # [ 1.360849] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)363server # [ 1.364475] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)364server # [ 1.369692] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)365server # [ 1.373660] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)366server # [ 1.377748] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)367server # [ 1.382111] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)368server # [ 1.407814] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled369server # [ 1.421418] msm_serial: driver initialized370server # [ 1.422493] SuperH (H)SCI(F) driver initialized371server # [ 1.422978] STM32 USART driver initialized372server # [ 1.498237] loop: module loaded373server # [ 1.499646] virtio_blk virtio5: 2/0/0 default/read/poll queues374server # [ 1.505055] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)375server # [ 1.520839] megasas: 07.734.00.00-rc1376server # [ 1.528739] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]377server # [ 1.532818] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000378server # [ 1.533740] Intel/Sharp Extended Query Table at 0x0031379server # [ 1.538262] Using buffer write method380server # [ 1.540304] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]381server # [ 1.541925] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000382server # [ 1.542255] Intel/Sharp Extended Query Table at 0x0031383server # [ 1.544463] Using buffer write method384server # [ 1.544903] Concatenating MTD devices:385server # [ 1.545054] (0): "0.flash"386server # [ 1.545160] (1): "0.flash"387server # [ 1.545203] into device "0.flash"388server # [ 2.144503] Freeing initrd memory: 25488K389server # [ 2.277568] tun: Universal TUN/TAP device driver, 1.6390server # [ 2.296390] thunder_xcv, ver 1.0391server # [ 2.296617] thunder_bgx, ver 1.0392server # [ 2.296757] nicpf, ver 1.0393server # [ 2.300741] e1000: Intel(R) PRO/1000 Network Driver394server # [ 2.300810] e1000: Copyright (c) 1999-2006 Intel Corporation.395server # [ 2.301203] e1000e: Intel(R) PRO/1000 Network Driver396server # [ 2.301240] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.397server # [ 2.301390] igb: Intel(R) Gigabit Ethernet Network Driver398server # [ 2.301432] igb: Copyright (c) 2007-2014 Intel Corporation.399server # [ 2.301593] igbvf: Intel(R) Gigabit Virtual Function Network Driver400server # [ 2.301637] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.401server # [ 2.303842] sky2: driver version 1.30402server # [ 2.317936] ehci-pci 0000:00:0a.0: EHCI Host Controller403server # [ 2.319498] usbcore: registered new interface driver usb-storage404server # [ 2.319815] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1405server # [ 2.320560] usbcore: registered new interface driver usbserial_generic406server # [ 2.320878] usbserial: USB Serial support registered for generic407server # [ 2.327107] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000408server # [ 2.329174] hv_vmbus: registering driver hyperv_keyboard409server # [ 2.342647] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00410server # [ 2.344522] rtc-pl031 9010000.pl031: registered as rtc0411server # [ 2.345417] rtc-pl031 9010000.pl031: setting system clock to 2026-10-27T16:04:32 UTC (1793117072)412server # [ 2.351371] i2c_dev: i2c /dev entries driver413server # [ 2.364546] hub 1-0:1.0: USB hub found414server # [ 2.365870] hub 1-0:1.0: 6 ports detected415server # [ 2.382742] sdhci: Secure Digital Host Controller Interface driver416server # [ 2.382833] sdhci: Copyright(c) Pierre Ossman417server # [ 2.385598] Synopsys Designware Multimedia Card Interface Driver418server # [ 2.388545] sdhci-pltfm: SDHCI platform and OF driver helper419server # [ 2.400907] hid: raw HID events driver (C) Jiri Kosina420server # [ 2.403519] usbcore: registered new interface driver usbhid421server # [ 2.403596] usbhid: USB HID core driver422server # [ 2.416493] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available423server # [ 2.428438] drop_monitor: Initializing network drop monitor service424server # [ 2.429785] NET: Registered PF_INET6 protocol family425server # [ 2.441968] Segment Routing with IPv6426server # [ 2.442258] In-situ OAM (IOAM) with IPv6427server # [ 2.442863] NET: Registered PF_PACKET protocol family428server # [ 2.445322] 9pnet: Installing 9P2000 support429server # [ 2.450710] Key type dns_resolver registered430server # [ 2.503871] registered taskstats version 1431server # [ 2.507680] Loading compiled-in X.509 certificates432server # [ 2.590697] Demotion targets for Node 0: null433server # [ 2.591938] Key type .fscrypt registered434server # [ 2.591999] Key type fscrypt-provisioning registered435server # [ 2.596554] ima: No TPM chip found, activating TPM-bypass!436server # [ 2.596690] ima: Allocated hash algorithm: sha1437server # [ 2.599301] ima: No architecture policies found438server # [ 2.608512] input: gpio-keys as /devices/platform/gpio-keys/input/input0439server # [ 2.635061] usb 1-1: new high-speed USB device number 2 using ehci-pci440server # [ 2.662455] clk: Disabling unused clocks441server # [ 2.662806] PM: genpd: Disabling unused power domains442server # [ 2.819164] 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.890786] 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 # [ 3.010473] usb 1-2: new high-speed USB device number 3 using ehci-pci445server # [ 3.048309] Freeing unused kernel memory: 4736K446server # [ 3.051624] Run /init as init process447server # [ 3.214093] 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/input2448server # [ 3.215487] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0449server # [ 3.308778] systemd[1]: Successfully made /usr/ read-only.450server # [ 3.667721] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)451server # [ 3.667977] systemd[1]: Detected virtualization qemu.452server # [ 3.668336] systemd[1]: Detected architecture arm64.453server # [ 3.668406] systemd[1]: Running in initrd.454server # [ 3.676409] systemd[1]: Initializing machine ID from random generator.455server # [ 3.683328] systemd[1]: Hostname set to <server>.456server # [ 4.655930] systemd[1]: Queued start job for default target Initrd Default Target.457server # [ 4.719595] systemd[1]: Created slice Slice /system/modprobe.458server # [ 4.725534] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459server # [ 4.726312] systemd[1]: Expecting device /dev/disk/by-label/nixos...460server # [ 4.727484] systemd[1]: Reached target Path Units.461server # [ 4.727962] systemd[1]: Reached target Slice Units.462server # [ 4.728153] systemd[1]: Reached target Swaps.463server # [ 4.728326] systemd[1]: Reached target Timer Units.464server # [ 4.732958] systemd[1]: Listening on D-Bus System Message Bus Socket.465server # [ 4.735528] systemd[1]: Listening on Journal Socket (/dev/log).466server # [ 4.736941] systemd[1]: Listening on Journal Sockets.467server # [ 4.739380] systemd[1]: Listening on udev Control Socket.468server # [ 4.740181] systemd[1]: Listening on udev Kernel Socket.469server # [ 4.740394] systemd[1]: Reached target Socket Units.470server # [ 4.767696] systemd[1]: Starting Create List of Static Device Nodes...471server # [ 4.792457] systemd[1]: Starting Load Kernel Module 9pnet_virtio...472server # [ 4.799455] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs473server # [ 4.827303] systemd[1]: Mounting Kernel Configuration File System...474server # [ 4.979508] systemd[1]: Starting Journal Service...475server # [ 5.037804] systemd[1]: Starting Load Kernel Modules...476server # [ 5.043252] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki477server # [ 5.085993] systemd[1]: Starting Coldplug All udev Devices...478server # [ 5.208476] systemd[1]: Finished Create List of Static Device Nodes.479server # [ 5.242150] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480server # [ 5.244909] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481server # [ 5.260123] systemd[1]: Mounted Kernel Configuration File System.482server # [ 5.283180] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...483server # [ 5.540139] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.484server # [ 5.555719] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev485server # [ 5.569559] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0486server # [ 5.573913] [drm] features: -virgl +edid -resource_blob -host_visible487server # [ 5.573966] [drm] features: -context_init488server # [ 5.577810] [drm] number of scanouts: 1489server # [ 5.577913] [drm] number of cap sets: 0490server # [ 5.595812] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.491server # [ 5.596219] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic492server # [ 5.596333] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0493server # [ 5.644249] systemd[1]: Starting Create Static Device Nodes in /dev...494server # [ 5.644924] systemd-journald[81]: Collecting audit messages is disabled.495server # [ 5.669190] Console: switching to colour frame buffer device 160x50496server # [ 5.699077] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device497server # [ 5.771937] systemd[1]: Finished Load Kernel Modules.498server # [ 5.827210] systemd[1]: Starting Apply Kernel Variables...499server # [ 5.984804] systemd[1]: Finished Create Static Device Nodes in /dev.500server # [ 5.989376] systemd[1]: Reached target Preparation for Local File Systems.501server # [ 5.998974] systemd[1]: Reached target Local File Systems.502server # [ 6.032947] systemd[1]: Starting Rule-based Manager for Device Events and Files...503server # [ 6.105469] systemd[1]: Finished Apply Kernel Variables.504server # [ 6.865686] systemd[1]: Started Journal Service.505server # [ 6.753158] systemd-modules-load[82]: Using 2 probe threads506server # [ 6.772648] systemd-modules-load[82]: Module 'virtio_balloon' is built in507server # [ 6.779302] systemd-modules-load[82]: Module 'virtio_console' is built in508server # [ 6.785389] systemd-modules-load[82]: Inserted module 'dm_mod'509server # [ 6.791629] systemd-modules-load[82]: Module 'virtio_rng' is built in510server # [ 6.802287] systemd-modules-load[82]: Inserted module 'virtio_gpu'511server # [ 6.825172] systemd-udevd[89]: Using default interface naming scheme 'v260'.512server # [ 6.866147] systemd[1]: Starting Create System Files and Directories...513server # [ 6.883543] systemd[1]: Started Rule-based Manager for Device Events and Files.514server # [ 7.369440] systemd[1]: Finished Create System Files and Directories.515server # [ 7.546479] systemd[1]: Starting Virtual Console Setup...516server # [ 7.853814] systemd-vconsole-setup[111]: Configuration of first virtual console was skipped, ignoring remaining ones.517server # [ 7.874625] systemd[1]: Finished Virtual Console Setup.518server # [ 12.726179] systemd[1]: Finished Coldplug All udev Devices.519server # [ 12.738283] systemd[1]: Reached target System Initialization.520server # [ 12.742280] systemd[1]: Reached target Basic System.521server # [ 14.997249] (udev-worker)[127]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.522server # [ 15.001155] (udev-worker)[127]: Network interface NamePolicy= disabled on kernel command line.523server # [ 15.226858] (udev-worker)[130]: Network interface NamePolicy= disabled on kernel command line.524server # [ 15.597346] systemd[1]: Found device /dev/disk/by-label/nixos.525server # [ 15.702388] systemd[1]: Reached target Initrd Root Device.526server # [ 15.723772] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...527server # [ 16.055921] systemd-fsck[139]: nixos: clean, 12/65536 files, 13019/262144 blocks528server # [ 16.116985] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.529server # [ 16.143891] systemd[1]: Mounting /sysroot...530server # [ 16.624743] EXT4-fs (vda): mounted filesystem ee42a145-88f3-45b7-b79e-91c6984c2730 r/w with ordered data mode. Quota mode: none.531server # [ 16.543372] systemd[1]: Mounted /sysroot.532server # [ 16.565396] systemd[1]: Reached target Initrd Root File System.533server # [ 16.581735] systemd[1]: Starting Mountpoints Configured in the Real Root...534server # [ 16.723934] systemd-sysroot-fstab-check[147]: /sysroot should be mounted in the initrd, will request daemon-reload.535server # [ 16.759021] systemd[1]: Reload requested from client PID 147 ('systemd-sysroot') (unit initrd-parse-etc.service)...536server # [ 16.761816] systemd[1]: Reloading...537server # [ 18.842860] systemd[1]: Reloading finished in 2071 ms.538server # [ 18.945140] systemd-sysroot-fstab-check[147]: Requesting initrd-fs.target/start/replace...539server # [ 18.975512] systemd-sysroot-fstab-check[147]: Requesting swap.target/start/replace...540server # [ 19.018582] systemd[1]: Mounting /sysroot/nix/.rw-store...541server # [ 19.072988] systemd[1]: Mounting /sysroot/run...542server # [ 19.140209] systemd[1]: Starting Load Kernel Module 9pnet_virtio...543server # [ 19.257246] systemd[1]: initrd-parse-etc.service: Deactivated successfully.544server # [ 19.290782] systemd[1]: Finished Mountpoints Configured in the Real Root.545server # [ 19.293756] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.546server # [ 19.437360] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.547server # [ 19.451378] systemd[1]: Finished Load Kernel Module 9pnet_virtio.548server # [ 19.495567] systemd[1]: Mounted /sysroot/nix/.rw-store.549server # [ 19.505464] systemd[1]: Mounted /sysroot/run.550server # [ 19.568843] systemd[1]: Mounting /sysroot/nix/.ro-store...551server # [ 19.614413] systemd[1]: Mounting /sysroot/tmp/shared...552server # [ 19.681799] systemd[1]: Mounting /sysroot/tmp/xchg...553server # [ 19.727654] systemd[1]: Starting rw-sysroot-nix-store.service...554server # [ 19.993995] systemd[1]: Mounted /sysroot/nix/.ro-store.555server # [ 20.051211] systemd[1]: Mounted /sysroot/tmp/shared.556server # [ 20.054131] systemd[1]: Mounted /sysroot/tmp/xchg.557server # [ 20.058713] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.558server # [ 20.063542] systemd[1]: Finished rw-sysroot-nix-store.service.559server # [ 20.122604] systemd[1]: Mounting /sysroot/nix/store...560server # [ 20.477521] systemd[1]: Mounted /sysroot/nix/store.561server # [ 20.499860] systemd[1]: Reached target Initrd File Systems.562server # [ 20.541253] systemd[1]: Starting Find NixOS closure...563server # [ 20.595676] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...564server # [ 20.978395] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.565server # [ 21.286644] systemd[1]: Finished Find NixOS closure.566server # [ 21.304008] systemd[1]: Reached target Initrd Default Target.567server # [ 21.333505] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...568server # [ 21.580304] systemd[1]: Stopped target Initrd Default Target.569server # [ 21.587534] systemd[1]: Stopped target Basic System.570server # [ 21.595616] systemd[1]: Stopped target Initrd Root Device.571server # [ 21.598642] systemd[1]: Stopped target Path Units.572server # [ 21.607271] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.573server # [ 21.610314] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.574server # [ 21.622217] systemd[1]: Stopped target Slice Units.575server # [ 21.630286] systemd[1]: Stopped target Socket Units.576server # [ 21.635719] systemd[1]: Stopped target System Initialization.577server # [ 21.641838] systemd[1]: Stopped target Swaps.578server # [ 21.650211] systemd[1]: Stopped target Timer Units.579server # [ 21.654486] systemd[1]: dbus.socket: Deactivated successfully.580server # [ 21.661389] systemd[1]: Closed D-Bus System Message Bus Socket.581server # [ 21.665122] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.582server # [ 21.671877] systemd[1]: Stopped Find NixOS closure.583server # [ 21.704592] systemd[1]: Starting Load Kernel Module 9pnet_virtio...584server # [ 21.745729] systemd[1]: Starting rw-sysroot-nix-store.service...585server # [ 21.753360] systemd[1]: systemd-sysctl.service: Deactivated successfully.586server # [ 21.765878] systemd[1]: Stopped Apply Kernel Variables.587server # [ 21.776979] systemd[1]: systemd-modules-load.service: Deactivated successfully.588server # [ 21.789867] systemd[1]: Stopped Load Kernel Modules.589server # [ 21.807335] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.590server # [ 21.810173] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.591server # [ 21.820372] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.592server # [ 21.843281] systemd[1]: Stopped Create System Files and Directories.593server # [ 21.854413] systemd[1]: Stopped target Local File Systems.594server # [ 21.862692] systemd[1]: Stopped target Preparation for Local File Systems.595server # [ 21.869835] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.596server # [ 21.885192] systemd[1]: Stopped Coldplug All udev Devices.597server # [ 21.890706] systemd[1]: systemd-udev-trigger.service: Consumed 6.044s CPU time over 16.906s wall clock time, 9.4M memory peak.598server # [ 21.898744] systemd[1]: Stopping Rule-based Manager for Device Events and Files...599server # [ 21.900228] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.600server # [ 21.917549] systemd[1]: Stopped Virtual Console Setup.601server # [ 22.073310] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.602server # [ 22.078283] systemd[1]: Finished Load Kernel Module 9pnet_virtio.603server # [ 22.085746] systemd[1]: initrd-cleanup.service: Deactivated successfully.604server # [ 22.094521] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.605server # [ 22.104391] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.606server # [ 22.114529] systemd[1]: Finished rw-sysroot-nix-store.service.607server # [ 22.496251] systemd[1]: systemd-udevd.service: Deactivated successfully.608server # [ 22.500992] systemd[1]: Stopped Rule-based Manager for Device Events and Files.609server # [ 22.505765] systemd[1]: systemd-udevd.service: Consumed 12.709s CPU time over 16.567s wall clock time, 24.1M memory peak.610server # [ 22.518054] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.611server # [ 22.522505] systemd[1]: Closed udev Control Socket.612server # [ 22.537945] systemd[1]: Starting Cleanup udev Database...613server # [ 22.546371] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.614server # [ 22.550702] systemd[1]: Stopped Create Static Device Nodes in /dev.615server # [ 22.554102] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.616server # [ 22.555892] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.617server # [ 22.558010] systemd[1]: kmod-static-nodes.service: Deactivated successfully.618server # [ 22.560373] systemd[1]: Stopped Create List of Static Device Nodes.619server # [ 22.722558] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.620server # [ 22.726058] systemd[1]: Finished Cleanup udev Database.621server # [ 22.738900] systemd[1]: Reached target Switch Root.622server # [ 22.757098] systemd[1]: Starting NixOS Activation...623server # [ 24.786344] initrd-nixos-activation-start[239]: booting system configuration /nix/store/98k940gqfarnmsxiry7yqi1w40453j1m-nixos-system-server-test624server # [ 25.558227] initrd-nixos-activation-start[239]: running activation script...625server: Guest root shell did not produce any data yet...626server: To debug, enter the VM and run 'systemctl status backdoor.service'.627server # [ 30.501581] initrd-nixos-activation-start[262]: setting up /etc...628server # [ 33.255825] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.629server # [ 33.261290] systemd[1]: Finished NixOS Activation.630server # [ 33.263514] systemd[1]: initrd-nixos-activation.service: Consumed 5.345s CPU time over 10.499s wall clock time, 22.6M memory peak.631server # [ 33.277351] systemd[1]: Starting Switch Root...632server # [ 33.430693] systemd[1]: Switching root.633server # [ 33.692940] systemd-journald[81]: Received SIGTERM from PID 1 (systemd).634server # [ 36.421706] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)635server # [ 36.422133] systemd[1]: Detected virtualization qemu.636server # [ 36.426862] systemd[1]: Detected architecture arm64.637server # [ 36.429308] systemd[1]: Detected first boot.638server # [ 36.468181] systemd[1]: Initializing machine ID from random generator.639server # [ 38.540227] systemd[1]: bpf-restrict-fs: LSM BPF program attached640server # [ 40.063584] systemd[1]: Applying preset policy.641server # [ 44.950040] systemd[1]: Populated /etc with preset unit settings.642server # [ 50.320178] systemd[1]: initrd-switch-root.service: Deactivated successfully.643server # [ 50.326270] systemd[1]: Stopped initrd-switch-root.service.644server # [ 50.352639] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.645server # [ 50.368340] systemd[1]: Created slice Slice /system/getty.646server # [ 50.384544] systemd[1]: Created slice User and Session Slice.647server # [ 50.392382] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.648server # [ 50.399569] systemd[1]: Started Forward Password Requests to Wall Directory Watch.649server # [ 50.404902] systemd[1]: Expecting device /dev/hvc0...650server # [ 50.410460] systemd[1]: Expecting device /dev/ttyAMA0...651server # [ 50.416502] systemd[1]: Reached target Local Encrypted Volumes.652server # [ 50.421167] systemd[1]: Stopped target initrd-fs.target.653server # [ 50.425982] systemd[1]: Stopped target initrd-root-fs.target.654server # [ 50.431841] systemd[1]: Stopped target initrd-switch-root.target.655server # [ 50.436694] systemd[1]: Reached target Virtual Machines and Containers.656server # [ 50.442383] systemd[1]: Reached target Path Units.657server # [ 50.446705] systemd[1]: Reached target Remote File Systems.658server # [ 50.451743] systemd[1]: Reached target Slice Units.659server # [ 50.456067] systemd[1]: Reached target Swaps.660server # [ 50.496143] systemd[1]: Listening on Process Core Dump Socket.661server # [ 50.525138] systemd[1]: Listening on Credential Encryption/Decryption.662server # [ 50.572635] systemd[1]: Starting Journal Log Access Socket...663server # [ 50.591088] systemd[1]: Listening on Journal Audit Socket.664server # [ 50.610415] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.665server # [ 50.617665] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki666server # [ 50.627687] systemd[1]: Listening on udev Control Socket.667server # [ 50.668120] systemd[1]: Mounting Huge Pages File System...668server # [ 50.712083] systemd[1]: Mounting POSIX Message Queue File System...669server # [ 50.776900] systemd[1]: Mounting Kernel Debug File System...670server # [ 50.833233] systemd[1]: Mounting Kernel Trace File System...671server # [ 50.966897] systemd[1]: Starting Create List of Static Device Nodes...672server # [ 51.045468] systemd[1]: Starting Load Kernel Module 9pnet_virtio...673server # [ 51.059616] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs674server # [ 51.133080] systemd[1]: Mounting Kernel Configuration File System...675server # [ 51.150947] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm676server # [ 51.156007] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore677server # [ 51.243361] systemd[1]: Starting Load Kernel Module fuse...678server # [ 51.253186] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67679server # [ 51.537926] systemd[1]: Starting Journal Service...680server # [ 51.769455] systemd[1]: Starting Load Kernel Modules...681server # [ 52.081040] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...682server # [ 52.469638] systemd[1]: Starting Remount Root and Kernel File Systems...683server # [ 52.557634] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki684server # [ 52.591933] fuse: init (API version 7.45)685server # [ 52.640461] systemd[1]: Starting Coldplug All udev Devices...686server # [ 53.016748] systemd[1]: Listening on Journal Log Access Socket.687server # [ 53.119455] systemd[1]: Mounted Huge Pages File System.688server # [ 53.143719] systemd[1]: Mounted POSIX Message Queue File System.689server # [ 53.161344] systemd[1]: Mounted Kernel Debug File System.690server # [ 53.175138] systemd[1]: Mounted Kernel Trace File System.691server # [ 53.191183] systemd[1]: Finished Create List of Static Device Nodes.692server # [ 53.207518] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.693server # [ 53.216647] systemd[1]: Finished Load Kernel Module 9pnet_virtio.694server # [ 53.233393] systemd[1]: Mounted Kernel Configuration File System.695server # [ 53.245074] systemd[1]: modprobe@fuse.service: Deactivated successfully.696server # [ 53.262255] systemd[1]: Finished Load Kernel Module fuse.697server # [ 53.280232] systemd[1]: Finished Load Kernel Modules.698server # [ 53.327202] systemd-journald[333]: Collecting audit messages is enabled.699server # [ 53.357461] systemd[1]: Mounting FUSE Control File System...700server # [ 53.588172] systemd[1]: Starting Firewall...701server # [ 53.498342] systemd[1]: Queued start job for default target Multi-User System.702server # [ 53.508529] systemd[1]: systemd-journald.service: Deactivated successfully.703server # [ 53.524125] systemd[1]: systemd-journald.service: Consumed 1.407s CPU time over 45.254s wall clock time, 9.8M memory peak.704server # [ 53.539973] systemd-modules-load[334]: Using 2 probe threads705server # [ 53.544479] systemd-modules-load[334]: Module 'atkbd' is built in706server # [ 53.562279] systemd-modules-load[334]: Module 'loop' is built in707server # [ 53.799170] systemd[1]: Starting Apply Kernel Variables...708server # [ 53.820058] EXT4-fs (vda): re-mounted ee42a145-88f3-45b7-b79e-91c6984c2730.709server # [ 53.904851] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...710server # [ 53.948088] systemd[1]: Started Journal Service.711server # [ 53.889360] systemd[1]: Finished Remount Root and Kernel File Systems.712server # [ 54.839424] systemd-oomd[335]: No swap; memory pressure usage will be degraded713server # [ 55.123325] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.714server # [ 55.367999] systemd[1]: Mounted FUSE Control File System.715server # [ 55.753258] systemd[1]: Starting Flush Journal to Persistent Storage...716server # [ 55.798586] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore717server # [ 55.999923] systemd[1]: Starting Load/Save OS Random Seed...718server # [ 56.022463] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki719server # [ 56.301765] systemd[1]: Finished Apply Kernel Variables.720server # [ 56.478198] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.721server # [ 56.714870] systemd[1]: Starting Create Static Device Nodes in /dev...722server # [ 56.894011] systemd-journald[333]: Received client request to flush runtime journal.723server # [ 57.484158] systemd[1]: Finished Load/Save OS Random Seed.724server # [ 57.514846] systemd[1]: Reached target First Boot Complete.725server # [ 57.538614] systemd[1]: Finished Flush Journal to Persistent Storage.726server # [ 58.105989] systemd[1]: Finished Create Static Device Nodes in /dev.727server # [ 58.157220] systemd[1]: Reached target Preparation for Local File Systems.728server # [ 58.208191] systemd[1]: Mounting /run/wrappers...729server # [ 58.299468] systemd[1]: Starting Rule-based Manager for Device Events and Files...730server: Guest root shell did not produce any data yet...731server: To debug, enter the VM and run 'systemctl status backdoor.service'.732server # [ 58.859493] systemd[1]: Mounted /run/wrappers.733server # [ 58.882398] systemd[1]: Reached target Local File Systems.734server # [ 58.953556] systemd[1]: Listening on Boot Loader Control Service Socket.735server # [ 59.021049] systemd[1]: Starting register-nix-paths.service...736server # [ 59.131867] systemd[1]: Starting Create SUID/SGID Wrappers...737server # [ 59.143807] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.738server # [ 59.329154] systemd[1]: Starting Save Transient machine-id to Disk...739server # [ 59.529803] systemd[1]: Starting Create System Files and Directories...740server # [ 60.897413] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.741server # [ 60.931607] systemd[1]: Finished Save Transient machine-id to Disk.742server # [ 62.730901] systemd[1]: Finished Create System Files and Directories.743server # [ 62.991550] systemd[1]: Starting Rebuild Journal Catalog...744server # [ 63.185199] systemd[1]: Starting Record System Boot/Shutdown in UTMP...745server # [ 64.839271] systemd[1]: Finished Record System Boot/Shutdown in UTMP.746server # [ 66.098026] systemd[1]: Finished Rebuild Journal Catalog.747server # [ 66.226722] systemd[1]: Starting Update is Completed...748server # [ 67.327391] systemd[1]: Finished Update is Completed.749server # [ 69.378453] hrtimer: interrupt took 24176250 ns750server # [ 71.697621] systemd-udevd[385]: Using default interface naming scheme 'v260'.751server # [ 73.973471] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.752server # [ 73.992922] systemd[1]: Finished Create SUID/SGID Wrappers.753server # [ 74.008920] systemd[1]: suid-sgid-wrappers.service: Consumed 3.687s CPU time over 14.837s wall clock time, 5.2M memory peak.754server # [ 74.911158] systemd[1]: Started Rule-based Manager for Device Events and Files.755server # [ 76.115597] systemd[1]: Finished register-nix-paths.service.756server # [ 78.338357] systemd[1]: Finished Firewall.757server # [ 80.699833] systemd[1]: Finished Coldplug All udev Devices.758server # [ 80.703868] systemd[1]: Reached target System Initialization.759server # [ 80.727338] systemd[1]: Started Discard unused filesystem blocks once a week.760server # [ 80.735372] systemd[1]: Started Daily Cleanup of Temporary Directories.761server # [ 80.744647] systemd[1]: Reached target Timer Units.762server # [ 80.759600] systemd[1]: Listening on D-Bus System Message Bus Socket.763server # [ 80.765807] systemd[1]: Listening on Nix Daemon Socket.764server # [ 80.774429] systemd[1]: Listening on Hostname Service Socket.765server # [ 80.778280] systemd[1]: Reached target Socket Units.766server # [ 80.787832] systemd[1]: Reached target Basic System.767server # [ 80.822323] systemd[1]: Starting Import lastlog data into lastlog2 database...768server # [ 80.885582] systemd[1]: Starting Name Service Cache Daemon (nsncd)...769server # [ 80.966613] systemd[1]: Starting Post-Boot Actions...770server # [ 81.147247] systemd[1]: Started Reset console on configuration changes.771server # [ 81.356795] systemd[1]: Starting resolvconf update...772server # [ 81.910840] systemd[1]: Finished Post-Boot Actions.773server # [ 82.158069] systemd[1]: Starting D-Bus System Message Bus...774server # [ 82.535573] systemd[1]: Started Name Service Cache Daemon (nsncd).775server # [ 82.579532] nsncd[571]: Oct 27 16:05:52.755 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"776server # [ 82.597352] systemd[1]: Finished Import lastlog data into lastlog2 database.777server # [ 82.658698] systemd[1]: Reached target Host and Network Name Lookups.778server # [ 82.673745] systemd[1]: Reached target User and Group Name Lookups.779server # [ 82.838053] systemd[1]: Starting User Login Management...780server # [ 83.988438] dbus-broker-launch[579]: Looking up NSS user entry for 'systemd-timesync'...781server # [ 84.305173] dbus-broker-launch[579]: NSS returned no entry for 'systemd-timesync'782server # [ 84.318192] dbus-broker-launch[579]: Invalid user-name in /nix/store/3h8rckbl9vs21jahmva0fpck4y03w9xg-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"783server # [ 84.814749] systemd[1]: Started D-Bus System Message Bus.784server # [ 85.079054] systemd-logind[593]: New seat seat0.785server # [ 85.132965] systemd[1]: Started User Login Management.786server # [ 85.209182] systemd[1]: Starting linger-users.service...787server # [ 85.559394] dbus-broker-launch[579]: Ready788server # [ 85.907239] systemd[1]: linger-users.service: Deactivated successfully.789server # [ 85.927980] systemd[1]: Finished linger-users.service.790server # [ 86.097823] systemd[1]: Stopped target Host and Network Name Lookups.791server # [ 86.107949] systemd[1]: Stopping Host and Network Name Lookups...792server # [ 86.116856] systemd[1]: Stopped target User and Group Name Lookups.793server # [ 86.118649] systemd[1]: Stopping User and Group Name Lookups...794server # [ 86.141658] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...795server # [ 86.193679] systemd[1]: nscd.service: Deactivated successfully.796server # [ 86.202272] systemd[1]: Stopped Name Service Cache Daemon (nsncd).797server # [ 86.508926] systemd[1]: Starting Name Service Cache Daemon (nsncd)...798server # [ 87.187628] nsncd[657]: Oct 27 16:05:57.437 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"799server # [ 87.207377] systemd[1]: Started Name Service Cache Daemon (nsncd).800server # [ 87.218230] systemd[1]: Reached target Host and Network Name Lookups.801server # [ 87.222493] systemd[1]: Reached target User and Group Name Lookups.802server # [ 87.626792] systemd[1]: Finished resolvconf update.803server # [ 87.646348] systemd[1]: Reached target Preparation for Network.804server # [ 87.693589] systemd[1]: Starting DHCP Client...805server # [ 87.743371] systemd[1]: Starting Extra networking commands....806server: Guest root shell did not produce any data yet...807server: To debug, enter the VM and run 'systemctl status backdoor.service'.808server # [ 90.541042] dhcpcd[700]: dhcpcd-10.3.1 starting809server # [ 90.724519] dhcpcd[754]: dev: loaded udev810server # [ 90.874601] dhcpcd[754]: no valid interfaces found811server # [ 90.876310] dhcpcd[754]: no valid interfaces found812server # [ 91.152283] systemd[1]: Finished Extra networking commands..813server # [ 91.158466] systemd[1]: Reached target Network.814server # [ 91.194494] systemd[1]: Starting PostgreSQL Database Server...815server # [ 91.235664] systemd[1]: Starting Permit User Sessions...816server # [ 91.749818] systemd[1]: Finished Permit User Sessions.817server # [ 91.826371] systemd[1]: Started Getty on tty1.818server # [ 91.839037] systemd[1]: Reached target Login Prompts.819server # [ 92.919120] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[776]: Initializing database at /var/lib/postgresql/data820server # [ 94.549898] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: The files belonging to this database system will be owned by user "postgres".821server # [ 94.565023] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: This user must also own the server process.822server # [ 94.670681] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: The database cluster will be initialized with locale "en_US.UTF-8".823server # [ 94.677874] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: The default database encoding has accordingly been set to "UTF8".824server # [ 94.679705] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: The default text search configuration will be set to "english".825server # [ 94.681406] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: Data page checksums are enabled.826server # [ 94.685738] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: fixing permissions on existing directory /var/lib/postgresql/data ... ok827server # [ 94.692058] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: creating subdirectories ... ok828server # [ 94.701072] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: selecting dynamic shared memory implementation ... posix829server # [ 96.035247] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: selecting default max_connections ... 100830server # [ 96.704136] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: selecting default shared_buffers ... 128MB831server # [ 108.563588] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: selecting default time zone ... UTC832server # [ 108.613648] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: creating configuration files ... ok833server # [ 112.404890] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: running bootstrap script ... ok834server: Guest root shell did not produce any data yet...835server: To debug, enter the VM and run 'systemctl status backdoor.service'.836server # [ 120.929896] dhcpcd[754]: timed out837server # [ 120.938267] dhcpcd[754]: timed out838server # [ 120.963799] systemd[1]: Started DHCP Client.839server # [ 123.398169] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: performing post-bootstrap initialization ... ok840server # [ 126.194448] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: syncing data to disk ... ok841server # [ 126.221241] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: initdb: warning: enabling "trust" authentication for local connections842server # [ 126.225012] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: 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 # [ 126.226569] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: Success. You can now start the database server using:844server # [ 126.227697] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[788]: /nix/store/8f61g0kn68hhlcd0wj7h40w8h17hfb75-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start845server # [ 127.731895] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[776]: Validating PostgreSQL configuration...846server # [ 128.153755] rg2ds6bsd73gbbpl182gwjpww4kzq3fi-postgresql-init[1032]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault847server # [ 129.542968] postgres[1034]: 2026-10-27 16:06:39.787 UTC [1034] LOG: pgaudit extension initialized848server # [ 130.291503] postgres[1034]: 2026-10-27 16:06:40.554 UTC [1034] LOG: pgsodium primary server secret key loaded849server # [ 130.774176] postgres[1034]: 2026-10-27 16:06:41.033 UTC [1034] LOG: vault primary server secret key loaded850server # [ 131.199837] postgres[1034]: 2026-10-27 16:06:41.460 UTC [1034] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.3.0, 64-bit851server # [ 131.214276] postgres[1034]: 2026-10-27 16:06:41.479 UTC [1034] LOG: listening on IPv4 address "0.0.0.0", port 5432852server # [ 131.218314] postgres[1034]: 2026-10-27 16:06:41.480 UTC [1034] LOG: listening on IPv6 address "::", port 5432853server # [ 131.249235] postgres[1034]: 2026-10-27 16:06:41.512 UTC [1034] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"854server # [ 131.434472] postgres[1064]: 2026-10-27 16:06:41.666 UTC [1064] LOG: database system was shut down at 2026-10-27 16:06:33 UTC855server # [ 131.776040] postgres[1071]: 2026-10-27 16:06:42.023 UTC [1071] LOG: TimescaleDB background worker launcher connected to shared catalogs856server # [ 131.853684] postgres[1034]: 2026-10-27 16:06:42.035 UTC [1034] LOG: database system is ready to accept connections857server # [ 131.887505] postgres[1073]: 2026-10-27 16:06:42.051 UTC [1073] FATAL: role "postgres" does not exist858server # [ 131.942156] systemd[1]: Started PostgreSQL Database Server.859server # [ 131.963318] postgres[1034]: 2026-10-27 16:06:42.224 UTC [1034] LOG: background worker "pg_net 0.20.4 worker" (PID 1073) exited with exit code 1860server # [ 132.010888] systemd[1]: Starting Supabase Database Initialization...861server # [ 132.127927] postgres[1074]: 2026-10-27 16:06:42.382 UTC [1074] LOG: pg_cron scheduler started862server # [ 132.581698] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Waiting for PostgreSQL to be ready...863server # [ 133.103049] postgres[1090]: 2026-10-27 16:06:43.313 UTC [1090] FATAL: role "postgres" does not exist864server # [ 133.186850] postgres[1034]: 2026-10-27 16:06:43.424 UTC [1034] LOG: background worker "pg_net 0.20.4 worker" (PID 1090) exited with exit code 1865server # [ 133.483505] postgres[1091]: ::1 2026-10-27 16:06:43.719 UTC [1091] root@root FATAL: role "root" does not exist866server # [ 133.507984] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: PostgreSQL is ready867server # [ 133.522423] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Creating postgres role...868server # [ 133.945417] postgres[1093]: ::1 2026-10-27 16:06:44.201 UTC [1093] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN;869server # [ 133.975884] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1092]: CREATE ROLE870server # [ 134.367320] postgres[1096]: ::1 2026-10-27 16:06:44.624 UTC [1096] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres;871server # [ 134.387699] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1094]: ALTER DATABASE872server # [ 134.472251] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000000-initial-schema.sql873server # [ 134.818841] postgres[1098]: ::1 2026-10-27 16:06:45.039 UTC [1098] postgres@postgres LOG: statement: create publication supabase_realtime;874server # [ 134.862447] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE PUBLICATION875server # [ 134.873235] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER ROLE876server # [ 134.875771] postgres[1098]: ::1 2026-10-27 16:06:45.112 UTC [1098] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls;877server # [ 134.882303] postgres[1098]: ::1 2026-10-27 16:06:45.136 UTC [1098] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication;878server # [ 134.883637] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE ROLE879server # [ 134.885443] postgres[1098]: ::1 2026-10-27 16:06:45.149 UTC [1098] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls;880server # [ 134.901769] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE ROLE881server # [ 134.905702] postgres[1098]: ::1 2026-10-27 16:06:45.170 UTC [1098] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin;882server # [ 134.918234] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT ROLE883server # [ 134.926248] postgres[1098]: ::1 2026-10-27 16:06:45.183 UTC [1098] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin;884server # [ 134.936173] postgres[1098]: ::1 2026-10-27 16:06:45.196 UTC [1098] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls;885server # [ 134.943953] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT886server # [ 134.951022] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE ROLE887server # [ 134.957719] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT ROLE888server # [ 134.962425] postgres[1098]: ::1 2026-10-27 16:06:45.208 UTC [1098] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user;889server # [ 134.974316] postgres[1098]: ::1 2026-10-27 16:06:45.220 UTC [1098] postgres@postgres LOG: statement: create schema if not exists extensions;890server # [ 134.976062] postgres[1098]: ::1 2026-10-27 16:06:45.239 UTC [1098] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions;891server # [ 134.982672] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE SCHEMA892server # [ 137.815542] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE EXTENSION893server # [ 137.834323] postgres[1098]: ::1 2026-10-27 16:06:48.072 UTC [1098] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions;894server # [ 138.946432] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE EXTENSION895server # [ 138.954079] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE ROLE896server # [ 138.965193] postgres[1098]: ::1 2026-10-27 16:06:49.201 UTC [1098] postgres@postgres LOG: statement: create role anon nologin noinherit;897server # [ 138.968550] postgres[1098]: ::1 2026-10-27 16:06:49.211 UTC [1098] postgres@postgres LOG: statement: create role authenticated nologin noinherit;898server # [ 138.978187] postgres[1098]: ::1 2026-10-27 16:06:49.224 UTC [1098] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls;899server # [ 138.987614] postgres[1098]: ::1 2026-10-27 16:06:49.232 UTC [1098] postgres@postgres LOG: statement: create user authenticator noinherit;900server # [ 138.996817] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE ROLE901server # [ 139.001539] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE ROLE902server # [ 139.004195] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: CREATE ROLE903server # [ 139.014119] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT ROLE904server # [ 139.026169] postgres[1098]: ::1 2026-10-27 16:06:49.246 UTC [1098] postgres@postgres LOG: statement: grant anon to authenticator;905server # [ 139.029450] postgres[1098]: ::1 2026-10-27 16:06:49.259 UTC [1098] postgres@postgres LOG: statement: grant authenticated to authenticator;906server # [ 139.036356] postgres[1098]: ::1 2026-10-27 16:06:49.268 UTC [1098] postgres@postgres LOG: statement: grant service_role to authenticator;907server # [ 139.038977] postgres[1098]: ::1 2026-10-27 16:06:49.283 UTC [1098] postgres@postgres LOG: statement: grant supabase_admin to authenticator;908server # [ 139.046471] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT ROLE909server # [ 139.050489] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT ROLE910server # [ 139.057860] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT ROLE911server # [ 139.061095] postgres[1098]: ::1 2026-10-27 16:06:49.301 UTC [1098] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role;912server # [ 139.072309] postgres[1098]: ::1 2026-10-27 16:06:49.315 UTC [1098] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;913server # [ 139.082052] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT914server # [ 139.085574] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER DEFAULT PRIVILEGES915server # [ 139.095034] postgres[1098]: ::1 2026-10-27 16:06:49.337 UTC [1098] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;916server # [ 139.106514] postgres[1098]: ::1 2026-10-27 16:06:49.353 UTC [1098] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;917server # [ 139.116862] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER DEFAULT PRIVILEGES918server # [ 139.122301] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER DEFAULT PRIVILEGES919server # [ 139.130038] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: GRANT920server # [ 139.139309] postgres[1098]: ::1 2026-10-27 16:06:49.365 UTC [1098] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role;921server # [ 139.149523] postgres[1098]: ::1 2026-10-27 16:06:49.382 UTC [1098] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions;922server # [ 139.157974] postgres[1098]: ::1 2026-10-27 16:06:49.401 UTC [1098] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all923server # [ 139.162399] postgres[1098]: on sequences to postgres, anon, authenticated, service_role;924server # [ 139.170337] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER ROLE925server # [ 139.173869] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER DEFAULT PRIVILEGES926server # [ 139.178535] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER DEFAULT PRIVILEGES927server # [ 139.186367] postgres[1098]: ::1 2026-10-27 16:06:49.419 UTC [1098] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all928server # [ 139.193496] postgres[1098]: on tables to postgres, anon, authenticated, service_role;929server # [ 139.202002] postgres[1098]: ::1 2026-10-27 16:06:49.434 UTC [1098] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all930server # [ 139.204725] postgres[1098]: on functions to postgres, anon, authenticated, service_role;931server # [ 139.207862] postgres[1098]: ::1 2026-10-27 16:06:49.448 UTC [1098] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s';932server # [ 139.233109] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER DEFAULT PRIVILEGES933server # [ 139.244785] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER ROLE934server # [ 139.251771] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1097]: ALTER ROLE935server # [ 139.265976] postgres[1098]: ::1 2026-10-27 16:06:49.462 UTC [1098] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s';936server # [ 139.313451] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000001-auth-schema.sql937server # [ 139.599261] postgres[1140]: ::1 2026-10-27 16:06:49.851 UTC [1140] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;938server # [ 139.606812] postgres[1140]: ::1 2026-10-27 16:06:49.867 UTC [1140] postgres@postgres LOG: statement: CREATE TABLE auth.users (939server # [ 139.616409] postgres[1140]: instance_id uuid NULL,940server # [ 139.620116] postgres[1140]: id uuid NOT NULL UNIQUE,941server # [ 139.622439] postgres[1140]: aud varchar(255) NULL,942server # [ 139.629240] postgres[1140]: "role" varchar(255) NULL,943server # [ 139.631252] postgres[1140]: email varchar(255) NULL UNIQUE,944server # [ 139.632187] postgres[1140]: encrypted_password varchar(255) NULL,945server # [ 139.642405] postgres[1140]: confirmed_at timestamptz NULL,946server # [ 139.643576] postgres[1140]: invited_at timestamptz NULL,947server # [ 139.645217] postgres[1140]: confirmation_token varchar(255) NULL,948server # [ 139.650329] postgres[1140]: confirmation_sent_at timestamptz NULL,949server # [ 139.659270] postgres[1140]: recovery_token varchar(255) NULL,950server # [ 139.662325] postgres[1140]: recovery_sent_at timestamptz NULL,951server # [ 139.663399] postgres[1140]: email_change_token varchar(255) NULL,952server # [ 139.669404] postgres[1140]: email_change varchar(255) NULL,953server # [ 139.673335] postgres[1140]: email_change_sent_at timestamptz NULL,954server # [ 139.679510] postgres[1140]: last_sign_in_at timestamptz NULL,955server # [ 139.681194] postgres[1140]: raw_app_meta_data jsonb NULL,956server # [ 139.682520] postgres[1140]: raw_user_meta_data jsonb NULL,957server # [ 139.690317] postgres[1140]: is_super_admin bool NULL,958server # [ 139.697627] postgres[1140]: created_at timestamptz NULL,959server # [ 139.698996] postgres[1140]: updated_at timestamptz NULL,960server # [ 139.701998] postgres[1140]: CONSTRAINT users_pkey PRIMARY KEY (id)961server # [ 139.706576] postgres[1140]: );962server # [ 139.713795] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE SCHEMA963server # [ 139.762621] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE TABLE964server # [ 139.768967] postgres[1140]: ::1 2026-10-27 16:06:50.027 UTC [1140] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);965server # [ 139.794806] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE INDEX966server # [ 139.801405] postgres[1140]: ::1 2026-10-27 16:06:50.060 UTC [1140] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);967server # [ 139.829111] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE INDEX968server # [ 139.833406] postgres[1140]: ::1 2026-10-27 16:06:50.094 UTC [1140] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';969server # [ 139.842557] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: COMMENT970server # [ 139.846697] postgres[1140]: ::1 2026-10-27 16:06:50.111 UTC [1140] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens (971server # [ 139.848969] postgres[1140]: instance_id uuid NULL,972server # [ 139.851059] postgres[1140]: id bigserial NOT NULL,973server # [ 139.852577] postgres[1140]: "token" varchar(255) NULL,974server # [ 139.854594] postgres[1140]: user_id varchar(255) NULL,975server # [ 139.856884] postgres[1140]: revoked bool NULL,976server # [ 139.859518] postgres[1140]: created_at timestamptz NULL,977server # [ 139.860779] postgres[1140]: updated_at timestamptz NULL,978server # [ 139.862277] postgres[1140]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)979server # [ 139.866777] postgres[1140]: );980server # [ 139.966893] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE TABLE981server # [ 139.971770] postgres[1140]: ::1 2026-10-27 16:06:50.232 UTC [1140] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);982server # [ 140.006833] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE INDEX983server # [ 140.008315] postgres[1140]: ::1 2026-10-27 16:06:50.272 UTC [1140] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);984server # [ 140.054639] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE INDEX985server # [ 140.061108] postgres[1140]: ::1 2026-10-27 16:06:50.320 UTC [1140] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);986server # [ 140.099593] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE INDEX987server # [ 140.102129] postgres[1140]: ::1 2026-10-27 16:06:50.362 UTC [1140] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';988server # [ 140.107485] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: COMMENT989server # [ 140.109909] postgres[1140]: ::1 2026-10-27 16:06:50.372 UTC [1140] postgres@postgres LOG: statement: CREATE TABLE auth.instances (990server # [ 140.111752] postgres[1140]: id uuid NOT NULL,991server # [ 140.113735] postgres[1140]: uuid uuid NULL,992server # [ 140.114662] postgres[1140]: raw_base_config text NULL,993server # [ 140.115435] postgres[1140]: created_at timestamptz NULL,994server # [ 140.116143] postgres[1140]: updated_at timestamptz NULL,995server # [ 140.118093] postgres[1140]: CONSTRAINT instances_pkey PRIMARY KEY (id)996server # [ 140.119014] postgres[1140]: );997server # [ 140.198927] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE TABLE998server # [ 140.207351] postgres[1140]: ::1 2026-10-27 16:06:50.463 UTC [1140] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';999server # [ 140.210336] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: COMMENT1000server # [ 140.215906] postgres[1140]: ::1 2026-10-27 16:06:50.475 UTC [1140] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries (1001server # [ 140.218935] postgres[1140]: instance_id uuid NULL,1002server # [ 140.222104] postgres[1140]: id uuid NOT NULL,1003server # [ 140.225233] postgres[1140]: payload json NULL,1004server # [ 140.226188] postgres[1140]: created_at timestamptz NULL,1005server # [ 140.227715] postgres[1140]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)1006server # [ 140.234899] postgres[1140]: );1007server # [ 140.275756] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE TABLE1008server # [ 140.284205] postgres[1140]: ::1 2026-10-27 16:06:50.542 UTC [1140] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);1009server # [ 140.304561] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE INDEX1010server # [ 140.309862] postgres[1140]: ::1 2026-10-27 16:06:50.572 UTC [1140] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';1011server # [ 140.314624] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: COMMENT1012server # [ 140.319697] postgres[1140]: ::1 2026-10-27 16:06:50.579 UTC [1140] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations (1013server # [ 140.330513] postgres[1140]: "version" varchar(255) NOT NULL,1014server # [ 140.337355] postgres[1140]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")1015server # [ 140.342088] postgres[1140]: );1016server # [ 140.353908] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE TABLE1017server # [ 140.357512] postgres[1140]: ::1 2026-10-27 16:06:50.620 UTC [1140] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';1018server # [ 140.367633] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: COMMENT1019server # [ 140.402429] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: INSERT 0 71020server # [ 140.409979] postgres[1140]: ::1 2026-10-27 16:06:50.670 UTC [1140] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$1021server # [ 140.412811] postgres[1140]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;1022server # [ 140.415386] postgres[1140]: $$ language sql stable;1023server # [ 140.418634] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE FUNCTION1024server # [ 140.421729] postgres[1140]: ::1 2026-10-27 16:06:50.685 UTC [1140] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$1025server # [ 140.423796] postgres[1140]: select nullif(current_setting('request.jwt.claim.role', true), '')::text;1026server # [ 140.426140] postgres[1140]: $$ language sql stable;1027server # [ 140.441272] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE FUNCTION1028server # [ 140.448964] postgres[1140]: ::1 2026-10-27 16:06:50.703 UTC [1140] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$1029server # [ 140.452802] postgres[1140]: select nullif(current_setting('request.jwt.claim.email', true), '')::text;1030server # [ 140.458915] postgres[1140]: $$ language sql stable;1031server # [ 140.469687] postgres[1140]: ::1 2026-10-27 16:06:50.723 UTC [1140] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;1032server # [ 140.481720] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE FUNCTION1033server # [ 140.489287] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: GRANT1034server # [ 140.492580] postgres[1140]: ::1 2026-10-27 16:06:50.739 UTC [1140] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1035server # [ 140.498529] postgres[1140]: ::1 2026-10-27 16:06:50.748 UTC [1140] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1036server # [ 140.502290] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: CREATE ROLE1037server # [ 140.503831] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: GRANT1038server # [ 140.506157] postgres[1140]: ::1 2026-10-27 16:06:50.762 UTC [1140] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1039server # [ 140.516016] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: GRANT1040server # [ 140.524765] postgres[1140]: ::1 2026-10-27 16:06:50.784 UTC [1140] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1041server # [ 140.537632] postgres[1140]: ::1 2026-10-27 16:06:50.794 UTC [1140] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth";1042server # [ 140.539787] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: GRANT1043server # [ 140.544890] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: ALTER ROLE1044server # [ 140.550425] postgres[1140]: ::1 2026-10-27 16:06:50.807 UTC [1140] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin;1045server # [ 140.558461] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: ALTER TABLE1046server # [ 140.564058] postgres[1140]: ::1 2026-10-27 16:06:50.824 UTC [1140] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;1047server # [ 140.576616] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: ALTER TABLE1048server # [ 140.578171] postgres[1140]: ::1 2026-10-27 16:06:50.839 UTC [1140] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;1049server # [ 140.587211] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: ALTER TABLE1050server # [ 140.608436] postgres[1140]: ::1 2026-10-27 16:06:50.852 UTC [1140] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;1051server # [ 140.611055] postgres[1140]: ::1 2026-10-27 16:06:50.865 UTC [1140] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;1052server # [ 140.614024] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: ALTER TABLE1053server # [ 140.615261] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1139]: ALTER TABLE1054server # [ 140.680783] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000002-storage-schema.sql1055server # [ 141.011499] postgres[1142]: ::1 2026-10-27 16:06:51.240 UTC [1142] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;1056server # [ 141.023691] postgres[1142]: ::1 2026-10-27 16:06:51.282 UTC [1142] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1057server # [ 141.045932] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1141]: CREATE SCHEMA1058server # [ 141.047265] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1141]: CREATE ROLE1059server # [ 141.050984] postgres[1142]: ::1 2026-10-27 16:06:51.310 UTC [1142] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage";1060server # [ 141.060688] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1141]: ALTER ROLE1061server # [ 141.062357] postgres[1142]: ::1 2026-10-27 16:06:51.327 UTC [1142] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;1062server # [ 141.095706] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1141]: GRANT1063server # [ 141.157235] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1141]: DO1064server # [ 141.205043] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000003-post-setup.sql1065server # [ 141.614039] postgres[1157]: ::1 2026-10-27 16:06:51.865 UTC [1157] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;1066server # [ 141.675404] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: ALTER ROLE1067server # [ 141.687821] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: ALTER ROLE1068server # [ 141.694746] postgres[1157]: ::1 2026-10-27 16:06:51.910 UTC [1157] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;1069server # [ 141.702411] postgres[1157]: ::1 2026-10-27 16:06:51.926 UTC [1157] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()1070server # [ 141.714199] postgres[1157]: RETURNS event_trigger1071server # [ 141.717050] postgres[1157]: LANGUAGE plpgsql1072server # [ 141.726099] postgres[1157]: AS $$1073server # [ 141.740493] postgres[1157]: DECLARE1074server # [ 141.743805] postgres[1157]: schema_is_cron bool;1075server # [ 141.746819] postgres[1157]: BEGIN1076server # [ 141.749377] postgres[1157]: schema_is_cron = (1077server # [ 141.750607] postgres[1157]: SELECT n.nspname = 'cron'1078server # [ 141.751862] postgres[1157]: FROM pg_event_trigger_ddl_commands() AS ev1079server # [ 141.754609] postgres[1157]: LEFT JOIN pg_catalog.pg_namespace AS n1080server # [ 141.758737] postgres[1157]: ON ev.objid = n.oid1081server # [ 141.763892] postgres[1157]: );1082server # [ 141.767648] postgres[1157]:1083server # [ 141.769420] postgres[1157]: IF schema_is_cron1084server # [ 141.770163] postgres[1157]: THEN1085server # [ 141.771034] postgres[1157]: grant usage on schema cron to postgres with grant option;1086server # [ 141.772803] postgres[1157]:1087server # [ 141.774193] postgres[1157]: alter default privileges in schema cron grant all on tables to postgres with grant option;1088server # [ 141.775927] postgres[1157]: alter default privileges in schema cron grant all on functions to postgres with grant option;1089server # [ 141.783255] postgres[1157]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1090server # [ 141.785873] postgres[1157]:1091server # [ 141.787514] postgres[1157]: alter default privileges for user supabase_admin in schema cron grant all1092server # [ 141.789916] postgres[1157]: on sequences to postgres with grant option;1093server # [ 141.791383] postgres[1157]: alter default privileges for user supabase_admin in schema cron grant all1094server # [ 141.792641] postgres[1157]: on tables to postgres with grant option;1095server # [ 141.794195] postgres[1157]: alter default privileges for user supabase_admin in schema cron grant all1096server # [ 141.797285] postgres[1157]: on functions to postgres with grant option;1097server # [ 141.798178] postgres[1157]:1098server # [ 141.799032] postgres[1157]: grant all privileges on all tables in schema cron to postgres with grant option;1099server # [ 141.801553] postgres[1157]:1100server # [ 141.803495] postgres[1157]: END IF;1101server # [ 141.806246] postgres[1157]:1102server # [ 141.807560] postgres[1157]: END;1103server # [ 141.810703] postgres[1157]: $$;1104server # [ 141.813761] postgres[1157]: ::1 2026-10-27 16:06:52.008 UTC [1157] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')1105server # [ 141.815909] postgres[1157]: EXECUTE PROCEDURE extensions.grant_pg_cron_access();1106server # [ 141.821801] postgres[1157]: ::1 2026-10-27 16:06:52.036 UTC [1157] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';1107server # [ 141.822981] postgres[1157]: ::1 2026-10-27 16:06:52.054 UTC [1157] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()1108server # [ 141.828783] postgres[1157]: RETURNS event_trigger1109server # [ 141.830485] postgres[1157]: LANGUAGE plpgsql1110server # [ 141.832162] postgres[1157]: AS $$1111server # [ 141.838040] postgres[1157]: BEGIN1112server # [ 141.839844] postgres[1157]: IF EXISTS (1113server # [ 141.845733] postgres[1157]: SELECT 11114server # [ 141.847685] postgres[1157]: FROM pg_event_trigger_ddl_commands() AS ev1115server # [ 141.857919] postgres[1157]: JOIN pg_extension AS ext1116server # [ 141.860000] postgres[1157]: ON ev.objid = ext.oid1117server # [ 141.862581] postgres[1157]: WHERE ext.extname = 'pg_net'1118server # [ 141.863887] postgres[1157]: )1119server # [ 141.866295] postgres[1157]: THEN1120server # [ 141.869101] postgres[1157]: IF NOT EXISTS (1121server # [ 141.877677] postgres[1157]: SELECT 11122server # [ 141.881546] postgres[1157]: FROM pg_roles1123server # [ 141.885893] postgres[1157]: WHERE rolname = 'supabase_functions_admin'1124server # [ 141.887963] postgres[1157]: )1125server # [ 141.898975] postgres[1157]: THEN1126server # [ 141.901228] postgres[1157]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1127server # [ 141.902947] postgres[1157]: END IF;1128server # [ 141.909662] postgres[1157]:1129server # [ 141.911007] postgres[1157]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;1130server # [ 141.913198] postgres[1157]:1131server # [ 141.919209] postgres[1157]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1132server # [ 141.928011] postgres[1157]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1133server # [ 141.934425] postgres[1157]:1134server # [ 141.935066] postgres[1157]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1135server # [ 141.936774] postgres[1157]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1136server # [ 141.938531] postgres[1157]:1137server # [ 141.945252] postgres[1157]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1138server # [ 141.947420] postgres[1157]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1139server # [ 141.953333] postgres[1157]:1140server # [ 141.954662] postgres[1157]: 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;1141server # [ 141.962366] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: CREATE FUNCTION1142server # [ 141.964284] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: CREATE EVENT TRIGGER1143server # [ 141.966901] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: COMMENT1144server # [ 141.974814] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: CREATE FUNCTION1145server # [ 141.975925] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: COMMENT1146server # [ 141.981098] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: DO1147server # [ 141.982676] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: CREATE ROLE1148server # [ 141.990728] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1149server # [ 141.992229] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1150server # [ 141.993786] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1151server # [ 141.994623] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1152server # [ 141.996227] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1153server # [ 142.002651] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1154server # [ 142.003730] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1155server # [ 142.005459] postgres[1157]: 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;1156server # [ 142.009947] postgres[1157]: END IF;1157server # [ 142.017879] postgres[1157]: END;1158server # [ 142.019483] postgres[1157]: $$;1159server # [ 142.021298] postgres[1157]: ::1 2026-10-27 16:06:52.078 UTC [1157] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1160server # [ 142.023179] postgres[1157]: ::1 2026-10-27 16:06:52.131 UTC [1157] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1161server # [ 142.030633] postgres[1157]: ::1 2026-10-27 16:06:52.146 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1162server # [ 142.032493] postgres[1157]: ::1 2026-10-27 16:06:52.158 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1163server # [ 142.038806] postgres[1157]: ::1 2026-10-27 16:06:52.165 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1164server # [ 142.045325] postgres[1157]: ::1 2026-10-27 16:06:52.174 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1165server # [ 142.046799] postgres[1157]: ::1 2026-10-27 16:06:52.196 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1166server # [ 142.047987] postgres[1157]: ::1 2026-10-27 16:06:52.203 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1167server # [ 142.053135] postgres[1157]: ::1 2026-10-27 16:06:52.219 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1168server # [ 142.061050] postgres[1157]: ::1 2026-10-27 16:06:52.226 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1169server # [ 142.063963] postgres[1157]: ::1 2026-10-27 16:06:52.270 UTC [1157] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1170server # [ 142.069285] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1171server # [ 142.078303] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: GRANT1172server # [ 142.118316] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1151]: DO1173server # [ 142.180269] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running pgbouncer auth schema...1174server # [ 142.558852] postgres[1166]: ::1 2026-10-27 16:06:52.807 UTC [1166] postgres@postgres LOG: statement: CREATE USER pgbouncer;1175server # [ 142.571916] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1165]: CREATE ROLE1176server # [ 142.574696] postgres[1166]: ::1 2026-10-27 16:06:52.839 UTC [1166] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1177server # [ 142.601928] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1165]: REVOKE1178server # [ 142.605617] postgres[1166]: ::1 2026-10-27 16:06:52.864 UTC [1166] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1179server # [ 142.650097] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1165]: CREATE SCHEMA1180server # [ 142.651836] postgres[1166]: ::1 2026-10-27 16:06:52.916 UTC [1166] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1181server # [ 142.665204] postgres[1166]: RETURNS TABLE(username TEXT, password TEXT) AS1182server # [ 142.670530] postgres[1166]: $$1183server # [ 142.671761] postgres[1166]: BEGIN1184server # [ 142.679147] postgres[1166]: RAISE WARNING 'PgBouncer auth request: %', p_usename;1185server # [ 142.687859] postgres[1166]:1186server # [ 142.691510] postgres[1166]: RETURN QUERY1187server # [ 142.698929] postgres[1166]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1188server # [ 142.709266] postgres[1166]: WHERE usename = p_usename;1189server # [ 142.715974] postgres[1166]: END;1190server # [ 142.730964] postgres[1166]: $$ LANGUAGE plpgsql1191server # [ 142.746430] postgres[1166]: SET search_path = ''1192server # [ 142.762162] postgres[1166]: SECURITY DEFINER;1193server # [ 142.766736] postgres[1166]: ::1 2026-10-27 16:06:52.935 UTC [1166] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1194server # [ 142.784276] postgres[1166]: ::1 2026-10-27 16:06:52.948 UTC [1166] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1195server # [ 142.790553] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1165]: CREATE FUNCTION1196server # [ 142.805385] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1165]: REVOKE1197server # [ 142.809621] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1165]: GRANT1198server # [ 142.814554] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running stat extension...1199server # [ 143.067349] postgres[1168]: ::1 2026-10-27 16:06:53.319 UTC [1168] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions;1200server # [ 143.082808] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1167]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping1201server # [ 143.089561] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1167]: CREATE SCHEMA1202server # [ 143.090767] postgres[1168]: ::1 2026-10-27 16:06:53.351 UTC [1168] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1203server # [ 144.378555] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1167]: CREATE EXTENSION1204server # [ 144.423683] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/10000000000000_demote-postgres.sql1205server # [ 144.840231] postgres[1188]: ::1 2026-10-27 16:06:55.082 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres;1206server # [ 144.852031] postgres[1188]: ::1 2026-10-27 16:06:55.107 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres;1207server # [ 144.887056] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1208server # [ 144.897136] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1209server # [ 144.918316] postgres[1188]: ::1 2026-10-27 16:06:55.166 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres;1210server # [ 144.932814] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1211server # [ 144.934668] postgres[1188]: ::1 2026-10-27 16:06:55.197 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1212server # [ 144.955175] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1213server # [ 144.965579] postgres[1188]: ::1 2026-10-27 16:06:55.230 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1214server # [ 144.982201] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1215server # [ 144.983521] postgres[1188]: ::1 2026-10-27 16:06:55.247 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1216server # [ 144.993944] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1217server # [ 144.997742] postgres[1188]: ::1 2026-10-27 16:06:55.257 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1218server # [ 145.006669] postgres[1188]: ::1 2026-10-27 16:06:55.260 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1219server # [ 145.008990] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1220server # [ 145.016688] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1221server # [ 145.018212] postgres[1188]: ::1 2026-10-27 16:06:55.273 UTC [1188] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1222server # [ 145.045650] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: GRANT1223server # [ 145.081700] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: DO1224server # [ 145.087302] postgres[1188]: ::1 2026-10-27 16:06:55.344 UTC [1188] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1225server # [ 145.094309] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1181]: ALTER ROLE1226server # [ 145.142570] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211115181400_update-auth-permissions.sql1227server # [ 145.470867] postgres[1192]: ::1 2026-10-27 16:06:55.727 UTC [1192] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1228server # [ 145.502921] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: GRANT1229server # [ 145.508398] postgres[1192]: ::1 2026-10-27 16:06:55.753 UTC [1192] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1230server # [ 145.513893] postgres[1192]: ::1 2026-10-27 16:06:55.766 UTC [1192] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1231server # [ 145.518654] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: GRANT1232server # [ 145.521703] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: GRANT1233server # [ 145.522951] postgres[1192]: ::1 2026-10-27 16:06:55.786 UTC [1192] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1234server # [ 145.530240] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER TABLE1235server # [ 145.534435] postgres[1192]: ::1 2026-10-27 16:06:55.798 UTC [1192] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1236server # [ 145.542478] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER TABLE1237server # [ 145.550017] postgres[1192]: ::1 2026-10-27 16:06:55.811 UTC [1192] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1238server # [ 145.561839] postgres[1192]: ::1 2026-10-27 16:06:55.821 UTC [1192] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1239server # [ 145.570471] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER TABLE1240server # [ 145.573329] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER TABLE1241server # [ 145.574522] postgres[1192]: ::1 2026-10-27 16:06:55.831 UTC [1192] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1242server # [ 145.577709] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER TABLE1243server # [ 145.582007] postgres[1192]: ::1 2026-10-27 16:06:55.846 UTC [1192] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres;1244server # [ 145.590081] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: GRANT1245server # [ 145.594064] postgres[1192]: ::1 2026-10-27 16:06:55.857 UTC [1192] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1246server # [ 145.607887] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: GRANT1247server # [ 145.620887] postgres[1192]: ::1 2026-10-27 16:06:55.883 UTC [1192] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1248server # [ 145.646612] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: GRANT1249server # [ 145.652664] postgres[1192]: ::1 2026-10-27 16:06:55.901 UTC [1192] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1250server # [ 145.663438] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: GRANT1251server # [ 145.668336] postgres[1192]: ::1 2026-10-27 16:06:55.933 UTC [1192] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1252server # [ 145.689428] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER DEFAULT PRIVILEGES1253server # [ 145.693775] postgres[1192]: ::1 2026-10-27 16:06:55.956 UTC [1192] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1254server # [ 145.709486] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER DEFAULT PRIVILEGES1255server # [ 145.711001] postgres[1192]: ::1 2026-10-27 16:06:55.971 UTC [1192] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1256server # [ 145.729579] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1191]: ALTER DEFAULT PRIVILEGES1257server # [ 145.798107] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211118015519_create-realtime-schema.sql1258server # [ 146.074134] postgres[1194]: ::1 2026-10-27 16:06:56.332 UTC [1194] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime;1259server # [ 146.141202] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1193]: CREATE SCHEMA1260server # [ 146.152882] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1261server # [ 146.462786] postgres[1196]: ::1 2026-10-27 16:06:56.719 UTC [1196] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres;1262server # [ 146.494239] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1195]: GRANT1263server # [ 146.502757] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1195]: GRANT1264server # [ 146.507582] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1195]: GRANT1265server # [ 146.510010] postgres[1196]: ::1 2026-10-27 16:06:56.754 UTC [1196] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1266server # [ 146.512691] postgres[1196]: ::1 2026-10-27 16:06:56.764 UTC [1196] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1267server # [ 146.515490] postgres[1196]: ::1 2026-10-27 16:06:56.767 UTC [1196] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1268server # [ 146.520158] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1195]: GRANT1269server # [ 146.574357] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211124212715_update-auth-owner.sql1270server # [ 146.950862] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1197]: DO1271server # [ 146.959614] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1197]: DO1272server # [ 146.974566] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1197]: DO1273server # [ 147.035441] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1274server # [ 147.584315] postgres[1207]: ::1 2026-10-27 16:06:57.842 UTC [1207] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user;1275server # [ 147.624853] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1199]: ALTER DEFAULT PRIVILEGES1276server # [ 147.629810] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1199]: ALTER DEFAULT PRIVILEGES1277server # [ 147.635230] postgres[1207]: ::1 2026-10-27 16:06:57.881 UTC [1207] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1278server # [ 147.646757] postgres[1207]: ::1 2026-10-27 16:06:57.894 UTC [1207] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user;1279server # [ 147.669956] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1199]: ALTER DEFAULT PRIVILEGES1280server # [ 147.714815] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1281server # [ 148.270808] postgres[1217]: ::1 2026-10-27 16:06:58.510 UTC [1217] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1282server # [ 148.286295] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1212]: ALTER ROLE1283server # [ 148.359912] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1284server # [ 148.765512] postgres[1224]: ::1 2026-10-27 16:06:59.022 UTC [1224] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1285server # [ 148.779152] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1286server # [ 148.787930] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: DROP EVENT TRIGGER1287server # [ 148.794758] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1288server # [ 148.797670] postgres[1224]: ::1 2026-10-27 16:06:59.047 UTC [1224] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1289server # [ 148.805445] postgres[1224]: ::1 2026-10-27 16:06:59.056 UTC [1224] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1290server # [ 148.812763] postgres[1224]: DECLARE1291server # [ 148.814401] postgres[1224]: cmd record;1292server # [ 148.815363] postgres[1224]: BEGIN1293server # [ 148.821359] postgres[1224]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1294server # [ 148.825360] postgres[1224]: LOOP1295server # [ 148.826429] postgres[1224]: IF cmd.command_tag IN (1296server # [ 148.827871] postgres[1224]: 'CREATE SCHEMA', 'ALTER SCHEMA'1297server # [ 148.838287] postgres[1224]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1298server # [ 148.845717] postgres[1224]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1299server # [ 148.853347] postgres[1224]: , 'CREATE VIEW', 'ALTER VIEW'1300server # [ 148.854729] postgres[1224]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1301server # [ 148.857514] postgres[1224]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1302server # [ 148.866361] postgres[1224]: , 'CREATE TRIGGER'1303server # [ 148.869896] postgres[1224]: , 'CREATE TYPE'1304server # [ 148.877900] postgres[1224]: , 'CREATE RULE'1305server # [ 148.878980] postgres[1224]: , 'COMMENT'1306server # [ 148.879916] postgres[1224]: )1307server # [ 148.892579] postgres[1224]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1308server # [ 148.902185] postgres[1224]: AND cmd.schema_name is distinct from 'pg_temp'1309server # [ 148.908243] postgres[1224]: THEN1310server # [ 148.920259] postgres[1224]: NOTIFY pgrst, 'reload schema';1311server # [ 148.942625] postgres[1224]: END IF;1312server # [ 148.948400] postgres[1224]: END LOOP;1313server: Guest root shell did not produce any data yet...1314server: To debug, enter the VM and run 'systemctl status backdoor.service'.1315server # [ 148.955025] postgres[1224]: END; $$ LANGUAGE plpgsql;1316server # [ 148.960761] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: DROP FUNCTION1317server # [ 148.962301] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: CREATE FUNCTION1318server # [ 148.967487] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: CREATE FUNCTION1319server # [ 148.973687] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping1320server # [ 148.983229] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: DROP EVENT TRIGGER1321server # [ 148.990211] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: CREATE EVENT TRIGGER1322server # [ 148.995503] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping1323server # [ 149.001493] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: DROP EVENT TRIGGER1324server # [ 149.008829] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1221]: CREATE EVENT TRIGGER1325server # [ 149.019717] postgres[1224]: ::1 2026-10-27 16:06:59.099 UTC [1224] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1326server # [ 149.026029] postgres[1224]: DECLARE1327server # [ 149.029545] postgres[1224]: obj record;1328server # [ 149.037268] postgres[1224]: BEGIN1329server # [ 149.039246] postgres[1224]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1330server # [ 149.044386] postgres[1224]: LOOP1331server # [ 149.059865] postgres[1224]: IF obj.object_type IN (1332server # [ 149.069451] postgres[1224]: 'schema'1333server # [ 149.082230] postgres[1224]: , 'table'1334server # [ 149.087354] postgres[1224]: , 'foreign table'1335server # [ 149.090592] postgres[1224]: , 'view'1336server # [ 149.094812] postgres[1224]: , 'materialized view'1337server # [ 149.097497] postgres[1224]: , 'function'1338server # [ 149.100742] postgres[1224]: , 'trigger'1339server # [ 149.104107] postgres[1224]: , 'type'1340server # [ 149.106689] postgres[1224]: , 'rule'1341server # [ 149.107925] postgres[1224]: )1342server # [ 149.111728] postgres[1224]: AND obj.is_temporary IS false -- no pg_temp objects1343server # [ 149.114009] postgres[1224]: THEN1344server # [ 149.116612] postgres[1224]: NOTIFY pgrst, 'reload schema';1345server # [ 149.119778] postgres[1224]: END IF;1346server # [ 149.122961] postgres[1224]: END LOOP;1347server # [ 149.126050] postgres[1224]: END; $$ LANGUAGE plpgsql;1348server # [ 149.129699] postgres[1224]: ::1 2026-10-27 16:06:59.118 UTC [1224] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1349server # [ 149.134821] postgres[1224]: ::1 2026-10-27 16:06:59.125 UTC [1224] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1350server # [ 149.138518] postgres[1224]: ON ddl_command_end1351server # [ 149.143059] postgres[1224]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1352server # [ 149.146912] postgres[1224]: ::1 2026-10-27 16:06:59.141 UTC [1224] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1353server # [ 149.151202] postgres[1224]: ::1 2026-10-27 16:06:59.145 UTC [1224] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1354server # [ 149.154763] postgres[1224]: ON sql_drop1355server # [ 149.157763] postgres[1224]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1356server # [ 149.165398] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1357server # [ 149.999681] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1225]: DO1358server # [ 150.051982] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql1359server # [ 150.386337] postgres[1228]: ::1 2026-10-27 16:07:00.622 UTC [1228] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public;1360server # [ 150.510389] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: CREATE SCHEMA1361server # [ 150.523940] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping1362server # [ 150.533953] postgres[1228]: ::1 2026-10-27 16:07:00.752 UTC [1228] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb);1363server # [ 150.537163] postgres[1228]: ::1 2026-10-27 16:07:00.796 UTC [1228] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1364server # [ 150.538763] postgres[1228]: "operationName" text default null,1365server # [ 150.540381] postgres[1228]: query text default null,1366server # [ 150.542504] postgres[1228]: variables jsonb default null,1367server # [ 150.545262] postgres[1228]: extensions jsonb default null1368server # [ 150.547405] postgres[1228]: )1369server # [ 150.548988] postgres[1228]: returns jsonb1370server # [ 150.551333] postgres[1228]: language plpgsql1371server # [ 150.553774] postgres[1228]: as $$1372server # [ 150.554861] postgres[1228]: DECLARE1373server # [ 150.557472] postgres[1228]: server_version float;1374server # [ 150.558465] postgres[1228]: BEGIN1375server # [ 150.561220] postgres[1228]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1376server # [ 150.563863] postgres[1228]:1377server # [ 150.572740] postgres[1228]: IF server_version >= 14 THEN1378server # [ 150.573970] postgres[1228]: RETURN jsonb_build_object(1379server # [ 150.577877] postgres[1228]: 'data', null::jsonb,1380server # [ 150.581268] postgres[1228]: 'errors', array['pg_graphql extension is not enabled.']1381server # [ 150.587429] postgres[1228]: );1382server # [ 150.590060] postgres[1228]: ELSE1383server # [ 150.591597] postgres[1228]: RETURN jsonb_build_object(1384server # [ 150.597408] postgres[1228]: 'data', null::jsonb,1385server # [ 150.599067] postgres[1228]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1386server # [ 150.604981] postgres[1228]: );1387server # [ 150.611186] postgres[1228]: END IF;1388server # [ 150.614374] postgres[1228]: END;1389server # [ 150.615680] postgres[1228]: $$;1390server # [ 150.621854] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: DROP FUNCTION1391server # [ 150.624465] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: CREATE FUNCTION1392server # [ 150.631973] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: GRANT1393server # [ 150.638747] postgres[1228]: ::1 2026-10-27 16:07:00.838 UTC [1228] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1394server # [ 150.642881] postgres[1228]: ::1 2026-10-27 16:07:00.875 UTC [1228] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1395server # [ 150.647227] postgres[1228]: ::1 2026-10-27 16:07:00.896 UTC [1228] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1396server # [ 150.664052] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: ALTER DEFAULT PRIVILEGES1397server # [ 150.666260] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: ALTER DEFAULT PRIVILEGES1398server # [ 150.669080] postgres[1228]: ::1 2026-10-27 16:07:00.918 UTC [1228] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1399server # [ 150.678956] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: ALTER DEFAULT PRIVILEGES1400server # [ 150.681050] postgres[1228]: ::1 2026-10-27 16:07:00.944 UTC [1228] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1401server # [ 150.682326] postgres[1228]: on sequences to postgres, anon, authenticated, service_role;1402server # [ 150.697159] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: ALTER DEFAULT PRIVILEGES1403server # [ 150.698566] postgres[1228]: ::1 2026-10-27 16:07:00.962 UTC [1228] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1404server # [ 150.700357] postgres[1228]: on tables to postgres, anon, authenticated, service_role;1405server # [ 150.708382] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: ALTER DEFAULT PRIVILEGES1406server # [ 150.721259] postgres[1228]: ::1 2026-10-27 16:07:00.973 UTC [1228] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1407server # [ 150.723660] postgres[1228]: on functions to postgres, anon, authenticated, service_role;1408server # [ 150.734136] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: ALTER DEFAULT PRIVILEGES1409server # [ 150.742897] postgres[1228]: ::1 2026-10-27 16:07:01.007 UTC [1228] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1410server # [ 150.745543] postgres[1228]: RETURNS event_trigger1411server # [ 150.749938] postgres[1228]: LANGUAGE plpgsql1412server # [ 150.756004] postgres[1228]: AS $func$1413server # [ 150.760989] postgres[1228]: DECLARE1414server # [ 150.762843] postgres[1228]: func_is_graphql_resolve bool;1415server # [ 150.764077] postgres[1228]: BEGIN1416server # [ 150.771464] postgres[1228]: func_is_graphql_resolve = (1417server # [ 150.775380] postgres[1228]: SELECT n.proname = 'resolve'1418server # [ 150.781182] postgres[1228]: FROM pg_event_trigger_ddl_commands() AS ev1419server # [ 150.783883] postgres[1228]: LEFT JOIN pg_catalog.pg_proc AS n1420server # [ 150.794416] postgres[1228]: ON ev.objid = n.oid1421server # [ 150.797368] postgres[1228]: );1422server # [ 150.805417] postgres[1228]:1423server # [ 150.807617] postgres[1228]: IF func_is_graphql_resolve1424server # [ 150.810758] postgres[1228]: THEN1425server # [ 150.811783] postgres[1228]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1426server # [ 150.812690] postgres[1228]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1427server # [ 150.836180] postgres[1228]:1428server # [ 150.845212] postgres[1228]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1429server # [ 150.860910] postgres[1228]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1430server # [ 150.883558] postgres[1228]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1431server # [ 150.898103] postgres[1228]:1432server # [ 150.901245] postgres[1228]: DROP FUNCTION IF EXISTS graphql_public.graphql;1433server # [ 150.909169] postgres[1228]: create or replace function graphql_public.graphql(1434server # [ 150.913127] postgres[1228]: "operationName" text default null,1435server # [ 150.914798] postgres[1228]: query text default null,1436server # [ 150.919816] postgres[1228]: variables jsonb default null,1437server # [ 150.928685] postgres[1228]: extensions jsonb default null1438server # [ 150.933223] postgres[1228]: )1439server # [ 150.939009] postgres[1228]: returns jsonb1440server # [ 150.945694] postgres[1228]: language sql1441server # [ 150.946825] postgres[1228]: as $$1442server # [ 150.947577] postgres[1228]: SELECT graphql.resolve(query, coalesce(variables, '{}'));1443server # [ 150.950011] postgres[1228]: $$;1444server # [ 150.951411] postgres[1228]:1445server # [ 150.958806] postgres[1228]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1446server # [ 150.962155] postgres[1228]: END IF;1447server # [ 150.970688] postgres[1228]:1448server # [ 150.976572] postgres[1228]: END;1449server # [ 150.991402] postgres[1228]: $func$;1450server # [ 151.010320] postgres[1228]: ::1 2026-10-27 16:07:01.031 UTC [1228] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1451server # [ 151.036763] postgres[1228]: ::1 2026-10-27 16:07:01.042 UTC [1228] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1452server # [ 151.041615] postgres[1228]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1453server # [ 151.053429] postgres[1228]: ::1 2026-10-27 16:07:01.058 UTC [1228] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1454server # [ 151.059729] postgres[1228]: ::1 2026-10-27 16:07:01.088 UTC [1228] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1455server # [ 151.070406] postgres[1228]: RETURNS event_trigger1456server # [ 151.071664] postgres[1228]: LANGUAGE plpgsql1457server # [ 151.076878] postgres[1228]: AS $func$1458server # [ 151.078491] postgres[1228]: DECLARE1459server # [ 151.081899] postgres[1228]: graphql_is_dropped bool;1460server # [ 151.085872] postgres[1228]: BEGIN1461server # [ 151.089528] postgres[1228]: graphql_is_dropped = (1462server # [ 151.094436] postgres[1228]: SELECT ev.schema_name = 'graphql_public'1463server # [ 151.098207] postgres[1228]: FROM pg_event_trigger_dropped_objects() AS ev1464server # [ 151.101520] postgres[1228]: WHERE ev.schema_name = 'graphql_public'1465server # [ 151.103885] postgres[1228]: );1466server # [ 151.110275] postgres[1228]:1467server # [ 151.113248] postgres[1228]: IF graphql_is_dropped1468server # [ 151.118132] postgres[1228]: THEN1469server # [ 151.122329] postgres[1228]: create or replace function graphql_public.graphql(1470server # [ 151.125108] postgres[1228]: "operationName" text default null,1471server # [ 151.130499] postgres[1228]: query text default null,1472server # [ 151.134005] postgres[1228]: variables jsonb default null,1473server # [ 151.137177] postgres[1228]: extensions jsonb default null1474server # [ 151.139035] postgres[1228]: )1475server # [ 151.141422] postgres[1228]: returns jsonb1476server # [ 151.144697] postgres[1228]: language plpgsql1477server # [ 151.149839] postgres[1228]: as $$1478server # [ 151.153244] postgres[1228]: DECLARE1479server # [ 151.157646] postgres[1228]: server_version float;1480server # [ 151.161766] postgres[1228]: BEGIN1481server # [ 151.169414] postgres[1228]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1482server # [ 151.172039] postgres[1228]:1483server # [ 151.176802] postgres[1228]: IF server_version >= 14 THEN1484server # [ 151.178588] postgres[1228]: RETURN jsonb_build_object(1485server # [ 151.185325] postgres[1228]: 'data', null::jsonb,1486server # [ 151.188176] postgres[1228]: 'errors', array['pg_graphql extension is not enabled.']1487server # [ 151.190661] postgres[1228]: );1488server # [ 151.192218] postgres[1228]: ELSE1489server # [ 151.195561] postgres[1228]: RETURN jsonb_build_object(1490server # [ 151.202913] postgres[1228]: 'data', null::jsonb,1491server # [ 151.209963] postgres[1228]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1492server # [ 151.213341] postgres[1228]: );1493server # [ 151.217676] postgres[1228]: END IF;1494server # [ 151.225989] postgres[1228]: END;1495server # [ 151.229477] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: CREATE FUNCTION1496server # [ 151.233706] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping1497server # [ 151.236203] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: DROP EVENT TRIGGER1498server # [ 151.240809] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: CREATE EVENT TRIGGER1499server # [ 151.242010] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: COMMENT1500server # [ 151.243211] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: CREATE FUNCTION1501server # [ 151.249953] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping1502server # [ 151.255590] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: DROP EVENT TRIGGER1503server # [ 151.258303] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: CREATE EVENT TRIGGER1504server # [ 151.263224] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1227]: COMMENT1505server # [ 151.270214] postgres[1228]: $$;1506server # [ 151.279107] postgres[1228]: END IF;1507server # [ 151.281656] postgres[1228]:1508server # [ 151.282564] postgres[1228]: END;1509server # [ 151.283322] postgres[1228]: $func$;1510server # [ 151.288382] postgres[1228]: ::1 2026-10-27 16:07:01.210 UTC [1228] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1511server # [ 151.294704] postgres[1228]: ::1 2026-10-27 16:07:01.217 UTC [1228] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1512server # [ 151.309176] postgres[1228]: EXECUTE PROCEDURE extensions.set_graphql_placeholder();1513server # [ 151.314760] postgres[1228]: ::1 2026-10-27 16:07:01.229 UTC [1228] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1514server # [ 151.325884] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1515server # [ 151.482124] postgres[1244]: ::1 2026-10-27 16:07:01.730 UTC [1244] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1516server # [ 151.484748] postgres[1244]: ::1 2026-10-27 16:07:01.740 UTC [1244] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1517server # [ 151.491659] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1518server # [ 151.497748] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: DROP EVENT TRIGGER1519server # [ 151.501286] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: 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, skipping1520server # [ 151.505504] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: DROP FUNCTION1521server # [ 151.508041] postgres[1244]: ::1 2026-10-27 16:07:01.764 UTC [1244] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1522server # [ 151.515624] postgres[1244]: DECLARE1523server # [ 151.522028] postgres[1244]: cmd record;1524server # [ 151.523298] postgres[1244]: BEGIN1525server # [ 151.529367] postgres[1244]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1526server # [ 151.533593] postgres[1244]: LOOP1527server # [ 151.537213] postgres[1244]: IF cmd.command_tag IN (1528server # [ 151.541285] postgres[1244]: 'CREATE SCHEMA', 'ALTER SCHEMA'1529server # [ 151.547820] postgres[1244]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1530server # [ 151.554615] postgres[1244]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1531server # [ 151.561116] postgres[1244]: , 'CREATE VIEW', 'ALTER VIEW'1532server # [ 151.569861] postgres[1244]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1533server # [ 151.576335] postgres[1244]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1534server # [ 151.578772] postgres[1244]: , 'CREATE TRIGGER'1535server # [ 151.586239] postgres[1244]: , 'CREATE TYPE', 'ALTER TYPE'1536server # [ 151.590315] postgres[1244]: , 'CREATE RULE'1537server # [ 151.593564] postgres[1244]: , 'COMMENT'1538server # [ 151.597489] postgres[1244]: )1539server # [ 151.598770] postgres[1244]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1540server # [ 151.603833] postgres[1244]: AND cmd.schema_name is distinct from 'pg_temp'1541server # [ 151.614480] postgres[1244]: THEN1542server # [ 151.621434] postgres[1244]: NOTIFY pgrst, 'reload schema';1543server # [ 151.630471] postgres[1244]: END IF;1544server # [ 151.637496] postgres[1244]: END LOOP;1545server # [ 151.638682] postgres[1244]: END; $$ LANGUAGE plpgsql;1546server # [ 151.649053] postgres[1244]: ::1 2026-10-27 16:07:01.838 UTC [1244] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1547server # [ 151.651771] postgres[1244]: DECLARE1548server # [ 151.653909] postgres[1244]: obj record;1549server # [ 151.661073] postgres[1244]: BEGIN1550server # [ 151.667158] postgres[1244]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1551server # [ 151.677000] postgres[1244]: LOOP1552server # [ 151.686126] postgres[1244]: IF obj.object_type IN (1553server # [ 151.694573] postgres[1244]: 'schema'1554server # [ 151.704960] postgres[1244]: , 'table'1555server # [ 151.717008] postgres[1244]: , 'foreign table'1556server # [ 151.718836] postgres[1244]: , 'view'1557server # [ 151.726987] postgres[1244]: , 'materialized view'1558server # [ 151.734029] postgres[1244]: , 'function'1559server # [ 151.734983] postgres[1244]: , 'trigger'1560server # [ 151.735691] postgres[1244]: , 'type'1561server # [ 151.736968] postgres[1244]: , 'rule'1562server # [ 151.745712] postgres[1244]: )1563server # [ 151.749516] postgres[1244]: AND obj.is_temporary IS false -- no pg_temp objects1564server # [ 151.757962] postgres[1244]: THEN1565server # [ 151.759710] postgres[1244]: NOTIFY pgrst, 'reload schema';1566server # [ 151.765247] postgres[1244]: END IF;1567server # [ 151.773775] postgres[1244]: END LOOP;1568server # [ 151.779652] postgres[1244]: END; $$ LANGUAGE plpgsql;1569server # [ 151.785449] postgres[1244]: ::1 2026-10-27 16:07:01.867 UTC [1244] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1570server # [ 151.794076] postgres[1244]: ::1 2026-10-27 16:07:01.884 UTC [1244] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1571server # [ 151.797086] postgres[1244]: ON ddl_command_end1572server # [ 151.801617] postgres[1244]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1573server # [ 151.806937] postgres[1244]: ::1 2026-10-27 16:07:01.902 UTC [1244] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1574server # [ 151.819188] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: CREATE FUNCTION1575server # [ 151.821642] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: CREATE FUNCTION1576server # [ 151.825605] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: DROP EVENT TRIGGER1577server # [ 151.829138] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: CREATE EVENT TRIGGER1578server # [ 151.831941] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: DROP EVENT TRIGGER1579server # [ 151.837799] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1239]: CREATE EVENT TRIGGER1580server # [ 151.841982] postgres[1244]: ::1 2026-10-27 16:07:01.916 UTC [1244] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1581server # [ 151.845928] postgres[1244]: ON sql_drop1582server # [ 151.853139] postgres[1244]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1583server # [ 151.857132] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1584server # [ 152.147927] postgres[1252]: ::1 2026-10-27 16:07:02.395 UTC [1252] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1585server # [ 152.165158] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1250]: ALTER ROLE1586server # [ 152.207392] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1587server # [ 152.584026] postgres[1254]: ::1 2026-10-27 16:07:02.839 UTC [1254] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1588server # [ 152.587370] postgres[1254]: returns event_trigger1589server # [ 152.590122] postgres[1254]: language plpgsql1590server # [ 152.591832] postgres[1254]: AS $func$1591server # [ 152.593419] postgres[1254]: DECLARE1592server # [ 152.595333] postgres[1254]: func_is_graphql_resolve bool;1593server # [ 152.597910] postgres[1254]: BEGIN1594server # [ 152.599777] postgres[1254]: func_is_graphql_resolve = (1595server # [ 152.601512] postgres[1254]: SELECT n.proname = 'resolve'1596server # [ 152.603458] postgres[1254]: FROM pg_event_trigger_ddl_commands() AS ev1597server # [ 152.605094] postgres[1254]: LEFT JOIN pg_catalog.pg_proc AS n1598server # [ 152.606002] postgres[1254]: ON ev.objid = n.oid1599server # [ 152.607464] postgres[1254]: );1600server # [ 152.611055] postgres[1254]:1601server # [ 152.612859] postgres[1254]: IF func_is_graphql_resolve1602server # [ 152.614220] postgres[1254]: THEN1603server # [ 152.617662] postgres[1254]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1604server # [ 152.619325] postgres[1254]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1605server # [ 152.622461] postgres[1254]:1606server # [ 152.625244] postgres[1254]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1607server # [ 152.627611] postgres[1254]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1608server # [ 152.629525] postgres[1254]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1609server # [ 152.631002] postgres[1254]:1610server # [ 152.633687] postgres[1254]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1611server # [ 152.635012] postgres[1254]: DROP FUNCTION IF EXISTS graphql_public.graphql;1612server # [ 152.638571] postgres[1254]: create or replace function graphql_public.graphql(1613server # [ 152.639774] postgres[1254]: "operationName" text default null,1614server # [ 152.641289] postgres[1254]: query text default null,1615server # [ 152.642203] postgres[1254]: variables jsonb default null,1616server # [ 152.643793] postgres[1254]: extensions jsonb default null1617server # [ 152.645243] postgres[1254]: )1618server # [ 152.646388] postgres[1254]: returns jsonb1619server # [ 152.647757] postgres[1254]: language sql1620server # [ 152.648751] postgres[1254]: as $$1621server # [ 152.650809] postgres[1254]: -- This changed1622server # [ 152.652199] postgres[1254]: select graphql.resolve(1623server # [ 152.653752] postgres[1254]: query := query,1624server # [ 152.655430] postgres[1254]: variables := coalesce(variables, '{}'),1625server # [ 152.657732] postgres[1254]: "operationName" := "operationName",1626server # [ 152.658684] postgres[1254]: extensions := extensions1627server # [ 152.660403] postgres[1254]: );1628server # [ 152.661749] postgres[1254]: $$;1629server # [ 152.662865] postgres[1254]:1630server # [ 152.664692] postgres[1254]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1631server # [ 152.666559] postgres[1254]: END IF;1632server # [ 152.667714] postgres[1254]:1633server # [ 152.669194] postgres[1254]: END;1634server # [ 152.670463] postgres[1254]: $func$;1635server # [ 152.704513] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1253]: CREATE FUNCTION1636server # [ 152.706249] postgres[1254]: ::1 2026-10-27 16:07:02.963 UTC [1254] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1637server # [ 152.708088] postgres[1254]: RETURNS event_trigger1638server # [ 152.710368] postgres[1254]: LANGUAGE plpgsql1639server # [ 152.712100] postgres[1254]: AS $func$1640server # [ 152.714567] postgres[1254]: DECLARE1641server # [ 152.717085] postgres[1254]: graphql_is_dropped bool;1642server # [ 152.718783] postgres[1254]: BEGIN1643server # [ 152.721684] postgres[1254]: graphql_is_dropped = (1644server # [ 152.723844] postgres[1254]: SELECT ev.schema_name = 'graphql_public'1645server # [ 152.726052] postgres[1254]: FROM pg_event_trigger_dropped_objects() AS ev1646server # [ 152.727276] postgres[1254]: WHERE ev.schema_name = 'graphql_public'1647server # [ 152.729229] postgres[1254]: );1648server # [ 152.731482] postgres[1254]:1649server # [ 152.733619] postgres[1254]: IF graphql_is_dropped1650server # [ 152.735936] postgres[1254]: THEN1651server # [ 152.737385] postgres[1254]: create or replace function graphql_public.graphql(1652server # [ 152.738948] postgres[1254]: "operationName" text default null,1653server # [ 152.743929] postgres[1254]: query text default null,1654server # [ 152.747216] postgres[1254]: variables jsonb default null,1655server # [ 152.750633] postgres[1254]: extensions jsonb default null1656server # [ 152.753045] postgres[1254]: )1657server # [ 152.754413] postgres[1254]: returns jsonb1658server # [ 152.757399] postgres[1254]: language plpgsql1659server # [ 152.759368] postgres[1254]: as $$1660server # [ 152.763608] postgres[1254]: DECLARE1661server # [ 152.766454] postgres[1254]: server_version float;1662server # [ 152.769663] postgres[1254]: BEGIN1663server # [ 152.770964] postgres[1254]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1664server # [ 152.773636] postgres[1254]:1665server # [ 152.774639] postgres[1254]: IF server_version >= 14 THEN1666server # [ 152.775923] postgres[1254]: RETURN jsonb_build_object(1667server # [ 152.776979] postgres[1254]: 'errors', jsonb_build_array(1668server # [ 152.781745] postgres[1254]: jsonb_build_object(1669server # [ 152.789335] postgres[1254]: 'message', 'pg_graphql extension is not enabled.'1670server # [ 152.791770] postgres[1254]: )1671server # [ 152.795902] postgres[1254]: )1672server # [ 152.798117] postgres[1254]: );1673server # [ 152.799931] postgres[1254]: ELSE1674server # [ 152.801783] postgres[1254]: RETURN jsonb_build_object(1675server # [ 152.804649] postgres[1254]: 'errors', jsonb_build_array(1676server # [ 152.807657] postgres[1254]: jsonb_build_object(1677server # [ 152.810514] postgres[1254]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1678server # [ 152.813005] postgres[1254]: )1679server # [ 152.814087] postgres[1254]: )1680server # [ 152.814820] postgres[1254]: );1681server # [ 152.816906] postgres[1254]: END IF;1682server # [ 152.818803] postgres[1254]: END;1683server # [ 152.820314] postgres[1254]: $$;1684server # [ 152.822524] postgres[1254]: END IF;1685server # [ 152.823735] postgres[1254]:1686server # [ 152.824648] postgres[1254]: END;1687server # [ 152.825361] postgres[1254]: $func$;1688server # [ 152.829481] postgres[1254]: ::1 2026-10-27 16:07:03.029 UTC [1254] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1689server # [ 152.831858] postgres[1254]: "operationName" text default null,1690server # [ 152.834720] postgres[1254]: query text default null,1691server # [ 152.835783] postgres[1254]: variables jsonb default null,1692server # [ 152.838487] postgres[1254]: extensions jsonb default null1693server # [ 152.840172] postgres[1254]: )1694server # [ 152.845594] postgres[1254]: returns jsonb1695server # [ 152.846849] postgres[1254]: language plpgsql1696server # [ 152.848659] postgres[1254]: as $$1697server # [ 152.850469] postgres[1254]: DECLARE1698server # [ 152.851764] postgres[1254]: server_version float;1699server # [ 152.854501] postgres[1254]: BEGIN1700server # [ 152.855612] postgres[1254]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1701server # [ 152.857366] postgres[1254]:1702server # [ 152.859044] postgres[1254]: IF server_version >= 14 THEN1703server # [ 152.861292] postgres[1254]: RETURN jsonb_build_object(1704server # [ 152.863427] postgres[1254]: 'errors', jsonb_build_array(1705server # [ 152.865913] postgres[1254]: jsonb_build_object(1706server # [ 152.867586] postgres[1254]: 'message', 'pg_graphql extension is not enabled.'1707server # [ 152.871905] postgres[1254]: )1708server # [ 152.873452] postgres[1254]: )1709server # [ 152.874629] postgres[1254]: );1710server # [ 152.876332] postgres[1254]: ELSE1711server # [ 152.878511] postgres[1254]: RETURN jsonb_build_object(1712server # [ 152.880883] postgres[1254]: 'errors', jsonb_build_array(1713server # [ 152.882734] postgres[1254]: jsonb_build_object(1714server # [ 152.883631] postgres[1254]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1715server # [ 152.886972] postgres[1254]: )1716server # [ 152.894093] postgres[1254]: )1717server # [ 152.897300] postgres[1254]: );1718server # [ 152.901270] postgres[1254]: END IF;1719server # [ 152.905541] postgres[1254]: END;1720server # [ 152.909725] postgres[1254]: $$;1721server # [ 152.913662] postgres[1254]: ::1 2026-10-27 16:07:03.060 UTC [1254] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1722server # [ 152.916061] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1253]: CREATE FUNCTION1723server # [ 152.921355] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1253]: CREATE FUNCTION1724server # [ 152.923668] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1253]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping1725server # [ 152.926669] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1253]: DROP EXTENSION1726server # [ 154.614137] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1253]: DO1727server # [ 154.667305] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1728server # [ 155.033415] postgres[1272]: ::1 2026-10-27 16:07:05.279 UTC [1272] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1729server # [ 155.066218] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1267]: GRANT ROLE1730server # [ 155.084813] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1731server # [ 155.453056] postgres[1277]: ::1 2026-10-27 16:07:05.700 UTC [1277] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1732server # [ 155.461209] postgres[1277]: returns event_trigger1733server # [ 155.463479] postgres[1277]: language plpgsql1734server # [ 155.470612] postgres[1277]: AS $func$1735server # [ 155.481382] postgres[1277]: DECLARE1736server # [ 155.487613] postgres[1277]: func_is_graphql_resolve bool;1737server # [ 155.494733] postgres[1277]: BEGIN1738server # [ 155.502446] postgres[1277]: func_is_graphql_resolve = (1739server # [ 155.509738] postgres[1277]: SELECT n.proname = 'resolve'1740server # [ 155.525237] postgres[1277]: FROM pg_event_trigger_ddl_commands() AS ev1741server # [ 155.529684] postgres[1277]: LEFT JOIN pg_catalog.pg_proc AS n1742server # [ 155.537194] postgres[1277]: ON ev.objid = n.oid1743server # [ 155.559144] postgres[1277]: );1744server # [ 155.561877] postgres[1277]:1745server # [ 155.571688] postgres[1277]: IF func_is_graphql_resolve1746server # [ 155.574080] postgres[1277]: THEN1747server # [ 155.578780] postgres[1277]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1748server # [ 155.580701] postgres[1277]: DROP FUNCTION IF EXISTS graphql_public.graphql;1749server # [ 155.585506] postgres[1277]: create or replace function graphql_public.graphql(1750server # [ 155.587971] postgres[1277]: "operationName" text default null,1751server # [ 155.601364] postgres[1277]: query text default null,1752server # [ 155.604991] postgres[1277]: variables jsonb default null,1753server # [ 155.609199] postgres[1277]: extensions jsonb default null1754server # [ 155.621550] postgres[1277]: )1755server # [ 155.623717] postgres[1277]: returns jsonb1756server # [ 155.625678] postgres[1277]: language sql1757server # [ 155.626943] postgres[1277]: as $$1758server # [ 155.627841] postgres[1277]: select graphql.resolve(1759server # [ 155.632927] postgres[1277]: query := query,1760server # [ 155.634135] postgres[1277]: variables := coalesce(variables, '{}'),1761server # [ 155.634952] postgres[1277]: "operationName" := "operationName",1762server # [ 155.636597] postgres[1277]: extensions := extensions1763server # [ 155.641136] postgres[1277]: );1764server # [ 155.642832] postgres[1277]: $$;1765server # [ 155.649708] postgres[1277]:1766server # [ 155.651978] postgres[1277]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1767server # [ 155.654526] postgres[1277]: -- function in the extension so we need to grant permissions on existing entities AND1768server # [ 155.656850] postgres[1277]: -- update default permissions to any others that are created after `graphql.resolve`1769server # [ 155.658536] postgres[1277]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1770server # [ 155.662080] postgres[1277]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1771server # [ 155.665566] postgres[1277]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1772server # [ 155.674169] postgres[1277]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1773server # [ 155.675791] postgres[1277]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1774server # [ 155.678439] postgres[1277]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1775server # [ 155.680004] postgres[1277]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1776server # [ 155.684642] postgres[1277]: END IF;1777server # [ 155.686578] postgres[1277]:1778server # [ 155.689091] postgres[1277]: END;1779server # [ 155.692104] postgres[1277]: $func$;1780server # [ 155.698544] postgres[1277]: ::1 2026-10-27 16:07:05.867 UTC [1277] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1781server # [ 155.708335] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1274]: CREATE FUNCTION1782server # [ 155.712756] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1274]: DROP EXTENSION1783server # [ 157.378054] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1274]: DO1784server # [ 157.438416] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql1785server # [ 157.985407] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31786server # [ 158.717951] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1281]: DO1787server # [ 159.606751] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1281]: DO1788server # [ 159.666899] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1789server # [ 159.971876] postgres[1304]: ::1 2026-10-27 16:07:10.227 UTC [1304] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s';1790server # [ 159.996279] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1303]: ALTER ROLE1791server # [ 160.053417] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221103090837_revoke_admin.sql1792server # [ 160.334465] postgres[1306]: ::1 2026-10-27 16:07:10.591 UTC [1306] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator;1793server # [ 160.385574] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1305]: REVOKE ROLE1794server # [ 160.395303] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql1795server # [ 162.955935] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1307]: DO1796server # [ 162.994189] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql1797server # [ 163.335120] postgres[1330]: ::1 2026-10-27 16:07:13.590 UTC [1330] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres;1798server # [ 163.351842] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1329]: GRANT ROLE1799server # [ 163.392008] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql1800server # [ 163.703178] postgres[1332]: ::1 2026-10-27 16:07:13.952 UTC [1332] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option;1801server # [ 163.762636] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1331]: GRANT1802server # [ 163.768960] postgres[1332]: ::1 2026-10-27 16:07:14.022 UTC [1332] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option;1803server # [ 163.799023] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1331]: GRANT1804server # [ 163.806199] postgres[1332]: ::1 2026-10-27 16:07:14.067 UTC [1332] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option;1805server # [ 163.811741] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1331]: GRANT1806server # [ 163.815341] postgres[1332]: ::1 2026-10-27 16:07:14.079 UTC [1332] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option;1807server # [ 163.838060] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1331]: ALTER DEFAULT PRIVILEGES1808server # [ 163.839837] postgres[1332]: ::1 2026-10-27 16:07:14.103 UTC [1332] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option;1809server # [ 163.854964] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1331]: ALTER DEFAULT PRIVILEGES1810server # [ 163.858050] postgres[1332]: ::1 2026-10-27 16:07:14.118 UTC [1332] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option;1811server # [ 163.869253] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1331]: ALTER DEFAULT PRIVILEGES1812server # [ 163.929575] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql1813server # [ 164.187452] postgres[1334]: ::1 2026-10-27 16:07:14.447 UTC [1334] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres;1814server # [ 164.235458] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1333]: GRANT ROLE1815server # [ 164.268620] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql1816server # [ 164.570709] postgres[1336]: ::1 2026-10-27 16:07:14.827 UTC [1336] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin;1817server # [ 164.614301] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1335]: GRANT ROLE1818server # [ 164.636706] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql1819server # [ 165.022694] postgres[1342]: ::1 2026-10-27 16:07:15.276 UTC [1342] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit;1820server # [ 165.051535] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1337]: ALTER ROLE1821server # [ 165.069312] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1337]: ALTER ROLE1822server # [ 165.079788] postgres[1342]: ::1 2026-10-27 16:07:15.312 UTC [1342] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit;1823server # [ 165.090059] postgres[1342]: ::1 2026-10-27 16:07:15.330 UTC [1342] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit;1824server # [ 165.092974] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1337]: ALTER ROLE1825server # [ 165.170343] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1337]: DO1826server # [ 165.239195] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql1827server # [ 165.590107] postgres[1352]: ::1 2026-10-27 16:07:15.838 UTC [1352] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin;1828server # [ 165.623810] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1348]: GRANT ROLE1829server # [ 165.650716] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1348]: REVOKE ROLE1830server # [ 165.674273] postgres[1352]: ::1 2026-10-27 16:07:15.882 UTC [1352] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin;1831server # [ 165.704825] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql1832server # [ 166.124431] postgres[1359]: ::1 2026-10-27 16:07:16.369 UTC [1359] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1833server # [ 166.135152] postgres[1359]: returns event_trigger1834server # [ 166.142290] postgres[1359]: language plpgsql1835server # [ 166.145883] postgres[1359]: AS $func$1836server # [ 166.153513] postgres[1359]: DECLARE1837server # [ 166.154696] postgres[1359]: func_is_graphql_resolve bool;1838server # [ 166.161626] postgres[1359]: BEGIN1839server # [ 166.173188] postgres[1359]: func_is_graphql_resolve = (1840server # [ 166.174278] postgres[1359]: SELECT n.proname = 'resolve'1841server # [ 166.181279] postgres[1359]: FROM pg_event_trigger_ddl_commands() AS ev1842server # [ 166.182939] postgres[1359]: LEFT JOIN pg_catalog.pg_proc AS n1843server # [ 166.184186] postgres[1359]: ON ev.objid = n.oid1844server # [ 166.189252] postgres[1359]: );1845server # [ 166.190385] postgres[1359]:1846server # [ 166.192036] postgres[1359]: IF func_is_graphql_resolve1847server # [ 166.198812] postgres[1359]: THEN1848server # [ 166.206134] postgres[1359]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1849server # [ 166.217696] postgres[1359]: DROP FUNCTION IF EXISTS graphql_public.graphql;1850server # [ 166.219851] postgres[1359]: create or replace function graphql_public.graphql(1851server # [ 166.226505] postgres[1359]: "operationName" text default null,1852server # [ 166.227770] postgres[1359]: query text default null,1853server # [ 166.229853] postgres[1359]: variables jsonb default null,1854server # [ 166.233880] postgres[1359]: extensions jsonb default null1855server # [ 166.237352] postgres[1359]: )1856server # [ 166.238372] postgres[1359]: returns jsonb1857server # [ 166.239636] postgres[1359]: language sql1858server # [ 166.241073] postgres[1359]: as $$1859server # [ 166.241907] postgres[1359]: select graphql.resolve(1860server # [ 166.243563] postgres[1359]: query := query,1861server # [ 166.248924] postgres[1359]: variables := coalesce(variables, '{}'),1862server # [ 166.250153] postgres[1359]: "operationName" := "operationName",1863server # [ 166.250980] postgres[1359]: extensions := extensions1864server # [ 166.253450] postgres[1359]: );1865server # [ 166.257823] postgres[1359]: $$;1866server # [ 166.259644] postgres[1359]:1867server # [ 166.266186] postgres[1359]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1868server # [ 166.269254] postgres[1359]: -- function in the extension so we need to grant permissions on existing entities AND1869server # [ 166.270340] postgres[1359]: -- update default permissions to any others that are created after `graphql.resolve`1870server # [ 166.271694] postgres[1359]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1871server # [ 166.273625] postgres[1359]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1872server # [ 166.277284] postgres[1359]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1873server # [ 166.279264] postgres[1359]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1874server # [ 166.285707] postgres[1359]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1875server # [ 166.287724] postgres[1359]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1876server # [ 166.289640] postgres[1359]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1877server # [ 166.292832] postgres[1359]:1878server # [ 166.293910] postgres[1359]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1879server # [ 166.294808] postgres[1359]: grant usage on schema graphql_public to postgres with grant option;1880server # [ 166.301796] postgres[1359]: grant usage on schema graphql to postgres with grant option;1881server # [ 166.305337] postgres[1359]: END IF;1882server # [ 166.306769] postgres[1359]:1883server # [ 166.307945] postgres[1359]: END;1884server # [ 166.311870] postgres[1359]: $func$;1885server # [ 166.317878] postgres[1359]: ::1 2026-10-27 16:07:16.534 UTC [1359] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1886server # [ 166.337492] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1356]: CREATE FUNCTION1887server # [ 166.350490] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1356]: DROP EXTENSION1888server # [ 167.882856] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1356]: DO1889server # [ 167.930212] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql1890server # [ 168.240373] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1363]: DO1891server # [ 168.272890] postgres[1364]: ::1 2026-10-27 16:07:18.510 UTC [1364] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger1892server # [ 168.281391] postgres[1364]: LANGUAGE plpgsql1893server # [ 168.286730] postgres[1364]: AS $$1894server # [ 168.297980] postgres[1364]: BEGIN1895server # [ 168.311682] postgres[1364]: IF EXISTS (1896server # [ 168.317552] postgres[1364]: SELECT1897server # [ 168.319719] postgres[1364]: FROM pg_event_trigger_ddl_commands() AS ev1898server # [ 168.325575] postgres[1364]: JOIN pg_extension AS ext1899server # [ 168.326692] postgres[1364]: ON ev.objid = ext.oid1900server # [ 168.333292] postgres[1364]: WHERE ext.extname = 'pg_cron'1901server # [ 168.334847] postgres[1364]: )1902server # [ 168.335603] postgres[1364]: THEN1903server # [ 168.340745] postgres[1364]: grant usage on schema cron to postgres with grant option;1904server # [ 168.343286] postgres[1364]:1905server # [ 168.350128] postgres[1364]: alter default privileges in schema cron grant all on tables to postgres with grant option;1906server # [ 168.351024] postgres[1364]: alter default privileges in schema cron grant all on functions to postgres with grant option;1907server # [ 168.353551] postgres[1364]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1908server # [ 168.354685] postgres[1364]:1909server # [ 168.365573] postgres[1364]: alter default privileges for user supabase_admin in schema cron grant all1910server # [ 168.368896] postgres[1364]: on sequences to postgres with grant option;1911server # [ 168.370409] postgres[1364]: alter default privileges for user supabase_admin in schema cron grant all1912server # [ 168.371823] postgres[1364]: on tables to postgres with grant option;1913server # [ 168.372549] postgres[1364]: alter default privileges for user supabase_admin in schema cron grant all1914server # [ 168.375243] postgres[1364]: on functions to postgres with grant option;1915server # [ 168.376187] postgres[1364]:1916server # [ 168.378360] postgres[1364]: grant all privileges on all tables in schema cron to postgres with grant option;1917server # [ 168.381922] postgres[1364]: revoke all on table cron.job from postgres;1918server # [ 168.392005] postgres[1364]: grant select on table cron.job to postgres with grant option;1919server # [ 168.394287] postgres[1364]: END IF;1920server # [ 168.398448] postgres[1364]: END;1921server # [ 168.405102] postgres[1364]: $$;1922server # [ 168.409741] postgres[1364]: ::1 2026-10-27 16:07:18.615 UTC [1364] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access;1923server # [ 168.413879] postgres[1364]: ::1 2026-10-27 16:07:18.639 UTC [1364] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin;1924server # [ 168.422532] postgres[1364]: ::1 2026-10-27 16:07:18.653 UTC [1364] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end1925server # [ 168.426465] postgres[1364]: WHEN TAG IN ('CREATE EXTENSION')1926server # [ 168.433755] postgres[1364]: EXECUTE FUNCTION extensions.grant_pg_cron_access();1927server # [ 168.442116] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1363]: CREATE FUNCTION1928server # [ 168.450596] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1363]: DROP EVENT TRIGGER1929server # [ 168.462783] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1363]: ALTER FUNCTION1930server # [ 168.482519] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1363]: CREATE EVENT TRIGGER1931server # [ 168.486024] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql1932server # [ 168.979202] postgres[1377]: ::1 2026-10-27 16:07:19.207 UTC [1377] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s';1933server # [ 169.013854] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1370]: ALTER ROLE1934server # [ 169.033569] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql1935server # [ 169.412091] postgres[1386]: ::1 2026-10-27 16:07:19.662 UTC [1386] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin;1936server # [ 169.453555] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1380]: ALTER FUNCTION1937server # [ 169.457537] postgres[1386]: ::1 2026-10-27 16:07:19.709 UTC [1386] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin;1938server # [ 169.467295] postgres[1386]: ::1 2026-10-27 16:07:19.726 UTC [1386] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin;1939server # [ 169.481837] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1380]: ALTER FUNCTION1940server # [ 169.483500] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1380]: ALTER FUNCTION1941server # [ 169.521482] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql1942server # [ 169.812832] postgres[1390]: ::1 2026-10-27 16:07:20.074 UTC [1390] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres;1943server # [ 169.858331] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1389]: GRANT ROLE1944server # [ 169.869877] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241031003909_create_orioledb.sql1945server # [ 170.823035] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1391]: DO1946server # [ 170.876948] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1947server # [ 171.303144] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1393]: DO1948server # [ 171.310373] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1393]: DO1949server # [ 171.369118] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql1950server # [ 171.862619] postgres[1407]: ::1 2026-10-27 16:07:22.117 UTC [1407] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none;1951server # [ 171.886656] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1402]: ALTER ROLE1952server # [ 171.890658] postgres[1407]: ::1 2026-10-27 16:07:22.140 UTC [1407] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none;1953server # [ 171.897949] postgres[1407]: ::1 2026-10-27 16:07:22.154 UTC [1407] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none;1954server # [ 171.901453] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1402]: ALTER ROLE1955server # [ 171.915306] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1402]: ALTER ROLE1956server # [ 171.969394] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql1957server # [ 172.379058] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1412]: DO1958server # [ 172.425195] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1959server # [ 172.811827] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1419]: DO1960server # [ 172.849080] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql1961server # [ 173.161215] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1422]: CREATE FUNCTION1962server # [ 173.178225] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1422]: DO1963server # [ 173.225888] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1964server # [ 173.493925] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1424]: ALTER FUNCTION1965server # [ 173.498017] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1424]: GRANT1966server # [ 173.547989] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql1967server # [ 173.823940] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1426]: DROP EVENT TRIGGER1968server # [ 173.861637] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1426]: ALTER FUNCTION1969server # [ 173.874559] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1426]: CREATE EVENT TRIGGER1970server # [ 173.921155] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql1971server # [ 174.326933] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1428]: DO1972server # [ 174.378253] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql1973server # [ 174.935903] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1440]: CREATE FUNCTION1974server # [ 174.942481] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1440]: GRANT1975server # [ 175.010755] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql1976server # [ 175.326547] systemd[1]: Starting Virtual Console Setup...1977server # [ 175.455453] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1452]: REVOKE ROLE1978server # [ 175.515340] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1452]: DO1979server # [ 175.540751] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1452]: DO1980server # [ 175.545663] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1452]: REVOKE ROLE1981server # [ 175.552765] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1452]: REVOKE1982server # [ 175.606893] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1452]: DO1983server # [ 175.700191] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql1984server # [ 175.822020] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1985server # [ 175.851289] systemd[1]: Stopped Virtual Console Setup.1986server # [ 176.034266] systemd[1]: Starting Virtual Console Setup...1987server # [ 176.080527] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1457]: DO1988server # [ 176.125799] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql1989server # [ 176.684085] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1461]: DO1990server # [ 176.737138] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql1991server # [ 177.342452] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1463]: DO1992server # [ 177.390191] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql1993server # [ 177.859959] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1466]: ALTER ROLE1994server # [ 177.920173] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251001204436_predefined_role_grants.sql1995server # [ 178.432108] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1468]: GRANT ROLE1996server # [ 178.467238] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1468]: DO1997server # [ 178.521706] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql1998server: Guest root shell did not produce any data yet...1999server: To debug, enter the VM and run 'systemctl status backdoor.service'.2000server # [ 179.049571] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1470]: GRANT2001server # [ 179.108167] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql2002server # [ 179.681639] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1472]: CREATE FUNCTION2003server # [ 179.699530] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1472]: REVOKE2004server # [ 179.729108] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1472]: REVOKE2005server # [ 179.733787] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1472]: GRANT2006server # [ 179.757078] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260211120934_supabase_privileged_role.sql2007server # [ 180.430493] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1474]: DO2008server # [ 180.487991] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql2009server # [ 181.163637] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1476]: ALTER ROLE2010server # [ 181.199285] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql2011server # [ 181.667197] systemd-vconsole-setup[1460]: Configuration of first virtual console was skipped, ignoring remaining ones.2012server # [ 181.703910] systemd[1]: Finished Virtual Console Setup.2013server # [ 181.758179] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1478]: DROP EXTENSION2014server # [ 181.815142] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql2015server # [ 182.172288] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1481]: CREATE FUNCTION2016server # [ 182.189260] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1481]: DROP EVENT TRIGGER2017server # [ 182.202336] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1481]: CREATE EVENT TRIGGER2018server # [ 182.257437] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql2019server # [ 182.745743] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1483]: DO2020server # [ 182.809174] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1483]: CREATE FUNCTION2021server # [ 182.906950] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql2022server # [ 183.436437] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1496]: CREATE FUNCTION2023server # [ 183.451768] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1496]: CREATE FUNCTION2024server # [ 183.508877] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20261016000001_search_path_for_supa_functions.sql2025server # [ 183.816854] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1506]: CREATE FUNCTION2026server # [ 183.844950] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1506]: CREATE FUNCTION2027server # [ 183.859777] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1506]: CREATE FUNCTION2028server # [ 183.877465] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1506]: CREATE FUNCTION2029server # [ 183.892990] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1506]: CREATE FUNCTION2030server # [ 183.910623] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1506]: CREATE FUNCTION2031server # [ 183.959527] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Running postgresql schema...2032server # [ 184.235027] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER DATABASE2033server # [ 184.248764] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER DATABASE2034server # [ 184.317863] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2035server # [ 184.366262] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2036server # [ 184.430357] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2037server # [ 184.489681] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2038server # [ 184.547979] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2039server # [ 184.598657] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2040server # [ 184.685154] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2041server # [ 184.799852] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2042server # [ 184.954206] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2043server # [ 184.976964] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1508]: ALTER ROLE2044server # [ 185.031852] 1wrg825a3nq6qlidwqp8gr3hd5132j1n-supabase-db-init[1078]: Database initialization complete2045server # [ 185.077263] systemd[1]: Finished Supabase Database Initialization.2046server # [ 185.441608] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs2047server # [ 186.213500] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.2048server # [ 186.792228] 8021q: 802.1Q VLAN Support v1.82049server # [ 187.329531] cfg80211: Loading compiled-in X.509 certificates for regulatory database2050server # [ 187.602020] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'2051server # [ 187.603882] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'2052server # [ 187.631128] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -22053server # [ 187.632278] cfg80211: failed to load regulatory.db2054server # [ 188.415626] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.2055server # [ 188.467754] systemd[1]: Started backdoor.service.2056server # [ 188.498275] systemd[1]: Reached target Multi-User System.2057server # [ 188.517714] systemd[1]: Startup finished in 2.981s (kernel) + 31.652s (initrd) + 2min 33.883s (userspace) = 3min 8.516s.2058server # connecting to host...2059server # [ 189.239050] (udev-worker)[1541]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.2060server # [ 189.273715] (udev-worker)[1540]: Network interface NamePolicy= disabled on kernel command line.2061server # [ 189.282239] (udev-worker)[1541]: Network interface NamePolicy= disabled on kernel command line.2062server: Guest shell says: b'Spawning backdoor root shell...\n'2063server: connected to guest root shell2064server: (connecting took 190.50 seconds)2065server: (finished: waiting for the VM to finish booting, in 190.50 seconds)2066server # [ 190.262950] systemd[1]: Condition check resulted in Virtio network device being skipped.2067server: (finished: waiting for unit supabase-db-init.service, in 191.53 seconds)2068subtest: Prepare Regression Scenario2069server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS wrappers;" 2070server # [ 190.374361] systemd[1]: Starting Address configuration of eth1...2071server # [ 191.327525] 8021q: adding VLAN 0 to HW filter on device eth02072server # [ 191.260804] dhcpcd[754]: eth0: waiting for carrier2073server # [ 191.268349] dhcpcd[754]: eth0: carrier acquired2074server # NOTICE: extension "wrappers" does not exist, skipping2075server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS wrappers;" , in 1.08 seconds)2076server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION wrappers WITH VERSION '0.6.3' CASCADE;" 2077server # [ 191.545690] dhcpcd[754]: DUID 00:01:00:01:32:73:88:cd:52:54:00:12:34:562078server # [ 191.555003] dhcpcd[754]: eth0: IAID 00:12:34:562079server # [ 191.667723] 8021q: adding VLAN 0 to HW filter on device eth12080server # [ 191.566541] dhcpcd[754]: eth0: adding address fe80::5054:ff:fe12:34562081server # [ 191.882516] network-addresses-eth1-start[1574]: adding address 192.168.1.1/24... done2082server # [ 192.069559] network-addresses-eth1-start[1574]: adding address 2001:db8:1::1/64... done2083server # [ 192.343435] systemd[1]: Finished Address configuration of eth1.2084server # [ 193.448699] dhcpcd[754]: eth0: soliciting an IPv6 router2085server # [ 193.474368] dhcpcd[754]: eth0: Router Advertisement from fe80::22086server # [ 193.475831] dhcpcd[754]: eth0: adding address fec0::5054:ff:fe12:3456/642087server # [ 193.488139] dhcpcd[754]: eth0: adding route to fec0::/642088server # [ 193.489851] dhcpcd[754]: eth0: adding default route via fe80::22089server # [ 193.505600] dhcpcd[754]: eth0: soliciting a DHCP lease2090server # [ 193.534607] dhcpcd[754]: eth0: offered 10.0.2.15 from 10.0.2.22091server # [ 193.544923] dhcpcd[754]: eth0: probing address 10.0.2.15/242092server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION wrappers WITH VERSION '0.6.3' CASCADE;" , in 4.19 seconds)2093server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'wrappers';" 2094server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'wrappers';" , in 0.81 seconds)2095server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "2096 CREATE EXTENSION postgres_fdw;2097 CREATE SERVER wrappers_upgrade_test FOREIGN DATA WRAPPER postgres_fdw2098 OPTIONS (host 'localhost');2099 " 2100server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "2101 CREATE EXTENSION postgres_fdw;2102 CREATE SERVER wrappers_upgrade_test FOREIGN DATA WRAPPER postgres_fdw2103 OPTIONS (host 'localhost');2104 " , in 1.45 seconds)2105server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT 1 FROM pg_foreign_server WHERE srvname = 'wrappers_upgrade_test';" 2106server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT 1 FROM pg_foreign_server WHERE srvname = 'wrappers_upgrade_test';" , in 0.70 seconds)2107server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION supabase_vault;" 2108server # [ 199.090671] dhcpcd[754]: eth0: leased 10.0.2.15 for 86400 seconds2109server # [ 199.107514] dhcpcd[754]: eth0: adding route to 10.0.2.0/242110server # [ 199.113934] dhcpcd[754]: eth0: adding default route via 10.0.2.22111server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION supabase_vault;" , in 0.68 seconds)2112server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT 1 FROM pg_extension WHERE extname = 'supabase_vault';" 2113server # [ 199.702835] systemd-logind[593]: Watching system buttons on /dev/input/event0 (gpio-keys)2114server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT 1 FROM pg_extension WHERE extname = 'supabase_vault';" , in 0.82 seconds)2115(finished: subtest: Prepare Regression Scenario, in 9.73 seconds)2116subtest: Check Patch Wrappers Without Vault2117server: must succeed: /nix/store/020xmprsgd9nh6sg8vaygiv1h1cybgwy-pg_upgrade_scripts/complete.sh execute_wrappers_patch2118server: (finished: must succeed: /nix/store/020xmprsgd9nh6sg8vaygiv1h1cybgwy-pg_upgrade_scripts/complete.sh execute_wrappers_patch, in 1.92 seconds)2119(finished: subtest: Check Patch Wrappers Without Vault, in 1.92 seconds)2120(finished: run the VM test script, in 203.50 seconds)2121test script finished in 204.42s2122cleanup2123kill QemuMachine (pid 13)2124server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)2125(finished: cleanup, in 0.07 seconds)