1these 20 derivations will be built:2 /nix/store/qshm65kv1b6rsgxbghkk0hcwy1ba7pnq-unit-postgresql.service.drv3 /nix/store/27nq2gkaf346snayfgh2ww887wk3sgka-system-units.drv4 /nix/store/52bmiwdi5hn9kcq1i73jzmbwjadmmdis-etc.drv5 /nix/store/qjqnywww4c77bzq4x0rbh604qlms9c9x-activate.drv6 /nix/store/0374m29ryz823dkb33nr5iqppngfj436-nixos-system-server-test.drv7 /nix/store/rkh2x64ai095gma3z5dlx96dhvfcasq1-unit-postgresql.service.drv8 /nix/store/xgsj18569ayv6072qka7hmj7xq913qbr-system-units.drv9 /nix/store/fpg8abrjwjkf8jxgywc0sjn04nmf82m7-etc.drv10 /nix/store/si88d6zqy2wjhdb6n1qigvb8im96n61y-activate.drv11 /nix/store/8q13hp0syhz7ip89mp1hqp2izk729iz6-unit-postgresql.service.drv12 /nix/store/8gg2a2jgsn843h2xyy2glawp881502l2-system-units.drv13 /nix/store/nxsvx1xk3wnc7blz7mxnkhsynpp5hvdh-etc.drv14 /nix/store/aqj5jqg8pf2hkxz7czwcx34hfpdvy3fa-activate.drv15 /nix/store/vc56hvhf79svakpv4p37rk2lnbdgw8gi-nixos-system-server-test.drv16 /nix/store/68kl110zq6jqci995r841hl52k7c3d6b-nixos-system-server-test.drv17 /nix/store/cwpd0v9hsnihj1bj9hmvkwq4aiysn0xv-closure-info.drv18 /nix/store/7pifn41zydp38qysrmk58sfn86f5l8sa-run-nixos-vm.drv19 /nix/store/582akggrs3q1n263bp3c9kpib0nh9j15-nixos-vm.drv20 /nix/store/ybncp4vj8bvsq4x2bpc0b3hxgp8mwxmy-nixos-test-driver-http.drv21 /nix/store/hna0s4a3h8nzdlhv5ns4plslsz8nqm4v-vm-test-run-http.drv22building '/nix/store/rkh2x64ai095gma3z5dlx96dhvfcasq1-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'23building '/nix/store/8q13hp0syhz7ip89mp1hqp2izk729iz6-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'24building '/nix/store/qshm65kv1b6rsgxbghkk0hcwy1ba7pnq-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'25building '/nix/store/8q13hp0syhz7ip89mp1hqp2izk729iz6-unit-postgresql.service.drv'26building '/nix/store/rkh2x64ai095gma3z5dlx96dhvfcasq1-unit-postgresql.service.drv'27building '/nix/store/qshm65kv1b6rsgxbghkk0hcwy1ba7pnq-unit-postgresql.service.drv'28building '/nix/store/xgsj18569ayv6072qka7hmj7xq913qbr-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'29building '/nix/store/27nq2gkaf346snayfgh2ww887wk3sgka-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'30building '/nix/store/8gg2a2jgsn843h2xyy2glawp881502l2-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'31building '/nix/store/27nq2gkaf346snayfgh2ww887wk3sgka-system-units.drv'32building '/nix/store/xgsj18569ayv6072qka7hmj7xq913qbr-system-units.drv'33building '/nix/store/8gg2a2jgsn843h2xyy2glawp881502l2-system-units.drv'34building '/nix/store/fpg8abrjwjkf8jxgywc0sjn04nmf82m7-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'35building '/nix/store/fpg8abrjwjkf8jxgywc0sjn04nmf82m7-etc.drv'36building '/nix/store/nxsvx1xk3wnc7blz7mxnkhsynpp5hvdh-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'37building '/nix/store/nxsvx1xk3wnc7blz7mxnkhsynpp5hvdh-etc.drv'38building '/nix/store/aqj5jqg8pf2hkxz7czwcx34hfpdvy3fa-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'39building '/nix/store/vc56hvhf79svakpv4p37rk2lnbdgw8gi-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'40building '/nix/store/vc56hvhf79svakpv4p37rk2lnbdgw8gi-nixos-system-server-test.drv'41building '/nix/store/si88d6zqy2wjhdb6n1qigvb8im96n61y-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'42building '/nix/store/52bmiwdi5hn9kcq1i73jzmbwjadmmdis-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'43building '/nix/store/52bmiwdi5hn9kcq1i73jzmbwjadmmdis-etc.drv'44building '/nix/store/qjqnywww4c77bzq4x0rbh604qlms9c9x-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'45building '/nix/store/0374m29ryz823dkb33nr5iqppngfj436-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'46building '/nix/store/0374m29ryz823dkb33nr5iqppngfj436-nixos-system-server-test.drv'47building '/nix/store/68kl110zq6jqci995r841hl52k7c3d6b-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'48building '/nix/store/68kl110zq6jqci995r841hl52k7c3d6b-nixos-system-server-test.drv'49building '/nix/store/cwpd0v9hsnihj1bj9hmvkwq4aiysn0xv-closure-info.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'50building '/nix/store/7pifn41zydp38qysrmk58sfn86f5l8sa-run-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'51building '/nix/store/7pifn41zydp38qysrmk58sfn86f5l8sa-run-nixos-vm.drv'52building '/nix/store/582akggrs3q1n263bp3c9kpib0nh9j15-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'53building '/nix/store/ybncp4vj8bvsq4x2bpc0b3hxgp8mwxmy-nixos-test-driver-http.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247'54building '/nix/store/hna0s4a3h8nzdlhv5ns4plslsz8nqm4v-vm-test-run-http.drv' on 'ssh-ng://builder@mac-linux-builder'55building '/nix/store/hna0s4a3h8nzdlhv5ns4plslsz8nqm4v-vm-test-run-http.drv'56vm-test-run-http> Machine state will be reset. To keep it, pass --keep-vm-state57vm-test-run-http> start all VLans58vm-test-run-http> start vlan59vm-test-run-http> running vlan (pid 7; ctl /build/vde1.ctl)60vm-test-run-http> (finished: start all VLans, in 0.00 seconds)61vm-test-run-http> Test will time out and terminate in 3600 seconds62vm-test-run-http> run the VM test script63vm-test-run-http> additionally exposed symbols:64vm-test-run-http> server,65vm-test-run-http> vlan1,66vm-test-run-http> start_all, test_script, machines, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, Machine, t, debug67vm-test-run-http> start all VMs68vm-test-run-http> server: starting vm69vm-test-run-http> mke2fs 1.47.3 (8-Jul-2025)70vm-test-run-http> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory71vm-test-run-http> qemu-system-aarch64: failed to initialize kvm: No such file or directory72vm-test-run-http> qemu-system-aarch64: falling back to tcg73vm-test-run-http> server # Disk image does not exist, creating the virtualisation disk image...74vm-test-run-http> server # Formatting '/build/vm-state-server/tmp.kYkSNY6Vpl', fmt=raw size=107374182475vm-test-run-http> server # Discarding device blocks: 0/262144 done76vm-test-run-http> server # Creating filesystem with 262144 4k blocks and 65536 inodes77vm-test-run-http> server # Filesystem UUID: d8e30196-c62f-434a-8bba-e4653f0f09d978vm-test-run-http> server # Superblock backups stored on blocks:79vm-test-run-http> server: QEMU running (pid 9)80vm-test-run-http> server # 32768, 98304, 163840, 22937681vm-test-run-http> server # 82vm-test-run-http> server # Allocating group tables: 0/8 done83vm-test-run-http> server # Writing inode tables: 0/8 done84vm-test-run-http> server # Creating journal (8192 blocks): done85vm-test-run-http> server # Writing superblocks and filesystem accounting information: 0/8 done86vm-test-run-http> server # 87vm-test-run-http> server # Virtualisation disk image created.88vm-test-run-http> (finished: start all VMs, in 0.18 seconds)89vm-test-run-http> server: waiting for unit supabase-db-init.service90vm-test-run-http> server: waiting for the VM to finish booting91vm-test-run-http> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]92vm-test-run-http> server # [ 0.000000] Linux version 6.12.63 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.44) #1-NixOS SMP Thu Dec 18 12:55:23 UTC 202593vm-test-run-http> server # [ 0.000000] KASLR enabled94vm-test-run-http> server # [ 0.000000] random: crng init done95vm-test-run-http> server # [ 0.000000] Machine model: linux,dummy-virt96vm-test-run-http> server # [ 0.000000] efi: UEFI not found.97vm-test-run-http> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT98vm-test-run-http> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]99vm-test-run-http> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f]100vm-test-run-http> server # [ 0.000000] Zone ranges:101vm-test-run-http> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff]102vm-test-run-http> server # [ 0.000000] DMA32 empty103vm-test-run-http> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff]104vm-test-run-http> server # [ 0.000000] Device empty105vm-test-run-http> server # [ 0.000000] Movable zone start for each node106vm-test-run-http> server # [ 0.000000] Early memory node ranges107vm-test-run-http> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff]108vm-test-run-http> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]109vm-test-run-http> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1110vm-test-run-http> server # [ 0.000000] psci: probing for conduit method from DT.111vm-test-run-http> server # [ 0.000000] psci: PSCIv1.1 detected in firmware.112vm-test-run-http> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs113vm-test-run-http> server # [ 0.000000] psci: Trusted OS migration not required114vm-test-run-http> server # [ 0.000000] psci: SMC Calling Convention v1.0115vm-test-run-http> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448116vm-test-run-http> server # [ 0.000000] Detected PIPT I-cache on CPU0117vm-test-run-http> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)118vm-test-run-http> server # [ 0.000000] CPU features: detected: GIC system register CPU interface119vm-test-run-http> server # [ 0.000000] CPU features: detected: HCRX_EL2 register120vm-test-run-http> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)121vm-test-run-http> server # [ 0.000000] CPU features: detected: Spectre-v4122vm-test-run-http> server # [ 0.000000] alternatives: applying boot alternatives123vm-test-run-http> server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/g53is3x0c1mybrlfhqf0miwhfqvcpa6z-nixos-system-server-test/init regInfo=/nix/store/drivzxkzi7iq7vgzcqy3licfk72gkssk-closure-info/registration console=ttyAMA0,115200n8 console=tty0124vm-test-run-http> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/drivzxkzi7iq7vgzcqy3licfk72gkssk-closure-info/registration", will be passed to user space.125vm-test-run-http> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)126vm-test-run-http> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)127vm-test-run-http> server # [ 0.000000] Fallback order for Node 0: 0128vm-test-run-http> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576129vm-test-run-http> server # [ 0.000000] Policy zone: Normal130vm-test-run-http> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off131vm-test-run-http> server # [ 0.000000] software IO TLB: area num 2.132vm-test-run-http> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)133vm-test-run-http> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1134vm-test-run-http> server # [ 0.000000] allocated 8388608 bytes of page_ext135vm-test-run-http> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages136vm-test-run-http> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups137vm-test-run-http> server # [ 0.000000] rcu: Hierarchical RCU implementation.138vm-test-run-http> server # [ 0.000000] rcu: RCU event tracing is enabled.139vm-test-run-http> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2.140vm-test-run-http> server # [ 0.000000] Rude variant of Tasks RCU enabled.141vm-test-run-http> server # [ 0.000000] Tracing variant of Tasks RCU enabled.142vm-test-run-http> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.143vm-test-run-http> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2144vm-test-run-http> server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.145vm-test-run-http> server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.146vm-test-run-http> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0147vm-test-run-http> server # [ 0.000000] GICv3: 256 SPIs implemented148vm-test-run-http> server # [ 0.000000] GICv3: 0 Extended SPIs implemented149vm-test-run-http> server # [ 0.000000] Root IRQ handler: gic_handle_irq150vm-test-run-http> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs151vm-test-run-http> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0152vm-test-run-http> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000153vm-test-run-http> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]154vm-test-run-http> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ad0000 (indirect, esz 8, psz 64K, shr 1)155vm-test-run-http> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100ae0000 (flat, esz 8, psz 64K, shr 1)156vm-test-run-http> server # [ 0.000000] GICv3: using LPI property table @0x0000000100b00000157vm-test-run-http> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000158vm-test-run-http> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.159vm-test-run-http> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt).160vm-test-run-http> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns161vm-test-run-http> server # [ 0.000039] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns162vm-test-run-http> server # [ 0.003783] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)163vm-test-run-http> server # [ 0.014522] Console: colour dummy device 80x25164vm-test-run-http> server # [ 0.016166] printk: legacy console [tty0] enabled165vm-test-run-http> server # [ 0.021757] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)166vm-test-run-http> server # [ 0.022153] pid_max: default: 32768 minimum: 301167vm-test-run-http> server # [ 0.024039] LSM: initializing lsm=capability,landlock,yama,bpf168vm-test-run-http> server # [ 0.025821] landlock: Up and running.169vm-test-run-http> server # [ 0.025864] Yama: becoming mindful.170vm-test-run-http> server # [ 0.029073] LSM support for eBPF active171vm-test-run-http> server # [ 0.031908] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)172vm-test-run-http> server # [ 0.032962] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)173vm-test-run-http> server # [ 0.063191] cacheinfo: Unable to detect cache hierarchy for CPU 0174vm-test-run-http> server # [ 0.074218] rcu: Hierarchical SRCU implementation.175vm-test-run-http> server # [ 0.074307] rcu: Max phase no-delay instances is 1000.176vm-test-run-http> server # [ 0.075705] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level177vm-test-run-http> server # [ 0.083054] fsl-mc MSI: its@8080000 domain created178vm-test-run-http> server # [ 0.085751] EFI services will not be available.179vm-test-run-http> server # [ 0.087335] smp: Bringing up secondary CPUs ...180vm-test-run-http> server # [ 0.092484] Detected PIPT I-cache on CPU1181vm-test-run-http> server # [ 0.093759] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000182vm-test-run-http> server # [ 0.094466] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000183vm-test-run-http> server # [ 0.096022] CPU1: Booted secondary processor 0x0000000001 [0x000f0510]184vm-test-run-http> server # [ 0.099226] smp: Brought up 1 node, 2 CPUs185vm-test-run-http> server # [ 0.099368] SMP: Total of 2 processors activated.186vm-test-run-http> server # [ 0.099414] CPU: All CPU(s) started at EL1187vm-test-run-http> server # [ 0.099575] CPU features: detected: Branch Target Identification188vm-test-run-http> server # [ 0.099627] CPU features: detected: 32-bit EL0 Support189vm-test-run-http> server # [ 0.099663] CPU features: detected: 32-bit EL1 Support190vm-test-run-http> server # [ 0.099691] CPU features: detected: ARMv8.4 Translation Table Level191vm-test-run-http> server # [ 0.099724] CPU features: detected: Instruction cache invalidation not required for I/D coherence192vm-test-run-http> server # [ 0.099760] CPU features: detected: Data cache clean to the PoU not required for I/D coherence193vm-test-run-http> server # [ 0.099826] CPU features: detected: Common not Private translations194vm-test-run-http> server # [ 0.099848] CPU features: detected: CRC32 instructions195vm-test-run-http> server # [ 0.099872] CPU features: detected: Data cache clean to Point of Deep Persistence196vm-test-run-http> server # [ 0.099903] CPU features: detected: Data cache clean to Point of Persistence197vm-test-run-http> server # [ 0.099930] CPU features: detected: Data independent timing control (DIT)198vm-test-run-http> server # [ 0.099956] CPU features: detected: E0PD199vm-test-run-http> server # [ 0.099978] CPU features: detected: Enhanced Counter Virtualization200vm-test-run-http> server # [ 0.100003] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)201vm-test-run-http> server # [ 0.100031] CPU features: detected: Enhanced Privileged Access Never202vm-test-run-http> server # [ 0.100056] CPU features: detected: Enhanced Virtualization Traps203vm-test-run-http> server # [ 0.100083] CPU features: detected: Fine Grained Traps204vm-test-run-http> server # [ 0.100312] CPU features: detected: Generic authentication (IMP DEF algorithm)205vm-test-run-http> server # [ 0.100419] CPU features: detected: RCpc load-acquire (LDAPR)206vm-test-run-http> server # [ 0.100472] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)207vm-test-run-http> server # [ 0.100502] CPU features: detected: LSE atomic instructions208vm-test-run-http> server # [ 0.100528] CPU features: detected: Memory Copy and Memory Set instructions209vm-test-run-http> server # [ 0.100556] CPU features: detected: Privileged Access Never210vm-test-run-http> server # [ 0.100582] CPU features: detected: RAS Extension Support211vm-test-run-http> server # [ 0.100607] CPU features: detected: Random Number Generator212vm-test-run-http> server # [ 0.100631] CPU features: detected: Speculation barrier (SB)213vm-test-run-http> server # [ 0.100656] CPU features: detected: Stage-2 Force Write-Back214vm-test-run-http> server # [ 0.100679] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality215vm-test-run-http> server # [ 0.100708] CPU features: detected: TLB range maintenance instructions216vm-test-run-http> server # [ 0.100733] CPU features: detected: WFx with timeout217vm-test-run-http> server # [ 0.100779] CPU features: detected: Speculative Store Bypassing Safe (SSBS)218vm-test-run-http> server # [ 0.100966] CPU features: detected: Scalable Vector Extension219vm-test-run-http> server # [ 0.108249] alternatives: applying system-wide alternatives220vm-test-run-http> server # [ 0.124917] CPU features: detected: Hardware dirty bit management on CPU0-1221vm-test-run-http> server # [ 0.125710] SVE: maximum available vector length 256 bytes per vector222vm-test-run-http> server # [ 0.125811] SVE: default vector length 64 bytes per vector223vm-test-run-http> server # [ 0.140599] Memory: 3922864K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved)224vm-test-run-http> server # [ 0.164376] devtmpfs: initialized225vm-test-run-http> server # [ 0.191804] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns226vm-test-run-http> server # [ 0.192302] futex hash table entries: 512 (order: 3, 32768 bytes, linear)227vm-test-run-http> server # [ 0.194764] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL228vm-test-run-http> server # [ 0.194823] 0 pages in range for non-PLT usage229vm-test-run-http> server # [ 0.194865] 509248 pages in range for PLT usage230vm-test-run-http> server # [ 0.197329] pinctrl core: initialized pinctrl subsystem231vm-test-run-http> server # [ 0.208205] DMI not present or invalid.232vm-test-run-http> server # [ 0.227152] NET: Registered PF_NETLINK/PF_ROUTE protocol family233vm-test-run-http> server # [ 0.240120] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations234vm-test-run-http> server # [ 0.241172] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations235vm-test-run-http> server # [ 0.242370] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations236vm-test-run-http> server # [ 0.242651] audit: initializing netlink subsys (disabled)237vm-test-run-http> server # [ 0.244942] audit: type=2000 audit(0.180:1): state=initialized audit_enabled=0 res=1238vm-test-run-http> server # [ 0.250852] thermal_sys: Registered thermal governor 'fair_share'239vm-test-run-http> server # [ 0.250904] thermal_sys: Registered thermal governor 'bang_bang'240vm-test-run-http> server # [ 0.250958] thermal_sys: Registered thermal governor 'step_wise'241vm-test-run-http> server # [ 0.250988] thermal_sys: Registered thermal governor 'user_space'242vm-test-run-http> server # [ 0.251015] thermal_sys: Registered thermal governor 'power_allocator'243vm-test-run-http> server # [ 0.251688] cpuidle: using governor ladder244vm-test-run-http> server # [ 0.252071] cpuidle: using governor menu245vm-test-run-http> server # [ 0.254424] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.246vm-test-run-http> server # [ 0.255187] ASID allocator initialised with 65536 entries247vm-test-run-http> server # [ 0.262242] Serial: AMBA PL011 UART driver248vm-test-run-http> server # [ 0.313095] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1249vm-test-run-http> server # [ 0.314984] printk: legacy console [ttyAMA0] enabled250vm-test-run-http> server # [ 0.355219] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages251vm-test-run-http> server # [ 0.355717] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page252vm-test-run-http> server # [ 0.355852] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages253vm-test-run-http> server # [ 0.355950] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page254vm-test-run-http> server # [ 0.356039] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages255vm-test-run-http> server # [ 0.356139] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page256vm-test-run-http> server # [ 0.356227] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages257vm-test-run-http> server # [ 0.356316] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page258vm-test-run-http> server # [ 0.367910] fbcon: Taking over console259vm-test-run-http> server # [ 0.368499] ACPI: Interpreter disabled.260vm-test-run-http> server # [ 0.375977] iommu: Default domain type: Translated261vm-test-run-http> server # [ 0.376108] iommu: DMA domain TLB invalidation policy: strict mode262vm-test-run-http> server # [ 0.379028] SCSI subsystem initialized263vm-test-run-http> server # [ 0.381280] usbcore: registered new interface driver usbfs264vm-test-run-http> server # [ 0.381834] usbcore: registered new interface driver hub265vm-test-run-http> server # [ 0.382681] usbcore: registered new device driver usb266vm-test-run-http> server # [ 0.385143] pps_core: LinuxPPS API ver. 1 registered267vm-test-run-http> server # [ 0.385447] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>268vm-test-run-http> server # [ 0.385831] PTP clock support registered269vm-test-run-http> server # [ 0.386256] EDAC MC: Ver: 3.0.0270vm-test-run-http> server # [ 0.387870] scmi_core: SCMI protocol bus registered271vm-test-run-http> server # [ 0.390618] FPGA manager framework272vm-test-run-http> server # [ 0.392274] Advanced Linux Sound Architecture Driver Initialized.273vm-test-run-http> server # [ 0.399568] vgaarb: loaded274vm-test-run-http> server # [ 0.404360] clocksource: Switched to clocksource arch_sys_counter275vm-test-run-http> server # [ 0.408486] VFS: Disk quotas dquot_6.6.0276vm-test-run-http> server # [ 0.410036] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)277vm-test-run-http> server # [ 0.412732] netfs: FS-Cache loaded278vm-test-run-http> server # [ 0.414059] pnp: PnP ACPI: disabled279vm-test-run-http> server # [ 0.449833] NET: Registered PF_INET protocol family280vm-test-run-http> server # [ 0.450942] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)281vm-test-run-http> server # [ 0.560372] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)282vm-test-run-http> server # [ 0.561196] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)283vm-test-run-http> server # [ 0.562185] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)284vm-test-run-http> server # [ 0.563043] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)285vm-test-run-http> server # [ 0.564014] TCP: Hash tables configured (established 32768 bind 32768)286vm-test-run-http> server # [ 0.565848] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)287vm-test-run-http> server # [ 0.566533] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)288vm-test-run-http> server # [ 0.566978] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)289vm-test-run-http> server # [ 0.568483] NET: Registered PF_UNIX/PF_LOCAL protocol family290vm-test-run-http> server # [ 0.568957] NET: Registered PF_XDP protocol family291vm-test-run-http> server # [ 0.569408] PCI: CLS 0 bytes, default 64292vm-test-run-http> server # [ 0.571174] kvm [1]: HYP mode not available293vm-test-run-http> server # [ 0.573317] Trying to unpack rootfs image as initramfs...294vm-test-run-http> server # [ 0.668688] Initialise system trusted keyrings295vm-test-run-http> server # [ 0.670591] workingset: timestamp_bits=42 max_order=20 bucket_order=0296vm-test-run-http> server # [ 0.674347] squashfs: version 4.0 (2009/01/31) Phillip Lougher297vm-test-run-http> server # [ 0.675356] 9p: Installing v9fs 9p2000 file system support298vm-test-run-http> server # [ 0.735697] Key type asymmetric registered299vm-test-run-http> server # [ 0.736126] Asymmetric key parser 'x509' registered300vm-test-run-http> server # [ 0.737765] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)301vm-test-run-http> server # [ 0.740121] io scheduler mq-deadline registered302vm-test-run-http> server # [ 0.740431] io scheduler kyber registered303vm-test-run-http> server # [ 0.767001] pl061_gpio 9030000.pl061: PL061 GPIO chip registered304vm-test-run-http> server # [ 0.771331] ledtrig-cpu: registered to indicate activity on CPUs305vm-test-run-http> server # [ 0.774915] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:306vm-test-run-http> server # [ 0.775588] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000307vm-test-run-http> server # [ 0.776193] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000308vm-test-run-http> server # [ 0.776820] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000309vm-test-run-http> server # [ 0.777370] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits310vm-test-run-http> server # [ 0.777941] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]311vm-test-run-http> server # [ 0.779324] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00312vm-test-run-http> server # [ 0.779684] pci_bus 0000:00: root bus resource [bus 00-ff]313vm-test-run-http> server # [ 0.779844] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]314vm-test-run-http> server # [ 0.779970] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]315vm-test-run-http> server # [ 0.780135] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]316vm-test-run-http> server # [ 0.782006] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint317vm-test-run-http> server # [ 0.785601] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint318vm-test-run-http> server # [ 0.785942] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]319vm-test-run-http> server # [ 0.786385] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]320vm-test-run-http> server # [ 0.786561] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]321vm-test-run-http> server # [ 0.786717] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]322vm-test-run-http> server # [ 0.787291] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint323vm-test-run-http> server # [ 0.787537] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]324vm-test-run-http> server # [ 0.787641] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]325vm-test-run-http> server # [ 0.787909] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]326vm-test-run-http> server # [ 0.789405] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint327vm-test-run-http> server # [ 0.789785] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]328vm-test-run-http> server # [ 0.790538] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]329vm-test-run-http> server # [ 0.790862] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]330vm-test-run-http> server # [ 0.791724] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint331vm-test-run-http> server # [ 0.792306] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]332vm-test-run-http> server # [ 0.792484] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]333vm-test-run-http> server # [ 0.792607] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]334vm-test-run-http> server # [ 0.793896] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint335vm-test-run-http> server # [ 0.794071] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]336vm-test-run-http> server # [ 0.794178] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]337vm-test-run-http> server # [ 0.794293] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]338vm-test-run-http> server # [ 0.794815] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint339vm-test-run-http> server # [ 0.794973] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]340vm-test-run-http> server # [ 0.795075] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]341vm-test-run-http> server # [ 0.795186] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]342vm-test-run-http> server # [ 0.795657] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint343vm-test-run-http> server # [ 0.795822] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]344vm-test-run-http> server # [ 0.795927] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]345vm-test-run-http> server # [ 0.796044] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]346vm-test-run-http> server # [ 0.796618] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]347vm-test-run-http> server # [ 0.797781] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint348vm-test-run-http> server # [ 0.798102] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]349vm-test-run-http> server # [ 0.798227] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]350vm-test-run-http> server # [ 0.798750] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint351vm-test-run-http> server # [ 0.798919] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]352vm-test-run-http> server # [ 0.799042] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]353vm-test-run-http> server # [ 0.799592] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint354vm-test-run-http> server # [ 0.799740] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]355vm-test-run-http> server # [ 0.800153] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint356vm-test-run-http> server # [ 0.800785] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]357vm-test-run-http> server # [ 0.801333] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]358vm-test-run-http> server # [ 0.801709] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]359vm-test-run-http> server # [ 0.802224] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint360vm-test-run-http> server # [ 0.802375] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]361vm-test-run-http> server # [ 0.802466] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]362vm-test-run-http> server # [ 0.802577] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]363vm-test-run-http> server # [ 0.804646] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned364vm-test-run-http> server # [ 0.805011] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned365vm-test-run-http> server # [ 0.805214] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned366vm-test-run-http> server # [ 0.805447] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned367vm-test-run-http> server # [ 0.806170] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned368vm-test-run-http> server # [ 0.806484] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned369vm-test-run-http> server # [ 0.806644] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned370vm-test-run-http> server # [ 0.806795] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned371vm-test-run-http> server # [ 0.807114] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned372vm-test-run-http> server # [ 0.807268] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned373vm-test-run-http> server # [ 0.807414] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned374vm-test-run-http> server # [ 0.807557] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned375vm-test-run-http> server # [ 0.807718] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned376vm-test-run-http> server # [ 0.808315] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned377vm-test-run-http> server # [ 0.809221] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned378vm-test-run-http> server # [ 0.809931] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned379vm-test-run-http> server # [ 0.810055] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned380vm-test-run-http> server # [ 0.810172] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned381vm-test-run-http> server # [ 0.810288] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned382vm-test-run-http> server # [ 0.810402] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned383vm-test-run-http> server # [ 0.810517] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned384vm-test-run-http> server # [ 0.810695] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned385vm-test-run-http> server # [ 0.811073] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned386vm-test-run-http> server # [ 0.811437] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned387vm-test-run-http> server # [ 0.811559] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned388vm-test-run-http> server # [ 0.811684] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned389vm-test-run-http> server # [ 0.811809] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned390vm-test-run-http> server # [ 0.811914] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned391vm-test-run-http> server # [ 0.812020] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned392vm-test-run-http> server # [ 0.812126] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned393vm-test-run-http> server # [ 0.812230] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned394vm-test-run-http> server # [ 0.812331] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned395vm-test-run-http> server # [ 0.812435] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned396vm-test-run-http> server # [ 0.812538] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned397vm-test-run-http> server # [ 0.813326] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]398vm-test-run-http> server # [ 0.813648] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]399vm-test-run-http> server # [ 0.813767] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]400vm-test-run-http> server # [ 0.814700] pci 0000:00:0a.0: enabling device (0000 -> 0002)401vm-test-run-http> server # [ 0.874009] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)402vm-test-run-http> server # [ 0.878532] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)403vm-test-run-http> server # [ 0.881836] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)404vm-test-run-http> server # [ 0.886369] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)405vm-test-run-http> server # [ 0.888271] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)406vm-test-run-http> server # [ 0.894268] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)407vm-test-run-http> server # [ 0.897079] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)408vm-test-run-http> server # [ 0.901798] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)409vm-test-run-http> server # [ 0.903413] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)410vm-test-run-http> server # [ 0.907964] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)411vm-test-run-http> server # [ 0.913284] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)412vm-test-run-http> server # [ 0.932488] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled413vm-test-run-http> server # [ 0.941678] msm_serial: driver initialized414vm-test-run-http> server # [ 0.943065] SuperH (H)SCI(F) driver initialized415vm-test-run-http> server # [ 0.943541] STM32 USART driver initialized416vm-test-run-http> server # [ 0.988040] loop: module loaded417vm-test-run-http> server # [ 0.989337] virtio_blk virtio5: 2/0/0 default/read/poll queues418vm-test-run-http> server # [ 0.992842] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)419vm-test-run-http> server # [ 1.004421] megasas: 07.727.03.00-rc1420vm-test-run-http> server # [ 1.010470] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]421vm-test-run-http> server # [ 1.013269] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000422vm-test-run-http> server # [ 1.013901] Intel/Sharp Extended Query Table at 0x0031423vm-test-run-http> server # [ 1.015708] Using buffer write method424vm-test-run-http> server # [ 1.016298] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]425vm-test-run-http> server # [ 1.018900] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000426vm-test-run-http> server # [ 1.019369] Intel/Sharp Extended Query Table at 0x0031427vm-test-run-http> server # [ 1.021397] Using buffer write method428vm-test-run-http> server # [ 1.021777] Concatenating MTD devices:429vm-test-run-http> server # [ 1.022161] (0): "0.flash"430vm-test-run-http> server # [ 1.022500] (1): "0.flash"431vm-test-run-http> server # [ 1.022599] into device "0.flash"432vm-test-run-http> server # [ 1.023023] Freeing initrd memory: 10684K433vm-test-run-http> server # [ 1.044722] tun: Universal TUN/TAP device driver, 1.6434vm-test-run-http> server # [ 1.059091] thunder_xcv, ver 1.0435vm-test-run-http> server # [ 1.059315] thunder_bgx, ver 1.0436vm-test-run-http> server # [ 1.059520] nicpf, ver 1.0437vm-test-run-http> server # [ 1.063595] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version438vm-test-run-http> server # [ 1.063795] hns3: Copyright (c) 2017 Huawei Corporation.439vm-test-run-http> server # [ 1.064150] hclge is initializing440vm-test-run-http> server # [ 1.065392] e1000: Intel(R) PRO/1000 Network Driver441vm-test-run-http> server # [ 1.065921] e1000: Copyright (c) 1999-2006 Intel Corporation.442vm-test-run-http> server # [ 1.066154] e1000e: Intel(R) PRO/1000 Network Driver443vm-test-run-http> server # [ 1.066248] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.444vm-test-run-http> server # [ 1.066757] igb: Intel(R) Gigabit Ethernet Network Driver445vm-test-run-http> server # [ 1.067192] igb: Copyright (c) 2007-2014 Intel Corporation.446vm-test-run-http> server # [ 1.067881] igbvf: Intel(R) Gigabit Virtual Function Network Driver447vm-test-run-http> server # [ 1.068149] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.448vm-test-run-http> server # [ 1.069864] sky2: driver version 1.30449vm-test-run-http> server # [ 1.078336] ehci-pci 0000:00:0a.0: EHCI Host Controller450vm-test-run-http> server # [ 1.079660] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1451vm-test-run-http> server # [ 1.080137] usbcore: registered new interface driver usb-storage452vm-test-run-http> server # [ 1.081793] usbcore: registered new interface driver usbserial_generic453vm-test-run-http> server # [ 1.082096] usbserial: USB Serial support registered for generic454vm-test-run-http> server # [ 1.083179] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000455vm-test-run-http> server # [ 1.091499] rtc-pl031 9010000.pl031: registered as rtc0456vm-test-run-http> server # [ 1.092241] rtc-pl031 9010000.pl031: setting system clock to 2026-10-07T20:37:23 UTC (1791405443)457vm-test-run-http> server # [ 1.093692] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00458vm-test-run-http> server # [ 1.096026] i2c_dev: i2c /dev entries driver459vm-test-run-http> server # [ 1.100562] hub 1-0:1.0: USB hub found460vm-test-run-http> server # [ 1.101127] hub 1-0:1.0: 6 ports detected461vm-test-run-http> server # [ 1.110203] sdhci: Secure Digital Host Controller Interface driver462vm-test-run-http> server # [ 1.110762] sdhci: Copyright(c) Pierre Ossman463vm-test-run-http> server # [ 1.112830] Synopsys Designware Multimedia Card Interface Driver464vm-test-run-http> server # [ 1.115526] sdhci-pltfm: SDHCI platform and OF driver helper465vm-test-run-http> server # [ 1.122880] hid: raw HID events driver (C) Jiri Kosina466vm-test-run-http> server # [ 1.125629] usbcore: registered new interface driver usbhid467vm-test-run-http> server # [ 1.125785] usbhid: USB HID core driver468vm-test-run-http> server # [ 1.134264] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available469vm-test-run-http> server # [ 1.148062] drop_monitor: Initializing network drop monitor service470vm-test-run-http> server # [ 1.150051] NET: Registered PF_INET6 protocol family471vm-test-run-http> server # [ 1.168629] Segment Routing with IPv6472vm-test-run-http> server # [ 1.169417] In-situ OAM (IOAM) with IPv6473vm-test-run-http> server # [ 1.170277] NET: Registered PF_PACKET protocol family474vm-test-run-http> server # [ 1.171624] 9pnet: Installing 9P2000 support475vm-test-run-http> server # [ 1.175387] Key type dns_resolver registered476vm-test-run-http> server # [ 1.211791] registered taskstats version 1477vm-test-run-http> server # [ 1.215231] Loading compiled-in X.509 certificates478vm-test-run-http> server # [ 1.275342] Demotion targets for Node 0: null479vm-test-run-http> server # [ 1.278213] Key type .fscrypt registered480vm-test-run-http> server # [ 1.278374] Key type fscrypt-provisioning registered481vm-test-run-http> server # [ 1.288670] input: gpio-keys as /devices/platform/gpio-keys/input/input0482vm-test-run-http> server # [ 1.337064] clk: Disabling unused clocks483vm-test-run-http> server # [ 1.338066] PM: genpd: Disabling unused power domains484vm-test-run-http> server # [ 1.338597] ALSA device list:485vm-test-run-http> server # [ 1.338743] No soundcards found.486vm-test-run-http> server # [ 1.351001] usb 1-1: new high-speed USB device number 2 using ehci-pci487vm-test-run-http> server # [ 1.429029] Freeing unused kernel memory: 4416K488vm-test-run-http> server # [ 1.430754] Run /init as init process489vm-test-run-http> server # [ 1.563237] 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/input1490vm-test-run-http> server # [ 1.724243] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0491vm-test-run-http> server # [ 1.848691] usb 1-2: new high-speed USB device number 3 using ehci-pci492vm-test-run-http> server # [ 1.987996] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev493vm-test-run-http> server # [ 2.042726] 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/input2494vm-test-run-http> server # [ 2.045653] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0495vm-test-run-http> server # [ 2.127585] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0496vm-test-run-http> server # [ 2.129037] [drm] features: -virgl +edid -resource_blob -host_visible497vm-test-run-http> server # [ 2.129105] [drm] features: -context_init498vm-test-run-http> server # [ 2.131361] [drm] number of scanouts: 1499vm-test-run-http> server # [ 2.131477] [drm] number of cap sets: 0500vm-test-run-http> server # [ 2.143310] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0501vm-test-run-http> server # [ 2.178703] Console: switching to colour frame buffer device 160x50502vm-test-run-http> server # [ 2.191022] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device503vm-test-run-http> server # [ 5.037711] EXT4-fs (vda): mounted filesystem d8e30196-c62f-434a-8bba-e4653f0f09d9 r/w with ordered data mode. Quota mode: none.504vm-test-run-http> server # [ 7.493421] EXT4-fs (vda): re-mounted d8e30196-c62f-434a-8bba-e4653f0f09d9.505vm-test-run-http> server # [ 7.509711] booting system configuration /nix/store/g53is3x0c1mybrlfhqf0miwhfqvcpa6z-nixos-system-server-test506vm-test-run-http> server # [ 20.548017] systemd[1]: systemd 258.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN -IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)507vm-test-run-http> server # [ 20.559151] systemd[1]: Detected virtualization qemu.508vm-test-run-http> server # [ 20.564836] systemd[1]: Detected architecture arm64.509vm-test-run-http> server # [ 20.568408] systemd[1]: Detected first boot.510vm-test-run-http> server # [ 20.601843] systemd[1]: Initializing machine ID from random generator.511vm-test-run-http> server # [ 20.637911] systemd[1]: Hostname set to <server>.512vm-test-run-http> server # [ 21.939080] systemd[1]: bpf-restrict-fs: LSM BPF program attached513vm-test-run-http> server # [ 23.507441] systemd[1]: Applying preset policy.514vm-test-run-http> server # [ 27.589394] systemd[1]: Populated /etc with preset unit settings.515vm-test-run-http> server: Guest root shell did not produce any data yet...516vm-test-run-http> server: To debug, enter the VM and run 'systemctl status backdoor.service'.517vm-test-run-http> server # [ 31.448096] systemd[1]: Queued start job for default target Multi-User System.518vm-test-run-http> server # [ 31.683240] systemd[1]: Created slice Slice /system/getty.519vm-test-run-http> server # [ 31.699143] systemd[1]: Created slice Slice /system/modprobe.520vm-test-run-http> server # [ 31.713042] systemd[1]: Created slice User and Session Slice.521vm-test-run-http> server # [ 31.721587] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.522vm-test-run-http> server # [ 31.728013] systemd[1]: Started Forward Password Requests to Wall Directory Watch.523vm-test-run-http> server # [ 31.734508] systemd[1]: Expecting device /dev/hvc0...524vm-test-run-http> server # [ 31.740138] systemd[1]: Expecting device /dev/ttyAMA0...525vm-test-run-http> server # [ 31.745658] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1...526vm-test-run-http> server # [ 31.750262] systemd[1]: Reached target Local Encrypted Volumes.527vm-test-run-http> server # [ 31.755059] systemd[1]: Reached target Virtual Machines and Containers.528vm-test-run-http> server # [ 31.760843] systemd[1]: Reached target Path Units.529vm-test-run-http> server # [ 31.767241] systemd[1]: Reached target Remote File Systems.530vm-test-run-http> server # [ 31.772088] systemd[1]: Reached target Slice Units.531vm-test-run-http> server # [ 31.777891] systemd[1]: Reached target Swaps.532vm-test-run-http> server # [ 31.819691] systemd[1]: Listening on Process Core Dump Socket.533vm-test-run-http> server # [ 31.845955] systemd[1]: Listening on Credential Encryption/Decryption.534vm-test-run-http> server # [ 31.858545] systemd[1]: Listening on Journal Audit Socket.535vm-test-run-http> server # [ 31.864435] systemd[1]: Listening on Journal Socket (/dev/log).536vm-test-run-http> server # [ 31.871537] systemd[1]: Listening on Journal Sockets.537vm-test-run-http> server # [ 31.879891] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.538vm-test-run-http> server # [ 31.886347] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki).539vm-test-run-http> server # [ 31.893103] systemd[1]: Listening on udev Control Socket.540vm-test-run-http> server # [ 31.899788] systemd[1]: Listening on udev Kernel Socket.541vm-test-run-http> server # [ 31.949865] systemd[1]: Mounting Huge Pages File System...542vm-test-run-http> server # [ 31.992116] systemd[1]: Mounting POSIX Message Queue File System...543vm-test-run-http> server # [ 32.042951] systemd[1]: Mounting Kernel Debug File System...544vm-test-run-http> server # [ 32.093868] systemd[1]: Mounting Kernel Trace File System...545vm-test-run-http> server # [ 32.239850] systemd[1]: Starting Create List of Static Device Nodes...546vm-test-run-http> server # [ 32.387693] systemd[1]: Starting Load Kernel Module 9pnet_virtio...547vm-test-run-http> server # [ 32.420226] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs).548vm-test-run-http> server # [ 32.541810] systemd[1]: Mounting Kernel Configuration File System...549vm-test-run-http> server # [ 32.557906] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm).550vm-test-run-http> server # [ 32.564940] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore).551vm-test-run-http> server # [ 32.663321] systemd[1]: Starting Load Kernel Module fuse...552vm-test-run-http> server # [ 32.691997] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).553vm-test-run-http> server # [ 32.702094] systemd[1]: Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).554vm-test-run-http> server # [ 32.819638] systemd[1]: Starting Journal Service...555vm-test-run-http> server # [ 32.902472] systemd[1]: Starting Load Kernel Modules...556vm-test-run-http> server # [ 33.010261] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...557vm-test-run-http> server # [ 33.071297] systemd[1]: Starting Remount Root and Kernel File Systems...558vm-test-run-http> server # [ 33.078284] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).559vm-test-run-http> server # [ 33.160653] systemd[1]: Starting Coldplug All udev Devices...560vm-test-run-http> server # [ 33.251688] systemd[1]: Mounted Huge Pages File System.561vm-test-run-http> server # [ 33.270742] systemd[1]: Mounted POSIX Message Queue File System.562vm-test-run-http> server # [ 33.278815] systemd[1]: Mounted Kernel Debug File System.563vm-test-run-http> server # [ 33.292099] systemd[1]: Mounted Kernel Trace File System.564vm-test-run-http> server # [ 33.303970] systemd[1]: Mounted Kernel Configuration File System.565vm-test-run-http> server # [ 33.331682] systemd[1]: Finished Create List of Static Device Nodes.566vm-test-run-http> server # [ 33.446798] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...567vm-test-run-http> server # [ 34.114824] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.568vm-test-run-http> server # [ 34.133017] systemd[1]: Finished Load Kernel Module 9pnet_virtio.569vm-test-run-http> server # [ 34.528336] fuse: init (API version 7.41)570vm-test-run-http> server # [ 34.567927] EXT4-fs (vda): re-mounted d8e30196-c62f-434a-8bba-e4653f0f09d9.571vm-test-run-http> server # [ 34.620801] systemd[1]: Finished Load Kernel Modules.572vm-test-run-http> server # [ 34.692272] systemd[1]: Starting Firewall...573vm-test-run-http> server # [ 34.754994] systemd[1]: Starting Apply Kernel Variables...574vm-test-run-http> server # [ 34.790223] systemd[1]: modprobe@fuse.service: Deactivated successfully.575vm-test-run-http> server # [ 34.801416] systemd[1]: Finished Load Kernel Module fuse.576vm-test-run-http> server # [ 34.826975] systemd[1]: Finished Remount Root and Kernel File Systems.577vm-test-run-http> server # [ 34.883186] systemd[1]: Mounting FUSE Control File System...578vm-test-run-http> server # [ 34.893092] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).579vm-test-run-http> server # [ 34.983880] systemd[1]: Starting Load/Save OS Random Seed...580vm-test-run-http> server # [ 34.997922] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki).581vm-test-run-http> server # [ 35.194681] systemd-journald[397]: Collecting audit messages is enabled.582vm-test-run-http> server # [ 35.244414] systemd[1]: Mounted FUSE Control File System.583vm-test-run-http> server # [ 36.043565] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.584vm-test-run-http> server # [ 36.132114] systemd[1]: Starting Create Static Device Nodes in /dev...585vm-test-run-http> server # [ 36.374307] systemd[1]: Finished Load/Save OS Random Seed.586vm-test-run-http> server # [ 36.385069] systemd[1]: Reached target First Boot Complete.587vm-test-run-http> server # [ 36.690553] systemd[1]: Finished Apply Kernel Variables.588vm-test-run-http> server # [ 36.960008] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.589vm-test-run-http> server # [ 37.743344] systemd[1]: Finished Create Static Device Nodes in /dev.590vm-test-run-http> server # [ 37.768455] systemd[1]: Reached target Preparation for Local File Systems.591vm-test-run-http> server # [ 37.830185] systemd[1]: Mounting /run/wrappers...592vm-test-run-http> server # [ 37.895973] systemd[1]: Starting Rule-based Manager for Device Events and Files...593vm-test-run-http> server # [ 38.275322] systemd-modules-load[398]: Module 'atkbd' is built in[ 38.358060] systemd[1]: Started Journal Service.594vm-test-run-http> server # 595vm-test-run-http> server # [ 38.304096] systemd-modules-load[398]: Module 'loop' is built in596vm-test-run-http> server # [ 38.334792] systemd-oomd[399]: No swap; memory pressure usage will be degraded597vm-test-run-http> server # [ 38.368255] systemd[1]: Mounted /run/wrappers.598vm-test-run-http> server # [ 38.384292] systemd[1]: Reached target Local File Systems.599vm-test-run-http> server # [ 38.410010] systemd[1]: Listening on Boot Entries Service Socket.600vm-test-run-http> server # [ 38.471744] systemd[1]: Starting Create SUID/SGID Wrappers...601vm-test-run-http> server # [ 38.491711] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met.602vm-test-run-http> server # [ 38.523189] systemd[1]: Starting Flush Journal to Persistent Storage...603vm-test-run-http> server # [ 38.566267] systemd[1]: Starting Save Transient machine-id to Disk...604vm-test-run-http> server # [ 39.618308] systemd-journald[397]: Received client request to flush runtime journal.605vm-test-run-http> server # [ 40.400291] systemd-udevd[424]: Using default interface naming scheme 'v258'.606vm-test-run-http> server # [ 40.467942] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.607vm-test-run-http> server # [ 40.495956] systemd[1]: Finished Save Transient machine-id to Disk.608vm-test-run-http> server # [ 40.529245] systemd[1]: Finished Flush Journal to Persistent Storage.609vm-test-run-http> server # [ 40.543497] systemd[1]: Starting Create System Files and Directories...610vm-test-run-http> server # [ 42.575482] systemd[1]: Started Rule-based Manager for Device Events and Files.611vm-test-run-http> server # [ 42.773634] systemd[1]: Finished Create System Files and Directories.612vm-test-run-http> server # [ 42.852871] systemd[1]: Starting Rebuild Journal Catalog...613vm-test-run-http> server # [ 42.927112] systemd[1]: Starting Record System Boot/Shutdown in UTMP...614vm-test-run-http> server # [ 43.863452] systemd[1]: Finished Record System Boot/Shutdown in UTMP.615vm-test-run-http> server # [ 45.344433] systemd[1]: Finished Rebuild Journal Catalog.616vm-test-run-http> server # [ 45.421828] systemd[1]: Starting Update is Completed...617vm-test-run-http> server # [ 46.276985] systemd[1]: Finished Update is Completed.618vm-test-run-http> server # [ 50.484219] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.619vm-test-run-http> server # [ 50.497502] systemd[1]: Finished Create SUID/SGID Wrappers.620vm-test-run-http> server # [ 50.511422] systemd[1]: suid-sgid-wrappers.service: Consumed 3.285s CPU time, 5.6M memory peak.621vm-test-run-http> server # [ 53.906535] systemd[1]: Finished Firewall.622vm-test-run-http> server # [ 55.529257] systemd[1]: Finished Coldplug All udev Devices.623vm-test-run-http> server # [ 55.538452] systemd[1]: Reached target System Initialization.624vm-test-run-http> server # [ 55.539966] systemd[1]: Started Discard unused filesystem blocks once a week.625vm-test-run-http> server # [ 55.556382] systemd[1]: Started Daily Cleanup of Temporary Directories.626vm-test-run-http> server # [ 55.567510] systemd[1]: Reached target Timer Units.627vm-test-run-http> server # [ 55.582362] systemd[1]: Listening on D-Bus System Message Bus Socket.628vm-test-run-http> server # [ 55.589304] systemd[1]: Listening on Nix Daemon Socket.629vm-test-run-http> server # [ 55.592062] systemd[1]: Listening on Hostname Service Socket.630vm-test-run-http> server # [ 55.601117] systemd[1]: Reached target Socket Units.631vm-test-run-http> server # [ 55.606595] systemd[1]: Reached target Basic System.632vm-test-run-http> server # [ 55.625766] systemd[1]: Started http-mock-server.service.633vm-test-run-http> server # [ 55.674929] systemd[1]: Starting Import lastlog data into lastlog2 database...634vm-test-run-http> server # [ 55.735993] systemd[1]: Starting Name Service Cache Daemon (nsncd)...635vm-test-run-http> server # [ 55.805215] systemd[1]: Started Reset console on configuration changes.636vm-test-run-http> server # [ 55.844557] systemd[1]: Starting resolvconf update...637vm-test-run-http> server # [ 55.933881] systemd[1]: Starting D-Bus System Message Bus...638vm-test-run-http> server # [ 57.498157] systemd[1]: Started Name Service Cache Daemon (nsncd).639vm-test-run-http> server # [ 57.512031] systemd[1]: Finished Import lastlog data into lastlog2 database.640vm-test-run-http> server # [ 57.558226] nsncd[633]: Oct 07 20:38:19.921 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"641vm-test-run-http> server # [ 57.582153] systemd[1]: Reached target Host and Network Name Lookups.642vm-test-run-http> server # [ 57.603075] systemd[1]: Reached target User and Group Name Lookups.643vm-test-run-http> server # [ 57.629162] systemd[1]: Starting User Login Management...644vm-test-run-http> server # [ 58.017280] dbus-daemon[636]: dbus[636]: Unknown username "systemd-timesync" in message bus configuration file645vm-test-run-http> server # [ 58.627340] systemd[1]: Started D-Bus System Message Bus.646vm-test-run-http> server: Guest root shell did not produce any data yet...647vm-test-run-http> server: To debug, enter the VM and run 'systemctl status backdoor.service'.648vm-test-run-http> server # [ 59.753806] systemd-logind[651]: New seat seat0.649vm-test-run-http> server # [ 59.817575] systemd[1]: Started User Login Management.650vm-test-run-http> server # [ 59.861893] systemd[1]: Starting linger-users.service...651vm-test-run-http> server # [ 60.263102] systemd[1]: Stopped target Host and Network Name Lookups.652vm-test-run-http> server # [ 60.299945] systemd[1]: Stopping Host and Network Name Lookups...653vm-test-run-http> server # [ 60.311329] systemd[1]: Stopped target User and Group Name Lookups.654vm-test-run-http> server # [ 60.345066] systemd[1]: Stopping User and Group Name Lookups...655vm-test-run-http> server # [ 60.353966] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...656vm-test-run-http> server # [ 60.372122] systemd[1]: nscd.service: Deactivated successfully.657vm-test-run-http> server # [ 60.407389] systemd[1]: Stopped Name Service Cache Daemon (nsncd).658vm-test-run-http> server # [ 60.540021] systemd[1]: Starting Name Service Cache Daemon (nsncd)...659vm-test-run-http> server # [ 60.721689] systemd[1]: linger-users.service: Deactivated successfully.660vm-test-run-http> server # [ 60.748865] systemd[1]: Finished linger-users.service.661vm-test-run-http> server # [ 61.449238] nsncd[695]: Oct 07 20:38:23.897 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"662vm-test-run-http> server # [ 61.482179] systemd[1]: Started Name Service Cache Daemon (nsncd).663vm-test-run-http> server # [ 61.493150] systemd[1]: Reached target Host and Network Name Lookups.664vm-test-run-http> server # [ 61.495043] systemd[1]: Reached target User and Group Name Lookups.665vm-test-run-http> server # [ 62.364186] systemd[1]: Finished resolvconf update.666vm-test-run-http> server # [ 62.381259] systemd[1]: Reached target Preparation for Network.667vm-test-run-http> server # [ 62.455164] systemd[1]: Starting DHCP Client...668vm-test-run-http> server # [ 64.466787] dhcpcd[741]: dhcpcd-10.2.4 starting669vm-test-run-http> server # [ 64.639852] dhcpcd[750]: dev: loaded udev670vm-test-run-http> server # [ 65.189561] 8021q: 802.1Q VLAN Support v1.8671vm-test-run-http> server # [ 65.882778] cfg80211: Loading compiled-in X.509 certificates for regulatory database672vm-test-run-http> server # [ 65.819971] http-mock-server-start[631]: Mock HTTP server running on port 8880673vm-test-run-http> server # [ 65.862653] http-mock-server-start[631]: Port file written to /tmp/http-mock-port674vm-test-run-http> server # [ 66.179208] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'675vm-test-run-http> server # [ 66.188769] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'676vm-test-run-http> server # [ 66.219786] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2677vm-test-run-http> server # [ 66.225262] cfg80211: failed to load regulatory.db678vm-test-run-http> server # [ 66.942234] 8021q: adding VLAN 0 to HW filter on device eth0679vm-test-run-http> server # [ 66.874777] dhcpcd[750]: eth0: waiting for carrier680vm-test-run-http> server # [ 66.879480] dhcpcd[750]: eth0: carrier acquired681vm-test-run-http> server # [ 67.063263] dhcpcd[750]: DUID 00:01:00:01:32:59:6a:45:52:54:00:12:34:56682vm-test-run-http> server # [ 67.069801] dhcpcd[750]: eth0: IAID 00:12:34:56683vm-test-run-http> server # [ 67.071103] dhcpcd[750]: eth0: adding address fe80::5054:ff:fe12:3456684vm-test-run-http> server # [ 68.350907] systemd[1]: Found device /dev/hvc0.685vm-test-run-http> server # [ 68.707394] dhcpcd[750]: eth0: soliciting a DHCP lease686vm-test-run-http> server # [ 68.742459] dhcpcd[750]: eth0: offered 10.0.2.15 from 10.0.2.2687vm-test-run-http> server # [ 68.762945] dhcpcd[750]: eth0: probing address 10.0.2.15/24688vm-test-run-http> server # [ 68.885091] systemd[1]: Found device /dev/ttyAMA0.689vm-test-run-http> server # [ 68.929128] systemd[1]: Started backdoor.service.690vm-test-run-http> server # [ 68.948716] dhcpcd[750]: eth0: soliciting an IPv6 router691vm-test-run-http> server # [ 68.965973] dhcpcd[750]: eth0: Router Advertisement from fe80::2692vm-test-run-http> server # [ 68.968311] dhcpcd[750]: eth0: adding address fec0::5054:ff:fe12:3456/64693vm-test-run-http> server # [ 68.980066] dhcpcd[750]: eth0: adding route to fec0::/64694vm-test-run-http> server # [ 68.988567] dhcpcd[750]: eth0: adding default route via fe80::2695vm-test-run-http> server # connecting to host...696vm-test-run-http> server: Guest shell says: b'Spawning backdoor root shell...\n'697vm-test-run-http> server: connected to guest root shell698vm-test-run-http> server: (connecting took 70.78 seconds)699vm-test-run-http> server: (finished: waiting for the VM to finish booting, in 70.78 seconds)700vm-test-run-http> server # [ 72.210269] (udev-worker)[820]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.701vm-test-run-http> server # [ 72.217111] (udev-worker)[820]: Network interface NamePolicy= disabled on kernel command line.702vm-test-run-http> server # [ 72.260996] (udev-worker)[818]: Network interface NamePolicy= disabled on kernel command line.703vm-test-run-http> server # [ 72.754954] dhcpcd[750]: eth0: IAID 00:12:34:56704vm-test-run-http> server # [ 72.807033] systemd[1]: Found device Virtio network device.705vm-test-run-http> server # [ 72.831479] systemd[1]: Starting Address configuration of eth1...706vm-test-run-http> server # [ 73.402490] dhcpcd[750]: eth0: soliciting an IPv6 router707vm-test-run-http> server # [ 73.985765] 8021q: adding VLAN 0 to HW filter on device eth1708vm-test-run-http> server # [ 74.306915] network-addresses-eth1-start[843]: adding address 192.168.1.1/24... done709vm-test-run-http> server # [ 74.590763] dhcpcd[750]: eth0: leased 10.0.2.15 for 86400 seconds710vm-test-run-http> server # [ 74.607168] dhcpcd[750]: eth0: adding route to 10.0.2.0/24711vm-test-run-http> server # [ 74.615537] network-addresses-eth1-start[843]: adding address 2001:db8:1::1/64... done712vm-test-run-http> server # [ 74.622692] dhcpcd[750]: eth0: adding default route via 10.0.2.2713vm-test-run-http> server # [ 75.037823] systemd[1]: Finished Address configuration of eth1.714vm-test-run-http> server # [ 75.101122] systemd[1]: Starting Networking Setup...715vm-test-run-http> server # [ 78.310616] dhcpcd[750]: eth0: rebinding lease of 10.0.2.15716vm-test-run-http> server # [ 78.317752] systemd[1]: Started DHCP Client.717vm-test-run-http> server # [ 78.320794] dhcpcd[750]: eth0: leased 10.0.2.15 for 86400 seconds718vm-test-run-http> server # [ 81.299861] systemd[1]: Finished Networking Setup.719vm-test-run-http> server # [ 81.319763] systemd[1]: Reached target Network.720vm-test-run-http> server # [ 81.345813] systemd[1]: Starting PostgreSQL Database Server...721vm-test-run-http> server # [ 81.385220] systemd[1]: Starting Permit User Sessions...722vm-test-run-http> server # [ 82.045660] systemd[1]: Finished Permit User Sessions.723vm-test-run-http> server # [ 82.134442] systemd[1]: Started Getty on tty1.724vm-test-run-http> server # [ 82.141455] systemd[1]: Reached target Login Prompts.725vm-test-run-http> server # [ 82.978554] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1043]: Initializing database at /var/lib/postgresql/data726vm-test-run-http> server # [ 84.275083] systemd-logind[651]: Watching system buttons on /dev/input/event0 (gpio-keys)727vm-test-run-http> server # [ 84.805317] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The files belonging to this database system will be owned by user "postgres".728vm-test-run-http> server # [ 84.811459] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: This user must also own the server process.729vm-test-run-http> server # [ 84.902079] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The database cluster will be initialized with locale "en_US.UTF-8".730vm-test-run-http> server # [ 84.903493] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The default database encoding has accordingly been set to "UTF8".731vm-test-run-http> server # [ 84.910206] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: The default text search configuration will be set to "english".732vm-test-run-http> server # [ 84.911588] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: Data page checksums are enabled.733vm-test-run-http> server # [ 84.912014] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: fixing permissions on existing directory /var/lib/postgresql/data ... ok734vm-test-run-http> server # [ 84.918069] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: creating subdirectories ... ok735vm-test-run-http> server # [ 84.923128] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting dynamic shared memory implementation ... posix736vm-test-run-http> server # [ 86.318464] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting default max_connections ... 100737vm-test-run-http> server # [ 87.475525] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting default shared_buffers ... 128MB738vm-test-run-http> server # [ 94.449249] mousedev: PS/2 mouse device common for all mice739vm-test-run-http> server # [ 99.061902] systemd-logind[651]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)740vm-test-run-http> server # [ 99.307993] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: selecting default time zone ... UTC741vm-test-run-http> server # [ 99.332034] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: creating configuration files ... ok742vm-test-run-http> server # [ 105.177811] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: running bootstrap script ... ok743vm-test-run-http> server # [ 118.819124] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: performing post-bootstrap initialization ... ok744vm-test-run-http> server # [ 122.059410] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: syncing data to disk ... ok745vm-test-run-http> server # [ 122.061299] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: initdb: warning: enabling "trust" authentication for local connections746vm-test-run-http> server # [ 122.063122] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: 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.747vm-test-run-http> server # [ 122.066205] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: Success. You can now start the database server using:748vm-test-run-http> server # [ 122.070172] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1050]: /nix/store/2bhdbvpk81xy3mkfgqrpc719ip588cba-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start749vm-test-run-http> server # [ 124.456634] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1043]: Validating PostgreSQL configuration...750vm-test-run-http> server # [ 124.950537] 0kr7wx8rrfnvnx1fblkl6id4af4rvych-postgresql-init[1453]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault751vm-test-run-http> server # [ 126.119750] postgres[1455]: 2026-10-07 20:39:28.592 UTC [1455] LOG: pgaudit extension initialized752vm-test-run-http> server # [ 127.315936] postgres[1455]: 2026-10-07 20:39:29.787 UTC [1455] LOG: pgsodium primary server secret key loaded753vm-test-run-http> server # [ 127.969986] postgres[1455]: 2026-10-07 20:39:30.459 UTC [1455] LOG: vault primary server secret key loaded754vm-test-run-http> server # [ 128.517898] postgres[1455]: 2026-10-07 20:39:30.996 UTC [1455] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit755vm-test-run-http> server # [ 128.522770] postgres[1455]: 2026-10-07 20:39:31.014 UTC [1455] LOG: listening on IPv4 address "0.0.0.0", port 5432756vm-test-run-http> server # [ 128.532940] postgres[1455]: 2026-10-07 20:39:31.019 UTC [1455] LOG: listening on IPv6 address "::", port 5432757vm-test-run-http> server # [ 128.545959] postgres[1455]: 2026-10-07 20:39:31.035 UTC [1455] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"758vm-test-run-http> server # [ 128.718738] postgres[1493]: 2026-10-07 20:39:31.198 UTC [1493] LOG: database system was shut down at 2026-10-07 20:39:21 UTC759vm-test-run-http> server # [ 129.115837] postgres[1455]: 2026-10-07 20:39:31.606 UTC [1455] LOG: database system is ready to accept connections760vm-test-run-http> server # [ 129.191798] systemd[1]: Started PostgreSQL Database Server.761vm-test-run-http> server # [ 129.240063] postgres[1502]: 2026-10-07 20:39:31.708 UTC [1502] FATAL: role "postgres" does not exist762vm-test-run-http> server # [ 129.260079] postgres[1500]: 2026-10-07 20:39:31.731 UTC [1500] LOG: TimescaleDB background worker launcher connected to shared catalogs763vm-test-run-http> server # [ 129.262746] systemd[1]: Starting Supabase Database Initialization...764vm-test-run-http> server # [ 129.404265] postgres[1455]: 2026-10-07 20:39:31.895 UTC [1455] LOG: background worker "pg_net 0.20.4 worker" (PID 1502) exited with exit code 1765vm-test-run-http> server # [ 129.550129] postgres[1503]: 2026-10-07 20:39:32.033 UTC [1503] LOG: pg_cron scheduler started766vm-test-run-http> server # [ 129.731002] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Waiting for PostgreSQL to be ready...767vm-test-run-http> server # [ 130.359307] postgres[1511]: ::1 2026-10-07 20:39:32.802 UTC [1511] root@root FATAL: role "root" does not exist768vm-test-run-http> server # [ 130.409566] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: PostgreSQL is ready769vm-test-run-http> server # [ 130.411192] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Creating postgres role...770vm-test-run-http> server # [ 130.506185] postgres[1518]: 2026-10-07 20:39:32.971 UTC [1518] FATAL: role "postgres" does not exist771vm-test-run-http> server # [ 130.558058] postgres[1455]: 2026-10-07 20:39:33.034 UTC [1455] LOG: background worker "pg_net 0.20.4 worker" (PID 1518) exited with exit code 1772vm-test-run-http> server # [ 131.113259] postgres[1527]: ::1 2026-10-07 20:39:33.594 UTC [1527] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN;773vm-test-run-http> server # [ 131.245686] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1517]: CREATE ROLE774vm-test-run-http> server # [ 131.843013] postgres[1538]: ::1 2026-10-07 20:39:34.305 UTC [1538] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres;775vm-test-run-http> server # [ 131.879616] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1532]: ALTER DATABASE776vm-test-run-http> server # [ 131.929610] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000000-initial-schema.sql777vm-test-run-http> server # [ 132.204273] postgres[1541]: ::1 2026-10-07 20:39:34.693 UTC [1541] postgres@postgres LOG: statement: create publication supabase_realtime;778vm-test-run-http> server # [ 132.225786] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE PUBLICATION779vm-test-run-http> server # [ 132.227930] postgres[1541]: ::1 2026-10-07 20:39:34.711 UTC [1541] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls;780vm-test-run-http> server # [ 132.240011] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE781vm-test-run-http> server # [ 132.245674] postgres[1541]: ::1 2026-10-07 20:39:34.726 UTC [1541] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication;782vm-test-run-http> server # [ 132.250003] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE783vm-test-run-http> server # [ 132.253674] postgres[1541]: ::1 2026-10-07 20:39:34.743 UTC [1541] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls;784vm-test-run-http> server # [ 132.266684] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE785vm-test-run-http> server # [ 132.267980] postgres[1541]: ::1 2026-10-07 20:39:34.759 UTC [1541] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin;786vm-test-run-http> server # [ 132.280120] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE787vm-test-run-http> server # [ 132.285135] postgres[1541]: ::1 2026-10-07 20:39:34.776 UTC [1541] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin;788vm-test-run-http> server # [ 132.302103] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT789vm-test-run-http> server # [ 132.305565] postgres[1541]: ::1 2026-10-07 20:39:34.791 UTC [1541] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls;790vm-test-run-http> server # [ 132.309545] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE791vm-test-run-http> server # [ 132.313021] postgres[1541]: ::1 2026-10-07 20:39:34.803 UTC [1541] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user;792vm-test-run-http> server # [ 132.319678] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE793vm-test-run-http> server # [ 132.325695] postgres[1541]: ::1 2026-10-07 20:39:34.815 UTC [1541] postgres@postgres LOG: statement: create schema if not exists extensions;794vm-test-run-http> server # [ 132.335853] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE SCHEMA795vm-test-run-http> server # [ 132.337615] postgres[1541]: ::1 2026-10-07 20:39:34.828 UTC [1541] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions;796vm-test-run-http> server # [ 135.565734] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE EXTENSION797vm-test-run-http> server # [ 135.589301] postgres[1541]: ::1 2026-10-07 20:39:38.053 UTC [1541] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions;798vm-test-run-http> server # [ 136.474159] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE EXTENSION799vm-test-run-http> server # [ 136.475403] postgres[1541]: ::1 2026-10-07 20:39:38.967 UTC [1541] postgres@postgres LOG: statement: create role anon nologin noinherit;800vm-test-run-http> server # [ 136.483159] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE801vm-test-run-http> server # [ 136.485944] postgres[1541]: ::1 2026-10-07 20:39:38.975 UTC [1541] postgres@postgres LOG: statement: create role authenticated nologin noinherit;802vm-test-run-http> server # [ 136.496710] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE803vm-test-run-http> server # [ 136.498006] postgres[1541]: ::1 2026-10-07 20:39:38.987 UTC [1541] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls;804vm-test-run-http> server # [ 136.508533] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE805vm-test-run-http> server # [ 136.510423] postgres[1541]: ::1 2026-10-07 20:39:39.000 UTC [1541] postgres@postgres LOG: statement: create user authenticator noinherit;806vm-test-run-http> server # [ 136.518782] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: CREATE ROLE807vm-test-run-http> server # [ 136.529822] postgres[1541]: ::1 2026-10-07 20:39:39.019 UTC [1541] postgres@postgres LOG: statement: grant anon to authenticator;808vm-test-run-http> server # [ 136.538198] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE809vm-test-run-http> server # [ 136.540201] postgres[1541]: ::1 2026-10-07 20:39:39.031 UTC [1541] postgres@postgres LOG: statement: grant authenticated to authenticator;810vm-test-run-http> server # [ 136.554913] postgres[1541]: ::1 2026-10-07 20:39:39.042 UTC [1541] postgres@postgres LOG: statement: grant service_role to authenticator;811vm-test-run-http> server # [ 136.563679] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE812vm-test-run-http> server # [ 136.567815] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE813vm-test-run-http> server # [ 136.569236] postgres[1541]: ::1 2026-10-07 20:39:39.055 UTC [1541] postgres@postgres LOG: statement: grant supabase_admin to authenticator;814vm-test-run-http> server # [ 136.574879] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT ROLE815vm-test-run-http> server # [ 136.581822] postgres[1541]: ::1 2026-10-07 20:39:39.067 UTC [1541] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role;816vm-test-run-http> server # [ 136.589597] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT817vm-test-run-http> server # [ 136.591323] postgres[1541]: ::1 2026-10-07 20:39:39.082 UTC [1541] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role;818vm-test-run-http> server # [ 136.610322] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES819vm-test-run-http> server # [ 136.615827] postgres[1541]: ::1 2026-10-07 20:39:39.106 UTC [1541] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role;820vm-test-run-http> server # [ 136.634446] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES821vm-test-run-http> server # [ 136.641248] postgres[1541]: ::1 2026-10-07 20:39:39.129 UTC [1541] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role;822vm-test-run-http> server # [ 136.657542] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES823vm-test-run-http> server # [ 136.660230] postgres[1541]: ::1 2026-10-07 20:39:39.148 UTC [1541] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role;824vm-test-run-http> server # [ 136.683610] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: GRANT825vm-test-run-http> server # [ 136.686220] postgres[1541]: ::1 2026-10-07 20:39:39.168 UTC [1541] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions;826vm-test-run-http> server # [ 136.705009] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE827vm-test-run-http> server # [ 136.706806] postgres[1541]: ::1 2026-10-07 20:39:39.198 UTC [1541] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all828vm-test-run-http> server # [ 136.708035] postgres[1541]: on sequences to postgres, anon, authenticated, service_role;829vm-test-run-http> server # [ 136.725933] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES830vm-test-run-http> server # [ 136.733510] postgres[1541]: ::1 2026-10-07 20:39:39.221 UTC [1541] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all831vm-test-run-http> server # [ 136.735478] postgres[1541]: on tables to postgres, anon, authenticated, service_role;832vm-test-run-http> server # [ 136.742574] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES833vm-test-run-http> server # [ 136.745982] postgres[1541]: ::1 2026-10-07 20:39:39.237 UTC [1541] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all834vm-test-run-http> server # [ 136.751501] postgres[1541]: on functions to postgres, anon, authenticated, service_role;835vm-test-run-http> server # [ 136.760585] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER DEFAULT PRIVILEGES836vm-test-run-http> server # [ 136.763567] postgres[1541]: ::1 2026-10-07 20:39:39.255 UTC [1541] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s';837vm-test-run-http> server # [ 136.787564] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE838vm-test-run-http> server # [ 136.790248] postgres[1541]: ::1 2026-10-07 20:39:39.279 UTC [1541] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s';839vm-test-run-http> server # [ 136.809617] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1540]: ALTER ROLE840vm-test-run-http> server # [ 136.890429] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000001-auth-schema.sql841vm-test-run-http> server # [ 137.476127] postgres[1580]: ::1 2026-10-07 20:39:39.935 UTC [1580] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin;842vm-test-run-http> server # [ 137.510903] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE SCHEMA843vm-test-run-http> server # [ 137.512520] postgres[1580]: ::1 2026-10-07 20:39:39.992 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.users (844vm-test-run-http> server # [ 137.521689] postgres[1580]: instance_id uuid NULL,845vm-test-run-http> server # [ 137.523281] postgres[1580]: id uuid NOT NULL UNIQUE,846vm-test-run-http> server # [ 137.525642] postgres[1580]: aud varchar(255) NULL,847vm-test-run-http> server # [ 137.526470] postgres[1580]: "role" varchar(255) NULL,848vm-test-run-http> server # [ 137.526768] postgres[1580]: email varchar(255) NULL UNIQUE,849vm-test-run-http> server # [ 137.527031] postgres[1580]: encrypted_password varchar(255) NULL,850vm-test-run-http> server # [ 137.533298] postgres[1580]: confirmed_at timestamptz NULL,851vm-test-run-http> server # [ 137.535870] postgres[1580]: invited_at timestamptz NULL,852vm-test-run-http> server # [ 137.538949] postgres[1580]: confirmation_token varchar(255) NULL,853vm-test-run-http> server # [ 137.546885] postgres[1580]: confirmation_sent_at timestamptz NULL,854vm-test-run-http> server # [ 137.547492] postgres[1580]: recovery_token varchar(255) NULL,855vm-test-run-http> server # [ 137.547880] postgres[1580]: recovery_sent_at timestamptz NULL,856vm-test-run-http> server # [ 137.548242] postgres[1580]: email_change_token varchar(255) NULL,857vm-test-run-http> server # [ 137.548520] postgres[1580]: email_change varchar(255) NULL,858vm-test-run-http> server # [ 137.555256] postgres[1580]: email_change_sent_at timestamptz NULL,859vm-test-run-http> server # [ 137.555939] postgres[1580]: last_sign_in_at timestamptz NULL,860vm-test-run-http> server # [ 137.566067] postgres[1580]: raw_app_meta_data jsonb NULL,861vm-test-run-http> server # [ 137.566778] postgres[1580]: raw_user_meta_data jsonb NULL,862vm-test-run-http> server # [ 137.567063] postgres[1580]: is_super_admin bool NULL,863vm-test-run-http> server # [ 137.567293] postgres[1580]: created_at timestamptz NULL,864vm-test-run-http> server # [ 137.567644] postgres[1580]: updated_at timestamptz NULL,865vm-test-run-http> server # [ 137.572954] postgres[1580]: CONSTRAINT users_pkey PRIMARY KEY (id)866vm-test-run-http> server # [ 137.574106] postgres[1580]: );867vm-test-run-http> server # [ 137.853167] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE868vm-test-run-http> server # [ 137.854535] postgres[1580]: ::1 2026-10-07 20:39:40.342 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email);869vm-test-run-http> server # [ 137.931970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX870vm-test-run-http> server # [ 137.943259] postgres[1580]: ::1 2026-10-07 20:39:40.420 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id);871vm-test-run-http> server # [ 137.994203] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX872vm-test-run-http> server # [ 137.995781] postgres[1580]: ::1 2026-10-07 20:39:40.486 UTC [1580] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.';873vm-test-run-http> server # [ 138.010160] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT874vm-test-run-http> server # [ 138.011856] postgres[1580]: ::1 2026-10-07 20:39:40.503 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens (875vm-test-run-http> server # [ 138.014242] postgres[1580]: instance_id uuid NULL,876vm-test-run-http> server # [ 138.017163] postgres[1580]: id bigserial NOT NULL,877vm-test-run-http> server # [ 138.018049] postgres[1580]: "token" varchar(255) NULL,878vm-test-run-http> server # [ 138.018624] postgres[1580]: user_id varchar(255) NULL,879vm-test-run-http> server # [ 138.018932] postgres[1580]: revoked bool NULL,880vm-test-run-http> server # [ 138.019359] postgres[1580]: created_at timestamptz NULL,881vm-test-run-http> server # [ 138.019728] postgres[1580]: updated_at timestamptz NULL,882vm-test-run-http> server # [ 138.019983] postgres[1580]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id)883vm-test-run-http> server # [ 138.020236] postgres[1580]: );884vm-test-run-http> server # [ 138.187233] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE885vm-test-run-http> server # [ 138.193583] postgres[1580]: ::1 2026-10-07 20:39:40.679 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id);886vm-test-run-http> server # [ 138.230521] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX887vm-test-run-http> server # [ 138.234475] postgres[1580]: ::1 2026-10-07 20:39:40.725 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id);888vm-test-run-http> server # [ 138.266428] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX889vm-test-run-http> server # [ 138.270488] postgres[1580]: ::1 2026-10-07 20:39:40.761 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token);890vm-test-run-http> server # [ 138.299403] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX891vm-test-run-http> server # [ 138.309565] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT892vm-test-run-http> server # [ 138.311044] postgres[1580]: ::1 2026-10-07 20:39:40.793 UTC [1580] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.';893vm-test-run-http> server # [ 138.314754] postgres[1580]: ::1 2026-10-07 20:39:40.805 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.instances (894vm-test-run-http> server # [ 138.315554] postgres[1580]: id uuid NOT NULL,895vm-test-run-http> server # [ 138.319150] postgres[1580]: uuid uuid NULL,896vm-test-run-http> server # [ 138.325809] postgres[1580]: raw_base_config text NULL,897vm-test-run-http> server # [ 138.326934] postgres[1580]: created_at timestamptz NULL,898vm-test-run-http> server # [ 138.327285] postgres[1580]: updated_at timestamptz NULL,899vm-test-run-http> server # [ 138.327544] postgres[1580]: CONSTRAINT instances_pkey PRIMARY KEY (id)900vm-test-run-http> server # [ 138.328122] postgres[1580]: );901vm-test-run-http> server # [ 138.378846] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE902vm-test-run-http> server # [ 138.381095] postgres[1580]: ::1 2026-10-07 20:39:40.867 UTC [1580] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.';903vm-test-run-http> server # [ 138.388078] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT904vm-test-run-http> server # [ 138.393224] postgres[1580]: ::1 2026-10-07 20:39:40.882 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries (905vm-test-run-http> server # [ 138.394575] postgres[1580]: instance_id uuid NULL,906vm-test-run-http> server # [ 138.395520] postgres[1580]: id uuid NOT NULL,907vm-test-run-http> server # [ 138.396345] postgres[1580]: payload json NULL,908vm-test-run-http> server # [ 138.406230] postgres[1580]: created_at timestamptz NULL,909vm-test-run-http> server # [ 138.406831] postgres[1580]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id)910vm-test-run-http> server # [ 138.414695] postgres[1580]: );911vm-test-run-http> server # [ 138.478118] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE912vm-test-run-http> server # [ 138.491508] postgres[1580]: ::1 2026-10-07 20:39:40.965 UTC [1580] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id);913vm-test-run-http> server # [ 138.501926] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE INDEX914vm-test-run-http> server # [ 138.503108] postgres[1580]: ::1 2026-10-07 20:39:40.990 UTC [1580] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.';915vm-test-run-http> server # [ 138.507745] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT916vm-test-run-http> server # [ 138.513874] postgres[1580]: ::1 2026-10-07 20:39:41.002 UTC [1580] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations (917vm-test-run-http> server # [ 138.515226] postgres[1580]: "version" varchar(255) NOT NULL,918vm-test-run-http> server # [ 138.515911] postgres[1580]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version")919vm-test-run-http> server # [ 138.523683] postgres[1580]: );920vm-test-run-http> server # [ 138.548274] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE TABLE921vm-test-run-http> server # [ 138.551785] postgres[1580]: ::1 2026-10-07 20:39:41.043 UTC [1580] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.';922vm-test-run-http> server # [ 138.558914] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: COMMENT923vm-test-run-http> server # [ 138.579733] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: INSERT 0 7924vm-test-run-http> server # [ 138.581007] postgres[1580]: ::1 2026-10-07 20:39:41.072 UTC [1580] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$925vm-test-run-http> server # [ 138.581734] postgres[1580]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid;926vm-test-run-http> server # [ 138.583325] postgres[1580]: $$ language sql stable;927vm-test-run-http> server # [ 138.592070] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE FUNCTION928vm-test-run-http> server # [ 138.596341] postgres[1580]: ::1 2026-10-07 20:39:41.088 UTC [1580] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$929vm-test-run-http> server # [ 138.597951] postgres[1580]: select nullif(current_setting('request.jwt.claim.role', true), '')::text;930vm-test-run-http> server # [ 138.598768] postgres[1580]: $$ language sql stable;931vm-test-run-http> server # [ 138.611471] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE FUNCTION932vm-test-run-http> server # [ 138.615166] postgres[1580]: ::1 2026-10-07 20:39:41.101 UTC [1580] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$933vm-test-run-http> server # [ 138.618513] postgres[1580]: select nullif(current_setting('request.jwt.claim.email', true), '')::text;934vm-test-run-http> server # [ 138.621486] postgres[1580]: $$ language sql stable;935vm-test-run-http> server # [ 138.624880] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE FUNCTION936vm-test-run-http> server # [ 138.630611] postgres[1580]: ::1 2026-10-07 20:39:41.119 UTC [1580] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role;937vm-test-run-http> server # [ 138.636809] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT938vm-test-run-http> server # [ 138.639018] postgres[1580]: ::1 2026-10-07 20:39:41.130 UTC [1580] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;939vm-test-run-http> server # [ 138.648166] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: CREATE ROLE940vm-test-run-http> server # [ 138.653968] postgres[1580]: ::1 2026-10-07 20:39:41.139 UTC [1580] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;941vm-test-run-http> server # [ 138.655860] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT942vm-test-run-http> server # [ 138.660063] postgres[1580]: ::1 2026-10-07 20:39:41.149 UTC [1580] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;943vm-test-run-http> server # [ 138.671209] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT944vm-test-run-http> server # [ 138.672032] postgres[1580]: ::1 2026-10-07 20:39:41.163 UTC [1580] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;945vm-test-run-http> server # [ 138.678821] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: GRANT946vm-test-run-http> server # [ 138.685394] postgres[1580]: ::1 2026-10-07 20:39:41.173 UTC [1580] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth";947vm-test-run-http> server # [ 138.691993] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER ROLE948vm-test-run-http> server # [ 138.694655] postgres[1580]: ::1 2026-10-07 20:39:41.185 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin;949vm-test-run-http> server # [ 138.703398] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE950vm-test-run-http> server # [ 138.708921] postgres[1580]: ::1 2026-10-07 20:39:41.197 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin;951vm-test-run-http> server # [ 138.726119] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE952vm-test-run-http> server # [ 138.733798] postgres[1580]: ::1 2026-10-07 20:39:41.216 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin;953vm-test-run-http> server # [ 138.751851] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE954vm-test-run-http> server # [ 138.758778] postgres[1580]: ::1 2026-10-07 20:39:41.245 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin;955vm-test-run-http> server # [ 138.770724] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE956vm-test-run-http> server # [ 138.776386] postgres[1580]: ::1 2026-10-07 20:39:41.267 UTC [1580] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin;957vm-test-run-http> server # [ 138.790840] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1574]: ALTER TABLE958vm-test-run-http> server # [ 138.879796] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000002-storage-schema.sql959vm-test-run-http> server # [ 139.307899] postgres[1601]: ::1 2026-10-07 20:39:41.790 UTC [1601] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin;960vm-test-run-http> server # [ 139.330735] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: CREATE SCHEMA961vm-test-run-http> server # [ 139.332396] postgres[1601]: ::1 2026-10-07 20:39:41.817 UTC [1601] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;962vm-test-run-http> server # [ 139.341970] postgres[1601]: ::1 2026-10-07 20:39:41.832 UTC [1601] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage";963vm-test-run-http> server # [ 139.351921] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: CREATE ROLE964vm-test-run-http> server # [ 139.359033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: ALTER ROLE965vm-test-run-http> server # [ 139.364118] postgres[1601]: ::1 2026-10-07 20:39:41.848 UTC [1601] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin;966vm-test-run-http> server # [ 139.379593] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: GRANT967vm-test-run-http> server # [ 139.410975] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1599]: DO968vm-test-run-http> server # [ 139.478731] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running init-script: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/init-scripts/00000000000003-post-setup.sql969vm-test-run-http> server # [ 139.875478] postgres[1603]: ::1 2026-10-07 20:39:42.334 UTC [1603] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions;970vm-test-run-http> server # [ 139.889643] postgres[1603]: ::1 2026-10-07 20:39:42.375 UTC [1603] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions;971vm-test-run-http> server # [ 139.909178] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: ALTER ROLE972vm-test-run-http> server # [ 139.914388] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: ALTER ROLE973vm-test-run-http> server # [ 139.921246] postgres[1603]: ::1 2026-10-07 20:39:42.408 UTC [1603] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access()974vm-test-run-http> server # [ 139.929422] postgres[1603]: RETURNS event_trigger975vm-test-run-http> server # [ 139.930536] postgres[1603]: LANGUAGE plpgsql976vm-test-run-http> server # [ 139.930856] postgres[1603]: AS $$977vm-test-run-http> server # [ 139.931111] postgres[1603]: DECLARE978vm-test-run-http> server # [ 139.931339] postgres[1603]: schema_is_cron bool;979vm-test-run-http> server # [ 139.931563] postgres[1603]: BEGIN980vm-test-run-http> server # [ 139.931954] postgres[1603]: schema_is_cron = (981vm-test-run-http> server # [ 139.937440] postgres[1603]: SELECT n.nspname = 'cron'982vm-test-run-http> server # [ 139.939729] postgres[1603]: FROM pg_event_trigger_ddl_commands() AS ev983vm-test-run-http> server # [ 139.947074] postgres[1603]: LEFT JOIN pg_catalog.pg_namespace AS n984vm-test-run-http> server # [ 139.950042] postgres[1603]: ON ev.objid = n.oid985vm-test-run-http> server # [ 139.959325] postgres[1603]: );986vm-test-run-http> server # [ 139.967613] postgres[1603]:987vm-test-run-http> server # [ 139.979764] postgres[1603]: IF schema_is_cron988vm-test-run-http> server # [ 139.987286] postgres[1603]: THEN989vm-test-run-http> server # [ 139.988241] postgres[1603]: grant usage on schema cron to postgres with grant option;990vm-test-run-http> server # [ 139.993923] postgres[1603]:991vm-test-run-http> server # [ 139.995070] postgres[1603]: alter default privileges in schema cron grant all on tables to postgres with grant option;992vm-test-run-http> server # [ 140.002597] postgres[1603]: alter default privileges in schema cron grant all on functions to postgres with grant option;993vm-test-run-http> server # [ 140.003939] postgres[1603]: alter default privileges in schema cron grant all on sequences to postgres with grant option;994vm-test-run-http> server # [ 140.024872] postgres[1603]:995vm-test-run-http> server # [ 140.026717] postgres[1603]: alter default privileges for user supabase_admin in schema cron grant all996vm-test-run-http> server # [ 140.028322] postgres[1603]: on sequences to postgres with grant option;997vm-test-run-http> server # [ 140.029935] postgres[1603]: alter default privileges for user supabase_admin in schema cron grant all998vm-test-run-http> server # [ 140.030511] postgres[1603]: on tables to postgres with grant option;999vm-test-run-http> server # [ 140.030857] postgres[1603]: alter default privileges for user supabase_admin in schema cron grant all1000vm-test-run-http> server # [ 140.031156] postgres[1603]: on functions to postgres with grant option;1001vm-test-run-http> server # [ 140.032897] postgres[1603]:1002vm-test-run-http> server # [ 140.034717] postgres[1603]: grant all privileges on all tables in schema cron to postgres with grant option;1003vm-test-run-http> server # [ 140.036577] postgres[1603]:1004vm-test-run-http> server # [ 140.036951] postgres[1603]: END IF;1005vm-test-run-http> server # [ 140.037610] postgres[1603]:1006vm-test-run-http> server # [ 140.038802] postgres[1603]: END;1007vm-test-run-http> server # [ 140.039234] postgres[1603]: $$;1008vm-test-run-http> server # [ 140.040339] postgres[1603]: ::1 2026-10-07 20:39:42.465 UTC [1603] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA')1009vm-test-run-http> server # [ 140.041606] postgres[1603]: EXECUTE PROCEDURE extensions.grant_pg_cron_access();1010vm-test-run-http> server # [ 140.042842] postgres[1603]: ::1 2026-10-07 20:39:42.506 UTC [1603] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron';1011vm-test-run-http> server # [ 140.044067] postgres[1603]: ::1 2026-10-07 20:39:42.531 UTC [1603] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access()1012vm-test-run-http> server # [ 140.046244] postgres[1603]: RETURNS event_trigger1013vm-test-run-http> server # [ 140.046726] postgres[1603]: LANGUAGE plpgsql1014vm-test-run-http> server # [ 140.046989] postgres[1603]: AS $$1015vm-test-run-http> server # [ 140.047215] postgres[1603]: BEGIN1016vm-test-run-http> server # [ 140.047444] postgres[1603]: IF EXISTS (1017vm-test-run-http> server # [ 140.047689] postgres[1603]: SELECT 11018vm-test-run-http> server # [ 140.048056] postgres[1603]: FROM pg_event_trigger_ddl_commands() AS ev1019vm-test-run-http> server # [ 140.048671] postgres[1603]: JOIN pg_extension AS ext1020vm-test-run-http> server # [ 140.049176] postgres[1603]: ON ev.objid = ext.oid1021vm-test-run-http> server # [ 140.049928] postgres[1603]: WHERE ext.extname = 'pg_net'1022vm-test-run-http> server # [ 140.053219] postgres[1603]: )1023vm-test-run-http> server # [ 140.053754] postgres[1603]: THEN1024vm-test-run-http> server # [ 140.054056] postgres[1603]: IF NOT EXISTS (1025vm-test-run-http> server # [ 140.055366] postgres[1603]: SELECT 11026vm-test-run-http> server # [ 140.056322] postgres[1603]: FROM pg_roles1027vm-test-run-http> server # [ 140.057901] postgres[1603]: WHERE rolname = 'supabase_functions_admin'1028vm-test-run-http> server # [ 140.058949] postgres[1603]: )1029vm-test-run-http> server # [ 140.059356] postgres[1603]: THEN1030vm-test-run-http> server # [ 140.061142] postgres[1603]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION;1031vm-test-run-http> server # [ 140.062722] postgres[1603]: END IF;1032vm-test-run-http> server # [ 140.063794] postgres[1603]:1033vm-test-run-http> server # [ 140.064606] postgres[1603]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role;1034vm-test-run-http> server # [ 140.065849] postgres[1603]:1035vm-test-run-http> server # [ 140.066327] postgres[1603]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1036vm-test-run-http> server # [ 140.067325] postgres[1603]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER;1037vm-test-run-http> server # [ 140.067949] postgres[1603]:1038vm-test-run-http> server # [ 140.068239] postgres[1603]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1039vm-test-run-http> server # [ 140.069156] postgres[1603]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net;1040vm-test-run-http> server # [ 140.070020] postgres[1603]:1041vm-test-run-http> server # [ 140.070469] postgres[1603]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1042vm-test-run-http> server # [ 140.071213] postgres[1603]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC;1043vm-test-run-http> server # [ 140.072206] postgres[1603]:1044vm-test-run-http> server # [ 140.076937] postgres[1603]: 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;1045vm-test-run-http> server # [ 140.078317] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE FUNCTION1046vm-test-run-http> server # [ 140.078910] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE EVENT TRIGGER1047vm-test-run-http> server # [ 140.081206] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: COMMENT1048vm-test-run-http> server # [ 140.083541] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE FUNCTION1049vm-test-run-http> server # [ 140.084881] postgres[1603]: 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;1050vm-test-run-http> server # [ 140.086285] postgres[1603]: END IF;1051vm-test-run-http> server # [ 140.086588] postgres[1603]: END;1052vm-test-run-http> server # [ 140.086853] postgres[1603]: $$;1053vm-test-run-http> server # [ 140.087268] postgres[1603]: ::1 2026-10-07 20:39:42.558 UTC [1603] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net';1054vm-test-run-http> server # [ 140.089027] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: COMMENT1055vm-test-run-http> server # [ 140.133278] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: DO1056vm-test-run-http> server # [ 140.141631] postgres[1603]: ::1 2026-10-07 20:39:42.630 UTC [1603] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION;1057vm-test-run-http> server # [ 140.154866] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: CREATE ROLE1058vm-test-run-http> server # [ 140.166971] postgres[1603]: ::1 2026-10-07 20:39:42.653 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user;1059vm-test-run-http> server # [ 140.193084] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1060vm-test-run-http> server # [ 140.218463] postgres[1603]: ::1 2026-10-07 20:39:42.681 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user;1061vm-test-run-http> server # [ 140.230278] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1062vm-test-run-http> server # [ 140.242037] postgres[1603]: ::1 2026-10-07 20:39:42.723 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user;1063vm-test-run-http> server # [ 140.256599] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1064vm-test-run-http> server # [ 140.270713] postgres[1603]: ::1 2026-10-07 20:39:42.744 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user;1065vm-test-run-http> server # [ 140.284652] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1066vm-test-run-http> server # [ 140.289023] postgres[1603]: ::1 2026-10-07 20:39:42.779 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user;1067vm-test-run-http> server # [ 140.299922] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1068vm-test-run-http> server # [ 140.305127] postgres[1603]: ::1 2026-10-07 20:39:42.795 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user;1069vm-test-run-http> server # [ 140.333559] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1070vm-test-run-http> server # [ 140.335581] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1071vm-test-run-http> server # [ 140.346098] postgres[1603]: ::1 2026-10-07 20:39:42.817 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user;1072vm-test-run-http> server # [ 140.353524] postgres[1603]: ::1 2026-10-07 20:39:42.832 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user;1073vm-test-run-http> server # [ 140.385726] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1074vm-test-run-http> server # [ 140.392920] postgres[1603]: ::1 2026-10-07 20:39:42.881 UTC [1603] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user;1075vm-test-run-http> server # [ 140.473229] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: GRANT1076vm-test-run-http> server # [ 140.515210] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1602]: DO1077vm-test-run-http> server # [ 140.575102] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running pgbouncer auth schema...1078vm-test-run-http> server # [ 141.058179] postgres[1626]: ::1 2026-10-07 20:39:43.531 UTC [1626] postgres@postgres LOG: statement: CREATE USER pgbouncer;1079vm-test-run-http> server # [ 141.089737] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: CREATE ROLE1080vm-test-run-http> server # [ 141.090668] postgres[1626]: ::1 2026-10-07 20:39:43.561 UTC [1626] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer;1081vm-test-run-http> server # [ 141.091608] postgres[1626]: ::1 2026-10-07 20:39:43.579 UTC [1626] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer;1082vm-test-run-http> server # [ 141.101828] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: REVOKE1083vm-test-run-http> server # [ 141.152163] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: CREATE SCHEMA1084vm-test-run-http> server # [ 141.155452] postgres[1626]: ::1 2026-10-07 20:39:43.647 UTC [1626] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT)1085vm-test-run-http> server # [ 141.156012] postgres[1626]: RETURNS TABLE(username TEXT, password TEXT) AS1086vm-test-run-http> server # [ 141.166978] postgres[1626]: $$1087vm-test-run-http> server # [ 141.167755] postgres[1626]: BEGIN1088vm-test-run-http> server # [ 141.180125] postgres[1626]: RAISE WARNING 'PgBouncer auth request: %', p_usename;1089vm-test-run-http> server # [ 141.201160] postgres[1626]:1090vm-test-run-http> server # [ 141.202653] postgres[1626]: RETURN QUERY1091vm-test-run-http> server # [ 141.215270] postgres[1626]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow1092vm-test-run-http> server # [ 141.253834] postgres[1626]: WHERE usename = p_usename;1093vm-test-run-http> server # [ 141.254571] postgres[1626]: END;1094vm-test-run-http> server # [ 141.255548] postgres[1626]: $$ LANGUAGE plpgsql1095vm-test-run-http> server # [ 141.255845] postgres[1626]: SET search_path = ''1096vm-test-run-http> server # [ 141.256275] postgres[1626]: SECURITY DEFINER;1097vm-test-run-http> server # [ 141.275154] postgres[1626]: ::1 2026-10-07 20:39:43.678 UTC [1626] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC;1098vm-test-run-http> server # [ 141.314587] postgres[1626]: ::1 2026-10-07 20:39:43.685 UTC [1626] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer;1099vm-test-run-http> server # [ 141.327132] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: CREATE FUNCTION1100vm-test-run-http> server # [ 141.343696] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: REVOKE1101vm-test-run-http> server # [ 141.347338] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1618]: GRANT1102vm-test-run-http> server # [ 141.355230] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running stat extension...1103vm-test-run-http> server # [ 141.710769] postgres[1631]: ::1 2026-10-07 20:39:44.196 UTC [1631] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions;1104vm-test-run-http> server # [ 141.732977] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1630]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping1105vm-test-run-http> server # [ 141.753627] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1630]: CREATE SCHEMA1106vm-test-run-http> server # [ 141.757763] postgres[1631]: ::1 2026-10-07 20:39:44.248 UTC [1631] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions;1107vm-test-run-http> server # [ 143.090430] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1630]: CREATE EXTENSION1108vm-test-run-http> server # [ 143.189446] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/10000000000000_demote-postgres.sql1109vm-test-run-http> server # [ 143.923235] postgres[1648]: ::1 2026-10-07 20:39:46.389 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres;1110vm-test-run-http> server # [ 143.934485] postgres[1648]: ::1 2026-10-07 20:39:46.402 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres;1111vm-test-run-http> server # [ 143.935072] postgres[1648]: ::1 2026-10-07 20:39:46.419 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres;1112vm-test-run-http> server # [ 143.935914] postgres[1648]: ::1 2026-10-07 20:39:46.426 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres;1113vm-test-run-http> server # [ 143.955191] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1114vm-test-run-http> server # [ 143.956164] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1115vm-test-run-http> server # [ 143.970013] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1116vm-test-run-http> server # [ 143.970580] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1117vm-test-run-http> server # [ 143.970902] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1118vm-test-run-http> server # [ 143.972273] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1119vm-test-run-http> server # [ 143.988119] postgres[1648]: ::1 2026-10-07 20:39:46.442 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres;1120vm-test-run-http> server # [ 144.001961] postgres[1648]: ::1 2026-10-07 20:39:46.455 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres;1121vm-test-run-http> server # [ 144.002856] postgres[1648]: ::1 2026-10-07 20:39:46.466 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres;1122vm-test-run-http> server # [ 144.003280] postgres[1648]: ::1 2026-10-07 20:39:46.475 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres;1123vm-test-run-http> server # [ 144.003819] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1124vm-test-run-http> server # [ 144.004109] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1125vm-test-run-http> server # [ 144.020120] postgres[1648]: ::1 2026-10-07 20:39:46.493 UTC [1648] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres;1126vm-test-run-http> server # [ 144.063119] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: GRANT1127vm-test-run-http> server # [ 144.119856] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: DO1128vm-test-run-http> server # [ 144.125470] postgres[1648]: ::1 2026-10-07 20:39:46.612 UTC [1648] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS;1129vm-test-run-http> server # [ 144.133954] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1642]: ALTER ROLE1130vm-test-run-http> server # [ 144.212251] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211115181400_update-auth-permissions.sql1131vm-test-run-http> server # [ 144.548725] postgres[1659]: ::1 2026-10-07 20:39:47.038 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin;1132vm-test-run-http> server # [ 144.571833] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT1133vm-test-run-http> server # [ 144.578112] postgres[1659]: ::1 2026-10-07 20:39:47.052 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin;1134vm-test-run-http> server # [ 144.582844] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT1135vm-test-run-http> server # [ 144.588386] postgres[1659]: ::1 2026-10-07 20:39:47.076 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin;1136vm-test-run-http> server # [ 144.596884] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT1137vm-test-run-http> server # [ 144.601641] postgres[1659]: ::1 2026-10-07 20:39:47.091 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin;1138vm-test-run-http> server # [ 144.617657] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE1139vm-test-run-http> server # [ 144.618701] postgres[1659]: ::1 2026-10-07 20:39:47.107 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin;1140vm-test-run-http> server # [ 144.641953] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE1141vm-test-run-http> server # [ 144.643941] postgres[1659]: ::1 2026-10-07 20:39:47.128 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin;1142vm-test-run-http> server # [ 144.650329] postgres[1659]: ::1 2026-10-07 20:39:47.141 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin;1143vm-test-run-http> server # [ 144.658179] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE1144vm-test-run-http> server # [ 144.659116] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE1145vm-test-run-http> server # [ 144.659618] postgres[1659]: ::1 2026-10-07 20:39:47.151 UTC [1659] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin;1146vm-test-run-http> server # [ 144.670011] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER TABLE1147vm-test-run-http> server # [ 144.674065] postgres[1659]: ::1 2026-10-07 20:39:47.166 UTC [1659] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres;1148vm-test-run-http> server # [ 144.682432] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT1149vm-test-run-http> server # [ 144.686886] postgres[1659]: ::1 2026-10-07 20:39:47.176 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user;1150vm-test-run-http> server # [ 144.701559] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT1151vm-test-run-http> server # [ 144.703562] postgres[1659]: ::1 2026-10-07 20:39:47.192 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user;1152vm-test-run-http> server # [ 144.713962] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT1153vm-test-run-http> server # [ 144.714959] postgres[1659]: ::1 2026-10-07 20:39:47.202 UTC [1659] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user;1154vm-test-run-http> server # [ 144.727120] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: GRANT1155vm-test-run-http> server # [ 144.728403] postgres[1659]: ::1 2026-10-07 20:39:47.220 UTC [1659] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user;1156vm-test-run-http> server # [ 144.745265] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER DEFAULT PRIVILEGES1157vm-test-run-http> server # [ 144.746553] postgres[1659]: ::1 2026-10-07 20:39:47.233 UTC [1659] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1158vm-test-run-http> server # [ 144.762802] postgres[1659]: ::1 2026-10-07 20:39:47.246 UTC [1659] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user;1159vm-test-run-http> server # [ 144.763998] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER DEFAULT PRIVILEGES1160vm-test-run-http> server # [ 144.765919] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1656]: ALTER DEFAULT PRIVILEGES1161vm-test-run-http> server # [ 144.801078] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211118015519_create-realtime-schema.sql1162vm-test-run-http> server # [ 145.107802] postgres[1661]: ::1 2026-10-07 20:39:47.579 UTC [1661] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime;1163vm-test-run-http> server # [ 145.143055] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1660]: CREATE SCHEMA1164vm-test-run-http> server # [ 145.181109] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211122051245_update-realtime-permissions.sql1165vm-test-run-http> server # [ 145.604453] postgres[1667]: ::1 2026-10-07 20:39:48.089 UTC [1667] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres;1166vm-test-run-http> server # [ 145.628031] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT1167vm-test-run-http> server # [ 145.635955] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT1168vm-test-run-http> server # [ 145.643052] postgres[1667]: ::1 2026-10-07 20:39:48.110 UTC [1667] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user;1169vm-test-run-http> server # [ 145.659146] postgres[1667]: ::1 2026-10-07 20:39:48.123 UTC [1667] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user;1170vm-test-run-http> server # [ 145.666245] postgres[1667]: ::1 2026-10-07 20:39:48.131 UTC [1667] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user;1171vm-test-run-http> server # [ 145.680964] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT1172vm-test-run-http> server # [ 145.690932] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1664]: GRANT1173vm-test-run-http> server # [ 145.735235] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211124212715_update-auth-owner.sql1174vm-test-run-http> server # [ 146.121235] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1668]: DO1175vm-test-run-http> server # [ 146.135962] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1668]: DO1176vm-test-run-http> server # [ 146.151560] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1668]: DO1177vm-test-run-http> server # [ 146.206016] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20211130151719_update-realtime-permissions.sql1178vm-test-run-http> server # [ 146.686169] postgres[1677]: ::1 2026-10-07 20:39:49.166 UTC [1677] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user;1179vm-test-run-http> server # [ 146.720560] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1671]: ALTER DEFAULT PRIVILEGES1180vm-test-run-http> server # [ 146.736986] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1671]: ALTER DEFAULT PRIVILEGES1181vm-test-run-http> server # [ 146.738337] postgres[1677]: ::1 2026-10-07 20:39:49.205 UTC [1677] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user;1182vm-test-run-http> server # [ 146.745562] postgres[1677]: ::1 2026-10-07 20:39:49.217 UTC [1677] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user;1183vm-test-run-http> server # [ 146.756363] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1671]: ALTER DEFAULT PRIVILEGES1184vm-test-run-http> server # [ 146.839329] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql1185vm-test-run-http> server # [ 147.381435] postgres[1693]: ::1 2026-10-07 20:39:49.853 UTC [1693] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate';1186vm-test-run-http> server # [ 147.429589] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1686]: ALTER ROLE1187vm-test-run-http> server # [ 147.462540] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql1188vm-test-run-http> server # [ 147.855981] postgres[1699]: ::1 2026-10-07 20:39:50.336 UTC [1699] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1189vm-test-run-http> server # [ 147.875266] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1190vm-test-run-http> server # [ 147.885015] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP EVENT TRIGGER1191vm-test-run-http> server # [ 147.885937] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping1192vm-test-run-http> server # [ 147.887928] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP FUNCTION1193vm-test-run-http> server # [ 147.901599] postgres[1699]: ::1 2026-10-07 20:39:50.362 UTC [1699] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1194vm-test-run-http> server # [ 147.902524] postgres[1699]: ::1 2026-10-07 20:39:50.383 UTC [1699] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1195vm-test-run-http> server # [ 147.903525] postgres[1699]: DECLARE1196vm-test-run-http> server # [ 147.904172] postgres[1699]: cmd record;1197vm-test-run-http> server # [ 147.911052] postgres[1699]: BEGIN1198vm-test-run-http> server # [ 147.911698] postgres[1699]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1199vm-test-run-http> server # [ 147.921514] postgres[1699]: LOOP1200vm-test-run-http> server # [ 147.922584] postgres[1699]: IF cmd.command_tag IN (1201vm-test-run-http> server # [ 147.922956] postgres[1699]: 'CREATE SCHEMA', 'ALTER SCHEMA'1202vm-test-run-http> server # [ 147.923423] postgres[1699]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1203vm-test-run-http> server # [ 147.937591] postgres[1699]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1204vm-test-run-http> server # [ 147.938309] postgres[1699]: , 'CREATE VIEW', 'ALTER VIEW'1205vm-test-run-http> server # [ 147.938640] postgres[1699]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1206vm-test-run-http> server # [ 147.939759] postgres[1699]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1207vm-test-run-http> server # [ 147.940916] postgres[1699]: , 'CREATE TRIGGER'1208vm-test-run-http> server # [ 147.943371] postgres[1699]: , 'CREATE TYPE'1209vm-test-run-http> server # [ 147.958897] postgres[1699]: , 'CREATE RULE'1210vm-test-run-http> server # [ 147.959683] postgres[1699]: , 'COMMENT'1211vm-test-run-http> server # [ 147.959996] postgres[1699]: )1212vm-test-run-http> server # [ 147.964263] postgres[1699]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1213vm-test-run-http> server # [ 147.974402] postgres[1699]: AND cmd.schema_name is distinct from 'pg_temp'1214vm-test-run-http> server # [ 147.975662] postgres[1699]: THEN1215vm-test-run-http> server # [ 147.976027] postgres[1699]: NOTIFY pgrst, 'reload schema';1216vm-test-run-http> server # [ 147.982559] postgres[1699]: END IF;1217vm-test-run-http> server # [ 147.983503] postgres[1699]: END LOOP;1218vm-test-run-http> server # [ 147.991240] postgres[1699]: END; $$ LANGUAGE plpgsql;1219vm-test-run-http> server # [ 147.993930] postgres[1699]: ::1 2026-10-07 20:39:50.410 UTC [1699] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1220vm-test-run-http> server # [ 148.005393] postgres[1699]: DECLARE1221vm-test-run-http> server # [ 148.006100] postgres[1699]: obj record;1222vm-test-run-http> server # [ 148.006399] postgres[1699]: BEGIN1223vm-test-run-http> server # [ 148.007450] postgres[1699]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1224vm-test-run-http> server # [ 148.009505] postgres[1699]: LOOP1225vm-test-run-http> server # [ 148.009905] postgres[1699]: IF obj.object_type IN (1226vm-test-run-http> server # [ 148.010175] postgres[1699]: 'schema'1227vm-test-run-http> server # [ 148.010433] postgres[1699]: , 'table'1228vm-test-run-http> server # [ 148.010707] postgres[1699]: , 'foreign table'1229vm-test-run-http> server # [ 148.010965] postgres[1699]: , 'view'1230vm-test-run-http> server # [ 148.011570] postgres[1699]: , 'materialized view'1231vm-test-run-http> server # [ 148.011871] postgres[1699]: , 'function'1232vm-test-run-http> server # [ 148.012109] postgres[1699]: , 'trigger'1233vm-test-run-http> server # [ 148.012336] postgres[1699]: , 'type'1234vm-test-run-http> server # [ 148.014255] postgres[1699]: , 'rule'1235vm-test-run-http> server # [ 148.015947] postgres[1699]: )1236vm-test-run-http> server # [ 148.021693] postgres[1699]: AND obj.is_temporary IS false -- no pg_temp objects1237vm-test-run-http> server # [ 148.023700] postgres[1699]: THEN1238vm-test-run-http> server # [ 148.030014] postgres[1699]: NOTIFY pgrst, 'reload schema';1239vm-test-run-http> server # [ 148.030800] postgres[1699]: END IF;1240vm-test-run-http> server # [ 148.038175] postgres[1699]: END LOOP;1241vm-test-run-http> server # [ 148.039636] postgres[1699]: END; $$ LANGUAGE plpgsql;1242vm-test-run-http> server # [ 148.041419] postgres[1699]: ::1 2026-10-07 20:39:50.438 UTC [1699] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1243vm-test-run-http> server # [ 148.054497] postgres[1699]: ::1 2026-10-07 20:39:50.444 UTC [1699] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1244vm-test-run-http> server # [ 148.062471] postgres[1699]: ON ddl_command_end1245vm-test-run-http> server # [ 148.068807] postgres[1699]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1246vm-test-run-http> server # [ 148.070382] postgres[1699]: ::1 2026-10-07 20:39:50.461 UTC [1699] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1247vm-test-run-http> server # [ 148.074709] postgres[1699]: ::1 2026-10-07 20:39:50.470 UTC [1699] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1248vm-test-run-http> server # [ 148.076047] postgres[1699]: ON sql_drop1249vm-test-run-http> server # [ 148.082382] postgres[1699]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1250vm-test-run-http> server # [ 148.085160] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE FUNCTION1251vm-test-run-http> server # [ 148.103516] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE FUNCTION1252vm-test-run-http> server # [ 148.104206] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping1253vm-test-run-http> server # [ 148.128500] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP EVENT TRIGGER1254vm-test-run-http> server # [ 148.142173] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE EVENT TRIGGER1255vm-test-run-http> server # [ 148.146864] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping1256vm-test-run-http> server # [ 148.156309] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: DROP EVENT TRIGGER1257vm-test-run-http> server # [ 148.156931] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1698]: CREATE EVENT TRIGGER1258vm-test-run-http> server # [ 148.164538] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql1259vm-test-run-http> server # [ 149.042073] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1700]: DO1260vm-test-run-http> server # [ 149.064847] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql1261vm-test-run-http> server # [ 149.555290] postgres[1708]: ::1 2026-10-07 20:39:52.014 UTC [1708] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public;1262vm-test-run-http> server # [ 149.631363] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE SCHEMA1263vm-test-run-http> server # [ 149.639130] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping1264vm-test-run-http> server # [ 149.646131] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: DROP FUNCTION1265vm-test-run-http> server # [ 149.647400] postgres[1708]: ::1 2026-10-07 20:39:52.096 UTC [1708] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb);1266vm-test-run-http> server # [ 149.660205] postgres[1708]: ::1 2026-10-07 20:39:52.112 UTC [1708] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1267vm-test-run-http> server # [ 149.667382] postgres[1708]: "operationName" text default null,1268vm-test-run-http> server # [ 149.683504] postgres[1708]: query text default null,1269vm-test-run-http> server # [ 149.686178] postgres[1708]: variables jsonb default null,1270vm-test-run-http> server # [ 149.695126] postgres[1708]: extensions jsonb default null1271vm-test-run-http> server # [ 149.701057] postgres[1708]: )1272vm-test-run-http> server # [ 149.707053] postgres[1708]: returns jsonb1273vm-test-run-http> server # [ 149.727290] postgres[1708]: language plpgsql1274vm-test-run-http> server # [ 149.730867] postgres[1708]: as $$1275vm-test-run-http> server # [ 149.731501] postgres[1708]: DECLARE1276vm-test-run-http> server # [ 149.731868] postgres[1708]: server_version float;1277vm-test-run-http> server # [ 149.732157] postgres[1708]: BEGIN1278vm-test-run-http> server # [ 149.736895] postgres[1708]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1279vm-test-run-http> server # [ 149.750261] postgres[1708]:1280vm-test-run-http> server # [ 149.751017] postgres[1708]: IF server_version >= 14 THEN1281vm-test-run-http> server # [ 149.759299] postgres[1708]: RETURN jsonb_build_object(1282vm-test-run-http> server # [ 149.766566] postgres[1708]: 'data', null::jsonb,1283vm-test-run-http> server # [ 149.774947] postgres[1708]: 'errors', array['pg_graphql extension is not enabled.']1284vm-test-run-http> server # [ 149.782905] postgres[1708]: );1285vm-test-run-http> server # [ 149.783778] postgres[1708]: ELSE1286vm-test-run-http> server # [ 149.797146] postgres[1708]: RETURN jsonb_build_object(1287vm-test-run-http> server # [ 149.798566] postgres[1708]: 'data', null::jsonb,1288vm-test-run-http> server # [ 149.799148] postgres[1708]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1289vm-test-run-http> server # [ 149.812392] postgres[1708]: );1290vm-test-run-http> server # [ 149.839235] postgres[1708]: END IF;1291vm-test-run-http> server # [ 149.851448] postgres[1708]: END;1292vm-test-run-http> server # [ 149.866640] postgres[1708]: $$;1293vm-test-run-http> server # [ 149.868891] postgres[1708]: ::1 2026-10-07 20:39:52.153 UTC [1708] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role;1294vm-test-run-http> server # [ 149.890808] postgres[1708]: ::1 2026-10-07 20:39:52.171 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role;1295vm-test-run-http> server # [ 149.902356] postgres[1708]: ::1 2026-10-07 20:39:52.192 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role;1296vm-test-run-http> server # [ 149.903894] postgres[1708]: ::1 2026-10-07 20:39:52.209 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role;1297vm-test-run-http> server # [ 149.904608] postgres[1708]: ::1 2026-10-07 20:39:52.224 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1298vm-test-run-http> server # [ 149.919610] postgres[1708]: on sequences to postgres, anon, authenticated, service_role;1299vm-test-run-http> server # [ 149.930793] postgres[1708]: ::1 2026-10-07 20:39:52.241 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1300vm-test-run-http> server # [ 149.934300] postgres[1708]: on tables to postgres, anon, authenticated, service_role;1301vm-test-run-http> server # [ 149.935688] postgres[1708]: ::1 2026-10-07 20:39:52.255 UTC [1708] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all1302vm-test-run-http> server # [ 149.942420] postgres[1708]: on functions to postgres, anon, authenticated, service_role;1303vm-test-run-http> server # [ 149.948776] postgres[1708]: ::1 2026-10-07 20:39:52.273 UTC [1708] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access()1304vm-test-run-http> server # [ 149.951424] postgres[1708]: RETURNS event_trigger1305vm-test-run-http> server # [ 149.952169] postgres[1708]: LANGUAGE plpgsql1306vm-test-run-http> server # [ 149.961794] postgres[1708]: AS $func$1307vm-test-run-http> server # [ 149.962767] postgres[1708]: DECLARE1308vm-test-run-http> server # [ 149.968565] postgres[1708]: func_is_graphql_resolve bool;1309vm-test-run-http> server # [ 149.970638] postgres[1708]: BEGIN1310vm-test-run-http> server # [ 149.971144] postgres[1708]: func_is_graphql_resolve = (1311vm-test-run-http> server # [ 149.977039] postgres[1708]: SELECT n.proname = 'resolve'1312vm-test-run-http> server # [ 149.978485] postgres[1708]: FROM pg_event_trigger_ddl_commands() AS ev1313vm-test-run-http> server # [ 149.979260] postgres[1708]: LEFT JOIN pg_catalog.pg_proc AS n1314vm-test-run-http> server # [ 149.979912] postgres[1708]: ON ev.objid = n.oid1315vm-test-run-http> server # [ 149.986317] postgres[1708]: );1316vm-test-run-http> server # [ 149.994110] postgres[1708]:1317vm-test-run-http> server # [ 150.010307] postgres[1708]: IF func_is_graphql_resolve1318vm-test-run-http> server # [ 150.013449] postgres[1708]: THEN1319vm-test-run-http> server # [ 150.015043] postgres[1708]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1320vm-test-run-http> server # [ 150.017419] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE FUNCTION1321vm-test-run-http> server # [ 150.027929] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: GRANT1322vm-test-run-http> server # [ 150.038871] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES1323vm-test-run-http> server # [ 150.039566] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES1324vm-test-run-http> server # [ 150.046722] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES1325vm-test-run-http> server # [ 150.047721] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES1326vm-test-run-http> server # [ 150.048113] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES1327vm-test-run-http> server # [ 150.048534] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: ALTER DEFAULT PRIVILEGES1328vm-test-run-http> server # [ 150.051755] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE FUNCTION1329vm-test-run-http> server # [ 150.056006] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping1330vm-test-run-http> server # [ 150.059433] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: DROP EVENT TRIGGER1331vm-test-run-http> server # [ 150.062091] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE EVENT TRIGGER1332vm-test-run-http> server # [ 150.063520] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: COMMENT1333vm-test-run-http> server # [ 150.064309] postgres[1708]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1334vm-test-run-http> server # [ 150.070333] postgres[1708]:1335vm-test-run-http> server # [ 150.078288] postgres[1708]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1336vm-test-run-http> server # [ 150.079699] postgres[1708]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1337vm-test-run-http> server # [ 150.088548] postgres[1708]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1338vm-test-run-http> server # [ 150.090944] postgres[1708]:1339vm-test-run-http> server # [ 150.091802] postgres[1708]: DROP FUNCTION IF EXISTS graphql_public.graphql;1340vm-test-run-http> server # [ 150.098316] postgres[1708]: create or replace function graphql_public.graphql(1341vm-test-run-http> server # [ 150.102369] postgres[1708]: "operationName" text default null,1342vm-test-run-http> server # [ 150.114064] postgres[1708]: query text default null,1343vm-test-run-http> server # [ 150.115802] postgres[1708]: variables jsonb default null,1344vm-test-run-http> server # [ 150.125228] postgres[1708]: extensions jsonb default null1345vm-test-run-http> server # [ 150.126501] postgres[1708]: )1346vm-test-run-http> server # [ 150.126998] postgres[1708]: returns jsonb1347vm-test-run-http> server # [ 150.127272] postgres[1708]: language sql1348vm-test-run-http> server # [ 150.127497] postgres[1708]: as $$1349vm-test-run-http> server # [ 150.127845] postgres[1708]: SELECT graphql.resolve(query, coalesce(variables, '{}'));1350vm-test-run-http> server # [ 150.139099] postgres[1708]: $$;1351vm-test-run-http> server # [ 150.149707] postgres[1708]:1352vm-test-run-http> server # [ 150.150727] postgres[1708]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1353vm-test-run-http> server # [ 150.151230] postgres[1708]: END IF;1354vm-test-run-http> server # [ 150.151829] postgres[1708]:1355vm-test-run-http> server # [ 150.159875] postgres[1708]: END;1356vm-test-run-http> server # [ 150.160297] postgres[1708]: $func$;1357vm-test-run-http> server # [ 150.162634] postgres[1708]: ::1 2026-10-07 20:39:52.290 UTC [1708] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access;1358vm-test-run-http> server # [ 150.163575] postgres[1708]: ::1 2026-10-07 20:39:52.324 UTC [1708] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION')1359vm-test-run-http> server # [ 150.176007] postgres[1708]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access();1360vm-test-run-http> server # [ 150.179743] postgres[1708]: ::1 2026-10-07 20:39:52.369 UTC [1708] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql';1361vm-test-run-http> server # [ 150.188797] postgres[1708]: ::1 2026-10-07 20:39:52.419 UTC [1708] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1362vm-test-run-http> server # [ 150.191617] postgres[1708]: RETURNS event_trigger1363vm-test-run-http> server # [ 150.201766] postgres[1708]: LANGUAGE plpgsql1364vm-test-run-http> server # [ 150.203494] postgres[1708]: AS $func$1365vm-test-run-http> server # [ 150.213212] postgres[1708]: DECLARE1366vm-test-run-http> server # [ 150.215922] postgres[1708]: graphql_is_dropped bool;1367vm-test-run-http> server # [ 150.235143] postgres[1708]: BEGIN1368vm-test-run-http> server # [ 150.243636] postgres[1708]: graphql_is_dropped = (1369vm-test-run-http> server # [ 150.268245] postgres[1708]: SELECT ev.schema_name = 'graphql_public'1370vm-test-run-http> server # [ 150.270056] postgres[1708]: FROM pg_event_trigger_dropped_objects() AS ev1371vm-test-run-http> server # [ 150.272074] postgres[1708]: WHERE ev.schema_name = 'graphql_public'1372vm-test-run-http> server # [ 150.280581] postgres[1708]: );1373vm-test-run-http> server # [ 150.283595] postgres[1708]:1374vm-test-run-http> server # [ 150.314447] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE FUNCTION1375vm-test-run-http> server # [ 150.324436] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping1376vm-test-run-http> server # [ 150.327114] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: DROP EVENT TRIGGER1377vm-test-run-http> server # [ 150.331445] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: CREATE EVENT TRIGGER1378vm-test-run-http> server # [ 150.338438] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1702]: COMMENT1379vm-test-run-http> server # [ 150.347584] postgres[1708]: IF graphql_is_dropped1380vm-test-run-http> server # [ 150.361017] postgres[1708]: THEN1381vm-test-run-http> server # [ 150.362360] postgres[1708]: create or replace function graphql_public.graphql(1382vm-test-run-http> server # [ 150.369967] postgres[1708]: "operationName" text default null,1383vm-test-run-http> server # [ 150.371103] postgres[1708]: query text default null,1384vm-test-run-http> server # [ 150.371655] postgres[1708]: variables jsonb default null,1385vm-test-run-http> server # [ 150.373531] postgres[1708]: extensions jsonb default null1386vm-test-run-http> server # [ 150.373987] postgres[1708]: )1387vm-test-run-http> server # [ 150.374306] postgres[1708]: returns jsonb1388vm-test-run-http> server # [ 150.374788] postgres[1708]: language plpgsql1389vm-test-run-http> server # [ 150.380221] postgres[1708]: as $$1390vm-test-run-http> server # [ 150.386274] postgres[1708]: DECLARE1391vm-test-run-http> server # [ 150.389168] postgres[1708]: server_version float;1392vm-test-run-http> server # [ 150.391265] postgres[1708]: BEGIN1393vm-test-run-http> server # [ 150.393709] postgres[1708]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1394vm-test-run-http> server # [ 150.394682] postgres[1708]:1395vm-test-run-http> server # [ 150.395685] postgres[1708]: IF server_version >= 14 THEN1396vm-test-run-http> server # [ 150.400919] postgres[1708]: RETURN jsonb_build_object(1397vm-test-run-http> server # [ 150.403594] postgres[1708]: 'data', null::jsonb,1398vm-test-run-http> server # [ 150.405474] postgres[1708]: 'errors', array['pg_graphql extension is not enabled.']1399vm-test-run-http> server # [ 150.405938] postgres[1708]: );1400vm-test-run-http> server # [ 150.406214] postgres[1708]: ELSE1401vm-test-run-http> server # [ 150.407519] postgres[1708]: RETURN jsonb_build_object(1402vm-test-run-http> server # [ 150.409265] postgres[1708]: 'data', null::jsonb,1403vm-test-run-http> server # [ 150.417538] postgres[1708]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.']1404vm-test-run-http> server # [ 150.422276] postgres[1708]: );1405vm-test-run-http> server # [ 150.422925] postgres[1708]: END IF;1406vm-test-run-http> server # [ 150.423979] postgres[1708]: END;1407vm-test-run-http> server # [ 150.429754] postgres[1708]: $$;1408vm-test-run-http> server # [ 150.430974] postgres[1708]: END IF;1409vm-test-run-http> server # [ 150.431909] postgres[1708]:1410vm-test-run-http> server # [ 150.433761] postgres[1708]: END;1411vm-test-run-http> server # [ 150.436036] postgres[1708]: $func$;1412vm-test-run-http> server # [ 150.437285] postgres[1708]: ::1 2026-10-07 20:39:52.515 UTC [1708] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder;1413vm-test-run-http> server # [ 150.439530] postgres[1708]: ::1 2026-10-07 20:39:52.517 UTC [1708] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION')1414vm-test-run-http> server # [ 150.446944] postgres[1708]: EXECUTE PROCEDURE extensions.set_graphql_placeholder();1415vm-test-run-http> server # [ 150.447807] postgres[1708]: ::1 2026-10-07 20:39:52.527 UTC [1708] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql';1416vm-test-run-http> server # [ 150.464542] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql1417vm-test-run-http> server # [ 150.686275] postgres[1721]: ::1 2026-10-07 20:39:53.159 UTC [1721] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart;1418vm-test-run-http> server # [ 150.715751] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping1419vm-test-run-http> server # [ 150.726585] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP EVENT TRIGGER1420vm-test-run-http> server # [ 150.734927] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: 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, skipping1421vm-test-run-http> server # [ 150.743411] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP FUNCTION1422vm-test-run-http> server # [ 150.749873] postgres[1721]: ::1 2026-10-07 20:39:53.182 UTC [1721] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart();1423vm-test-run-http> server # [ 150.750425] postgres[1721]: ::1 2026-10-07 20:39:53.221 UTC [1721] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$1424vm-test-run-http> server # [ 150.751296] postgres[1721]: DECLARE1425vm-test-run-http> server # [ 150.752751] postgres[1721]: cmd record;1426vm-test-run-http> server # [ 150.761772] postgres[1721]: BEGIN1427vm-test-run-http> server # [ 150.762670] postgres[1721]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands()1428vm-test-run-http> server # [ 150.773825] postgres[1721]: LOOP1429vm-test-run-http> server # [ 150.774654] postgres[1721]: IF cmd.command_tag IN (1430vm-test-run-http> server # [ 150.775231] postgres[1721]: 'CREATE SCHEMA', 'ALTER SCHEMA'1431vm-test-run-http> server # [ 150.775681] postgres[1721]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE'1432vm-test-run-http> server # [ 150.776346] postgres[1721]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE'1433vm-test-run-http> server # [ 150.778965] postgres[1721]: , 'CREATE VIEW', 'ALTER VIEW'1434vm-test-run-http> server # [ 150.779593] postgres[1721]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW'1435vm-test-run-http> server # [ 150.779917] postgres[1721]: , 'CREATE FUNCTION', 'ALTER FUNCTION'1436vm-test-run-http> server # [ 150.780614] postgres[1721]: , 'CREATE TRIGGER'1437vm-test-run-http> server # [ 150.781602] postgres[1721]: , 'CREATE TYPE', 'ALTER TYPE'1438vm-test-run-http> server # [ 150.782151] postgres[1721]: , 'CREATE RULE'1439vm-test-run-http> server # [ 150.783217] postgres[1721]: , 'COMMENT'1440vm-test-run-http> server # [ 150.784121] postgres[1721]: )1441vm-test-run-http> server # [ 150.789807] postgres[1721]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp1442vm-test-run-http> server # [ 150.790972] postgres[1721]: AND cmd.schema_name is distinct from 'pg_temp'1443vm-test-run-http> server # [ 150.791357] postgres[1721]: THEN1444vm-test-run-http> server # [ 150.791595] postgres[1721]: NOTIFY pgrst, 'reload schema';1445vm-test-run-http> server # [ 150.796815] postgres[1721]: END IF;1446vm-test-run-http> server # [ 150.797886] postgres[1721]: END LOOP;1447vm-test-run-http> server # [ 150.798264] postgres[1721]: END; $$ LANGUAGE plpgsql;1448vm-test-run-http> server # [ 150.821309] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE FUNCTION1449vm-test-run-http> server # [ 150.824799] postgres[1721]: ::1 2026-10-07 20:39:53.315 UTC [1721] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$1450vm-test-run-http> server # [ 150.825855] postgres[1721]: DECLARE1451vm-test-run-http> server # [ 150.826217] postgres[1721]: obj record;1452vm-test-run-http> server # [ 150.826612] postgres[1721]: BEGIN1453vm-test-run-http> server # [ 150.827427] postgres[1721]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects()1454vm-test-run-http> server # [ 150.827772] postgres[1721]: LOOP1455vm-test-run-http> server # [ 150.828019] postgres[1721]: IF obj.object_type IN (1456vm-test-run-http> server # [ 150.833274] postgres[1721]: 'schema'1457vm-test-run-http> server # [ 150.835591] postgres[1721]: , 'table'1458vm-test-run-http> server # [ 150.836220] postgres[1721]: , 'foreign table'1459vm-test-run-http> server # [ 150.841673] postgres[1721]: , 'view'1460vm-test-run-http> server # [ 150.842410] postgres[1721]: , 'materialized view'1461vm-test-run-http> server # [ 150.842704] postgres[1721]: , 'function'1462vm-test-run-http> server # [ 150.843258] postgres[1721]: , 'trigger'1463vm-test-run-http> server # [ 150.843548] postgres[1721]: , 'type'1464vm-test-run-http> server # [ 150.843905] postgres[1721]: , 'rule'1465vm-test-run-http> server # [ 150.844533] postgres[1721]: )1466vm-test-run-http> server # [ 150.853938] postgres[1721]: AND obj.is_temporary IS false -- no pg_temp objects1467vm-test-run-http> server # [ 150.854812] postgres[1721]: THEN1468vm-test-run-http> server # [ 150.855303] postgres[1721]: NOTIFY pgrst, 'reload schema';1469vm-test-run-http> server # [ 150.855621] postgres[1721]: END IF;1470vm-test-run-http> server # [ 150.855883] postgres[1721]: END LOOP;1471vm-test-run-http> server # [ 150.856118] postgres[1721]: END; $$ LANGUAGE plpgsql;1472vm-test-run-http> server # [ 150.856531] postgres[1721]: ::1 2026-10-07 20:39:53.348 UTC [1721] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch;1473vm-test-run-http> server # [ 150.858390] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE FUNCTION1474vm-test-run-http> server # [ 150.873655] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP EVENT TRIGGER1475vm-test-run-http> server # [ 150.877484] postgres[1721]: ::1 2026-10-07 20:39:53.366 UTC [1721] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch1476vm-test-run-http> server # [ 150.879571] postgres[1721]: ON ddl_command_end1477vm-test-run-http> server # [ 150.879965] postgres[1721]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch();1478vm-test-run-http> server # [ 150.891076] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE EVENT TRIGGER1479vm-test-run-http> server # [ 150.892044] postgres[1721]: ::1 2026-10-07 20:39:53.384 UTC [1721] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch;1480vm-test-run-http> server # [ 150.916970] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: DROP EVENT TRIGGER1481vm-test-run-http> server # [ 150.918541] postgres[1721]: ::1 2026-10-07 20:39:53.409 UTC [1721] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch1482vm-test-run-http> server # [ 150.919460] postgres[1721]: ON sql_drop1483vm-test-run-http> server # [ 150.920452] postgres[1721]: EXECUTE PROCEDURE extensions.pgrst_drop_watch();1484vm-test-run-http> server # [ 150.927735] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1716]: CREATE EVENT TRIGGER1485vm-test-run-http> server # [ 150.979955] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220322085208_gotrue-session-limit.sql1486vm-test-run-http> server # [ 151.382872] postgres[1731]: ::1 2026-10-07 20:39:53.845 UTC [1731] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000;1487vm-test-run-http> server # [ 151.425764] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1729]: ALTER ROLE1488vm-test-run-http> server # [ 151.489718] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql1489vm-test-run-http> server # [ 151.825966] postgres[1737]: ::1 2026-10-07 20:39:54.302 UTC [1737] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1490vm-test-run-http> server # [ 151.827054] postgres[1737]: returns event_trigger1491vm-test-run-http> server # [ 151.827751] postgres[1737]: language plpgsql1492vm-test-run-http> server # [ 151.828080] postgres[1737]: AS $func$1493vm-test-run-http> server # [ 151.832765] postgres[1737]: DECLARE1494vm-test-run-http> server # [ 151.843713] postgres[1737]: func_is_graphql_resolve bool;1495vm-test-run-http> server # [ 151.845888] postgres[1737]: BEGIN1496vm-test-run-http> server # [ 151.846710] postgres[1737]: func_is_graphql_resolve = (1497vm-test-run-http> server # [ 151.847047] postgres[1737]: SELECT n.proname = 'resolve'1498vm-test-run-http> server # [ 151.847429] postgres[1737]: FROM pg_event_trigger_ddl_commands() AS ev1499vm-test-run-http> server # [ 151.848040] postgres[1737]: LEFT JOIN pg_catalog.pg_proc AS n1500vm-test-run-http> server # [ 151.849062] postgres[1737]: ON ev.objid = n.oid1501vm-test-run-http> server # [ 151.857593] postgres[1737]: );1502vm-test-run-http> server # [ 151.858262] postgres[1737]:1503vm-test-run-http> server # [ 151.859203] postgres[1737]: IF func_is_graphql_resolve1504vm-test-run-http> server # [ 151.859800] postgres[1737]: THEN1505vm-test-run-http> server # [ 151.860363] postgres[1737]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1506vm-test-run-http> server # [ 151.860712] postgres[1737]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role;1507vm-test-run-http> server # [ 151.866466] postgres[1737]:1508vm-test-run-http> server # [ 151.868067] postgres[1737]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1509vm-test-run-http> server # [ 151.878880] postgres[1737]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1510vm-test-run-http> server # [ 151.887378] postgres[1737]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1511vm-test-run-http> server # [ 151.889584] postgres[1737]:1512vm-test-run-http> server # [ 151.890093] postgres[1737]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1513vm-test-run-http> server # [ 151.891126] postgres[1737]: DROP FUNCTION IF EXISTS graphql_public.graphql;1514vm-test-run-http> server # [ 151.891842] postgres[1737]: create or replace function graphql_public.graphql(1515vm-test-run-http> server # [ 151.900621] postgres[1737]: "operationName" text default null,1516vm-test-run-http> server # [ 151.901291] postgres[1737]: query text default null,1517vm-test-run-http> server # [ 151.902117] postgres[1737]: variables jsonb default null,1518vm-test-run-http> server # [ 151.902854] postgres[1737]: extensions jsonb default null1519vm-test-run-http> server # [ 151.903596] postgres[1737]: )1520vm-test-run-http> server # [ 151.904172] postgres[1737]: returns jsonb1521vm-test-run-http> server # [ 151.917016] postgres[1737]: language sql1522vm-test-run-http> server # [ 151.917641] postgres[1737]: as $$1523vm-test-run-http> server # [ 151.918376] postgres[1737]: -- This changed1524vm-test-run-http> server # [ 151.924188] postgres[1737]: select graphql.resolve(1525vm-test-run-http> server # [ 151.935171] postgres[1737]: query := query,1526vm-test-run-http> server # [ 151.938077] postgres[1737]: variables := coalesce(variables, '{}'),1527vm-test-run-http> server # [ 151.939219] postgres[1737]: "operationName" := "operationName",1528vm-test-run-http> server # [ 151.942376] postgres[1737]: extensions := extensions1529vm-test-run-http> server # [ 151.949412] postgres[1737]: );1530vm-test-run-http> server # [ 151.950165] postgres[1737]: $$;1531vm-test-run-http> server # [ 151.950697] postgres[1737]:1532vm-test-run-http> server # [ 151.951352] postgres[1737]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role;1533vm-test-run-http> server # [ 151.957630] postgres[1737]: END IF;1534vm-test-run-http> server # [ 151.962602] postgres[1737]:1535vm-test-run-http> server # [ 151.962991] postgres[1737]: END;1536vm-test-run-http> server # [ 151.963597] postgres[1737]: $func$;1537vm-test-run-http> server # [ 151.964050] postgres[1737]: ::1 2026-10-07 20:39:54.437 UTC [1737] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder()1538vm-test-run-http> server # [ 151.973835] postgres[1737]: RETURNS event_trigger1539vm-test-run-http> server # [ 151.974452] postgres[1737]: LANGUAGE plpgsql1540vm-test-run-http> server # [ 151.975353] postgres[1737]: AS $func$1541vm-test-run-http> server # [ 151.975656] postgres[1737]: DECLARE1542vm-test-run-http> server # [ 151.976904] postgres[1737]: graphql_is_dropped bool;1543vm-test-run-http> server # [ 151.977594] postgres[1737]: BEGIN1544vm-test-run-http> server # [ 151.977926] postgres[1737]: graphql_is_dropped = (1545vm-test-run-http> server # [ 151.978580] postgres[1737]: SELECT ev.schema_name = 'graphql_public'1546vm-test-run-http> server # [ 151.980359] postgres[1737]: FROM pg_event_trigger_dropped_objects() AS ev1547vm-test-run-http> server # [ 151.982003] postgres[1737]: WHERE ev.schema_name = 'graphql_public'1548vm-test-run-http> server # [ 151.982564] postgres[1737]: );1549vm-test-run-http> server # [ 151.983248] postgres[1737]:1550vm-test-run-http> server # [ 151.983594] postgres[1737]: IF graphql_is_dropped1551vm-test-run-http> server # [ 151.984044] postgres[1737]: THEN1552vm-test-run-http> server # [ 151.984291] postgres[1737]: create or replace function graphql_public.graphql(1553vm-test-run-http> server # [ 151.986908] postgres[1737]: "operationName" text default null,1554vm-test-run-http> server # [ 151.987479] postgres[1737]: query text default null,1555vm-test-run-http> server # [ 151.988936] postgres[1737]: variables jsonb default null,1556vm-test-run-http> server # [ 151.989822] postgres[1737]: extensions jsonb default null1557vm-test-run-http> server # [ 151.990988] postgres[1737]: )1558vm-test-run-http> server # [ 151.991486] postgres[1737]: returns jsonb1559vm-test-run-http> server # [ 151.993162] postgres[1737]: language plpgsql1560vm-test-run-http> server # [ 151.995079] postgres[1737]: as $$1561vm-test-run-http> server # [ 151.995473] postgres[1737]: DECLARE1562vm-test-run-http> server # [ 151.995732] postgres[1737]: server_version float;1563vm-test-run-http> server # [ 151.995987] postgres[1737]: BEGIN1564vm-test-run-http> server # [ 151.997638] postgres[1737]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1565vm-test-run-http> server # [ 151.998199] postgres[1737]:1566vm-test-run-http> server # [ 151.998739] postgres[1737]: IF server_version >= 14 THEN1567vm-test-run-http> server # [ 151.999548] postgres[1737]: RETURN jsonb_build_object(1568vm-test-run-http> server # [ 152.000431] postgres[1737]: 'errors', jsonb_build_array(1569vm-test-run-http> server # [ 152.002394] postgres[1737]: jsonb_build_object(1570vm-test-run-http> server # [ 152.002964] postgres[1737]: 'message', 'pg_graphql extension is not enabled.'1571vm-test-run-http> server # [ 152.004567] postgres[1737]: )1572vm-test-run-http> server # [ 152.005066] postgres[1737]: )1573vm-test-run-http> server # [ 152.005472] postgres[1737]: );1574vm-test-run-http> server # [ 152.006121] postgres[1737]: ELSE1575vm-test-run-http> server # [ 152.006706] postgres[1737]: RETURN jsonb_build_object(1576vm-test-run-http> server # [ 152.008270] postgres[1737]: 'errors', jsonb_build_array(1577vm-test-run-http> server # [ 152.011347] postgres[1737]: jsonb_build_object(1578vm-test-run-http> server # [ 152.012954] postgres[1737]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1579vm-test-run-http> server # [ 152.018712] postgres[1737]: )1580vm-test-run-http> server # [ 152.019474] postgres[1737]: )1581vm-test-run-http> server # [ 152.021776] postgres[1737]: );1582vm-test-run-http> server # [ 152.026959] postgres[1737]: END IF;1583vm-test-run-http> server # [ 152.027692] postgres[1737]: END;1584vm-test-run-http> server # [ 152.028027] postgres[1737]: $$;1585vm-test-run-http> server # [ 152.034207] postgres[1737]: END IF;1586vm-test-run-http> server # [ 152.034943] postgres[1737]:1587vm-test-run-http> server # [ 152.035212] postgres[1737]: END;1588vm-test-run-http> server # [ 152.035580] postgres[1737]: $func$;1589vm-test-run-http> server # [ 152.043495] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: CREATE FUNCTION1590vm-test-run-http> server # [ 152.051127] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: CREATE FUNCTION1591vm-test-run-http> server # [ 152.059576] postgres[1737]: ::1 2026-10-07 20:39:54.519 UTC [1737] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql(1592vm-test-run-http> server # [ 152.065573] postgres[1737]: "operationName" text default null,1593vm-test-run-http> server # [ 152.066269] postgres[1737]: query text default null,1594vm-test-run-http> server # [ 152.067385] postgres[1737]: variables jsonb default null,1595vm-test-run-http> server # [ 152.067843] postgres[1737]: extensions jsonb default null1596vm-test-run-http> server # [ 152.072073] postgres[1737]: )1597vm-test-run-http> server # [ 152.073528] postgres[1737]: returns jsonb1598vm-test-run-http> server # [ 152.073907] postgres[1737]: language plpgsql1599vm-test-run-http> server # [ 152.074601] postgres[1737]: as $$1600vm-test-run-http> server # [ 152.075069] postgres[1737]: DECLARE1601vm-test-run-http> server # [ 152.075344] postgres[1737]: server_version float;1602vm-test-run-http> server # [ 152.075911] postgres[1737]: BEGIN1603vm-test-run-http> server # [ 152.076185] postgres[1737]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float);1604vm-test-run-http> server # [ 152.077306] postgres[1737]:1605vm-test-run-http> server # [ 152.078107] postgres[1737]: IF server_version >= 14 THEN1606vm-test-run-http> server # [ 152.078555] postgres[1737]: RETURN jsonb_build_object(1607vm-test-run-http> server # [ 152.079168] postgres[1737]: 'errors', jsonb_build_array(1608vm-test-run-http> server # [ 152.079656] postgres[1737]: jsonb_build_object(1609vm-test-run-http> server # [ 152.082954] postgres[1737]: 'message', 'pg_graphql extension is not enabled.'1610vm-test-run-http> server # [ 152.083528] postgres[1737]: )1611vm-test-run-http> server # [ 152.083834] postgres[1737]: )1612vm-test-run-http> server # [ 152.084416] postgres[1737]: );1613vm-test-run-http> server # [ 152.084714] postgres[1737]: ELSE1614vm-test-run-http> server # [ 152.089038] postgres[1737]: RETURN jsonb_build_object(1615vm-test-run-http> server # [ 152.089905] postgres[1737]: 'errors', jsonb_build_array(1616vm-test-run-http> server # [ 152.090615] postgres[1737]: jsonb_build_object(1617vm-test-run-http> server # [ 152.098256] postgres[1737]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.'1618vm-test-run-http> server # [ 152.099509] postgres[1737]: )1619vm-test-run-http> server # [ 152.099931] postgres[1737]: )1620vm-test-run-http> server # [ 152.100194] postgres[1737]: );1621vm-test-run-http> server # [ 152.100443] postgres[1737]: END IF;1622vm-test-run-http> server # [ 152.100677] postgres[1737]: END;1623vm-test-run-http> server # [ 152.106764] postgres[1737]: $$;1624vm-test-run-http> server # [ 152.115088] postgres[1737]: ::1 2026-10-07 20:39:54.579 UTC [1737] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1625vm-test-run-http> server # [ 152.121969] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: CREATE FUNCTION1626vm-test-run-http> server # [ 152.122562] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: psql:/nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping1627vm-test-run-http> server # [ 152.123834] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: DROP EXTENSION1628vm-test-run-http> server # [ 154.377952] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1735]: DO1629vm-test-run-http> server # [ 154.390082] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql1630vm-test-run-http> server # [ 154.754849] postgres[1763]: ::1 2026-10-07 20:39:57.234 UTC [1763] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres;1631vm-test-run-http> server # [ 154.772050] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1762]: GRANT ROLE1632vm-test-run-http> server # [ 154.812961] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql1633vm-test-run-http> server # [ 155.070420] postgres[1765]: ::1 2026-10-07 20:39:57.554 UTC [1765] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1634vm-test-run-http> server # [ 155.075734] postgres[1765]: returns event_trigger1635vm-test-run-http> server # [ 155.081242] postgres[1765]: language plpgsql1636vm-test-run-http> server # [ 155.082170] postgres[1765]: AS $func$1637vm-test-run-http> server # [ 155.082508] postgres[1765]: DECLARE1638vm-test-run-http> server # [ 155.082747] postgres[1765]: func_is_graphql_resolve bool;1639vm-test-run-http> server # [ 155.082997] postgres[1765]: BEGIN1640vm-test-run-http> server # [ 155.083222] postgres[1765]: func_is_graphql_resolve = (1641vm-test-run-http> server # [ 155.083625] postgres[1765]: SELECT n.proname = 'resolve'1642vm-test-run-http> server # [ 155.089225] postgres[1765]: FROM pg_event_trigger_ddl_commands() AS ev1643vm-test-run-http> server # [ 155.090158] postgres[1765]: LEFT JOIN pg_catalog.pg_proc AS n1644vm-test-run-http> server # [ 155.090913] postgres[1765]: ON ev.objid = n.oid1645vm-test-run-http> server # [ 155.091238] postgres[1765]: );1646vm-test-run-http> server # [ 155.093096] postgres[1765]:1647vm-test-run-http> server # [ 155.093490] postgres[1765]: IF func_is_graphql_resolve1648vm-test-run-http> server # [ 155.093755] postgres[1765]: THEN1649vm-test-run-http> server # [ 155.094098] postgres[1765]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1650vm-test-run-http> server # [ 155.098899] postgres[1765]: DROP FUNCTION IF EXISTS graphql_public.graphql;1651vm-test-run-http> server # [ 155.099537] postgres[1765]: create or replace function graphql_public.graphql(1652vm-test-run-http> server # [ 155.101939] postgres[1765]: "operationName" text default null,1653vm-test-run-http> server # [ 155.103058] postgres[1765]: query text default null,1654vm-test-run-http> server # [ 155.103645] postgres[1765]: variables jsonb default null,1655vm-test-run-http> server # [ 155.104231] postgres[1765]: extensions jsonb default null1656vm-test-run-http> server # [ 155.105550] postgres[1765]: )1657vm-test-run-http> server # [ 155.106502] postgres[1765]: returns jsonb1658vm-test-run-http> server # [ 155.106830] postgres[1765]: language sql1659vm-test-run-http> server # [ 155.107098] postgres[1765]: as $$1660vm-test-run-http> server # [ 155.107607] postgres[1765]: select graphql.resolve(1661vm-test-run-http> server # [ 155.108008] postgres[1765]: query := query,1662vm-test-run-http> server # [ 155.108824] postgres[1765]: variables := coalesce(variables, '{}'),1663vm-test-run-http> server # [ 155.109884] postgres[1765]: "operationName" := "operationName",1664vm-test-run-http> server # [ 155.110751] postgres[1765]: extensions := extensions1665vm-test-run-http> server # [ 155.111991] postgres[1765]: );1666vm-test-run-http> server # [ 155.113698] postgres[1765]: $$;1667vm-test-run-http> server # [ 155.114086] postgres[1765]:1668vm-test-run-http> server # [ 155.114311] postgres[1765]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1669vm-test-run-http> server # [ 155.115167] postgres[1765]: -- function in the extension so we need to grant permissions on existing entities AND1670vm-test-run-http> server # [ 155.115882] postgres[1765]: -- update default permissions to any others that are created after `graphql.resolve`1671vm-test-run-http> server # [ 155.116389] postgres[1765]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1672vm-test-run-http> server # [ 155.118096] postgres[1765]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1673vm-test-run-http> server # [ 155.119064] postgres[1765]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1674vm-test-run-http> server # [ 155.120174] postgres[1765]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1675vm-test-run-http> server # [ 155.129851] postgres[1765]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1676vm-test-run-http> server # [ 155.131118] postgres[1765]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1677vm-test-run-http> server # [ 155.132587] postgres[1765]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1678vm-test-run-http> server # [ 155.134269] postgres[1765]: END IF;1679vm-test-run-http> server # [ 155.135225] postgres[1765]:1680vm-test-run-http> server # [ 155.135932] postgres[1765]: END;1681vm-test-run-http> server # [ 155.136436] postgres[1765]: $func$;1682vm-test-run-http> server # [ 155.165894] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1764]: CREATE FUNCTION1683vm-test-run-http> server # [ 155.167292] postgres[1765]: ::1 2026-10-07 20:39:57.654 UTC [1765] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1684vm-test-run-http> server # [ 155.207760] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1764]: DROP EXTENSION1685vm-test-run-http> server # [ 157.467299] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1764]: DO1686vm-test-run-http> server # [ 157.507156] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql1687vm-test-run-http> server # [ 158.719052] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1790]: DO1688vm-test-run-http> server # [ 159.390840] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1790]: DO1689vm-test-run-http> server # [ 159.462033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql1690vm-test-run-http> server # [ 160.008969] postgres[1804]: ::1 2026-10-07 20:40:02.475 UTC [1804] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s';1691vm-test-run-http> server # [ 160.040184] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1796]: ALTER ROLE1692vm-test-run-http> server # [ 160.082837] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221103090837_revoke_admin.sql1693vm-test-run-http> server # [ 160.551669] postgres[1814]: ::1 2026-10-07 20:40:03.015 UTC [1814] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator;1694vm-test-run-http> server # [ 160.594669] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1809]: REVOKE ROLE1695vm-test-run-http> server # [ 160.619519] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql1696vm-test-run-http> server # [ 163.314665] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1816]: DO1697vm-test-run-http> server # [ 163.354947] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql1698vm-test-run-http> server # [ 163.731647] postgres[1847]: ::1 2026-10-07 20:40:06.213 UTC [1847] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres;1699vm-test-run-http> server # [ 163.753438] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1842]: GRANT ROLE1700vm-test-run-http> server # [ 163.808279] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql1701vm-test-run-http> server # [ 164.303630] postgres[1857]: ::1 2026-10-07 20:40:06.783 UTC [1857] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option;1702vm-test-run-http> server # [ 164.359212] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: GRANT1703vm-test-run-http> server # [ 164.369921] postgres[1857]: ::1 2026-10-07 20:40:06.848 UTC [1857] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option;1704vm-test-run-http> server # [ 164.404028] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: GRANT1705vm-test-run-http> server # [ 164.409066] postgres[1857]: ::1 2026-10-07 20:40:06.898 UTC [1857] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option;1706vm-test-run-http> server # [ 164.416697] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: GRANT1707vm-test-run-http> server # [ 164.420908] postgres[1857]: ::1 2026-10-07 20:40:06.911 UTC [1857] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option;1708vm-test-run-http> server # [ 164.435142] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: ALTER DEFAULT PRIVILEGES1709vm-test-run-http> server # [ 164.436026] postgres[1857]: ::1 2026-10-07 20:40:06.927 UTC [1857] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option;1710vm-test-run-http> server # [ 164.451536] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: ALTER DEFAULT PRIVILEGES1711vm-test-run-http> server # [ 164.455945] postgres[1857]: ::1 2026-10-07 20:40:06.946 UTC [1857] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option;1712vm-test-run-http> server # [ 164.471164] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1854]: ALTER DEFAULT PRIVILEGES1713vm-test-run-http> server # [ 164.518475] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql1714vm-test-run-http> server # [ 164.871837] postgres[1859]: ::1 2026-10-07 20:40:07.359 UTC [1859] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres;1715vm-test-run-http> server # [ 164.910627] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1858]: GRANT ROLE1716vm-test-run-http> server # [ 164.963107] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql1717vm-test-run-http> server # [ 165.242764] postgres[1861]: ::1 2026-10-07 20:40:07.730 UTC [1861] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin;1718vm-test-run-http> server # [ 165.252636] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1860]: GRANT ROLE1719vm-test-run-http> server # [ 165.292971] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql1720vm-test-run-http> server # [ 165.575411] postgres[1863]: ::1 2026-10-07 20:40:08.055 UTC [1863] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit;1721vm-test-run-http> server # [ 165.588074] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: ALTER ROLE1722vm-test-run-http> server # [ 165.594006] postgres[1863]: ::1 2026-10-07 20:40:08.071 UTC [1863] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit;1723vm-test-run-http> server # [ 165.595500] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: ALTER ROLE1724vm-test-run-http> server # [ 165.598060] postgres[1863]: ::1 2026-10-07 20:40:08.089 UTC [1863] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit;1725vm-test-run-http> server # [ 165.609996] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: ALTER ROLE1726vm-test-run-http> server # [ 165.650562] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1862]: DO1727vm-test-run-http> server # [ 165.727692] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql1728vm-test-run-http> server # [ 166.210324] postgres[1869]: ::1 2026-10-07 20:40:08.699 UTC [1869] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin;1729vm-test-run-http> server # [ 166.254534] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1866]: GRANT ROLE1730vm-test-run-http> server # [ 166.255808] postgres[1869]: ::1 2026-10-07 20:40:08.720 UTC [1869] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin;1731vm-test-run-http> server # [ 166.279043] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1866]: REVOKE ROLE1732vm-test-run-http> server # [ 166.336026] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql1733vm-test-run-http> server # [ 166.805084] postgres[1880]: ::1 2026-10-07 20:40:09.288 UTC [1880] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access()1734vm-test-run-http> server # [ 166.806319] postgres[1880]: returns event_trigger1735vm-test-run-http> server # [ 166.808225] postgres[1880]: language plpgsql1736vm-test-run-http> server # [ 166.808671] postgres[1880]: AS $func$1737vm-test-run-http> server # [ 166.814999] postgres[1880]: DECLARE1738vm-test-run-http> server # [ 166.820704] postgres[1880]: func_is_graphql_resolve bool;1739vm-test-run-http> server # [ 166.823276] postgres[1880]: BEGIN1740vm-test-run-http> server # [ 166.823955] postgres[1880]: func_is_graphql_resolve = (1741vm-test-run-http> server # [ 166.828801] postgres[1880]: SELECT n.proname = 'resolve'1742vm-test-run-http> server # [ 166.829888] postgres[1880]: FROM pg_event_trigger_ddl_commands() AS ev1743vm-test-run-http> server # [ 166.831763] postgres[1880]: LEFT JOIN pg_catalog.pg_proc AS n1744vm-test-run-http> server # [ 166.841022] postgres[1880]: ON ev.objid = n.oid1745vm-test-run-http> server # [ 166.841699] postgres[1880]: );1746vm-test-run-http> server # [ 166.842571] postgres[1880]:1747vm-test-run-http> server # [ 166.843544] postgres[1880]: IF func_is_graphql_resolve1748vm-test-run-http> server # [ 166.849997] postgres[1880]: THEN1749vm-test-run-http> server # [ 166.857227] postgres[1880]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func1750vm-test-run-http> server # [ 166.859327] postgres[1880]: DROP FUNCTION IF EXISTS graphql_public.graphql;1751vm-test-run-http> server # [ 166.860185] postgres[1880]: create or replace function graphql_public.graphql(1752vm-test-run-http> server # [ 166.867882] postgres[1880]: "operationName" text default null,1753vm-test-run-http> server # [ 166.868749] postgres[1880]: query text default null,1754vm-test-run-http> server # [ 166.869529] postgres[1880]: variables jsonb default null,1755vm-test-run-http> server # [ 166.870167] postgres[1880]: extensions jsonb default null1756vm-test-run-http> server # [ 166.870852] postgres[1880]: )1757vm-test-run-http> server # [ 166.871418] postgres[1880]: returns jsonb1758vm-test-run-http> server # [ 166.872529] postgres[1880]: language sql1759vm-test-run-http> server # [ 166.873215] postgres[1880]: as $$1760vm-test-run-http> server # [ 166.873625] postgres[1880]: select graphql.resolve(1761vm-test-run-http> server # [ 166.874273] postgres[1880]: query := query,1762vm-test-run-http> server # [ 166.875227] postgres[1880]: variables := coalesce(variables, '{}'),1763vm-test-run-http> server # [ 166.875777] postgres[1880]: "operationName" := "operationName",1764vm-test-run-http> server # [ 166.880820] postgres[1880]: extensions := extensions1765vm-test-run-http> server # [ 166.883768] postgres[1880]: );1766vm-test-run-http> server # [ 166.885241] postgres[1880]: $$;1767vm-test-run-http> server # [ 166.886262] postgres[1880]:1768vm-test-run-http> server # [ 166.886848] postgres[1880]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last1769vm-test-run-http> server # [ 166.887484] postgres[1880]: -- function in the extension so we need to grant permissions on existing entities AND1770vm-test-run-http> server # [ 166.888179] postgres[1880]: -- update default permissions to any others that are created after `graphql.resolve`1771vm-test-run-http> server # [ 166.896528] postgres[1880]: grant usage on schema graphql to postgres, anon, authenticated, service_role;1772vm-test-run-http> server # [ 166.897495] postgres[1880]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role;1773vm-test-run-http> server # [ 166.897993] postgres[1880]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role;1774vm-test-run-http> server # [ 166.898395] postgres[1880]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role;1775vm-test-run-http> server # [ 166.898965] postgres[1880]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role;1776vm-test-run-http> server # [ 166.899885] postgres[1880]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role;1777vm-test-run-http> server # [ 166.907966] postgres[1880]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role;1778vm-test-run-http> server # [ 166.909810] postgres[1880]:1779vm-test-run-http> server # [ 166.910353] postgres[1880]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles1780vm-test-run-http> server # [ 166.915160] postgres[1880]: grant usage on schema graphql_public to postgres with grant option;1781vm-test-run-http> server # [ 166.920923] postgres[1880]: grant usage on schema graphql to postgres with grant option;1782vm-test-run-http> server # [ 166.921776] postgres[1880]: END IF;1783vm-test-run-http> server # [ 166.922060] postgres[1880]:1784vm-test-run-http> server # [ 166.922287] postgres[1880]: END;1785vm-test-run-http> server # [ 166.924791] postgres[1880]: $func$;1786vm-test-run-http> server # [ 166.931250] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1876]: CREATE FUNCTION1787vm-test-run-http> server # [ 166.937870] postgres[1880]: ::1 2026-10-07 20:40:09.421 UTC [1880] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql;1788vm-test-run-http> server # [ 167.028701] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1876]: DROP EXTENSION1789vm-test-run-http> server # [ 168.859658] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1876]: DO1790vm-test-run-http> server # [ 168.907427] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql1791vm-test-run-http> server # [ 169.225277] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: DO1792vm-test-run-http> server # [ 169.242941] postgres[1897]: ::1 2026-10-07 20:40:11.708 UTC [1897] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger1793vm-test-run-http> server # [ 169.249814] postgres[1897]: LANGUAGE plpgsql1794vm-test-run-http> server # [ 169.251013] postgres[1897]: AS $$1795vm-test-run-http> server # [ 169.256883] postgres[1897]: BEGIN1796vm-test-run-http> server # [ 169.258239] postgres[1897]: IF EXISTS (1797vm-test-run-http> server # [ 169.259076] postgres[1897]: SELECT1798vm-test-run-http> server # [ 169.259492] postgres[1897]: FROM pg_event_trigger_ddl_commands() AS ev1799vm-test-run-http> server # [ 169.259791] postgres[1897]: JOIN pg_extension AS ext1800vm-test-run-http> server # [ 169.260034] postgres[1897]: ON ev.objid = ext.oid1801vm-test-run-http> server # [ 169.260273] postgres[1897]: WHERE ext.extname = 'pg_cron'1802vm-test-run-http> server # [ 169.260741] postgres[1897]: )1803vm-test-run-http> server # [ 169.268065] postgres[1897]: THEN1804vm-test-run-http> server # [ 169.269229] postgres[1897]: grant usage on schema cron to postgres with grant option;1805vm-test-run-http> server # [ 169.271221] postgres[1897]:1806vm-test-run-http> server # [ 169.271561] postgres[1897]: alter default privileges in schema cron grant all on tables to postgres with grant option;1807vm-test-run-http> server # [ 169.271870] postgres[1897]: alter default privileges in schema cron grant all on functions to postgres with grant option;1808vm-test-run-http> server # [ 169.272157] postgres[1897]: alter default privileges in schema cron grant all on sequences to postgres with grant option;1809vm-test-run-http> server # [ 169.277509] postgres[1897]:1810vm-test-run-http> server # [ 169.278184] postgres[1897]: alter default privileges for user supabase_admin in schema cron grant all1811vm-test-run-http> server # [ 169.278949] postgres[1897]: on sequences to postgres with grant option;1812vm-test-run-http> server # [ 169.279277] postgres[1897]: alter default privileges for user supabase_admin in schema cron grant all1813vm-test-run-http> server # [ 169.279790] postgres[1897]: on tables to postgres with grant option;1814vm-test-run-http> server # [ 169.280091] postgres[1897]: alter default privileges for user supabase_admin in schema cron grant all1815vm-test-run-http> server # [ 169.285997] postgres[1897]: on functions to postgres with grant option;1816vm-test-run-http> server # [ 169.286868] postgres[1897]:1817vm-test-run-http> server # [ 169.287196] postgres[1897]: grant all privileges on all tables in schema cron to postgres with grant option;1818vm-test-run-http> server # [ 169.287478] postgres[1897]: revoke all on table cron.job from postgres;1819vm-test-run-http> server # [ 169.288730] postgres[1897]: grant select on table cron.job to postgres with grant option;1820vm-test-run-http> server # [ 169.289190] postgres[1897]: END IF;1821vm-test-run-http> server # [ 169.293695] postgres[1897]: END;1822vm-test-run-http> server # [ 169.294438] postgres[1897]: $$;1823vm-test-run-http> server # [ 169.304064] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: CREATE FUNCTION1824vm-test-run-http> server # [ 169.309779] postgres[1897]: ::1 2026-10-07 20:40:11.796 UTC [1897] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access;1825vm-test-run-http> server # [ 169.329230] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: DROP EVENT TRIGGER1826vm-test-run-http> server # [ 169.331534] postgres[1897]: ::1 2026-10-07 20:40:11.822 UTC [1897] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin;1827vm-test-run-http> server # [ 169.350443] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: ALTER FUNCTION1828vm-test-run-http> server # [ 169.357130] postgres[1897]: ::1 2026-10-07 20:40:11.847 UTC [1897] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end1829vm-test-run-http> server # [ 169.358257] postgres[1897]: WHEN TAG IN ('CREATE EXTENSION')1830vm-test-run-http> server # [ 169.359046] postgres[1897]: EXECUTE FUNCTION extensions.grant_pg_cron_access();1831vm-test-run-http> server # [ 169.366923] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1896]: CREATE EVENT TRIGGER1832vm-test-run-http> server # [ 169.413705] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql1833vm-test-run-http> server # [ 169.905550] postgres[1909]: ::1 2026-10-07 20:40:12.351 UTC [1909] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s';1834vm-test-run-http> server # [ 169.961475] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1903]: ALTER ROLE1835vm-test-run-http> server # [ 169.985940] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql1836vm-test-run-http> server # [ 170.435890] postgres[1921]: ::1 2026-10-07 20:40:12.903 UTC [1921] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin;1837vm-test-run-http> server # [ 170.482988] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1916]: ALTER FUNCTION1838vm-test-run-http> server # [ 170.491177] postgres[1921]: ::1 2026-10-07 20:40:12.970 UTC [1921] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin;1839vm-test-run-http> server # [ 170.497841] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1916]: ALTER FUNCTION1840vm-test-run-http> server # [ 170.501706] postgres[1921]: ::1 2026-10-07 20:40:12.991 UTC [1921] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin;1841vm-test-run-http> server # [ 170.522806] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1916]: ALTER FUNCTION1842vm-test-run-http> server # [ 170.589663] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql1843vm-test-run-http> server # [ 171.019934] postgres[1927]: ::1 2026-10-07 20:40:13.502 UTC [1927] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres;1844vm-test-run-http> server # [ 171.050503] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1925]: GRANT ROLE1845vm-test-run-http> server # [ 171.103703] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241031003909_create_orioledb.sql1846vm-test-run-http> server # [ 172.042162] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1928]: DO1847vm-test-run-http> server # [ 172.094259] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql1848vm-test-run-http> server # [ 172.543984] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1933]: DO1849vm-test-run-http> server # [ 172.555392] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1933]: DO1850vm-test-run-http> server # [ 172.649282] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql1851vm-test-run-http> server # [ 173.100163] postgres[1945]: ::1 2026-10-07 20:40:15.589 UTC [1945] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none;1852vm-test-run-http> server # [ 173.113033] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1938]: ALTER ROLE1853vm-test-run-http> server # [ 173.115306] postgres[1945]: ::1 2026-10-07 20:40:15.602 UTC [1945] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none;1854vm-test-run-http> server # [ 173.121643] postgres[1945]: ::1 2026-10-07 20:40:15.613 UTC [1945] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none;1855vm-test-run-http> server # [ 173.123089] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1938]: ALTER ROLE1856vm-test-run-http> server # [ 173.130380] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1938]: ALTER ROLE1857vm-test-run-http> server # [ 173.181151] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql1858vm-test-run-http> server # [ 173.660996] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1949]: DO1859vm-test-run-http> server # [ 173.731383] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql1860vm-test-run-http> server # [ 174.231930] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1960]: DO1861vm-test-run-http> server # [ 174.286179] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql1862vm-test-run-http> server # [ 174.813853] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1966]: CREATE FUNCTION1863vm-test-run-http> server # [ 174.829059] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1966]: DO1864vm-test-run-http> server # [ 174.885963] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql1865vm-test-run-http> server # [ 175.178456] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1968]: ALTER FUNCTION1866vm-test-run-http> server # [ 175.197116] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1968]: GRANT1867vm-test-run-http> server # [ 175.221726] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql1868vm-test-run-http> server # [ 175.515917] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1970]: DROP EVENT TRIGGER1869vm-test-run-http> server # [ 175.546558] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1970]: ALTER FUNCTION1870vm-test-run-http> server # [ 175.561858] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1970]: CREATE EVENT TRIGGER1871vm-test-run-http> server # [ 175.607213] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql1872vm-test-run-http> server # [ 176.128101] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1972]: DO1873vm-test-run-http> server # [ 176.202747] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql1874vm-test-run-http> server # [ 176.883842] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1988]: CREATE FUNCTION1875vm-test-run-http> server # [ 176.925697] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1988]: GRANT1876vm-test-run-http> server # [ 176.991442] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql1877vm-test-run-http> server # [ 177.378295] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: REVOKE ROLE1878vm-test-run-http> server # [ 177.421103] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: DO1879vm-test-run-http> server # [ 177.438637] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: DO1880vm-test-run-http> server # [ 177.445543] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: REVOKE ROLE1881vm-test-run-http> server # [ 177.461145] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: REVOKE1882vm-test-run-http> server # [ 177.494607] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1999]: DO1883vm-test-run-http> server # [ 177.568005] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql1884vm-test-run-http> server # [ 177.883046] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2002]: DO1885vm-test-run-http> server # [ 177.918153] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql1886vm-test-run-http> server # [ 178.347968] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2004]: DO1887vm-test-run-http> server # [ 178.388252] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql1888vm-test-run-http> server # [ 178.903188] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2010]: DO1889vm-test-run-http> server # [ 178.932293] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql1890vm-test-run-http> server # [ 179.339362] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2012]: ALTER ROLE1891vm-test-run-http> server # [ 179.375499] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251001204436_predefined_role_grants.sql1892vm-test-run-http> server # [ 179.870332] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2022]: GRANT ROLE1893vm-test-run-http> server # [ 179.892444] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2022]: DO1894vm-test-run-http> server # [ 179.993040] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql1895vm-test-run-http> server # [ 180.601851] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2034]: GRANT1896vm-test-run-http> server # [ 180.649139] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql1897vm-test-run-http> server # [ 181.115729] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: CREATE FUNCTION1898vm-test-run-http> server # [ 181.125261] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: REVOKE1899vm-test-run-http> server # [ 181.139452] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: REVOKE1900vm-test-run-http> server # [ 181.153471] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2042]: GRANT1901vm-test-run-http> server # [ 181.209824] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260211120934_supabase_privileged_role.sql1902vm-test-run-http> server # [ 181.486895] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2044]: DO1903vm-test-run-http> server # [ 181.523463] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql1904vm-test-run-http> server # [ 181.800688] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2046]: ALTER ROLE1905vm-test-run-http> server # [ 181.839809] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql1906vm-test-run-http> server # [ 182.566133] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2048]: DROP EXTENSION1907vm-test-run-http> server # [ 182.614286] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql1908vm-test-run-http> server # [ 183.152741] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2060]: CREATE FUNCTION1909vm-test-run-http> server # [ 183.158598] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2060]: DROP EVENT TRIGGER1910vm-test-run-http> server # [ 183.169739] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2060]: CREATE EVENT TRIGGER1911vm-test-run-http> server # [ 183.207449] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql1912vm-test-run-http> server # [ 183.587370] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2069]: DO1913vm-test-run-http> server # [ 183.619640] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2069]: CREATE FUNCTION1914vm-test-run-http> server # [ 183.680081] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running migration: /nix/store/nw0bs8nddlaf6wh127acj2xwsdq5p7qz-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql1915vm-test-run-http> server # [ 184.107471] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2078]: CREATE FUNCTION1916vm-test-run-http> server # [ 184.127794] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2078]: CREATE FUNCTION1917vm-test-run-http> server # [ 184.173103] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Running postgresql schema...1918vm-test-run-http> server # [ 184.619281] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER DATABASE1919vm-test-run-http> server # [ 184.630388] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER DATABASE1920vm-test-run-http> server # [ 184.735346] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1921vm-test-run-http> server # [ 184.816827] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1922vm-test-run-http> server # [ 184.890273] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1923vm-test-run-http> server # [ 184.968734] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1924vm-test-run-http> server # [ 185.050906] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1925vm-test-run-http> server # [ 185.165245] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1926vm-test-run-http> server # [ 185.290979] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1927vm-test-run-http> server # [ 185.410715] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1928vm-test-run-http> server # [ 185.556971] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1929vm-test-run-http> server # [ 185.565111] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[2083]: ALTER ROLE1930vm-test-run-http> server # [ 185.643295] z8w0zyjml4ky3aw4mdlmwdwjsyk9dyr0-supabase-db-init[1505]: Database initialization complete1931vm-test-run-http> server # [ 185.695852] systemd[1]: Finished Supabase Database Initialization.1932vm-test-run-http> server # [ 185.707045] systemd[1]: Reached target Multi-User System.1933vm-test-run-http> server # [ 185.707932] systemd[1]: Startup finished in 19.661s (kernel) + 2min 46.038s (userspace) = 3min 5.699s.1934vm-test-run-http> server: (finished: waiting for unit supabase-db-init.service, in 187.46 seconds)1935vm-test-run-http> subtest: Verify PostgreSQL 15 is our custom build1936vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();"1937vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT version();", in 0.57 seconds)1938vm-test-run-http> server: must succeed: readlink -f $(which postgres)1939vm-test-run-http> server: (finished: must succeed: readlink -f $(which postgres), in 0.43 seconds)1940vm-test-run-http> (finished: subtest: Verify PostgreSQL 15 is our custom build, in 1.01 seconds)1941vm-test-run-http> subtest: Verify ansible config loaded1942vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;"1943vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW shared_preload_libraries;", in 0.49 seconds)1944vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;"1945vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SHOW session_preload_libraries;", in 0.77 seconds)1946vm-test-run-http> (finished: subtest: Verify ansible config loaded, in 1.26 seconds)1947vm-test-run-http> subtest: Verify init scripts and migrations ran1948vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;"1949vm-test-run-http> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT rolname FROM pg_roles ORDER BY rolname;", in 0.89 seconds)1950vm-test-run-http> server: must succeed: psql -U supabase_admin -d postgres -t -A -c "SELECT schema_name FROM information_schema.schemata ORDER BY schema_name;"