vm-test-run-amcheck-upgrade
checks.aarch64-linux.ext-amcheck-upgrade
· build #143
· 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: QEMU running (pid 13)14server # Formatting '/build/vm-state-server/tmp.TK0Lk7YZrU', fmt=raw size=107374182415server # mke2fs 1.47.4 (6-Mar-2025)16server # Discarding device blocks: 0/262144 done17server # Creating filesystem with 262144 4k blocks and 65536 inodes18server # Filesystem UUID: 73a85636-f575-4433-b359-b81d9542309819server # 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.36 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/n4802arzqzzwrj3g75cyp51grh2zxicg-nixos-system-server-test/init regInfo=/nix/store/k00j8z106rhyy2cdky7y43j003lfw7br-closure-info/registration console=ttyAMA0,115200n8 console=tty068server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/k00j8z106rhyy2cdky7y43j003lfw7br-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.000051] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns110server # [ 0.003960] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)111server # [ 0.017261] Console: colour dummy device 80x25112server # [ 0.018967] printk: legacy console [tty0] enabled113server # [ 0.025453] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)114server # [ 0.026316] pid_max: default: 32768 minimum: 301115server # [ 0.029381] LSM: initializing lsm=capability,landlock,yama,bpf,ima116server # [ 0.031529] landlock: Up and running.117server # [ 0.031602] Yama: becoming mindful.118server # [ 0.034317] LSM support for eBPF active119server # [ 0.037634] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)120server # [ 0.038118] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)121server # [ 0.083350] rcu: Hierarchical SRCU implementation.122server # [ 0.083466] rcu: Max phase no-delay instances is 1000.123server # [ 0.084887] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level124server # [ 0.095254] fsl-mc MSI: its@8080000 domain created125server # [ 0.097427] EFI services will not be available.126server # [ 0.099708] smp: Bringing up secondary CPUs ...127server # [ 0.106996] Detected PIPT I-cache on CPU1128server # [ 0.108688] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000129server # [ 0.109429] GICv3: CPU1: using allocated LPI pending table @0x0000000100b50000130server # [ 0.111091] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]131server # [ 0.115422] smp: Brought up 1 node, 2 CPUs132server # [ 0.115618] SMP: Total of 2 processors activated.133server # [ 0.115666] CPU: All CPU(s) started at EL1134server # [ 0.115880] CPU features: detected: Branch Target Identification135server # [ 0.115939] CPU features: detected: 32-bit EL0 Support136server # [ 0.115971] CPU features: detected: 32-bit EL1 Support137server # [ 0.116001] CPU features: detected: ARMv8.4 Translation Table Level138server # [ 0.116041] CPU features: detected: Instruction cache invalidation not required for I/D coherence139server # [ 0.116089] CPU features: detected: Data cache clean to the PoU not required for I/D coherence140server # [ 0.116156] CPU features: detected: Common not Private translations141server # [ 0.116191] CPU features: detected: CRC32 instructions142server # [ 0.116221] CPU features: detected: Data cache clean to Point of Deep Persistence143server # [ 0.116259] CPU features: detected: Data cache clean to Point of Persistence144server # [ 0.116294] CPU features: detected: Data independent timing control (DIT)145server # [ 0.116327] CPU features: detected: E0PD146server # [ 0.116352] CPU features: detected: Enhanced Counter Virtualization147server # [ 0.116385] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)148server # [ 0.116422] CPU features: detected: Enhanced Privileged Access Never149server # [ 0.116637] CPU features: detected: Enhanced Virtualization Traps150server # [ 0.116680] CPU features: detected: Fine Grained Traps151server # [ 0.116716] CPU features: detected: Guarded Control Stack (GCS)152server # [ 0.116795] CPU features: detected: Generic authentication (IMP DEF algorithm)153server # [ 0.116909] CPU features: detected: RCpc load-acquire (LDAPR)154server # [ 0.116971] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)155server # [ 0.117008] CPU features: detected: LSE atomic instructions156server # [ 0.117041] CPU features: detected: Memory Copy and Memory Set instructions157server # [ 0.117072] CPU features: detected: Privileged Access Never158server # [ 0.117130] CPU features: detected: PMUv3159server # [ 0.117160] CPU features: detected: SCTLR2160server # [ 0.117185] CPU features: detected: RAS Extension Support161server # [ 0.117249] CPU features: detected: RASv1p1 Extension Support162server # [ 0.117278] CPU features: detected: Random Number Generator163server # [ 0.117308] CPU features: detected: Speculation barrier (SB)164server # [ 0.117337] CPU features: detected: Stage-2 Force Write-Back165server # [ 0.117369] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality166server # [ 0.117402] CPU features: detected: TLB range maintenance instructions167server # [ 0.117432] CPU features: detected: WFx with timeout168server # [ 0.117526] CPU features: detected: Scalable Matrix Extension169server # [ 0.117561] CPU features: detected: FA64170server # [ 0.117587] CPU features: detected: SME2171server # [ 0.117609] CPU features: detected: Speculative Store Bypassing Safe (SSBS)172server # [ 0.117638] CPU features: detected: Scalable Vector Extension173server # [ 0.123948] alternatives: applying system-wide alternatives174server # [ 0.141417] CPU features: detected: Hardware dirty bit management on CPU0-1175server # [ 0.142285] SVE: maximum available vector length 256 bytes per vector176server # [ 0.142379] SVE: default vector length 64 bytes per vector177server # [ 0.143478] SME: minimum available vector length 16 bytes per vector178server # [ 0.143525] SME: maximum available vector length 256 bytes per vector179server # [ 0.143570] SME: default vector length 32 bytes per vector180server # [ 0.162227] Memory: 3904048K/4194304K available (24320K kernel code, 7090K rwdata, 26572K rodata, 4736K init, 1103K bss, 241772K reserved, 32768K cma-reserved)181server # [ 0.188418] devtmpfs: initialized182server # [ 0.223311] posixtimers hash table entries: 1024 (order: 2, 16384 bytes, linear)183server # [ 0.223676] futex hash table entries: 512 (32768 bytes on 1 NUMA nodes, total 32 KiB, linear).184server # [ 0.226557] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL185server # [ 0.226649] 0 pages in range for non-PLT usage186server # [ 0.226703] 508272 pages in range for PLT usage187server # [ 0.230418] pinctrl core: initialized pinctrl subsystem188server # [ 0.244245] DMI not present or invalid.189server # [ 0.267635] NET: Registered PF_NETLINK/PF_ROUTE protocol family190server # [ 0.280347] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations191server # [ 0.281562] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations192server # [ 0.282757] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations193server # [ 0.283130] audit: initializing netlink subsys (disabled)194server # [ 0.285799] audit: type=2000 audit(0.168:1): state=initialized audit_enabled=0 res=1195server # [ 0.292583] thermal_sys: Registered thermal governor 'fair_share'196server # [ 0.292655] thermal_sys: Registered thermal governor 'bang_bang'197server # [ 0.292721] thermal_sys: Registered thermal governor 'step_wise'198server # [ 0.292760] thermal_sys: Registered thermal governor 'user_space'199server # [ 0.292795] thermal_sys: Registered thermal governor 'power_allocator'200server # [ 0.293730] cpuidle: using governor ladder201server # [ 0.294197] cpuidle: using governor menu202server # [ 0.296448] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.203server # [ 0.297383] ASID allocator initialised with 65536 entries204server # [ 0.305457] Serial: AMBA PL011 UART driver205server # [ 0.380771] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1206server # [ 0.383550] printk: console [ttyAMA0] enabled207server # [ 0.436974] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages208server # [ 0.437069] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page209server # [ 0.437115] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages210server # [ 0.437146] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page211server # [ 0.437177] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages212server # [ 0.437206] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page213server # [ 0.437239] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages214server # [ 0.437267] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page215server # [ 0.507324] fbcon: Taking over console216server # [ 0.507630] ACPI: Interpreter disabled.217server # [ 0.515081] iommu: Default domain type: Translated218server # [ 0.515151] iommu: DMA domain TLB invalidation policy: strict mode219server # [ 0.519515] SCSI subsystem initialized220server # [ 0.523038] usbcore: registered new interface driver usbfs221server # [ 0.523289] usbcore: registered new interface driver hub222server # [ 0.523520] usbcore: registered new device driver usb223server # [ 0.526001] pps_core: LinuxPPS API ver. 1 registered224server # [ 0.526053] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>225server # [ 0.526137] PTP clock support registered226server # [ 0.526961] EDAC MC: Ver: 3.0.0227server # [ 0.529118] scmi_core: SCMI protocol bus registered228server # [ 0.533087] FPGA manager framework229server # [ 0.543418] vgaarb: loaded230server # [ 0.546999] clocksource: Switched to clocksource arch_sys_counter231server # [ 0.554065] VFS: Disk quotas dquot_6.6.0232server # [ 0.554267] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)233server # [ 0.556593] netfs: FS-Cache loaded234server # [ 0.557900] pnp: PnP ACPI: disabled235server # [ 0.605427] NET: Registered PF_INET protocol family236server # [ 0.607901] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)237server # [ 0.726788] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)238server # [ 0.727005] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)239server # [ 0.727168] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)240server # [ 0.727653] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)241server # [ 0.727998] TCP: Hash tables configured (established 32768 bind 32768)242server # [ 0.729366] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)243server # [ 0.730089] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)244server # [ 0.732826] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)245server # [ 0.736248] NET: Registered PF_UNIX/PF_LOCAL protocol family246server # [ 0.736774] NET: Registered PF_XDP protocol family247server # [ 0.736990] PCI: CLS 0 bytes, default 64248server # [ 0.740628] Trying to unpack rootfs image as initramfs...249server # [ 0.748588] kvm [1]: HYP mode not available250server # [ 0.915655] Initialise system trusted keyrings251server # [ 0.920264] workingset: timestamp_bits=42 max_order=20 bucket_order=0252server # [ 0.930777] squashfs: version 4.0 (2009/01/31) Phillip Lougher253server # [ 0.933004] 9p: Installing v9fs 9p2000 file system support254server # [ 0.995697] Key type asymmetric registered255server # [ 0.995856] Asymmetric key parser 'x509' registered256server # [ 0.996251] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)257server # [ 0.999484] io scheduler mq-deadline registered258server # [ 0.999610] io scheduler kyber registered259server # [ 1.053798] pl061_gpio 9030000.pl061: PL061 GPIO chip registered260server # [ 1.064399] ledtrig-cpu: registered to indicate activity on CPUs261server # [ 1.069348] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:262server # [ 1.070328] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000263server # [ 1.070752] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000264server # [ 1.070842] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000265server # [ 1.075389] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits266server # [ 1.083148] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]267server # [ 1.093534] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00268server # [ 1.097122] pci_bus 0000:00: root bus resource [bus 00-ff]269server # [ 1.097485] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]270server # [ 1.097582] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]271server # [ 1.097638] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]272server # [ 1.099209] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint273server # [ 1.114785] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint274server # [ 1.117463] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]275server # [ 1.120296] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]276server # [ 1.120993] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]277server # [ 1.121136] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]278server # [ 1.125054] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint279server # [ 1.125888] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]280server # [ 1.125951] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]281server # [ 1.126001] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282server # [ 1.130036] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint283server # [ 1.130189] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]284server # [ 1.130284] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]285server # [ 1.131052] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]286server # [ 1.133925] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287server # [ 1.134366] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]288server # [ 1.134860] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]289server # [ 1.135095] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]290server # [ 1.141491] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291server # [ 1.141645] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]292server # [ 1.141871] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]293server # [ 1.141976] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]294server # [ 1.142504] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint295server # [ 1.142597] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]296server # [ 1.142648] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]297server # [ 1.142693] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]298server # [ 1.144112] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint299server # [ 1.144226] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]300server # [ 1.144272] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]301server # [ 1.144314] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302server # [ 1.144352] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]303server # [ 1.144895] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint304server # [ 1.144988] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]305server # [ 1.145035] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]306server # [ 1.145608] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint307server # [ 1.146011] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]308server # [ 1.146221] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]309server # [ 1.146977] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint310server # [ 1.147098] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]311server # [ 1.147676] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint312server # [ 1.147796] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]313server # [ 1.147852] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]314server # [ 1.147912] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]315server # [ 1.148439] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint316server # [ 1.148535] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]317server # [ 1.148574] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]318server # [ 1.148621] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]319server # [ 1.152965] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned320server # [ 1.153181] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned321server # [ 1.153309] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned322server # [ 1.154068] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned323server # [ 1.154180] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned324server # [ 1.156231] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned325server # [ 1.156347] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned326server # [ 1.156406] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned327server # [ 1.156465] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned328server # [ 1.156673] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned329server # [ 1.156736] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned330server # [ 1.156799] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned331server # [ 1.157359] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned332server # [ 1.157444] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned333server # [ 1.157512] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned334server # [ 1.157563] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned335server # [ 1.157615] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned336server # [ 1.157756] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned337server # [ 1.157824] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned338server # [ 1.157869] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned339server # [ 1.157916] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned340server # [ 1.157965] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned341server # [ 1.158015] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned342server # [ 1.158162] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned343server # [ 1.158211] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned344server # [ 1.158272] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned345server # [ 1.158332] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned346server # [ 1.158375] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned347server # [ 1.158420] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned348server # [ 1.158458] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned349server # [ 1.158497] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned350server # [ 1.158537] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned351server # [ 1.158573] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned352server # [ 1.158615] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned353server # [ 1.162339] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]354server # [ 1.162425] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]355server # [ 1.162463] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]356server # [ 1.166917] pci 0000:00:0a.0: enabling device (0000 -> 0002)357server # [ 1.254345] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)358server # [ 1.261426] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)359server # [ 1.266238] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)360server # [ 1.268912] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)361server # [ 1.275932] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)362server # [ 1.280039] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)363server # [ 1.282387] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)364server # [ 1.286338] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)365server # [ 1.292595] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)366server # [ 1.297803] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)367server # [ 1.304003] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)368server # [ 1.332788] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled369server # [ 1.346474] msm_serial: driver initialized370server # [ 1.348026] SuperH (H)SCI(F) driver initialized371server # [ 1.348460] STM32 USART driver initialized372server # [ 1.434720] loop: module loaded373server # [ 1.435335] virtio_blk virtio5: 2/0/0 default/read/poll queues374server # [ 1.439290] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)375server # [ 1.457657] megasas: 07.734.00.00-rc1376server # [ 1.465949] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]377server # [ 1.477042] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000378server # [ 1.478380] Intel/Sharp Extended Query Table at 0x0031379server # [ 1.481843] Using buffer write method380server # [ 1.485333] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]381server # [ 1.486678] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000382server # [ 1.486932] Intel/Sharp Extended Query Table at 0x0031383server # [ 1.488398] Using buffer write method384server # [ 1.490432] Concatenating MTD devices:385server # [ 1.490491] (0): "0.flash"386server # [ 1.490522] (1): "0.flash"387server # [ 1.490545] into device "0.flash"388server # [ 2.089712] Freeing initrd memory: 25488K389server # [ 2.216837] tun: Universal TUN/TAP device driver, 1.6390server # [ 2.233196] thunder_xcv, ver 1.0391server # [ 2.233435] thunder_bgx, ver 1.0392server # [ 2.233565] nicpf, ver 1.0393server # [ 2.238420] e1000: Intel(R) PRO/1000 Network Driver394server # [ 2.238496] e1000: Copyright (c) 1999-2006 Intel Corporation.395server # [ 2.238687] e1000e: Intel(R) PRO/1000 Network Driver396server # [ 2.238724] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.397server # [ 2.238853] igb: Intel(R) Gigabit Ethernet Network Driver398server # [ 2.238886] igb: Copyright (c) 2007-2014 Intel Corporation.399server # [ 2.239007] igbvf: Intel(R) Gigabit Virtual Function Network Driver400server # [ 2.239038] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.401server # [ 2.239754] sky2: driver version 1.30402server # [ 2.253043] usbcore: registered new interface driver usb-storage403server # [ 2.254350] ehci-pci 0000:00:0a.0: EHCI Host Controller404server # [ 2.254898] usbcore: registered new interface driver usbserial_generic405server # [ 2.254912] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1406server # [ 2.255173] usbserial: USB Serial support registered for generic407server # [ 2.256928] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000408server # [ 2.259710] hv_vmbus: registering driver hyperv_keyboard409server # [ 2.263883] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00410server # [ 2.266812] rtc-pl031 9010000.pl031: registered as rtc0411server # [ 2.267339] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T00:01:51 UTC (1791331311)412server # [ 2.271540] i2c_dev: i2c /dev entries driver413server # [ 2.273482] hub 1-0:1.0: USB hub found414server # [ 2.274067] hub 1-0:1.0: 6 ports detected415server # [ 2.288518] sdhci: Secure Digital Host Controller Interface driver416server # [ 2.288663] sdhci: Copyright(c) Pierre Ossman417server # [ 2.290235] Synopsys Designware Multimedia Card Interface Driver418server # [ 2.293563] sdhci-pltfm: SDHCI platform and OF driver helper419server # [ 2.302441] hid: raw HID events driver (C) Jiri Kosina420server # [ 2.305864] usbcore: registered new interface driver usbhid421server # [ 2.305933] usbhid: USB HID core driver422server # [ 2.317015] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available423server # [ 2.328055] drop_monitor: Initializing network drop monitor service424server # [ 2.329176] NET: Registered PF_INET6 protocol family425server # [ 2.342000] Segment Routing with IPv6426server # [ 2.342246] In-situ OAM (IOAM) with IPv6427server # [ 2.342737] NET: Registered PF_PACKET protocol family428server # [ 2.344850] 9pnet: Installing 9P2000 support429server # [ 2.350283] Key type dns_resolver registered430server # [ 2.399245] registered taskstats version 1431server # [ 2.402834] Loading compiled-in X.509 certificates432server # [ 2.480409] Demotion targets for Node 0: null433server # [ 2.481611] Key type .fscrypt registered434server # [ 2.481696] Key type fscrypt-provisioning registered435server # [ 2.484145] ima: No TPM chip found, activating TPM-bypass!436server # [ 2.484326] ima: Allocated hash algorithm: sha1437server # [ 2.486430] ima: No architecture policies found438server # [ 2.494359] input: gpio-keys as /devices/platform/gpio-keys/input/input0439server # [ 2.541148] clk: Disabling unused clocks440server # [ 2.541380] PM: genpd: Disabling unused power domains441server # [ 2.544314] usb 1-1: new high-speed USB device number 2 using ehci-pci442server # [ 2.735897] 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.801061] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0444server # [ 2.888067] Freeing unused kernel memory: 4736K445server # [ 2.892407] Run /init as init process446server # [ 2.924768] usb 1-2: new high-speed USB device number 3 using ehci-pci447server # [ 3.041977] systemd[1]: Successfully made /usr/ read-only.448server # [ 3.093185] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2449server # [ 3.094344] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0450server # [ 3.408115] 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.408451] systemd[1]: Detected virtualization qemu.452server # [ 3.409058] systemd[1]: Detected architecture arm64.453server # [ 3.409137] systemd[1]: Running in initrd.454server # [ 3.421035] systemd[1]: Initializing machine ID from random generator.455server # [ 3.425128] systemd[1]: Hostname set to <server>.456server # [ 4.250970] systemd[1]: Queued start job for default target Initrd Default Target.457server # [ 4.331712] systemd[1]: Created slice Slice /system/modprobe.458server # [ 4.343293] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459server # [ 4.348147] systemd[1]: Expecting device /dev/disk/by-label/nixos...460server # [ 4.350096] systemd[1]: Reached target Path Units.461server # [ 4.355588] systemd[1]: Reached target Slice Units.462server # [ 4.356484] systemd[1]: Reached target Swaps.463server # [ 4.357248] systemd[1]: Reached target Timer Units.464server # [ 4.366369] systemd[1]: Listening on D-Bus System Message Bus Socket.465server # [ 4.376856] systemd[1]: Listening on Journal Socket (/dev/log).466server # [ 4.391340] systemd[1]: Listening on Journal Sockets.467server # [ 4.406127] systemd[1]: Listening on udev Control Socket.468server # [ 4.411950] systemd[1]: Listening on udev Kernel Socket.469server # [ 4.415627] systemd[1]: Reached target Socket Units.470server # [ 4.469611] systemd[1]: Starting Create List of Static Device Nodes...471server # [ 4.503752] systemd[1]: Starting Load Kernel Module 9pnet_virtio...472server # [ 4.504609] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs473server # [ 4.560258] systemd[1]: Mounting Kernel Configuration File System...474server # [ 4.617958] systemd[1]: Starting Journal Service...475server # [ 4.662855] systemd[1]: Starting Load Kernel Modules...476server # [ 4.663738] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki477server # [ 4.710092] systemd[1]: Starting Coldplug All udev Devices...478server # [ 4.835376] systemd[1]: Finished Create List of Static Device Nodes.479server # [ 4.899411] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480server # [ 4.940234] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481server # [ 4.978194] systemd[1]: Mounted Kernel Configuration File System.482server # [ 5.073657] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...483server # [ 5.313281] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0484server # [ 5.314579] [drm] features: -virgl +edid -resource_blob -host_visible485server # [ 5.314617] [drm] features: -context_init486server # [ 5.346554] [drm] number of scanouts: 1487server # [ 5.371803] [drm] number of cap sets: 0488server # [ 5.400662] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.489server # [ 5.424000] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic490server # [ 5.424144] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0491server # [ 5.424455] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev492server # [ 5.498601] systemd-journald[81]: Collecting audit messages is disabled.493server # [ 5.521281] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.494server # [ 5.546690] Console: switching to colour frame buffer device 160x50495server # [ 5.563774] systemd[1]: Starting Create Static Device Nodes in /dev...496server # [ 5.577667] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device497server # [ 5.653373] systemd[1]: Finished Load Kernel Modules.498server # [ 5.746189] systemd[1]: Starting Apply Kernel Variables...499server # [ 5.965751] systemd[1]: Finished Create Static Device Nodes in /dev.500server # [ 5.978561] systemd[1]: Reached target Preparation for Local File Systems.501server # [ 5.984447] systemd[1]: Reached target Local File Systems.502server # [ 6.048800] systemd[1]: Starting Rule-based Manager for Device Events and Files...503server # [ 6.154386] systemd[1]: Finished Apply Kernel Variables.504server # [ 6.407235] systemd[1]: Started Journal Service.505server # [ 6.179352] systemd-modules-load[82]: Using 2 probe threads506server # [ 6.195239] systemd-modules-load[82]: Module 'virtio_balloon' is built in507server # [ 6.209770] systemd-modules-load[82]: Module 'virtio_console' is built in508server # [ 6.219875] systemd-modules-load[82]: Inserted module 'dm_mod'509server # [ 6.227921] systemd-modules-load[82]: Module 'virtio_rng' is built in510server # [ 6.236463] systemd-modules-load[82]: Inserted module 'virtio_gpu'511server # [ 6.239583] systemd[1]: Starting Create System Files and Directories...512server # [ 6.494156] systemd-udevd[89]: Using default interface naming scheme 'v260'.513server # [ 6.600015] systemd[1]: Finished Create System Files and Directories.514server # [ 6.887084] systemd[1]: Started Rule-based Manager for Device Events and Files.515server # [ 7.577948] systemd[1]: Starting Virtual Console Setup...516server # [ 7.875945] systemd-vconsole-setup[111]: Configuration of first virtual console was skipped, ignoring remaining ones.517server # [ 7.909838] systemd[1]: Finished Virtual Console Setup.518server # [ 12.514746] systemd[1]: Finished Coldplug All udev Devices.519server # [ 12.520063] systemd[1]: Reached target System Initialization.520server # [ 12.522118] systemd[1]: Reached target Basic System.521server # [ 14.557196] (udev-worker)[121]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.522server # [ 14.599674] (udev-worker)[121]: Network interface NamePolicy= disabled on kernel command line.523server # [ 14.684725] (udev-worker)[125]: Network interface NamePolicy= disabled on kernel command line.524server # [ 15.360977] systemd[1]: Found device /dev/disk/by-label/nixos.525server # [ 15.362635] systemd[1]: Reached target Initrd Root Device.526server # [ 15.377508] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...527server # [ 15.702432] systemd-fsck[139]: nixos: clean, 12/65536 files, 13019/262144 blocks528server # [ 15.977102] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.529server # [ 16.013376] systemd[1]: Mounting /sysroot...530server # [ 16.543522] EXT4-fs (vda): mounted filesystem 73a85636-f575-4433-b359-b81d95423098 r/w with ordered data mode. Quota mode: none.531server # [ 16.324261] systemd[1]: Mounted /sysroot.532server # [ 16.335627] systemd[1]: Reached target Initrd Root File System.533server # [ 16.389219] systemd[1]: Mounting /sysroot/nix/.ro-store...534server # [ 16.456582] systemd[1]: Mounting /sysroot/nix/.rw-store...535server # [ 16.557484] systemd[1]: Mounting /sysroot/run...536server # [ 16.655287] systemd[1]: Mounting /sysroot/tmp/shared...537server # [ 16.755089] systemd[1]: Mounting /sysroot/tmp/xchg...538server # [ 16.948750] systemd[1]: Starting Mountpoints Configured in the Real Root...539server # [ 17.240712] systemd[1]: Mounted /sysroot/nix/.ro-store.540server # [ 17.365003] systemd[1]: Mounted /sysroot/nix/.rw-store.541server # [ 17.380076] systemd[1]: Mounted /sysroot/run.542server # [ 17.385812] systemd[1]: Mounted /sysroot/tmp/shared.543server # [ 17.392539] systemd[1]: Mounted /sysroot/tmp/xchg.544server # [ 17.406395] systemd-sysroot-fstab-check[170]: /sysroot should be mounted in the initrd, will request daemon-reload.545server # [ 17.543438] systemd[1]: Starting rw-sysroot-nix-store.service...546server # [ 17.548292] systemd[1]: Reload requested from client PID 170 ('systemd-sysroot') (unit initrd-parse-etc.service)...547server # [ 17.553593] systemd[1]: Reloading...548server # [ 19.443566] systemd[1]: Reloading finished in 1879 ms.549server # [ 19.504167] systemd-sysroot-fstab-check[170]: Requesting initrd-fs.target/start/replace...550server # [ 19.513364] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.551server # [ 19.515927] systemd[1]: Finished rw-sysroot-nix-store.service.552server # [ 19.525240] systemd-sysroot-fstab-check[170]: Requesting swap.target/start/replace...553server # [ 19.550877] systemd[1]: initrd-parse-etc.service: Deactivated successfully.554server # [ 19.554395] systemd[1]: Finished Mountpoints Configured in the Real Root.555server # [ 19.558770] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.556server # [ 19.586416] systemd[1]: Starting rw-sysroot-nix-store.service...557server # [ 19.691834] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.558server # [ 19.699462] systemd[1]: Finished rw-sysroot-nix-store.service.559server # [ 19.734013] systemd[1]: Mounting /sysroot/nix/store...560server # [ 19.991517] systemd[1]: Mounted /sysroot/nix/store.561server # [ 20.005451] systemd[1]: Reached target Initrd File Systems.562server # [ 20.023619] systemd[1]: Starting Find NixOS closure...563server # [ 20.048559] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...564server # [ 20.344974] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.565server # [ 20.389911] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.566server # [ 20.713273] systemd[1]: Finished Find NixOS closure.567server # [ 20.720886] systemd[1]: Reached target Initrd Default Target.568server # [ 20.755065] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...569server # [ 21.090228] systemd[1]: Stopped target Initrd Default Target.570server # [ 21.094383] systemd[1]: Stopped target Basic System.571server # [ 21.100094] systemd[1]: Stopped target Initrd Root Device.572server # [ 21.103298] systemd[1]: Stopped target Path Units.573server # [ 21.107532] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.574server # [ 21.112226] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.575server # [ 21.125811] systemd[1]: Stopped target Slice Units.576server # [ 21.128780] systemd[1]: Stopped target Socket Units.577server # [ 21.135587] systemd[1]: Stopped target System Initialization.578server # [ 21.141761] systemd[1]: Stopped target Swaps.579server # [ 21.146142] systemd[1]: Stopped target Timer Units.580server # [ 21.148505] systemd[1]: dbus.socket: Deactivated successfully.581server # [ 21.154548] systemd[1]: Closed D-Bus System Message Bus Socket.582server # [ 21.158992] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.583server # [ 21.162844] systemd[1]: Stopped Find NixOS closure.584server # [ 21.192602] systemd[1]: Starting Load Kernel Module 9pnet_virtio...585server # [ 21.217534] systemd[1]: Starting rw-sysroot-nix-store.service...586server # [ 21.229949] systemd[1]: systemd-sysctl.service: Deactivated successfully.587server # [ 21.236759] systemd[1]: Stopped Apply Kernel Variables.588server # [ 21.249645] systemd[1]: systemd-modules-load.service: Deactivated successfully.589server # [ 21.253405] systemd[1]: Stopped Load Kernel Modules.590server # [ 21.257572] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.591server # [ 21.259740] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.592server # [ 21.286073] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.593server # [ 21.293830] systemd[1]: Stopped Create System Files and Directories.594server # [ 21.303913] systemd[1]: Stopped target Local File Systems.595server # [ 21.307167] systemd[1]: Stopped target Preparation for Local File Systems.596server # [ 21.311764] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.597server # [ 21.313817] systemd[1]: Stopped Coldplug All udev Devices.598server # [ 21.319463] systemd[1]: systemd-udev-trigger.service: Consumed 6.281s CPU time over 16.840s wall clock time, 9.5M memory peak.599server # [ 21.342660] systemd[1]: Stopping Rule-based Manager for Device Events and Files...600server # [ 21.346251] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.601server # [ 21.355524] systemd[1]: Stopped Virtual Console Setup.602server # [ 21.419238] systemd[1]: initrd-cleanup.service: Deactivated successfully.603server # [ 21.421457] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.604server # [ 21.426197] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.605server # [ 21.428571] systemd[1]: Finished Load Kernel Module 9pnet_virtio.606server # [ 21.483363] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.607server # [ 21.487711] systemd[1]: Finished rw-sysroot-nix-store.service.608server # [ 21.666744] systemd[1]: systemd-udevd.service: Deactivated successfully.609server # [ 21.671567] systemd[1]: Stopped Rule-based Manager for Device Events and Files.610server # [ 21.674301] systemd[1]: systemd-udevd.service: Consumed 12.313s CPU time over 15.856s wall clock time, 31.1M memory peak.611server # [ 21.677232] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.612server # [ 21.679566] systemd[1]: Closed udev Control Socket.613server # [ 21.689031] systemd[1]: Starting Cleanup udev Database...614server # [ 21.692495] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.615server # [ 21.695185] systemd[1]: Stopped Create Static Device Nodes in /dev.616server # [ 21.696466] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.617server # [ 21.699526] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.618server # [ 21.702407] systemd[1]: kmod-static-nodes.service: Deactivated successfully.619server # [ 21.707121] systemd[1]: Stopped Create List of Static Device Nodes.620server # [ 21.870714] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.621server # [ 21.873757] systemd[1]: Finished Cleanup udev Database.622server # [ 21.881248] systemd[1]: Reached target Switch Root.623server # [ 21.890615] systemd[1]: Starting NixOS Activation...624server # [ 23.326214] initrd-nixos-activation-start[239]: booting system configuration /nix/store/n4802arzqzzwrj3g75cyp51grh2zxicg-nixos-system-server-test625server # [ 24.084802] initrd-nixos-activation-start[239]: running activation script...626server # [ 28.210362] initrd-nixos-activation-start[262]: setting up /etc...627server: Guest root shell did not produce any data yet...628server: To debug, enter the VM and run 'systemctl status backdoor.service'.629server # [ 30.617734] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.630server # [ 30.620552] systemd[1]: Finished NixOS Activation.631server # [ 30.623784] systemd[1]: initrd-nixos-activation.service: Consumed 4.738s CPU time over 8.719s wall clock time, 22.6M memory peak.632server # [ 30.642183] systemd[1]: Starting Switch Root...633server # [ 30.800709] systemd[1]: Switching root.634server # [ 31.208538] systemd-journald[81]: Received SIGTERM from PID 1 (systemd).635server # [ 33.430217] 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)636server # [ 33.430636] systemd[1]: Detected virtualization qemu.637server # [ 33.430934] systemd[1]: Detected architecture arm64.638server # [ 33.435783] systemd[1]: Detected first boot.639server # [ 33.468891] systemd[1]: Initializing machine ID from random generator.640server # [ 34.765425] systemd[1]: bpf-restrict-fs: LSM BPF program attached641server # [ 36.255117] systemd[1]: Applying preset policy.642server # [ 40.841095] systemd[1]: Populated /etc with preset unit settings.643server # [ 45.505838] systemd[1]: initrd-switch-root.service: Deactivated successfully.644server # [ 45.511184] systemd[1]: Stopped initrd-switch-root.service.645server # [ 45.534187] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.646server # [ 45.551410] systemd[1]: Created slice Slice /system/getty.647server # [ 45.563552] systemd[1]: Created slice User and Session Slice.648server # [ 45.570743] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.649server # [ 45.576891] systemd[1]: Started Forward Password Requests to Wall Directory Watch.650server # [ 45.581646] systemd[1]: Expecting device /dev/hvc0...651server # [ 45.585184] systemd[1]: Expecting device /dev/ttyAMA0...652server # [ 45.588421] systemd[1]: Reached target Local Encrypted Volumes.653server # [ 45.592019] systemd[1]: Stopped target initrd-fs.target.654server # [ 45.594540] systemd[1]: Stopped target initrd-root-fs.target.655server # [ 45.598234] systemd[1]: Stopped target initrd-switch-root.target.656server # [ 45.601984] systemd[1]: Reached target Virtual Machines and Containers.657server # [ 45.605873] systemd[1]: Reached target Path Units.658server # [ 45.610409] systemd[1]: Reached target Remote File Systems.659server # [ 45.613610] systemd[1]: Reached target Slice Units.660server # [ 45.617847] systemd[1]: Reached target Swaps.661server # [ 45.646609] systemd[1]: Listening on Process Core Dump Socket.662server # [ 45.669144] systemd[1]: Listening on Credential Encryption/Decryption.663server # [ 45.704586] systemd[1]: Starting Journal Log Access Socket...664server # [ 45.714388] systemd[1]: Listening on Journal Audit Socket.665server # [ 45.725063] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.666server # [ 45.732649] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki667server # [ 45.740132] systemd[1]: Listening on udev Control Socket.668server # [ 45.771647] systemd[1]: Mounting Huge Pages File System...669server # [ 45.808082] systemd[1]: Mounting POSIX Message Queue File System...670server # [ 45.858019] systemd[1]: Mounting Kernel Debug File System...671server # [ 45.926337] systemd[1]: Mounting Kernel Trace File System...672server # [ 45.997451] systemd[1]: Starting Create List of Static Device Nodes...673server # [ 46.054578] systemd[1]: Starting Load Kernel Module 9pnet_virtio...674server # [ 46.069632] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs675server # [ 46.128856] systemd[1]: Mounting Kernel Configuration File System...676server # [ 46.152112] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm677server # [ 46.169333] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore678server # [ 46.438404] systemd[1]: Starting Load Kernel Module fuse...679server # [ 46.485543] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67680server # [ 46.578455] systemd[1]: Starting Journal Service...681server # [ 46.625996] systemd[1]: Starting Load Kernel Modules...682server # [ 46.713747] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...683server # [ 46.802057] systemd[1]: Starting Remount Root and Kernel File Systems...684server # [ 46.814557] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki685server # [ 46.905185] systemd[1]: Starting Coldplug All udev Devices...686server # [ 46.961359] systemd[1]: Listening on Journal Log Access Socket.687server # [ 47.006206] systemd[1]: Finished Create List of Static Device Nodes.688server # [ 47.146211] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.689server # [ 47.236463] systemd[1]: Finished Load Kernel Module 9pnet_virtio.690server # [ 47.344090] fuse: init (API version 7.45)691server # [ 47.358843] systemd[1]: Mounted Huge Pages File System.692server # [ 47.435071] systemd[1]: Mounted POSIX Message Queue File System.693server # [ 47.463901] systemd[1]: Mounted Kernel Debug File System.694server # [ 47.476203] systemd[1]: Mounted Kernel Trace File System.695server # [ 47.485523] systemd[1]: Mounted Kernel Configuration File System.696server # [ 47.497152] systemd[1]: modprobe@fuse.service: Deactivated successfully.697server # [ 47.509279] systemd[1]: Finished Load Kernel Module fuse.698server # [ 47.575814] systemd[1]: Mounting FUSE Control File System...699server # [ 47.705597] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...700server # [ 47.882662] systemd-journald[333]: Collecting audit messages is enabled.701server # [ 48.179681] EXT4-fs (vda): re-mounted 73a85636-f575-4433-b359-b81d95423098.702server # [ 48.245388] systemd[1]: Finished Load Kernel Modules.703server # [ 48.081852] systemd[1]: Queued start job for default target Multi-User System.704server # [ 48.326418] systemd[1]: Started Journal Service.705server # [ 48.118013] systemd[1]: systemd-journald.service: Deactivated successfully.706server # [ 48.142278] systemd[1]: systemd-journald.service: Consumed 1.392s CPU time over 40.822s wall clock time, 9.8M memory peak.707server # [ 48.159936] systemd-modules-load[334]: Using 2 probe threads708server # [ 48.167359] systemd-modules-load[334]: Module 'atkbd' is built in709server # [ 48.181264] systemd-modules-load[334]: Module 'loop' is built in710server # [ 48.191893] systemd[1]: Finished Remount Root and Kernel File Systems.711server # [ 48.465003] systemd[1]: Mounted FUSE Control File System.712server # [ 48.669309] systemd[1]: Starting Firewall...713server # [ 48.815446] systemd[1]: Starting Flush Journal to Persistent Storage...714server # [ 48.825513] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore715server # [ 48.864489] systemd-oomd[335]: No swap; memory pressure usage will be degraded716server # [ 48.927514] systemd[1]: Starting Load/Save OS Random Seed...717server # [ 49.107395] systemd[1]: Starting Apply Kernel Variables...718server # [ 49.132937] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki719server # [ 49.134264] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.720server # [ 49.290622] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.721server # [ 49.911256] systemd-journald[333]: Received client request to flush runtime journal.722server # [ 50.507808] systemd[1]: Starting Create Static Device Nodes in /dev...723server # [ 50.527354] systemd[1]: Finished Load/Save OS Random Seed.724server # [ 50.557296] systemd[1]: Reached target First Boot Complete.725server # [ 50.582057] systemd[1]: Finished Flush Journal to Persistent Storage.726server # [ 50.614993] systemd[1]: Finished Apply Kernel Variables.727server # [ 50.895982] systemd[1]: Finished Create Static Device Nodes in /dev.728server # [ 50.932879] systemd[1]: Reached target Preparation for Local File Systems.729server # [ 50.971261] systemd[1]: Mounting /run/wrappers...730server # [ 51.027785] systemd[1]: Starting Rule-based Manager for Device Events and Files...731server # [ 51.477282] systemd[1]: Mounted /run/wrappers.732server # [ 51.541885] systemd[1]: Reached target Local File Systems.733server # [ 51.577321] systemd[1]: Listening on Boot Loader Control Service Socket.734server # [ 51.615975] systemd[1]: Starting register-nix-paths.service...735server # [ 51.742008] systemd[1]: Starting Create SUID/SGID Wrappers...736server # [ 51.751178] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.737server # [ 51.910871] systemd[1]: Starting Save Transient machine-id to Disk...738server # [ 52.343053] systemd[1]: Starting Create System Files and Directories...739server # [ 53.510095] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.740server # [ 53.554874] systemd[1]: Finished Save Transient machine-id to Disk.741server # [ 54.779622] systemd[1]: Finished Create System Files and Directories.742server # [ 54.946123] systemd[1]: Starting Rebuild Journal Catalog...743server # [ 55.090674] systemd[1]: Starting Record System Boot/Shutdown in UTMP...744server # [ 56.158325] systemd[1]: Finished Record System Boot/Shutdown in UTMP.745server # [ 57.160373] systemd[1]: Finished Rebuild Journal Catalog.746server # [ 57.267766] systemd[1]: Starting Update is Completed...747server # [ 58.118190] systemd[1]: Finished Update is Completed.748server: Guest root shell did not produce any data yet...749server: To debug, enter the VM and run 'systemctl status backdoor.service'.750server # [ 61.365336] systemd-udevd[377]: Using default interface naming scheme 'v260'.751server # [ 62.395850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.752server # [ 62.437415] systemd[1]: Finished Create SUID/SGID Wrappers.753server # [ 62.442449] systemd[1]: suid-sgid-wrappers.service: Consumed 2.636s CPU time over 10.654s wall clock time, 5.4M memory peak.754server # [ 63.243999] systemd[1]: Started Rule-based Manager for Device Events and Files.755server # [ 65.078818] systemd[1]: Finished register-nix-paths.service.756server # [ 66.849842] systemd[1]: Finished Firewall.757server # [ 68.747313] systemd[1]: Finished Coldplug All udev Devices.758server # [ 68.758297] systemd[1]: Reached target System Initialization.759server # [ 68.769353] systemd[1]: Started Discard unused filesystem blocks once a week.760server # [ 68.774846] systemd[1]: Started Daily Cleanup of Temporary Directories.761server # [ 68.782107] systemd[1]: Reached target Timer Units.762server # [ 68.789797] systemd[1]: Listening on D-Bus System Message Bus Socket.763server # [ 68.792883] systemd[1]: Listening on Nix Daemon Socket.764server # [ 68.798328] systemd[1]: Listening on Hostname Service Socket.765server # [ 68.800949] systemd[1]: Reached target Socket Units.766server # [ 68.806258] systemd[1]: Reached target Basic System.767server # [ 68.837920] systemd[1]: Starting Import lastlog data into lastlog2 database...768server # [ 68.885711] systemd[1]: Starting Name Service Cache Daemon (nsncd)...769server # [ 68.949329] systemd[1]: Starting Post-Boot Actions...770server # [ 69.049311] systemd[1]: Started Reset console on configuration changes.771server # [ 69.252903] systemd[1]: Starting resolvconf update...772server # [ 69.685021] systemd[1]: Starting D-Bus System Message Bus...773server # [ 69.967623] systemd[1]: Finished Post-Boot Actions.774server # [ 70.435865] systemd[1]: Started Name Service Cache Daemon (nsncd).775server # [ 70.494421] nsncd[590]: Oct 07 00:02:59.898 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"776server # [ 70.538691] systemd[1]: Reached target Host and Network Name Lookups.777server # [ 70.542689] systemd[1]: Reached target User and Group Name Lookups.778server # [ 70.714451] systemd[1]: Starting User Login Management...779server # [ 70.922894] systemd[1]: Finished Import lastlog data into lastlog2 database.780server # [ 71.783385] dbus-broker-launch[595]: Looking up NSS user entry for 'systemd-timesync'...781server # [ 71.948201] dbus-broker-launch[595]: NSS returned no entry for 'systemd-timesync'782server # [ 71.957349] dbus-broker-launch[595]: Invalid user-name in /nix/store/62wal8y6jvsipyjy235plnw6k1qcscn0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"783server # [ 72.238024] systemd[1]: Started D-Bus System Message Bus.784server # [ 72.758055] dbus-broker-launch[595]: Ready785server # [ 72.958632] systemd-logind[611]: New seat seat0.786server # [ 72.981563] systemd[1]: Started User Login Management.787server # [ 73.086247] systemd[1]: Starting linger-users.service...788server # [ 73.446094] systemd[1]: Stopped target Host and Network Name Lookups.789server # [ 73.455507] systemd[1]: Stopping Host and Network Name Lookups...790server # [ 73.479035] systemd[1]: Stopped target User and Group Name Lookups.791server # [ 73.493866] systemd[1]: Stopping User and Group Name Lookups...792server # [ 73.496039] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...793server # [ 73.511344] systemd[1]: nscd.service: Deactivated successfully.794server # [ 73.523340] systemd[1]: Stopped Name Service Cache Daemon (nsncd).795server # [ 73.741650] systemd[1]: linger-users.service: Deactivated successfully.796server # [ 73.764592] systemd[1]: Finished linger-users.service.797server # [ 73.909014] systemd[1]: Starting Name Service Cache Daemon (nsncd)...798server # [ 74.602551] systemd[1]: Started Name Service Cache Daemon (nsncd).799server # [ 74.629268] nsncd[662]: Oct 07 00:03:04.054 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"800server # [ 74.654055] systemd[1]: Reached target Host and Network Name Lookups.801server # [ 74.663116] systemd[1]: Reached target User and Group Name Lookups.802server # [ 75.119707] systemd[1]: Finished resolvconf update.803server # [ 75.156109] systemd[1]: Reached target Preparation for Network.804server # [ 75.216160] systemd[1]: Starting DHCP Client...805server # [ 75.262086] systemd[1]: Starting Extra networking commands....806server # [ 77.799905] dhcpcd[713]: dhcpcd-10.3.1 starting807server # [ 78.001801] dhcpcd[767]: dev: loaded udev808server # [ 78.159420] dhcpcd[767]: no valid interfaces found809server # [ 78.165263] dhcpcd[767]: no valid interfaces found810server # [ 78.173856] dhcpcd[767]: libudev: received NULL device811server # [ 78.188119] dhcpcd[767]: libudev: received NULL device812server # [ 78.484935] systemd[1]: Finished Extra networking commands..813server # [ 78.490900] systemd[1]: Reached target Network.814server # [ 78.512306] systemd[1]: Starting PostgreSQL Database Server...815server # [ 78.590301] systemd[1]: Starting Permit User Sessions...816server # [ 79.086366] systemd[1]: Finished Permit User Sessions.817server # [ 79.205377] systemd[1]: Started Getty on tty1.818server # [ 79.223058] systemd[1]: Reached target Login Prompts.819server # [ 80.019004] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[788]: Initializing database at /var/lib/postgresql/data820server # [ 81.534122] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: The files belonging to this database system will be owned by user "postgres".821server # [ 81.539030] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: This user must also own the server process.822server # [ 81.606734] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: The database cluster will be initialized with locale "en_US.UTF-8".823server # [ 81.613352] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: The default database encoding has accordingly been set to "UTF8".824server # [ 81.615103] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: The default text search configuration will be set to "english".825server # [ 81.622427] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: Data page checksums are enabled.826server # [ 81.625959] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: fixing permissions on existing directory /var/lib/postgresql/data ... ok827server # [ 81.633539] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: creating subdirectories ... ok828server # [ 81.636227] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: selecting dynamic shared memory implementation ... posix829server # [ 82.554063] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: selecting default max_connections ... 100830server # [ 83.479473] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: selecting default shared_buffers ... 128MB831server: Guest root shell did not produce any data yet...832server: To debug, enter the VM and run 'systemctl status backdoor.service'.833server # [ 94.104358] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: selecting default time zone ... UTC834server # [ 94.146215] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: creating configuration files ... ok835server # [ 98.151915] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: running bootstrap script ... ok836server # [ 108.214272] dhcpcd[767]: timed out837server # [ 108.238719] dhcpcd[767]: timed out838server # [ 108.268096] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: performing post-bootstrap initialization ... ok839server # [ 108.286748] systemd[1]: Started DHCP Client.840server # [ 110.962646] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: syncing data to disk ... ok841server # [ 110.965750] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: initdb: warning: enabling "trust" authentication for local connections842server # [ 110.969061] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: 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 # [ 110.972236] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: Success. You can now start the database server using:844server # [ 110.978503] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[803]: /nix/store/wci7v43bmwrfyq70ig1hf0ryax0p916n-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start845server # [ 112.190566] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3846server # [ 112.604937] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[788]: Validating PostgreSQL configuration...847server # [ 113.004034] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[1033]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault848server # [ 113.914056] postgres[1035]: 2026-10-07 00:03:43.372 UTC [1035] LOG: pgaudit extension initialized849server # [ 114.801234] postgres[1035]: 2026-10-07 00:03:44.268 UTC [1035] LOG: pgsodium primary server secret key loaded850server # [ 115.302576] postgres[1035]: 2026-10-07 00:03:44.758 UTC [1035] LOG: vault primary server secret key loaded851server # [ 115.668305] postgres[1035]: 2026-10-07 00:03:45.138 UTC [1035] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.3.0, 64-bit852server # [ 115.685113] postgres[1035]: 2026-10-07 00:03:45.151 UTC [1035] LOG: listening on IPv4 address "0.0.0.0", port 5432853server # [ 115.688640] postgres[1035]: 2026-10-07 00:03:45.152 UTC [1035] LOG: listening on IPv6 address "::", port 5432854server # [ 115.708166] postgres[1035]: 2026-10-07 00:03:45.178 UTC [1035] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"855server # [ 115.801159] postgres[1060]: 2026-10-07 00:03:45.267 UTC [1060] LOG: database system was shut down at 2026-10-07 00:03:37 UTC856server # [ 116.047704] postgres[1064]: 2026-10-07 00:03:45.497 UTC [1064] FATAL: role "postgres" does not exist857server # [ 116.067208] postgres[1035]: 2026-10-07 00:03:45.513 UTC [1035] LOG: database system is ready to accept connections858server # [ 116.108534] postgres[1063]: 2026-10-07 00:03:45.571 UTC [1063] LOG: TimescaleDB background worker launcher connected to shared catalogs859server # [ 116.186127] systemd[1]: Started PostgreSQL Database Server.860server # [ 116.191756] postgres[1035]: 2026-10-07 00:03:45.646 UTC [1035] LOG: background worker "pg_net 0.20.4 worker" (PID 1064) exited with exit code 1861server # [ 116.294554] postgres[1065]: 2026-10-07 00:03:45.754 UTC [1065] LOG: pg_cron scheduler started862server # [ 116.310755] systemd[1]: Starting Supabase Database Initialization...863server # [ 116.782350] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Waiting for PostgreSQL to be ready...864server # [ 117.264054] postgres[1071]: 2026-10-07 00:03:46.713 UTC [1071] FATAL: role "postgres" does not exist865server # [ 117.363236] postgres[1035]: 2026-10-07 00:03:46.829 UTC [1035] LOG: background worker "pg_net 0.20.4 worker" (PID 1071) exited with exit code 1866server # [ 117.444356] postgres[1072]: ::1 2026-10-07 00:03:46.904 UTC [1072] root@root FATAL: role "root" does not exist867server # [ 117.470385] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: PostgreSQL is ready868server # [ 117.475180] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Creating postgres role...869server # [ 117.937185] postgres[1074]: ::1 2026-10-07 00:03:47.393 UTC [1074] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN;870server # [ 117.983429] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1073]: CREATE ROLE871server # [ 118.393956] postgres[1078]: ::1 2026-10-07 00:03:47.827 UTC [1078] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres;872server # [ 118.435429] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1075]: ALTER DATABASE873server: Guest root shell did not produce any data yet...874server: To debug, enter the VM and run 'systemctl status backdoor.service'.875server # [ 118.547532] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000000-initial-schema.sql876server # [ 118.953254] postgres[1091]: ::1 2026-10-07 00:03:48.411 UTC [1091] postgres@postgres LOG: statement: create publication supabase_realtime;877server # [ 119.005455] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE PUBLICATION878server # [ 119.009902] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER ROLE879server # [ 119.017833] postgres[1091]: ::1 2026-10-07 00:03:48.462 UTC [1091] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls;880server # [ 119.028867] postgres[1091]: ::1 2026-10-07 00:03:48.474 UTC [1091] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication;881server # [ 119.032359] postgres[1091]: ::1 2026-10-07 00:03:48.492 UTC [1091] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls;882server # [ 119.035397] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE ROLE883server # [ 119.036939] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE ROLE884server # [ 119.042358] postgres[1091]: ::1 2026-10-07 00:03:48.504 UTC [1091] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin;885server # [ 119.044359] postgres[1091]: ::1 2026-10-07 00:03:48.515 UTC [1091] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin;886server # [ 119.056696] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT ROLE887server # [ 119.069538] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT888server # [ 119.074638] postgres[1091]: ::1 2026-10-07 00:03:48.538 UTC [1091] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls;889server # [ 119.082521] postgres[1091]: ::1 2026-10-07 00:03:48.550 UTC [1091] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user;890server # [ 119.085932] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE ROLE891server # [ 119.090786] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT ROLE892server # [ 119.092603] postgres[1091]: ::1 2026-10-07 00:03:48.559 UTC [1091] postgres@postgres LOG: statement: create schema if not exists extensions;893server # [ 119.102596] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE SCHEMA894server # [ 119.104921] postgres[1091]: ::1 2026-10-07 00:03:48.574 UTC [1091] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions;895server # [ 121.545036] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE EXTENSION896server # [ 121.553226] postgres[1091]: ::1 2026-10-07 00:03:51.014 UTC [1091] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions;897server # [ 122.829520] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE EXTENSION898server # [ 122.857680] postgres[1091]: ::1 2026-10-07 00:03:52.305 UTC [1091] postgres@postgres LOG: statement: create role anon nologin noinherit;899server # [ 122.863174] postgres[1091]: ::1 2026-10-07 00:03:52.317 UTC [1091] postgres@postgres LOG: statement: create role authenticated nologin noinherit;900server # [ 122.874297] postgres[1091]: ::1 2026-10-07 00:03:52.324 UTC [1091] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls;901server # [ 122.885041] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE ROLE902server # [ 122.887225] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE ROLE903server # [ 122.893226] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE ROLE904server # [ 122.898824] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: CREATE ROLE905server # [ 122.901676] postgres[1091]: ::1 2026-10-07 00:03:52.344 UTC [1091] postgres@postgres LOG: statement: create user authenticator noinherit;906server # [ 122.907848] postgres[1091]: ::1 2026-10-07 00:03:52.356 UTC [1091] postgres@postgres LOG: statement: grant anon to authenticator;907server # [ 122.911785] postgres[1091]: ::1 2026-10-07 00:03:52.370 UTC [1091] postgres@postgres LOG: statement: grant authenticated to authenticator;908server # [ 122.921896] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT ROLE909server # [ 122.923557] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT ROLE910server # [ 122.926650] postgres[1091]: ::1 2026-10-07 00:03:52.385 UTC [1091] postgres@postgres LOG: statement: grant service_role to authenticator;911server # [ 122.940967] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT ROLE912server # [ 122.963270] postgres[1091]: ::1 2026-10-07 00:03:52.420 UTC [1091] postgres@postgres LOG: statement: grant supabase_admin to authenticator;913server # [ 122.981074] postgres[1091]: ::1 2026-10-07 00:03:52.452 UTC [1091] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role;914server # [ 122.986230] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT ROLE915server # [ 122.993433] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT916server # [ 122.997468] postgres[1091]: ::1 2026-10-07 00:03:52.466 UTC [1091] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;917server # [ 123.016767] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER DEFAULT PRIVILEGES918server # [ 123.021374] postgres[1091]: ::1 2026-10-07 00:03:52.491 UTC [1091] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;919server # [ 123.036963] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER DEFAULT PRIVILEGES920server # [ 123.040072] postgres[1091]: ::1 2026-10-07 00:03:52.509 UTC [1091] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;921server # [ 123.051273] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER DEFAULT PRIVILEGES922server # [ 123.058747] postgres[1091]: ::1 2026-10-07 00:03:52.523 UTC [1091] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role;923server # [ 123.073806] postgres[1091]: ::1 2026-10-07 00:03:52.541 UTC [1091] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions;924server # [ 123.075255] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: GRANT925server # [ 123.096312] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER ROLE926server # [ 123.102398] postgres[1091]: ::1 2026-10-07 00:03:52.566 UTC [1091] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all927server # [ 123.108134] postgres[1091]: on sequences to postgres, anon, authenticated, service_role;928server # [ 123.117639] postgres[1091]: ::1 2026-10-07 00:03:52.580 UTC [1091] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all929server # [ 123.119481] postgres[1091]: on tables to postgres, anon, authenticated, service_role;930server # [ 123.121916] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER DEFAULT PRIVILEGES931server # [ 123.125919] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER DEFAULT PRIVILEGES932server # [ 123.128352] postgres[1091]: ::1 2026-10-07 00:03:52.595 UTC [1091] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all933server # [ 123.135523] postgres[1091]: on functions to postgres, anon, authenticated, service_role;934server # [ 123.139653] postgres[1091]: ::1 2026-10-07 00:03:52.606 UTC [1091] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s';935server # [ 123.143767] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER DEFAULT PRIVILEGES936server # [ 123.151143] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER ROLE937server # [ 123.161629] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1085]: ALTER ROLE938server # [ 123.164600] postgres[1091]: ::1 2026-10-07 00:03:52.625 UTC [1091] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s';939server # [ 123.215819] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000001-auth-schema.sql940server # [ 123.482673] postgres[1121]: ::1 2026-10-07 00:03:52.949 UTC [1121] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;941server # [ 123.512602] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE SCHEMA942server # [ 123.519382] postgres[1121]: ::1 2026-10-07 00:03:52.979 UTC [1121] postgres@postgres LOG: statement: CREATE TABLE auth.users (943server # [ 123.532031] postgres[1121]: instance_id uuid NULL,944server # [ 123.534399] postgres[1121]: id uuid NOT NULL UNIQUE,945server # [ 123.535829] postgres[1121]: aud varchar(255) NULL,946server # [ 123.538675] postgres[1121]: "role" varchar(255) NULL,947server # [ 123.540575] postgres[1121]: email varchar(255) NULL UNIQUE,948server # [ 123.546093] postgres[1121]: encrypted_password varchar(255) NULL,949server # [ 123.553733] postgres[1121]: confirmed_at timestamptz NULL,950server # [ 123.554723] postgres[1121]: invited_at timestamptz NULL,951server # [ 123.556873] postgres[1121]: confirmation_token varchar(255) NULL,952server # [ 123.558136] postgres[1121]: confirmation_sent_at timestamptz NULL,953server # [ 123.560812] postgres[1121]: recovery_token varchar(255) NULL,954server # [ 123.562497] postgres[1121]: recovery_sent_at timestamptz NULL,955server # [ 123.564187] postgres[1121]: email_change_token varchar(255) NULL,956server # [ 123.567539] postgres[1121]: email_change varchar(255) NULL,957server # [ 123.570374] postgres[1121]: email_change_sent_at timestamptz NULL,958server # [ 123.574301] postgres[1121]: last_sign_in_at timestamptz NULL,959server # [ 123.579310] postgres[1121]: raw_app_meta_data jsonb NULL,960server # [ 123.582002] postgres[1121]: raw_user_meta_data jsonb NULL,961server # [ 123.584318] postgres[1121]: is_super_admin bool NULL,962server # [ 123.586538] postgres[1121]: created_at timestamptz NULL,963server # [ 123.587790] postgres[1121]: updated_at timestamptz NULL,964server # [ 123.595499] postgres[1121]: CONSTRAINT users_pkey PRIMARY KEY (id)965server # [ 123.599778] postgres[1121]: );966server # [ 123.683529] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE TABLE967server # [ 123.686104] postgres[1121]: ::1 2026-10-07 00:03:53.156 UTC [1121] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);968server # [ 123.717305] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE INDEX969server # [ 123.722460] postgres[1121]: ::1 2026-10-07 00:03:53.193 UTC [1121] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);970server # [ 123.752462] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE INDEX971server # [ 123.758883] postgres[1121]: ::1 2026-10-07 00:03:53.224 UTC [1121] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';972server # [ 123.767491] postgres[1121]: ::1 2026-10-07 00:03:53.235 UTC [1121] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens (973server # [ 123.769835] postgres[1121]: instance_id uuid NULL,974server # [ 123.770846] postgres[1121]: id bigserial NOT NULL,975server # [ 123.772730] postgres[1121]: "token" varchar(255) NULL,976server # [ 123.773971] postgres[1121]: user_id varchar(255) NULL,977server # [ 123.776071] postgres[1121]: revoked bool NULL,978server # [ 123.779237] postgres[1121]: created_at timestamptz NULL,979server # [ 123.780512] postgres[1121]: updated_at timestamptz NULL,980server # [ 123.782122] postgres[1121]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)981server # [ 123.788887] postgres[1121]: );982server # [ 123.791484] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: COMMENT983server # [ 123.873869] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE TABLE984server # [ 123.877174] postgres[1121]: ::1 2026-10-07 00:03:53.346 UTC [1121] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);985server # [ 123.914887] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE INDEX986server # [ 123.920214] postgres[1121]: ::1 2026-10-07 00:03:53.387 UTC [1121] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);987server # [ 123.946357] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE INDEX988server # [ 123.948884] postgres[1121]: ::1 2026-10-07 00:03:53.419 UTC [1121] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);989server # [ 123.975712] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE INDEX990server # [ 123.979716] postgres[1121]: ::1 2026-10-07 00:03:53.450 UTC [1121] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';991server # [ 123.991311] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: COMMENT992server # [ 123.994326] postgres[1121]: ::1 2026-10-07 00:03:53.463 UTC [1121] postgres@postgres LOG: statement: CREATE TABLE auth.instances (993server # [ 123.995916] postgres[1121]: id uuid NOT NULL,994server # [ 123.997848] postgres[1121]: uuid uuid NULL,995server # [ 123.998775] postgres[1121]: raw_base_config text NULL,996server # [ 123.999956] postgres[1121]: created_at timestamptz NULL,997server # [ 124.001564] postgres[1121]: updated_at timestamptz NULL,998server # [ 124.006651] postgres[1121]: CONSTRAINT instances_pkey PRIMARY KEY (id)999server # [ 124.008009] postgres[1121]: );1000server # [ 124.062080] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE TABLE1001server # [ 124.067503] postgres[1121]: ::1 2026-10-07 00:03:53.531 UTC [1121] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';1002server # [ 124.075616] postgres[1121]: ::1 2026-10-07 00:03:53.542 UTC [1121] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries (1003server # [ 124.079882] postgres[1121]: instance_id uuid NULL,1004server # [ 124.084523] postgres[1121]: id uuid NOT NULL,1005server # [ 124.086391] postgres[1121]: payload json NULL,1006server # [ 124.090764] postgres[1121]: created_at timestamptz NULL,1007server # [ 124.102138] postgres[1121]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)1008server # [ 124.107517] postgres[1121]: );1009server # [ 124.114777] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: COMMENT1010server # [ 124.142453] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE TABLE1011server # [ 124.144046] postgres[1121]: ::1 2026-10-07 00:03:53.614 UTC [1121] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);1012server # [ 124.173618] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE INDEX1013server # [ 124.178308] postgres[1121]: ::1 2026-10-07 00:03:53.644 UTC [1121] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';1014server # [ 124.186462] postgres[1121]: ::1 2026-10-07 00:03:53.655 UTC [1121] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations (1015server # [ 124.187920] postgres[1121]: "version" varchar(255) NOT NULL,1016server # [ 124.193554] postgres[1121]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")1017server # [ 124.200564] postgres[1121]: );1018server # [ 124.202517] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: COMMENT1019server # [ 124.223036] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE TABLE1020server # [ 124.238411] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: COMMENT1021server # [ 124.240080] postgres[1121]: ::1 2026-10-07 00:03:53.694 UTC [1121] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';1022server # [ 124.258661] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: INSERT 0 71023server # [ 124.261379] postgres[1121]: ::1 2026-10-07 00:03:53.731 UTC [1121] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$1024server # [ 124.264180] postgres[1121]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;1025server # [ 124.266270] postgres[1121]: $$ language sql stable;1026server # [ 124.281954] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE FUNCTION1027server # [ 124.288618] postgres[1121]: ::1 2026-10-07 00:03:53.755 UTC [1121] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$1028server # [ 124.294362] postgres[1121]: select nullif(current_setting('request.jwt.claim.role', true), '')::text;1029server # [ 124.298579] postgres[1121]: $$ language sql stable;1030server # [ 124.307145] postgres[1121]: ::1 2026-10-07 00:03:53.772 UTC [1121] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$1031server # [ 124.310184] postgres[1121]: select nullif(current_setting('request.jwt.claim.email', true), '')::text;1032server # [ 124.313036] postgres[1121]: $$ language sql stable;1033server # [ 124.315622] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE FUNCTION1034server # [ 124.318151] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE FUNCTION1035server # [ 124.321159] postgres[1121]: ::1 2026-10-07 00:03:53.786 UTC [1121] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;1036server # [ 124.327649] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: GRANT1037server # [ 124.330404] postgres[1121]: ::1 2026-10-07 00:03:53.801 UTC [1121] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1038server # [ 124.338866] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: CREATE ROLE1039server # [ 124.347105] postgres[1121]: ::1 2026-10-07 00:03:53.813 UTC [1121] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1040server # [ 124.354467] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: GRANT1041server # [ 124.361781] postgres[1121]: ::1 2026-10-07 00:03:53.829 UTC [1121] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1042server # [ 124.373621] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: GRANT1043server # [ 124.381410] postgres[1121]: ::1 2026-10-07 00:03:53.850 UTC [1121] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1044server # [ 124.392426] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: GRANT1045server # [ 124.396399] postgres[1121]: ::1 2026-10-07 00:03:53.867 UTC [1121] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth";1046server # [ 124.405858] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: ALTER ROLE1047server # [ 124.410460] postgres[1121]: ::1 2026-10-07 00:03:53.880 UTC [1121] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin;1048server # [ 124.423093] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: ALTER TABLE1049server # [ 124.427334] postgres[1121]: ::1 2026-10-07 00:03:53.898 UTC [1121] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;1050server # [ 124.452370] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: ALTER TABLE1051server # [ 124.454273] postgres[1121]: ::1 2026-10-07 00:03:53.924 UTC [1121] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;1052server # [ 124.479211] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: ALTER TABLE1053server # [ 124.492151] postgres[1121]: ::1 2026-10-07 00:03:53.953 UTC [1121] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;1054server # [ 124.498547] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: ALTER TABLE1055server # [ 124.500971] postgres[1121]: ::1 2026-10-07 00:03:53.970 UTC [1121] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;1056server # [ 124.539391] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1120]: ALTER TABLE1057server # [ 124.601186] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000002-storage-schema.sql1058server # [ 125.007480] postgres[1132]: ::1 2026-10-07 00:03:54.475 UTC [1132] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;1059server # [ 125.033862] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1125]: CREATE SCHEMA1060server # [ 125.043462] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1125]: CREATE ROLE1061server # [ 125.051115] postgres[1132]: ::1 2026-10-07 00:03:54.495 UTC [1132] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1062server # [ 125.053972] postgres[1132]: ::1 2026-10-07 00:03:54.512 UTC [1132] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage";1063server # [ 125.058789] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1125]: ALTER ROLE1064server # [ 125.061762] postgres[1132]: ::1 2026-10-07 00:03:54.523 UTC [1132] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;1065server # [ 125.072174] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1125]: GRANT1066server # [ 125.153118] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1125]: DO1067server # [ 125.205190] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000003-post-setup.sql1068server # [ 125.690543] postgres[1144]: ::1 2026-10-07 00:03:55.141 UTC [1144] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;1069server # [ 125.715354] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: ALTER ROLE1070server # [ 125.723624] postgres[1144]: ::1 2026-10-07 00:03:55.178 UTC [1144] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;1071server # [ 125.730351] postgres[1144]: ::1 2026-10-07 00:03:55.189 UTC [1144] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()1072server # [ 125.739339] postgres[1144]: RETURNS event_trigger1073server # [ 125.740516] postgres[1144]: LANGUAGE plpgsql1074server # [ 125.741669] postgres[1144]: AS $$1075server # [ 125.749894] postgres[1144]: DECLARE1076server # [ 125.755433] postgres[1144]: schema_is_cron bool;1077server # [ 125.758839] postgres[1144]: BEGIN1078server # [ 125.766278] postgres[1144]: schema_is_cron = (1079server # [ 125.770258] postgres[1144]: SELECT n.nspname = 'cron'1080server # [ 125.776174] postgres[1144]: FROM pg_event_trigger_ddl_commands() AS ev1081server # [ 125.781220] postgres[1144]: LEFT JOIN pg_catalog.pg_namespace AS n1082server # [ 125.782704] postgres[1144]: ON ev.objid = n.oid1083server # [ 125.786106] postgres[1144]: );1084server # [ 125.794695] postgres[1144]:1085server # [ 125.796076] postgres[1144]: IF schema_is_cron1086server # [ 125.797497] postgres[1144]: THEN1087server # [ 125.802773] postgres[1144]: grant usage on schema cron to postgres with grant option;1088server # [ 125.809667] postgres[1144]:1089server # [ 125.813718] postgres[1144]: alter default privileges in schema cron grant all on tables to postgres with grant option;1090server # [ 125.817959] postgres[1144]: alter default privileges in schema cron grant all on functions to postgres with grant option;1091server # [ 125.822878] postgres[1144]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1092server # [ 125.834300] postgres[1144]:1093server # [ 125.835658] postgres[1144]: alter default privileges for user supabase_admin in schema cron grant all1094server # [ 125.839338] postgres[1144]: on sequences to postgres with grant option;1095server # [ 125.845420] postgres[1144]: alter default privileges for user supabase_admin in schema cron grant all1096server # [ 125.846447] postgres[1144]: on tables to postgres with grant option;1097server # [ 125.847447] postgres[1144]: alter default privileges for user supabase_admin in schema cron grant all1098server # [ 125.855845] postgres[1144]: on functions to postgres with grant option;1099server # [ 125.863902] postgres[1144]:1100server # [ 125.866596] postgres[1144]: grant all privileges on all tables in schema cron to postgres with grant option;1101server # [ 125.877765] postgres[1144]:1102server # [ 125.881554] postgres[1144]: END IF;1103server # [ 125.891665] postgres[1144]:1104server # [ 125.893916] postgres[1144]: END;1105server # [ 125.895259] postgres[1144]: $$;1106server # [ 125.897247] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: ALTER ROLE1107server # [ 125.901090] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: CREATE FUNCTION1108server # [ 125.906074] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: CREATE EVENT TRIGGER1109server # [ 125.907899] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: COMMENT1110server # [ 125.913709] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: CREATE FUNCTION1111server # [ 125.915381] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: COMMENT1112server # [ 125.916314] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: DO1113server # [ 125.921988] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: CREATE ROLE1114server # [ 125.928914] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1115server # [ 125.930370] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1116server # [ 125.931640] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1117server # [ 125.933457] postgres[1144]: ::1 2026-10-07 00:03:55.221 UTC [1144] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')1118server # [ 125.937454] postgres[1144]: EXECUTE PROCEDURE extensions.grant_pg_cron_access();1119server # [ 125.943430] postgres[1144]: ::1 2026-10-07 00:03:55.236 UTC [1144] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';1120server # [ 125.950237] postgres[1144]: ::1 2026-10-07 00:03:55.252 UTC [1144] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()1121server # [ 125.952645] postgres[1144]: RETURNS event_trigger1122server # [ 125.958462] postgres[1144]: LANGUAGE plpgsql1123server # [ 125.959911] postgres[1144]: AS $$1124server # [ 125.965480] postgres[1144]: BEGIN1125server # [ 125.966672] postgres[1144]: IF EXISTS (1126server # [ 125.967878] postgres[1144]: SELECT 11127server # [ 125.971397] postgres[1144]: FROM pg_event_trigger_ddl_commands() AS ev1128server # [ 125.974006] postgres[1144]: JOIN pg_extension AS ext1129server # [ 125.977536] postgres[1144]: ON ev.objid = ext.oid1130server # [ 125.982566] postgres[1144]: WHERE ext.extname = 'pg_net'1131server # [ 125.989351] postgres[1144]: )1132server # [ 125.990786] postgres[1144]: THEN1133server # [ 125.991640] postgres[1144]: IF NOT EXISTS (1134server # [ 125.992122] postgres[1144]: SELECT 11135server # [ 125.994241] postgres[1144]: FROM pg_roles1136server # [ 125.998047] postgres[1144]: WHERE rolname = 'supabase_functions_admin'1137server # [ 125.999956] postgres[1144]: )1138server # [ 126.005898] postgres[1144]: THEN1139server # [ 126.009545] postgres[1144]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1140server # [ 126.014364] postgres[1144]: END IF;1141server # [ 126.017568] postgres[1144]:1142server # [ 126.019582] postgres[1144]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;1143server # [ 126.025770] postgres[1144]:1144server # [ 126.026531] postgres[1144]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1145server # [ 126.029637] postgres[1144]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1146server # [ 126.033391] postgres[1144]:1147server # [ 126.034850] postgres[1144]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1148server # [ 126.038329] postgres[1144]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1149server # [ 126.040084] postgres[1144]:1150server # [ 126.045430] postgres[1144]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1151server # [ 126.055324] postgres[1144]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1152server # [ 126.069782] postgres[1144]:1153server # [ 126.071468] postgres[1144]: 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;1154server # [ 126.073922] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1155server # [ 126.081122] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1156server # [ 126.087855] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1157server # [ 126.102142] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1158server # [ 126.110019] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1159server # [ 126.111318] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: GRANT1160server # [ 126.117499] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1139]: DO1161server # [ 126.124949] postgres[1144]: 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;1162server # [ 126.134584] postgres[1144]: END IF;1163server # [ 126.135957] postgres[1144]: END;1164server # [ 126.142578] postgres[1144]: $$;1165server # [ 126.147513] postgres[1144]: ::1 2026-10-07 00:03:55.266 UTC [1144] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1166server # [ 126.160311] postgres[1144]: ::1 2026-10-07 00:03:55.306 UTC [1144] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1167server # [ 126.165401] postgres[1144]: ::1 2026-10-07 00:03:55.321 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1168server # [ 126.170244] postgres[1144]: ::1 2026-10-07 00:03:55.332 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1169server # [ 126.173549] postgres[1144]: ::1 2026-10-07 00:03:55.349 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1170server # [ 126.179594] postgres[1144]: ::1 2026-10-07 00:03:55.363 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1171server # [ 126.188170] postgres[1144]: ::1 2026-10-07 00:03:55.377 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1172server # [ 126.197930] postgres[1144]: ::1 2026-10-07 00:03:55.387 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1173server # [ 126.205042] postgres[1144]: ::1 2026-10-07 00:03:55.404 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1174server # [ 126.208082] postgres[1144]: ::1 2026-10-07 00:03:55.409 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1175server # [ 126.210837] postgres[1144]: ::1 2026-10-07 00:03:55.436 UTC [1144] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1176server # [ 126.225546] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running pgbouncer auth schema...1177server # [ 126.395997] postgres[1147]: ::1 2026-10-07 00:03:55.862 UTC [1147] postgres@postgres LOG: statement: CREATE USER pgbouncer;1178server # [ 126.421082] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1146]: CREATE ROLE1179server # [ 126.428884] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1146]: REVOKE1180server # [ 126.430523] postgres[1147]: ::1 2026-10-07 00:03:55.885 UTC [1147] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1181server # [ 126.440186] postgres[1147]: ::1 2026-10-07 00:03:55.895 UTC [1147] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1182server # [ 126.498177] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1146]: CREATE SCHEMA1183server # [ 126.499535] postgres[1147]: ::1 2026-10-07 00:03:55.969 UTC [1147] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1184server # [ 126.501429] postgres[1147]: RETURNS TABLE(username TEXT, password TEXT) AS1185server # [ 126.503443] postgres[1147]: $$1186server # [ 126.505798] postgres[1147]: BEGIN1187server # [ 126.512280] postgres[1147]: RAISE WARNING 'PgBouncer auth request: %', p_usename;1188server # [ 126.514452] postgres[1147]:1189server # [ 126.515654] postgres[1147]: RETURN QUERY1190server # [ 126.525070] postgres[1147]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1191server # [ 126.527024] postgres[1147]: WHERE usename = p_usename;1192server # [ 126.529532] postgres[1147]: END;1193server # [ 126.532319] postgres[1147]: $$ LANGUAGE plpgsql1194server # [ 126.534824] postgres[1147]: SET search_path = ''1195server # [ 126.537898] postgres[1147]: SECURITY DEFINER;1196server # [ 126.540413] postgres[1147]: ::1 2026-10-07 00:03:55.985 UTC [1147] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1197server # [ 126.543243] postgres[1147]: ::1 2026-10-07 00:03:55.995 UTC [1147] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1198server # [ 126.546481] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1146]: CREATE FUNCTION1199server # [ 126.549440] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1146]: REVOKE1200server # [ 126.550385] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1146]: GRANT1201server # [ 126.584926] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running stat extension...1202server # [ 126.843660] postgres[1149]: ::1 2026-10-07 00:03:56.311 UTC [1149] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions;1203server # [ 126.861612] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1148]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping1204server # [ 126.879414] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1148]: CREATE SCHEMA1205server # [ 126.885747] postgres[1149]: ::1 2026-10-07 00:03:56.354 UTC [1149] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1206server # [ 128.129397] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1148]: CREATE EXTENSION1207server # [ 128.231371] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/10000000000000_demote-postgres.sql1208server # [ 128.651734] postgres[1172]: ::1 2026-10-07 00:03:58.112 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres;1209server # [ 128.662633] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1210server # [ 128.670516] postgres[1172]: ::1 2026-10-07 00:03:58.132 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres;1211server # [ 128.677573] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1212server # [ 128.678870] postgres[1172]: ::1 2026-10-07 00:03:58.149 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres;1213server # [ 128.685808] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1214server # [ 128.690429] postgres[1172]: ::1 2026-10-07 00:03:58.161 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1215server # [ 128.705816] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1216server # [ 128.713578] postgres[1172]: ::1 2026-10-07 00:03:58.173 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1217server # [ 128.716209] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1218server # [ 128.718393] postgres[1172]: ::1 2026-10-07 00:03:58.188 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1219server # [ 128.726276] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1220server # [ 128.731505] postgres[1172]: ::1 2026-10-07 00:03:58.202 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1221server # [ 128.734807] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1222server # [ 128.738248] postgres[1172]: ::1 2026-10-07 00:03:58.206 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1223server # [ 128.762316] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1224server # [ 128.764690] postgres[1172]: ::1 2026-10-07 00:03:58.231 UTC [1172] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1225server # [ 128.848776] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: GRANT1226server # [ 128.893085] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: DO1227server # [ 128.897315] postgres[1172]: ::1 2026-10-07 00:03:58.365 UTC [1172] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1228server # [ 128.906803] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1166]: ALTER ROLE1229server # [ 128.953704] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211115181400_update-auth-permissions.sql1230server # [ 129.273597] postgres[1176]: ::1 2026-10-07 00:03:58.742 UTC [1176] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1231server # [ 129.314432] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: GRANT1232server # [ 129.320566] postgres[1176]: ::1 2026-10-07 00:03:58.778 UTC [1176] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1233server # [ 129.333934] postgres[1176]: ::1 2026-10-07 00:03:58.799 UTC [1176] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1234server # [ 129.341826] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: GRANT1235server # [ 129.358718] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: GRANT1236server # [ 129.362109] postgres[1176]: ::1 2026-10-07 00:03:58.826 UTC [1176] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1237server # [ 129.381972] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER TABLE1238server # [ 129.383651] postgres[1176]: ::1 2026-10-07 00:03:58.847 UTC [1176] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1239server # [ 129.403783] systemd[1]: Starting Virtual Console Setup...1240server # [ 129.426030] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER TABLE1241server # [ 129.431377] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER TABLE1242server # [ 129.435418] postgres[1176]: ::1 2026-10-07 00:03:58.870 UTC [1176] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1243server # [ 129.443083] postgres[1176]: ::1 2026-10-07 00:03:58.894 UTC [1176] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1244server # [ 129.449809] postgres[1176]: ::1 2026-10-07 00:03:58.911 UTC [1176] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1245server # [ 129.461251] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER TABLE1246server # [ 129.462180] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER TABLE1247server # [ 129.462704] postgres[1176]: ::1 2026-10-07 00:03:58.927 UTC [1176] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres;1248server # [ 129.463922] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: GRANT1249server # [ 129.474304] postgres[1176]: ::1 2026-10-07 00:03:58.935 UTC [1176] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1250server # [ 129.481680] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: GRANT1251server # [ 129.486425] postgres[1176]: ::1 2026-10-07 00:03:58.946 UTC [1176] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1252server # [ 129.493889] postgres[1176]: ::1 2026-10-07 00:03:58.958 UTC [1176] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1253server # [ 129.495504] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: GRANT1254server # [ 129.513069] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: GRANT1255server # [ 129.518126] postgres[1176]: ::1 2026-10-07 00:03:58.982 UTC [1176] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1256server # [ 129.547276] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER DEFAULT PRIVILEGES1257server # [ 129.551489] postgres[1176]: ::1 2026-10-07 00:03:59.019 UTC [1176] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1258server # [ 129.574016] postgres[1176]: ::1 2026-10-07 00:03:59.035 UTC [1176] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1259server # [ 129.577430] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER DEFAULT PRIVILEGES1260server # [ 129.594282] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1175]: ALTER DEFAULT PRIVILEGES1261server # [ 129.655810] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211118015519_create-realtime-schema.sql1262server # [ 130.107577] postgres[1179]: ::1 2026-10-07 00:03:59.564 UTC [1179] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime;1263server # [ 130.162559] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1178]: CREATE SCHEMA1264server # [ 130.209999] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1265server # [ 130.623591] postgres[1182]: ::1 2026-10-07 00:04:00.083 UTC [1182] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres;1266server # [ 130.673416] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1180]: GRANT1267server # [ 130.679639] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1180]: GRANT1268server # [ 130.690375] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1180]: GRANT1269server # [ 130.699787] postgres[1182]: ::1 2026-10-07 00:04:00.125 UTC [1182] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1270server # [ 130.712816] postgres[1182]: ::1 2026-10-07 00:04:00.141 UTC [1182] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1271server # [ 130.717039] postgres[1182]: ::1 2026-10-07 00:04:00.152 UTC [1182] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1272server # [ 130.727560] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1180]: GRANT1273server # [ 130.795885] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211124212715_update-auth-owner.sql1274server # [ 131.435195] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1183]: DO1275server # [ 131.454261] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1183]: DO1276server # [ 131.485519] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1183]: DO1277server # [ 131.547768] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1278server # [ 131.945286] postgres[1186]: ::1 2026-10-07 00:04:01.395 UTC [1186] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user;1279server # [ 131.964409] postgres[1186]: ::1 2026-10-07 00:04:01.423 UTC [1186] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1280server # [ 132.029301] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1185]: ALTER DEFAULT PRIVILEGES1281server # [ 132.043523] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1185]: ALTER DEFAULT PRIVILEGES1282server # [ 132.061104] postgres[1186]: ::1 2026-10-07 00:04:01.445 UTC [1186] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user;1283server # [ 132.076879] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1185]: ALTER DEFAULT PRIVILEGES1284server # [ 132.115557] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1285server # [ 132.462008] postgres[1188]: ::1 2026-10-07 00:04:01.912 UTC [1188] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1286server # [ 132.497553] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1187]: ALTER ROLE1287server # [ 132.533350] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1288server # [ 133.062538] postgres[1190]: ::1 2026-10-07 00:04:02.506 UTC [1190] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1289server # [ 133.066878] postgres[1190]: ::1 2026-10-07 00:04:02.531 UTC [1190] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1290server # [ 133.097097] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1291server # [ 133.102788] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: DROP EVENT TRIGGER1292server # [ 133.109409] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1293server # [ 133.114669] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: DROP FUNCTION1294server # [ 133.122488] postgres[1190]: ::1 2026-10-07 00:04:02.559 UTC [1190] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1295server # [ 133.130983] postgres[1190]: DECLARE1296server # [ 133.134389] postgres[1190]: cmd record;1297server # [ 133.147121] postgres[1190]: BEGIN1298server # [ 133.158552] postgres[1190]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1299server # [ 133.173742] postgres[1190]: LOOP1300server # [ 133.177503] postgres[1190]: IF cmd.command_tag IN (1301server # [ 133.182672] postgres[1190]: 'CREATE SCHEMA', 'ALTER SCHEMA'1302server # [ 133.190272] postgres[1190]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1303server # [ 133.195292] postgres[1190]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1304server # [ 133.205211] postgres[1190]: , 'CREATE VIEW', 'ALTER VIEW'1305server # [ 133.214068] postgres[1190]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1306server # [ 133.225592] postgres[1190]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1307server # [ 133.227959] postgres[1190]: , 'CREATE TRIGGER'1308server # [ 133.230885] postgres[1190]: , 'CREATE TYPE'1309server # [ 133.233171] postgres[1190]: , 'CREATE RULE'1310server # [ 133.235226] postgres[1190]: , 'COMMENT'1311server # [ 133.241942] postgres[1190]: )1312server # [ 133.246229] postgres[1190]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1313server # [ 133.250383] postgres[1190]: AND cmd.schema_name is distinct from 'pg_temp'1314server # [ 133.252357] postgres[1190]: THEN1315server # [ 133.254714] postgres[1190]: NOTIFY pgrst, 'reload schema';1316server # [ 133.256602] postgres[1190]: END IF;1317server # [ 133.259050] postgres[1190]: END LOOP;1318server # [ 133.262592] postgres[1190]: END; $$ LANGUAGE plpgsql;1319server # [ 133.264927] postgres[1190]: ::1 2026-10-07 00:04:02.621 UTC [1190] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1320server # [ 133.269198] postgres[1190]: DECLARE1321server # [ 133.272896] postgres[1190]: obj record;1322server # [ 133.275826] postgres[1190]: BEGIN1323server # [ 133.277292] postgres[1190]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1324server # [ 133.279552] postgres[1190]: LOOP1325server # [ 133.282557] postgres[1190]: IF obj.object_type IN (1326server # [ 133.286833] postgres[1190]: 'schema'1327server # [ 133.289814] postgres[1190]: , 'table'1328server # [ 133.293032] postgres[1190]: , 'foreign table'1329server # [ 133.294215] postgres[1190]: , 'view'1330server # [ 133.296080] postgres[1190]: , 'materialized view'1331server # [ 133.298255] postgres[1190]: , 'function'1332server # [ 133.300654] postgres[1190]: , 'trigger'1333server # [ 133.305519] postgres[1190]: , 'type'1334server # [ 133.306698] postgres[1190]: , 'rule'1335server # [ 133.309020] postgres[1190]: )1336server # [ 133.311303] postgres[1190]: AND obj.is_temporary IS false -- no pg_temp objects1337server # [ 133.313557] postgres[1190]: THEN1338server # [ 133.317735] postgres[1190]: NOTIFY pgrst, 'reload schema';1339server # [ 133.320448] postgres[1190]: END IF;1340server # [ 133.329052] postgres[1190]: END LOOP;1341server # [ 133.331535] postgres[1190]: END; $$ LANGUAGE plpgsql;1342server # [ 133.335639] postgres[1190]: ::1 2026-10-07 00:04:02.641 UTC [1190] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1343server # [ 133.350567] postgres[1190]: ::1 2026-10-07 00:04:02.657 UTC [1190] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1344server # [ 133.358013] postgres[1190]: ON ddl_command_end1345server # [ 133.365953] postgres[1190]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1346server # [ 133.370762] postgres[1190]: ::1 2026-10-07 00:04:02.679 UTC [1190] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1347server # [ 133.379596] postgres[1190]: ::1 2026-10-07 00:04:02.693 UTC [1190] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1348server # [ 133.388324] postgres[1190]: ON sql_drop1349server # [ 133.395261] postgres[1190]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1350server # [ 133.417422] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: CREATE FUNCTION1351server # [ 133.419960] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: CREATE FUNCTION1352server # [ 133.432864] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping1353server # [ 133.444105] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: DROP EVENT TRIGGER1354server # [ 133.451555] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: CREATE EVENT TRIGGER1355server # [ 133.462010] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping1356server # [ 133.474381] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: DROP EVENT TRIGGER1357server # [ 133.478601] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1189]: CREATE EVENT TRIGGER1358server # [ 133.507326] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1359server # [ 134.633316] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1191]: DO1360server # [ 134.706345] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql1361server # [ 135.056333] systemd-vconsole-setup[1177]: Configuration of first virtual console was skipped, ignoring remaining ones.1362server # [ 135.131994] systemd[1]: Finished Virtual Console Setup.1363server # [ 135.263929] postgres[1195]: ::1 2026-10-07 00:04:04.729 UTC [1195] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public;1364server # [ 135.332018] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: CREATE SCHEMA1365server # [ 135.342760] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping1366server # [ 135.351793] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: DROP FUNCTION1367server # [ 135.359107] postgres[1195]: ::1 2026-10-07 00:04:04.796 UTC [1195] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb);1368server # [ 135.361593] postgres[1195]: ::1 2026-10-07 00:04:04.807 UTC [1195] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1369server # [ 135.363355] postgres[1195]: "operationName" text default null,1370server # [ 135.365547] postgres[1195]: query text default null,1371server # [ 135.367863] postgres[1195]: variables jsonb default null,1372server # [ 135.369413] postgres[1195]: extensions jsonb default null1373server # [ 135.371460] postgres[1195]: )1374server # [ 135.372332] postgres[1195]: returns jsonb1375server # [ 135.375317] postgres[1195]: language plpgsql1376server # [ 135.377399] postgres[1195]: as $$1377server # [ 135.378490] postgres[1195]: DECLARE1378server # [ 135.379765] postgres[1195]: server_version float;1379server # [ 135.381187] postgres[1195]: BEGIN1380server # [ 135.383415] postgres[1195]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1381server # [ 135.386393] postgres[1195]:1382server # [ 135.388169] postgres[1195]: IF server_version >= 14 THEN1383server # [ 135.390893] postgres[1195]: RETURN jsonb_build_object(1384server # [ 135.393560] postgres[1195]: 'data', null::jsonb,1385server # [ 135.397079] postgres[1195]: 'errors', array['pg_graphql extension is not enabled.']1386server # [ 135.398094] postgres[1195]: );1387server # [ 135.398908] postgres[1195]: ELSE1388server # [ 135.400641] postgres[1195]: RETURN jsonb_build_object(1389server # [ 135.401918] postgres[1195]: 'data', null::jsonb,1390server # [ 135.403045] postgres[1195]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1391server # [ 135.405003] postgres[1195]: );1392server # [ 135.405867] postgres[1195]: END IF;1393server # [ 135.406653] postgres[1195]: END;1394server # [ 135.411893] postgres[1195]: $$;1395server # [ 135.414149] postgres[1195]: ::1 2026-10-07 00:04:04.843 UTC [1195] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1396server # [ 135.425196] postgres[1195]: ::1 2026-10-07 00:04:04.873 UTC [1195] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1397server # [ 135.429947] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: CREATE FUNCTION1398server # [ 135.438388] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: GRANT1399server # [ 135.447970] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: ALTER DEFAULT PRIVILEGES1400server # [ 135.450178] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: ALTER DEFAULT PRIVILEGES1401server # [ 135.457461] postgres[1195]: ::1 2026-10-07 00:04:04.902 UTC [1195] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1402server # [ 135.461929] postgres[1195]: ::1 2026-10-07 00:04:04.912 UTC [1195] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1403server # [ 135.466896] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: ALTER DEFAULT PRIVILEGES1404server # [ 135.473236] postgres[1195]: ::1 2026-10-07 00:04:04.939 UTC [1195] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1405server # [ 135.485257] postgres[1195]: on sequences to postgres, anon, authenticated, service_role;1406server # [ 135.489554] postgres[1195]: ::1 2026-10-07 00:04:04.957 UTC [1195] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1407server # [ 135.497923] postgres[1195]: on tables to postgres, anon, authenticated, service_role;1408server # [ 135.500105] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: ALTER DEFAULT PRIVILEGES1409server # [ 135.502116] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: ALTER DEFAULT PRIVILEGES1410server # [ 135.506169] postgres[1195]: ::1 2026-10-07 00:04:04.972 UTC [1195] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1411server # [ 135.517956] postgres[1195]: on functions to postgres, anon, authenticated, service_role;1412server # [ 135.519511] postgres[1195]: ::1 2026-10-07 00:04:04.989 UTC [1195] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1413server # [ 135.522713] postgres[1195]: RETURNS event_trigger1414server # [ 135.530457] postgres[1195]: LANGUAGE plpgsql1415server # [ 135.538207] postgres[1195]: AS $func$1416server # [ 135.539966] postgres[1195]: DECLARE1417server # [ 135.547200] postgres[1195]: func_is_graphql_resolve bool;1418server # [ 135.550873] postgres[1195]: BEGIN1419server # [ 135.559968] postgres[1195]: func_is_graphql_resolve = (1420server # [ 135.562385] postgres[1195]: SELECT n.proname = 'resolve'1421server # [ 135.566497] postgres[1195]: FROM pg_event_trigger_ddl_commands() AS ev1422server # [ 135.573442] postgres[1195]: LEFT JOIN pg_catalog.pg_proc AS n1423server # [ 135.581377] postgres[1195]: ON ev.objid = n.oid1424server # [ 135.583659] postgres[1195]: );1425server # [ 135.590541] postgres[1195]:1426server # [ 135.591960] postgres[1195]: IF func_is_graphql_resolve1427server # [ 135.593023] postgres[1195]: THEN1428server # [ 135.595217] postgres[1195]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1429server # [ 135.602505] postgres[1195]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1430server # [ 135.605676] postgres[1195]:1431server # [ 135.609040] postgres[1195]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1432server # [ 135.617584] postgres[1195]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1433server # [ 135.620142] postgres[1195]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1434server # [ 135.622099] postgres[1195]:1435server # [ 135.629571] postgres[1195]: DROP FUNCTION IF EXISTS graphql_public.graphql;1436server # [ 135.631801] postgres[1195]: create or replace function graphql_public.graphql(1437server # [ 135.633512] postgres[1195]: "operationName" text default null,1438server # [ 135.634398] postgres[1195]: query text default null,1439server # [ 135.635388] postgres[1195]: variables jsonb default null,1440server # [ 135.639761] postgres[1195]: extensions jsonb default null1441server # [ 135.645954] postgres[1195]: )1442server # [ 135.647528] postgres[1195]: returns jsonb1443server # [ 135.653941] postgres[1195]: language sql1444server # [ 135.661175] postgres[1195]: as $$1445server # [ 135.663036] postgres[1195]: SELECT graphql.resolve(query, coalesce(variables, '{}'));1446server # [ 135.665410] postgres[1195]: $$;1447server # [ 135.669086] postgres[1195]:1448server # [ 135.670785] postgres[1195]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1449server # [ 135.679853] postgres[1195]: END IF;1450server # [ 135.692341] postgres[1195]:1451server # [ 135.694266] postgres[1195]: END;1452server # [ 135.698224] postgres[1195]: $func$;1453server # [ 135.701892] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: ALTER DEFAULT PRIVILEGES1454server # [ 135.715056] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: CREATE FUNCTION1455server # [ 135.730658] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping1456server # [ 135.746412] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: DROP EVENT TRIGGER1457server # [ 135.753372] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: CREATE EVENT TRIGGER1458server # [ 135.778303] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: COMMENT1459server # [ 135.779873] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: CREATE FUNCTION1460server # [ 135.786548] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping1461server # [ 135.796044] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: DROP EVENT TRIGGER1462server # [ 135.809551] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: CREATE EVENT TRIGGER1463server # [ 135.817594] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1193]: COMMENT1464server # [ 135.830269] postgres[1195]: ::1 2026-10-07 00:04:05.009 UTC [1195] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1465server # [ 135.835640] postgres[1195]: ::1 2026-10-07 00:04:05.015 UTC [1195] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1466server # [ 135.845380] postgres[1195]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1467server # [ 135.847117] postgres[1195]: ::1 2026-10-07 00:04:05.029 UTC [1195] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1468server # [ 135.850016] postgres[1195]: ::1 2026-10-07 00:04:05.053 UTC [1195] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1469server # [ 135.854066] postgres[1195]: RETURNS event_trigger1470server # [ 135.865908] postgres[1195]: LANGUAGE plpgsql1471server # [ 135.867988] postgres[1195]: AS $func$1472server # [ 135.870343] postgres[1195]: DECLARE1473server # [ 135.871787] postgres[1195]: graphql_is_dropped bool;1474server # [ 135.873501] postgres[1195]: BEGIN1475server # [ 135.877849] postgres[1195]: graphql_is_dropped = (1476server # [ 135.893208] postgres[1195]: SELECT ev.schema_name = 'graphql_public'1477server # [ 135.898061] postgres[1195]: FROM pg_event_trigger_dropped_objects() AS ev1478server # [ 135.900321] postgres[1195]: WHERE ev.schema_name = 'graphql_public'1479server # [ 135.909344] postgres[1195]: );1480server # [ 135.915034] postgres[1195]:1481server # [ 135.917723] postgres[1195]: IF graphql_is_dropped1482server # [ 135.925074] postgres[1195]: THEN1483server # [ 135.927313] postgres[1195]: create or replace function graphql_public.graphql(1484server # [ 135.931095] postgres[1195]: "operationName" text default null,1485server # [ 135.937883] postgres[1195]: query text default null,1486server # [ 135.939941] postgres[1195]: variables jsonb default null,1487server # [ 135.942669] postgres[1195]: extensions jsonb default null1488server # [ 135.951623] postgres[1195]: )1489server # [ 135.962886] postgres[1195]: returns jsonb1490server # [ 135.967807] postgres[1195]: language plpgsql1491server # [ 135.974778] postgres[1195]: as $$1492server # [ 135.976381] postgres[1195]: DECLARE1493server # [ 135.982324] postgres[1195]: server_version float;1494server # [ 135.994808] postgres[1195]: BEGIN1495server # [ 135.998872] postgres[1195]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1496server # [ 136.005031] postgres[1195]:1497server # [ 136.014503] postgres[1195]: IF server_version >= 14 THEN1498server # [ 136.021750] postgres[1195]: RETURN jsonb_build_object(1499server # [ 136.030855] postgres[1195]: 'data', null::jsonb,1500server # [ 136.033799] postgres[1195]: 'errors', array['pg_graphql extension is not enabled.']1501server # [ 136.038146] postgres[1195]: );1502server # [ 136.039915] postgres[1195]: ELSE1503server # [ 136.043379] postgres[1195]: RETURN jsonb_build_object(1504server # [ 136.049366] postgres[1195]: 'data', null::jsonb,1505server # [ 136.050671] postgres[1195]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1506server # [ 136.059133] postgres[1195]: );1507server # [ 136.062813] postgres[1195]: END IF;1508server # [ 136.067199] postgres[1195]: END;1509server # [ 136.079134] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1510server # [ 136.088448] postgres[1195]: $$;1511server # [ 136.090505] postgres[1195]: END IF;1512server # [ 136.099588] postgres[1195]:1513server # [ 136.101253] postgres[1195]: END;1514server # [ 136.102685] postgres[1195]: $func$;1515server # [ 136.109482] postgres[1195]: ::1 2026-10-07 00:04:05.111 UTC [1195] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1516server # [ 136.118376] postgres[1195]: ::1 2026-10-07 00:04:05.123 UTC [1195] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1517server # [ 136.120802] postgres[1195]: EXECUTE PROCEDURE extensions.set_graphql_placeholder();1518server # [ 136.129352] postgres[1195]: ::1 2026-10-07 00:04:05.138 UTC [1195] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1519server # [ 136.259685] postgres[1205]: ::1 2026-10-07 00:04:05.710 UTC [1205] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1520server # [ 136.270758] postgres[1205]: ::1 2026-10-07 00:04:05.735 UTC [1205] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1521server # [ 136.303090] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1522server # [ 136.306294] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: DROP EVENT TRIGGER1523server # [ 136.322460] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1524server # [ 136.345422] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: DROP FUNCTION1525server # [ 136.357862] postgres[1205]: ::1 2026-10-07 00:04:05.824 UTC [1205] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1526server # [ 136.361652] postgres[1205]: DECLARE1527server # [ 136.366635] postgres[1205]: cmd record;1528server # [ 136.368063] postgres[1205]: BEGIN1529server # [ 136.374007] postgres[1205]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1530server # [ 136.376218] postgres[1205]: LOOP1531server # [ 136.381055] postgres[1205]: IF cmd.command_tag IN (1532server # [ 136.382351] postgres[1205]: 'CREATE SCHEMA', 'ALTER SCHEMA'1533server # [ 136.385905] postgres[1205]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1534server # [ 136.390285] postgres[1205]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1535server # [ 136.391685] postgres[1205]: , 'CREATE VIEW', 'ALTER VIEW'1536server # [ 136.397896] postgres[1205]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1537server # [ 136.406432] postgres[1205]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1538server # [ 136.407346] postgres[1205]: , 'CREATE TRIGGER'1539server # [ 136.407806] postgres[1205]: , 'CREATE TYPE', 'ALTER TYPE'1540server # [ 136.408194] postgres[1205]: , 'CREATE RULE'1541server # [ 136.409514] postgres[1205]: , 'COMMENT'1542server # [ 136.416226] postgres[1205]: )1543server # [ 136.422335] postgres[1205]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1544server # [ 136.431048] postgres[1205]: AND cmd.schema_name is distinct from 'pg_temp'1545server # [ 136.433051] postgres[1205]: THEN1546server # [ 136.434327] postgres[1205]: NOTIFY pgrst, 'reload schema';1547server # [ 136.436038] postgres[1205]: END IF;1548server # [ 136.438442] postgres[1205]: END LOOP;1549server # [ 136.446309] postgres[1205]: END; $$ LANGUAGE plpgsql;1550server # [ 136.455729] postgres[1205]: ::1 2026-10-07 00:04:05.906 UTC [1205] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1551server # [ 136.457349] postgres[1205]: DECLARE1552server # [ 136.458075] postgres[1205]: obj record;1553server # [ 136.458499] postgres[1205]: BEGIN1554server # [ 136.458851] postgres[1205]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1555server # [ 136.463572] postgres[1205]: LOOP1556server # [ 136.470521] postgres[1205]: IF obj.object_type IN (1557server # [ 136.471929] postgres[1205]: 'schema'1558server # [ 136.473982] postgres[1205]: , 'table'1559server # [ 136.475137] postgres[1205]: , 'foreign table'1560server # [ 136.481359] postgres[1205]: , 'view'1561server # [ 136.485824] postgres[1205]: , 'materialized view'1562server # [ 136.490121] postgres[1205]: , 'function'1563server # [ 136.491923] postgres[1205]: , 'trigger'1564server # [ 136.497979] postgres[1205]: , 'type'1565server # [ 136.507258] postgres[1205]: , 'rule'1566server # [ 136.516112] postgres[1205]: )1567server # [ 136.524644] postgres[1205]: AND obj.is_temporary IS false -- no pg_temp objects1568server # [ 136.526861] postgres[1205]: THEN1569server # [ 136.534072] postgres[1205]: NOTIFY pgrst, 'reload schema';1570server # [ 136.535762] postgres[1205]: END IF;1571server # [ 136.537410] postgres[1205]: END LOOP;1572server # [ 136.538039] postgres[1205]: END; $$ LANGUAGE plpgsql;1573server # [ 136.539407] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: CREATE FUNCTION1574server # [ 136.545942] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: CREATE FUNCTION1575server # [ 136.547130] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: DROP EVENT TRIGGER1576server # [ 136.548082] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: CREATE EVENT TRIGGER1577server # [ 136.553761] postgres[1205]: ::1 2026-10-07 00:04:05.941 UTC [1205] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1578server # [ 136.557692] postgres[1205]: ::1 2026-10-07 00:04:05.960 UTC [1205] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1579server # [ 136.561929] postgres[1205]: ON ddl_command_end1580server # [ 136.563813] postgres[1205]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1581server # [ 136.569790] postgres[1205]: ::1 2026-10-07 00:04:05.975 UTC [1205] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1582server # [ 136.578134] postgres[1205]: ::1 2026-10-07 00:04:06.024 UTC [1205] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1583server # [ 136.586266] postgres[1205]: ON sql_drop1584server # [ 136.593076] postgres[1205]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1585server # [ 136.598721] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: DROP EVENT TRIGGER1586server # [ 136.608357] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1202]: CREATE EVENT TRIGGER1587server # [ 136.614765] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1588server # [ 137.094738] postgres[1218]: ::1 2026-10-07 00:04:06.547 UTC [1218] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1589server # [ 137.118775] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1212]: ALTER ROLE1590server # [ 137.161345] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1591server # [ 137.414706] postgres[1222]: ::1 2026-10-07 00:04:06.872 UTC [1222] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1592server # [ 137.417408] postgres[1222]: returns event_trigger1593server # [ 137.422125] postgres[1222]: language plpgsql1594server # [ 137.423734] postgres[1222]: AS $func$1595server # [ 137.429300] postgres[1222]: DECLARE1596server # [ 137.432149] postgres[1222]: func_is_graphql_resolve bool;1597server # [ 137.442454] postgres[1222]: BEGIN1598server # [ 137.444189] postgres[1222]: func_is_graphql_resolve = (1599server # [ 137.445148] postgres[1222]: SELECT n.proname = 'resolve'1600server # [ 137.447455] postgres[1222]: FROM pg_event_trigger_ddl_commands() AS ev1601server # [ 137.453787] postgres[1222]: LEFT JOIN pg_catalog.pg_proc AS n1602server # [ 137.455508] postgres[1222]: ON ev.objid = n.oid1603server # [ 137.461144] postgres[1222]: );1604server # [ 137.462392] postgres[1222]:1605server # [ 137.465668] postgres[1222]: IF func_is_graphql_resolve1606server # [ 137.468541] postgres[1222]: THEN1607server # [ 137.470577] postgres[1222]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1608server # [ 137.473027] postgres[1222]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1609server # [ 137.475326] postgres[1222]:1610server # [ 137.477412] postgres[1222]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1611server # [ 137.478436] postgres[1222]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1612server # [ 137.479801] postgres[1222]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1613server # [ 137.481204] postgres[1222]:1614server # [ 137.482056] postgres[1222]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1615server # [ 137.483510] postgres[1222]: DROP FUNCTION IF EXISTS graphql_public.graphql;1616server # [ 137.485405] postgres[1222]: create or replace function graphql_public.graphql(1617server # [ 137.487475] postgres[1222]: "operationName" text default null,1618server # [ 137.491341] postgres[1222]: query text default null,1619server # [ 137.493222] postgres[1222]: variables jsonb default null,1620server # [ 137.498048] postgres[1222]: extensions jsonb default null1621server # [ 137.501640] postgres[1222]: )1622server # [ 137.503498] postgres[1222]: returns jsonb1623server # [ 137.505511] postgres[1222]: language sql1624server # [ 137.511293] postgres[1222]: as $$1625server # [ 137.517692] postgres[1222]: -- This changed1626server # [ 137.519300] postgres[1222]: select graphql.resolve(1627server # [ 137.526210] postgres[1222]: query := query,1628server # [ 137.528181] postgres[1222]: variables := coalesce(variables, '{}'),1629server # [ 137.533200] postgres[1222]: "operationName" := "operationName",1630server # [ 137.537539] postgres[1222]: extensions := extensions1631server # [ 137.541436] postgres[1222]: );1632server # [ 137.546380] postgres[1222]: $$;1633server # [ 137.549860] postgres[1222]:1634server # [ 137.555131] postgres[1222]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1635server # [ 137.559285] postgres[1222]: END IF;1636server # [ 137.566025] postgres[1222]:1637server # [ 137.570028] postgres[1222]: END;1638server # [ 137.575550] postgres[1222]: $func$;1639server # [ 137.578747] postgres[1222]: ::1 2026-10-07 00:04:06.962 UTC [1222] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1640server # [ 137.582998] postgres[1222]: RETURNS event_trigger1641server # [ 137.586105] postgres[1222]: LANGUAGE plpgsql1642server # [ 137.590049] postgres[1222]: AS $func$1643server # [ 137.598255] postgres[1222]: DECLARE1644server # [ 137.604083] postgres[1222]: graphql_is_dropped bool;1645server # [ 137.606664] postgres[1222]: BEGIN1646server # [ 137.611639] postgres[1222]: graphql_is_dropped = (1647server # [ 137.617510] postgres[1222]: SELECT ev.schema_name = 'graphql_public'1648server # [ 137.622445] postgres[1222]: FROM pg_event_trigger_dropped_objects() AS ev1649server # [ 137.626517] postgres[1222]: WHERE ev.schema_name = 'graphql_public'1650server # [ 137.629783] postgres[1222]: );1651server # [ 137.634399] postgres[1222]:1652server # [ 137.637642] postgres[1222]: IF graphql_is_dropped1653server # [ 137.638693] postgres[1222]: THEN1654server # [ 137.642016] postgres[1222]: create or replace function graphql_public.graphql(1655server # [ 137.643751] postgres[1222]: "operationName" text default null,1656server # [ 137.646107] postgres[1222]: query text default null,1657server # [ 137.648545] postgres[1222]: variables jsonb default null,1658server # [ 137.649730] postgres[1222]: extensions jsonb default null1659server # [ 137.651406] postgres[1222]: )1660server # [ 137.653306] postgres[1222]: returns jsonb1661server # [ 137.654699] postgres[1222]: language plpgsql1662server # [ 137.656125] postgres[1222]: as $$1663server # [ 137.658703] postgres[1222]: DECLARE1664server # [ 137.660132] postgres[1222]: server_version float;1665server # [ 137.662194] postgres[1222]: BEGIN1666server # [ 137.663456] postgres[1222]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1667server # [ 137.666609] postgres[1222]:1668server # [ 137.668826] postgres[1222]: IF server_version >= 14 THEN1669server # [ 137.671377] postgres[1222]: RETURN jsonb_build_object(1670server # [ 137.673584] postgres[1222]: 'errors', jsonb_build_array(1671server # [ 137.675680] postgres[1222]: jsonb_build_object(1672server # [ 137.678101] postgres[1222]: 'message', 'pg_graphql extension is not enabled.'1673server # [ 137.679906] postgres[1222]: )1674server # [ 137.681882] postgres[1222]: )1675server # [ 137.686246] postgres[1222]: );1676server # [ 137.687475] postgres[1222]: ELSE1677server # [ 137.690057] postgres[1222]: RETURN jsonb_build_object(1678server # [ 137.691479] postgres[1222]: 'errors', jsonb_build_array(1679server # [ 137.694063] postgres[1222]: jsonb_build_object(1680server # [ 137.696935] postgres[1222]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1681server # [ 137.698422] postgres[1222]: )1682server # [ 137.699939] postgres[1222]: )1683server # [ 137.701543] postgres[1222]: );1684server # [ 137.704180] postgres[1222]: END IF;1685server # [ 137.707709] postgres[1222]: END;1686server # [ 137.709028] postgres[1222]: $$;1687server # [ 137.711021] postgres[1222]: END IF;1688server # [ 137.714632] postgres[1222]:1689server # [ 137.716980] postgres[1222]: END;1690server # [ 137.718143] postgres[1222]: $func$;1691server # [ 137.719819] postgres[1222]: ::1 2026-10-07 00:04:06.986 UTC [1222] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1692server # [ 137.723609] postgres[1222]: "operationName" text default null,1693server # [ 137.728205] postgres[1222]: query text default null,1694server # [ 137.733251] postgres[1222]: variables jsonb default null,1695server # [ 137.751839] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1221]: CREATE FUNCTION1696server # [ 137.757985] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1221]: CREATE FUNCTION1697server # [ 137.761385] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1221]: CREATE FUNCTION1698server # [ 137.764054] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1221]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping1699server # [ 137.767041] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1221]: DROP EXTENSION1700server # [ 137.770027] postgres[1222]: extensions jsonb default null1701server # [ 137.772151] postgres[1222]: )1702server # [ 137.775109] postgres[1222]: returns jsonb1703server # [ 137.778303] postgres[1222]: language plpgsql1704server # [ 137.785185] postgres[1222]: as $$1705server # [ 137.788226] postgres[1222]: DECLARE1706server # [ 137.790244] postgres[1222]: server_version float;1707server # [ 137.794040] postgres[1222]: BEGIN1708server # [ 137.796143] postgres[1222]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1709server # [ 137.798300] postgres[1222]:1710server # [ 137.801243] postgres[1222]: IF server_version >= 14 THEN1711server # [ 137.803175] postgres[1222]: RETURN jsonb_build_object(1712server # [ 137.806498] postgres[1222]: 'errors', jsonb_build_array(1713server # [ 137.808655] postgres[1222]: jsonb_build_object(1714server # [ 137.810209] postgres[1222]: 'message', 'pg_graphql extension is not enabled.'1715server # [ 137.813174] postgres[1222]: )1716server # [ 137.815520] postgres[1222]: )1717server # [ 137.817612] postgres[1222]: );1718server # [ 137.819531] postgres[1222]: ELSE1719server # [ 137.822119] postgres[1222]: RETURN jsonb_build_object(1720server # [ 137.823836] postgres[1222]: 'errors', jsonb_build_array(1721server # [ 137.826249] postgres[1222]: jsonb_build_object(1722server # [ 137.827893] postgres[1222]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1723server # [ 137.830722] postgres[1222]: )1724server # [ 137.831639] postgres[1222]: )1725server # [ 137.833971] postgres[1222]: );1726server # [ 137.835344] postgres[1222]: END IF;1727server # [ 137.837124] postgres[1222]: END;1728server # [ 137.838739] postgres[1222]: $$;1729server # [ 137.841051] postgres[1222]: ::1 2026-10-07 00:04:07.008 UTC [1222] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1730server # [ 139.247975] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1221]: DO1731server # [ 139.286494] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1732server # [ 139.754880] postgres[1244]: ::1 2026-10-07 00:04:09.211 UTC [1244] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1733server # [ 139.768853] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1238]: GRANT ROLE1734server # [ 139.809259] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1735server # [ 139.973007] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1736server # [ 140.095356] postgres[1246]: ::1 2026-10-07 00:04:09.558 UTC [1246] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1737server # [ 140.103862] postgres[1246]: returns event_trigger1738server # [ 140.108934] postgres[1246]: language plpgsql1739server # [ 140.110658] postgres[1246]: AS $func$1740server # [ 140.115780] postgres[1246]: DECLARE1741server # [ 140.118621] postgres[1246]: func_is_graphql_resolve bool;1742server # [ 140.121750] postgres[1246]: BEGIN1743server # [ 140.123543] postgres[1246]: func_is_graphql_resolve = (1744server # [ 140.129742] postgres[1246]: SELECT n.proname = 'resolve'1745server # [ 140.130878] postgres[1246]: FROM pg_event_trigger_ddl_commands() AS ev1746server # [ 140.131895] postgres[1246]: LEFT JOIN pg_catalog.pg_proc AS n1747server # [ 140.133574] postgres[1246]: ON ev.objid = n.oid1748server # [ 140.134365] postgres[1246]: );1749server # [ 140.137403] postgres[1246]:1750server # [ 140.138463] postgres[1246]: IF func_is_graphql_resolve1751server # [ 140.143211] postgres[1246]: THEN1752server # [ 140.147133] postgres[1246]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1753server # [ 140.153195] postgres[1246]: DROP FUNCTION IF EXISTS graphql_public.graphql;1754server # [ 140.156721] postgres[1246]: create or replace function graphql_public.graphql(1755server # [ 140.158707] postgres[1246]: "operationName" text default null,1756server # [ 140.164863] postgres[1246]: query text default null,1757server # [ 140.169517] postgres[1246]: variables jsonb default null,1758server # [ 140.172944] postgres[1246]: extensions jsonb default null1759server # [ 140.174918] postgres[1246]: )1760server # [ 140.177219] postgres[1246]: returns jsonb1761server # [ 140.179951] postgres[1246]: language sql1762server # [ 140.181707] postgres[1246]: as $$1763server # [ 140.185101] postgres[1246]: select graphql.resolve(1764server # [ 140.187289] postgres[1246]: query := query,1765server # [ 140.191401] postgres[1246]: variables := coalesce(variables, '{}'),1766server # [ 140.194459] postgres[1246]: "operationName" := "operationName",1767server # [ 140.198566] postgres[1246]: extensions := extensions1768server # [ 140.201621] postgres[1246]: );1769server # [ 140.205415] postgres[1246]: $$;1770server # [ 140.206806] postgres[1246]:1771server # [ 140.210400] postgres[1246]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1772server # [ 140.227938] postgres[1246]: -- function in the extension so we need to grant permissions on existing entities AND1773server # [ 140.229634] postgres[1246]: -- update default permissions to any others that are created after `graphql.resolve`1774server # [ 140.231415] postgres[1246]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1775server # [ 140.239234] postgres[1246]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1776server # [ 140.245322] postgres[1246]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1777server # [ 140.246720] postgres[1246]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1778server # [ 140.255366] postgres[1246]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1779server # [ 140.259790] postgres[1246]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1780server # [ 140.264778] postgres[1246]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1781server # [ 140.266667] postgres[1246]: END IF;1782server # [ 140.273471] postgres[1246]:1783server # [ 140.277252] postgres[1246]: END;1784server # [ 140.279067] postgres[1246]: $func$;1785server # [ 140.280809] postgres[1246]: ::1 2026-10-07 00:04:09.622 UTC [1246] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1786server # [ 140.289512] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1245]: CREATE FUNCTION1787server # [ 140.291501] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1245]: DROP EXTENSION1788server # [ 141.515177] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1245]: DO1789server # [ 141.555069] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql1790server # [ 142.779510] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1247]: DO1791server # [ 143.421503] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1247]: DO1792server # [ 143.455431] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1793server # [ 143.735803] postgres[1270]: ::1 2026-10-07 00:04:13.202 UTC [1270] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s';1794server # [ 143.761094] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1269]: ALTER ROLE1795server # [ 143.790437] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221103090837_revoke_admin.sql1796server # [ 144.049636] postgres[1272]: ::1 2026-10-07 00:04:13.512 UTC [1272] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator;1797server # [ 144.070267] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1271]: REVOKE ROLE1798server # [ 144.104765] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql1799server # [ 146.384822] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1273]: DO1800server # [ 146.431294] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql1801server # [ 146.686504] postgres[1296]: ::1 2026-10-07 00:04:16.147 UTC [1296] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres;1802server # [ 146.729186] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1295]: GRANT ROLE1803server # [ 146.734254] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql1804server # [ 146.963829] postgres[1298]: ::1 2026-10-07 00:04:16.425 UTC [1298] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option;1805server # [ 146.998880] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1297]: GRANT1806server # [ 147.006326] postgres[1298]: ::1 2026-10-07 00:04:16.473 UTC [1298] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option;1807server # [ 147.032428] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1297]: GRANT1808server # [ 147.036833] postgres[1298]: ::1 2026-10-07 00:04:16.506 UTC [1298] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option;1809server # [ 147.042899] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1297]: GRANT1810server # [ 147.049271] postgres[1298]: ::1 2026-10-07 00:04:16.517 UTC [1298] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option;1811server # [ 147.058379] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1297]: ALTER DEFAULT PRIVILEGES1812server # [ 147.061183] postgres[1298]: ::1 2026-10-07 00:04:16.531 UTC [1298] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option;1813server # [ 147.077413] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1297]: ALTER DEFAULT PRIVILEGES1814server # [ 147.079807] postgres[1298]: ::1 2026-10-07 00:04:16.545 UTC [1298] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option;1815server # [ 147.086415] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1297]: ALTER DEFAULT PRIVILEGES1816server # [ 147.131588] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql1817server # [ 147.392368] postgres[1300]: ::1 2026-10-07 00:04:16.854 UTC [1300] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres;1818server # [ 147.418768] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1299]: GRANT ROLE1819server # [ 147.462074] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql1820server # [ 147.715999] postgres[1302]: ::1 2026-10-07 00:04:17.178 UTC [1302] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin;1821server # [ 147.733981] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1301]: GRANT ROLE1822server # [ 147.767994] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql1823server # [ 148.239556] postgres[1313]: ::1 2026-10-07 00:04:17.690 UTC [1313] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit;1824server # [ 148.278168] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1307]: ALTER ROLE1825server # [ 148.289982] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1307]: ALTER ROLE1826server # [ 148.297164] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1307]: ALTER ROLE1827server # [ 148.299106] postgres[1313]: ::1 2026-10-07 00:04:17.735 UTC [1313] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit;1828server # [ 148.308832] postgres[1313]: ::1 2026-10-07 00:04:17.746 UTC [1313] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit;1829server # [ 148.337732] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1307]: DO1830server # [ 148.394843] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql1831server: Guest root shell did not produce any data yet...1832server: To debug, enter the VM and run 'systemctl status backdoor.service'.1833server # [ 148.757669] postgres[1326]: ::1 2026-10-07 00:04:18.214 UTC [1326] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin;1834server # [ 148.781315] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1319]: GRANT ROLE1835server # [ 148.797032] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1319]: REVOKE ROLE1836server # [ 148.798715] postgres[1326]: ::1 2026-10-07 00:04:18.248 UTC [1326] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin;1837server # [ 148.851085] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql1838server # [ 149.145668] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1839server # [ 149.156903] postgres[1328]: ::1 2026-10-07 00:04:18.614 UTC [1328] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1840server # [ 149.161407] postgres[1328]: returns event_trigger1841server # [ 149.162474] postgres[1328]: language plpgsql1842server # [ 149.163911] postgres[1328]: AS $func$1843server # [ 149.170697] postgres[1328]: DECLARE1844server # [ 149.172209] postgres[1328]: func_is_graphql_resolve bool;1845server # [ 149.177504] postgres[1328]: BEGIN1846server # [ 149.178529] postgres[1328]: func_is_graphql_resolve = (1847server # [ 149.179662] postgres[1328]: SELECT n.proname = 'resolve'1848server # [ 149.183950] postgres[1328]: FROM pg_event_trigger_ddl_commands() AS ev1849server # [ 149.185217] postgres[1328]: LEFT JOIN pg_catalog.pg_proc AS n1850server # [ 149.186324] postgres[1328]: ON ev.objid = n.oid1851server # [ 149.189539] postgres[1328]: );1852server # [ 149.193679] postgres[1328]:1853server # [ 149.196043] postgres[1328]: IF func_is_graphql_resolve1854server # [ 149.197560] postgres[1328]: THEN1855server # [ 149.200940] postgres[1328]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1856server # [ 149.202188] postgres[1328]: DROP FUNCTION IF EXISTS graphql_public.graphql;1857server # [ 149.203171] postgres[1328]: create or replace function graphql_public.graphql(1858server # [ 149.209305] postgres[1328]: "operationName" text default null,1859server # [ 149.210699] postgres[1328]: query text default null,1860server # [ 149.212564] postgres[1328]: variables jsonb default null,1861server # [ 149.214531] postgres[1328]: extensions jsonb default null1862server # [ 149.217663] postgres[1328]: )1863server # [ 149.222823] postgres[1328]: returns jsonb1864server # [ 149.225369] postgres[1328]: language sql1865server # [ 149.227912] postgres[1328]: as $$1866server # [ 149.233764] postgres[1328]: select graphql.resolve(1867server # [ 149.242084] postgres[1328]: query := query,1868server # [ 149.243500] postgres[1328]: variables := coalesce(variables, '{}'),1869server # [ 149.250245] postgres[1328]: "operationName" := "operationName",1870server # [ 149.251951] postgres[1328]: extensions := extensions1871server # [ 149.254247] postgres[1328]: );1872server # [ 149.257143] postgres[1328]: $$;1873server # [ 149.257973] postgres[1328]:1874server # [ 149.259997] postgres[1328]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1875server # [ 149.266058] postgres[1328]: -- function in the extension so we need to grant permissions on existing entities AND1876server # [ 149.268859] postgres[1328]: -- update default permissions to any others that are created after `graphql.resolve`1877server # [ 149.274830] postgres[1328]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1878server # [ 149.281524] postgres[1328]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1879server # [ 149.283568] postgres[1328]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1880server # [ 149.289698] postgres[1328]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1881server # [ 149.291177] postgres[1328]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1882server # [ 149.295239] postgres[1328]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1883server # [ 149.302599] postgres[1328]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1884server # [ 149.309627] postgres[1328]:1885server # [ 149.310657] postgres[1328]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1886server # [ 149.311539] postgres[1328]: grant usage on schema graphql_public to postgres with grant option;1887server # [ 149.312225] postgres[1328]: grant usage on schema graphql to postgres with grant option;1888server # [ 149.314051] postgres[1328]: END IF;1889server # [ 149.317037] postgres[1328]:1890server # [ 149.322985] postgres[1328]: END;1891server # [ 149.324057] postgres[1328]: $func$;1892server # [ 149.325792] postgres[1328]: ::1 2026-10-07 00:04:18.714 UTC [1328] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1893server # [ 149.345065] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1327]: CREATE FUNCTION1894server # [ 149.351666] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1327]: DROP EXTENSION1895server # [ 150.242640] 8021q: 802.1Q VLAN Support v1.81896server # [ 151.316782] cfg80211: Loading compiled-in X.509 certificates for regulatory database1897server # [ 151.207722] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1327]: DO1898server # [ 151.291944] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql1899server # [ 151.794792] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1900server # [ 151.800669] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1901server # [ 151.840345] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21902server # [ 151.840829] cfg80211: failed to load regulatory.db1903server # [ 151.754273] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1333]: DO1904server # [ 151.761481] postgres[1337]: ::1 2026-10-07 00:04:21.217 UTC [1337] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger1905server # [ 151.762837] postgres[1337]: LANGUAGE plpgsql1906server # [ 151.765536] postgres[1337]: AS $$1907server # [ 151.766687] postgres[1337]: BEGIN1908server # [ 151.767603] postgres[1337]: IF EXISTS (1909server # [ 151.770227] postgres[1337]: SELECT1910server # [ 151.771152] postgres[1337]: FROM pg_event_trigger_ddl_commands() AS ev1911server # [ 151.771910] postgres[1337]: JOIN pg_extension AS ext1912server # [ 151.773424] postgres[1337]: ON ev.objid = ext.oid1913server # [ 151.774286] postgres[1337]: WHERE ext.extname = 'pg_cron'1914server # [ 151.775320] postgres[1337]: )1915server # [ 151.776552] postgres[1337]: THEN1916server # [ 151.778146] postgres[1337]: grant usage on schema cron to postgres with grant option;1917server # [ 151.780039] postgres[1337]:1918server # [ 151.782038] postgres[1337]: alter default privileges in schema cron grant all on tables to postgres with grant option;1919server # [ 151.782847] postgres[1337]: alter default privileges in schema cron grant all on functions to postgres with grant option;1920server # [ 151.785668] postgres[1337]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1921server # [ 151.787381] postgres[1337]:1922server # [ 151.788934] postgres[1337]: alter default privileges for user supabase_admin in schema cron grant all1923server # [ 151.790236] postgres[1337]: on sequences to postgres with grant option;1924server # [ 151.791943] postgres[1337]: alter default privileges for user supabase_admin in schema cron grant all1925server # [ 151.794434] postgres[1337]: on tables to postgres with grant option;1926server # [ 151.795466] postgres[1337]: alter default privileges for user supabase_admin in schema cron grant all1927server # [ 151.797590] postgres[1337]: on functions to postgres with grant option;1928server # [ 151.798761] postgres[1337]:1929server # [ 151.799638] postgres[1337]: grant all privileges on all tables in schema cron to postgres with grant option;1930server # [ 151.805865] postgres[1337]: revoke all on table cron.job from postgres;1931server # [ 151.813092] postgres[1337]: grant select on table cron.job to postgres with grant option;1932server # [ 151.814049] postgres[1337]: END IF;1933server # [ 151.814613] postgres[1337]: END;1934server # [ 151.815333] postgres[1337]: $$;1935server # [ 151.846027] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1333]: CREATE FUNCTION1936server # [ 151.862415] postgres[1337]: ::1 2026-10-07 00:04:21.325 UTC [1337] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access;1937server # [ 151.885020] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1333]: DROP EVENT TRIGGER1938server # [ 151.893466] postgres[1337]: ::1 2026-10-07 00:04:21.360 UTC [1337] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin;1939server # [ 151.914647] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1333]: ALTER FUNCTION1940server # [ 151.921736] postgres[1337]: ::1 2026-10-07 00:04:21.390 UTC [1337] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end1941server # [ 151.931208] postgres[1337]: WHEN TAG IN ('CREATE EXTENSION')1942server # [ 151.937145] postgres[1337]: EXECUTE FUNCTION extensions.grant_pg_cron_access();1943server # [ 151.942236] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1333]: CREATE EVENT TRIGGER1944server # [ 152.005489] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql1945server # [ 152.446073] postgres[1349]: ::1 2026-10-07 00:04:21.912 UTC [1349] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s';1946server # [ 152.461427] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1344]: ALTER ROLE1947server # [ 152.525690] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql1948server # [ 152.980253] postgres[1361]: ::1 2026-10-07 00:04:22.443 UTC [1361] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin;1949server # [ 153.053810] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1355]: ALTER FUNCTION1950server # [ 153.055252] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1355]: ALTER FUNCTION1951server # [ 153.058648] postgres[1361]: ::1 2026-10-07 00:04:22.511 UTC [1361] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin;1952server # [ 153.067080] postgres[1361]: ::1 2026-10-07 00:04:22.525 UTC [1361] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin;1953server # [ 153.075487] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1355]: ALTER FUNCTION1954server # [ 153.138399] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql1955server # [ 153.450882] postgres[1363]: ::1 2026-10-07 00:04:22.914 UTC [1363] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres;1956server # [ 153.503842] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1362]: GRANT ROLE1957server # [ 153.530166] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241031003909_create_orioledb.sql1958server # [ 154.447284] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1364]: DO1959server # [ 154.495496] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1960server # [ 154.962842] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1366]: DO1961server # [ 154.984969] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1366]: DO1962server # [ 155.064050] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql1963server # [ 155.391155] (udev-worker)[1368]: Network interface NamePolicy= disabled on kernel command line.1964server # [ 155.422173] postgres[1385]: ::1 2026-10-07 00:04:24.883 UTC [1385] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none;1965server # [ 155.438834] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1378]: ALTER ROLE1966server # [ 155.451296] (udev-worker)[1369]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1967server # [ 155.459946] postgres[1385]: ::1 2026-10-07 00:04:24.904 UTC [1385] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none;1968server # [ 155.467312] postgres[1385]: ::1 2026-10-07 00:04:24.913 UTC [1385] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none;1969server # [ 155.481801] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1378]: ALTER ROLE1970server # [ 155.490688] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1378]: ALTER ROLE1971server # [ 155.503040] (udev-worker)[1369]: Network interface NamePolicy= disabled on kernel command line.1972server # [ 155.514454] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql1973server # [ 156.019790] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1391]: DO1974server # [ 156.037282] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1975server # [ 156.495549] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1394]: DO1976server # [ 156.557271] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql1977server # [ 156.726847] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1978server # [ 156.847964] systemd[1]: Started backdoor.service.1979server # [ 157.060365] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1397]: CREATE FUNCTION1980server # [ 157.079379] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1397]: DO1981server # [ 157.126608] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1982server # [ 157.434839] systemd[1]: Condition check resulted in Virtio network device being skipped.1983server # [ 157.561931] systemd[1]: Starting Address configuration of eth1...1984server # [ 157.808883] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1400]: ALTER FUNCTION1985server # [ 157.832806] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1400]: GRANT1986server # [ 157.944181] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql1987server # connecting to host...1988server: Guest shell says: b'Spawning backdoor root shell...\n'1989server: connected to guest root shell1990server: (connecting took 160.26 seconds)1991server: (finished: waiting for the VM to finish booting, in 160.26 seconds)1992server # [ 158.701679] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1408]: DROP EVENT TRIGGER1993server # [ 158.772372] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1408]: ALTER FUNCTION1994server # [ 158.812647] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1408]: CREATE EVENT TRIGGER1995server # [ 158.878694] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql1996server # [ 159.458749] 8021q: adding VLAN 0 to HW filter on device eth11997server # [ 159.599158] 8021q: adding VLAN 0 to HW filter on device eth01998server # [ 159.417928] dhcpcd[767]: eth0: waiting for carrier1999server # [ 159.464877] dhcpcd[767]: eth0: carrier acquired2000server # [ 159.574158] network-addresses-eth1-start[1405]: adding address 192.168.1.1/24... done2001server # [ 159.759500] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1417]: DO2002server # [ 159.773565] dhcpcd[767]: DUID 00:01:00:01:32:58:49:0d:52:54:00:12:34:562003server # [ 159.810388] dhcpcd[767]: eth0: IAID 00:12:34:562004server # [ 159.846619] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql2005server # [ 159.859429] dhcpcd[767]: eth0: adding address fe80::5054:ff:fe12:34562006server # [ 159.886564] network-addresses-eth1-start[1405]: adding address 2001:db8:1::1/64... done2007server # [ 160.417848] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1429]: CREATE FUNCTION2008server # [ 160.438359] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1429]: GRANT2009server # [ 160.469148] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql2010server # [ 160.507430] systemd[1]: Finished Address configuration of eth1.2011server # [ 160.877768] dhcpcd[767]: eth0: soliciting a DHCP lease2012server # [ 160.935000] dhcpcd[767]: eth0: offered 10.0.2.15 from 10.0.2.22013server # [ 160.961489] dhcpcd[767]: eth0: probing address 10.0.2.15/242014server # [ 160.992938] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1433]: REVOKE ROLE2015server # [ 161.032838] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1433]: DO2016server # [ 161.042727] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1433]: DO2017server # [ 161.054102] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1433]: REVOKE ROLE2018server # [ 161.070554] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1433]: REVOKE2019server # [ 161.107540] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1433]: DO2020server # [ 161.167828] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql2021server # [ 161.498366] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1437]: DO2022server # [ 161.526886] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql2023server # [ 161.539461] dhcpcd[767]: eth0: soliciting an IPv6 router2024server # [ 161.572618] dhcpcd[767]: eth0: Router Advertisement from fe80::22025server # [ 161.584488] dhcpcd[767]: eth0: adding address fec0::5054:ff:fe12:3456/642026server # [ 161.605911] dhcpcd[767]: eth0: adding route to fec0::/642027server # [ 161.608032] dhcpcd[767]: eth0: adding default route via fe80::22028server # [ 162.022380] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1439]: DO2029server # [ 162.067858] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql2030server # [ 162.699399] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1445]: DO2031server # [ 162.785537] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql2032server # [ 163.305631] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1452]: ALTER ROLE2033server # [ 163.365784] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251001204436_predefined_role_grants.sql2034server # [ 163.952932] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1470]: GRANT ROLE2035server # [ 163.976972] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1470]: DO2036server # [ 164.081867] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql2037server # [ 164.703412] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1478]: GRANT2038server # [ 164.738483] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql2039server # [ 165.063500] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1480]: CREATE FUNCTION2040server # [ 165.074698] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1480]: REVOKE2041server # [ 165.092133] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1480]: REVOKE2042server # [ 165.103458] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1480]: GRANT2043server # [ 165.179693] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260211120934_supabase_privileged_role.sql2044server # [ 165.492339] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1482]: DO2045server # [ 165.545166] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql2046server # [ 165.811973] dhcpcd[767]: eth0: leased 10.0.2.15 for 86400 seconds2047server # [ 165.831828] dhcpcd[767]: eth0: adding route to 10.0.2.0/242048server # [ 165.841226] dhcpcd[767]: eth0: adding default route via 10.0.2.22049server # [ 166.081260] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1484]: ALTER ROLE2050server # [ 166.129773] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql2051server # [ 166.987626] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1494]: DROP EXTENSION2052server # [ 167.059997] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql2053server # [ 167.767029] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1513]: CREATE FUNCTION2054server # [ 167.775415] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1513]: DROP EVENT TRIGGER2055server # [ 167.786253] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1513]: CREATE EVENT TRIGGER2056server # [ 167.866235] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql2057server # [ 168.630116] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1543]: DO2058server # [ 168.721059] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1543]: CREATE FUNCTION2059server # [ 168.804136] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql2060server # [ 169.339301] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1560]: CREATE FUNCTION2061server # [ 169.375655] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1560]: CREATE FUNCTION2062server # [ 169.425527] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Running postgresql schema...2063server # [ 169.893918] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER DATABASE2064server # [ 169.929215] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER DATABASE2065server # [ 170.069189] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2066server # [ 170.189974] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2067server # [ 170.270893] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2068server # [ 170.344722] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2069server # [ 170.420773] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2070server # [ 170.517069] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2071server # [ 170.575697] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2072server # [ 170.636754] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2073server # [ 170.714677] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2074server # [ 170.731662] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1570]: ALTER ROLE2075server # [ 170.806861] c60wv9harjpiddsm4g57adza670p5qlc-supabase-db-init[1068]: Database initialization complete2076server # [ 170.865551] systemd[1]: Finished Supabase Database Initialization.2077server # [ 170.906229] systemd[1]: Reached target Multi-User System.2078server # [ 170.915856] systemd[1]: Startup finished in 2.689s (kernel) + 29.242s (initrd) + 2min 18.981s (userspace) = 2min 50.914s.2079server: (finished: waiting for unit supabase-db-init.service, in 173.79 seconds)2080subtest: Preconditions2081server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select rolsuper from pg_roles where rolname = 'postgres'"2082server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select rolsuper from pg_roles where rolname = 'postgres'", in 0.69 seconds)2083server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select current_user"2084server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select current_user", in 0.85 seconds)2085(finished: subtest: Preconditions, in 1.54 seconds)2086subtest: A non-superuser installs amcheck on PG 15, pinned to pg_catalog2087server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension amcheck"2088server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension amcheck", in 1.71 seconds)2089server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'"2090server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'", in 0.68 seconds)2091PG 15 amcheck: 1.3,pg_catalog2092(finished: subtest: A non-superuser installs amcheck on PG 15, pinned to pg_catalog, in 2.40 seconds)2093subtest: PG 15 grants reach postgres only2094server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname"2095server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname", in 1.04 seconds)2096(finished: subtest: PG 15 grants reach postgres only, in 1.04 seconds)2097subtest: amcheck dropped before upgrade, mirroring EXTENSIONS_TO_DISABLE2098server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "drop extension if exists amcheck cascade"2099server # [ 177.790112] systemd-logind[611]: Watching system buttons on /dev/input/event0 (gpio-keys)2100server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "drop extension if exists amcheck cascade", in 0.80 seconds)2101(finished: subtest: amcheck dropped before upgrade, mirroring EXTENSIONS_TO_DISABLE, in 0.80 seconds)2102subtest: switch to postgresql 172103server # Checking switch inhibitors... done2104server # [ 183.785066] mousedev: PS/2 mouse device common for all mice2105server # stopping the following units: postgresql.service, supabase-db-init.service2106server # [ 186.517169] nixos[1676]: switching to system configuration /nix/store/3bn35xzw0zfj21qn4wpl67p7d27h2786-nixos-system-server-test2107server # [ 186.552820] systemd[1]: supabase-db-init.service: Deactivated successfully.2108server # [ 186.572368] systemd[1]: Stopped Supabase Database Initialization.2109server # [ 186.661684] postgres[1035]: 2026-10-07 00:04:56.026 UTC [1035] LOG: received fast shutdown request2110server # [ 186.702453] postgres[1035]: 2026-10-07 00:04:56.054 UTC [1035] LOG: aborting any active transactions2111server # [ 186.721015] systemd[1]: supabase-db-init.service: Consumed 9.027s CPU time over 1min 10.229s wall clock time, 4M memory peak, 89.4K incoming IP traffic, 120.7K outgoing IP traffic.2112server # [ 186.759841] postgres[1065]: 2026-10-07 00:04:56.061 UTC [1065] LOG: pg_cron scheduler shutting down2113server # [ 186.774082] systemd[1]: Stopping PostgreSQL Database Server...2114server # [ 186.798469] postgres[1063]: 2026-10-07 00:04:56.073 UTC [1063] FATAL: terminating background worker "TimescaleDB Background Worker Launcher" due to administrator command2115server # [ 186.800735] systemd[1]: Stopped target Remote File Systems.2116server # [ 186.814180] postgres[1035]: 2026-10-07 00:04:56.241 UTC [1035] LOG: background worker "pg_cron launcher" (PID 1065) exited with exit code 12117server # [ 186.816163] postgres[1035]: 2026-10-07 00:04:56.271 UTC [1035] LOG: background worker "logical replication launcher" (PID 1066) exited with exit code 12118server # [ 186.827117] postgres[1035]: 2026-10-07 00:04:56.279 UTC [1035] LOG: background worker "TimescaleDB Background Worker Launcher" (PID 1063) exited with exit code 12119server # [ 186.829171] systemd[1]: Stopped target Local File Systems.2120server # [ 186.836707] postgres[1035]: 2026-10-07 00:04:56.305 UTC [1035] LOG: background worker "pg_net 0.20.4 worker" (PID 1083) exited with exit code 12121server # [ 186.846527] postgres[1058]: 2026-10-07 00:04:56.307 UTC [1058] LOG: shutting down2122server # [ 186.857121] postgres[1058]: 2026-10-07 00:04:56.327 UTC [1058] LOG: checkpoint starting: shutdown immediate2123server # [ 186.969024] systemd-logind[611]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)2124server # [ 187.357794] postgres[1058]: 2026-10-07 00:04:56.825 UTC [1058] LOG: checkpoint complete: wrote 268 buffers (1.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.150 s, sync=0.263 s, total=0.518 s; sync files=136, longest=0.015 s, average=0.002 s; distance=1961 kB, estimate=1961 kB2125server # [ 187.538321] postgres[1035]: 2026-10-07 00:04:57.004 UTC [1035] LOG: database system is shut down2126server # [ 187.631985] systemd[1]: postgresql.service: Deactivated successfully.2127server # [ 187.637887] systemd[1]: Stopped PostgreSQL Database Server.2128server # activating the configuration...2129server # [ 187.645569] systemd[1]: postgresql.service: Consumed 38.672s CPU time over 1min 49.118s wall clock time, 127.2M memory peak, 45.3M written to disk, 125.2K incoming IP traffic, 94.1K outgoing IP traffic.2130server # setting up /etc...2131server # [ 196.237035] systemd[1]: Reload requested from client PID 1676 ('.switch-to-conf') (unit backdoor.service)...2132server # [ 196.240158] systemd[1]: Reloading...2133server # [ 202.596343] systemd[1]: Reloading finished in 6328 ms.2134server # restarting sysinit-reactivation.target2135server # [ 203.001317] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....2136server # [ 204.682069] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..2137server # [ 204.708143] systemd[1]: Reached target Reactivate sysinit units.2138server # reloading the following units: dbus-broker.service2139server # [ 204.799957] dbus-broker-launch[595]: Caught SIGHUP, trigger reload.2140server # [ 204.811733] systemd[1]: Reloading D-Bus System Message Bus...2141server # [ 205.096409] dbus-broker-launch[595]: Looking up NSS user entry for 'systemd-timesync'...2142server # [ 205.145013] dbus-broker-launch[595]: NSS returned no entry for 'systemd-timesync'2143server # [ 205.146109] dbus-broker-launch[595]: Invalid user-name in /nix/store/gh5405qnnzi90ffy49vd7ag9d0n6nbvv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2144server # [ 205.513431] systemd[1]: Reloaded D-Bus System Message Bus.2145server # starting the following units: postgresql.service, supabase-db-init.service2146server # [ 205.607208] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2147server # [ 205.639938] systemd[1]: Reached target Local File Systems.2148server # [ 205.957083] systemd[1]: Reached target Remote File Systems.2149server # [ 205.969188] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2150server # [ 206.045391] systemd[1]: Starting Create SUID/SGID Wrappers...2151server # [ 206.052869] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2152server # [ 206.062065] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672153server # [ 206.081930] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2154server # [ 206.138717] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki2155server # [ 206.143045] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki2156server # [ 212.571921] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2157server # [ 212.582673] systemd[1]: Finished Create SUID/SGID Wrappers.2158server # [ 212.605773] systemd[1]: suid-sgid-wrappers.service: Consumed 3.121s CPU time over 6.511s wall clock time, 3.2M memory peak.2159server # [ 212.700493] systemd[1]: Starting linger-users.service...2160server # [ 212.805514] systemd[1]: Starting PostgreSQL Major Version Migration...2161server # [ 213.612004] systemd[1]: linger-users.service: Deactivated successfully.2162server # [ 213.627846] systemd[1]: Finished linger-users.service.2163server # [ 213.681715] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1985]: Starting pg_upgrade from 15 to 172164server # [ 214.819783] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: The files belonging to this database system will be owned by user "postgres".2165server # [ 214.832126] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: This user must also own the server process.2166server # [ 214.905265] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: The database cluster will be initialized with locale "en_US.UTF-8".2167server # [ 214.906778] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: The default database encoding has accordingly been set to "UTF8".2168server # [ 214.913762] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: The default text search configuration will be set to "english".2169server # [ 214.915317] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: Data page checksums are enabled.2170server # [ 214.916060] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: fixing permissions on existing directory /var/lib/postgresql/data-17 ... ok2171server # [ 214.932252] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: creating subdirectories ... ok2172server # [ 214.934384] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: selecting dynamic shared memory implementation ... posix2173server # [ 216.469231] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: selecting default "max_connections" ... 1002174server # [ 217.256666] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: selecting default "shared_buffers" ... 128MB2175server # [ 228.698459] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: selecting default time zone ... UTC2176server # [ 228.745388] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: creating configuration files ... ok2177server # [ 232.689333] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: running bootstrap script ... ok2178server # [ 242.113379] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: performing post-bootstrap initialization ... ok2179server # [ 245.104259] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: syncing data to disk ... ok2180server # [ 245.110997] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: initdb: warning: enabling "trust" authentication for local connections2181server # [ 245.121933] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: 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.2182server # [ 245.126977] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: Success. You can now start the database server using:2183server # [ 245.127967] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1993]: /nix/store/38z1a7qbbidjkd14rinhbz7dkcykc6gd-postgresql-and-plugins-17.11/bin/pg_ctl -D /var/lib/postgresql/data-17 -l logfile start2184server # [ 251.732002] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Performing Consistency Checks2185server # [ 251.883485] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: -----------------------------2186server # [ 251.905754] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking cluster versions ok2187server # [ 257.484104] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking database user is the install user ok2188server # [ 257.561131] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking database connection settings ok2189server # [ 257.686812] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking for prepared transactions ok2190server # [ 257.702164] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking for contrib/isn with bigint-passing mismatch ok2191server # [ 259.395234] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking data type usage ok2192server # [ 259.796866] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking for not-null constraint inconsistencies ok2193server # [ 260.652425] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Creating dump of global objects ok2194server # [ 265.451552] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Creating dump of database schemas ok2195server # [ 271.083177] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking for presence of required libraries ok2196server # [ 271.381180] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking database user is the install user ok2197server # [ 271.583326] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking for prepared transactions ok2198server # [ 271.585985] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking for new cluster tablespace directories ok2199server # [ 271.602800] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: If pg_upgrade fails after this point, you must re-initdb the2200server # [ 271.621693] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: new cluster before continuing.2201server # [ 271.632529] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Performing Upgrade2202server # [ 271.634402] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: ------------------2203server # [ 271.714585] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Setting locale and encoding for new cluster ok2204server # [ 275.597447] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Analyzing all rows in the new cluster ok2205server # [ 277.515959] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Freezing all rows in the new cluster ok2206server # [ 278.287135] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Deleting files from new pg_xact ok2207server # [ 278.589970] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Copying old pg_xact to new server ok2208server # [ 279.170884] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Setting oldest XID for new cluster ok2209server # [ 280.292413] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Setting next transaction ID and epoch for new cluster ok2210server # [ 280.303978] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Deleting files from new pg_multixact/offsets ok2211server # [ 280.589949] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Copying old pg_multixact/offsets to new server ok2212server # [ 280.595561] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Deleting files from new pg_multixact/members ok2213server # [ 280.946482] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Copying old pg_multixact/members to new server ok2214server # [ 281.625077] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Setting next multixact ID and offset for new cluster ok2215server # [ 282.161872] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Resetting WAL archives ok2216server # [ 286.376046] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Setting frozenxid and minmxid counters in new cluster ok2217server # [ 288.315842] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Restoring global objects in the new cluster ok2218server # [ 290.384577] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2219server # [ 290.405881] systemd[1]: Stopped Virtual Console Setup.2220server # [ 290.407697] systemd[1]: systemd-vconsole-setup.service: Consumed 1.234s CPU time over 2min 40.992s wall clock time, 2.8M memory peak.2221server # [ 290.415651] systemd[1]: Stopping Virtual Console Setup...2222server # [ 290.491499] systemd[1]: Starting Virtual Console Setup...2223server # [ 290.877478] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.2224server # [ 290.887307] systemd[1]: Stopped Virtual Console Setup.2225server # [ 290.967619] systemd[1]: Starting Virtual Console Setup...2226server # [ 294.598807] systemd-vconsole-setup[2656]: Configuration of first virtual console was skipped, ignoring remaining ones.2227server # [ 294.628545] systemd[1]: Finished Virtual Console Setup.2228server # [ 295.450848] systemd-logind[611]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)2229server # [ 296.000049] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Restoring database schemas in the new cluster ok2230server # [ 297.649887] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.2231server # [ 297.922584] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Copying user relation files ok2232server # [ 298.214899] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Setting next OID for new cluster ok2233server # [ 300.103938] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Sync data directory to disk ok2234server # [ 300.109846] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Creating script to delete old cluster ok2235server # [ 304.398699] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Checking for extension updates notice2236server # [ 304.401918] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Your installation contains extensions that should be updated2237server # [ 304.403225] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: with the ALTER EXTENSION command. The file2238server # [ 304.403816] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: update_extensions.sql2239server # [ 304.413519] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: when executed by psql by the database superuser will update2240server # [ 304.415619] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: these extensions.2241server # [ 304.751483] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Upgrade Complete2242server # [ 304.756437] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: ----------------2243server # [ 304.760262] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Optimizer statistics are not transferred by pg_upgrade.2244server # [ 304.763186] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Once you start the new server, consider running:2245server # [ 304.767766] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: /nix/store/38z1a7qbbidjkd14rinhbz7dkcykc6gd-postgresql-and-plugins-17.11/bin/vacuumdb -U supabase_admin --all --analyze-in-stages2246server # [ 304.774471] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: Running this script will delete the old cluster's data files:2247server # [ 304.776582] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[2225]: ./delete_old_cluster.sh2248server # [ 304.807734] w7j1hmz2nl49snxcwg54msbaf2ngs9y4-postgresql-migrate[1985]: pg_upgrade completed successfully2249server # [ 304.835794] systemd[1]: Finished PostgreSQL Major Version Migration.2250server # [ 304.876569] systemd[1]: Starting PostgreSQL Database Server...2251server # [ 306.390317] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[2723]: Validating PostgreSQL configuration...2252server # [ 306.716147] wyacxvfngkssr519w1xcwmcaphhkfvhk-postgresql-init[2735]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault2253server # [ 307.396705] postgres[2737]: 2026-10-07 00:06:56.859 UTC [2737] LOG: pgaudit extension initialized2254server # [ 307.707249] postgres[2737]: 2026-10-07 00:06:57.176 UTC [2737] LOG: pgsodium primary server secret key loaded2255server # [ 307.923942] postgres[2737]: 2026-10-07 00:06:57.395 UTC [2737] LOG: vault primary server secret key loaded2256server # [ 308.176158] postgres[2737]: 2026-10-07 00:06:57.646 UTC [2737] LOG: starting PostgreSQL 17.11 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.3.0, 64-bit2257server # [ 308.186839] postgres[2737]: 2026-10-07 00:06:57.657 UTC [2737] LOG: listening on IPv4 address "0.0.0.0", port 54322258server # [ 308.190140] postgres[2737]: 2026-10-07 00:06:57.658 UTC [2737] LOG: listening on IPv6 address "::", port 54322259server # [ 308.203125] postgres[2737]: 2026-10-07 00:06:57.673 UTC [2737] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"2260server # [ 308.274769] postgres[2742]: 2026-10-07 00:06:57.741 UTC [2742] LOG: database system was shut down at 2026-10-07 00:06:54 UTC2261server # [ 308.403689] postgres[2737]: 2026-10-07 00:06:57.861 UTC [2737] LOG: database system is ready to accept connections2262server # [ 308.423619] systemd[1]: Started PostgreSQL Database Server.2263server # [ 308.490166] systemd[1]: Starting Supabase Database Initialization...2264server # [ 308.571353] postgres[2746]: 2026-10-07 00:06:58.028 UTC [2746] LOG: pg_cron scheduler started2265server # [ 308.763880] systemd[1]: Finished Supabase Database Initialization.2266server # the following new units were started: postgresql-migrate.service, sys-devices-virtual-input-mice.device, sys-devices-virtual-misc-rfkill.device, sysinit-reactivation.target, systemd-rfkill.socket, systemd-tmpfiles-resetup.service2267server # [ 309.071377] nixos[1676]: finished switching to system configuration /nix/store/3bn35xzw0zfj21qn4wpl67p7d27h2786-nixos-system-server-test2268server: waiting for unit postgresql.service2269server: (finished: waiting for unit postgresql.service, in 0.47 seconds)2270(finished: subtest: switch to postgresql 17, in 131.63 seconds)2271subtest: amcheck recreated fresh on PG 17, mirroring the EXTENSIONS_TO_DISABLE fix2272server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension if not exists amcheck cascade"2273server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create extension if not exists amcheck cascade", in 1.18 seconds)2274server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select default_version from pg_available_extensions where name = 'amcheck'"2275server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select default_version from pg_available_extensions where name = 'amcheck'", in 0.88 seconds)2276server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'"2277server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select extversion, extnamespace::regnamespace from pg_extension where extname = 'amcheck'", in 0.40 seconds)2278server: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname"2279server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select r.rolname, bool_and(has_function_privilege(r.rolname, p.oid, 'execute')), bool_or(has_function_privilege(r.rolname, p.oid, 'execute')) from pg_proc p join pg_depend d on d.objid = p.oid and d.deptype = 'e' join pg_extension e on e.oid = d.refobjid and e.extname = 'amcheck' cross join (values ('postgres'), ('anon'), ('authenticated'), ('service_role')) as r(rolname) group by r.rolname order by r.rolname", in 0.41 seconds)2280(finished: subtest: amcheck recreated fresh on PG 17, mirroring the EXTENSIONS_TO_DISABLE fix, in 2.86 seconds)2281subtest: The support workflow still works after the upgrade2282server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create table amcheck_heap(i int primary key) using heap; insert into amcheck_heap select generate_series(1, 100)"2283server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "create table amcheck_heap(i int primary key) using heap; insert into amcheck_heap select generate_series(1, 100)", in 0.42 seconds)2284server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass)"2285server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass)", in 0.42 seconds)2286server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass, false, false)"2287server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('amcheck_heap_pkey'::regclass, false, false)", in 0.44 seconds)2288server: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('auth.users_pkey'::regclass)"2289server: (finished: must succeed: PGOPTIONS="-c role=postgres" psql -U supabase_admin -d postgres -t -A -F, -v ON_ERROR_STOP=1 -c "select pg_catalog.bt_index_check('auth.users_pkey'::regclass)", in 0.44 seconds)2290(finished: subtest: The support workflow still works after the upgrade, in 1.71 seconds)2291(finished: run the VM test script, in 316.13 seconds)2292test script finished in 316.54s2293cleanup2294kill QemuMachine (pid 13)2295server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)2296(finished: cleanup, in 0.09 seconds)