these 20 derivations will be built: /nix/store/4b7qwlqd7jbpjd2ksail6rvc78xdyqz7-unit-postgresql.service.drv /nix/store/23aiw7si0n4aqzi2838mm7cv8r5wjq0c-system-units.drv /nix/store/cfxn7h4admkqpdn161qs83ajqm5qv2ym-unit-postgresql.service.drv /nix/store/3a5dvbgz3ls7307xlfglrkha4xxjf390-system-units.drv /nix/store/gpbmnc41dj40pwhc2cagarajw0p5fx84-unit-postgresql.service.drv /nix/store/wpkq4fhq82sch86a5zaqbayxgr9r34c8-system-units.drv /nix/store/5lsi7mcbdkghxh4blsjrqhai3cw55q3h-etc.drv /nix/store/3pkr1bym6qyhgap3dwb9vyiiraxm44r1-activate.drv /nix/store/psswg2gb08k46d1a2y5jsjh7gniqrkrk-etc.drv /nix/store/9lri0rw98k9q34qd0bdr5f5y4cix3mqn-activate.drv /nix/store/axigh1vsxan9yj72k652xfc31i2rh300-nixos-system-server-test.drv /nix/store/z100vba2x52v385pk5wf1rh9hra5jczx-etc.drv /nix/store/jsqfir6pbbqshlg15d101liy1kdh0csz-activate.drv /nix/store/yjc8w67x0i8km5dlyqhzccfqnf95piai-nixos-system-server-test.drv /nix/store/77x7v2a77m9imr1ddkcnm8s3s10dic6w-nixos-system-server-test.drv /nix/store/m08q90mmd2v7l138hgwk4v37kv23yap5-closure-info.drv /nix/store/wma034dsw0k0hqcb9p3399bjwf1jvnvi-run-nixos-vm.drv /nix/store/9vzicx439lkypl4qmkspar6l5njrvnj3-nixos-vm.drv /nix/store/5cwv8cyyxv781zkd8hx31xhrvw275ycx-nixos-test-driver-pgroonga.drv /nix/store/qiwyranhk0lafa0hyxw2q64hxrjn49di-vm-test-run-pgroonga.drv building '/nix/store/gpbmnc41dj40pwhc2cagarajw0p5fx84-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/4b7qwlqd7jbpjd2ksail6rvc78xdyqz7-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/cfxn7h4admkqpdn161qs83ajqm5qv2ym-unit-postgresql.service.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/4b7qwlqd7jbpjd2ksail6rvc78xdyqz7-unit-postgresql.service.drv' building '/nix/store/gpbmnc41dj40pwhc2cagarajw0p5fx84-unit-postgresql.service.drv' building '/nix/store/cfxn7h4admkqpdn161qs83ajqm5qv2ym-unit-postgresql.service.drv' building '/nix/store/wpkq4fhq82sch86a5zaqbayxgr9r34c8-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/23aiw7si0n4aqzi2838mm7cv8r5wjq0c-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/3a5dvbgz3ls7307xlfglrkha4xxjf390-system-units.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/23aiw7si0n4aqzi2838mm7cv8r5wjq0c-system-units.drv' building '/nix/store/wpkq4fhq82sch86a5zaqbayxgr9r34c8-system-units.drv' building '/nix/store/3a5dvbgz3ls7307xlfglrkha4xxjf390-system-units.drv' building '/nix/store/5lsi7mcbdkghxh4blsjrqhai3cw55q3h-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/5lsi7mcbdkghxh4blsjrqhai3cw55q3h-etc.drv' building '/nix/store/psswg2gb08k46d1a2y5jsjh7gniqrkrk-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/z100vba2x52v385pk5wf1rh9hra5jczx-etc.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/psswg2gb08k46d1a2y5jsjh7gniqrkrk-etc.drv' building '/nix/store/z100vba2x52v385pk5wf1rh9hra5jczx-etc.drv' building '/nix/store/3pkr1bym6qyhgap3dwb9vyiiraxm44r1-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/9lri0rw98k9q34qd0bdr5f5y4cix3mqn-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/jsqfir6pbbqshlg15d101liy1kdh0csz-activate.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/3pkr1bym6qyhgap3dwb9vyiiraxm44r1-activate.drv' building '/nix/store/9lri0rw98k9q34qd0bdr5f5y4cix3mqn-activate.drv' building '/nix/store/jsqfir6pbbqshlg15d101liy1kdh0csz-activate.drv' building '/nix/store/axigh1vsxan9yj72k652xfc31i2rh300-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/yjc8w67x0i8km5dlyqhzccfqnf95piai-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/axigh1vsxan9yj72k652xfc31i2rh300-nixos-system-server-test.drv' building '/nix/store/yjc8w67x0i8km5dlyqhzccfqnf95piai-nixos-system-server-test.drv' building '/nix/store/77x7v2a77m9imr1ddkcnm8s3s10dic6w-nixos-system-server-test.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/77x7v2a77m9imr1ddkcnm8s3s10dic6w-nixos-system-server-test.drv' building '/nix/store/m08q90mmd2v7l138hgwk4v37kv23yap5-closure-info.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/m08q90mmd2v7l138hgwk4v37kv23yap5-closure-info.drv' closure-info> structuredAttrs is enabled building '/nix/store/wma034dsw0k0hqcb9p3399bjwf1jvnvi-run-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/wma034dsw0k0hqcb9p3399bjwf1jvnvi-run-nixos-vm.drv' building '/nix/store/9vzicx439lkypl4qmkspar6l5njrvnj3-nixos-vm.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/9vzicx439lkypl4qmkspar6l5njrvnj3-nixos-vm.drv' building '/nix/store/5cwv8cyyxv781zkd8hx31xhrvw275ycx-nixos-test-driver-pgroonga.drv' on 'ssh-ng://nixbot-remote-build@78.232.36.247' building '/nix/store/5cwv8cyyxv781zkd8hx31xhrvw275ycx-nixos-test-driver-pgroonga.drv' nixos-test-driver-pgroonga> Running type check (enable/disable: config.skipTypeCheck) nixos-test-driver-pgroonga> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck nixos-test-driver-pgroonga> Success: no issues found in 1 source file nixos-test-driver-pgroonga> additionally exposed symbols: nixos-test-driver-pgroonga> , nixos-test-driver-pgroonga> , nixos-test-driver-pgroonga> 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, debug nixos-test-driver-pgroonga> Linting test script (enable/disable: config.skipLint) nixos-test-driver-pgroonga> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint building '/nix/store/qiwyranhk0lafa0hyxw2q64hxrjn49di-vm-test-run-pgroonga.drv' on 'ssh-ng://builder@mac-linux-builder' building '/nix/store/qiwyranhk0lafa0hyxw2q64hxrjn49di-vm-test-run-pgroonga.drv' vm-test-run-pgroonga> Machine state will be reset. To keep it, pass --keep-vm-state vm-test-run-pgroonga> start all VLans vm-test-run-pgroonga> start vlan vm-test-run-pgroonga> running vlan (pid 7; ctl /build/vde1.ctl) vm-test-run-pgroonga> (finished: start all VLans, in 0.00 seconds) vm-test-run-pgroonga> Test will time out and terminate in 3600 seconds vm-test-run-pgroonga> run the VM test script vm-test-run-pgroonga> additionally exposed symbols: vm-test-run-pgroonga> server, vm-test-run-pgroonga> vlan1, vm-test-run-pgroonga> 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, debug vm-test-run-pgroonga> start all VMs vm-test-run-pgroonga> server: starting vm vm-test-run-pgroonga> mke2fs 1.47.3 (8-Jul-2025) vm-test-run-pgroonga> qemu-system-aarch64: Could not access KVM kernel module: No such file or directory vm-test-run-pgroonga> qemu-system-aarch64: failed to initialize kvm: No such file or directory vm-test-run-pgroonga> qemu-system-aarch64: falling back to tcg vm-test-run-pgroonga> server # Disk image does not exist, creating the virtualisation disk image... vm-test-run-pgroonga> server # Formatting '/build/vm-state-server/tmp.Ziimi2Qxpb', fmt=raw size=1073741824 vm-test-run-pgroonga> server # Discarding device blocks: 0/262144 done vm-test-run-pgroonga> server # Creating filesystem with 262144 4k blocks and 65536 inodes vm-test-run-pgroonga> server # Filesystem UUID: f319f267-9ec1-4aa7-97b7-e0f1263e29ac vm-test-run-pgroonga> server # Superblock backups stored on blocks: vm-test-run-pgroonga> server # 32768, 98304, 163840, 229376 vm-test-run-pgroonga> server # vm-test-run-pgroonga> server # Allocating group tables: 0/8 done vm-test-run-pgroonga> server # Writing inode tables: 0/8 done vm-test-run-pgroonga> server # Creating journal (8192 blocks): done vm-test-run-pgroonga> server # Writing superblocks and filesystem accounting information: 0/8 done vm-test-run-pgroonga> server # vm-test-run-pgroonga> server # Virtualisation disk image created. vm-test-run-pgroonga> server: QEMU running (pid 9) vm-test-run-pgroonga> (finished: start all VMs, in 0.14 seconds) vm-test-run-pgroonga> server: waiting for unit supabase-db-init.service vm-test-run-pgroonga> server: waiting for the VM to finish booting vm-test-run-pgroonga> server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510] vm-test-run-pgroonga> 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 2025 vm-test-run-pgroonga> server # [ 0.000000] KASLR enabled vm-test-run-pgroonga> server # [ 0.000000] random: crng init done vm-test-run-pgroonga> server # [ 0.000000] Machine model: linux,dummy-virt vm-test-run-pgroonga> server # [ 0.000000] efi: UEFI not found. vm-test-run-pgroonga> server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT vm-test-run-pgroonga> server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgroonga> server # [ 0.000000] NODE_DATA(0) allocated [mem 0x13f602200-0x13f60593f] vm-test-run-pgroonga> server # [ 0.000000] Zone ranges: vm-test-run-pgroonga> server # [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] vm-test-run-pgroonga> server # [ 0.000000] DMA32 empty vm-test-run-pgroonga> server # [ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff] vm-test-run-pgroonga> server # [ 0.000000] Device empty vm-test-run-pgroonga> server # [ 0.000000] Movable zone start for each node vm-test-run-pgroonga> server # [ 0.000000] Early memory node ranges vm-test-run-pgroonga> server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgroonga> server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff] vm-test-run-pgroonga> server # [ 0.000000] cma: Reserved 32 MiB at 0x00000000fe000000 on node -1 vm-test-run-pgroonga> server # [ 0.000000] psci: probing for conduit method from DT. vm-test-run-pgroonga> server # [ 0.000000] psci: PSCIv1.1 detected in firmware. vm-test-run-pgroonga> server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs vm-test-run-pgroonga> server # [ 0.000000] psci: Trusted OS migration not required vm-test-run-pgroonga> server # [ 0.000000] psci: SMC Calling Convention v1.0 vm-test-run-pgroonga> server # [ 0.000000] percpu: Embedded 88 pages/cpu s180056 r65536 d114856 u360448 vm-test-run-pgroonga> server # [ 0.000000] Detected PIPT I-cache on CPU0 vm-test-run-pgroonga> server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) vm-test-run-pgroonga> server # [ 0.000000] CPU features: detected: GIC system register CPU interface vm-test-run-pgroonga> server # [ 0.000000] CPU features: detected: HCRX_EL2 register vm-test-run-pgroonga> server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2) vm-test-run-pgroonga> server # [ 0.000000] CPU features: detected: Spectre-v4 vm-test-run-pgroonga> server # [ 0.000000] alternatives: applying boot alternatives vm-test-run-pgroonga> 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/9syfgkv5brfgymybdgvmif6cqw1njdpj-nixos-system-server-test/init regInfo=/nix/store/apd5nwjcg9snsmfm5qxjq0fgncy051nq-closure-info/registration console=ttyAMA0,115200n8 console=tty0 vm-test-run-pgroonga> server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/apd5nwjcg9snsmfm5qxjq0fgncy051nq-closure-info/registration", will be passed to user space. vm-test-run-pgroonga> server # [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) vm-test-run-pgroonga> server # [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) vm-test-run-pgroonga> server # [ 0.000000] Fallback order for Node 0: 0 vm-test-run-pgroonga> server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576 vm-test-run-pgroonga> server # [ 0.000000] Policy zone: Normal vm-test-run-pgroonga> server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off vm-test-run-pgroonga> server # [ 0.000000] software IO TLB: area num 2. vm-test-run-pgroonga> server # [ 0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB) vm-test-run-pgroonga> server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 vm-test-run-pgroonga> server # [ 0.000000] allocated 8388608 bytes of page_ext vm-test-run-pgroonga> server # [ 0.000000] ftrace: allocating 72257 entries in 283 pages vm-test-run-pgroonga> server # [ 0.000000] ftrace: allocated 283 pages with 5 groups vm-test-run-pgroonga> server # [ 0.000000] rcu: Hierarchical RCU implementation. vm-test-run-pgroonga> server # [ 0.000000] rcu: RCU event tracing is enabled. vm-test-run-pgroonga> server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=2. vm-test-run-pgroonga> server # [ 0.000000] Rude variant of Tasks RCU enabled. vm-test-run-pgroonga> server # [ 0.000000] Tracing variant of Tasks RCU enabled. vm-test-run-pgroonga> server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. vm-test-run-pgroonga> server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 vm-test-run-pgroonga> server # [ 0.000000] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-pgroonga> server # [ 0.000000] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. vm-test-run-pgroonga> server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 vm-test-run-pgroonga> server # [ 0.000000] GICv3: 256 SPIs implemented vm-test-run-pgroonga> server # [ 0.000000] GICv3: 0 Extended SPIs implemented vm-test-run-pgroonga> server # [ 0.000000] Root IRQ handler: gic_handle_irq vm-test-run-pgroonga> server # [ 0.000000] GICv3: GICv3 features: 16 PPIs vm-test-run-pgroonga> server # [ 0.000000] GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 vm-test-run-pgroonga> server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 vm-test-run-pgroonga> server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] vm-test-run-pgroonga> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100ad0000 (indirect, esz 8, psz 64K, shr 1) vm-test-run-pgroonga> server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100ae0000 (flat, esz 8, psz 64K, shr 1) vm-test-run-pgroonga> server # [ 0.000000] GICv3: using LPI property table @0x0000000100b00000 vm-test-run-pgroonga> server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b10000 vm-test-run-pgroonga> server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. vm-test-run-pgroonga> server # [ 0.000000] arch_timer: cp15 timer(s) running at 1000.00MHz (virt). vm-test-run-pgroonga> server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns vm-test-run-pgroonga> server # [ 0.000042] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns vm-test-run-pgroonga> server # [ 0.004509] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) vm-test-run-pgroonga> server # [ 0.015584] Console: colour dummy device 80x25 vm-test-run-pgroonga> server # [ 0.017143] printk: legacy console [tty0] enabled vm-test-run-pgroonga> server # [ 0.023424] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000) vm-test-run-pgroonga> server # [ 0.023856] pid_max: default: 32768 minimum: 301 vm-test-run-pgroonga> server # [ 0.026045] LSM: initializing lsm=capability,landlock,yama,bpf vm-test-run-pgroonga> server # [ 0.028231] landlock: Up and running. vm-test-run-pgroonga> server # [ 0.028359] Yama: becoming mindful. vm-test-run-pgroonga> server # [ 0.033484] LSM support for eBPF active vm-test-run-pgroonga> server # [ 0.037405] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-pgroonga> server # [ 0.037640] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) vm-test-run-pgroonga> server # [ 0.070669] cacheinfo: Unable to detect cache hierarchy for CPU 0 vm-test-run-pgroonga> server # [ 0.084010] rcu: Hierarchical SRCU implementation. vm-test-run-pgroonga> server # [ 0.084119] rcu: Max phase no-delay instances is 1000. vm-test-run-pgroonga> server # [ 0.085759] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level vm-test-run-pgroonga> server # [ 0.093600] fsl-mc MSI: its@8080000 domain created vm-test-run-pgroonga> server # [ 0.095482] EFI services will not be available. vm-test-run-pgroonga> server # [ 0.097216] smp: Bringing up secondary CPUs ... vm-test-run-pgroonga> server # [ 0.102905] Detected PIPT I-cache on CPU1 vm-test-run-pgroonga> server # [ 0.104221] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 vm-test-run-pgroonga> server # [ 0.104921] GICv3: CPU1: using allocated LPI pending table @0x0000000100b20000 vm-test-run-pgroonga> server # [ 0.106518] CPU1: Booted secondary processor 0x0000000001 [0x000f0510] vm-test-run-pgroonga> server # [ 0.110294] smp: Brought up 1 node, 2 CPUs vm-test-run-pgroonga> server # [ 0.110451] SMP: Total of 2 processors activated. vm-test-run-pgroonga> server # [ 0.110491] CPU: All CPU(s) started at EL1 vm-test-run-pgroonga> server # [ 0.110669] CPU features: detected: Branch Target Identification vm-test-run-pgroonga> server # [ 0.110721] CPU features: detected: 32-bit EL0 Support vm-test-run-pgroonga> server # [ 0.110753] CPU features: detected: 32-bit EL1 Support vm-test-run-pgroonga> server # [ 0.110780] CPU features: detected: ARMv8.4 Translation Table Level vm-test-run-pgroonga> server # [ 0.110816] CPU features: detected: Instruction cache invalidation not required for I/D coherence vm-test-run-pgroonga> server # [ 0.110860] CPU features: detected: Data cache clean to the PoU not required for I/D coherence vm-test-run-pgroonga> server # [ 0.111250] CPU features: detected: Common not Private translations vm-test-run-pgroonga> server # [ 0.111277] CPU features: detected: CRC32 instructions vm-test-run-pgroonga> server # [ 0.111300] CPU features: detected: Data cache clean to Point of Deep Persistence vm-test-run-pgroonga> server # [ 0.111330] CPU features: detected: Data cache clean to Point of Persistence vm-test-run-pgroonga> server # [ 0.111355] CPU features: detected: Data independent timing control (DIT) vm-test-run-pgroonga> server # [ 0.111378] CPU features: detected: E0PD vm-test-run-pgroonga> server # [ 0.111397] CPU features: detected: Enhanced Counter Virtualization vm-test-run-pgroonga> server # [ 0.111420] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF) vm-test-run-pgroonga> server # [ 0.111445] CPU features: detected: Enhanced Privileged Access Never vm-test-run-pgroonga> server # [ 0.111468] CPU features: detected: Enhanced Virtualization Traps vm-test-run-pgroonga> server # [ 0.111491] CPU features: detected: Fine Grained Traps vm-test-run-pgroonga> server # [ 0.111545] CPU features: detected: Generic authentication (IMP DEF algorithm) vm-test-run-pgroonga> server # [ 0.111659] CPU features: detected: RCpc load-acquire (LDAPR) vm-test-run-pgroonga> server # [ 0.111709] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2) vm-test-run-pgroonga> server # [ 0.111740] CPU features: detected: LSE atomic instructions vm-test-run-pgroonga> server # [ 0.111767] CPU features: detected: Memory Copy and Memory Set instructions vm-test-run-pgroonga> server # [ 0.111792] CPU features: detected: Privileged Access Never vm-test-run-pgroonga> server # [ 0.111817] CPU features: detected: RAS Extension Support vm-test-run-pgroonga> server # [ 0.111841] CPU features: detected: Random Number Generator vm-test-run-pgroonga> server # [ 0.111867] CPU features: detected: Speculation barrier (SB) vm-test-run-pgroonga> server # [ 0.111889] CPU features: detected: Stage-2 Force Write-Back vm-test-run-pgroonga> server # [ 0.111916] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality vm-test-run-pgroonga> server # [ 0.111950] CPU features: detected: TLB range maintenance instructions vm-test-run-pgroonga> server # [ 0.111978] CPU features: detected: WFx with timeout vm-test-run-pgroonga> server # [ 0.112018] CPU features: detected: Speculative Store Bypassing Safe (SSBS) vm-test-run-pgroonga> server # [ 0.112043] CPU features: detected: Scalable Vector Extension vm-test-run-pgroonga> server # [ 0.121913] alternatives: applying system-wide alternatives vm-test-run-pgroonga> server # [ 0.140446] CPU features: detected: Hardware dirty bit management on CPU0-1 vm-test-run-pgroonga> server # [ 0.141201] SVE: maximum available vector length 256 bytes per vector vm-test-run-pgroonga> server # [ 0.141503] SVE: default vector length 64 bytes per vector vm-test-run-pgroonga> server # [ 0.154165] Memory: 3922888K/4194304K available (23296K kernel code, 6546K rwdata, 24596K rodata, 4416K init, 1047K bss, 223164K reserved, 32768K cma-reserved) vm-test-run-pgroonga> server # [ 0.180189] devtmpfs: initialized vm-test-run-pgroonga> server # [ 0.210635] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns vm-test-run-pgroonga> server # [ 0.211118] futex hash table entries: 512 (order: 3, 32768 bytes, linear) vm-test-run-pgroonga> server # [ 0.213536] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL vm-test-run-pgroonga> server # [ 0.213619] 0 pages in range for non-PLT usage vm-test-run-pgroonga> server # [ 0.213664] 509248 pages in range for PLT usage vm-test-run-pgroonga> server # [ 0.216031] pinctrl core: initialized pinctrl subsystem vm-test-run-pgroonga> server # [ 0.226880] DMI not present or invalid. vm-test-run-pgroonga> server # [ 0.251592] NET: Registered PF_NETLINK/PF_ROUTE protocol family vm-test-run-pgroonga> server # [ 0.265022] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations vm-test-run-pgroonga> server # [ 0.266448] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations vm-test-run-pgroonga> server # [ 0.267663] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations vm-test-run-pgroonga> server # [ 0.267949] audit: initializing netlink subsys (disabled) vm-test-run-pgroonga> server # [ 0.271617] audit: type=2000 audit(0.192:1): state=initialized audit_enabled=0 res=1 vm-test-run-pgroonga> server # [ 0.278686] thermal_sys: Registered thermal governor 'fair_share' vm-test-run-pgroonga> server # [ 0.278776] thermal_sys: Registered thermal governor 'bang_bang' vm-test-run-pgroonga> server # [ 0.278876] thermal_sys: Registered thermal governor 'step_wise' vm-test-run-pgroonga> server # [ 0.278926] thermal_sys: Registered thermal governor 'user_space' vm-test-run-pgroonga> server # [ 0.278974] thermal_sys: Registered thermal governor 'power_allocator' vm-test-run-pgroonga> server # [ 0.279842] cpuidle: using governor ladder vm-test-run-pgroonga> server # [ 0.280477] cpuidle: using governor menu vm-test-run-pgroonga> server # [ 0.282477] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. vm-test-run-pgroonga> server # [ 0.283457] ASID allocator initialised with 65536 entries vm-test-run-pgroonga> server # [ 0.292139] Serial: AMBA PL011 UART driver vm-test-run-pgroonga> server # [ 0.343130] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 vm-test-run-pgroonga> server # [ 0.344230] printk: legacy console [ttyAMA0] enabled vm-test-run-pgroonga> server # [ 0.385438] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages vm-test-run-pgroonga> server # [ 0.385765] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page vm-test-run-pgroonga> server # [ 0.385975] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages vm-test-run-pgroonga> server # [ 0.386161] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page vm-test-run-pgroonga> server # [ 0.386332] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages vm-test-run-pgroonga> server # [ 0.386501] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page vm-test-run-pgroonga> server # [ 0.386664] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages vm-test-run-pgroonga> server # [ 0.386828] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page vm-test-run-pgroonga> server # [ 0.402406] fbcon: Taking over console vm-test-run-pgroonga> server # [ 0.403760] ACPI: Interpreter disabled. vm-test-run-pgroonga> server # [ 0.414555] iommu: Default domain type: Translated vm-test-run-pgroonga> server # [ 0.414735] iommu: DMA domain TLB invalidation policy: strict mode vm-test-run-pgroonga> server # [ 0.424997] SCSI subsystem initialized vm-test-run-pgroonga> server # [ 0.428635] usbcore: registered new interface driver usbfs vm-test-run-pgroonga> server # [ 0.430349] usbcore: registered new interface driver hub vm-test-run-pgroonga> server # [ 0.431154] usbcore: registered new device driver usb vm-test-run-pgroonga> server # [ 0.435317] pps_core: LinuxPPS API ver. 1 registered vm-test-run-pgroonga> server # [ 0.435477] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti vm-test-run-pgroonga> server # [ 0.435682] PTP clock support registered vm-test-run-pgroonga> server # [ 0.436054] EDAC MC: Ver: 3.0.0 vm-test-run-pgroonga> server # [ 0.437886] scmi_core: SCMI protocol bus registered vm-test-run-pgroonga> server # [ 0.440904] FPGA manager framework vm-test-run-pgroonga> server # [ 0.443385] Advanced Linux Sound Architecture Driver Initialized. vm-test-run-pgroonga> server # [ 0.451928] vgaarb: loaded vm-test-run-pgroonga> server # [ 0.455951] clocksource: Switched to clocksource arch_sys_counter vm-test-run-pgroonga> server # [ 0.459536] VFS: Disk quotas dquot_6.6.0 vm-test-run-pgroonga> server # [ 0.460944] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) vm-test-run-pgroonga> server # [ 0.466705] netfs: FS-Cache loaded vm-test-run-pgroonga> server # [ 0.468026] pnp: PnP ACPI: disabled vm-test-run-pgroonga> server # [ 0.505721] NET: Registered PF_INET protocol family vm-test-run-pgroonga> server # [ 0.507008] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) vm-test-run-pgroonga> server # [ 0.623551] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) vm-test-run-pgroonga> server # [ 0.623997] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) vm-test-run-pgroonga> server # [ 0.625773] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) vm-test-run-pgroonga> server # [ 0.626899] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) vm-test-run-pgroonga> server # [ 0.628011] TCP: Hash tables configured (established 32768 bind 32768) vm-test-run-pgroonga> server # [ 0.630798] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear) vm-test-run-pgroonga> server # [ 0.631541] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-pgroonga> server # [ 0.633542] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) vm-test-run-pgroonga> server # [ 0.635763] NET: Registered PF_UNIX/PF_LOCAL protocol family vm-test-run-pgroonga> server # [ 0.636589] NET: Registered PF_XDP protocol family vm-test-run-pgroonga> server # [ 0.637336] PCI: CLS 0 bytes, default 64 vm-test-run-pgroonga> server # [ 0.641693] kvm [1]: HYP mode not available vm-test-run-pgroonga> server # [ 0.642512] Trying to unpack rootfs image as initramfs... vm-test-run-pgroonga> server # [ 0.766090] Initialise system trusted keyrings vm-test-run-pgroonga> server # [ 0.767545] workingset: timestamp_bits=42 max_order=20 bucket_order=0 vm-test-run-pgroonga> server # [ 0.772754] squashfs: version 4.0 (2009/01/31) Phillip Lougher vm-test-run-pgroonga> server # [ 0.774188] 9p: Installing v9fs 9p2000 file system support vm-test-run-pgroonga> server # [ 0.842244] Key type asymmetric registered vm-test-run-pgroonga> server # [ 0.842483] Asymmetric key parser 'x509' registered vm-test-run-pgroonga> server # [ 0.842933] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244) vm-test-run-pgroonga> server # [ 0.843966] io scheduler mq-deadline registered vm-test-run-pgroonga> server # [ 0.844135] io scheduler kyber registered vm-test-run-pgroonga> server # [ 0.878015] pl061_gpio 9030000.pl061: PL061 GPIO chip registered vm-test-run-pgroonga> server # [ 0.882528] ledtrig-cpu: registered to indicate activity on CPUs vm-test-run-pgroonga> server # [ 0.887608] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: vm-test-run-pgroonga> server # [ 0.889203] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 vm-test-run-pgroonga> server # [ 0.890302] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 vm-test-run-pgroonga> server # [ 0.890936] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 vm-test-run-pgroonga> server # [ 0.891616] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits vm-test-run-pgroonga> server # [ 0.892954] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] vm-test-run-pgroonga> server # [ 0.894531] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 vm-test-run-pgroonga> server # [ 0.894948] pci_bus 0000:00: root bus resource [bus 00-ff] vm-test-run-pgroonga> server # [ 0.895119] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] vm-test-run-pgroonga> server # [ 0.895255] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] vm-test-run-pgroonga> server # [ 0.895382] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] vm-test-run-pgroonga> server # [ 0.897075] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.901778] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.902181] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgroonga> server # [ 0.902522] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.902941] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.903129] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pgroonga> server # [ 0.904621] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.905616] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgroonga> server # [ 0.905740] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.905885] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.906920] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.907191] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pgroonga> server # [ 0.907306] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.907426] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.908607] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.908813] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgroonga> server # [ 0.908929] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.909060] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.909720] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.910284] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgroonga> server # [ 0.910390] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.910508] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.911015] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.911618] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] vm-test-run-pgroonga> server # [ 0.911728] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.912990] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.914310] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.914558] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgroonga> server # [ 0.914679] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.914805] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.915123] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] vm-test-run-pgroonga> server # [ 0.915691] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.915861] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.915986] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.916450] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.916602] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.917219] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.918205] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.918388] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.919366] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.919921] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] vm-test-run-pgroonga> server # [ 0.920832] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.921053] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.921909] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint vm-test-run-pgroonga> server # [ 0.922073] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] vm-test-run-pgroonga> server # [ 0.922183] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] vm-test-run-pgroonga> server # [ 0.922297] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] vm-test-run-pgroonga> server # [ 0.924599] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned vm-test-run-pgroonga> server # [ 0.924923] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned vm-test-run-pgroonga> server # [ 0.925995] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.926880] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.927508] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.927690] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.928676] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.929607] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.929974] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.930128] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.930275] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.931115] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.931502] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned vm-test-run-pgroonga> server # [ 0.931648] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned vm-test-run-pgroonga> server # [ 0.933194] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned vm-test-run-pgroonga> server # [ 0.933341] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned vm-test-run-pgroonga> server # [ 0.933473] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned vm-test-run-pgroonga> server # [ 0.933902] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned vm-test-run-pgroonga> server # [ 0.934808] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned vm-test-run-pgroonga> server # [ 0.934930] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned vm-test-run-pgroonga> server # [ 0.935099] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned vm-test-run-pgroonga> server # [ 0.935383] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned vm-test-run-pgroonga> server # [ 0.936663] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned vm-test-run-pgroonga> server # [ 0.936797] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned vm-test-run-pgroonga> server # [ 0.936916] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned vm-test-run-pgroonga> server # [ 0.938004] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned vm-test-run-pgroonga> server # [ 0.938449] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned vm-test-run-pgroonga> server # [ 0.938558] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned vm-test-run-pgroonga> server # [ 0.938672] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned vm-test-run-pgroonga> server # [ 0.938777] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned vm-test-run-pgroonga> server # [ 0.938885] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned vm-test-run-pgroonga> server # [ 0.938991] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned vm-test-run-pgroonga> server # [ 0.939100] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned vm-test-run-pgroonga> server # [ 0.939390] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned vm-test-run-pgroonga> server # [ 0.941062] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] vm-test-run-pgroonga> server # [ 0.941277] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] vm-test-run-pgroonga> server # [ 0.941405] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] vm-test-run-pgroonga> server # [ 0.943741] pci 0000:00:0a.0: enabling device (0000 -> 0002) vm-test-run-pgroonga> server # [ 1.009519] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.015186] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.017976] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.021521] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.025768] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.028755] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.032455] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.035912] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) vm-test-run-pgroonga> server # [ 1.038059] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) vm-test-run-pgroonga> server # [ 1.040636] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.043121] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) vm-test-run-pgroonga> server # [ 1.066161] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled vm-test-run-pgroonga> server # [ 1.077551] msm_serial: driver initialized vm-test-run-pgroonga> server # [ 1.078555] SuperH (H)SCI(F) driver initialized vm-test-run-pgroonga> server # [ 1.079015] STM32 USART driver initialized vm-test-run-pgroonga> server # [ 1.137446] loop: module loaded vm-test-run-pgroonga> server # [ 1.138182] virtio_blk virtio5: 2/0/0 default/read/poll queues vm-test-run-pgroonga> server # [ 1.143024] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) vm-test-run-pgroonga> server # [ 1.156460] megasas: 07.727.03.00-rc1 vm-test-run-pgroonga> server # [ 1.163695] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] vm-test-run-pgroonga> server # [ 1.167457] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pgroonga> server # [ 1.168430] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pgroonga> server # [ 1.170096] Using buffer write method vm-test-run-pgroonga> server # [ 1.170861] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] vm-test-run-pgroonga> server # [ 1.172079] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 vm-test-run-pgroonga> server # [ 1.173318] Intel/Sharp Extended Query Table at 0x0031 vm-test-run-pgroonga> server # [ 1.175025] Using buffer write method vm-test-run-pgroonga> server # [ 1.175316] Concatenating MTD devices: vm-test-run-pgroonga> server # [ 1.175397] (0): "0.flash" vm-test-run-pgroonga> server # [ 1.175459] (1): "0.flash" vm-test-run-pgroonga> server # [ 1.175519] into device "0.flash" vm-test-run-pgroonga> server # [ 1.181757] Freeing initrd memory: 10684K vm-test-run-pgroonga> server # [ 1.205887] tun: Universal TUN/TAP device driver, 1.6 vm-test-run-pgroonga> server # [ 1.222511] thunder_xcv, ver 1.0 vm-test-run-pgroonga> server # [ 1.222776] thunder_bgx, ver 1.0 vm-test-run-pgroonga> server # [ 1.223294] nicpf, ver 1.0 vm-test-run-pgroonga> server # [ 1.226930] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version vm-test-run-pgroonga> server # [ 1.227141] hns3: Copyright (c) 2017 Huawei Corporation. vm-test-run-pgroonga> server # [ 1.227548] hclge is initializing vm-test-run-pgroonga> server # [ 1.227775] e1000: Intel(R) PRO/1000 Network Driver vm-test-run-pgroonga> server # [ 1.227859] e1000: Copyright (c) 1999-2006 Intel Corporation. vm-test-run-pgroonga> server # [ 1.228451] e1000e: Intel(R) PRO/1000 Network Driver vm-test-run-pgroonga> server # [ 1.228556] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. vm-test-run-pgroonga> server # [ 1.229351] igb: Intel(R) Gigabit Ethernet Network Driver vm-test-run-pgroonga> server # [ 1.229471] igb: Copyright (c) 2007-2014 Intel Corporation. vm-test-run-pgroonga> server # [ 1.229689] igbvf: Intel(R) Gigabit Virtual Function Network Driver vm-test-run-pgroonga> server # [ 1.229796] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. vm-test-run-pgroonga> server # [ 1.230497] sky2: driver version 1.30 vm-test-run-pgroonga> server # [ 1.239819] ehci-pci 0000:00:0a.0: EHCI Host Controller vm-test-run-pgroonga> server # [ 1.241806] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 vm-test-run-pgroonga> server # [ 1.244705] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 vm-test-run-pgroonga> server # [ 1.246513] usbcore: registered new interface driver usb-storage vm-test-run-pgroonga> server # [ 1.247079] usbcore: registered new interface driver usbserial_generic vm-test-run-pgroonga> server # [ 1.247759] usbserial: USB Serial support registered for generic vm-test-run-pgroonga> server # [ 1.256619] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 vm-test-run-pgroonga> server # [ 1.258567] rtc-pl031 9010000.pl031: registered as rtc0 vm-test-run-pgroonga> server # [ 1.259201] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T21:24:43 UTC (1792704283) vm-test-run-pgroonga> server # [ 1.262129] i2c_dev: i2c /dev entries driver vm-test-run-pgroonga> server # [ 1.265240] hub 1-0:1.0: USB hub found vm-test-run-pgroonga> server # [ 1.265953] hub 1-0:1.0: 6 ports detected vm-test-run-pgroonga> server # [ 1.278659] sdhci: Secure Digital Host Controller Interface driver vm-test-run-pgroonga> server # [ 1.278852] sdhci: Copyright(c) Pierre Ossman vm-test-run-pgroonga> server # [ 1.280233] Synopsys Designware Multimedia Card Interface Driver vm-test-run-pgroonga> server # [ 1.282701] sdhci-pltfm: SDHCI platform and OF driver helper vm-test-run-pgroonga> server # [ 1.289178] hid: raw HID events driver (C) Jiri Kosina vm-test-run-pgroonga> server # [ 1.291805] usbcore: registered new interface driver usbhid vm-test-run-pgroonga> server # [ 1.292097] usbhid: USB HID core driver vm-test-run-pgroonga> server # [ 1.302170] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available vm-test-run-pgroonga> server # [ 1.318528] drop_monitor: Initializing network drop monitor service vm-test-run-pgroonga> server # [ 1.319788] NET: Registered PF_INET6 protocol family vm-test-run-pgroonga> server # [ 1.341880] Segment Routing with IPv6 vm-test-run-pgroonga> server # [ 1.342231] In-situ OAM (IOAM) with IPv6 vm-test-run-pgroonga> server # [ 1.342858] NET: Registered PF_PACKET protocol family vm-test-run-pgroonga> server # [ 1.345106] 9pnet: Installing 9P2000 support vm-test-run-pgroonga> server # [ 1.349742] Key type dns_resolver registered vm-test-run-pgroonga> server # [ 1.387197] registered taskstats version 1 vm-test-run-pgroonga> server # [ 1.390102] Loading compiled-in X.509 certificates vm-test-run-pgroonga> server # [ 1.452666] Demotion targets for Node 0: null vm-test-run-pgroonga> server # [ 1.455617] Key type .fscrypt registered vm-test-run-pgroonga> server # [ 1.455779] Key type fscrypt-provisioning registered vm-test-run-pgroonga> server # [ 1.469069] input: gpio-keys as /devices/platform/gpio-keys/input/input0 vm-test-run-pgroonga> server # [ 1.518058] clk: Disabling unused clocks vm-test-run-pgroonga> server # [ 1.518483] PM: genpd: Disabling unused power domains vm-test-run-pgroonga> server # [ 1.519421] ALSA device list: vm-test-run-pgroonga> server # [ 1.519868] No soundcards found. vm-test-run-pgroonga> server # [ 1.521479] usb 1-1: new high-speed USB device number 2 using ehci-pci vm-test-run-pgroonga> server # [ 1.609381] Freeing unused kernel memory: 4416K vm-test-run-pgroonga> server # [ 1.610956] Run /init as init process vm-test-run-pgroonga> server # [ 1.703182] 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/input1 vm-test-run-pgroonga> server # [ 1.838180] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 vm-test-run-pgroonga> server # [ 1.956211] usb 1-2: new high-speed USB device number 3 using ehci-pci vm-test-run-pgroonga> server # [ 2.147382] 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/input2 vm-test-run-pgroonga> server # [ 2.150752] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 vm-test-run-pgroonga> server # [ 2.271648] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev vm-test-run-pgroonga> server # [ 2.413041] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 vm-test-run-pgroonga> server # [ 2.413942] [drm] features: -virgl +edid -resource_blob -host_visible vm-test-run-pgroonga> server # [ 2.413971] [drm] features: -context_init vm-test-run-pgroonga> server # [ 2.416639] [drm] number of scanouts: 1 vm-test-run-pgroonga> server # [ 2.416789] [drm] number of cap sets: 0 vm-test-run-pgroonga> server # [ 2.427860] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 vm-test-run-pgroonga> server # [ 2.462049] Console: switching to colour frame buffer device 160x50 vm-test-run-pgroonga> server # [ 2.474400] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device vm-test-run-pgroonga> server # [ 5.748389] EXT4-fs (vda): mounted filesystem f319f267-9ec1-4aa7-97b7-e0f1263e29ac r/w with ordered data mode. Quota mode: none. vm-test-run-pgroonga> server # [ 8.305861] EXT4-fs (vda): re-mounted f319f267-9ec1-4aa7-97b7-e0f1263e29ac. vm-test-run-pgroonga> server # [ 8.327474] booting system configuration /nix/store/9syfgkv5brfgymybdgvmif6cqw1njdpj-nixos-system-server-test vm-test-run-pgroonga> server # [ 21.003861] 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) vm-test-run-pgroonga> server # [ 21.013533] systemd[1]: Detected virtualization qemu. vm-test-run-pgroonga> server # [ 21.018259] systemd[1]: Detected architecture arm64. vm-test-run-pgroonga> server # [ 21.022561] systemd[1]: Detected first boot. vm-test-run-pgroonga> server # [ 21.049526] systemd[1]: Initializing machine ID from random generator. vm-test-run-pgroonga> server # [ 21.078007] systemd[1]: Hostname set to . vm-test-run-pgroonga> server # [ 22.310620] systemd[1]: bpf-restrict-fs: LSM BPF program attached vm-test-run-pgroonga> server # [ 23.634106] systemd[1]: Applying preset policy. vm-test-run-pgroonga> server # [ 26.813872] systemd[1]: Populated /etc with preset unit settings. vm-test-run-pgroonga> server: Guest root shell did not produce any data yet... vm-test-run-pgroonga> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-pgroonga> server # [ 29.644794] systemd[1]: Queued start job for default target Multi-User System. vm-test-run-pgroonga> server # [ 29.837333] systemd[1]: Created slice Slice /system/getty. vm-test-run-pgroonga> server # [ 29.851466] systemd[1]: Created slice Slice /system/modprobe. vm-test-run-pgroonga> server # [ 29.862455] systemd[1]: Created slice User and Session Slice. vm-test-run-pgroonga> server # [ 29.869856] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. vm-test-run-pgroonga> server # [ 29.875679] systemd[1]: Started Forward Password Requests to Wall Directory Watch. vm-test-run-pgroonga> server # [ 29.882037] systemd[1]: Expecting device /dev/hvc0... vm-test-run-pgroonga> server # [ 29.886151] systemd[1]: Expecting device /dev/ttyAMA0... vm-test-run-pgroonga> server # [ 29.890639] systemd[1]: Expecting device /sys/subsystem/net/devices/eth1... vm-test-run-pgroonga> server # [ 29.895175] systemd[1]: Reached target Local Encrypted Volumes. vm-test-run-pgroonga> server # [ 29.899903] systemd[1]: Reached target Virtual Machines and Containers. vm-test-run-pgroonga> server # [ 29.905069] systemd[1]: Reached target Path Units. vm-test-run-pgroonga> server # [ 29.909303] systemd[1]: Reached target Remote File Systems. vm-test-run-pgroonga> server # [ 29.913956] systemd[1]: Reached target Slice Units. vm-test-run-pgroonga> server # [ 29.917543] systemd[1]: Reached target Swaps. vm-test-run-pgroonga> server # [ 29.953728] systemd[1]: Listening on Process Core Dump Socket. vm-test-run-pgroonga> server # [ 29.975031] systemd[1]: Listening on Credential Encryption/Decryption. vm-test-run-pgroonga> server # [ 29.986277] systemd[1]: Listening on Journal Audit Socket. vm-test-run-pgroonga> server # [ 29.992228] systemd[1]: Listening on Journal Socket (/dev/log). vm-test-run-pgroonga> server # [ 29.997639] systemd[1]: Listening on Journal Sockets. vm-test-run-pgroonga> server # [ 30.005412] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. vm-test-run-pgroonga> server # [ 30.010585] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgroonga> server # [ 30.017385] systemd[1]: Listening on udev Control Socket. vm-test-run-pgroonga> server # [ 30.022377] systemd[1]: Listening on udev Kernel Socket. vm-test-run-pgroonga> server # [ 30.069801] systemd[1]: Mounting Huge Pages File System... vm-test-run-pgroonga> server # [ 30.111228] systemd[1]: Mounting POSIX Message Queue File System... vm-test-run-pgroonga> server # [ 30.158966] systemd[1]: Mounting Kernel Debug File System... vm-test-run-pgroonga> server # [ 30.251360] systemd[1]: Mounting Kernel Trace File System... vm-test-run-pgroonga> server # [ 30.357807] systemd[1]: Starting Create List of Static Device Nodes... vm-test-run-pgroonga> server # [ 30.445665] systemd[1]: Starting Load Kernel Module 9pnet_virtio... vm-test-run-pgroonga> server # [ 30.459487] systemd[1]: Load Kernel Module configfs was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!configfs). vm-test-run-pgroonga> server # [ 30.593575] systemd[1]: Mounting Kernel Configuration File System... vm-test-run-pgroonga> server # [ 30.616101] systemd[1]: Load Kernel Module drm was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!drm). vm-test-run-pgroonga> server # [ 30.634851] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-pgroonga> server # [ 30.797031] systemd[1]: Starting Load Kernel Module fuse... vm-test-run-pgroonga> server # [ 30.812959] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-pgroonga> server # [ 30.825430] 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). vm-test-run-pgroonga> server # [ 30.943798] systemd[1]: Starting Journal Service... vm-test-run-pgroonga> server # [ 31.030616] systemd[1]: Starting Load Kernel Modules... vm-test-run-pgroonga> server # [ 31.112780] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... vm-test-run-pgroonga> server # [ 31.154566] systemd[1]: Starting Remount Root and Kernel File Systems... vm-test-run-pgroonga> server # [ 31.165579] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgroonga> server # [ 31.221812] systemd[1]: Starting Coldplug All udev Devices... vm-test-run-pgroonga> server # [ 31.295599] systemd[1]: Mounted Huge Pages File System. vm-test-run-pgroonga> server # [ 31.310747] systemd[1]: Mounted POSIX Message Queue File System. vm-test-run-pgroonga> server # [ 31.319663] systemd[1]: Mounted Kernel Debug File System. vm-test-run-pgroonga> server # [ 31.336953] systemd[1]: Mounted Kernel Trace File System. vm-test-run-pgroonga> server # [ 31.356842] systemd[1]: Finished Create List of Static Device Nodes. vm-test-run-pgroonga> server # [ 31.371072] systemd[1]: Mounted Kernel Configuration File System. vm-test-run-pgroonga> server # [ 31.430513] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... vm-test-run-pgroonga> server # [ 31.914453] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 31.929278] systemd[1]: Finished Load Kernel Module 9pnet_virtio. vm-test-run-pgroonga> server # [ 32.178381] EXT4-fs (vda): re-mounted f319f267-9ec1-4aa7-97b7-e0f1263e29ac. vm-test-run-pgroonga> server # [ 32.254626] systemd[1]: Finished Load Kernel Modules. vm-test-run-pgroonga> server # [ 32.254967] fuse: init (API version 7.41) vm-test-run-pgroonga> server # [ 32.321153] systemd[1]: Starting Firewall... vm-test-run-pgroonga> server # [ 32.381893] systemd[1]: Starting Apply Kernel Variables... vm-test-run-pgroonga> server # [ 32.391923] systemd[1]: modprobe@fuse.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 32.413395] systemd[1]: Finished Load Kernel Module fuse. vm-test-run-pgroonga> server # [ 32.435038] systemd[1]: Finished Remount Root and Kernel File Systems. vm-test-run-pgroonga> server # [ 32.478841] systemd-journald[397]: Collecting audit messages is enabled. vm-test-run-pgroonga> server # [ 32.490820] systemd[1]: Mounting FUSE Control File System... vm-test-run-pgroonga> server # [ 32.504338] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-pgroonga> server # [ 32.609384] systemd[1]: Starting Load/Save OS Random Seed... vm-test-run-pgroonga> server # [ 32.616685] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgroonga> server # [ 32.746158] systemd[1]: Mounted FUSE Control File System. vm-test-run-pgroonga> server # [ 33.485442] systemd[1]: Finished Load/Save OS Random Seed. vm-test-run-pgroonga> server # [ 33.491060] systemd[1]: Reached target First Boot Complete. vm-test-run-pgroonga> server # [ 33.596798] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. vm-test-run-pgroonga> server # [ 33.670657] systemd[1]: Starting Create Static Device Nodes in /dev... vm-test-run-pgroonga> server # [ 33.860960] systemd[1]: Finished Apply Kernel Variables. vm-test-run-pgroonga> server # [ 33.883341] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. vm-test-run-pgroonga> server # [ 34.651270] systemd[1]: Finished Create Static Device Nodes in /dev. vm-test-run-pgroonga> server # [ 34.675502] systemd[1]: Reached target Preparation for Local File Systems. vm-test-run-pgroonga> server # [ 34.732133] systemd[1]: Mounting /run/wrappers... vm-test-run-pgroonga> server # [ 34.795098] systemd[1]: Starting Rule-based Manager for Device Events and Files... vm-test-run-pgroonga> server # [ 35.155408] systemd[1]: Mounted /run/wrappers. vm-test-run-pgroonga> server # [ 35.170003] systemd[1]: Reached target Local File Systems. vm-test-run-pgroonga> server # [ 35.199101] systemd[1]: Listening on Boot Entries Service Socket. vm-test-run-pgroonga> server # [ 35.245602] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-pgroonga> server # [ 35.254629] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-pgroonga> server # [ 35.318706] systemd[1]: Starting Save Transient machine-id to Disk... vm-test-run-pgroonga> server # [ 35.388663] systemd[1]: Started Journal Service. vm-test-run-pgroonga> server # [ 35.307134] systemd-modules-load[398]: Module 'atkbd' is built in vm-test-run-pgroonga> server # [ 35.326441] systemd-modules-load[398]: Module 'loop' is built in vm-test-run-pgroonga> server # [ 35.346363] systemd-oomd[399]: No swap; memory pressure usage will be degraded vm-test-run-pgroonga> server # [ 35.415103] systemd[1]: Starting Flush Journal to Persistent Storage... vm-test-run-pgroonga> server # [ 36.287827] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. vm-test-run-pgroonga> server # [ 36.475178] systemd-journald[397]: Received client request to flush runtime journal. vm-test-run-pgroonga> server # [ 36.831664] systemd-udevd[425]: Using default interface naming scheme 'v258'. vm-test-run-pgroonga> server # [ 36.909773] systemd[1]: Finished Save Transient machine-id to Disk. vm-test-run-pgroonga> server # [ 36.927211] systemd[1]: Finished Flush Journal to Persistent Storage. vm-test-run-pgroonga> server # [ 36.933693] systemd[1]: Starting Create System Files and Directories... vm-test-run-pgroonga> server # [ 38.514974] systemd[1]: Started Rule-based Manager for Device Events and Files. vm-test-run-pgroonga> server # [ 38.555781] systemd[1]: Finished Create System Files and Directories. vm-test-run-pgroonga> server # [ 38.665267] systemd[1]: Starting Rebuild Journal Catalog... vm-test-run-pgroonga> server # [ 38.729134] systemd[1]: Starting Record System Boot/Shutdown in UTMP... vm-test-run-pgroonga> server # [ 39.791861] systemd[1]: Finished Record System Boot/Shutdown in UTMP. vm-test-run-pgroonga> server # [ 40.942797] systemd[1]: Finished Rebuild Journal Catalog. vm-test-run-pgroonga> server # [ 41.008705] systemd[1]: Starting Update is Completed... vm-test-run-pgroonga> server # [ 41.730112] systemd[1]: Finished Update is Completed. vm-test-run-pgroonga> server # [ 45.127894] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 45.151243] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-pgroonga> server # [ 45.155666] systemd[1]: suid-sgid-wrappers.service: Consumed 2.747s CPU time, 5.6M memory peak. vm-test-run-pgroonga> server # [ 47.940517] systemd[1]: Finished Firewall. vm-test-run-pgroonga> server # [ 49.298986] systemd[1]: Finished Coldplug All udev Devices. vm-test-run-pgroonga> server # [ 49.307149] systemd[1]: Reached target System Initialization. vm-test-run-pgroonga> server # [ 49.325249] systemd[1]: Started Discard unused filesystem blocks once a week. vm-test-run-pgroonga> server # [ 49.330850] systemd[1]: Started Daily Cleanup of Temporary Directories. vm-test-run-pgroonga> server # [ 49.357073] systemd[1]: Reached target Timer Units. vm-test-run-pgroonga> server # [ 49.359157] systemd[1]: Listening on D-Bus System Message Bus Socket. vm-test-run-pgroonga> server # [ 49.370793] systemd[1]: Listening on Nix Daemon Socket. vm-test-run-pgroonga> server # [ 49.377715] systemd[1]: Listening on Hostname Service Socket. vm-test-run-pgroonga> server # [ 49.382307] systemd[1]: Reached target Socket Units. vm-test-run-pgroonga> server # [ 49.387208] systemd[1]: Reached target Basic System. vm-test-run-pgroonga> server # [ 49.395896] systemd[1]: Starting Import lastlog data into lastlog2 database... vm-test-run-pgroonga> server # [ 49.453092] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pgroonga> server # [ 49.517536] systemd[1]: Started Reset console on configuration changes. vm-test-run-pgroonga> server # [ 49.550107] systemd[1]: Starting resolvconf update... vm-test-run-pgroonga> server # [ 49.614019] systemd[1]: Starting D-Bus System Message Bus... vm-test-run-pgroonga> server # [ 50.931337] systemd[1]: Finished Import lastlog data into lastlog2 database. vm-test-run-pgroonga> server # [ 51.076884] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pgroonga> server # [ 51.079992] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pgroonga> server # [ 51.087418] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pgroonga> server # [ 51.189965] systemd[1]: Starting User Login Management... vm-test-run-pgroonga> server # [ 51.222964] nsncd[631]: Oct 22 21:25:33.395 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pgroonga> server # [ 51.467036] dbus-daemon[634]: dbus[634]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-pgroonga> server # [ 51.970155] systemd[1]: Started D-Bus System Message Bus. vm-test-run-pgroonga> server # [ 52.763334] systemd-logind[653]: New seat seat0. vm-test-run-pgroonga> server # [ 52.787922] systemd[1]: Started User Login Management. vm-test-run-pgroonga> server # [ 52.829566] systemd[1]: Starting linger-users.service... vm-test-run-pgroonga> server # [ 53.085699] systemd[1]: Stopped target Host and Network Name Lookups. vm-test-run-pgroonga> server # [ 53.117678] systemd[1]: Stopping Host and Network Name Lookups... vm-test-run-pgroonga> server # [ 53.127614] systemd[1]: Stopped target User and Group Name Lookups. vm-test-run-pgroonga> server # [ 53.135561] systemd[1]: Stopping User and Group Name Lookups... vm-test-run-pgroonga> server # [ 53.159335] systemd[1]: Stopping Name Service Cache Daemon (nsncd)... vm-test-run-pgroonga> server # [ 53.185267] systemd[1]: nscd.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 53.198082] systemd[1]: Stopped Name Service Cache Daemon (nsncd). vm-test-run-pgroonga> server # [ 53.386349] systemd[1]: Starting Name Service Cache Daemon (nsncd)... vm-test-run-pgroonga> server # [ 53.509126] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 53.523476] systemd[1]: Finished linger-users.service. vm-test-run-pgroonga> server # [ 54.235533] nsncd[693]: Oct 22 21:25:36.574 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" vm-test-run-pgroonga> server # [ 54.260089] systemd[1]: Started Name Service Cache Daemon (nsncd). vm-test-run-pgroonga> server # [ 54.268722] systemd[1]: Reached target Host and Network Name Lookups. vm-test-run-pgroonga> server # [ 54.286832] systemd[1]: Reached target User and Group Name Lookups. vm-test-run-pgroonga> server # [ 54.881213] systemd[1]: Finished resolvconf update. vm-test-run-pgroonga> server # [ 54.897157] systemd[1]: Reached target Preparation for Network. vm-test-run-pgroonga> server # [ 54.950762] systemd[1]: Starting DHCP Client... vm-test-run-pgroonga> server # [ 56.565933] dhcpcd[742]: dhcpcd-10.2.4 starting vm-test-run-pgroonga> server # [ 56.709723] dhcpcd[760]: dev: loaded udev vm-test-run-pgroonga> server # [ 57.233021] 8021q: 802.1Q VLAN Support v1.8 vm-test-run-pgroonga> server # [ 57.722328] cfg80211: Loading compiled-in X.509 certificates for regulatory database vm-test-run-pgroonga> server # [ 57.979191] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' vm-test-run-pgroonga> server # [ 57.989802] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' vm-test-run-pgroonga> server # [ 58.013579] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 vm-test-run-pgroonga> server # [ 58.025247] cfg80211: failed to load regulatory.db vm-test-run-pgroonga> server # [ 58.857420] 8021q: adding VLAN 0 to HW filter on device eth0 vm-test-run-pgroonga> server # [ 58.755206] dhcpcd[760]: eth0: waiting for carrier vm-test-run-pgroonga> server # [ 58.759469] dhcpcd[760]: eth0: carrier acquired vm-test-run-pgroonga> server # [ 58.869233] dhcpcd[760]: DUID 00:01:00:01:32:6d:3b:d5:52:54:00:12:34:56 vm-test-run-pgroonga> server # [ 58.874669] dhcpcd[760]: eth0: IAID 00:12:34:56 vm-test-run-pgroonga> server # [ 58.878548] dhcpcd[760]: eth0: adding address fe80::5054:ff:fe12:3456 vm-test-run-pgroonga> server: Guest root shell did not produce any data yet... vm-test-run-pgroonga> server: To debug, enter the VM and run 'systemctl status backdoor.service'. vm-test-run-pgroonga> server # [ 59.675446] systemd[1]: Found device /dev/hvc0. vm-test-run-pgroonga> server # [ 60.252157] dhcpcd[760]: eth0: soliciting a DHCP lease vm-test-run-pgroonga> server # [ 60.292662] dhcpcd[760]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-pgroonga> server # [ 60.303888] dhcpcd[760]: eth0: probing address 10.0.2.15/24 vm-test-run-pgroonga> server # [ 60.378520] systemd[1]: Found device /dev/ttyAMA0. vm-test-run-pgroonga> server # [ 60.429376] systemd[1]: Started backdoor.service. vm-test-run-pgroonga> server # [ 60.678113] (udev-worker)[791]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name. vm-test-run-pgroonga> server # [ 60.689698] (udev-worker)[791]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgroonga> server # [ 60.702276] (udev-worker)[786]: Network interface NamePolicy= disabled on kernel command line. vm-test-run-pgroonga> server # [ 61.015993] dhcpcd[760]: eth0: soliciting an IPv6 router vm-test-run-pgroonga> server # [ 61.053442] dhcpcd[760]: eth0: Router Advertisement from fe80::2 vm-test-run-pgroonga> server # [ 61.060617] dhcpcd[760]: eth0: adding address fec0::5054:ff:fe12:3456/64 vm-test-run-pgroonga> server # [ 61.070614] dhcpcd[760]: eth0: adding route to fec0::/64 vm-test-run-pgroonga> server # [ 61.075587] dhcpcd[760]: eth0: adding default route via fe80::2 vm-test-run-pgroonga> server # connecting to host... vm-test-run-pgroonga> server: Guest shell says: b'Spawning backdoor root shell...\n' vm-test-run-pgroonga> server: connected to guest root shell vm-test-run-pgroonga> server: (connecting took 62.53 seconds) vm-test-run-pgroonga> server: (finished: waiting for the VM to finish booting, in 62.53 seconds) vm-test-run-pgroonga> server # [ 62.080837] dhcpcd[760]: eth0: IAID 00:12:34:56 vm-test-run-pgroonga> server # [ 62.194952] systemd[1]: Found device Virtio network device. vm-test-run-pgroonga> server # [ 62.237080] systemd[1]: Starting Address configuration of eth1... vm-test-run-pgroonga> server # [ 62.398165] dhcpcd[760]: eth0: soliciting an IPv6 router vm-test-run-pgroonga> server # [ 63.290615] 8021q: adding VLAN 0 to HW filter on device eth1 vm-test-run-pgroonga> server # [ 63.548738] network-addresses-eth1-start[814]: adding address 192.168.1.1/24... done vm-test-run-pgroonga> server # [ 63.789970] network-addresses-eth1-start[814]: adding address 2001:db8:1::1/64... done vm-test-run-pgroonga> server # [ 64.005895] dhcpcd[760]: eth0: soliciting a DHCP lease vm-test-run-pgroonga> server # [ 64.014710] dhcpcd[760]: eth0: offered 10.0.2.15 from 10.0.2.2 vm-test-run-pgroonga> server # [ 64.015998] dhcpcd[760]: eth0: probing address 10.0.2.15/24 vm-test-run-pgroonga> server # [ 64.130238] systemd[1]: Finished Address configuration of eth1. vm-test-run-pgroonga> server # [ 64.214880] systemd[1]: Starting Networking Setup... vm-test-run-pgroonga> server # [ 65.997899] dhcpcd[760]: eth0: leased 10.0.2.15 for 86400 seconds vm-test-run-pgroonga> server # [ 66.010449] dhcpcd[760]: eth0: adding route to 10.0.2.0/24 vm-test-run-pgroonga> server # [ 66.016075] dhcpcd[760]: eth0: adding default route via 10.0.2.2 vm-test-run-pgroonga> server # [ 66.825083] dhcpcd[894]: /nix/store/hsj50yr7hr62ql9xaynbihkhfhbgprcn-openresolv-3.17.0/sbin/.resolvconf-wrapped: line 1221: kill: (856) - Operation not permitted vm-test-run-pgroonga> server # [ 66.828414] dhcpcd[894]: clearing stale lock pid 856 vm-test-run-pgroonga> server # [ 67.364146] systemd[1]: Finished Networking Setup. vm-test-run-pgroonga> server # [ 67.386597] systemd[1]: Reached target Network. vm-test-run-pgroonga> server # [ 67.465108] systemd[1]: Starting PostgreSQL Database Server... vm-test-run-pgroonga> server # [ 67.535253] systemd[1]: Starting Permit User Sessions... vm-test-run-pgroonga> server # [ 68.574709] systemd[1]: Finished Permit User Sessions. vm-test-run-pgroonga> server # [ 68.616602] systemd[1]: Started Getty on tty1. vm-test-run-pgroonga> server # [ 68.618626] systemd[1]: Reached target Login Prompts. vm-test-run-pgroonga> server # [ 69.794531] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[966]: Initializing database at /var/lib/postgresql/data vm-test-run-pgroonga> server # [ 70.833841] systemd[1]: Started DHCP Client. vm-test-run-pgroonga> server # [ 72.010489] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: The files belonging to this database system will be owned by user "postgres". vm-test-run-pgroonga> server # [ 72.039408] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: This user must also own the server process. vm-test-run-pgroonga> server # [ 72.135138] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-pgroonga> server # [ 72.139865] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: The default database encoding has accordingly been set to "UTF8". vm-test-run-pgroonga> server # [ 72.143478] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: The default text search configuration will be set to "english". vm-test-run-pgroonga> server # [ 72.153519] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: Data page checksums are enabled. vm-test-run-pgroonga> server # [ 72.154470] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: fixing permissions on existing directory /var/lib/postgresql/data ... ok vm-test-run-pgroonga> server # [ 72.167148] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: creating subdirectories ... ok vm-test-run-pgroonga> server # [ 72.176285] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: selecting dynamic shared memory implementation ... posix vm-test-run-pgroonga> server # [ 73.669788] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: selecting default max_connections ... 100 vm-test-run-pgroonga> server # [ 74.576899] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: selecting default shared_buffers ... 128MB vm-test-run-pgroonga> server # [ 77.175621] systemd-logind[653]: Watching system buttons on /dev/input/event0 (gpio-keys) vm-test-run-pgroonga> server # [ 86.363668] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: selecting default time zone ... UTC vm-test-run-pgroonga> server # [ 86.427158] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: creating configuration files ... ok vm-test-run-pgroonga> server # [ 86.602271] mousedev: PS/2 mouse device common for all mice vm-test-run-pgroonga> server # [ 90.499992] systemd-logind[653]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) vm-test-run-pgroonga> server # [ 91.310126] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: running bootstrap script ... ok vm-test-run-pgroonga> server # [ 104.700967] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: performing post-bootstrap initialization ... ok vm-test-run-pgroonga> server # [ 107.943972] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: syncing data to disk ... ok vm-test-run-pgroonga> server # [ 107.951122] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-pgroonga> server # [ 107.963833] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: 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. vm-test-run-pgroonga> server # [ 107.979580] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: Success. You can now start the database server using: vm-test-run-pgroonga> server # [ 107.980922] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[977]: /nix/store/i45yhjwwac2d5ygyv0s5gbg2cgdjclf9-postgresql-and-plugins-15.19/bin/pg_ctl -D /var/lib/postgresql/data -l logfile start vm-test-run-pgroonga> server # [ 110.315944] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[966]: Validating PostgreSQL configuration... vm-test-run-pgroonga> server # [ 110.786741] hc3ypq1zkd92a66rf3394zwff56vmm5m-postgresql-init[1378]: pg_stat_statements, pgaudit, plpgsql, plpgsql_check, pg_cron, pg_net, pgsodium, timescaledb, auto_explain, pg_tle, plan_filter, supabase_vault vm-test-run-pgroonga> server # [ 112.194080] postgres[1380]: 2026-10-22 21:26:34.535 UTC [1380] LOG: pgaudit extension initialized vm-test-run-pgroonga> server # [ 113.249775] postgres[1380]: 2026-10-22 21:26:35.593 UTC [1380] LOG: pgsodium primary server secret key loaded vm-test-run-pgroonga> server # [ 113.745831] postgres[1380]: 2026-10-22 21:26:36.105 UTC [1380] LOG: vault primary server secret key loaded vm-test-run-pgroonga> server # [ 114.073553] postgres[1380]: 2026-10-22 21:26:36.431 UTC [1380] LOG: starting PostgreSQL 15.19 on aarch64-unknown-linux-gnu, compiled by gcc (GCC) 15.2.0, 64-bit vm-test-run-pgroonga> server # [ 114.090168] postgres[1380]: 2026-10-22 21:26:36.448 UTC [1380] LOG: listening on IPv4 address "0.0.0.0", port 5432 vm-test-run-pgroonga> server # [ 114.095238] postgres[1380]: 2026-10-22 21:26:36.453 UTC [1380] LOG: listening on IPv6 address "::", port 5432 vm-test-run-pgroonga> server # [ 114.114070] postgres[1380]: 2026-10-22 21:26:36.472 UTC [1380] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432" vm-test-run-pgroonga> server # [ 114.239997] postgres[1414]: 2026-10-22 21:26:36.591 UTC [1414] LOG: database system was shut down at 2026-10-22 21:26:26 UTC vm-test-run-pgroonga> server # [ 114.767073] postgres[1380]: 2026-10-22 21:26:37.100 UTC [1380] LOG: database system is ready to accept connections vm-test-run-pgroonga> server # [ 114.806395] postgres[1426]: 2026-10-22 21:26:37.101 UTC [1426] FATAL: role "postgres" does not exist vm-test-run-pgroonga> server # [ 114.852814] postgres[1423]: 2026-10-22 21:26:37.181 UTC [1423] LOG: TimescaleDB background worker launcher connected to shared catalogs vm-test-run-pgroonga> server # [ 114.900001] systemd[1]: Started PostgreSQL Database Server. vm-test-run-pgroonga> server # [ 114.958611] systemd[1]: Starting Supabase Database Initialization... vm-test-run-pgroonga> server # [ 115.181330] postgres[1380]: 2026-10-22 21:26:37.520 UTC [1380] LOG: background worker "pg_net 0.20.4 worker" (PID 1426) exited with exit code 1 vm-test-run-pgroonga> server # [ 115.450931] postgres[1428]: 2026-10-22 21:26:37.802 UTC [1428] LOG: pg_cron scheduler started vm-test-run-pgroonga> server # [ 115.519695] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Waiting for PostgreSQL to be ready... vm-test-run-pgroonga> server # [ 116.307782] postgres[1449]: 2026-10-22 21:26:38.637 UTC [1449] FATAL: role "postgres" does not exist vm-test-run-pgroonga> server # [ 116.385331] postgres[1380]: 2026-10-22 21:26:38.731 UTC [1380] LOG: background worker "pg_net 0.20.4 worker" (PID 1449) exited with exit code 1 vm-test-run-pgroonga> server # [ 116.420031] postgres[1450]: ::1 2026-10-22 21:26:38.728 UTC [1450] root@root FATAL: role "root" does not exist vm-test-run-pgroonga> server # [ 116.438492] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: PostgreSQL is ready vm-test-run-pgroonga> server # [ 116.441154] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Creating postgres role... vm-test-run-pgroonga> server # [ 116.959465] postgres[1452]: ::1 2026-10-22 21:26:39.310 UTC [1452] supabase_admin@postgres LOG: statement: CREATE ROLE postgres SUPERUSER LOGIN; vm-test-run-pgroonga> server # [ 117.008078] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1451]: CREATE ROLE vm-test-run-pgroonga> server # [ 117.451960] postgres[1458]: ::1 2026-10-22 21:26:39.806 UTC [1458] supabase_admin@postgres LOG: statement: ALTER DATABASE postgres OWNER TO postgres; vm-test-run-pgroonga> server # [ 117.491946] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1457]: ALTER DATABASE vm-test-run-pgroonga> server # [ 117.531126] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000000-initial-schema.sql vm-test-run-pgroonga> server # [ 117.874850] postgres[1461]: ::1 2026-10-22 21:26:40.230 UTC [1461] postgres@postgres LOG: statement: create publication supabase_realtime; vm-test-run-pgroonga> server # [ 117.902037] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE PUBLICATION vm-test-run-pgroonga> server # [ 117.907543] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER ROLE vm-test-run-pgroonga> server # [ 117.909628] postgres[1461]: ::1 2026-10-22 21:26:40.258 UTC [1461] postgres@postgres LOG: statement: alter user supabase_admin with superuser createdb createrole replication bypassrls; vm-test-run-pgroonga> server # [ 117.910443] postgres[1461]: ::1 2026-10-22 21:26:40.267 UTC [1461] postgres@postgres LOG: statement: create user supabase_replication_admin with login replication; vm-test-run-pgroonga> server # [ 117.919449] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE ROLE vm-test-run-pgroonga> server # [ 117.921269] postgres[1461]: ::1 2026-10-22 21:26:40.280 UTC [1461] postgres@postgres LOG: statement: create user supabase_etl_admin with login replication bypassrls; vm-test-run-pgroonga> server # [ 117.935812] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE ROLE vm-test-run-pgroonga> server # [ 117.938377] postgres[1461]: ::1 2026-10-22 21:26:40.298 UTC [1461] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_etl_admin; vm-test-run-pgroonga> server # [ 117.955854] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT ROLE vm-test-run-pgroonga> server # [ 117.963486] postgres[1461]: ::1 2026-10-22 21:26:40.317 UTC [1461] postgres@postgres LOG: statement: grant create on database postgres to supabase_etl_admin; vm-test-run-pgroonga> server # [ 117.968925] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT vm-test-run-pgroonga> server # [ 117.979478] postgres[1461]: ::1 2026-10-22 21:26:40.331 UTC [1461] postgres@postgres LOG: statement: create role supabase_read_only_user with login bypassrls; vm-test-run-pgroonga> server # [ 117.986525] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE ROLE vm-test-run-pgroonga> server # [ 117.993597] postgres[1461]: ::1 2026-10-22 21:26:40.353 UTC [1461] postgres@postgres LOG: statement: grant pg_read_all_data to supabase_read_only_user; vm-test-run-pgroonga> server # [ 118.000979] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT ROLE vm-test-run-pgroonga> server # [ 118.005174] postgres[1461]: ::1 2026-10-22 21:26:40.363 UTC [1461] postgres@postgres LOG: statement: create schema if not exists extensions; vm-test-run-pgroonga> server # [ 118.016123] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE SCHEMA vm-test-run-pgroonga> server # [ 118.025253] postgres[1461]: ::1 2026-10-22 21:26:40.379 UTC [1461] postgres@postgres LOG: statement: create extension if not exists "uuid-ossp" with schema extensions; vm-test-run-pgroonga> server # [ 120.879167] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE EXTENSION vm-test-run-pgroonga> server # [ 120.915029] postgres[1461]: ::1 2026-10-22 21:26:43.225 UTC [1461] postgres@postgres LOG: statement: create extension if not exists pgcrypto with schema extensions; vm-test-run-pgroonga> server # [ 122.194390] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE EXTENSION vm-test-run-pgroonga> server # [ 122.226586] postgres[1461]: ::1 2026-10-22 21:26:44.543 UTC [1461] postgres@postgres LOG: statement: create role anon nologin noinherit; vm-test-run-pgroonga> server # [ 122.239328] postgres[1461]: ::1 2026-10-22 21:26:44.554 UTC [1461] postgres@postgres LOG: statement: create role authenticated nologin noinherit; vm-test-run-pgroonga> server # [ 122.250451] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE ROLE vm-test-run-pgroonga> server # [ 122.251645] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE ROLE vm-test-run-pgroonga> server # [ 122.258865] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE ROLE vm-test-run-pgroonga> server # [ 122.265848] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: CREATE ROLE vm-test-run-pgroonga> server # [ 122.268528] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT ROLE vm-test-run-pgroonga> server # [ 122.274381] postgres[1461]: ::1 2026-10-22 21:26:44.562 UTC [1461] postgres@postgres LOG: statement: create role service_role nologin noinherit bypassrls; vm-test-run-pgroonga> server # [ 122.281898] postgres[1461]: ::1 2026-10-22 21:26:44.575 UTC [1461] postgres@postgres LOG: statement: create user authenticator noinherit; vm-test-run-pgroonga> server # [ 122.282562] postgres[1461]: ::1 2026-10-22 21:26:44.584 UTC [1461] postgres@postgres LOG: statement: grant anon to authenticator; vm-test-run-pgroonga> server # [ 122.282929] postgres[1461]: ::1 2026-10-22 21:26:44.607 UTC [1461] postgres@postgres LOG: statement: grant authenticated to authenticator; vm-test-run-pgroonga> server # [ 122.283483] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT ROLE vm-test-run-pgroonga> server # [ 122.289037] postgres[1461]: ::1 2026-10-22 21:26:44.636 UTC [1461] postgres@postgres LOG: statement: grant service_role to authenticator; vm-test-run-pgroonga> server # [ 122.306999] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT ROLE vm-test-run-pgroonga> server # [ 122.319103] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT ROLE vm-test-run-pgroonga> server # [ 122.319733] postgres[1461]: ::1 2026-10-22 21:26:44.668 UTC [1461] postgres@postgres LOG: statement: grant supabase_admin to authenticator; vm-test-run-pgroonga> server # [ 122.334177] postgres[1461]: ::1 2026-10-22 21:26:44.689 UTC [1461] postgres@postgres LOG: statement: grant usage on schema public to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.367320] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT vm-test-run-pgroonga> server # [ 122.369985] postgres[1461]: ::1 2026-10-22 21:26:44.728 UTC [1461] postgres@postgres LOG: statement: alter default privileges in schema public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.395607] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 122.396750] postgres[1461]: ::1 2026-10-22 21:26:44.756 UTC [1461] postgres@postgres LOG: statement: alter default privileges in schema public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.409744] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 122.410785] postgres[1461]: ::1 2026-10-22 21:26:44.771 UTC [1461] postgres@postgres LOG: statement: alter default privileges in schema public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.423852] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 122.434861] postgres[1461]: ::1 2026-10-22 21:26:44.787 UTC [1461] postgres@postgres LOG: statement: grant usage on schema extensions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.443326] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: GRANT vm-test-run-pgroonga> server # [ 122.449200] postgres[1461]: ::1 2026-10-22 21:26:44.804 UTC [1461] postgres@postgres LOG: statement: alter user supabase_admin SET search_path TO public, extensions; vm-test-run-pgroonga> server # [ 122.459730] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER ROLE vm-test-run-pgroonga> server # [ 122.466017] postgres[1461]: ::1 2026-10-22 21:26:44.823 UTC [1461] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-pgroonga> server # [ 122.467716] postgres[1461]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.477951] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 122.489612] postgres[1461]: ::1 2026-10-22 21:26:44.836 UTC [1461] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-pgroonga> server # [ 122.491066] postgres[1461]: on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.499505] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 122.522892] postgres[1461]: ::1 2026-10-22 21:26:44.861 UTC [1461] postgres@postgres LOG: statement: alter default privileges for user supabase_admin in schema public grant all vm-test-run-pgroonga> server # [ 122.527371] postgres[1461]: on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 122.538394] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 122.539330] postgres[1461]: ::1 2026-10-22 21:26:44.899 UTC [1461] postgres@postgres LOG: statement: alter role anon set statement_timeout = '3s'; vm-test-run-pgroonga> server # [ 122.548105] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER ROLE vm-test-run-pgroonga> server # [ 122.550029] postgres[1461]: ::1 2026-10-22 21:26:44.909 UTC [1461] postgres@postgres LOG: statement: alter role authenticated set statement_timeout = '8s'; vm-test-run-pgroonga> server # [ 122.561545] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1460]: ALTER ROLE vm-test-run-pgroonga> server # [ 122.670291] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000001-auth-schema.sql vm-test-run-pgroonga> server # [ 123.095597] postgres[1512]: ::1 2026-10-22 21:26:45.436 UTC [1512] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS auth AUTHORIZATION supabase_admin; vm-test-run-pgroonga> server # [ 123.102968] postgres[1512]: ::1 2026-10-22 21:26:45.455 UTC [1512] postgres@postgres LOG: statement: CREATE TABLE auth.users ( vm-test-run-pgroonga> server # [ 123.103606] postgres[1512]: instance_id uuid NULL, vm-test-run-pgroonga> server # [ 123.104945] postgres[1512]: id uuid NOT NULL UNIQUE, vm-test-run-pgroonga> server # [ 123.122078] postgres[1512]: aud varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.123204] postgres[1512]: "role" varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.123945] postgres[1512]: email varchar(255) NULL UNIQUE, vm-test-run-pgroonga> server # [ 123.141171] postgres[1512]: encrypted_password varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.142283] postgres[1512]: confirmed_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.143511] postgres[1512]: invited_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.144245] postgres[1512]: confirmation_token varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.150293] postgres[1512]: confirmation_sent_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.151615] postgres[1512]: recovery_token varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.162634] postgres[1512]: recovery_sent_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.170155] postgres[1512]: email_change_token varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.171227] postgres[1512]: email_change varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.172119] postgres[1512]: email_change_sent_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.182318] postgres[1512]: last_sign_in_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.183463] postgres[1512]: raw_app_meta_data jsonb NULL, vm-test-run-pgroonga> server # [ 123.184048] postgres[1512]: raw_user_meta_data jsonb NULL, vm-test-run-pgroonga> server # [ 123.198125] postgres[1512]: is_super_admin bool NULL, vm-test-run-pgroonga> server # [ 123.214224] postgres[1512]: created_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.223666] postgres[1512]: updated_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.226731] postgres[1512]: CONSTRAINT users_pkey PRIMARY KEY (id) vm-test-run-pgroonga> server # [ 123.235847] postgres[1512]: ); vm-test-run-pgroonga> server # [ 123.249519] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE SCHEMA vm-test-run-pgroonga> server # [ 123.343681] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE TABLE vm-test-run-pgroonga> server # [ 123.357924] postgres[1512]: ::1 2026-10-22 21:26:45.703 UTC [1512] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_email_idx ON auth.users USING btree (instance_id, email); vm-test-run-pgroonga> server # [ 123.386233] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE INDEX vm-test-run-pgroonga> server # [ 123.390024] postgres[1512]: ::1 2026-10-22 21:26:45.750 UTC [1512] postgres@postgres LOG: statement: CREATE INDEX users_instance_id_idx ON auth.users USING btree (instance_id); vm-test-run-pgroonga> server # [ 123.426760] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE INDEX vm-test-run-pgroonga> server # [ 123.433961] postgres[1512]: ::1 2026-10-22 21:26:45.788 UTC [1512] postgres@postgres LOG: statement: comment on table auth.users is 'Auth: Stores user login data within a secure schema.'; vm-test-run-pgroonga> server # [ 123.445839] postgres[1512]: ::1 2026-10-22 21:26:45.804 UTC [1512] postgres@postgres LOG: statement: CREATE TABLE auth.refresh_tokens ( vm-test-run-pgroonga> server # [ 123.447242] postgres[1512]: instance_id uuid NULL, vm-test-run-pgroonga> server # [ 123.447875] postgres[1512]: id bigserial NOT NULL, vm-test-run-pgroonga> server # [ 123.448403] postgres[1512]: "token" varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.459085] postgres[1512]: user_id varchar(255) NULL, vm-test-run-pgroonga> server # [ 123.460146] postgres[1512]: revoked bool NULL, vm-test-run-pgroonga> server # [ 123.465857] postgres[1512]: created_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.467603] postgres[1512]: updated_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.469768] postgres[1512]: CONSTRAINT refresh_tokens_pkey PRIMARY KEY (id) vm-test-run-pgroonga> server # [ 123.471770] postgres[1512]: ); vm-test-run-pgroonga> server # [ 123.473150] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: COMMENT vm-test-run-pgroonga> server # [ 123.589316] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE TABLE vm-test-run-pgroonga> server # [ 123.591511] postgres[1512]: ::1 2026-10-22 21:26:45.949 UTC [1512] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_idx ON auth.refresh_tokens USING btree (instance_id); vm-test-run-pgroonga> server # [ 123.638520] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE INDEX vm-test-run-pgroonga> server # [ 123.639427] postgres[1512]: ::1 2026-10-22 21:26:45.999 UTC [1512] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_instance_id_user_id_idx ON auth.refresh_tokens USING btree (instance_id, user_id); vm-test-run-pgroonga> server # [ 123.677283] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE INDEX vm-test-run-pgroonga> server # [ 123.678491] postgres[1512]: ::1 2026-10-22 21:26:46.033 UTC [1512] postgres@postgres LOG: statement: CREATE INDEX refresh_tokens_token_idx ON auth.refresh_tokens USING btree (token); vm-test-run-pgroonga> server # [ 123.727627] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE INDEX vm-test-run-pgroonga> server # [ 123.729848] postgres[1512]: ::1 2026-10-22 21:26:46.084 UTC [1512] postgres@postgres LOG: statement: comment on table auth.refresh_tokens is 'Auth: Store of tokens used to refresh JWT tokens once they expire.'; vm-test-run-pgroonga> server # [ 123.747669] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: COMMENT vm-test-run-pgroonga> server # [ 123.749983] postgres[1512]: ::1 2026-10-22 21:26:46.100 UTC [1512] postgres@postgres LOG: statement: CREATE TABLE auth.instances ( vm-test-run-pgroonga> server # [ 123.757538] postgres[1512]: id uuid NOT NULL, vm-test-run-pgroonga> server # [ 123.758744] postgres[1512]: uuid uuid NULL, vm-test-run-pgroonga> server # [ 123.765335] postgres[1512]: raw_base_config text NULL, vm-test-run-pgroonga> server # [ 123.766574] postgres[1512]: created_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.767666] postgres[1512]: updated_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.768007] postgres[1512]: CONSTRAINT instances_pkey PRIMARY KEY (id) vm-test-run-pgroonga> server # [ 123.768665] postgres[1512]: ); vm-test-run-pgroonga> server # [ 123.834363] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE TABLE vm-test-run-pgroonga> server # [ 123.835557] postgres[1512]: ::1 2026-10-22 21:26:46.191 UTC [1512] postgres@postgres LOG: statement: comment on table auth.instances is 'Auth: Manages users across multiple sites.'; vm-test-run-pgroonga> server # [ 123.847000] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: COMMENT vm-test-run-pgroonga> server # [ 123.847972] postgres[1512]: ::1 2026-10-22 21:26:46.208 UTC [1512] postgres@postgres LOG: statement: CREATE TABLE auth.audit_log_entries ( vm-test-run-pgroonga> server # [ 123.848398] postgres[1512]: instance_id uuid NULL, vm-test-run-pgroonga> server # [ 123.853007] postgres[1512]: id uuid NOT NULL, vm-test-run-pgroonga> server # [ 123.856014] postgres[1512]: payload json NULL, vm-test-run-pgroonga> server # [ 123.866149] postgres[1512]: created_at timestamptz NULL, vm-test-run-pgroonga> server # [ 123.866721] postgres[1512]: CONSTRAINT audit_log_entries_pkey PRIMARY KEY (id) vm-test-run-pgroonga> server # [ 123.868224] postgres[1512]: ); vm-test-run-pgroonga> server # [ 123.934989] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE TABLE vm-test-run-pgroonga> server # [ 123.938152] postgres[1512]: ::1 2026-10-22 21:26:46.298 UTC [1512] postgres@postgres LOG: statement: CREATE INDEX audit_logs_instance_id_idx ON auth.audit_log_entries USING btree (instance_id); vm-test-run-pgroonga> server # [ 123.972796] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE INDEX vm-test-run-pgroonga> server # [ 123.975822] postgres[1512]: ::1 2026-10-22 21:26:46.336 UTC [1512] postgres@postgres LOG: statement: comment on table auth.audit_log_entries is 'Auth: Audit trail for user actions.'; vm-test-run-pgroonga> server # [ 123.982826] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: COMMENT vm-test-run-pgroonga> server # [ 123.987612] postgres[1512]: ::1 2026-10-22 21:26:46.347 UTC [1512] postgres@postgres LOG: statement: CREATE TABLE auth.schema_migrations ( vm-test-run-pgroonga> server # [ 123.989822] postgres[1512]: "version" varchar(255) NOT NULL, vm-test-run-pgroonga> server # [ 123.990869] postgres[1512]: CONSTRAINT schema_migrations_pkey PRIMARY KEY ("version") vm-test-run-pgroonga> server # [ 123.991758] postgres[1512]: ); vm-test-run-pgroonga> server # [ 124.026360] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE TABLE vm-test-run-pgroonga> server # [ 124.028175] postgres[1512]: ::1 2026-10-22 21:26:46.387 UTC [1512] postgres@postgres LOG: statement: comment on table auth.schema_migrations is 'Auth: Manages updates to the auth system.'; vm-test-run-pgroonga> server # [ 124.039300] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: COMMENT vm-test-run-pgroonga> server # [ 124.062837] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: INSERT 0 7 vm-test-run-pgroonga> server # [ 124.068935] postgres[1512]: ::1 2026-10-22 21:26:46.426 UTC [1512] postgres@postgres LOG: statement: create or replace function auth.uid() returns uuid as $$ vm-test-run-pgroonga> server # [ 124.070689] postgres[1512]: select nullif(current_setting('request.jwt.claim.sub', true), '')::uuid; vm-test-run-pgroonga> server # [ 124.072287] postgres[1512]: $$ language sql stable; vm-test-run-pgroonga> server # [ 124.078338] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 124.079318] postgres[1512]: ::1 2026-10-22 21:26:46.439 UTC [1512] postgres@postgres LOG: statement: create or replace function auth.role() returns text as $$ vm-test-run-pgroonga> server # [ 124.090868] postgres[1512]: select nullif(current_setting('request.jwt.claim.role', true), '')::text; vm-test-run-pgroonga> server # [ 124.092693] postgres[1512]: $$ language sql stable; vm-test-run-pgroonga> server # [ 124.094362] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 124.098005] postgres[1512]: ::1 2026-10-22 21:26:46.458 UTC [1512] postgres@postgres LOG: statement: create or replace function auth.email() returns text as $$ vm-test-run-pgroonga> server # [ 124.100011] postgres[1512]: select nullif(current_setting('request.jwt.claim.email', true), '')::text; vm-test-run-pgroonga> server # [ 124.102003] postgres[1512]: $$ language sql stable; vm-test-run-pgroonga> server # [ 124.110889] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 124.111801] postgres[1512]: ::1 2026-10-22 21:26:46.471 UTC [1512] postgres@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 124.119724] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: GRANT vm-test-run-pgroonga> server # [ 124.124807] postgres[1512]: ::1 2026-10-22 21:26:46.482 UTC [1512] postgres@postgres LOG: statement: CREATE USER supabase_auth_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-pgroonga> server # [ 124.134357] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: CREATE ROLE vm-test-run-pgroonga> server # [ 124.136037] postgres[1512]: ::1 2026-10-22 21:26:46.496 UTC [1512] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.146488] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: GRANT vm-test-run-pgroonga> server # [ 124.150446] postgres[1512]: ::1 2026-10-22 21:26:46.509 UTC [1512] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.161924] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: GRANT vm-test-run-pgroonga> server # [ 124.168873] postgres[1512]: ::1 2026-10-22 21:26:46.525 UTC [1512] postgres@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.175466] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: GRANT vm-test-run-pgroonga> server # [ 124.177332] postgres[1512]: ::1 2026-10-22 21:26:46.536 UTC [1512] postgres@postgres LOG: statement: ALTER USER supabase_auth_admin SET search_path = "auth"; vm-test-run-pgroonga> server # [ 124.184970] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: ALTER ROLE vm-test-run-pgroonga> server # [ 124.186169] postgres[1512]: ::1 2026-10-22 21:26:46.544 UTC [1512] postgres@postgres LOG: statement: ALTER table "auth".users OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.200725] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: ALTER TABLE vm-test-run-pgroonga> server # [ 124.204124] postgres[1512]: ::1 2026-10-22 21:26:46.561 UTC [1512] postgres@postgres LOG: statement: ALTER table "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.216167] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: ALTER TABLE vm-test-run-pgroonga> server # [ 124.218837] postgres[1512]: ::1 2026-10-22 21:26:46.579 UTC [1512] postgres@postgres LOG: statement: ALTER table "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.228044] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: ALTER TABLE vm-test-run-pgroonga> server # [ 124.234007] postgres[1512]: ::1 2026-10-22 21:26:46.588 UTC [1512] postgres@postgres LOG: statement: ALTER table "auth".instances OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.244619] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: ALTER TABLE vm-test-run-pgroonga> server # [ 124.247904] postgres[1512]: ::1 2026-10-22 21:26:46.603 UTC [1512] postgres@postgres LOG: statement: ALTER table "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 124.255895] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1505]: ALTER TABLE vm-test-run-pgroonga> server # [ 124.312532] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000002-storage-schema.sql vm-test-run-pgroonga> server # [ 124.602682] postgres[1517]: ::1 2026-10-22 21:26:46.955 UTC [1517] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS storage AUTHORIZATION supabase_admin; vm-test-run-pgroonga> server # [ 124.622348] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1516]: CREATE SCHEMA vm-test-run-pgroonga> server # [ 124.624955] postgres[1517]: ::1 2026-10-22 21:26:46.978 UTC [1517] postgres@postgres LOG: statement: CREATE USER supabase_storage_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-pgroonga> server # [ 124.629112] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1516]: CREATE ROLE vm-test-run-pgroonga> server # [ 124.632749] postgres[1517]: ::1 2026-10-22 21:26:46.988 UTC [1517] postgres@postgres LOG: statement: ALTER USER supabase_storage_admin SET search_path = "storage"; vm-test-run-pgroonga> server # [ 124.641223] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1516]: ALTER ROLE vm-test-run-pgroonga> server # [ 124.643059] postgres[1517]: ::1 2026-10-22 21:26:47.002 UTC [1517] postgres@postgres LOG: statement: GRANT CREATE ON DATABASE postgres TO supabase_storage_admin; vm-test-run-pgroonga> server # [ 124.654537] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1516]: GRANT vm-test-run-pgroonga> server # [ 124.702926] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1516]: DO vm-test-run-pgroonga> server # [ 124.751217] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running init-script: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/init-scripts/00000000000003-post-setup.sql vm-test-run-pgroonga> server # [ 125.201622] postgres[1524]: ::1 2026-10-22 21:26:47.550 UTC [1524] postgres@postgres LOG: statement: ALTER ROLE supabase_admin SET search_path TO "\$user",public,auth,extensions; vm-test-run-pgroonga> server # [ 125.255490] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: ALTER ROLE vm-test-run-pgroonga> server # [ 125.271911] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: ALTER ROLE vm-test-run-pgroonga> server # [ 125.278397] postgres[1524]: ::1 2026-10-22 21:26:47.596 UTC [1524] postgres@postgres LOG: statement: ALTER ROLE postgres SET search_path TO "\$user",public,extensions; vm-test-run-pgroonga> server # [ 125.279123] postgres[1524]: ::1 2026-10-22 21:26:47.614 UTC [1524] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() vm-test-run-pgroonga> server # [ 125.303361] postgres[1524]: RETURNS event_trigger vm-test-run-pgroonga> server # [ 125.316196] postgres[1524]: LANGUAGE plpgsql vm-test-run-pgroonga> server # [ 125.317930] postgres[1524]: AS $$ vm-test-run-pgroonga> server # [ 125.318946] postgres[1524]: DECLARE vm-test-run-pgroonga> server # [ 125.319591] postgres[1524]: schema_is_cron bool; vm-test-run-pgroonga> server # [ 125.320208] postgres[1524]: BEGIN vm-test-run-pgroonga> server # [ 125.329687] postgres[1524]: schema_is_cron = ( vm-test-run-pgroonga> server # [ 125.331190] postgres[1524]: SELECT n.nspname = 'cron' vm-test-run-pgroonga> server # [ 125.341944] postgres[1524]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgroonga> server # [ 125.349370] postgres[1524]: LEFT JOIN pg_catalog.pg_namespace AS n vm-test-run-pgroonga> server # [ 125.355311] postgres[1524]: ON ev.objid = n.oid vm-test-run-pgroonga> server # [ 125.357032] postgres[1524]: ); vm-test-run-pgroonga> server # [ 125.358261] postgres[1524]: vm-test-run-pgroonga> server # [ 125.359998] postgres[1524]: IF schema_is_cron vm-test-run-pgroonga> server # [ 125.362211] postgres[1524]: THEN vm-test-run-pgroonga> server # [ 125.362711] postgres[1524]: grant usage on schema cron to postgres with grant option; vm-test-run-pgroonga> server # [ 125.364472] postgres[1524]: vm-test-run-pgroonga> server # [ 125.365291] postgres[1524]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-pgroonga> server # [ 125.366680] postgres[1524]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-pgroonga> server # [ 125.367547] postgres[1524]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-pgroonga> server # [ 125.370026] postgres[1524]: vm-test-run-pgroonga> server # [ 125.370623] postgres[1524]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgroonga> server # [ 125.373623] postgres[1524]: on sequences to postgres with grant option; vm-test-run-pgroonga> server # [ 125.374614] postgres[1524]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgroonga> server # [ 125.377673] postgres[1524]: on tables to postgres with grant option; vm-test-run-pgroonga> server # [ 125.379435] postgres[1524]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgroonga> server # [ 125.381049] postgres[1524]: on functions to postgres with grant option; vm-test-run-pgroonga> server # [ 125.382132] postgres[1524]: vm-test-run-pgroonga> server # [ 125.383530] postgres[1524]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-pgroonga> server # [ 125.388113] postgres[1524]: vm-test-run-pgroonga> server # [ 125.390535] postgres[1524]: END IF; vm-test-run-pgroonga> server # [ 125.391076] postgres[1524]: vm-test-run-pgroonga> server # [ 125.392795] postgres[1524]: END; vm-test-run-pgroonga> server # [ 125.393791] postgres[1524]: $$; vm-test-run-pgroonga> server # [ 125.395344] postgres[1524]: ::1 2026-10-22 21:26:47.676 UTC [1524] postgres@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end WHEN TAG in ('CREATE SCHEMA') vm-test-run-pgroonga> server # [ 125.395983] postgres[1524]: EXECUTE PROCEDURE extensions.grant_pg_cron_access(); vm-test-run-pgroonga> server # [ 125.398429] postgres[1524]: ::1 2026-10-22 21:26:47.716 UTC [1524] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_cron_access IS 'Grants access to pg_cron'; vm-test-run-pgroonga> server # [ 125.399392] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 125.401002] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 125.403016] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: COMMENT vm-test-run-pgroonga> server # [ 125.404084] postgres[1524]: ::1 2026-10-22 21:26:47.758 UTC [1524] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_net_access() vm-test-run-pgroonga> server # [ 125.422105] postgres[1524]: RETURNS event_trigger vm-test-run-pgroonga> server # [ 125.422894] postgres[1524]: LANGUAGE plpgsql vm-test-run-pgroonga> server # [ 125.428455] postgres[1524]: AS $$ vm-test-run-pgroonga> server # [ 125.435968] postgres[1524]: BEGIN vm-test-run-pgroonga> server # [ 125.446306] postgres[1524]: IF EXISTS ( vm-test-run-pgroonga> server # [ 125.454527] postgres[1524]: SELECT 1 vm-test-run-pgroonga> server # [ 125.456035] postgres[1524]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgroonga> server # [ 125.457855] postgres[1524]: JOIN pg_extension AS ext vm-test-run-pgroonga> server # [ 125.458204] postgres[1524]: ON ev.objid = ext.oid vm-test-run-pgroonga> server # [ 125.465101] postgres[1524]: WHERE ext.extname = 'pg_net' vm-test-run-pgroonga> server # [ 125.466392] postgres[1524]: ) vm-test-run-pgroonga> server # [ 125.466751] postgres[1524]: THEN vm-test-run-pgroonga> server # [ 125.467007] postgres[1524]: IF NOT EXISTS ( vm-test-run-pgroonga> server # [ 125.467288] postgres[1524]: SELECT 1 vm-test-run-pgroonga> server # [ 125.467655] postgres[1524]: FROM pg_roles vm-test-run-pgroonga> server # [ 125.469323] postgres[1524]: WHERE rolname = 'supabase_functions_admin' vm-test-run-pgroonga> server # [ 125.470055] postgres[1524]: ) vm-test-run-pgroonga> server # [ 125.471424] postgres[1524]: THEN vm-test-run-pgroonga> server # [ 125.472243] postgres[1524]: CREATE USER supabase_functions_admin NOINHERIT CREATEROLE LOGIN NOREPLICATION; vm-test-run-pgroonga> server # [ 125.473058] postgres[1524]: END IF; vm-test-run-pgroonga> server # [ 125.477120] postgres[1524]: vm-test-run-pgroonga> server # [ 125.477956] postgres[1524]: GRANT USAGE ON SCHEMA net TO supabase_functions_admin, postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 125.479447] postgres[1524]: vm-test-run-pgroonga> server # [ 125.481772] postgres[1524]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-pgroonga> server # [ 125.489327] postgres[1524]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SECURITY DEFINER; vm-test-run-pgroonga> server # [ 125.491628] postgres[1524]: vm-test-run-pgroonga> server # [ 125.493649] postgres[1524]: ALTER function net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-pgroonga> server # [ 125.495827] postgres[1524]: ALTER function net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) SET search_path = net; vm-test-run-pgroonga> server # [ 125.498410] postgres[1524]: vm-test-run-pgroonga> server # [ 125.498938] postgres[1524]: REVOKE ALL ON FUNCTION net.http_get(url text, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-pgroonga> server # [ 125.506972] postgres[1524]: REVOKE ALL ON FUNCTION net.http_post(url text, body jsonb, params jsonb, headers jsonb, timeout_milliseconds integer) FROM PUBLIC; vm-test-run-pgroonga> server # [ 125.513801] postgres[1524]: vm-test-run-pgroonga> server # [ 125.515254] postgres[1524]: 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; vm-test-run-pgroonga> server # [ 125.516873] postgres[1524]: 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; vm-test-run-pgroonga> server # [ 125.518005] postgres[1524]: END IF; vm-test-run-pgroonga> server # [ 125.518301] postgres[1524]: END; vm-test-run-pgroonga> server # [ 125.518558] postgres[1524]: $$; vm-test-run-pgroonga> server # [ 125.520037] postgres[1524]: ::1 2026-10-22 21:26:47.792 UTC [1524] postgres@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_net_access IS 'Grants access to pg_net'; vm-test-run-pgroonga> server # [ 125.526592] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 125.527205] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: COMMENT vm-test-run-pgroonga> server # [ 125.549121] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: DO vm-test-run-pgroonga> server # [ 125.557169] postgres[1524]: ::1 2026-10-22 21:26:47.916 UTC [1524] postgres@postgres LOG: statement: CREATE ROLE dashboard_user NOSUPERUSER CREATEDB CREATEROLE REPLICATION; vm-test-run-pgroonga> server # [ 125.570792] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: CREATE ROLE vm-test-run-pgroonga> server # [ 125.577039] postgres[1524]: ::1 2026-10-22 21:26:47.936 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO dashboard_user; vm-test-run-pgroonga> server # [ 125.602442] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.609325] postgres[1524]: ::1 2026-10-22 21:26:47.958 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO dashboard_user; vm-test-run-pgroonga> server # [ 125.619543] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.627272] postgres[1524]: ::1 2026-10-22 21:26:47.983 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO dashboard_user; vm-test-run-pgroonga> server # [ 125.642016] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.654016] postgres[1524]: ::1 2026-10-22 21:26:48.011 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO dashboard_user; vm-test-run-pgroonga> server # [ 125.678503] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.687453] postgres[1524]: ::1 2026-10-22 21:26:48.041 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO dashboard_user; vm-test-run-pgroonga> server # [ 125.699242] postgres[1524]: ::1 2026-10-22 21:26:48.052 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO dashboard_user; vm-test-run-pgroonga> server # [ 125.706291] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.721557] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.723710] postgres[1524]: ::1 2026-10-22 21:26:48.072 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO dashboard_user; vm-test-run-pgroonga> server # [ 125.745077] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.770041] postgres[1524]: ::1 2026-10-22 21:26:48.114 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO dashboard_user; vm-test-run-pgroonga> server # [ 125.787008] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.797850] postgres[1524]: ::1 2026-10-22 21:26:48.152 UTC [1524] postgres@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO dashboard_user; vm-test-run-pgroonga> server # [ 125.840866] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: GRANT vm-test-run-pgroonga> server # [ 125.873957] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1518]: DO vm-test-run-pgroonga> server # [ 125.990413] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running pgbouncer auth schema... vm-test-run-pgroonga> server # [ 126.383994] postgres[1541]: ::1 2026-10-22 21:26:48.734 UTC [1541] postgres@postgres LOG: statement: CREATE USER pgbouncer; vm-test-run-pgroonga> server # [ 126.463616] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1534]: CREATE ROLE vm-test-run-pgroonga> server # [ 126.465693] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1534]: REVOKE vm-test-run-pgroonga> server # [ 126.474211] postgres[1541]: ::1 2026-10-22 21:26:48.782 UTC [1541] postgres@postgres LOG: statement: REVOKE ALL PRIVILEGES ON SCHEMA public FROM pgbouncer; vm-test-run-pgroonga> server # [ 126.482049] postgres[1541]: ::1 2026-10-22 21:26:48.795 UTC [1541] postgres@postgres LOG: statement: CREATE SCHEMA pgbouncer AUTHORIZATION pgbouncer; vm-test-run-pgroonga> server # [ 126.556110] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1534]: CREATE SCHEMA vm-test-run-pgroonga> server # [ 126.567706] postgres[1541]: ::1 2026-10-22 21:26:48.918 UTC [1541] postgres@postgres LOG: statement: CREATE OR REPLACE FUNCTION pgbouncer.get_auth(p_usename TEXT) vm-test-run-pgroonga> server # [ 126.572584] postgres[1541]: RETURNS TABLE(username TEXT, password TEXT) AS vm-test-run-pgroonga> server # [ 126.583085] postgres[1541]: $$ vm-test-run-pgroonga> server # [ 126.597201] postgres[1541]: BEGIN vm-test-run-pgroonga> server # [ 126.598721] postgres[1541]: RAISE WARNING 'PgBouncer auth request: %', p_usename; vm-test-run-pgroonga> server # [ 126.600096] postgres[1541]: vm-test-run-pgroonga> server # [ 126.606870] postgres[1541]: RETURN QUERY vm-test-run-pgroonga> server # [ 126.618270] postgres[1541]: SELECT usename::TEXT, passwd::TEXT FROM pg_catalog.pg_shadow vm-test-run-pgroonga> server # [ 126.629160] postgres[1541]: WHERE usename = p_usename; vm-test-run-pgroonga> server # [ 126.629891] postgres[1541]: END; vm-test-run-pgroonga> server # [ 126.630193] postgres[1541]: $$ LANGUAGE plpgsql vm-test-run-pgroonga> server # [ 126.630904] postgres[1541]: SET search_path = '' vm-test-run-pgroonga> server # [ 126.631682] postgres[1541]: SECURITY DEFINER; vm-test-run-pgroonga> server # [ 126.657467] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1534]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 126.661716] postgres[1541]: ::1 2026-10-22 21:26:49.020 UTC [1541] postgres@postgres LOG: statement: REVOKE ALL ON FUNCTION pgbouncer.get_auth(p_usename TEXT) FROM PUBLIC; vm-test-run-pgroonga> server # [ 126.676548] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1534]: REVOKE vm-test-run-pgroonga> server # [ 126.679242] postgres[1541]: ::1 2026-10-22 21:26:49.033 UTC [1541] postgres@postgres LOG: statement: GRANT EXECUTE ON FUNCTION pgbouncer.get_auth(p_usename TEXT) TO pgbouncer; vm-test-run-pgroonga> server # [ 126.686405] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1534]: GRANT vm-test-run-pgroonga> server # [ 126.732984] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running stat extension... vm-test-run-pgroonga> server # [ 127.071540] postgres[1547]: ::1 2026-10-22 21:26:49.410 UTC [1547] postgres@postgres LOG: statement: CREATE SCHEMA IF NOT exists extensions; vm-test-run-pgroonga> server # [ 127.097169] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1546]: psql:/nix/store/vgajqkksdcv2cbrba1cgngx3rrkdykp1-stat-extension.sql:1: NOTICE: schema "extensions" already exists, skipping vm-test-run-pgroonga> server # [ 127.151455] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1546]: CREATE SCHEMA vm-test-run-pgroonga> server # [ 127.159815] postgres[1547]: ::1 2026-10-22 21:26:49.515 UTC [1547] postgres@postgres LOG: statement: CREATE EXTENSION IF NOT EXISTS pg_stat_statements with schema extensions; vm-test-run-pgroonga> server # [ 128.614979] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1546]: CREATE EXTENSION vm-test-run-pgroonga> server # [ 128.698319] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/10000000000000_demote-postgres.sql vm-test-run-pgroonga> server # [ 129.169792] postgres[1569]: ::1 2026-10-22 21:26:51.514 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON DATABASE postgres TO postgres; vm-test-run-pgroonga> server # [ 129.218762] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.244083] postgres[1569]: ::1 2026-10-22 21:26:51.562 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA auth TO postgres; vm-test-run-pgroonga> server # [ 129.247029] postgres[1569]: ::1 2026-10-22 21:26:51.602 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON SCHEMA extensions TO postgres; vm-test-run-pgroonga> server # [ 129.251136] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.264300] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.269491] postgres[1569]: ::1 2026-10-22 21:26:51.618 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres; vm-test-run-pgroonga> server # [ 129.296898] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.299077] postgres[1569]: ::1 2026-10-22 21:26:51.653 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA extensions TO postgres; vm-test-run-pgroonga> server # [ 129.325926] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.341579] postgres[1569]: ::1 2026-10-22 21:26:51.697 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres; vm-test-run-pgroonga> server # [ 129.372794] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.374712] postgres[1569]: ::1 2026-10-22 21:26:51.731 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA extensions TO postgres; vm-test-run-pgroonga> server # [ 129.397971] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.405155] postgres[1569]: ::1 2026-10-22 21:26:51.763 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres; vm-test-run-pgroonga> server # [ 129.440430] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.443562] postgres[1569]: ::1 2026-10-22 21:26:51.797 UTC [1569] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA extensions TO postgres; vm-test-run-pgroonga> server # [ 129.493350] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: GRANT vm-test-run-pgroonga> server # [ 129.548934] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: DO vm-test-run-pgroonga> server # [ 129.551113] postgres[1569]: ::1 2026-10-22 21:26:51.907 UTC [1569] supabase_admin@postgres LOG: statement: ALTER ROLE postgres NOSUPERUSER CREATEDB CREATEROLE LOGIN REPLICATION BYPASSRLS; vm-test-run-pgroonga> server # [ 129.573610] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1560]: ALTER ROLE vm-test-run-pgroonga> server # [ 129.679418] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211115181400_update-auth-permissions.sql vm-test-run-pgroonga> server # [ 130.077273] postgres[1579]: ::1 2026-10-22 21:26:52.434 UTC [1579] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON SCHEMA auth TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.091305] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: GRANT vm-test-run-pgroonga> server # [ 130.096071] postgres[1579]: ::1 2026-10-22 21:26:52.450 UTC [1579] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL TABLES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.105826] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: GRANT vm-test-run-pgroonga> server # [ 130.108016] postgres[1579]: ::1 2026-10-22 21:26:52.467 UTC [1579] supabase_admin@postgres LOG: statement: GRANT ALL PRIVILEGES ON ALL SEQUENCES IN SCHEMA auth TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.124645] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: GRANT vm-test-run-pgroonga> server # [ 130.127283] postgres[1579]: ::1 2026-10-22 21:26:52.483 UTC [1579] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".users OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.134392] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER TABLE vm-test-run-pgroonga> server # [ 130.135828] postgres[1579]: ::1 2026-10-22 21:26:52.496 UTC [1579] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".refresh_tokens OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.151242] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER TABLE vm-test-run-pgroonga> server # [ 130.155078] postgres[1579]: ::1 2026-10-22 21:26:52.515 UTC [1579] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".audit_log_entries OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.169080] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER TABLE vm-test-run-pgroonga> server # [ 130.173105] postgres[1579]: ::1 2026-10-22 21:26:52.531 UTC [1579] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".instances OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.185594] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER TABLE vm-test-run-pgroonga> server # [ 130.188004] postgres[1579]: ::1 2026-10-22 21:26:52.542 UTC [1579] supabase_admin@postgres LOG: statement: ALTER table IF EXISTS "auth".schema_migrations OWNER TO supabase_auth_admin; vm-test-run-pgroonga> server # [ 130.194550] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER TABLE vm-test-run-pgroonga> server # [ 130.195497] postgres[1579]: ::1 2026-10-22 21:26:52.555 UTC [1579] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA auth TO postgres; vm-test-run-pgroonga> server # [ 130.207218] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: GRANT vm-test-run-pgroonga> server # [ 130.208113] postgres[1579]: ::1 2026-10-22 21:26:52.568 UTC [1579] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.217902] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: GRANT vm-test-run-pgroonga> server # [ 130.218769] postgres[1579]: ::1 2026-10-22 21:26:52.579 UTC [1579] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.227815] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: GRANT vm-test-run-pgroonga> server # [ 130.233919] postgres[1579]: ::1 2026-10-22 21:26:52.588 UTC [1579] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA auth TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.242821] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: GRANT vm-test-run-pgroonga> server # [ 130.251265] postgres[1579]: ::1 2026-10-22 21:26:52.604 UTC [1579] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON TABLES TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.266929] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 130.272741] postgres[1579]: ::1 2026-10-22 21:26:52.629 UTC [1579] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON SEQUENCES TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.279804] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 130.286013] postgres[1579]: ::1 2026-10-22 21:26:52.642 UTC [1579] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_auth_admin IN SCHEMA auth GRANT ALL ON ROUTINES TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.299093] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1577]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 130.351760] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211118015519_create-realtime-schema.sql vm-test-run-pgroonga> server # [ 130.606520] postgres[1581]: ::1 2026-10-22 21:26:52.963 UTC [1581] supabase_admin@postgres LOG: statement: CREATE SCHEMA IF NOT EXISTS realtime; vm-test-run-pgroonga> server # [ 130.638689] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1580]: CREATE SCHEMA vm-test-run-pgroonga> server # [ 130.669519] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211122051245_update-realtime-permissions.sql vm-test-run-pgroonga> server # [ 130.924042] postgres[1583]: ::1 2026-10-22 21:26:53.279 UTC [1583] supabase_admin@postgres LOG: statement: GRANT USAGE ON SCHEMA realtime TO postgres; vm-test-run-pgroonga> server # [ 130.954081] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: GRANT vm-test-run-pgroonga> server # [ 130.959609] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: GRANT vm-test-run-pgroonga> server # [ 130.960369] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: GRANT vm-test-run-pgroonga> server # [ 130.965066] postgres[1583]: ::1 2026-10-22 21:26:53.306 UTC [1583] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL TABLES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.967395] postgres[1583]: ::1 2026-10-22 21:26:53.311 UTC [1583] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL SEQUENCES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.969343] postgres[1583]: ::1 2026-10-22 21:26:53.318 UTC [1583] supabase_admin@postgres LOG: statement: GRANT ALL ON ALL ROUTINES IN SCHEMA realtime TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 130.974187] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1582]: GRANT vm-test-run-pgroonga> server # [ 131.015770] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211124212715_update-auth-owner.sql vm-test-run-pgroonga> server # [ 131.471553] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1584]: DO vm-test-run-pgroonga> server # [ 131.483450] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1584]: DO vm-test-run-pgroonga> server # [ 131.512967] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1584]: DO vm-test-run-pgroonga> server # [ 131.594200] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20211130151719_update-realtime-permissions.sql vm-test-run-pgroonga> server # [ 132.004488] postgres[1596]: ::1 2026-10-22 21:26:54.347 UTC [1596] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON TABLES TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 132.091572] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1592]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 132.098126] postgres[1596]: ::1 2026-10-22 21:26:54.418 UTC [1596] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON SEQUENCES TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 132.106298] postgres[1596]: ::1 2026-10-22 21:26:54.437 UTC [1596] supabase_admin@postgres LOG: statement: ALTER DEFAULT PRIVILEGES FOR ROLE supabase_admin IN SCHEMA realtime GRANT ALL ON ROUTINES TO postgres, dashboard_user; vm-test-run-pgroonga> server # [ 132.108028] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1592]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 132.114029] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1592]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 132.142615] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220118070449_enable-safeupdate-postgrest.sql vm-test-run-pgroonga> server # [ 132.552804] postgres[1606]: ::1 2026-10-22 21:26:54.887 UTC [1606] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator SET session_preload_libraries = 'safeupdate'; vm-test-run-pgroonga> server # [ 132.599608] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1599]: ALTER ROLE vm-test-run-pgroonga> server # [ 132.635246] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql vm-test-run-pgroonga> server # [ 133.055195] postgres[1614]: ::1 2026-10-22 21:26:55.405 UTC [1614] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-pgroonga> server # [ 133.069861] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-pgroonga> server # [ 133.071183] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 133.072275] postgres[1614]: ::1 2026-10-22 21:26:55.427 UTC [1614] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-pgroonga> server # [ 133.085734] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-pgroonga> server # [ 133.094317] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: DROP FUNCTION vm-test-run-pgroonga> server # [ 133.095882] postgres[1614]: ::1 2026-10-22 21:26:55.454 UTC [1614] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-pgroonga> server # [ 133.107988] postgres[1614]: DECLARE vm-test-run-pgroonga> server # [ 133.117807] postgres[1614]: cmd record; vm-test-run-pgroonga> server # [ 133.118361] postgres[1614]: BEGIN vm-test-run-pgroonga> server # [ 133.118874] postgres[1614]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-pgroonga> server # [ 133.119612] postgres[1614]: LOOP vm-test-run-pgroonga> server # [ 133.133599] postgres[1614]: IF cmd.command_tag IN ( vm-test-run-pgroonga> server # [ 133.150286] postgres[1614]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-pgroonga> server # [ 133.151708] postgres[1614]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-pgroonga> server # [ 133.155239] postgres[1614]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-pgroonga> server # [ 133.155999] postgres[1614]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-pgroonga> server # [ 133.157572] postgres[1614]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-pgroonga> server # [ 133.158506] postgres[1614]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-pgroonga> server # [ 133.159417] postgres[1614]: , 'CREATE TRIGGER' vm-test-run-pgroonga> server # [ 133.159735] postgres[1614]: , 'CREATE TYPE' vm-test-run-pgroonga> server # [ 133.160009] postgres[1614]: , 'CREATE RULE' vm-test-run-pgroonga> server # [ 133.160507] postgres[1614]: , 'COMMENT' vm-test-run-pgroonga> server # [ 133.169929] postgres[1614]: ) vm-test-run-pgroonga> server # [ 133.170542] postgres[1614]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-pgroonga> server # [ 133.172061] postgres[1614]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-pgroonga> server # [ 133.178957] postgres[1614]: THEN vm-test-run-pgroonga> server # [ 133.179509] postgres[1614]: NOTIFY pgrst, 'reload schema'; vm-test-run-pgroonga> server # [ 133.179816] postgres[1614]: END IF; vm-test-run-pgroonga> server # [ 133.195898] postgres[1614]: END LOOP; vm-test-run-pgroonga> server # [ 133.201048] postgres[1614]: END; $$ LANGUAGE plpgsql; vm-test-run-pgroonga> server # [ 133.202226] postgres[1614]: ::1 2026-10-22 21:26:55.499 UTC [1614] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-pgroonga> server # [ 133.203489] postgres[1614]: DECLARE vm-test-run-pgroonga> server # [ 133.204076] postgres[1614]: obj record; vm-test-run-pgroonga> server # [ 133.210963] postgres[1614]: BEGIN vm-test-run-pgroonga> server # [ 133.211388] postgres[1614]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-pgroonga> server # [ 133.211881] postgres[1614]: LOOP vm-test-run-pgroonga> server # [ 133.214837] postgres[1614]: IF obj.object_type IN ( vm-test-run-pgroonga> server # [ 133.222290] postgres[1614]: 'schema' vm-test-run-pgroonga> server # [ 133.227224] postgres[1614]: , 'table' vm-test-run-pgroonga> server # [ 133.227951] postgres[1614]: , 'foreign table' vm-test-run-pgroonga> server # [ 133.228326] postgres[1614]: , 'view' vm-test-run-pgroonga> server # [ 133.231462] postgres[1614]: , 'materialized view' vm-test-run-pgroonga> server # [ 133.236958] postgres[1614]: , 'function' vm-test-run-pgroonga> server # [ 133.242512] postgres[1614]: , 'trigger' vm-test-run-pgroonga> server # [ 133.243154] postgres[1614]: , 'type' vm-test-run-pgroonga> server # [ 133.243894] postgres[1614]: , 'rule' vm-test-run-pgroonga> server # [ 133.254977] postgres[1614]: ) vm-test-run-pgroonga> server # [ 133.260992] postgres[1614]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-pgroonga> server # [ 133.269232] postgres[1614]: THEN vm-test-run-pgroonga> server # [ 133.281325] postgres[1614]: NOTIFY pgrst, 'reload schema'; vm-test-run-pgroonga> server # [ 133.282315] postgres[1614]: END IF; vm-test-run-pgroonga> server # [ 133.297514] postgres[1614]: END LOOP; vm-test-run-pgroonga> server # [ 133.298261] postgres[1614]: END; $$ LANGUAGE plpgsql; vm-test-run-pgroonga> server # [ 133.299345] postgres[1614]: ::1 2026-10-22 21:26:55.516 UTC [1614] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-pgroonga> server # [ 133.299843] postgres[1614]: ::1 2026-10-22 21:26:55.535 UTC [1614] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-pgroonga> server # [ 133.307676] postgres[1614]: ON ddl_command_end vm-test-run-pgroonga> server # [ 133.313646] postgres[1614]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-pgroonga> server # [ 133.314381] postgres[1614]: ::1 2026-10-22 21:26:55.555 UTC [1614] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-pgroonga> server # [ 133.315954] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 133.327063] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 133.333824] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:59: NOTICE: event trigger "pgrst_ddl_watch" does not exist, skipping vm-test-run-pgroonga> server # [ 133.336066] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 133.343151] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 133.348534] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220126121436_finer-postgrest-triggers.sql:64: NOTICE: event trigger "pgrst_drop_watch" does not exist, skipping vm-test-run-pgroonga> server # [ 133.351896] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 133.354208] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1609]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 133.363764] postgres[1614]: ::1 2026-10-22 21:26:55.568 UTC [1614] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-pgroonga> server # [ 133.367224] postgres[1614]: ON sql_drop vm-test-run-pgroonga> server # [ 133.372473] postgres[1614]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-pgroonga> server # [ 133.373630] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220224211803_fix-postgrest-supautils.sql vm-test-run-pgroonga> server # [ 134.359955] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1619]: DO vm-test-run-pgroonga> server # [ 134.410619] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql vm-test-run-pgroonga> server # [ 134.686416] postgres[1623]: ::1 2026-10-22 21:26:57.038 UTC [1623] supabase_admin@postgres LOG: statement: create schema if not exists graphql_public; vm-test-run-pgroonga> server # [ 134.725374] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: CREATE SCHEMA vm-test-run-pgroonga> server # [ 134.728932] postgres[1623]: ::1 2026-10-22 21:26:57.084 UTC [1623] supabase_admin@postgres LOG: statement: drop function if exists graphql_public.graphql(text, text, jsonb); vm-test-run-pgroonga> server # [ 134.730200] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:5: NOTICE: function graphql_public.graphql(text,text,jsonb) does not exist, skipping vm-test-run-pgroonga> server # [ 134.740325] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: DROP FUNCTION vm-test-run-pgroonga> server # [ 134.745001] postgres[1623]: ::1 2026-10-22 21:26:57.103 UTC [1623] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 134.747348] postgres[1623]: "operationName" text default null, vm-test-run-pgroonga> server # [ 134.747946] postgres[1623]: query text default null, vm-test-run-pgroonga> server # [ 134.751227] postgres[1623]: variables jsonb default null, vm-test-run-pgroonga> server # [ 134.751974] postgres[1623]: extensions jsonb default null vm-test-run-pgroonga> server # [ 134.755390] postgres[1623]: ) vm-test-run-pgroonga> server # [ 134.758255] postgres[1623]: returns jsonb vm-test-run-pgroonga> server # [ 134.768478] postgres[1623]: language plpgsql vm-test-run-pgroonga> server # [ 134.770332] postgres[1623]: as $$ vm-test-run-pgroonga> server # [ 134.774900] postgres[1623]: DECLARE vm-test-run-pgroonga> server # [ 134.775485] postgres[1623]: server_version float; vm-test-run-pgroonga> server # [ 134.777754] postgres[1623]: BEGIN vm-test-run-pgroonga> server # [ 134.778243] postgres[1623]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-pgroonga> server # [ 134.779079] postgres[1623]: vm-test-run-pgroonga> server # [ 134.779999] postgres[1623]: IF server_version >= 14 THEN vm-test-run-pgroonga> server # [ 134.781330] postgres[1623]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 134.782300] postgres[1623]: 'data', null::jsonb, vm-test-run-pgroonga> server # [ 134.782820] postgres[1623]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-pgroonga> server # [ 134.784127] postgres[1623]: ); vm-test-run-pgroonga> server # [ 134.785348] postgres[1623]: ELSE vm-test-run-pgroonga> server # [ 134.786048] postgres[1623]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 134.786343] postgres[1623]: 'data', null::jsonb, vm-test-run-pgroonga> server # [ 134.786603] postgres[1623]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-pgroonga> server # [ 134.786887] postgres[1623]: ); vm-test-run-pgroonga> server # [ 134.787223] postgres[1623]: END IF; vm-test-run-pgroonga> server # [ 134.787921] postgres[1623]: END; vm-test-run-pgroonga> server # [ 134.788161] postgres[1623]: $$; vm-test-run-pgroonga> server # [ 134.789248] postgres[1623]: ::1 2026-10-22 21:26:57.139 UTC [1623] supabase_admin@postgres LOG: statement: grant usage on schema graphql_public to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 134.794069] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 134.815470] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: GRANT vm-test-run-pgroonga> server # [ 134.823945] postgres[1623]: ::1 2026-10-22 21:26:57.176 UTC [1623] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 134.831233] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 134.843262] postgres[1623]: ::1 2026-10-22 21:26:57.196 UTC [1623] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 134.853763] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 134.857958] postgres[1623]: ::1 2026-10-22 21:26:57.216 UTC [1623] supabase_admin@postgres LOG: statement: alter default privileges in schema graphql_public grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 134.877686] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 134.878812] postgres[1623]: ::1 2026-10-22 21:26:57.239 UTC [1623] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-pgroonga> server # [ 134.879312] postgres[1623]: on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 134.897803] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 134.898702] postgres[1623]: ::1 2026-10-22 21:26:57.258 UTC [1623] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-pgroonga> server # [ 134.899152] postgres[1623]: on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 134.910176] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 134.918913] postgres[1623]: ::1 2026-10-22 21:26:57.273 UTC [1623] supabase_admin@postgres LOG: statement: alter default privileges for user supabase_admin in schema graphql_public grant all vm-test-run-pgroonga> server # [ 134.929205] postgres[1623]: on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 134.943893] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 134.961303] postgres[1623]: ::1 2026-10-22 21:26:57.320 UTC [1623] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_graphql_access() vm-test-run-pgroonga> server # [ 134.988706] postgres[1623]: RETURNS event_trigger vm-test-run-pgroonga> server # [ 134.989904] postgres[1623]: LANGUAGE plpgsql vm-test-run-pgroonga> server # [ 134.990708] postgres[1623]: AS $func$ vm-test-run-pgroonga> server # [ 134.991023] postgres[1623]: DECLARE vm-test-run-pgroonga> server # [ 134.991887] postgres[1623]: func_is_graphql_resolve bool; vm-test-run-pgroonga> server # [ 134.997463] postgres[1623]: BEGIN vm-test-run-pgroonga> server # [ 134.998888] postgres[1623]: func_is_graphql_resolve = ( vm-test-run-pgroonga> server # [ 134.999703] postgres[1623]: SELECT n.proname = 'resolve' vm-test-run-pgroonga> server # [ 135.000279] postgres[1623]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgroonga> server # [ 135.006622] postgres[1623]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-pgroonga> server # [ 135.007718] postgres[1623]: ON ev.objid = n.oid vm-test-run-pgroonga> server # [ 135.008746] postgres[1623]: ); vm-test-run-pgroonga> server # [ 135.014317] postgres[1623]: vm-test-run-pgroonga> server # [ 135.015954] postgres[1623]: IF func_is_graphql_resolve vm-test-run-pgroonga> server # [ 135.024871] postgres[1623]: THEN vm-test-run-pgroonga> server # [ 135.026003] postgres[1623]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 135.026631] postgres[1623]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 135.041931] postgres[1623]: vm-test-run-pgroonga> server # [ 135.043201] postgres[1623]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 135.053727] postgres[1623]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 135.054649] postgres[1623]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 135.055049] postgres[1623]: vm-test-run-pgroonga> server # [ 135.055279] postgres[1623]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-pgroonga> server # [ 135.055548] postgres[1623]: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 135.055800] postgres[1623]: "operationName" text default null, vm-test-run-pgroonga> server # [ 135.063753] postgres[1623]: query text default null, vm-test-run-pgroonga> server # [ 135.069553] postgres[1623]: variables jsonb default null, vm-test-run-pgroonga> server # [ 135.070394] postgres[1623]: extensions jsonb default null vm-test-run-pgroonga> server # [ 135.070948] postgres[1623]: ) vm-test-run-pgroonga> server # [ 135.071985] postgres[1623]: returns jsonb vm-test-run-pgroonga> server # [ 135.082491] postgres[1623]: language sql vm-test-run-pgroonga> server # [ 135.083182] postgres[1623]: as $$ vm-test-run-pgroonga> server # [ 135.083464] postgres[1623]: SELECT graphql.resolve(query, coalesce(variables, '{}')); vm-test-run-pgroonga> server # [ 135.083767] postgres[1623]: $$; vm-test-run-pgroonga> server # [ 135.084012] postgres[1623]: vm-test-run-pgroonga> server # [ 135.084237] postgres[1623]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 135.098635] postgres[1623]: END IF; vm-test-run-pgroonga> server # [ 135.099250] postgres[1623]: vm-test-run-pgroonga> server # [ 135.099588] postgres[1623]: END; vm-test-run-pgroonga> server # [ 135.099812] postgres[1623]: $func$; vm-test-run-pgroonga> server # [ 135.107005] postgres[1623]: ::1 2026-10-22 21:26:57.385 UTC [1623] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_pg_graphql_access; vm-test-run-pgroonga> server # [ 135.108043] postgres[1623]: ::1 2026-10-22 21:26:57.393 UTC [1623] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_graphql_access ON ddl_command_end WHEN TAG in ('CREATE FUNCTION') vm-test-run-pgroonga> server # [ 135.108471] postgres[1623]: EXECUTE PROCEDURE extensions.grant_pg_graphql_access(); vm-test-run-pgroonga> server # [ 135.116260] postgres[1623]: ::1 2026-10-22 21:26:57.412 UTC [1623] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.grant_pg_graphql_access IS 'Grants access to pg_graphql'; vm-test-run-pgroonga> server # [ 135.116810] postgres[1623]: ::1 2026-10-22 21:26:57.428 UTC [1623] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-pgroonga> server # [ 135.124955] postgres[1623]: RETURNS event_trigger vm-test-run-pgroonga> server # [ 135.125545] postgres[1623]: LANGUAGE plpgsql vm-test-run-pgroonga> server # [ 135.126401] postgres[1623]: AS $func$ vm-test-run-pgroonga> server # [ 135.126929] postgres[1623]: DECLARE vm-test-run-pgroonga> server # [ 135.127235] postgres[1623]: graphql_is_dropped bool; vm-test-run-pgroonga> server # [ 135.139252] postgres[1623]: BEGIN vm-test-run-pgroonga> server # [ 135.141026] postgres[1623]: graphql_is_dropped = ( vm-test-run-pgroonga> server # [ 135.142284] postgres[1623]: SELECT ev.schema_name = 'graphql_public' vm-test-run-pgroonga> server # [ 135.142795] postgres[1623]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-pgroonga> server # [ 135.143417] postgres[1623]: WHERE ev.schema_name = 'graphql_public' vm-test-run-pgroonga> server # [ 135.143727] postgres[1623]: ); vm-test-run-pgroonga> server # [ 135.161922] postgres[1623]: vm-test-run-pgroonga> server # [ 135.181232] postgres[1623]: IF graphql_is_dropped vm-test-run-pgroonga> server # [ 135.182742] postgres[1623]: THEN vm-test-run-pgroonga> server # [ 135.183277] postgres[1623]: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 135.189102] postgres[1623]: "operationName" text default null, vm-test-run-pgroonga> server # [ 135.191339] postgres[1623]: query text default null, vm-test-run-pgroonga> server # [ 135.205759] postgres[1623]: variables jsonb default null, vm-test-run-pgroonga> server # [ 135.229109] postgres[1623]: extensions jsonb default null vm-test-run-pgroonga> server # [ 135.238885] postgres[1623]: ) vm-test-run-pgroonga> server # [ 135.246599] postgres[1623]: returns jsonb vm-test-run-pgroonga> server # [ 135.260665] postgres[1623]: language plpgsql vm-test-run-pgroonga> server # [ 135.262784] postgres[1623]: as $$ vm-test-run-pgroonga> server # [ 135.263135] postgres[1623]: DECLARE vm-test-run-pgroonga> server # [ 135.263401] postgres[1623]: server_version float; vm-test-run-pgroonga> server # [ 135.270157] postgres[1623]: BEGIN vm-test-run-pgroonga> server # [ 135.277086] postgres[1623]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-pgroonga> server # [ 135.287208] postgres[1623]: vm-test-run-pgroonga> server # [ 135.288669] postgres[1623]: IF server_version >= 14 THEN vm-test-run-pgroonga> server # [ 135.289303] postgres[1623]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 135.289792] postgres[1623]: 'data', null::jsonb, vm-test-run-pgroonga> server # [ 135.297676] postgres[1623]: 'errors', array['pg_graphql extension is not enabled.'] vm-test-run-pgroonga> server # [ 135.298595] postgres[1623]: ); vm-test-run-pgroonga> server # [ 135.299519] postgres[1623]: ELSE vm-test-run-pgroonga> server # [ 135.301154] postgres[1623]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 135.301709] postgres[1623]: 'data', null::jsonb, vm-test-run-pgroonga> server # [ 135.302296] postgres[1623]: 'errors', array['pg_graphql is only available on projects running Postgres 14 onwards.'] vm-test-run-pgroonga> server # [ 135.308979] postgres[1623]: ); vm-test-run-pgroonga> server # [ 135.310763] postgres[1623]: END IF; vm-test-run-pgroonga> server # [ 135.311762] postgres[1623]: END; vm-test-run-pgroonga> server # [ 135.318184] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 135.325349] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:90: NOTICE: event trigger "issue_pg_graphql_access" does not exist, skipping vm-test-run-pgroonga> server # [ 135.326907] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 135.333117] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 135.333938] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: COMMENT vm-test-run-pgroonga> server # [ 135.335955] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 135.342092] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220317095840_pg_graphql.sql:143: NOTICE: event trigger "issue_graphql_placeholder" does not exist, skipping vm-test-run-pgroonga> server # [ 135.343780] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 135.345978] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 135.353194] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1622]: COMMENT vm-test-run-pgroonga> server # [ 135.355083] postgres[1623]: $$; vm-test-run-pgroonga> server # [ 135.360051] postgres[1623]: END IF; vm-test-run-pgroonga> server # [ 135.364203] postgres[1623]: vm-test-run-pgroonga> server # [ 135.371234] postgres[1623]: END; vm-test-run-pgroonga> server # [ 135.371639] postgres[1623]: $func$; vm-test-run-pgroonga> server # [ 135.372433] postgres[1623]: ::1 2026-10-22 21:26:57.487 UTC [1623] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS issue_graphql_placeholder; vm-test-run-pgroonga> server # [ 135.382228] postgres[1623]: ::1 2026-10-22 21:26:57.497 UTC [1623] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_graphql_placeholder ON sql_drop WHEN TAG in ('DROP EXTENSION') vm-test-run-pgroonga> server # [ 135.383543] postgres[1623]: EXECUTE PROCEDURE extensions.set_graphql_placeholder(); vm-test-run-pgroonga> server # [ 135.389582] postgres[1623]: ::1 2026-10-22 21:26:57.511 UTC [1623] supabase_admin@postgres LOG: statement: COMMENT ON FUNCTION extensions.set_graphql_placeholder IS 'Reintroduces placeholder function for graphql_public.graphql'; vm-test-run-pgroonga> server # [ 135.391509] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql vm-test-run-pgroonga> server # [ 135.926498] postgres[1639]: ::1 2026-10-22 21:26:58.277 UTC [1639] supabase_admin@postgres LOG: statement: drop event trigger if exists api_restart; vm-test-run-pgroonga> server # [ 135.931989] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:3: NOTICE: event trigger "api_restart" does not exist, skipping vm-test-run-pgroonga> server # [ 135.938154] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 135.945899] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220321174452_fix-postgrest-alter-type-event-trigger.sql:4: NOTICE: function extensions.notify_api_restart() does not exist, skipping vm-test-run-pgroonga> server # [ 135.952986] postgres[1639]: ::1 2026-10-22 21:26:58.291 UTC [1639] supabase_admin@postgres LOG: statement: drop function if exists extensions.notify_api_restart(); vm-test-run-pgroonga> server # [ 135.987163] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: DROP FUNCTION vm-test-run-pgroonga> server # [ 135.990311] postgres[1639]: ::1 2026-10-22 21:26:58.345 UTC [1639] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_ddl_watch() RETURNS event_trigger AS $$ vm-test-run-pgroonga> server # [ 135.991939] postgres[1639]: DECLARE vm-test-run-pgroonga> server # [ 135.997702] postgres[1639]: cmd record; vm-test-run-pgroonga> server # [ 135.998066] postgres[1639]: BEGIN vm-test-run-pgroonga> server # [ 135.998328] postgres[1639]: FOR cmd IN SELECT * FROM pg_event_trigger_ddl_commands() vm-test-run-pgroonga> server # [ 135.998592] postgres[1639]: LOOP vm-test-run-pgroonga> server # [ 135.999005] postgres[1639]: IF cmd.command_tag IN ( vm-test-run-pgroonga> server # [ 135.999258] postgres[1639]: 'CREATE SCHEMA', 'ALTER SCHEMA' vm-test-run-pgroonga> server # [ 135.999491] postgres[1639]: , 'CREATE TABLE', 'CREATE TABLE AS', 'SELECT INTO', 'ALTER TABLE' vm-test-run-pgroonga> server # [ 135.999734] postgres[1639]: , 'CREATE FOREIGN TABLE', 'ALTER FOREIGN TABLE' vm-test-run-pgroonga> server # [ 135.999961] postgres[1639]: , 'CREATE VIEW', 'ALTER VIEW' vm-test-run-pgroonga> server # [ 136.005493] postgres[1639]: , 'CREATE MATERIALIZED VIEW', 'ALTER MATERIALIZED VIEW' vm-test-run-pgroonga> server # [ 136.006693] postgres[1639]: , 'CREATE FUNCTION', 'ALTER FUNCTION' vm-test-run-pgroonga> server # [ 136.007740] postgres[1639]: , 'CREATE TRIGGER' vm-test-run-pgroonga> server # [ 136.013101] postgres[1639]: , 'CREATE TYPE', 'ALTER TYPE' vm-test-run-pgroonga> server # [ 136.015242] postgres[1639]: , 'CREATE RULE' vm-test-run-pgroonga> server # [ 136.015692] postgres[1639]: , 'COMMENT' vm-test-run-pgroonga> server # [ 136.022986] postgres[1639]: ) vm-test-run-pgroonga> server # [ 136.023762] postgres[1639]: -- don't notify in case of CREATE TEMP table or other objects created on pg_temp vm-test-run-pgroonga> server # [ 136.027585] postgres[1639]: AND cmd.schema_name is distinct from 'pg_temp' vm-test-run-pgroonga> server # [ 136.027989] postgres[1639]: THEN vm-test-run-pgroonga> server # [ 136.037624] postgres[1639]: NOTIFY pgrst, 'reload schema'; vm-test-run-pgroonga> server # [ 136.039815] postgres[1639]: END IF; vm-test-run-pgroonga> server # [ 136.041850] postgres[1639]: END LOOP; vm-test-run-pgroonga> server # [ 136.043092] postgres[1639]: END; $$ LANGUAGE plpgsql; vm-test-run-pgroonga> server # [ 136.162843] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 136.169038] postgres[1639]: ::1 2026-10-22 21:26:58.525 UTC [1639] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.pgrst_drop_watch() RETURNS event_trigger AS $$ vm-test-run-pgroonga> server # [ 136.171083] postgres[1639]: DECLARE vm-test-run-pgroonga> server # [ 136.171756] postgres[1639]: obj record; vm-test-run-pgroonga> server # [ 136.172414] postgres[1639]: BEGIN vm-test-run-pgroonga> server # [ 136.181888] postgres[1639]: FOR obj IN SELECT * FROM pg_event_trigger_dropped_objects() vm-test-run-pgroonga> server # [ 136.183255] postgres[1639]: LOOP vm-test-run-pgroonga> server # [ 136.184029] postgres[1639]: IF obj.object_type IN ( vm-test-run-pgroonga> server # [ 136.193532] postgres[1639]: 'schema' vm-test-run-pgroonga> server # [ 136.194828] postgres[1639]: , 'table' vm-test-run-pgroonga> server # [ 136.195640] postgres[1639]: , 'foreign table' vm-test-run-pgroonga> server # [ 136.208003] postgres[1639]: , 'view' vm-test-run-pgroonga> server # [ 136.231951] postgres[1639]: , 'materialized view' vm-test-run-pgroonga> server # [ 136.239157] postgres[1639]: , 'function' vm-test-run-pgroonga> server # [ 136.239768] postgres[1639]: , 'trigger' vm-test-run-pgroonga> server # [ 136.240089] postgres[1639]: , 'type' vm-test-run-pgroonga> server # [ 136.240336] postgres[1639]: , 'rule' vm-test-run-pgroonga> server # [ 136.270488] postgres[1639]: ) vm-test-run-pgroonga> server # [ 136.278445] postgres[1639]: AND obj.is_temporary IS false -- no pg_temp objects vm-test-run-pgroonga> server # [ 136.294409] postgres[1639]: THEN vm-test-run-pgroonga> server # [ 136.295771] postgres[1639]: NOTIFY pgrst, 'reload schema'; vm-test-run-pgroonga> server # [ 136.297993] postgres[1639]: END IF; vm-test-run-pgroonga> server # [ 136.298342] postgres[1639]: END LOOP; vm-test-run-pgroonga> server # [ 136.298624] postgres[1639]: END; $$ LANGUAGE plpgsql; vm-test-run-pgroonga> server # [ 136.299634] postgres[1639]: ::1 2026-10-22 21:26:58.582 UTC [1639] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_ddl_watch; vm-test-run-pgroonga> server # [ 136.305097] postgres[1639]: ::1 2026-10-22 21:26:58.645 UTC [1639] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_ddl_watch vm-test-run-pgroonga> server # [ 136.307012] postgres[1639]: ON ddl_command_end vm-test-run-pgroonga> server # [ 136.307440] postgres[1639]: EXECUTE PROCEDURE extensions.pgrst_ddl_watch(); vm-test-run-pgroonga> server # [ 136.308884] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 136.314079] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 136.315169] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 136.316019] postgres[1639]: ::1 2026-10-22 21:26:58.668 UTC [1639] supabase_admin@postgres LOG: statement: DROP EVENT TRIGGER IF EXISTS pgrst_drop_watch; vm-test-run-pgroonga> server # [ 136.325057] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 136.333322] postgres[1639]: ::1 2026-10-22 21:26:58.692 UTC [1639] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER pgrst_drop_watch vm-test-run-pgroonga> server # [ 136.334446] postgres[1639]: ON sql_drop vm-test-run-pgroonga> server # [ 136.335306] postgres[1639]: EXECUTE PROCEDURE extensions.pgrst_drop_watch(); vm-test-run-pgroonga> server # [ 136.342366] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1633]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 136.410950] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220322085208_gotrue-session-limit.sql vm-test-run-pgroonga> server # [ 136.790631] postgres[1651]: ::1 2026-10-22 21:26:59.139 UTC [1651] supabase_admin@postgres LOG: statement: ALTER ROLE supabase_auth_admin SET idle_in_transaction_session_timeout TO 60000; vm-test-run-pgroonga> server # [ 136.810066] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1649]: ALTER ROLE vm-test-run-pgroonga> server # [ 136.835937] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql vm-test-run-pgroonga> server # [ 137.108426] postgres[1653]: ::1 2026-10-22 21:26:59.461 UTC [1653] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-pgroonga> server # [ 137.112023] postgres[1653]: returns event_trigger vm-test-run-pgroonga> server # [ 137.115642] postgres[1653]: language plpgsql vm-test-run-pgroonga> server # [ 137.118549] postgres[1653]: AS $func$ vm-test-run-pgroonga> server # [ 137.119442] postgres[1653]: DECLARE vm-test-run-pgroonga> server # [ 137.119934] postgres[1653]: func_is_graphql_resolve bool; vm-test-run-pgroonga> server # [ 137.121224] postgres[1653]: BEGIN vm-test-run-pgroonga> server # [ 137.121929] postgres[1653]: func_is_graphql_resolve = ( vm-test-run-pgroonga> server # [ 137.122844] postgres[1653]: SELECT n.proname = 'resolve' vm-test-run-pgroonga> server # [ 137.123116] postgres[1653]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgroonga> server # [ 137.123564] postgres[1653]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-pgroonga> server # [ 137.123824] postgres[1653]: ON ev.objid = n.oid vm-test-run-pgroonga> server # [ 137.124039] postgres[1653]: ); vm-test-run-pgroonga> server # [ 137.124231] postgres[1653]: vm-test-run-pgroonga> server # [ 137.124420] postgres[1653]: IF func_is_graphql_resolve vm-test-run-pgroonga> server # [ 137.124624] postgres[1653]: THEN vm-test-run-pgroonga> server # [ 137.124845] postgres[1653]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 137.125973] postgres[1653]: grant all on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 137.128120] postgres[1653]: vm-test-run-pgroonga> server # [ 137.131070] postgres[1653]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 137.133747] postgres[1653]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 137.135260] postgres[1653]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 137.136992] postgres[1653]: vm-test-run-pgroonga> server # [ 137.137371] postgres[1653]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-pgroonga> server # [ 137.139003] postgres[1653]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-pgroonga> server # [ 137.139557] postgres[1653]: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 137.141841] postgres[1653]: "operationName" text default null, vm-test-run-pgroonga> server # [ 137.142401] postgres[1653]: query text default null, vm-test-run-pgroonga> server # [ 137.142888] postgres[1653]: variables jsonb default null, vm-test-run-pgroonga> server # [ 137.143196] postgres[1653]: extensions jsonb default null vm-test-run-pgroonga> server # [ 137.144224] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.157190] postgres[1653]: returns jsonb vm-test-run-pgroonga> server # [ 137.157836] postgres[1653]: language sql vm-test-run-pgroonga> server # [ 137.158214] postgres[1653]: as $$ vm-test-run-pgroonga> server # [ 137.159119] postgres[1653]: -- This changed vm-test-run-pgroonga> server # [ 137.159686] postgres[1653]: select graphql.resolve( vm-test-run-pgroonga> server # [ 137.159996] postgres[1653]: query := query, vm-test-run-pgroonga> server # [ 137.163129] postgres[1653]: variables := coalesce(variables, '{}'), vm-test-run-pgroonga> server # [ 137.163739] postgres[1653]: "operationName" := "operationName", vm-test-run-pgroonga> server # [ 137.166613] postgres[1653]: extensions := extensions vm-test-run-pgroonga> server # [ 137.167015] postgres[1653]: ); vm-test-run-pgroonga> server # [ 137.167254] postgres[1653]: $$; vm-test-run-pgroonga> server # [ 137.167480] postgres[1653]: vm-test-run-pgroonga> server # [ 137.168031] postgres[1653]: grant execute on function graphql.resolve to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 137.168618] postgres[1653]: END IF; vm-test-run-pgroonga> server # [ 137.169180] postgres[1653]: vm-test-run-pgroonga> server # [ 137.169503] postgres[1653]: END; vm-test-run-pgroonga> server # [ 137.169785] postgres[1653]: $func$; vm-test-run-pgroonga> server # [ 137.190737] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1652]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 137.191751] postgres[1653]: ::1 2026-10-22 21:26:59.545 UTC [1653] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.set_graphql_placeholder() vm-test-run-pgroonga> server # [ 137.192218] postgres[1653]: RETURNS event_trigger vm-test-run-pgroonga> server # [ 137.197981] postgres[1653]: LANGUAGE plpgsql vm-test-run-pgroonga> server # [ 137.198516] postgres[1653]: AS $func$ vm-test-run-pgroonga> server # [ 137.198787] postgres[1653]: DECLARE vm-test-run-pgroonga> server # [ 137.199023] postgres[1653]: graphql_is_dropped bool; vm-test-run-pgroonga> server # [ 137.199291] postgres[1653]: BEGIN vm-test-run-pgroonga> server # [ 137.199546] postgres[1653]: graphql_is_dropped = ( vm-test-run-pgroonga> server # [ 137.200130] postgres[1653]: SELECT ev.schema_name = 'graphql_public' vm-test-run-pgroonga> server # [ 137.200449] postgres[1653]: FROM pg_event_trigger_dropped_objects() AS ev vm-test-run-pgroonga> server # [ 137.206971] postgres[1653]: WHERE ev.schema_name = 'graphql_public' vm-test-run-pgroonga> server # [ 137.208167] postgres[1653]: ); vm-test-run-pgroonga> server # [ 137.213249] postgres[1653]: vm-test-run-pgroonga> server # [ 137.213836] postgres[1653]: IF graphql_is_dropped vm-test-run-pgroonga> server # [ 137.214231] postgres[1653]: THEN vm-test-run-pgroonga> server # [ 137.214449] postgres[1653]: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 137.214693] postgres[1653]: "operationName" text default null, vm-test-run-pgroonga> server # [ 137.214924] postgres[1653]: query text default null, vm-test-run-pgroonga> server # [ 137.215173] postgres[1653]: variables jsonb default null, vm-test-run-pgroonga> server # [ 137.215970] postgres[1653]: extensions jsonb default null vm-test-run-pgroonga> server # [ 137.216255] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.216453] postgres[1653]: returns jsonb vm-test-run-pgroonga> server # [ 137.223631] postgres[1653]: language plpgsql vm-test-run-pgroonga> server # [ 137.230460] postgres[1653]: as $$ vm-test-run-pgroonga> server # [ 137.231116] postgres[1653]: DECLARE vm-test-run-pgroonga> server # [ 137.231417] postgres[1653]: server_version float; vm-test-run-pgroonga> server # [ 137.231965] postgres[1653]: BEGIN vm-test-run-pgroonga> server # [ 137.232235] postgres[1653]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-pgroonga> server # [ 137.232517] postgres[1653]: vm-test-run-pgroonga> server # [ 137.238322] postgres[1653]: IF server_version >= 14 THEN vm-test-run-pgroonga> server # [ 137.239384] postgres[1653]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 137.240277] postgres[1653]: 'errors', jsonb_build_array( vm-test-run-pgroonga> server # [ 137.242346] postgres[1653]: jsonb_build_object( vm-test-run-pgroonga> server # [ 137.243199] postgres[1653]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-pgroonga> server # [ 137.245131] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.245851] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.246393] postgres[1653]: ); vm-test-run-pgroonga> server # [ 137.247109] postgres[1653]: ELSE vm-test-run-pgroonga> server # [ 137.247408] postgres[1653]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 137.250054] postgres[1653]: 'errors', jsonb_build_array( vm-test-run-pgroonga> server # [ 137.250744] postgres[1653]: jsonb_build_object( vm-test-run-pgroonga> server # [ 137.251256] postgres[1653]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-pgroonga> server # [ 137.251626] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.253672] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.254026] postgres[1653]: ); vm-test-run-pgroonga> server # [ 137.254395] postgres[1653]: END IF; vm-test-run-pgroonga> server # [ 137.254669] postgres[1653]: END; vm-test-run-pgroonga> server # [ 137.254909] postgres[1653]: $$; vm-test-run-pgroonga> server # [ 137.255333] postgres[1653]: END IF; vm-test-run-pgroonga> server # [ 137.255832] postgres[1653]: vm-test-run-pgroonga> server # [ 137.256056] postgres[1653]: END; vm-test-run-pgroonga> server # [ 137.256759] postgres[1653]: $func$; vm-test-run-pgroonga> server # [ 137.257903] postgres[1653]: ::1 2026-10-22 21:26:59.566 UTC [1653] supabase_admin@postgres LOG: statement: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 137.258730] postgres[1653]: "operationName" text default null, vm-test-run-pgroonga> server # [ 137.259026] postgres[1653]: query text default null, vm-test-run-pgroonga> server # [ 137.259254] postgres[1653]: variables jsonb default null, vm-test-run-pgroonga> server # [ 137.259480] postgres[1653]: extensions jsonb default null vm-test-run-pgroonga> server # [ 137.259696] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.259908] postgres[1653]: returns jsonb vm-test-run-pgroonga> server # [ 137.261848] postgres[1653]: language plpgsql vm-test-run-pgroonga> server # [ 137.262363] postgres[1653]: as $$ vm-test-run-pgroonga> server # [ 137.262609] postgres[1653]: DECLARE vm-test-run-pgroonga> server # [ 137.263306] postgres[1653]: server_version float; vm-test-run-pgroonga> server # [ 137.263591] postgres[1653]: BEGIN vm-test-run-pgroonga> server # [ 137.263804] postgres[1653]: server_version = (SELECT (SPLIT_PART((select version()), ' ', 2))::float); vm-test-run-pgroonga> server # [ 137.264542] postgres[1653]: vm-test-run-pgroonga> server # [ 137.266055] postgres[1653]: IF server_version >= 14 THEN vm-test-run-pgroonga> server # [ 137.266406] postgres[1653]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 137.266693] postgres[1653]: 'errors', jsonb_build_array( vm-test-run-pgroonga> server # [ 137.266951] postgres[1653]: jsonb_build_object( vm-test-run-pgroonga> server # [ 137.267417] postgres[1653]: 'message', 'pg_graphql extension is not enabled.' vm-test-run-pgroonga> server # [ 137.269188] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.269902] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.270860] postgres[1653]: ); vm-test-run-pgroonga> server # [ 137.271914] postgres[1653]: ELSE vm-test-run-pgroonga> server # [ 137.272326] postgres[1653]: RETURN jsonb_build_object( vm-test-run-pgroonga> server # [ 137.272599] postgres[1653]: 'errors', jsonb_build_array( vm-test-run-pgroonga> server # [ 137.273553] postgres[1653]: jsonb_build_object( vm-test-run-pgroonga> server # [ 137.275372] postgres[1653]: 'message', 'pg_graphql is only available on projects running Postgres 14 onwards.' vm-test-run-pgroonga> server # [ 137.277231] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.279470] postgres[1653]: ) vm-test-run-pgroonga> server # [ 137.281198] postgres[1653]: ); vm-test-run-pgroonga> server # [ 137.281657] postgres[1653]: END IF; vm-test-run-pgroonga> server # [ 137.281905] postgres[1653]: END; vm-test-run-pgroonga> server # [ 137.282113] postgres[1653]: $$; vm-test-run-pgroonga> server # [ 137.282322] postgres[1653]: ::1 2026-10-22 21:26:59.592 UTC [1653] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-pgroonga> server # [ 137.283385] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1652]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 137.285351] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1652]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 137.286169] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1652]: psql:/nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220404205710_pg_graphql-on-by-default.sql:142: NOTICE: extension "pg_graphql" does not exist, skipping vm-test-run-pgroonga> server # [ 137.287547] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1652]: DROP EXTENSION vm-test-run-pgroonga> server # [ 139.287344] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1652]: DO vm-test-run-pgroonga> server # [ 139.323585] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220609081115_grant-supabase-auth-admin-and-supabase-storage-admin-to-postgres.sql vm-test-run-pgroonga> server # [ 139.764536] postgres[1683]: ::1 2026-10-22 21:27:02.096 UTC [1683] supabase_admin@postgres LOG: statement: grant supabase_auth_admin, supabase_storage_admin to postgres; vm-test-run-pgroonga> server # [ 139.806936] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1680]: GRANT ROLE vm-test-run-pgroonga> server # [ 139.835518] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220613123923_pg_graphql-pg-dump-perms.sql vm-test-run-pgroonga> server # [ 140.209923] postgres[1685]: ::1 2026-10-22 21:27:02.566 UTC [1685] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-pgroonga> server # [ 140.210920] postgres[1685]: returns event_trigger vm-test-run-pgroonga> server # [ 140.211348] postgres[1685]: language plpgsql vm-test-run-pgroonga> server # [ 140.211681] postgres[1685]: AS $func$ vm-test-run-pgroonga> server # [ 140.212228] postgres[1685]: DECLARE vm-test-run-pgroonga> server # [ 140.212534] postgres[1685]: func_is_graphql_resolve bool; vm-test-run-pgroonga> server # [ 140.221102] postgres[1685]: BEGIN vm-test-run-pgroonga> server # [ 140.222916] postgres[1685]: func_is_graphql_resolve = ( vm-test-run-pgroonga> server # [ 140.226050] postgres[1685]: SELECT n.proname = 'resolve' vm-test-run-pgroonga> server # [ 140.226927] postgres[1685]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgroonga> server # [ 140.236354] postgres[1685]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-pgroonga> server # [ 140.245164] postgres[1685]: ON ev.objid = n.oid vm-test-run-pgroonga> server # [ 140.246412] postgres[1685]: ); vm-test-run-pgroonga> server # [ 140.246810] postgres[1685]: vm-test-run-pgroonga> server # [ 140.248316] postgres[1685]: IF func_is_graphql_resolve vm-test-run-pgroonga> server # [ 140.253815] postgres[1685]: THEN vm-test-run-pgroonga> server # [ 140.255530] postgres[1685]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-pgroonga> server # [ 140.265286] postgres[1685]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-pgroonga> server # [ 140.274411] postgres[1685]: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 140.274919] postgres[1685]: "operationName" text default null, vm-test-run-pgroonga> server # [ 140.275234] postgres[1685]: query text default null, vm-test-run-pgroonga> server # [ 140.275700] postgres[1685]: variables jsonb default null, vm-test-run-pgroonga> server # [ 140.282485] postgres[1685]: extensions jsonb default null vm-test-run-pgroonga> server # [ 140.283570] postgres[1685]: ) vm-test-run-pgroonga> server # [ 140.283964] postgres[1685]: returns jsonb vm-test-run-pgroonga> server # [ 140.284760] postgres[1685]: language sql vm-test-run-pgroonga> server # [ 140.290002] postgres[1685]: as $$ vm-test-run-pgroonga> server # [ 140.294343] postgres[1685]: select graphql.resolve( vm-test-run-pgroonga> server # [ 140.295338] postgres[1685]: query := query, vm-test-run-pgroonga> server # [ 140.295652] postgres[1685]: variables := coalesce(variables, '{}'), vm-test-run-pgroonga> server # [ 140.300925] postgres[1685]: "operationName" := "operationName", vm-test-run-pgroonga> server # [ 140.301908] postgres[1685]: extensions := extensions vm-test-run-pgroonga> server # [ 140.303036] postgres[1685]: ); vm-test-run-pgroonga> server # [ 140.303645] postgres[1685]: $$; vm-test-run-pgroonga> server # [ 140.309605] postgres[1685]: vm-test-run-pgroonga> server # [ 140.311043] postgres[1685]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-pgroonga> server # [ 140.317729] postgres[1685]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-pgroonga> server # [ 140.320021] postgres[1685]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-pgroonga> server # [ 140.325125] postgres[1685]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 140.325824] postgres[1685]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 140.326751] postgres[1685]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 140.330916] postgres[1685]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 140.335601] postgres[1685]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 140.344976] postgres[1685]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 140.346395] postgres[1685]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 140.347101] postgres[1685]: END IF; vm-test-run-pgroonga> server # [ 140.347572] postgres[1685]: vm-test-run-pgroonga> server # [ 140.347991] postgres[1685]: END; vm-test-run-pgroonga> server # [ 140.357937] postgres[1685]: $func$; vm-test-run-pgroonga> server # [ 140.358607] postgres[1685]: ::1 2026-10-22 21:27:02.703 UTC [1685] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-pgroonga> server # [ 140.367450] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1684]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 140.403540] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1684]: DROP EXTENSION vm-test-run-pgroonga> server # [ 142.310107] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1684]: DO vm-test-run-pgroonga> server # [ 142.340592] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20220713082019_pg_cron-pg_net-temp-perms-fix.sql vm-test-run-pgroonga> server # [ 143.638689] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1703]: DO vm-test-run-pgroonga> server # [ 144.334069] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1703]: DO vm-test-run-pgroonga> server # [ 144.394370] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221028101028_set_authenticator_timeout.sql vm-test-run-pgroonga> server # [ 144.630729] postgres[1717]: ::1 2026-10-22 21:27:06.984 UTC [1717] supabase_admin@postgres LOG: statement: alter role authenticator set statement_timeout = '8s'; vm-test-run-pgroonga> server # [ 144.665329] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1716]: ALTER ROLE vm-test-run-pgroonga> server # [ 144.684102] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221103090837_revoke_admin.sql vm-test-run-pgroonga> server # [ 145.099568] postgres[1721]: ::1 2026-10-22 21:27:07.436 UTC [1721] supabase_admin@postgres LOG: statement: revoke supabase_admin from authenticator; vm-test-run-pgroonga> server # [ 145.147643] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1718]: REVOKE ROLE vm-test-run-pgroonga> server # [ 145.179192] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20221207154255_create_pgsodium_and_vault.sql vm-test-run-pgroonga> server # [ 148.009991] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1727]: DO vm-test-run-pgroonga> server # [ 148.044853] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230201083204_grant_auth_roles_to_postgres.sql vm-test-run-pgroonga> server # [ 148.527178] postgres[1755]: ::1 2026-10-22 21:27:10.879 UTC [1755] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to postgres; vm-test-run-pgroonga> server # [ 148.570875] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1750]: GRANT ROLE vm-test-run-pgroonga> server # [ 148.603428] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230224042246_grant_extensions_perms_for_postgres.sql vm-test-run-pgroonga> server # [ 149.107010] postgres[1766]: ::1 2026-10-22 21:27:11.433 UTC [1766] supabase_admin@postgres LOG: statement: grant all privileges on all tables in schema extensions to postgres with grant option; vm-test-run-pgroonga> server # [ 149.164691] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1759]: GRANT vm-test-run-pgroonga> server # [ 149.178245] postgres[1766]: ::1 2026-10-22 21:27:11.510 UTC [1766] supabase_admin@postgres LOG: statement: grant all privileges on all routines in schema extensions to postgres with grant option; vm-test-run-pgroonga> server # [ 149.227096] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1759]: GRANT vm-test-run-pgroonga> server # [ 149.234399] postgres[1766]: ::1 2026-10-22 21:27:11.591 UTC [1766] supabase_admin@postgres LOG: statement: grant all privileges on all sequences in schema extensions to postgres with grant option; vm-test-run-pgroonga> server # [ 149.243689] postgres[1766]: ::1 2026-10-22 21:27:11.602 UTC [1766] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on tables to postgres with grant option; vm-test-run-pgroonga> server # [ 149.250063] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1759]: GRANT vm-test-run-pgroonga> server # [ 149.271203] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1759]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 149.301587] postgres[1766]: ::1 2026-10-22 21:27:11.634 UTC [1766] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on routines to postgres with grant option; vm-test-run-pgroonga> server # [ 149.315310] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1759]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 149.329096] postgres[1766]: ::1 2026-10-22 21:27:11.678 UTC [1766] supabase_admin@postgres LOG: statement: alter default privileges in schema extensions grant all on sequences to postgres with grant option; vm-test-run-pgroonga> server # [ 149.341218] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1759]: ALTER DEFAULT PRIVILEGES vm-test-run-pgroonga> server # [ 149.435427] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230306081037_grant_pg_monitor_to_postgres.sql vm-test-run-pgroonga> server # [ 149.891199] postgres[1779]: ::1 2026-10-22 21:27:12.227 UTC [1779] supabase_admin@postgres LOG: statement: grant pg_monitor to postgres; vm-test-run-pgroonga> server # [ 149.923030] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1774]: GRANT ROLE vm-test-run-pgroonga> server # [ 149.957659] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230327032006_grant_auth_roles_to_supabase_storage_admin.sql vm-test-run-pgroonga> server # [ 150.307894] postgres[1781]: ::1 2026-10-22 21:27:12.663 UTC [1781] supabase_admin@postgres LOG: statement: grant anon, authenticated, service_role to supabase_storage_admin; vm-test-run-pgroonga> server # [ 150.334261] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1780]: GRANT ROLE vm-test-run-pgroonga> server # [ 150.383870] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20230529180330_alter_api_roles_for_inherit.sql vm-test-run-pgroonga> server # [ 150.659500] postgres[1783]: ::1 2026-10-22 21:27:13.015 UTC [1783] supabase_admin@postgres LOG: statement: ALTER ROLE authenticated inherit; vm-test-run-pgroonga> server # [ 150.689098] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1782]: ALTER ROLE vm-test-run-pgroonga> server # [ 150.691309] postgres[1783]: ::1 2026-10-22 21:27:13.035 UTC [1783] supabase_admin@postgres LOG: statement: ALTER ROLE anon inherit; vm-test-run-pgroonga> server # [ 150.691900] postgres[1783]: ::1 2026-10-22 21:27:13.046 UTC [1783] supabase_admin@postgres LOG: statement: ALTER ROLE service_role inherit; vm-test-run-pgroonga> server # [ 150.694525] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1782]: ALTER ROLE vm-test-run-pgroonga> server # [ 150.698025] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1782]: ALTER ROLE vm-test-run-pgroonga> server # [ 150.723211] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1782]: DO vm-test-run-pgroonga> server # [ 150.779675] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231013070755_grant_authenticator_to_supabase_storage_admin.sql vm-test-run-pgroonga> server # [ 151.020337] postgres[1785]: ::1 2026-10-22 21:27:13.374 UTC [1785] supabase_admin@postgres LOG: statement: grant authenticator to supabase_storage_admin; vm-test-run-pgroonga> server # [ 151.053678] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1784]: GRANT ROLE vm-test-run-pgroonga> server # [ 151.058173] postgres[1785]: ::1 2026-10-22 21:27:13.411 UTC [1785] supabase_admin@postgres LOG: statement: revoke anon, authenticated, service_role from supabase_storage_admin; vm-test-run-pgroonga> server # [ 151.064040] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1784]: REVOKE ROLE vm-test-run-pgroonga> server # [ 151.119525] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231017062225_grant_pg_graphql_permissions_for_custom_roles.sql vm-test-run-pgroonga> server # [ 151.471950] postgres[1787]: ::1 2026-10-22 21:27:13.816 UTC [1787] supabase_admin@postgres LOG: statement: create or replace function extensions.grant_pg_graphql_access() vm-test-run-pgroonga> server # [ 151.490325] postgres[1787]: returns event_trigger vm-test-run-pgroonga> server # [ 151.517566] postgres[1787]: language plpgsql vm-test-run-pgroonga> server # [ 151.518900] postgres[1787]: AS $func$ vm-test-run-pgroonga> server # [ 151.519377] postgres[1787]: DECLARE vm-test-run-pgroonga> server # [ 151.519810] postgres[1787]: func_is_graphql_resolve bool; vm-test-run-pgroonga> server # [ 151.538009] postgres[1787]: BEGIN vm-test-run-pgroonga> server # [ 151.538526] postgres[1787]: func_is_graphql_resolve = ( vm-test-run-pgroonga> server # [ 151.540143] postgres[1787]: SELECT n.proname = 'resolve' vm-test-run-pgroonga> server # [ 151.551230] postgres[1787]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgroonga> server # [ 151.551824] postgres[1787]: LEFT JOIN pg_catalog.pg_proc AS n vm-test-run-pgroonga> server # [ 151.557116] postgres[1787]: ON ev.objid = n.oid vm-test-run-pgroonga> server # [ 151.559306] postgres[1787]: ); vm-test-run-pgroonga> server # [ 151.561218] postgres[1787]: vm-test-run-pgroonga> server # [ 151.561714] postgres[1787]: IF func_is_graphql_resolve vm-test-run-pgroonga> server # [ 151.562221] postgres[1787]: THEN vm-test-run-pgroonga> server # [ 151.562553] postgres[1787]: -- Update public wrapper to pass all arguments through to the pg_graphql resolve func vm-test-run-pgroonga> server # [ 151.563843] postgres[1787]: DROP FUNCTION IF EXISTS graphql_public.graphql; vm-test-run-pgroonga> server # [ 151.578570] postgres[1787]: create or replace function graphql_public.graphql( vm-test-run-pgroonga> server # [ 151.580156] postgres[1787]: "operationName" text default null, vm-test-run-pgroonga> server # [ 151.587229] postgres[1787]: query text default null, vm-test-run-pgroonga> server # [ 151.598538] postgres[1787]: variables jsonb default null, vm-test-run-pgroonga> server # [ 151.609120] postgres[1787]: extensions jsonb default null vm-test-run-pgroonga> server # [ 151.610368] postgres[1787]: ) vm-test-run-pgroonga> server # [ 151.611502] postgres[1787]: returns jsonb vm-test-run-pgroonga> server # [ 151.612310] postgres[1787]: language sql vm-test-run-pgroonga> server # [ 151.625435] postgres[1787]: as $$ vm-test-run-pgroonga> server # [ 151.626105] postgres[1787]: select graphql.resolve( vm-test-run-pgroonga> server # [ 151.627998] postgres[1787]: query := query, vm-test-run-pgroonga> server # [ 151.633879] postgres[1787]: variables := coalesce(variables, '{}'), vm-test-run-pgroonga> server # [ 151.641048] postgres[1787]: "operationName" := "operationName", vm-test-run-pgroonga> server # [ 151.643390] postgres[1787]: extensions := extensions vm-test-run-pgroonga> server # [ 151.649314] postgres[1787]: ); vm-test-run-pgroonga> server # [ 151.651306] postgres[1787]: $$; vm-test-run-pgroonga> server # [ 151.659115] postgres[1787]: vm-test-run-pgroonga> server # [ 151.660376] postgres[1787]: -- This hook executes when `graphql.resolve` is created. That is not necessarily the last vm-test-run-pgroonga> server # [ 151.660835] postgres[1787]: -- function in the extension so we need to grant permissions on existing entities AND vm-test-run-pgroonga> server # [ 151.665289] postgres[1787]: -- update default permissions to any others that are created after `graphql.resolve` vm-test-run-pgroonga> server # [ 151.667750] postgres[1787]: grant usage on schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 151.675697] postgres[1787]: grant select on all tables in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 151.678071] postgres[1787]: grant execute on all functions in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 151.688983] postgres[1787]: grant all on all sequences in schema graphql to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 151.689696] postgres[1787]: alter default privileges in schema graphql grant all on tables to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 151.690402] postgres[1787]: alter default privileges in schema graphql grant all on functions to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 151.691176] postgres[1787]: alter default privileges in schema graphql grant all on sequences to postgres, anon, authenticated, service_role; vm-test-run-pgroonga> server # [ 151.697250] postgres[1787]: vm-test-run-pgroonga> server # [ 151.698233] postgres[1787]: -- Allow postgres role to allow granting usage on graphql and graphql_public schemas to custom roles vm-test-run-pgroonga> server # [ 151.699401] postgres[1787]: grant usage on schema graphql_public to postgres with grant option; vm-test-run-pgroonga> server # [ 151.713981] postgres[1787]: grant usage on schema graphql to postgres with grant option; vm-test-run-pgroonga> server # [ 151.721024] postgres[1787]: END IF; vm-test-run-pgroonga> server # [ 151.722222] postgres[1787]: vm-test-run-pgroonga> server # [ 151.722691] postgres[1787]: END; vm-test-run-pgroonga> server # [ 151.730201] postgres[1787]: $func$; vm-test-run-pgroonga> server # [ 151.755864] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1786]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 151.763274] postgres[1787]: ::1 2026-10-22 21:27:14.114 UTC [1787] supabase_admin@postgres LOG: statement: drop extension if exists pg_graphql; vm-test-run-pgroonga> server # [ 151.849080] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1786]: DROP EXTENSION vm-test-run-pgroonga> server # [ 153.722662] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1786]: DO vm-test-run-pgroonga> server # [ 153.786634] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231020085357_revoke_writes_on_cron_job_from_postgres.sql vm-test-run-pgroonga> server # [ 154.172832] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1816]: DO vm-test-run-pgroonga> server # [ 154.207102] postgres[1817]: ::1 2026-10-22 21:27:16.526 UTC [1817] supabase_admin@postgres LOG: statement: CREATE OR REPLACE FUNCTION extensions.grant_pg_cron_access() RETURNS event_trigger vm-test-run-pgroonga> server # [ 154.223522] postgres[1817]: LANGUAGE plpgsql vm-test-run-pgroonga> server # [ 154.224127] postgres[1817]: AS $$ vm-test-run-pgroonga> server # [ 154.226814] postgres[1817]: BEGIN vm-test-run-pgroonga> server # [ 154.227119] postgres[1817]: IF EXISTS ( vm-test-run-pgroonga> server # [ 154.227805] postgres[1817]: SELECT vm-test-run-pgroonga> server # [ 154.228210] postgres[1817]: FROM pg_event_trigger_ddl_commands() AS ev vm-test-run-pgroonga> server # [ 154.228489] postgres[1817]: JOIN pg_extension AS ext vm-test-run-pgroonga> server # [ 154.231148] postgres[1817]: ON ev.objid = ext.oid vm-test-run-pgroonga> server # [ 154.232057] postgres[1817]: WHERE ext.extname = 'pg_cron' vm-test-run-pgroonga> server # [ 154.233702] postgres[1817]: ) vm-test-run-pgroonga> server # [ 154.235080] postgres[1817]: THEN vm-test-run-pgroonga> server # [ 154.235941] postgres[1817]: grant usage on schema cron to postgres with grant option; vm-test-run-pgroonga> server # [ 154.238677] postgres[1817]: vm-test-run-pgroonga> server # [ 154.239053] postgres[1817]: alter default privileges in schema cron grant all on tables to postgres with grant option; vm-test-run-pgroonga> server # [ 154.239391] postgres[1817]: alter default privileges in schema cron grant all on functions to postgres with grant option; vm-test-run-pgroonga> server # [ 154.239695] postgres[1817]: alter default privileges in schema cron grant all on sequences to postgres with grant option; vm-test-run-pgroonga> server # [ 154.239998] postgres[1817]: vm-test-run-pgroonga> server # [ 154.241135] postgres[1817]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgroonga> server # [ 154.241851] postgres[1817]: on sequences to postgres with grant option; vm-test-run-pgroonga> server # [ 154.245239] postgres[1817]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgroonga> server # [ 154.245767] postgres[1817]: on tables to postgres with grant option; vm-test-run-pgroonga> server # [ 154.246077] postgres[1817]: alter default privileges for user supabase_admin in schema cron grant all vm-test-run-pgroonga> server # [ 154.247298] postgres[1817]: on functions to postgres with grant option; vm-test-run-pgroonga> server # [ 154.247749] postgres[1817]: vm-test-run-pgroonga> server # [ 154.250366] postgres[1817]: grant all privileges on all tables in schema cron to postgres with grant option; vm-test-run-pgroonga> server # [ 154.254479] postgres[1817]: revoke all on table cron.job from postgres; vm-test-run-pgroonga> server # [ 154.255631] postgres[1817]: grant select on table cron.job to postgres with grant option; vm-test-run-pgroonga> server # [ 154.257032] postgres[1817]: END IF; vm-test-run-pgroonga> server # [ 154.257404] postgres[1817]: END; vm-test-run-pgroonga> server # [ 154.257667] postgres[1817]: $$; vm-test-run-pgroonga> server # [ 154.283541] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1816]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 154.285878] postgres[1817]: ::1 2026-10-22 21:27:16.644 UTC [1817] supabase_admin@postgres LOG: statement: drop event trigger if exists issue_pg_cron_access; vm-test-run-pgroonga> server # [ 154.326423] postgres[1817]: ::1 2026-10-22 21:27:16.674 UTC [1817] supabase_admin@postgres LOG: statement: alter function extensions.grant_pg_cron_access owner to supabase_admin; vm-test-run-pgroonga> server # [ 154.335015] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1816]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 154.335743] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1816]: ALTER FUNCTION vm-test-run-pgroonga> server # [ 154.343821] postgres[1817]: ::1 2026-10-22 21:27:16.696 UTC [1817] supabase_admin@postgres LOG: statement: CREATE EVENT TRIGGER issue_pg_cron_access ON ddl_command_end vm-test-run-pgroonga> server # [ 154.356978] postgres[1817]: WHEN TAG IN ('CREATE EXTENSION') vm-test-run-pgroonga> server # [ 154.359276] postgres[1817]: EXECUTE FUNCTION extensions.grant_pg_cron_access(); vm-test-run-pgroonga> server # [ 154.359990] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1816]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 154.406888] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20231130133139_set_lock_timeout_to_authenticator_role.sql vm-test-run-pgroonga> server # [ 154.833529] postgres[1824]: ::1 2026-10-22 21:27:17.182 UTC [1824] supabase_admin@postgres LOG: statement: ALTER ROLE authenticator set lock_timeout to '8s'; vm-test-run-pgroonga> server # [ 154.860540] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1818]: ALTER ROLE vm-test-run-pgroonga> server # [ 154.919993] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240124080435_alter_lo_export_lo_import_owner.sql vm-test-run-pgroonga> server # [ 155.335941] postgres[1836]: ::1 2026-10-22 21:27:17.675 UTC [1836] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_export owner to supabase_admin; vm-test-run-pgroonga> server # [ 155.388928] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1830]: ALTER FUNCTION vm-test-run-pgroonga> server # [ 155.391945] postgres[1836]: ::1 2026-10-22 21:27:17.738 UTC [1836] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text) owner to supabase_admin; vm-test-run-pgroonga> server # [ 155.404525] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1830]: ALTER FUNCTION vm-test-run-pgroonga> server # [ 155.407701] postgres[1836]: ::1 2026-10-22 21:27:17.763 UTC [1836] supabase_admin@postgres LOG: statement: alter function pg_catalog.lo_import(text, oid) owner to supabase_admin; vm-test-run-pgroonga> server # [ 155.437926] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1830]: ALTER FUNCTION vm-test-run-pgroonga> server # [ 155.497271] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20240606060239_grant_predefined_roles_to_postgres.sql vm-test-run-pgroonga> server # [ 155.895736] postgres[1847]: ::1 2026-10-22 21:27:18.243 UTC [1847] supabase_admin@postgres LOG: statement: grant pg_read_all_data, pg_signal_backend to postgres; vm-test-run-pgroonga> server # [ 155.941507] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1843]: GRANT ROLE vm-test-run-pgroonga> server # [ 155.985160] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241031003909_create_orioledb.sql vm-test-run-pgroonga> server # [ 157.086244] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1848]: DO vm-test-run-pgroonga> server # [ 157.131472] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20241215003910_backfill_pgmq_metadata.sql vm-test-run-pgroonga> server # [ 157.598516] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1850]: DO vm-test-run-pgroonga> server # [ 157.633892] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1850]: DO vm-test-run-pgroonga> server # [ 157.693928] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205060043_disable_log_statement_on_internal_roles.sql vm-test-run-pgroonga> server # [ 158.103267] postgres[1862]: ::1 2026-10-22 21:27:20.443 UTC [1862] supabase_admin@postgres LOG: statement: alter role supabase_admin set log_statement = none; vm-test-run-pgroonga> server # [ 158.127769] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1859]: ALTER ROLE vm-test-run-pgroonga> server # [ 158.133535] postgres[1862]: ::1 2026-10-22 21:27:20.482 UTC [1862] supabase_admin@postgres LOG: statement: alter role supabase_auth_admin set log_statement = none; vm-test-run-pgroonga> server # [ 158.144179] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1859]: ALTER ROLE vm-test-run-pgroonga> server # [ 158.149573] postgres[1862]: ::1 2026-10-22 21:27:20.502 UTC [1862] supabase_admin@postgres LOG: statement: alter role supabase_storage_admin set log_statement = none; vm-test-run-pgroonga> server # [ 158.153094] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1859]: ALTER ROLE vm-test-run-pgroonga> server # [ 158.249499] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250205144616_move_orioledb_to_extensions_schema.sql vm-test-run-pgroonga> server # [ 158.863395] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1867]: DO vm-test-run-pgroonga> server # [ 158.901766] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250218031949_pgsodium_mask_role.sql vm-test-run-pgroonga> server # [ 159.293375] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1879]: DO vm-test-run-pgroonga> server # [ 159.360815] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250220051611_pg_net_perms_fix.sql vm-test-run-pgroonga> server # [ 159.897812] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1882]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 159.914621] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1882]: DO vm-test-run-pgroonga> server # [ 159.963896] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250312095419_pgbouncer_ownership.sql vm-test-run-pgroonga> server # [ 160.373705] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1888]: ALTER FUNCTION vm-test-run-pgroonga> server # [ 160.375954] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1888]: GRANT vm-test-run-pgroonga> server # [ 160.419360] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402065937_alter_internal_event_triggers_owner_to_supabase_admin.sql vm-test-run-pgroonga> server # [ 160.757917] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1890]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 160.776664] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1890]: ALTER FUNCTION vm-test-run-pgroonga> server # [ 160.787068] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1890]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 160.843427] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250402093753_grant_subscription_to_postgres_16_and_above.sql vm-test-run-pgroonga> server # [ 161.399341] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1894]: DO vm-test-run-pgroonga> server # [ 161.486847] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250417190610_update_pgbouncer_get_auth.sql vm-test-run-pgroonga> server # [ 162.154476] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1909]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 162.157831] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1909]: GRANT vm-test-run-pgroonga> server # [ 162.243296] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250421084701_revoke_admin_roles_from_postgres.sql vm-test-run-pgroonga> server # [ 162.594908] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1920]: REVOKE ROLE vm-test-run-pgroonga> server # [ 162.642810] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1920]: DO vm-test-run-pgroonga> server # [ 162.659522] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1920]: DO vm-test-run-pgroonga> server # [ 162.668120] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1920]: REVOKE ROLE vm-test-run-pgroonga> server # [ 162.690819] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1920]: REVOKE vm-test-run-pgroonga> server # [ 162.720448] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1920]: DO vm-test-run-pgroonga> server # [ 162.771317] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250605172253_grant_with_admin_to_postgres_16_and_above.sql vm-test-run-pgroonga> server # [ 163.040699] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1922]: DO vm-test-run-pgroonga> server # [ 163.078996] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250623125453_tmp_grant_storage_tables_to_postgres_with_grant_option.sql vm-test-run-pgroonga> server # [ 163.376806] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1924]: DO vm-test-run-pgroonga> server # [ 163.413960] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250709135250_grant_storage_schema_to_postgres_with_grant_option.sql vm-test-run-pgroonga> server # [ 163.879590] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1926]: DO vm-test-run-pgroonga> server # [ 163.948029] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20250710151649_supabase_read_only_user_default_transaction_read_only.sql vm-test-run-pgroonga> server # [ 164.488868] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1932]: ALTER ROLE vm-test-run-pgroonga> server # [ 164.524931] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251001204436_predefined_role_grants.sql vm-test-run-pgroonga> server # [ 164.986176] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1942]: GRANT ROLE vm-test-run-pgroonga> server # [ 165.011538] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1942]: DO vm-test-run-pgroonga> server # [ 165.076979] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251105172723_grant_pg_reload_conf_to_postgres.sql vm-test-run-pgroonga> server # [ 165.531268] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1954]: GRANT vm-test-run-pgroonga> server # [ 165.558591] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20251121132723_correct_search_path_pgbouncer.sql vm-test-run-pgroonga> server # [ 166.023635] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1960]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 166.037518] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1960]: REVOKE vm-test-run-pgroonga> server # [ 166.061378] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1960]: REVOKE vm-test-run-pgroonga> server # [ 166.077001] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1960]: GRANT vm-test-run-pgroonga> server # [ 166.128708] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260211120934_supabase_privileged_role.sql vm-test-run-pgroonga> server # [ 166.539260] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1964]: DO vm-test-run-pgroonga> server # [ 166.573697] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260413000000_fix-authenticator-session-preload-libraries.sql vm-test-run-pgroonga> server # [ 166.865159] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1966]: ALTER ROLE vm-test-run-pgroonga> server # [ 166.882214] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000000_pg_graphql-off-by-default.sql vm-test-run-pgroonga> server # [ 167.262616] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1968]: DROP EXTENSION vm-test-run-pgroonga> server # [ 167.314714] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260421000001_rescope_pg_graphql_access_trigger.sql vm-test-run-pgroonga> server # [ 168.012412] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1970]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 168.035051] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1970]: DROP EVENT TRIGGER vm-test-run-pgroonga> server # [ 168.052044] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1970]: CREATE EVENT TRIGGER vm-test-run-pgroonga> server # [ 168.137218] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260730000000_revoke_trigger_on_cron_tables_from_postgres.sql vm-test-run-pgroonga> server # [ 168.819539] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1986]: DO vm-test-run-pgroonga> server # [ 168.842779] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1986]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 168.897608] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20260923021047_update_pgrst_watch_triggers.sql vm-test-run-pgroonga> server # [ 169.228100] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1998]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.238956] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1998]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.285796] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running migration: /nix/store/js0m5hf160s54dcv5rwafq9rrcgmr3bn-migrations-db/migrations/20261016000001_search_path_for_supa_functions.sql vm-test-run-pgroonga> server # [ 169.670696] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2000]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.698397] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2000]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.719366] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2000]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.747327] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2000]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.769103] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2000]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.786367] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2000]: CREATE FUNCTION vm-test-run-pgroonga> server # [ 169.865123] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Running postgresql schema... vm-test-run-pgroonga> server # [ 170.306369] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER DATABASE vm-test-run-pgroonga> server # [ 170.315844] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER DATABASE vm-test-run-pgroonga> server # [ 170.462442] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 170.621413] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 170.746965] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 170.862358] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 170.988624] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 171.134453] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 171.275876] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 171.408856] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 171.474492] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 171.494618] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[2006]: ALTER ROLE vm-test-run-pgroonga> server # [ 171.543596] vajsrmb87v5n6q7zw49mmp7q0dxq2gln-supabase-db-init[1434]: Database initialization complete vm-test-run-pgroonga> server # [ 171.599617] systemd[1]: Finished Supabase Database Initialization. vm-test-run-pgroonga> server # [ 171.615715] systemd[1]: Reached target Multi-User System. vm-test-run-pgroonga> server # [ 171.624397] systemd[1]: Startup finished in 20.232s (kernel) + 2min 31.361s (userspace) = 2min 51.594s. vm-test-run-pgroonga> server: (finished: waiting for unit supabase-db-init.service, in 173.31 seconds) vm-test-run-pgroonga> subtest: Check upgrade path with postgresql 15 vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgroonga;" vm-test-run-pgroonga> server # NOTICE: extension "pgroonga" does not exist, skipping vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgroonga;" , in 0.55 seconds) vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgroonga WITH VERSION '3.0.7' CASCADE;" vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgroonga WITH VERSION '3.0.7' CASCADE;" , in 8.79 seconds) vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgroonga';" vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgroonga';" , in 0.63 seconds) vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgroonga UPDATE TO '3.2.5';" vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "ALTER EXTENSION pgroonga UPDATE TO '3.2.5';" , in 2.36 seconds) vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgroonga';" vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgroonga';" , in 0.61 seconds) vm-test-run-pgroonga> (finished: subtest: Check upgrade path with postgresql 15, in 12.95 seconds) vm-test-run-pgroonga> subtest: Check the install of the last version of the extension vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgroonga;" vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "DROP EXTENSION IF EXISTS pgroonga;" , in 1.78 seconds) vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgroonga WITH VERSION '3.2.5' CASCADE;" vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "CREATE EXTENSION pgroonga WITH VERSION '3.2.5' CASCADE;" , in 5.98 seconds) vm-test-run-pgroonga> server: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgroonga';" vm-test-run-pgroonga> server # [ 193.563636] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 vm-test-run-pgroonga> server: (finished: must succeed: psql -U supabase_admin -d postgres -t -A -F"," -c "SELECT extversion FROM pg_extension WHERE extname = 'pgroonga';" , in 0.73 seconds) vm-test-run-pgroonga> (finished: subtest: Check the install of the last version of the extension, in 8.49 seconds) vm-test-run-pgroonga> subtest: switch to postgresql 17 vm-test-run-pgroonga> server # Checking switch inhibitors... done vm-test-run-pgroonga> server # [ 203.267555] nixos[2212]: switching to system configuration /nix/store/5wj4nd82bp2sk1j9r0s3mn4qk91dski9-nixos-system-server-test vm-test-run-pgroonga> server # stopping the following units: postgresql.service, supabase-db-init.service vm-test-run-pgroonga> server # [ 203.319289] systemd[1]: Stopped target Local File Systems. vm-test-run-pgroonga> server # [ 203.348829] systemd[1]: supabase-db-init.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 203.359004] systemd[1]: Stopped Supabase Database Initialization. vm-test-run-pgroonga> server # [ 203.387334] postgres[1380]: 2026-10-22 21:28:05.715 UTC [1380] LOG: received fast shutdown request vm-test-run-pgroonga> server # [ 203.399912] systemd[1]: supabase-db-init.service: Consumed 8.759s CPU time, 3.5M memory peak, 91K incoming IP traffic, 131.1K outgoing IP traffic. vm-test-run-pgroonga> server # [ 203.413007] postgres[1380]: 2026-10-22 21:28:05.741 UTC [1380] LOG: aborting any active transactions vm-test-run-pgroonga> server # [ 203.414927] systemd[1]: Stopping PostgreSQL Database Server... vm-test-run-pgroonga> server # [ 203.426907] postgres[1428]: 2026-10-22 21:28:05.746 UTC [1428] LOG: pg_cron scheduler shutting down vm-test-run-pgroonga> server # [ 203.430956] systemd[1]: Stopped target Remote File Systems. vm-test-run-pgroonga> server # [ 203.441555] postgres[1423]: 2026-10-22 21:28:05.787 UTC [1423] FATAL: terminating background worker "TimescaleDB Background Worker Launcher" due to administrator command vm-test-run-pgroonga> server # [ 203.650485] postgres[1380]: 2026-10-22 21:28:06.010 UTC [1380] LOG: background worker "logical replication launcher" (PID 1430) exited with exit code 1 vm-test-run-pgroonga> server # [ 203.656974] postgres[1380]: 2026-10-22 21:28:06.016 UTC [1380] LOG: background worker "TimescaleDB Background Worker Launcher" (PID 1423) exited with exit code 1 vm-test-run-pgroonga> server # [ 203.686224] postgres[1380]: 2026-10-22 21:28:06.045 UTC [1380] LOG: background worker "pg_cron launcher" (PID 1428) exited with exit code 1 vm-test-run-pgroonga> server # [ 203.694387] postgres[1380]: 2026-10-22 21:28:06.052 UTC [1380] LOG: background worker "pg_net 0.20.4 worker" (PID 1459) exited with exit code 1 vm-test-run-pgroonga> server # [ 203.713961] postgres[1412]: 2026-10-22 21:28:06.062 UTC [1412] LOG: shutting down vm-test-run-pgroonga> server # [ 203.742545] postgres[1412]: 2026-10-22 21:28:06.099 UTC [1412] LOG: checkpoint starting: shutdown immediate vm-test-run-pgroonga> server # [ 204.228854] postgres[1412]: 2026-10-22 21:28:06.587 UTC [1412] LOG: checkpoint complete: wrote 411 buffers (2.5%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.159 s, sync=0.237 s, total=0.525 s; sync files=157, longest=0.016 s, average=0.002 s; distance=4871 kB, estimate=4871 kB vm-test-run-pgroonga> server # [ 204.398419] postgres[1380]: 2026-10-22 21:28:06.751 UTC [1380] LOG: database system is shut down vm-test-run-pgroonga> server # [ 204.533086] systemd[1]: postgresql.service: Deactivated successfully. vm-test-run-pgroonga> server # activating the configuration... vm-test-run-pgroonga> server # [ 204.535616] systemd[1]: Stopped PostgreSQL Database Server. vm-test-run-pgroonga> server # [ 204.554190] systemd[1]: postgresql.service: Consumed 49.527s CPU time, 156M memory peak, 58.8M written to disk, 135.4K incoming IP traffic, 95.5K outgoing IP traffic. vm-test-run-pgroonga> server # setting up /etc... vm-test-run-pgroonga> server # [ 209.235477] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. vm-test-run-pgroonga> server # [ 209.373370] systemd[1]: Starting Virtual Console Setup... vm-test-run-pgroonga> server # [ 209.691051] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 209.701249] systemd[1]: Stopped Virtual Console Setup. vm-test-run-pgroonga> server # [ 209.766539] systemd[1]: Starting Virtual Console Setup... vm-test-run-pgroonga> server # [ 214.717005] systemd[1]: Reload requested from client PID 2212 ('.switch-to-conf') (unit backdoor.service)... vm-test-run-pgroonga> server # [ 214.727541] systemd[1]: Reloading... vm-test-run-pgroonga> server # [ 221.532972] systemd[1]: Reloading finished in 6777 ms. vm-test-run-pgroonga> server # [ 221.790729] systemd[1]: Finished Virtual Console Setup. vm-test-run-pgroonga> server # restarting sysinit-reactivation.target vm-test-run-pgroonga> server # [ 222.026809] systemd[1]: Starting Re-setup tmpfiles on a system that is already running.... vm-test-run-pgroonga> server # [ 223.566683] systemd[1]: Finished Re-setup tmpfiles on a system that is already running.. vm-test-run-pgroonga> server # [ 223.571718] systemd[1]: Reached target Reactivate sysinit units. vm-test-run-pgroonga> server # reloading the following units: dbus.service vm-test-run-pgroonga> server # [ 223.626878] systemd[1]: Reloading D-Bus System Message Bus... vm-test-run-pgroonga> server # [ 224.210166] dbus-daemon[634]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-pgroonga> server # [ 224.476480] dbus-daemon[634]: [system] Reloaded configuration vm-test-run-pgroonga> server # [ 224.487154] dbus-send[2417]: method return time=1792704506.844106 sender=org.freedesktop.DBus -> destination=:1.5 serial=3 reply_serial=2 vm-test-run-pgroonga> server # [ 224.719949] dbus-daemon[634]: Unknown username "systemd-timesync" in message bus configuration file vm-test-run-pgroonga> server # [ 224.895408] dbus-daemon[634]: [system] Reloaded configuration vm-test-run-pgroonga> server # [ 224.910678] systemd[1]: Reloaded D-Bus System Message Bus. vm-test-run-pgroonga> server # starting the following units: postgresql.service, supabase-db-init.service vm-test-run-pgroonga> server # [ 225.008861] systemd[1]: File System Check on Root Device was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). vm-test-run-pgroonga> server # [ 225.011522] systemd[1]: Reached target Local File Systems. vm-test-run-pgroonga> server # [ 225.044592] systemd-logind[653]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) vm-test-run-pgroonga> server # [ 225.071215] systemd[1]: Reached target Remote File Systems. vm-test-run-pgroonga> server # [ 225.118809] systemd[1]: Starting PostgreSQL Major Version Migration... vm-test-run-pgroonga> server # [ 225.194018] systemd[1]: Starting linger-users.service... vm-test-run-pgroonga> server # [ 225.220932] systemd[1]: Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgroonga> server # [ 225.455730] systemd[1]: Load Kernel Module efi_pstore was skipped because of an unmet condition check (ConditionKernelModuleLoaded=!efi_pstore). vm-test-run-pgroonga> server # [ 225.501452] systemd[1]: Starting Create SUID/SGID Wrappers... vm-test-run-pgroonga> server # [ 225.504135] systemd[1]: Update Boot Loader Random Seed was skipped because no trigger condition checks were met. vm-test-run-pgroonga> server # [ 225.530890] 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). vm-test-run-pgroonga> server # [ 225.544751] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). vm-test-run-pgroonga> server # [ 225.546878] systemd[1]: Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgroonga> server # [ 225.547594] systemd[1]: TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). vm-test-run-pgroonga> server # [ 225.619280] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2433]: Starting pg_upgrade from 15 to 17 vm-test-run-pgroonga> server # [ 225.718548] systemd[1]: linger-users.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 225.734838] systemd[1]: Finished linger-users.service. vm-test-run-pgroonga> server # [ 226.817968] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: The files belonging to this database system will be owned by user "postgres". vm-test-run-pgroonga> server # [ 226.819895] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: This user must also own the server process. vm-test-run-pgroonga> server # [ 226.893308] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: The database cluster will be initialized with locale "en_US.UTF-8". vm-test-run-pgroonga> server # [ 226.894417] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: The default database encoding has accordingly been set to "UTF8". vm-test-run-pgroonga> server # [ 226.895462] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: The default text search configuration will be set to "english". vm-test-run-pgroonga> server # [ 226.899270] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: Data page checksums are enabled. vm-test-run-pgroonga> server # [ 226.909548] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: fixing permissions on existing directory /var/lib/postgresql/data-17 ... ok vm-test-run-pgroonga> server # [ 226.911403] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: creating subdirectories ... ok vm-test-run-pgroonga> server # [ 226.913876] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: selecting dynamic shared memory implementation ... posix vm-test-run-pgroonga> server # [ 227.723030] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: selecting default "max_connections" ... 100 vm-test-run-pgroonga> server # [ 228.337720] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: selecting default "shared_buffers" ... 128MB vm-test-run-pgroonga> server # [ 230.508047] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. vm-test-run-pgroonga> server # [ 230.521327] systemd[1]: Finished Create SUID/SGID Wrappers. vm-test-run-pgroonga> server # [ 230.530325] systemd[1]: suid-sgid-wrappers.service: Consumed 2.422s CPU time, 3.4M memory peak. vm-test-run-pgroonga> server # [ 235.921122] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: selecting default time zone ... UTC vm-test-run-pgroonga> server # [ 235.953498] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: creating configuration files ... ok vm-test-run-pgroonga> server # [ 238.035221] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: running bootstrap script ... ok vm-test-run-pgroonga> server # [ 243.389848] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: performing post-bootstrap initialization ... ok vm-test-run-pgroonga> server # [ 244.812249] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: syncing data to disk ... ok vm-test-run-pgroonga> server # [ 244.814587] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: initdb: warning: enabling "trust" authentication for local connections vm-test-run-pgroonga> server # [ 244.816792] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: 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. vm-test-run-pgroonga> server # [ 244.818920] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: Success. You can now start the database server using: vm-test-run-pgroonga> server # [ 244.820080] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2439]: /nix/store/2b08ap06lbcgyk3pnh18zsw06n7rp0vb-postgresql-and-plugins-17.11/bin/pg_ctl -D /var/lib/postgresql/data-17 -l logfile start vm-test-run-pgroonga> server # [ 249.008421] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Performing Consistency Checks vm-test-run-pgroonga> server # [ 249.095855] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: ----------------------------- vm-test-run-pgroonga> server # [ 249.118434] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Checking cluster versions ok vm-test-run-pgroonga> server # [ 252.351387] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Checking database user is the install user ok vm-test-run-pgroonga> server # [ 252.427140] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Checking database connection settings ok vm-test-run-pgroonga> server # [ 252.530933] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Checking for prepared transactions ok vm-test-run-pgroonga> server # [ 252.539202] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Checking for contrib/isn with bigint-passing mismatch ok vm-test-run-pgroonga> server # [ 253.220834] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Checking data type usage ok vm-test-run-pgroonga> server # [ 253.463766] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Checking for not-null constraint inconsistencies ok vm-test-run-pgroonga> server # [ 254.173022] zg842yb6kxar74345ia195gikgp6cajq-postgresql-migrate[2528]: Creating dump of global objects ok