vm-test-run-mobilizon
checks.aarch64-linux.nixosTest
· build #18
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 server,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10server: waiting for unit mobilizon.service11server: waiting for the VM to finish booting12server: starting vm13server # Disk image does not exist, creating the virtualisation disk image...14server: QEMU running (pid 12)15server # Formatting '/build/vm-state-server/tmp.Fq4zwAjkXQ', fmt=raw size=107374182416server # mke2fs 1.47.3 (8-Jul-2025)17server # Discarding device blocks: 0/262144 done18server # Creating filesystem with 262144 4k blocks and 65536 inodes19server # Filesystem UUID: 0a4227d5-a7b8-4c9d-8b71-221b89298b1920server # Superblock backups stored on blocks:21server # 32768, 98304, 163840, 22937622server # 23server # Allocating group tables: 0/8 done24server # Writing inode tables: 0/8 done25server # Creating journal (8192 blocks): done26server # Writing superblocks and filesystem accounting information: 0/8 done27server # 28server # Virtualisation disk image created.29server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory30server # qemu-system-aarch64: failed to initialize kvm: No such file or directory31server # qemu-system-aarch64: falling back to tcg32server # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]33server # [ 0.000000] Linux version 6.18.35 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP Tue Jun 9 10:28:53 UTC 202634server # [ 0.000000] KASLR enabled35server # [ 0.000000] random: crng init done36server # [ 0.000000] Machine model: linux,dummy-virt37server # [ 0.000000] efi: UEFI not found.38server # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT39server # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]40server # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf]41server # [ 0.000000] Zone ranges:42server # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]43server # [ 0.000000] DMA32 empty44server # [ 0.000000] Normal empty45server # [ 0.000000] Device empty46server # [ 0.000000] Movable zone start for each node47server # [ 0.000000] Early memory node ranges48server # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]49server # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]50server # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000051server # [ 0.000000] psci: probing for conduit method from DT.52server # [ 0.000000] psci: PSCIv1.1 detected in firmware.53server # [ 0.000000] psci: Using standard PSCI v0.2 function IDs54server # [ 0.000000] psci: Trusted OS migration not required55server # [ 0.000000] psci: SMC Calling Convention v1.056server # [ 0.000000] percpu: Embedded 76 pages/cpu s185688 r8192 d117416 u31129657server # [ 0.000000] Detected PIPT I-cache on CPU058server # [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)59server # [ 0.000000] CPU features: detected: GICv3 CPU interface60server # [ 0.000000] CPU features: detected: HCRX_EL2 register61server # [ 0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE)62server # [ 0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)63server # [ 0.000000] CPU features: detected: Spectre-v464server # [ 0.000000] alternatives: applying boot alternatives65server # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/h8mjayp97swrv476s970jm93miz3jdsm-nixos-system-server-test/init regInfo=/nix/store/6vgby84q736fzm3cydn99fvna4x2yb0p-closure-info/registration console=ttyAMA0,115200n8 console=tty066server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/6vgby84q736fzm3cydn99fvna4x2yb0p-closure-info/registration", will be passed to user space.67server # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes68server # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)69server # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)70server # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB71server # [ 0.000000] software IO TLB: area num 1.72server # [ 0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB)73server # [ 0.000000] Fallback order for Node 0: 074server # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214475server # [ 0.000000] Policy zone: DMA76server # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off77server # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=178server # [ 0.000000] allocated 2097152 bytes of page_ext79server # [ 0.000000] ftrace: allocating 74729 entries in 292 pages80server # [ 0.000000] ftrace: allocated 292 pages with 3 groups81server # [ 0.000000] rcu: Hierarchical RCU implementation.82server # [ 0.000000] rcu: RCU event tracing is enabled.83server # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.84server # [ 0.000000] Rude variant of Tasks RCU enabled.85server # [ 0.000000] Tracing variant of Tasks RCU enabled.86server # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.87server # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=188server # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.89server # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.90server # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 091server # [ 0.000000] GICv3: 256 SPIs implemented92server # [ 0.000000] GICv3: 0 Extended SPIs implemented93server # [ 0.000000] Root IRQ handler: gic_handle_irq94server # [ 0.000000] GICv3: GICv3 features: 16 PPIs95server # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=096server # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a000097server # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]98server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)99server # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)100server # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000101server # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000102server # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.103server # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).104server # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns105server # [ 0.000093] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns106server # [ 0.006576] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)107server # [ 0.037105] Console: colour dummy device 80x25108server # [ 0.041410] printk: legacy console [tty0] enabled109server # [ 0.056200] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)110server # [ 0.057860] pid_max: default: 32768 minimum: 301111server # [ 0.065944] LSM: initializing lsm=capability,landlock,yama,bpf,ima112server # [ 0.069449] landlock: Up and running.113server # [ 0.069623] Yama: becoming mindful.114server # [ 0.072292] LSM support for eBPF active115server # [ 0.079662] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116server # [ 0.079970] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)117server # [ 0.160296] cacheinfo: Unable to detect cache hierarchy for CPU 0118server # [ 0.195790] rcu: Hierarchical SRCU implementation.119server # [ 0.195999] rcu: Max phase no-delay instances is 1000.120server # [ 0.216504] fsl-mc MSI: its@8080000 domain created121server # [ 0.221208] EFI services will not be available.122server # [ 0.224648] smp: Bringing up secondary CPUs ...123server # [ 0.226375] smp: Brought up 1 node, 1 CPU124server # [ 0.226537] SMP: Total of 1 processors activated.125server # [ 0.226642] CPU: All CPU(s) started at EL1126server # [ 0.227397] CPU features: detected: Branch Target Identification127server # [ 0.227613] CPU features: detected: 32-bit EL0 Support128server # [ 0.227699] CPU features: detected: 32-bit EL1 Support129server # [ 0.228740] CPU features: detected: ARMv8.4 Translation Table Level130server # [ 0.228852] CPU features: detected: Instruction cache invalidation not required for I/D coherence131server # [ 0.228985] CPU features: detected: Data cache clean to the PoU not required for I/D coherence132server # [ 0.229165] CPU features: detected: Common not Private translations133server # [ 0.229238] CPU features: detected: CRC32 instructions134server # [ 0.229294] CPU features: detected: Data cache clean to Point of Deep Persistence135server # [ 0.229366] CPU features: detected: Data cache clean to Point of Persistence136server # [ 0.229506] CPU features: detected: Data independent timing control (DIT)137server # [ 0.229574] CPU features: detected: E0PD138server # [ 0.229625] CPU features: detected: Enhanced Counter Virtualization139server # [ 0.229685] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)140server # [ 0.229748] CPU features: detected: Enhanced Privileged Access Never141server # [ 0.229808] CPU features: detected: Enhanced Virtualization Traps142server # [ 0.229886] CPU features: detected: Fine Grained Traps143server # [ 0.229968] CPU features: detected: Guarded Control Stack (GCS)144server # [ 0.230125] CPU features: detected: Generic authentication (IMP DEF algorithm)145server # [ 0.230333] CPU features: detected: RCpc load-acquire (LDAPR)146server # [ 0.230516] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)147server # [ 0.230598] CPU features: detected: LSE atomic instructions148server # [ 0.230656] CPU features: detected: Memory Copy and Memory Set instructions149server # [ 0.230720] CPU features: detected: Privileged Access Never150server # [ 0.230841] CPU features: detected: PMUv3151server # [ 0.230901] CPU features: detected: SCTLR2152server # [ 0.230952] CPU features: detected: RAS Extension Support153server # [ 0.231059] CPU features: detected: RASv1p1 Extension Support154server # [ 0.231118] CPU features: detected: Random Number Generator155server # [ 0.231175] CPU features: detected: Speculation barrier (SB)156server # [ 0.231232] CPU features: detected: Stage-2 Force Write-Back157server # [ 0.231290] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality158server # [ 0.231360] CPU features: detected: TLB range maintenance instructions159server # [ 0.231420] CPU features: detected: WFx with timeout160server # [ 0.231654] CPU features: detected: Scalable Matrix Extension161server # [ 0.231724] CPU features: detected: FA64162server # [ 0.231775] CPU features: detected: SME2163server # [ 0.231825] CPU features: detected: Speculative Store Bypassing Safe (SSBS)164server # [ 0.231890] CPU features: detected: Scalable Vector Extension165server # [ 0.259340] alternatives: applying system-wide alternatives166server # [ 0.288843] CPU features: detected: Hardware dirty bit management on CPU0167server # [ 0.290527] SVE: maximum available vector length 256 bytes per vector168server # [ 0.290723] SVE: default vector length 64 bytes per vector169server # [ 0.293392] SME: minimum available vector length 16 bytes per vector170server # [ 0.293561] SME: maximum available vector length 256 bytes per vector171server # [ 0.293648] SME: default vector length 32 bytes per vector172server # [ 0.328996] Memory: 891080K/1048576K available (24576K kernel code, 7086K rwdata, 26312K rodata, 4864K init, 1102K bss, 116268K reserved, 32768K cma-reserved)173server # [ 0.377440] devtmpfs: initialized174server # [ 0.441744] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns175server # [ 0.444531] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)176server # [ 0.445272] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).177server # [ 0.452769] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL178server # [ 0.452993] 0 pages in range for non-PLT usage179server # [ 0.453113] 508240 pages in range for PLT usage180server # [ 0.460120] pinctrl core: initialized pinctrl subsystem181server # [ 0.488137] DMI not present or invalid.182server # [ 0.526797] NET: Registered PF_NETLINK/PF_ROUTE protocol family183server # [ 0.555279] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations184server # [ 0.558885] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations185server # [ 0.563005] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations186server # [ 0.563946] audit: initializing netlink subsys (disabled)187server # [ 0.568515] audit: type=2000 audit(0.472:1): state=initialized audit_enabled=0 res=1188server # [ 0.586077] thermal_sys: Registered thermal governor 'fair_share'189server # [ 0.586233] thermal_sys: Registered thermal governor 'bang_bang'190server # [ 0.586359] thermal_sys: Registered thermal governor 'step_wise'191server # [ 0.586425] thermal_sys: Registered thermal governor 'user_space'192server # [ 0.586511] thermal_sys: Registered thermal governor 'power_allocator'193server # [ 0.587671] cpuidle: using governor ladder194server # [ 0.588106] cpuidle: using governor menu195server # [ 0.593132] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.196server # [ 0.594329] ASID allocator initialised with 65536 entries197server # [ 0.616169] Serial: AMBA PL011 UART driver198server # [ 0.761051] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1199server # [ 0.766405] printk: console [ttyAMA0] enabled200server # [ 0.947479] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages201server # [ 0.947782] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page202server # [ 0.947958] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages203server # [ 0.948053] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page204server # [ 0.948145] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages205server # [ 0.948231] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page206server # [ 0.948368] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages207server # [ 0.948618] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page208server # [ 1.003169] fbcon: Taking over console209server # [ 1.008304] ACPI: Interpreter disabled.210server # [ 1.046161] iommu: Default domain type: Translated211server # [ 1.046462] iommu: DMA domain TLB invalidation policy: strict mode212server # [ 1.063580] SCSI subsystem initialized213server # [ 1.070678] usbcore: registered new interface driver usbfs214server # [ 1.076767] usbcore: registered new interface driver hub215server # [ 1.078174] usbcore: registered new device driver usb216server # [ 1.090751] pps_core: LinuxPPS API ver. 1 registered217server # [ 1.091016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>218server # [ 1.101336] PTP clock support registered219server # [ 1.102701] EDAC MC: Ver: 3.0.0220server # [ 1.110736] scmi_core: SCMI protocol bus registered221server # [ 1.125748] FPGA manager framework222server # [ 1.178839] vgaarb: loaded223server # [ 1.207998] clocksource: Switched to clocksource arch_sys_counter224server # [ 1.327617] VFS: Disk quotas dquot_6.6.0225server # [ 1.328730] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)226server # [ 1.333663] netfs: FS-Cache loaded227server # [ 1.341400] pnp: PnP ACPI: disabled228server # [ 1.412889] NET: Registered PF_INET protocol family229server # [ 1.416191] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)230server # [ 1.671728] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)231server # [ 1.672233] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)232server # [ 1.673802] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)233server # [ 1.675336] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)234server # [ 1.675842] TCP: Hash tables configured (established 8192 bind 8192)235server # [ 1.680226] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)236server # [ 1.681766] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)237server # [ 1.683353] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)238server # [ 1.687699] NET: Registered PF_UNIX/PF_LOCAL protocol family239server # [ 1.689419] NET: Registered PF_XDP protocol family240server # [ 1.691394] PCI: CLS 0 bytes, default 64241server # [ 1.700760] Trying to unpack rootfs image as initramfs...242server # [ 1.710119] kvm [1]: HYP mode not available243server # [ 2.378382] Initialise system trusted keyrings244server # [ 2.385915] workingset: timestamp_bits=42 max_order=18 bucket_order=0245server # [ 2.417079] squashfs: version 4.0 (2009/01/31) Phillip Lougher246server # [ 2.423919] 9p: Installing v9fs 9p2000 file system support247server # [ 2.688386] Key type asymmetric registered248server # [ 2.689385] Asymmetric key parser 'x509' registered249server # [ 2.691346] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)250server # [ 2.696970] io scheduler mq-deadline registered251server # [ 2.697320] io scheduler kyber registered252server # [ 2.867830] pl061_gpio 9030000.pl061: PL061 GPIO chip registered253server # [ 2.895597] ledtrig-cpu: registered to indicate activity on CPUs254server # [ 2.916632] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:255server # [ 2.919015] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000256server # [ 2.923568] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000257server # [ 2.924758] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000258server # [ 2.931635] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits259server # [ 2.934233] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]260server # [ 2.944795] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00261server # [ 2.946879] pci_bus 0000:00: root bus resource [bus 00-ff]262server # [ 2.947854] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]263server # [ 2.948731] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]264server # [ 2.949523] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]265server # [ 2.958202] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint266server # [ 2.974764] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint267server # [ 2.975694] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]268server # [ 2.975956] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]269server # [ 2.976126] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270server # [ 2.976370] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]271server # [ 2.985179] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint272server # [ 2.985584] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]273server # [ 2.985731] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]274server # [ 2.985848] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275server # [ 2.989591] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint276server # [ 2.989905] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]277server # [ 2.990021] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]278server # [ 2.990133] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279server # [ 2.997567] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint280server # [ 2.997894] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]281server # [ 2.998018] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]282server # [ 2.998127] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]283server # [ 3.004742] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint284server # [ 3.005112] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]285server # [ 3.005274] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]286server # [ 3.005390] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287server # [ 3.012916] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint288server # [ 3.013291] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]289server # [ 3.013417] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]290server # [ 3.013755] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]291server # [ 3.017844] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint292server # [ 3.018180] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]293server # [ 3.018304] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]294server # [ 3.018418] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]295server # [ 3.018581] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]296server # [ 3.024150] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint297server # [ 3.024622] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]298server # [ 3.024765] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]299server # [ 3.032100] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint300server # [ 3.032432] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]301server # [ 3.032594] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302server # [ 3.036153] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint303server # [ 3.036459] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]304server # [ 3.040509] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint305server # [ 3.040956] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]306server # [ 3.041108] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]307server # [ 3.041226] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]308server # [ 3.048142] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint309server # [ 3.048466] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]310server # [ 3.048619] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]311server # [ 3.048730] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]312server # [ 3.061884] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned313server # [ 3.062822] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned314server # [ 3.063172] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned315server # [ 3.063670] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned316server # [ 3.063935] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned317server # [ 3.064106] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned318server # [ 3.064258] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned319server # [ 3.064403] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned320server # [ 3.064576] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned321server # [ 3.064742] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned322server # [ 3.064892] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned323server # [ 3.065080] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned324server # [ 3.065309] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned325server # [ 3.065523] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned326server # [ 3.065708] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned327server # [ 3.065872] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned328server # [ 3.066006] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned329server # [ 3.066137] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned330server # [ 3.066262] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned331server # [ 3.066386] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned332server # [ 3.066557] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned333server # [ 3.066877] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned334server # [ 3.067045] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned335server # [ 3.067175] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned336server # [ 3.067297] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned337server # [ 3.067451] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned338server # [ 3.067654] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned339server # [ 3.067788] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned340server # [ 3.067908] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned341server # [ 3.068019] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned342server # [ 3.068135] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned343server # [ 3.068252] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned344server # [ 3.068363] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned345server # [ 3.068495] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned346server # [ 3.069221] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]347server # [ 3.069425] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]348server # [ 3.069599] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]349server # [ 3.109931] pci 0000:00:0a.0: enabling device (0000 -> 0002)350server # [ 3.119689] pci 0000:00:0a.0: quirk_usb_early_handoff+0x0/0xa58 took 10171 usecs351server # [ 3.488265] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)352server # [ 3.505990] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)353server # [ 3.513220] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)354server # [ 3.522619] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)355server # [ 3.529212] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)356server # [ 3.537566] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)357server # [ 3.553897] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)358server # [ 3.561198] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)359server # [ 3.578498] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)360server # [ 3.585651] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)361server # [ 3.600718] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)362server # [ 3.703733] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled363server # [ 3.762524] msm_serial: driver initialized364server # [ 3.769221] SuperH (H)SCI(F) driver initialized365server # [ 3.776152] STM32 USART driver initialized366server # [ 4.043539] loop: module loaded367server # [ 4.045449] virtio_blk virtio5: 1/0/0 default/read/poll queues368server # [ 4.064602] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)369server # [ 4.145330] megasas: 07.734.00.00-rc1370server # [ 4.173458] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]371server # [ 4.184135] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000372server # [ 4.188516] Intel/Sharp Extended Query Table at 0x0031373server # [ 4.193766] Using buffer write method374server # [ 4.200212] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]375server # [ 4.205009] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000376server # [ 4.211534] Intel/Sharp Extended Query Table at 0x0031377server # [ 4.214226] Using buffer write method378server # [ 4.219416] Concatenating MTD devices:379server # [ 4.219650] (0): "0.flash"380server # [ 4.219764] (1): "0.flash"381server # [ 4.219843] into device "0.flash"382server # [ 5.602398] Freeing initrd memory: 25352K383server # [ 5.858318] tun: Universal TUN/TAP device driver, 1.6384server # [ 5.891696] thunder_xcv, ver 1.0385server # [ 5.892796] thunder_bgx, ver 1.0386server # [ 5.893810] nicpf, ver 1.0387server # [ 5.904251] e1000: Intel(R) PRO/1000 Network Driver388server # [ 5.904582] e1000: Copyright (c) 1999-2006 Intel Corporation.389server # [ 5.906164] e1000e: Intel(R) PRO/1000 Network Driver390server # [ 5.906364] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.391server # [ 5.908367] igb: Intel(R) Gigabit Ethernet Network Driver392server # [ 5.908588] igb: Copyright (c) 2007-2014 Intel Corporation.393server # [ 5.910229] igbvf: Intel(R) Gigabit Virtual Function Network Driver394server # [ 5.910424] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.395server # [ 5.914459] sky2: driver version 1.30396server # [ 5.937824] ehci-pci 0000:00:0a.0: EHCI Host Controller397server # [ 5.943728] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1398server # [ 5.953217] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000399server # [ 5.956863] usbcore: registered new interface driver usb-storage400server # [ 5.958964] usbcore: registered new interface driver usbserial_generic401server # [ 5.960256] usbserial: USB Serial support registered for generic402server # [ 5.967874] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00403server # [ 5.976525] hv_vmbus: registering driver hyperv_keyboard404server # [ 6.001537] hub 1-0:1.0: USB hub found405server # [ 6.006599] hub 1-0:1.0: 6 ports detected406server # [ 6.021361] rtc-pl031 9010000.pl031: registered as rtc0407server # [ 6.028378] rtc-pl031 9010000.pl031: setting system clock to 2026-10-21T13:41:19 UTC (1792590079)408server # [ 6.037691] i2c_dev: i2c /dev entries driver409server # [ 6.080079] sdhci: Secure Digital Host Controller Interface driver410server # [ 6.080356] sdhci: Copyright(c) Pierre Ossman411server # [ 6.085418] Synopsys Designware Multimedia Card Interface Driver412server # [ 6.092124] sdhci-pltfm: SDHCI platform and OF driver helper413server # [ 6.114172] hid: raw HID events driver (C) Jiri Kosina414server # [ 6.120356] usbcore: registered new interface driver usbhid415server # [ 6.120621] usbhid: USB HID core driver416server # [ 6.142034] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available417server # [ 6.172270] drop_monitor: Initializing network drop monitor service418server # [ 6.176344] NET: Registered PF_INET6 protocol family419server # [ 6.195912] Segment Routing with IPv6420server # [ 6.197212] In-situ OAM (IOAM) with IPv6421server # [ 6.199146] NET: Registered PF_PACKET protocol family422server # [ 6.201974] 9pnet: Installing 9P2000 support423server # [ 6.213489] Key type dns_resolver registered424server # [ 6.283631] usb 1-1: new high-speed USB device number 2 using ehci-pci425server # [ 6.336528] registered taskstats version 1426server # [ 6.343017] Loading compiled-in X.509 certificates427server # [ 6.515246] 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/input0428server # [ 6.547196] Demotion targets for Node 0: null429server # [ 6.550533] Key type .fscrypt registered430server # [ 6.551405] Key type fscrypt-provisioning registered431server # [ 6.560038] ima: No TPM chip found, activating TPM-bypass!432server # [ 6.561001] ima: Allocated hash algorithm: sha1433server # [ 6.567335] ima: No architecture policies found434server # [ 6.595410] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0435server # [ 6.601443] input: gpio-keys as /devices/platform/gpio-keys/input/input1436server # [ 6.721035] clk: Disabling unused clocks437server # [ 6.722240] PM: genpd: Disabling unused power domains438server # [ 6.731300] usb 1-2: new high-speed USB device number 3 using ehci-pci439server # [ 7.353722] Freeing unused kernel memory: 4864K440server # [ 7.359017] Run /init as init process441server # [ 7.481201] 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/input2442server # [ 7.488667] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0443server # [ 7.697714] systemd[1]: Successfully made /usr/ read-only.444server # [ 8.073981] systemd[1]: systemd 260.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)445server # [ 8.077433] systemd[1]: Detected virtualization qemu.446server # [ 8.079699] systemd[1]: Detected architecture arm64.447server # [ 8.080805] systemd[1]: Running in initrd.448server # [ 8.101513] systemd[1]: Initializing machine ID from random generator.449server # [ 8.111662] systemd[1]: Hostname set to <server>.450server # [ 10.206060] systemd[1]: Queued start job for default target Initrd Default Target.451server # [ 11.907382] systemd[1]: Created slice Slice /system/modprobe.452server # [ 11.919947] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.453server # [ 11.923900] systemd[1]: Expecting device /dev/disk/by-label/nixos...454server # [ 11.926576] systemd[1]: Reached target Path Units.455server # [ 11.928700] systemd[1]: Reached target Slice Units.456server # [ 11.930389] systemd[1]: Reached target Swaps.457server # [ 11.932631] systemd[1]: Reached target Timer Units.458server # [ 11.942464] systemd[1]: Listening on D-Bus System Message Bus Socket.459server # [ 11.949700] systemd[1]: Listening on Journal Socket (/dev/log).460server # [ 11.955862] systemd[1]: Listening on Journal Sockets.461server # [ 11.961112] systemd[1]: Listening on udev Control Socket.462server # [ 11.965532] systemd[1]: Listening on udev Kernel Socket.463server # [ 11.967939] systemd[1]: Reached target Socket Units.464server # [ 12.060997] systemd[1]: Starting Create List of Static Device Nodes...465server # [ 12.177011] systemd[1]: Starting Load Kernel Module 9pnet_virtio...466server # [ 12.185457] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs467server # [ 12.358583] systemd[1]: Mounting Kernel Configuration File System...468server # [ 12.845014] systemd[1]: Starting Journal Service...469server # [ 13.004754] systemd[1]: Starting Load Kernel Modules...470server # [ 13.008429] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki471server # [ 13.165072] systemd[1]: Starting Coldplug All udev Devices...472server # [ 13.720398] systemd[1]: Finished Create List of Static Device Nodes.473server # [ 13.873065] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.474server # [ 14.022570] systemd[1]: Finished Load Kernel Module 9pnet_virtio.475server # [ 14.317972] systemd[1]: Mounted Kernel Configuration File System.476server # [ 14.496502] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.477server # [ 14.513274] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev478server # [ 14.576830] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...479server # [ 14.804919] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0480server # [ 14.812195] [drm] features: -virgl +edid -resource_blob -host_visible481server # [ 14.812439] [drm] features: -context_init482server # [ 14.831067] [drm] number of scanouts: 1483server # [ 14.843454] [drm] number of cap sets: 0484server # [ 14.919222] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic485server # [ 14.919585] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0486server # [ 14.930310] systemd-journald[131]: Collecting audit messages is disabled.487server # [ 15.315061] Console: switching to colour frame buffer device 160x50488server # [ 15.395436] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device489server # [ 15.601520] systemd[1]: Finished Load Kernel Modules.490server # [ 15.756258] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.491server # [ 15.945145] systemd[1]: Starting Apply Kernel Variables...492server # [ 16.221022] systemd[1]: Starting Create Static Device Nodes in /dev...493server # [ 17.154499] systemd[1]: Finished Apply Kernel Variables.494server # [ 17.368283] systemd[1]: Finished Create Static Device Nodes in /dev.495server # [ 17.403683] systemd[1]: Reached target Preparation for Local File Systems.496server # [ 17.407187] systemd[1]: Reached target Local File Systems.497server # [ 17.589031] systemd[1]: Starting Rule-based Manager for Device Events and Files...498server # [ 20.405797] systemd[1]: Started Journal Service.499server # [ 20.283602] systemd-modules-load[133]: Inserted module 'dm_mod'500server # [ 20.376930] systemd-modules-load[133]: Module 'virtio_balloon' is built in501server # [ 20.395184] systemd-modules-load[133]: Module 'virtio_console' is built in502server # [ 20.422942] systemd-modules-load[133]: Inserted module 'virtio_gpu'503server # [ 20.451142] systemd-modules-load[133]: Module 'virtio_rng' is built in504server # [ 20.506430] systemd-udevd[154]: Using default interface naming scheme 'v260'.505server # [ 20.545355] systemd[1]: Starting Create System Files and Directories...506server # [ 21.559756] systemd[1]: Finished Create System Files and Directories.507server # [ 21.745474] systemd[1]: Started Rule-based Manager for Device Events and Files.508server # [ 23.565574] systemd[1]: Starting Virtual Console Setup...509server # [ 25.270329] systemd-vconsole-setup[179]: Configuration of first virtual console was skipped, ignoring remaining ones.510server # [ 25.447765] systemd[1]: Finished Virtual Console Setup.511server: Guest root shell did not produce any data yet...512server: To debug, enter the VM and run 'systemctl status backdoor.service'.513server # [ 48.605846] systemd[1]: Finished Coldplug All udev Devices.514server # [ 48.615472] systemd[1]: Reached target System Initialization.515server # [ 48.639380] systemd[1]: Reached target Basic System.516server: Guest root shell did not produce any data yet...517server: To debug, enter the VM and run 'systemctl status backdoor.service'.518server # [ 60.311314] (udev-worker)[207]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.519server # [ 60.354053] (udev-worker)[213]: Network interface NamePolicy= disabled on kernel command line.520server # [ 60.361647] (udev-worker)[207]: Network interface NamePolicy= disabled on kernel command line.521server # [ 61.843565] systemd[1]: Found device /dev/disk/by-label/nixos.522server # [ 61.855276] systemd[1]: Reached target Initrd Root Device.523server # [ 61.969208] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...524server # [ 62.955959] systemd-fsck[232]: nixos: clean, 12/65536 files, 13019/262144 blocks525server # [ 63.165717] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.526server # [ 63.295388] systemd[1]: Mounting /sysroot...527server # [ 64.298302] EXT4-fs (vda): mounted filesystem 0a4227d5-a7b8-4c9d-8b71-221b89298b19 r/w with ordered data mode. Quota mode: none.528server # [ 64.256891] systemd[1]: Mounted /sysroot.529server # [ 64.289634] systemd[1]: Reached target Initrd Root File System.530server # [ 64.428947] systemd[1]: Mounting /sysroot/nix/.ro-store...531server # [ 64.563551] systemd[1]: Mounting /sysroot/nix/.rw-store...532server # [ 64.723110] systemd[1]: Mounting /sysroot/run...533server # [ 64.891412] systemd[1]: Mounting /sysroot/tmp/shared...534server # [ 65.101400] systemd[1]: Mounting /sysroot/tmp/xchg...535server # [ 65.311495] systemd[1]: Starting Mountpoints Configured in the Real Root...536server # [ 65.981209] systemd[1]: Mounted /sysroot/nix/.ro-store.537server # [ 66.277791] systemd-sysroot-fstab-check[253]: /sysroot should be mounted in the initrd, will request daemon-reload.538server # [ 66.316183] systemd[1]: Mounted /sysroot/nix/.rw-store.539server # [ 66.327446] systemd[1]: Mounted /sysroot/run.540server # [ 66.335670] systemd[1]: Mounted /sysroot/tmp/shared.541server # [ 66.355175] systemd[1]: Mounted /sysroot/tmp/xchg.542server # [ 66.525349] systemd[1]: Reload requested from client PID 253 ('systemd-sysroot') (unit initrd-parse-etc.service)...543server # [ 66.534294] systemd[1]: Reloading...544server # [ 74.991874] systemd[1]: Reloading finished in 8425 ms.545server # [ 75.847948] systemd-sysroot-fstab-check[253]: Requesting initrd-fs.target/start/replace...546server # [ 77.981651] systemd-sysroot-fstab-check[253]: Requesting swap.target/start/replace...547server # [ 78.051741] systemd[1]: initrd-parse-etc.service: Deactivated successfully.548server # [ 78.107353] systemd[1]: Finished Mountpoints Configured in the Real Root.549server # [ 78.122416] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.550server # [ 78.333782] systemd[1]: Starting rw-sysroot-nix-store.service...551server # [ 78.903549] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.552server # [ 79.099675] systemd[1]: Finished rw-sysroot-nix-store.service.553server # [ 79.540120] systemd[1]: Mounting /sysroot/nix/store...554server # [ 80.436007] systemd[1]: Mounted /sysroot/nix/store.555server # [ 80.471357] systemd[1]: Reached target Initrd File Systems.556server # [ 80.549136] systemd[1]: Starting Find NixOS closure...557server # [ 80.695882] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...558server # [ 81.935165] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.559server # [ 81.993475] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.560server # [ 82.897568] systemd[1]: Finished Find NixOS closure.561server # [ 82.969377] systemd[1]: Reached target Initrd Default Target.562server # [ 83.097239] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...563server # [ 84.221178] systemd[1]: initrd-cleanup.service: Deactivated successfully.564server # [ 84.286129] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.565server # [ 84.402133] systemd[1]: Stopped target Initrd Default Target.566server # [ 84.418457] systemd[1]: Stopped target Basic System.567server # [ 84.431709] systemd[1]: Stopped target Initrd Root Device.568server # [ 84.447146] systemd[1]: Stopped target Path Units.569server # [ 84.466239] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.570server # [ 84.474421] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.571server # [ 84.483078] systemd[1]: Stopped target Slice Units.572server # [ 84.498321] systemd[1]: Stopped target Socket Units.573server # [ 84.507725] systemd[1]: Stopped target System Initialization.574server # [ 84.518397] systemd[1]: Stopped target Swaps.575server # [ 84.531321] systemd[1]: Stopped target Timer Units.576server # [ 84.537859] systemd[1]: dbus.socket: Deactivated successfully.577server # [ 84.550087] systemd[1]: Closed D-Bus System Message Bus Socket.578server # [ 84.596808] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.579server # [ 84.606211] systemd[1]: Stopped Find NixOS closure.580server # [ 84.751902] systemd[1]: Starting Load Kernel Module 9pnet_virtio...581server # [ 84.889617] systemd[1]: Starting rw-sysroot-nix-store.service...582server # [ 84.902529] systemd[1]: systemd-sysctl.service: Deactivated successfully.583server # [ 84.917222] systemd[1]: Stopped Apply Kernel Variables.584server # [ 85.085411] systemd[1]: systemd-modules-load.service: Deactivated successfully.585server # [ 85.111000] systemd[1]: Stopped Load Kernel Modules.586server # [ 85.347714] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.587server # [ 85.374410] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.588server # [ 85.513290] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.589server # [ 85.525222] systemd[1]: Stopped Create System Files and Directories.590server # [ 85.595484] systemd[1]: Stopped target Local File Systems.591server # [ 85.603741] systemd[1]: Stopped target Preparation for Local File Systems.592server # [ 85.615861] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.593server # [ 85.630622] systemd[1]: Stopped Coldplug All udev Devices.594server # [ 85.642320] systemd[1]: systemd-udev-trigger.service: Consumed 18.224s CPU time over 1min 12.581s wall clock time, 9.2M memory peak.595server # [ 85.708765] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.596server # [ 85.722220] systemd[1]: Stopped Virtual Console Setup.597server # [ 85.817346] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.598server # [ 85.869542] systemd[1]: Finished Load Kernel Module 9pnet_virtio.599server # [ 85.947077] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.600server # [ 85.995059] systemd[1]: Finished rw-sysroot-nix-store.service.601server # [ 87.011481] systemd[1]: systemd-udevd.service: Deactivated successfully.602server # [ 87.058720] systemd[1]: Stopped Rule-based Manager for Device Events and Files.603server # [ 87.070109] systemd[1]: systemd-udevd.service: Consumed 22.062s CPU time over 1min 9.565s wall clock time, 19.1M memory peak.604server # [ 87.150516] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.605server # [ 87.161881] systemd[1]: Closed udev Control Socket.606server # [ 87.285409] systemd[1]: Starting Cleanup udev Database...607server # [ 87.295447] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.608server # [ 87.303226] systemd[1]: Stopped Create Static Device Nodes in /dev.609server # [ 87.430441] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.610server # [ 87.438637] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.611server # [ 87.570900] systemd[1]: kmod-static-nodes.service: Deactivated successfully.612server # [ 87.582589] systemd[1]: Stopped Create List of Static Device Nodes.613server # [ 87.901498] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.614server # [ 87.951841] systemd[1]: Finished Cleanup udev Database.615server # [ 88.045799] systemd[1]: Reached target Switch Root.616server # [ 88.101188] systemd[1]: Starting NixOS Activation...617server: Guest root shell did not produce any data yet...618server: To debug, enter the VM and run 'systemctl status backdoor.service'.619server # [ 91.343385] initrd-nixos-activation-start[556]: booting system configuration /nix/store/h8mjayp97swrv476s970jm93miz3jdsm-nixos-system-server-test620server # [ 92.673524] initrd-nixos-activation-start[556]: running activation script...621server # [ 102.437653] initrd-nixos-activation-start[581]: setting up /etc...622server # [ 107.568900] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.623server # [ 107.610829] systemd[1]: Finished NixOS Activation.624server # [ 107.622782] systemd[1]: initrd-nixos-activation.service: Consumed 11.346s CPU time over 19.452s wall clock time, 22.4M memory peak.625server # [ 107.755313] systemd[1]: Starting Switch Root...626server # [ 108.075125] systemd[1]: Switching root.627server # [ 112.938259] systemd-journald[131]: Received SIGTERM from PID 1 (systemd).628server # [ 117.482116] systemd[1]: systemd 260.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)629server # [ 117.489865] systemd[1]: Detected virtualization qemu.630server # [ 117.494424] systemd[1]: Detected architecture arm64.631server # [ 117.501406] systemd[1]: Detected first boot.632server # [ 117.572367] systemd[1]: Initializing machine ID from random generator.633server: Guest root shell did not produce any data yet...634server: To debug, enter the VM and run 'systemctl status backdoor.service'.635server # [ 121.669683] systemd[1]: bpf-restrict-fs: LSM BPF program attached636server # [ 125.067944] systemd[1]: Applying preset policy.637server # [ 133.795465] systemd[1]: Populated /etc with preset unit settings.638server # [ 143.414437] systemd[1]: initrd-switch-root.service: Deactivated successfully.639server # [ 143.429159] systemd[1]: Stopped initrd-switch-root.service.640server # [ 143.495478] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.641server # [ 143.538350] systemd[1]: Created slice Slice /system/getty.642server # [ 143.568999] systemd[1]: Created slice User and Session Slice.643server # [ 143.585127] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.644server # [ 143.595780] systemd[1]: Started Forward Password Requests to Wall Directory Watch.645server # [ 143.605108] systemd[1]: Expecting device /dev/hvc0...646server # [ 143.613107] systemd[1]: Expecting device /dev/ttyAMA0...647server # [ 143.620079] systemd[1]: Reached target Local Encrypted Volumes.648server # [ 143.629461] systemd[1]: Stopped target initrd-fs.target.649server # [ 143.638499] systemd[1]: Stopped target initrd-root-fs.target.650server # [ 143.648031] systemd[1]: Stopped target initrd-switch-root.target.651server # [ 143.658118] systemd[1]: Reached target Virtual Machines and Containers.652server # [ 143.667087] systemd[1]: Reached target Path Units.653server # [ 143.675236] systemd[1]: Reached target Remote File Systems.654server # [ 143.682997] systemd[1]: Reached target Slice Units.655server # [ 143.691100] systemd[1]: Reached target Swaps.656server # [ 143.760823] systemd[1]: Listening on Process Core Dump Socket.657server # [ 143.812877] systemd[1]: Listening on Credential Encryption/Decryption.658server # [ 143.901390] systemd[1]: Starting Journal Log Access Socket...659server # [ 143.941896] systemd[1]: Listening on Journal Audit Socket.660server # [ 143.989561] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.661server # [ 144.005622] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki662server # [ 144.019079] systemd[1]: Listening on udev Control Socket.663server # [ 144.110056] systemd[1]: Mounting Huge Pages File System...664server # [ 144.237057] systemd[1]: Mounting POSIX Message Queue File System...665server # [ 144.412815] systemd[1]: Mounting Kernel Debug File System...666server # [ 144.632767] systemd[1]: Mounting Kernel Trace File System...667server # [ 144.933363] systemd[1]: Starting Create List of Static Device Nodes...668server # [ 145.281478] systemd[1]: Starting Load Kernel Module 9pnet_virtio...669server # [ 145.324701] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs670server # [ 145.764761] systemd[1]: Mounting Kernel Configuration File System...671server # [ 145.860857] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm672server # [ 145.907963] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore673server # [ 147.337186] systemd[1]: Starting Load Kernel Module fuse...674server # [ 147.370400] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67675server # [ 147.700663] systemd[1]: Starting Journal Service...676server # [ 148.097859] systemd[1]: Starting Load Kernel Modules...677server # [ 148.625300] fuse: init (API version 7.45)678server # [ 148.697032] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...679server # [ 148.950496] systemd[1]: Starting Remount Root and Kernel File Systems...680server # [ 148.996394] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki681server: Guest root shell did not produce any data yet...682server: To debug, enter the VM and run 'systemctl status backdoor.service'.683server # [ 149.536874] systemd[1]: Starting Coldplug All udev Devices...684server # [ 150.606235] systemd[1]: Listening on Journal Log Access Socket.685server # [ 150.739132] systemd[1]: Mounted Huge Pages File System.686server # [ 150.781456] systemd-journald[790]: Collecting audit messages is enabled.687server # [ 150.821980] systemd[1]: Mounted POSIX Message Queue File System.688server # [ 150.886568] systemd[1]: Mounted Kernel Debug File System.689server # [ 150.949301] systemd[1]: Mounted Kernel Trace File System.690server # [ 151.084732] systemd[1]: Finished Create List of Static Device Nodes.691server # [ 151.193507] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.692server # [ 151.293732] systemd[1]: Finished Load Kernel Module 9pnet_virtio.693server # [ 151.365289] systemd[1]: Mounted Kernel Configuration File System.694server # [ 151.466180] systemd[1]: modprobe@fuse.service: Deactivated successfully.695server # [ 151.576725] systemd[1]: Finished Load Kernel Module fuse.696server # [ 151.740588] systemd[1]: Finished Load Kernel Modules.697server # [ 151.836750] EXT4-fs (vda): re-mounted 0a4227d5-a7b8-4c9d-8b71-221b89298b19.698server # [ 151.913428] systemd[1]: Queued start job for default target Multi-User System.699server # [ 152.077716] systemd[1]: Started Journal Service.700server # [ 152.001896] systemd[1]: systemd-journald.service: Deactivated successfully.701server # [ 152.055445] systemd[1]: systemd-journald.service: Consumed 2.867s CPU time over 2min 10.388s wall clock time, 3.9M memory peak.702server # [ 152.128424] systemd-modules-load[791]: Module 'atkbd' is built in703server # [ 152.185756] systemd-modules-load[791]: Module 'loop' is built in704server # [ 152.233622] systemd-modules-load[791]: Inserted module 'tls'705server # [ 152.267641] systemd[1]: Finished Remount Root and Kernel File Systems.706server # [ 153.009149] systemd[1]: Mounting FUSE Control File System...707server # [ 153.253080] systemd[1]: Starting Firewall...708server # [ 153.639328] systemd[1]: Starting Flush Journal to Persistent Storage...709server # [ 153.673095] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore710server # [ 154.276029] systemd[1]: Starting Load/Save OS Random Seed...711server # [ 154.365702] systemd-oomd[792]: No swap; memory pressure usage will be degraded712server # [ 154.903281] systemd[1]: Starting Apply Kernel Variables...713server # [ 155.749983] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...714server # [ 155.777327] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki715server # [ 156.345894] systemd-journald[790]: Received client request to flush runtime journal.716server # [ 159.301657] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.717server # [ 159.355355] systemd[1]: Mounted FUSE Control File System.718server # [ 159.431817] systemd[1]: Finished Load/Save OS Random Seed.719server # [ 159.522209] systemd[1]: Reached target First Boot Complete.720server # [ 159.585902] systemd[1]: Finished Flush Journal to Persistent Storage.721server # [ 160.778763] systemd[1]: Finished Apply Kernel Variables.722server # [ 161.890263] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.723server # [ 162.137336] systemd[1]: Starting Create Static Device Nodes in /dev...724server # [ 165.049683] systemd[1]: Finished Create Static Device Nodes in /dev.725server # [ 165.133383] systemd[1]: Reached target Preparation for Local File Systems.726server # [ 165.305000] systemd[1]: Mounting /run/wrappers...727server # [ 165.629822] systemd[1]: Starting Rule-based Manager for Device Events and Files...728server # [ 167.482041] systemd[1]: Mounted /run/wrappers.729server # [ 167.533274] systemd[1]: Reached target Local File Systems.730server # [ 167.737227] systemd[1]: Listening on Boot Loader Control Service Socket.731server # [ 167.911515] systemd[1]: Starting register-nix-paths.service...732server # [ 168.297794] systemd[1]: Starting Create SUID/SGID Wrappers...733server # [ 168.331160] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.734server # [ 168.975165] systemd[1]: Starting Save Transient machine-id to Disk...735server # [ 169.737135] systemd[1]: Starting Create System Files and Directories...736server # [ 173.465870] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.737server # [ 173.591745] systemd[1]: Finished Save Transient machine-id to Disk.738server: Guest root shell did not produce any data yet...739server: To debug, enter the VM and run 'systemctl status backdoor.service'.740server # [ 179.667222] systemd[1]: Finished Create System Files and Directories.741server # [ 180.229306] systemd[1]: Starting Rebuild Journal Catalog...742server # [ 180.893524] systemd[1]: Starting Record System Boot/Shutdown in UTMP...743server # [ 185.161591] systemd[1]: Finished Record System Boot/Shutdown in UTMP.744server # [ 189.351050] systemd[1]: Finished Rebuild Journal Catalog.745server # [ 189.702486] systemd[1]: Starting Update is Completed...746server # [ 192.614561] systemd[1]: Finished Update is Completed.747server # [ 202.127426] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.748server # [ 202.201811] systemd[1]: Finished Create SUID/SGID Wrappers.749server # [ 202.238733] systemd[1]: suid-sgid-wrappers.service: Consumed 4.222s CPU time over 33.815s wall clock time, 5.2M memory peak.750server # [ 207.065798] systemd[1]: Finished Firewall.751server: Guest root shell did not produce any data yet...752server: To debug, enter the VM and run 'systemctl status backdoor.service'.753server # [ 211.221307] systemd-udevd[847]: Using default interface naming scheme 'v260'.754server # [ 212.043803] systemd[1]: Finished register-nix-paths.service.755server # [ 214.626659] systemd[1]: Started Rule-based Manager for Device Events and Files.756server # [ 236.122049] systemd[1]: Finished Coldplug All udev Devices.757server # [ 236.141871] systemd[1]: Reached target System Initialization.758server # [ 236.173054] systemd[1]: Started Discard unused filesystem blocks once a week.759server # [ 236.194542] systemd[1]: Started Daily Cleanup of Temporary Directories.760server # [ 236.210780] systemd[1]: Reached target Timer Units.761server # [ 236.226616] systemd[1]: Listening on D-Bus System Message Bus Socket.762server # [ 236.238321] systemd[1]: Listening on Nix Daemon Socket.763server # [ 236.255378] systemd[1]: Listening on Hostname Service Socket.764server # [ 236.273485] systemd[1]: Reached target Socket Units.765server # [ 236.286593] systemd[1]: Reached target Basic System.766server # [ 236.354188] systemd[1]: Starting Import lastlog data into lastlog2 database...767server # [ 236.561335] systemd[1]: Starting Name Service Cache Daemon (nsncd)...768server # [ 236.825418] systemd[1]: Starting Post-Boot Actions...769server # [ 237.287346] systemd[1]: Started Reset console on configuration changes.770server # [ 237.855027] systemd[1]: Starting resolvconf update...771server: Guest root shell did not produce any data yet...772server: To debug, enter the VM and run 'systemctl status backdoor.service'.773server # [ 240.045316] systemd[1]: Starting D-Bus System Message Bus...774server # [ 240.382381] nsncd[1035]: Oct 21 13:45:13.888 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"775server # [ 240.521682] systemd[1]: Finished Post-Boot Actions.776server # [ 240.603900] systemd[1]: Started Name Service Cache Daemon (nsncd).777server # [ 241.183502] systemd[1]: Finished Import lastlog data into lastlog2 database.778server # [ 241.329314] systemd[1]: Reached target Host and Network Name Lookups.779server # [ 241.382438] systemd[1]: Reached target User and Group Name Lookups.780server # [ 241.964435] systemd[1]: Starting User Login Management...781server # [ 245.195549] dbus-broker-launch[1042]: Looking up NSS user entry for 'systemd-timesync'...782server # [ 245.789934] dbus-broker-launch[1042]: NSS returned no entry for 'systemd-timesync'783server # [ 245.808954] dbus-broker-launch[1042]: Invalid user-name in /nix/store/60d582rv76ykld4d2wlnzxjq4sfspcl5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"784server # [ 246.726947] systemd[1]: Started D-Bus System Message Bus.785server # [ 247.822402] systemd-logind[1057]: New seat seat0.786server # [ 248.113455] dbus-broker-launch[1042]: Ready787server # [ 248.278882] systemd[1]: Started User Login Management.788server # [ 248.629428] systemd[1]: Stopped target Host and Network Name Lookups.789server # [ 248.643754] systemd[1]: Stopping Host and Network Name Lookups...790server # [ 248.662783] systemd[1]: Stopped target User and Group Name Lookups.791server # [ 248.686830] systemd[1]: Stopping User and Group Name Lookups...792server # [ 248.875067] systemd[1]: Starting linger-users.service...793server # [ 249.009172] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...794server # [ 249.201461] systemd[1]: nscd.service: Deactivated successfully.795server # [ 249.299682] systemd[1]: Stopped Name Service Cache Daemon (nsncd).796server # [ 250.384098] systemd[1]: Starting Name Service Cache Daemon (nsncd)...797server # [ 251.274459] systemd[1]: linger-users.service: Deactivated successfully.798server # [ 251.327851] systemd[1]: Finished linger-users.service.799server # [ 251.685883] systemd[1]: Finished resolvconf update.800server # [ 251.737153] systemd[1]: Reached target Preparation for Network.801server # [ 251.956780] systemd[1]: Starting DHCP Client...802server # [ 252.151736] nsncd[1108]: Oct 21 13:45:25.740 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"803server # [ 252.215392] systemd[1]: Starting Extra networking commands....804server # [ 252.327768] systemd[1]: Started Name Service Cache Daemon (nsncd).805server # [ 252.438211] systemd[1]: Reached target Host and Network Name Lookups.806server # [ 252.478725] systemd[1]: Reached target User and Group Name Lookups.807server # [ 259.429704] systemd[1]: Finished Extra networking commands..808server # [ 259.559472] dhcpcd[1140]: dhcpcd-10.3.2 starting809server # [ 259.571781] systemd[1]: Reached target Network.810server # [ 259.861992] systemd[1]: Starting Nginx Web Server...811server # [ 260.089688] dhcpcd[1183]: dev: loaded udev812server # [ 260.388916] dhcpcd[1183]: no valid interfaces found813server # [ 260.401940] dhcpcd[1183]: no valid interfaces found814server # [ 260.502873] systemd[1]: Starting PostgreSQL Server...815server # [ 260.789779] systemd[1]: Starting Permit User Sessions...816server # [ 263.219878] systemd[1]: Finished Permit User Sessions.817server # [ 263.495932] systemd[1]: Started Getty on tty1.818server # [ 263.573922] systemd[1]: Reached target Login Prompts.819server: Guest root shell did not produce any data yet...820server: To debug, enter the VM and run 'systemctl status backdoor.service'.821server # [ 273.171536] postgresql-pre-start[1202]: The files belonging to this database system will be owned by user "postgres".822server # [ 273.263632] postgresql-pre-start[1202]: This user must also own the server process.823server # [ 273.437116] postgresql-pre-start[1202]: The database cluster will be initialized with locale "en_US.UTF-8".824server # [ 273.449937] postgresql-pre-start[1202]: The default database encoding has accordingly been set to "UTF8".825server # [ 273.466915] postgresql-pre-start[1202]: The default text search configuration will be set to "english".826server # [ 273.494447] postgresql-pre-start[1202]: Data page checksums are disabled.827server # [ 273.506072] postgresql-pre-start[1202]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok828server # [ 273.640779] postgresql-pre-start[1202]: creating subdirectories ... ok829server # [ 273.673693] postgresql-pre-start[1202]: selecting dynamic shared memory implementation ... posix830server # [ 273.937875] nginx-pre-start[1195]: nginx: the configuration file /nix/store/ikrbs31amb4hnw5f24wff1xrag8dsmr3-nginx.conf syntax is ok831server # [ 274.045691] nginx-pre-start[1195]: nginx: configuration file /nix/store/ikrbs31amb4hnw5f24wff1xrag8dsmr3-nginx.conf test is successful832server # [ 274.546187] systemd[1]: Started Nginx Web Server.833server # [ 277.843017] postgresql-pre-start[1202]: selecting default "max_connections" ... 100834server # [ 280.697643] postgresql-pre-start[1202]: selecting default "shared_buffers" ... 128MB835server # [ 290.497391] dhcpcd[1183]: timed out836server # [ 290.507520] dhcpcd[1183]: timed out837server # [ 290.642748] systemd[1]: Started DHCP Client.838server: Guest root shell did not produce any data yet...839server: To debug, enter the VM and run 'systemctl status backdoor.service'.840server # [ 303.286609] systemd-logind[1057]: Watching system buttons on /dev/input/event1 (gpio-keys)841server: Guest root shell did not produce any data yet...842server: To debug, enter the VM and run 'systemctl status backdoor.service'.843server # [ 332.596649] mousedev: PS/2 mouse device common for all mice844server # [ 337.489356] postgresql-pre-start[1202]: selecting default time zone ... UTC845server # [ 337.645157] postgresql-pre-start[1202]: creating configuration files ... ok846server # [ 343.029918] systemd-logind[1057]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)847server # [ 353.934687] postgresql-pre-start[1202]: running bootstrap script ... ok848server: Guest root shell did not produce any data yet...849server: To debug, enter the VM and run 'systemctl status backdoor.service'.850server # [ 379.539723] systemd[1]: postgresql.service: start-pre operation timed out. Terminating.851server: Guest root shell did not produce any data yet...852server: To debug, enter the VM and run 'systemctl status backdoor.service'.853server # [ 397.563849] postgresql-pre-start[1202]: performing post-bootstrap initialization ... ok854server # [ 404.975401] postgresql-pre-start[1202]: syncing data to disk ... ok855server # [ 404.998337] postgresql-pre-start[1202]: initdb: warning: enabling "trust" authentication for local connections856server # [ 405.015432] postgresql-pre-start[1202]: 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.857server # [ 405.031383] postgresql-pre-start[1202]: Success. You can now start the database server using:858server # [ 405.075615] postgresql-pre-start[1202]: pg_ctl -D /var/lib/postgresql/17 -l logfile start859server # [ 405.670873] systemd[1]: postgresql.service: Failed with result 'timeout'.860server # [ 405.707904] systemd[1]: Failed to start PostgreSQL Server.861server # [ 405.738642] systemd[1]: Dependency failed for PostgreSQL Setup Scripts.862server # [ 405.755521] systemd[1]: Dependency failed for PostgreSQL.863server # [ 405.775183] systemd[1]: postgresql.target: Job postgresql.target/start failed with result 'dependency'.864server # [ 405.784118] systemd[1]: postgresql-setup.service: Job postgresql-setup.service/start failed with result 'dependency'.865server # [ 405.814622] systemd[1]: postgresql.service: Consumed 46.804s CPU time over 2min 25.183s wall clock time, 97.9M memory peak, 48.9M written to disk.866server # [ 405.972217] systemd[1]: postgresql.service: Scheduled restart job, restart counter is at 1.867server # [ 406.190157] systemd[1]: Starting PostgreSQL Server...868server # [ 414.242724] postgres[1385]: [1385] LOG: starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit869server # [ 414.654480] postgres[1385]: [1385] LOG: listening on IPv6 address "::1", port 5432870server # [ 414.693314] postgres[1385]: [1385] LOG: listening on IPv4 address "127.0.0.1", port 5432871server # [ 414.737104] postgres[1385]: [1385] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"872server # [ 415.173389] postgres[1391]: [1391] LOG: database system was shut down at 2026-10-21 13:47:50 GMT873server # [ 415.583649] postgres[1385]: [1385] LOG: database system is ready to accept connections874server # [ 415.747299] systemd[1]: Started PostgreSQL Server.875server # [ 415.918094] systemd[1]: Starting PostgreSQL Setup Scripts...876server: Guest root shell did not produce any data yet...877server: To debug, enter the VM and run 'systemctl status backdoor.service'.878server # [ 425.639975] postgresql-setup-start[1412]: CREATE DATABASE879server # [ 427.263685] postgresql-setup-start[1427]: CREATE ROLE880server # [ 427.445452] systemd[1]: Finished PostgreSQL Setup Scripts.881server # [ 427.476784] systemd[1]: Reached target PostgreSQL.882server # [ 427.620985] systemd[1]: Starting Mobilizon PostgreSQL setup...883server # [ 443.665492] systemd[1]: dev-hvc0.device: Job dev-hvc0.device/start timed out.884server # [ 443.682993] systemd[1]: Timed out waiting for device /dev/hvc0.885server # [ 443.714912] systemd[1]: Dependency failed for backdoor.service.886server # [ 443.739639] systemd[1]: backdoor.service: Job backdoor.service/start failed with result 'dependency'.887server # [ 443.750690] systemd[1]: dev-hvc0.device: Job dev-hvc0.device/start failed with result 'timeout'.888server # [ 443.767147] systemd[1]: Unnecessary job was removed for /dev/ttyAMA0.889server: Guest root shell did not produce any data yet...890server: To debug, enter the VM and run 'systemctl status backdoor.service'.891server # [ 470.101596] mobilizon-postgresql-start[1431]: CREATE EXTENSION892server # [ 470.197785] mobilizon-postgresql-start[1431]: CREATE EXTENSION893server # [ 470.215590] mobilizon-postgresql-start[1431]: CREATE EXTENSION894server # [ 471.239077] mobilizon-postgresql-start[1484]: ALTER DATABASE895server # [ 471.399378] systemd[1]: mobilizon-postgresql.service: Deactivated successfully.896server # [ 471.414460] systemd[1]: Finished Mobilizon PostgreSQL setup.897server # [ 471.536001] systemd[1]: Starting Mobilizon setup secrets...898server # [ 472.779508] mobilizon-setup-secrets-start[1488]: + '[' '!' -f /var/lib/mobilizon/secret-env.sh ']'899server # [ 472.806544] mobilizon-setup-secrets-start[1488]: + install -m 600 /dev/null /var/lib/mobilizon/secret-env.sh900server # [ 473.003453] mobilizon-setup-secrets-start[1488]: + cat901server # [ 473.124150] mobilizon-setup-secrets-start[1492]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(:crypto.strong_rand_bytes(64)|> Base.encode64()|> binary_part(0, 64))'902server: Guest root shell did not produce any data yet...903server: To debug, enter the VM and run 'systemctl status backdoor.service'.904server: Guest root shell did not produce any data yet...905server: To debug, enter the VM and run 'systemctl status backdoor.service'.906server # [ 518.743571] mobilizon-setup-secrets-start[1581]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(:crypto.strong_rand_bytes(64)|> Base.encode64()|> binary_part(0, 64))'907server: Guest root shell did not produce any data yet...908server: To debug, enter the VM and run 'systemctl status backdoor.service'.909server # [ 558.986781] mobilizon-setup-secrets-start[1666]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(Base.encode32(:crypto.strong_rand_bytes(32)))'910server: Guest root shell did not produce any data yet...911server: To debug, enter the VM and run 'systemctl status backdoor.service'.912server # [ 596.761661] systemd[1]: mobilizon-setup-secrets.service: Deactivated successfully.913server # [ 596.801107] systemd[1]: Finished Mobilizon setup secrets.914server # [ 596.818535] systemd[1]: mobilizon-setup-secrets.service: Consumed 47.227s CPU time over 2min 5.203s wall clock time, 63.4M memory peak, 4K written to disk.915server # [ 597.003592] systemd[1]: Starting Mobilizon federated organization and mobilization platform...916server: Guest root shell did not produce any data yet...917server: To debug, enter the VM and run 'systemctl status backdoor.service'.918server: Guest root shell did not produce any data yet...919server: To debug, enter the VM and run 'systemctl status backdoor.service'.920server # [ 655.791313] mobilizon_ctl[1759]: Loading app..921server # [ 656.296723] mobilizon_ctl[1759]: Starting dependencies..922server: Guest root shell did not produce any data yet...923server: To debug, enter the VM and run 'systemctl status backdoor.service'.924server: Guest root shell did not produce any data yet...925server: To debug, enter the VM and run 'systemctl status backdoor.service'.926server # [ 715.205996] postgres[1389]: [1389] LOG: checkpoint starting: time927server: Guest root shell did not produce any data yet...928server: To debug, enter the VM and run 'systemctl status backdoor.service'.929server: Guest root shell did not produce any data yet...930server: To debug, enter the VM and run 'systemctl status backdoor.service'.931server: Guest root shell did not produce any data yet...932server: To debug, enter the VM and run 'systemctl status backdoor.service'.933server: Guest root shell did not produce any data yet...934server: To debug, enter the VM and run 'systemctl status backdoor.service'.935server: Guest root shell did not produce any data yet...936server: To debug, enter the VM and run 'systemctl status backdoor.service'.937server # [ 852.109823] mobilizon_ctl[1759]: #########################################################938server # [ 852.202928] mobilizon_ctl[1759]: # If the CREATE EXTENSION or DROP EXTENSION calls fail, #939server # [ 852.218405] mobilizon_ctl[1759]: # please manually execute them with an authorized #940server # [ 852.231662] mobilizon_ctl[1759]: # PostgreSQL user with SUPER USER role. #941server # [ 852.254709] mobilizon_ctl[1759]: #########################################################942server # [ 852.266397] mobilizon_ctl[1759]:943server # [ 852.282807] mobilizon_ctl[1759]:944server # [ 852.298960] mobilizon_ctl[1759]: 13:55:25.524 [info] == Running 20180109150000 Mobilizon.Repo.Migrations.Prerequites.up/0 forward945server # [ 852.342751] mobilizon_ctl[1759]: 13:55:25.923 [info] execute "CREATE TYPE datetimetz AS (\n dt timestamptz,\n tz varchar\n);\n"946server # [ 852.402024] mobilizon_ctl[1759]: 13:55:25.995 [info] execute "CREATE EXTENSION IF NOT EXISTS postgis"947server # [ 852.459302] mobilizon_ctl[1759]: 13:55:26.056 [info] extension "postgis" already exists, skipping948server # [ 852.497487] mobilizon_ctl[1759]: 13:55:26.057 [info] execute "CREATE EXTENSION IF NOT EXISTS pg_trgm"949server # [ 852.510216] mobilizon_ctl[1759]: 13:55:26.104 [info] extension "pg_trgm" already exists, skipping950server # [ 852.541730] mobilizon_ctl[1759]: 13:55:26.106 [info] execute "CREATE EXTENSION IF NOT EXISTS unaccent"951server # [ 852.569691] mobilizon_ctl[1759]: 13:55:26.148 [info] extension "unaccent" already exists, skipping952server # [ 852.715443] mobilizon_ctl[1759]: 13:55:26.301 [info] == Migrated 20180109150000 in 0.4s953server # [ 855.062924] mobilizon_ctl[1759]: 13:55:28.626 [info] == Running 20180109165738 Mobilizon.Repo.Migrations.CreateAccounts.change/0 forward954server # [ 855.110519] mobilizon_ctl[1759]: 13:55:28.692 [info] create table accounts955server # [ 855.461824] mobilizon_ctl[1759]: 13:55:29.043 [info] create index accounts_username_domain_index956server # [ 855.525072] mobilizon_ctl[1759]: 13:55:29.114 [info] == Migrated 20180109165738 in 0.4s957server # [ 855.656995] mobilizon_ctl[1759]: 13:55:29.255 [info] == Running 20180109170315 Mobilizon.Repo.Migrations.CreateUsers.change/0 forward958server # [ 855.690036] mobilizon_ctl[1759]: 13:55:29.281 [info] create table users959server # [ 856.187031] mobilizon_ctl[1759]: 13:55:29.690 [info] create index users_email_index960server # [ 856.223652] mobilizon_ctl[1759]: 13:55:29.786 [info] == Migrated 20180109170315 in 0.5s961server # [ 856.333350] mobilizon_ctl[1759]: 13:55:29.938 [info] == Running 20180110091747 Mobilizon.Repo.Migrations.CreateCategories.change/0 forward962server # [ 856.377555] mobilizon_ctl[1759]: 13:55:29.970 [info] create table categories963server # [ 856.516866] mobilizon_ctl[1759]: 13:55:30.121 [info] create index categories_title_index964server # [ 856.580878] mobilizon_ctl[1759]: 13:55:30.175 [info] == Migrated 20180110091747 in 0.2s965server # [ 856.697537] mobilizon_ctl[1759]: 13:55:30.296 [info] == Running 20180110092400 Mobilizon.Repo.Migrations.CreateAddresses.change/0 forward966server # [ 856.735590] mobilizon_ctl[1759]: 13:55:30.313 [info] create table addresses967server # [ 856.891595] mobilizon_ctl[1759]: 13:55:30.485 [info] == Migrated 20180110092400 in 0.1s968server # [ 856.990408] mobilizon_ctl[1759]: 13:55:30.592 [info] == Running 20180110092500 Mobilizon.Repo.Migrations.CreateGroups.change/0 forward969server # [ 857.038061] mobilizon_ctl[1759]: 13:55:30.620 [info] create table groups970server # [ 857.282021] mobilizon_ctl[1759]: 13:55:30.837 [info] create index groups_slug_index971server # [ 857.317164] mobilizon_ctl[1759]: 13:55:30.919 [info] == Migrated 20180110092500 in 0.3s972server # [ 857.416994] mobilizon_ctl[1759]: 13:55:31.015 [info] == Running 20180110092600 Mobilizon.Repo.Migrations.CreateEvents.change/0 forward973server # [ 857.463497] mobilizon_ctl[1759]: 13:55:31.054 [info] create table events974server # [ 857.730319] mobilizon_ctl[1759]: 13:55:31.332 [info] create index events_organizer_account_id_index975server # [ 857.818513] mobilizon_ctl[1759]: 13:55:31.413 [info] create index events_organizer_group_id_index976server # [ 857.877194] mobilizon_ctl[1759]: 13:55:31.470 [info] create index events_slug_index977server # [ 857.935725] mobilizon_ctl[1759]: 13:55:31.522 [info] == Migrated 20180110092600 in 0.5s978server # [ 858.027311] mobilizon_ctl[1759]: 13:55:31.626 [info] == Running 20180110093905 Mobilizon.Repo.Migrations.CreateTags.change/0 forward979server # [ 858.064965] mobilizon_ctl[1759]: 13:55:31.649 [info] create table tags980server # [ 858.225653] mobilizon_ctl[1759]: 13:55:31.817 [info] create index tags_slug_index981server # [ 858.319802] mobilizon_ctl[1759]: 13:55:31.882 [info] == Migrated 20180110093905 in 0.2s982server # [ 858.388792] mobilizon_ctl[1759]: 13:55:31.993 [info] == Running 20180110093906 Mobilizon.Repo.Migrations.CreateEventsTags.change/0 forward983server # [ 858.420372] mobilizon_ctl[1759]: 13:55:31.998 [info] create table events_tags984server # [ 858.510696] mobilizon_ctl[1759]: 13:55:32.108 [info] == Migrated 20180110093906 in 0.1s985server # [ 858.629001] mobilizon_ctl[1759]: 13:55:32.220 [info] == Running 20180110094301 Mobilizon.Repo.Migrations.CreateParticipants.change/0 forward986server # [ 858.666824] mobilizon_ctl[1759]: 13:55:32.241 [info] create table participants987server # [ 858.808732] mobilizon_ctl[1759]: 13:55:32.397 [info] create index participants_event_id_index988server # [ 858.861811] mobilizon_ctl[1759]: 13:55:32.457 [info] create index participants_account_id_index989server # [ 858.909286] mobilizon_ctl[1759]: 13:55:32.513 [info] == Migrated 20180110094301 in 0.2s990server # [ 859.010157] mobilizon_ctl[1759]: 13:55:32.606 [info] == Running 20180110094502 Mobilizon.Repo.Migrations.CreateEventRequests.change/0 forward991server # [ 859.036927] mobilizon_ctl[1759]: 13:55:32.627 [info] create table event_requests992server # [ 859.194071] mobilizon_ctl[1759]: 13:55:32.781 [info] create index event_requests_event_id_index993server # [ 859.245731] mobilizon_ctl[1759]: 13:55:32.851 [info] create index event_requests_account_id_index994server # [ 859.367503] mobilizon_ctl[1759]: 13:55:32.914 [info] == Migrated 20180110094502 in 0.3s995server # [ 859.474167] mobilizon_ctl[1759]: 13:55:33.082 [info] == Running 20180110095317 Mobilizon.Repo.Migrations.CreateMembers.change/0 forward996server # [ 859.535429] mobilizon_ctl[1759]: 13:55:33.125 [info] create table members997server # [ 859.678620] mobilizon_ctl[1759]: 13:55:33.263 [info] create index members_group_id_index998server # [ 859.742473] mobilizon_ctl[1759]: 13:55:33.331 [info] create index members_account_id_index999server # [ 859.813051] mobilizon_ctl[1759]: 13:55:33.391 [info] == Migrated 20180110095317 in 0.3s1000server # [ 859.915697] mobilizon_ctl[1759]: 13:55:33.510 [info] == Running 20180110095515 Mobilizon.Repo.Migrations.CreateGroupRequests.change/0 forward1001server # [ 859.981076] mobilizon_ctl[1759]: 13:55:33.562 [info] create table group_requests1002server # [ 860.228886] mobilizon_ctl[1759]: 13:55:33.803 [info] create index group_requests_group_id_index1003server # [ 860.294003] mobilizon_ctl[1759]: 13:55:33.881 [info] create index group_requests_account_id_index1004server # [ 860.427774] mobilizon_ctl[1759]: 13:55:33.956 [info] == Migrated 20180110095515 in 0.4s1005server # [ 860.489995] mobilizon_ctl[1759]: 13:55:34.090 [info] == Running 20180110165029 Mobilizon.Repo.Migrations.Guardian.DB.change/0 forward1006server # [ 860.533432] mobilizon_ctl[1759]: 13:55:34.121 [info] create table guardian_tokens1007server # [ 860.695681] mobilizon_ctl[1759]: 13:55:34.287 [info] == Migrated 20180110165029 in 0.1s1008server # [ 860.806498] mobilizon_ctl[1759]: 13:55:34.404 [info] == Running 20180112100000 Mobilizon.Repo.Migrations.CreateTracks.change/0 forward1009server # [ 860.843757] mobilizon_ctl[1759]: 13:55:34.430 [info] create table tracks1010server # [ 861.065639] mobilizon_ctl[1759]: 13:55:34.655 [info] == Migrated 20180112100000 in 0.2s1011server # [ 861.173019] mobilizon_ctl[1759]: 13:55:34.762 [info] == Running 20180112105354 Mobilizon.Repo.Migrations.CreateSessions.change/0 forward1012server # [ 861.223771] mobilizon_ctl[1759]: 13:55:34.805 [info] create table sessions1013server # [ 861.553257] mobilizon_ctl[1759]: 13:55:35.101 [info] == Migrated 20180112105354 in 0.3s1014server # [ 861.671000] mobilizon_ctl[1759]: 13:55:35.234 [info] == Running 20180430071244 Mobilizon.Repo.Migrations.RemoveUri.up/0 forward1015server # [ 861.708071] mobilizon_ctl[1759]: 13:55:35.263 [info] alter table accounts1016server # [ 861.771373] mobilizon_ctl[1759]: 13:55:35.369 [info] alter table groups1017server # [ 861.805601] mobilizon_ctl[1759]: 13:55:35.404 [info] == Migrated 20180430071244 in 0.1s1018server # [ 861.901897] mobilizon_ctl[1759]: 13:55:35.506 [info] == Running 20180515091106 Mobilizon.Repo.Migrations.AddUrlFieldToEvents.up/0 forward1019server # [ 861.935712] mobilizon_ctl[1759]: 13:55:35.529 [info] alter table events1020server # [ 862.006401] mobilizon_ctl[1759]: 13:55:35.586 [info] == Migrated 20180515091106 in 0.0s1021server # [ 862.097528] mobilizon_ctl[1759]: 13:55:35.704 [info] == Running 20180515100237 Mobilizon.Repo.Migrations.CreateComments.change/0 forward1022server # [ 862.130885] mobilizon_ctl[1759]: 13:55:35.728 [info] create table comments1023server # [ 862.435419] mobilizon_ctl[1759]: 13:55:36.022 [info] == Migrated 20180515100237 in 0.3s1024server # [ 862.566958] mobilizon_ctl[1759]: 13:55:36.137 [info] == Running 20180515142835 Mobilizon.Repo.Migrations.AlterEventTimestampsToDateTimeWithTimeZone.up/0 forward1025server # [ 862.590024] mobilizon_ctl[1759]: 13:55:36.146 [info] alter table events1026server # [ 862.612701] mobilizon_ctl[1759]: 13:55:36.206 [info] == Migrated 20180515142835 in 0.0s1027server # [ 862.713879] mobilizon_ctl[1759]: 13:55:36.310 [info] == Running 20180515145438 Mobilizon.Repo.Migrations.AddLocalAttributeToEventsAndComments.up/0 forward1028server # [ 862.746557] mobilizon_ctl[1759]: 13:55:36.322 [info] alter table events1029server # [ 862.800908] mobilizon_ctl[1759]: 13:55:36.393 [info] alter table comments1030server # [ 862.837073] mobilizon_ctl[1759]: 13:55:36.430 [info] == Migrated 20180515145438 in 0.1s1031server # [ 862.957134] mobilizon_ctl[1759]: 13:55:36.554 [info] == Running 20180517100700 Mobilizon.Repo.Migrations.MoveFromAccountToActor.up/0 forward1032server # [ 863.145125] mobilizon_ctl[1759]: 13:55:36.729 [info] drop table event_requests1033server # [ 863.329312] mobilizon_ctl[1759]: 13:55:36.923 [info] drop table group_requests1034server # [ 863.466666] mobilizon_ctl[1759]: 13:55:37.061 [info] alter table events1035server # [ 863.594013] mobilizon_ctl[1759]: 13:55:37.144 [info] rename column account_id to actor_id on table members1036server # [ 863.610981] mobilizon_ctl[1759]: 13:55:37.177 [info] alter table members1037server # [ 863.802054] mobilizon_ctl[1759]: 13:55:37.394 [info] drop table groups1038server # [ 863.919580] mobilizon_ctl[1759]: 13:55:37.521 [info] rename table accounts to actors1039server # [ 863.985287] mobilizon_ctl[1759]: 13:55:37.570 [info] execute "CREATE TYPE public.actor_type AS ENUM ('Person', 'Application', 'Group', 'Organization', 'Service')"1040server # [ 864.077252] mobilizon_ctl[1759]: 13:55:37.668 [info] rename column username to name on table actors1041server # [ 864.136988] mobilizon_ctl[1759]: 13:55:37.703 [info] rename column description to summary on table actors1042server # [ 864.177305] mobilizon_ctl[1759]: 13:55:37.726 [info] rename column display_name to preferred_username on table actors1043server # [ 864.193518] mobilizon_ctl[1759]: 13:55:37.759 [info] alter table actors1044server # [ 864.397150] mobilizon_ctl[1759]: 13:55:37.985 [info] create index actors_preferred_username_domain_index1045server # [ 864.461047] mobilizon_ctl[1759]: 13:55:38.046 [info] rename column organizer_account_id to organizer_actor_id on table events1046server # [ 864.503588] mobilizon_ctl[1759]: 13:55:38.074 [info] rename column account_id to actor_id on table participants1047server # [ 864.529036] mobilizon_ctl[1759]: 13:55:38.102 [info] create table followers1048server # [ 864.809017] mobilizon_ctl[1759]: 13:55:38.321 [info] rename column account_id to actor_id on table comments1049server # [ 864.819769] mobilizon_ctl[1759]: 13:55:38.351 [info] rename column account_id to actor_id on table users1050server # [ 864.846388] mobilizon_ctl[1759]: 13:55:38.394 [info] == Migrated 20180517100700 in 1.8s1051server # [ 865.078031] mobilizon_ctl[1759]: 13:55:38.671 [info] == Running 20180522133844 Mobilizon.Repo.Migrations.AddBotsTable.up/0 forward1052server # [ 865.125150] mobilizon_ctl[1759]: 13:55:38.703 [info] create table bots1053server # [ 865.392897] mobilizon_ctl[1759]: 13:55:38.987 [info] == Migrated 20180522133844 in 0.3s1054server # [ 865.484747] mobilizon_ctl[1759]: 13:55:39.086 [info] == Running 20180530125159 Mobilizon.Repo.Migrations.AddAvatarAndBannerToActor.up/0 forward1055server # [ 865.515037] mobilizon_ctl[1759]: 13:55:39.101 [info] alter table actors1056server # [ 865.558018] mobilizon_ctl[1759]: 13:55:39.155 [info] == Migrated 20180530125159 in 0.0s1057server # [ 865.661833] mobilizon_ctl[1759]: 13:55:39.264 [info] == Running 20180530152323 Mobilizon.Repo.Migrations.MakeActorPublicKeyNullable.up/0 forward1058server # [ 865.684945] mobilizon_ctl[1759]: 13:55:39.276 [info] alter table actors1059server # [ 865.721164] mobilizon_ctl[1759]: 13:55:39.321 [info] == Migrated 20180530152323 in 0.0s1060server # [ 865.873825] mobilizon_ctl[1759]: 13:55:39.432 [info] == Running 20180530170808 Mobilizon.Repo.Migrations.AddUUIDIdToEvents.up/0 forward1061server # [ 865.883746] mobilizon_ctl[1759]: 13:55:39.441 [info] alter table events1062server # [ 865.898154] mobilizon_ctl[1759]: 13:55:39.485 [info] == Migrated 20180530170808 in 0.0s1063server # [ 865.978570] mobilizon_ctl[1759]: 13:55:39.584 [info] == Running 20180531162301 Mobilizon.Repo.Migrations.AddApprovedStatusToMember.up/0 forward1064server # [ 866.000014] mobilizon_ctl[1759]: 13:55:39.593 [info] alter table members1065server # [ 866.031218] mobilizon_ctl[1759]: 13:55:39.629 [info] == Migrated 20180531162301 in 0.0s1066server # [ 866.118556] mobilizon_ctl[1759]: 13:55:39.722 [info] == Running 20180607095732 Mobilizon.Repo.Migrations.AddUUIDToComments.up/0 forward1067server # [ 866.149683] mobilizon_ctl[1759]: 13:55:39.730 [info] alter table comments1068server # [ 866.178258] mobilizon_ctl[1759]: 13:55:39.760 [info] == Migrated 20180607095732 in 0.0s1069server # [ 866.241502] mobilizon_ctl[1759]: 13:55:39.850 [info] == Running 20180614094653 Mobilizon.Repo.Migrations.MakeSharedInboxUrlNullable.up/0 forward1070server # [ 866.269525] mobilizon_ctl[1759]: 13:55:39.869 [info] alter table actors1071server # [ 866.345028] mobilizon_ctl[1759]: 13:55:39.938 [info] == Migrated 20180614094653 in 0.0s1072server # [ 866.417522] mobilizon_ctl[1759]: 13:55:40.027 [info] == Running 20180614104618 Mobilizon.Repo.Migrations.FusionPublicPrivateKeyIntoKeysColumn.up/0 forward1073server # [ 866.453065] mobilizon_ctl[1759]: 13:55:40.048 [info] rename column private_key to keys on table actors1074server # [ 866.474840] mobilizon_ctl[1759]: 13:55:40.069 [info] alter table actors1075server # [ 866.512763] mobilizon_ctl[1759]: 13:55:40.102 [info] == Migrated 20180614104618 in 0.0s1076server # [ 866.596942] mobilizon_ctl[1759]: 13:55:40.198 [info] == Running 20180614131328 Mobilizon.Repo.Migrations.AddAttributedToFieldToEventsAndComments.up/0 forward1077server # [ 866.639771] mobilizon_ctl[1759]: 13:55:40.232 [info] alter table events1078server # [ 866.757727] mobilizon_ctl[1759]: 13:55:40.354 [info] alter table comments1079server # [ 866.878709] mobilizon_ctl[1759]: 13:55:40.445 [info] == Migrated 20180614131328 in 0.2s1080server # [ 866.961199] mobilizon_ctl[1759]: 13:55:40.562 [info] == Running 20180628091920 :"Elixir.Mobilizon.Repo.Migrations.Add-user-confirm-email-fields".up/0 forward1081server # [ 866.986704] mobilizon_ctl[1759]: 13:55:40.578 [info] alter table users1082server # [ 867.045778] mobilizon_ctl[1759]: 13:55:40.641 [info] create index index_unique_users_confirmation_token1083server # [ 867.103931] mobilizon_ctl[1759]: 13:55:40.694 [info] == Migrated 20180628091920 in 0.1s1084server # [ 867.205639] mobilizon_ctl[1759]: 13:55:40.806 [info] == Running 20180628130746 :"Elixir.Mobilizon.Repo.Migrations.Add-user-password-reset-fields".up/0 forward1085server # [ 867.223646] mobilizon_ctl[1759]: 13:55:40.818 [info] alter table users1086server # [ 867.280487] mobilizon_ctl[1759]: 13:55:40.880 [info] create index index_unique_users_reset_password_token1087server # [ 867.350749] mobilizon_ctl[1759]: 13:55:40.942 [info] == Migrated 20180628130746 in 0.1s1088server # [ 867.473002] mobilizon_ctl[1759]: 13:55:41.070 [info] == Running 20180628142835 Mobilizon.Repo.Migrations.RevertAlterEventTimestampsToDateTimeWithTimeZone.up/0 forward1089server # [ 867.494021] mobilizon_ctl[1759]: 13:55:41.081 [info] alter table events1090server # [ 867.526584] mobilizon_ctl[1759]: 13:55:41.122 [info] == Migrated 20180628142835 in 0.0s1091server # [ 867.649283] mobilizon_ctl[1759]: 13:55:41.239 [info] == Running 20180702150922 Mobilizon.Repo.Migrations.AddAddressType.up/0 forward1092server # [ 867.682964] mobilizon_ctl[1759]: 13:55:41.267 [info] alter table events1093server # [ 867.748541] mobilizon_ctl[1759]: 13:55:41.327 [info] drop constraint events_address_id_fkey from table events1094server # [ 867.818825] mobilizon_ctl[1759]: 13:55:41.400 [info] rename column address_id to physical_address_id on table events1095server # [ 867.834966] mobilizon_ctl[1759]: 13:55:41.416 [info] alter table events1096server # [ 867.961583] mobilizon_ctl[1759]: 13:55:41.516 [info] == Migrated 20180702150922 in 0.2s1097server # [ 868.019724] mobilizon_ctl[1759]: 13:55:41.619 [info] == Running 20180702154630 Mobilizon.Repo.Migrations.RemoveSlugForEvent.up/0 forward1098server # [ 868.038830] mobilizon_ctl[1759]: 13:55:41.627 [info] alter table events1099server # [ 868.081806] mobilizon_ctl[1759]: 13:55:41.681 [info] == Migrated 20180702154630 in 0.0s1100server # [ 868.214741] mobilizon_ctl[1759]: 13:55:41.818 [info] == Running 20180706082208 Mobilizon.Repo.Migrations.AlterEventsSetDescriptionAsText.up/0 forward1101server # [ 868.261134] mobilizon_ctl[1759]: 13:55:41.844 [info] alter table events1102server # [ 868.290837] mobilizon_ctl[1759]: 13:55:41.878 [info] == Migrated 20180706082208 in 0.0s1103server # [ 868.411331] mobilizon_ctl[1759]: 13:55:42.007 [info] == Running 20180709154152 Mobilizon.Repo.Migrations.AllowMultipleAccountsForUser.up/0 forward1104server # [ 868.440824] mobilizon_ctl[1759]: 13:55:42.023 [info] alter table actors1105server # [ 868.601781] mobilizon_ctl[1759]: 13:55:42.173 [info] alter table users1106server # [ 868.677416] mobilizon_ctl[1759]: 13:55:42.261 [info] == Migrated 20180709154152 in 0.2s1107server # [ 868.781441] mobilizon_ctl[1759]: 13:55:42.383 [info] == Running 20180802133951 Mobilizon.Repo.Migrations.MakeFollowerTableUnique.up/0 forward1108server # [ 868.823496] mobilizon_ctl[1759]: 13:55:42.387 [info] create index followers_actor_target_actor_unique_index1109server # [ 868.905226] mobilizon_ctl[1759]: 13:55:42.482 [info] == Migrated 20180802133951 in 0.0s1110server # [ 869.063976] mobilizon_ctl[1759]: 13:55:42.617 [info] == Running 20180816093446 Mobilizon.Repo.Migrations.AddPrimaryKeyToMember.up/0 forward1111server # [ 869.079296] mobilizon_ctl[1759]: 13:55:42.646 [info] execute "ALTER TABLE members DROP CONSTRAINT IF EXISTS members_pkey"1112server # [ 869.096046] mobilizon_ctl[1759]: 13:55:42.685 [info] constraint "members_pkey" of relation "members" does not exist, skipping1113server # [ 869.144003] mobilizon_ctl[1759]: 13:55:42.686 [info] drop index if exists members_members_account_id_index_index1114server # [ 869.158541] mobilizon_ctl[1759]: 13:55:42.734 [info] index "members_members_account_id_index_index" does not exist, skipping1115server # [ 869.177992] mobilizon_ctl[1759]: 13:55:42.734 [info] create index members_actor_parent_unique_index1116server # [ 869.244766] mobilizon_ctl[1759]: 13:55:42.838 [info] alter table members1117server # [ 869.523461] mobilizon_ctl[1759]: 13:55:43.099 [info] == Migrated 20180816093446 in 0.4s1118server # [ 869.665180] mobilizon_ctl[1759]: 13:55:43.270 [info] == Running 20181021161324 Mobilizon.Repo.Migrations.AddDefaultActorUserField.up/0 forward1119server # [ 869.694998] mobilizon_ctl[1759]: 13:55:43.288 [info] alter table users1120server # [ 869.739307] mobilizon_ctl[1759]: 13:55:43.327 [info] == Migrated 20181021161324 in 0.0s1121server # [ 869.850200] mobilizon_ctl[1759]: 13:55:43.449 [info] == Running 20181108165151 Mobilizon.Repo.Migrations.FixCommentsReferences.up/0 forward1122server # [ 869.893131] mobilizon_ctl[1759]: 13:55:43.473 [info] drop constraint comments_in_reply_to_comment_id_fkey from table comments1123server: Guest root shell did not produce any data yet...1124server: To debug, enter the VM and run 'systemctl status backdoor.service'.1125server # [ 869.965228] mobilizon_ctl[1759]: 13:55:43.550 [info] drop constraint comments_origin_comment_id_fkey from table comments1126server # [ 870.079012] mobilizon_ctl[1759]: 13:55:43.618 [info] alter table comments1127server # [ 870.204927] mobilizon_ctl[1759]: 13:55:43.796 [info] == Migrated 20181108165151 in 0.3s1128server # [ 870.353619] mobilizon_ctl[1759]: 13:55:43.946 [info] == Running 20181203092128 Mobilizon.Repo.Migrations.ChangeActorsIndexes.up/0 forward1129server # [ 870.387233] mobilizon_ctl[1759]: 13:55:43.976 [info] drop index actors_preferred_username_domain_index1130server # [ 870.424537] mobilizon_ctl[1759]: 13:55:44.017 [info] drop index accounts_username_domain_index1131server # [ 870.471774] mobilizon_ctl[1759]: 13:55:44.054 [info] execute "ALTER INDEX accounts_pkey RENAME TO actors_pkey"1132server # [ 870.513198] mobilizon_ctl[1759]: 13:55:44.102 [info] create index actors_preferred_username_domain_type_index1133server # [ 870.579058] mobilizon_ctl[1759]: 13:55:44.178 [info] create index actors_url_index1134server # [ 870.685619] mobilizon_ctl[1759]: 13:55:44.261 [info] == Migrated 20181203092128 in 0.3s1135server # [ 870.839948] mobilizon_ctl[1759]: 13:55:44.398 [info] == Running 20190103150805 Mobilizon.Repo.Migrations.FixEventVisibility.up/0 forward1136server # [ 870.876917] mobilizon_ctl[1759]: 13:55:44.446 [info] execute "CREATE TYPE public.event_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1137server # [ 870.940932] mobilizon_ctl[1759]: 13:55:44.514 [info] execute "CREATE TYPE public.event_status AS ENUM ('tentative', 'confirmed', 'cancelled')"1138server # [ 870.964880] mobilizon_ctl[1759]: 13:55:44.538 [info] execute "CREATE TYPE public.comment_visibility AS ENUM ('public', 'unlisted', 'private', 'moderated', 'invite')"1139server # [ 870.982224] mobilizon_ctl[1759]: 13:55:44.554 [info] alter table events1140server # [ 871.066215] mobilizon_ctl[1759]: 13:55:44.637 [info] alter table comments1141server # [ 871.097232] mobilizon_ctl[1759]: 13:55:44.677 [info] == Migrated 20190103150805 in 0.2s1142server # [ 871.201345] mobilizon_ctl[1759]: 13:55:44.799 [info] == Running 20190114162055 Mobilizon.Repo.Migrations.RemoveAddressType.up/0 forward1143server # [ 871.235813] mobilizon_ctl[1759]: 13:55:44.809 [info] execute "ALTER TABLE \"events\" DROP COLUMN IF EXISTS address_type"1144server # [ 871.268868] mobilizon_ctl[1759]: 13:55:44.835 [info] column "address_type" of relation "events" does not exist, skipping1145server # [ 871.290987] mobilizon_ctl[1759]: 13:55:44.841 [info] execute "DROP TYPE IF EXISTS address_type"1146server # [ 871.315425] mobilizon_ctl[1759]: 13:55:44.875 [info] type "address_type" does not exist, skipping1147server # [ 871.325855] mobilizon_ctl[1759]: 13:55:44.876 [info] rename column phone to phone_address on table events1148server # [ 871.345288] mobilizon_ctl[1759]: 13:55:44.900 [info] == Migrated 20190114162055 in 0.0s1149server # [ 871.424943] mobilizon_ctl[1759]: 13:55:45.026 [info] == Running 20190125143718 Mobilizon.Repo.Migrations.GroupDeleteMembersCascade.change/0 forward1150server # [ 871.444953] mobilizon_ctl[1759]: 13:55:45.042 [info] drop constraint members_account_id_fkey from table members1151server # [ 871.499118] mobilizon_ctl[1759]: 13:55:45.101 [info] drop constraint members_parent_id_fkey from table members1152server # [ 871.552823] mobilizon_ctl[1759]: 13:55:45.150 [info] alter table members1153server # [ 871.770055] mobilizon_ctl[1759]: 13:55:45.368 [info] == Migrated 20190125143718 in 0.3s1154server # [ 871.853802] mobilizon_ctl[1759]: 13:55:45.457 [info] == Running 20190130151607 Mobilizon.Repo.Migrations.SplitEventVisibilityAndJoinOptions.up/0 forward1155server # [ 871.880476] mobilizon_ctl[1759]: 13:55:45.480 [info] execute "ALTER TABLE events ALTER COLUMN visibility TYPE VARCHAR USING visibility::text"1156server # [ 872.210960] mobilizon_ctl[1759]: 13:55:45.753 [info] execute "DROP TYPE public.event_visibility"1157server # [ 872.236772] mobilizon_ctl[1759]: 13:55:45.790 [info] execute "CREATE TYPE public.event_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1158server # [ 872.271070] mobilizon_ctl[1759]: 13:55:45.850 [info] execute "ALTER TABLE events ALTER COLUMN visibility TYPE event_visibility USING visibility::event_visibility"1159server # [ 872.529845] mobilizon_ctl[1759]: 13:55:46.102 [info] execute "CREATE TYPE public.join_options AS ENUM ('free', 'restricted', 'invite', 'external')"1160server # [ 872.543545] mobilizon_ctl[1759]: 13:55:46.126 [info] alter table events1161server # [ 872.611425] mobilizon_ctl[1759]: 13:55:46.202 [info] execute "UPDATE events SET visibility = 'public' WHERE visibility IS NULL"1162server # [ 872.655463] mobilizon_ctl[1759]: 13:55:46.254 [info] alter table events1163server # [ 872.725587] mobilizon_ctl[1759]: 13:55:46.315 [info] == Migrated 20190130151607 in 0.8s1164server # [ 872.910220] mobilizon_ctl[1759]: 13:55:46.505 [info] == Running 20190207134142 Mobilizon.Repo.Migrations.MoveParticipantRoleToEnum.up/0 forward1165server # [ 872.944956] mobilizon_ctl[1759]: 13:55:46.533 [info] execute "CREATE TYPE public.participant_role AS ENUM ('not_approved', 'not_confirmed', 'rejected', 'participant', 'moderator', 'administrator', 'creator')"1166server # [ 872.989137] mobilizon_ctl[1759]: 13:55:46.561 [info] alter table participants1167server # [ 873.036941] mobilizon_ctl[1759]: 13:55:46.638 [info] execute "UPDATE participants set role_tmp = 'not_approved' where role = 0"1168server # [ 873.113196] mobilizon_ctl[1759]: 13:55:46.702 [info] execute "UPDATE participants set role_tmp = 'participant' where role = 1"1169server # [ 873.211532] mobilizon_ctl[1759]: 13:55:46.736 [info] execute "UPDATE participants set role_tmp = 'moderator' where role = 2"1170server # [ 873.238393] mobilizon_ctl[1759]: 13:55:46.760 [info] execute "UPDATE participants set role_tmp = 'administrator' where role = 3"1171server # [ 873.254669] mobilizon_ctl[1759]: 13:55:46.781 [info] execute "UPDATE participants set role_tmp = 'creator' where role = 4"1172server # [ 873.299795] mobilizon_ctl[1759]: 13:55:46.821 [info] alter table participants1173server # [ 873.328916] mobilizon_ctl[1759]: 13:55:46.877 [info] rename column role_tmp to role on table participants1174server # [ 873.357308] mobilizon_ctl[1759]: 13:55:46.898 [info] == Migrated 20190207134142 in 0.3s1175server # [ 873.433808] mobilizon_ctl[1759]: 13:55:47.027 [info] == Running 20190208141700 Mobilizon.Repo.Migrations.TagsRelations.up/0 forward1176server # [ 873.472780] mobilizon_ctl[1759]: 13:55:47.061 [info] create table tag_relations1177server # [ 873.647034] mobilizon_ctl[1759]: 13:55:47.230 [info] create check constraint no_self_loops_check on table tag_relations1178server # [ 873.699279] mobilizon_ctl[1759]: 13:55:47.286 [info] create index index_tag_relations_tag_id1179server # [ 873.770449] mobilizon_ctl[1759]: 13:55:47.366 [info] create index index_tag_relations_link_id1180server # [ 873.872831] mobilizon_ctl[1759]: 13:55:47.465 [info] == Migrated 20190208141700 in 0.4s1181server # [ 873.971436] mobilizon_ctl[1759]: 13:55:47.574 [info] == Running 20190214100858 Mobilizon.Repo.Migrations.DropDatetimetz.up/0 forward1182server # [ 874.017685] mobilizon_ctl[1759]: 13:55:47.603 [info] alter table events1183server # [ 874.105696] mobilizon_ctl[1759]: 13:55:47.695 [info] alter table sessions1184server # [ 874.237399] mobilizon_ctl[1759]: 13:55:47.775 [info] execute "DROP TYPE datetimetz"1185server # [ 874.258263] mobilizon_ctl[1759]: 13:55:47.831 [info] == Migrated 20190214100858 in 0.2s1186server # [ 874.361455] mobilizon_ctl[1759]: #########################################################1187server # [ 874.374258] mobilizon_ctl[1759]: # If the CREATE EXTENSION or DROP EXTENSION calls fail, #1188server # [ 874.381690] mobilizon_ctl[1759]: # please manually execute them with an authorized #1189server # [ 874.394180] mobilizon_ctl[1759]: # PostgreSQL user with SUPER USER role. #1190server # [ 874.406134] mobilizon_ctl[1759]: #########################################################1191server # [ 874.417866] mobilizon_ctl[1759]:1192server # [ 874.430750] mobilizon_ctl[1759]:1193server # [ 874.442935] mobilizon_ctl[1759]: 13:55:47.973 [info] == Running 20190219164310 Mobilizon.Repo.Migrations.CreateSearchIndexes.change/0 forward1194server # [ 874.461658] mobilizon_ctl[1759]: 13:55:47.990 [info] execute "CREATE EXTENSION IF NOT EXISTS pg_trgm"1195server # [ 874.476978] mobilizon_ctl[1759]: 13:55:48.041 [info] extension "pg_trgm" already exists, skipping1196server # [ 874.531173] mobilizon_ctl[1759]: 13:55:48.042 [info] execute "CREATE EXTENSION IF NOT EXISTS unaccent"1197server # [ 874.550016] mobilizon_ctl[1759]: 13:55:48.102 [info] extension "unaccent" already exists, skipping1198server # [ 874.566021] mobilizon_ctl[1759]: 13:55:48.111 [info] execute "CREATE OR REPLACE FUNCTION public.f_unaccent(text)\n RETURNS text AS\n$func$\nSELECT public.unaccent('public.unaccent', $1)\n$func$ LANGUAGE sql IMMUTABLE;"1199server # [ 874.601075] mobilizon_ctl[1759]: 13:55:48.180 [info] execute "CREATE INDEX \"event_title_trigram\" ON \"events\" USING GIN (f_unaccent(title) gin_trgm_ops)"1200server # [ 874.757840] mobilizon_ctl[1759]: 13:55:48.359 [info] execute "CREATE INDEX \"actor_preferred_username_trigram\" ON \"actors\"\n USING GIN (f_unaccent(preferred_username) gin_trgm_ops)"1201server # [ 874.833643] mobilizon_ctl[1759]: 13:55:48.428 [info] execute "CREATE INDEX \"actor_name_trigram\" ON \"actors\"\n USING GIN (f_unaccent(name) gin_trgm_ops)"1202server # [ 874.896929] mobilizon_ctl[1759]: 13:55:48.495 [info] == Migrated 20190219164310 in 0.5s1203server # [ 874.994658] mobilizon_ctl[1759]: 13:55:48.597 [info] == Running 20190222151449 Mobilizon.Repo.Migrations.DropCategories.up/0 forward1204server # [ 875.009011] mobilizon_ctl[1759]: 13:55:48.606 [info] alter table events1205server # [ 875.066478] mobilizon_ctl[1759]: 13:55:48.673 [info] drop table categories1206server # [ 875.144819] mobilizon_ctl[1759]: 13:55:48.742 [info] == Migrated 20190222151449 in 0.1s1207server # [ 875.307951] mobilizon_ctl[1759]: 13:55:48.874 [info] == Running 20190301141830 Mobilizon.Repo.Migrations.MoveMemberRoleToEnum.up/0 forward1208server # [ 875.331911] mobilizon_ctl[1759]: 13:55:48.905 [info] execute "CREATE TYPE public.member_role AS ENUM ('invited', 'not_approved', 'member', 'moderator', 'administrator', 'creator', 'rejected')"1209server # [ 875.357780] mobilizon_ctl[1759]: 13:55:48.937 [info] alter table members1210server # [ 875.379766] mobilizon_ctl[1759]: 13:55:48.989 [info] execute "UPDATE members set role_tmp = 'member' where role = 0"1211server # [ 875.430578] mobilizon_ctl[1759]: 13:55:49.035 [info] execute "UPDATE members set role_tmp = 'moderator' where role = 1"1212server # [ 875.465998] mobilizon_ctl[1759]: 13:55:49.072 [info] execute "UPDATE members set role_tmp = 'creator' where role = 2"1213server # [ 875.500844] mobilizon_ctl[1759]: 13:55:49.102 [info] execute "UPDATE members set role_tmp = 'not_approved' where approved is false"1214server # [ 875.535448] mobilizon_ctl[1759]: 13:55:49.129 [info] alter table members1215server # [ 875.587416] mobilizon_ctl[1759]: 13:55:49.177 [info] rename column role_tmp to role on table members1216server # [ 875.604814] mobilizon_ctl[1759]: 13:55:49.191 [info] == Migrated 20190301141830 in 0.3s1217server # [ 875.679683] mobilizon_ctl[1759]: 13:55:49.289 [info] == Running 20190301143831 Mobilizon.Repo.Migrations.ActorGroupOpenness.up/0 forward1218server # [ 875.807821] mobilizon_ctl[1759]: 13:55:49.381 [info] execute "CREATE TYPE public.actor_openness AS ENUM ('invite_only', 'moderated', 'open')"1219server # [ 875.829197] mobilizon_ctl[1759]: 13:55:49.405 [info] alter table actors1220server # [ 875.905182] mobilizon_ctl[1759]: 13:55:49.487 [info] == Migrated 20190301143831 in 0.1s1221server # [ 876.025046] mobilizon_ctl[1759]: 13:55:49.618 [info] == Running 20190307125009 Mobilizon.Repo.Migrations.MoveUserRoleToEnum.up/0 forward1222server # [ 876.092943] mobilizon_ctl[1759]: 13:55:49.643 [info] execute "CREATE TYPE public.user_role AS ENUM ('administrator', 'moderator', 'user', 'pending')"1223server # [ 876.103023] mobilizon_ctl[1759]: 13:55:49.673 [info] alter table users1224server # [ 876.163382] mobilizon_ctl[1759]: 13:55:49.749 [info] execute "UPDATE users set role_tmp = 'user' where role = 0"1225server # [ 876.244890] mobilizon_ctl[1759]: 13:55:49.817 [info] execute "UPDATE users set role_tmp = 'moderator' where role = 1"1226server # [ 876.271502] mobilizon_ctl[1759]: 13:55:49.845 [info] execute "UPDATE users set role_tmp = 'administrator' where role = 2"1227server # [ 876.362320] mobilizon_ctl[1759]: 13:55:49.889 [info] alter table users1228server # [ 876.375021] mobilizon_ctl[1759]: 13:55:49.945 [info] rename column role_tmp to role on table users1229server # [ 876.400711] mobilizon_ctl[1759]: 13:55:49.970 [info] == Migrated 20190307125009 in 0.3s1230server # [ 876.498403] mobilizon_ctl[1759]: 13:55:50.102 [info] == Running 20190307133518 Mobilizon.Repo.Migrations.FeedTokenTable.change/0 forward1231server # [ 876.737792] mobilizon_ctl[1759]: 13:55:50.329 [info] create table feed_tokens1232server # [ 876.886971] mobilizon_ctl[1759]: 13:55:50.477 [info] == Migrated 20190307133518 in 0.3s1233server # [ 876.977060] mobilizon_ctl[1759]: 13:55:50.572 [info] == Running 20190322143724 Mobilizon.Repo.Migrations.RenameAddressFields.change/0 forward1234server # [ 877.009458] mobilizon_ctl[1759]: 13:55:50.582 [info] rename column addressCountry to country on table addresses1235server # [ 877.052935] mobilizon_ctl[1759]: 13:55:50.637 [info] rename column addressLocality to locality on table addresses1236server # [ 877.063066] mobilizon_ctl[1759]: 13:55:50.657 [info] rename column addressRegion to region on table addresses1237server # [ 877.116853] mobilizon_ctl[1759]: 13:55:50.693 [info] rename column postalCode to postal_code on table addresses1238server # [ 877.148796] mobilizon_ctl[1759]: 13:55:50.711 [info] rename column streetAddress to street on table addresses1239server # [ 877.157931] mobilizon_ctl[1759]: 13:55:50.734 [info] == Migrated 20190322143724 in 0.1s1240server # [ 877.239281] mobilizon_ctl[1759]: 13:55:50.838 [info] == Running 20190411124138 Mobilizon.Repo.Migrations.EventAddDescriptionSlug.change/0 forward1241server # [ 877.262163] mobilizon_ctl[1759]: 13:55:50.851 [info] alter table events1242server # [ 877.301655] mobilizon_ctl[1759]: 13:55:50.894 [info] == Migrated 20190411124138 in 0.0s1243server # [ 877.455707] mobilizon_ctl[1759]: 13:55:51.023 [info] == Running 20190411161557 Mobilizon.Repo.Migrations.EventEventTagOnDelete.up/0 forward1244server # [ 877.481009] mobilizon_ctl[1759]: 13:55:51.034 [info] drop constraint events_tags_event_id_fkey from table events_tags1245server # [ 877.503805] mobilizon_ctl[1759]: 13:55:51.097 [info] drop constraint events_tags_tag_id_fkey from table events_tags1246server # [ 877.554464] mobilizon_ctl[1759]: 13:55:51.151 [info] alter table events_tags1247server # [ 877.693226] mobilizon_ctl[1759]: 13:55:51.286 [info] == Migrated 20190411161557 in 0.2s1248server # [ 877.805405] mobilizon_ctl[1759]: 13:55:51.401 [info] == Running 20190425075451 Mobilizon.Repo.Migrations.AddVisibilityToActor.up/0 forward1249server # [ 877.818451] mobilizon_ctl[1759]: 13:55:51.414 [info] execute "CREATE TYPE public.actor_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1250server # [ 877.859146] mobilizon_ctl[1759]: 13:55:51.462 [info] alter table actors1251server # [ 877.937391] mobilizon_ctl[1759]: 13:55:51.525 [info] == Migrated 20190425075451 in 0.1s1252server # [ 878.043158] mobilizon_ctl[1759]: 13:55:51.642 [info] == Running 20190426093202 Mobilizon.Repo.Migrations.CreatePictures.up/0 forward1253server # [ 878.086186] mobilizon_ctl[1759]: 13:55:51.683 [info] create table pictures1254server # [ 878.284778] mobilizon_ctl[1759]: 13:55:51.888 [info] alter table actors1255server # [ 878.367839] mobilizon_ctl[1759]: 13:55:51.958 [info] alter table events1256server # [ 878.559511] mobilizon_ctl[1759]: 13:55:52.100 [info] == Migrated 20190426093202 in 0.4s1257server # [ 878.619118] mobilizon_ctl[1759]: 13:55:52.223 [info] == Running 20190531131458 :"Elixir.Mobilizon.Repo.Migrations.Attach-pictures-to-actors".change/0 forward1258server # [ 878.658737] mobilizon_ctl[1759]: 13:55:52.246 [info] alter table pictures1259server # [ 878.797757] mobilizon_ctl[1759]: 13:55:52.384 [info] == Migrated 20190531131458 in 0.1s1260server # [ 878.906412] mobilizon_ctl[1759]: 13:55:52.499 [info] == Running 20190712125833 Mobilizon.Repo.Migrations.CreateReports.up/0 forward1261server # [ 879.098939] mobilizon_ctl[1759]: 13:55:52.658 [info] execute "CREATE TYPE public.report_status AS ENUM ('open', 'closed', 'resolved')"1262server # [ 879.110495] mobilizon_ctl[1759]: 13:55:52.694 [info] create table reports1263server # [ 879.470979] mobilizon_ctl[1759]: 13:55:53.058 [info] create table reports_comments1264server # [ 879.682949] mobilizon_ctl[1759]: 13:55:53.193 [info] == Migrated 20190712125833 in 0.6s1265server # [ 879.753574] mobilizon_ctl[1759]: 13:55:53.350 [info] == Running 20190712132438 Mobilizon.Repo.Migrations.CreateReportNotes.change/0 forward1266server # [ 879.779933] mobilizon_ctl[1759]: 13:55:53.370 [info] create table report_notes1267server # [ 880.003284] mobilizon_ctl[1759]: 13:55:53.590 [info] == Migrated 20190712132438 in 0.2s1268server # [ 880.147698] mobilizon_ctl[1759]: 13:55:53.744 [info] == Running 20190718122556 Mobilizon.Repo.Migrations.CreateAdminActionLogs.change/0 forward1269server # [ 880.177944] mobilizon_ctl[1759]: 13:55:53.768 [info] create table admin_action_logs1270server # [ 880.380844] mobilizon_ctl[1759]: 13:55:53.974 [info] == Migrated 20190718122556 in 0.2s1271server # [ 880.466943] mobilizon_ctl[1759]: 13:55:54.070 [info] == Running 20190729125532 :"Elixir.Mobilizon.Repo.Migrations.Add-url-to-addresses".change/0 forward1272server # [ 880.493033] mobilizon_ctl[1759]: 13:55:54.078 [info] alter table addresses1273server # [ 880.523500] mobilizon_ctl[1759]: 13:55:54.122 [info] == Migrated 20190729125532 in 0.0s1274server # [ 880.618959] mobilizon_ctl[1759]: 13:55:54.215 [info] == Running 20190806131306 Mobilizon.Repo.Migrations.AddUrlToFollowsAndMoveToUuid.up/0 forward1275server # [ 880.693855] mobilizon_ctl[1759]: 13:55:54.230 [info] alter table followers1276server # [ 880.819383] mobilizon_ctl[1759]: 13:55:54.413 [info] == Migrated 20190806131306 in 0.1s1277server # [ 880.937078] mobilizon_ctl[1759]: 13:55:54.534 [info] == Running 20190813160207 Mobilizon.Repo.Migrations.AddUrlAndUuidToParticipants.up/0 forward1278server # [ 880.978256] mobilizon_ctl[1759]: 13:55:54.574 [info] drop index participants_event_id_index1279server # [ 881.009055] mobilizon_ctl[1759]: 13:55:54.610 [info] drop index if exists participants_account_id_index1280server # [ 881.047402] mobilizon_ctl[1759]: 13:55:54.637 [info] drop index if exists participants_actor_id_index1281server # [ 881.063252] mobilizon_ctl[1759]: 13:55:54.654 [info] index "participants_actor_id_index" does not exist, skipping1282server # [ 881.089502] mobilizon_ctl[1759]: 13:55:54.654 [info] drop constraint participants_event_id_fkey from table participants1283server # [ 881.122268] mobilizon_ctl[1759]: 13:55:54.726 [info] drop constraint if exists participants_account_id_fkey from table participants1284server # [ 881.188842] mobilizon_ctl[1759]: 13:55:54.790 [info] drop constraint if exists participants_actor_id_fkey from table participants1285server # [ 881.227218] mobilizon_ctl[1759]: 13:55:54.822 [info] constraint "participants_actor_id_fkey" of relation "participants" does not exist, skipping1286server # [ 881.243990] mobilizon_ctl[1759]: 13:55:54.823 [info] drop constraint participants_pkey from table participants1287server # [ 881.287433] mobilizon_ctl[1759]: 13:55:54.869 [info] alter table participants1288server # [ 881.538452] mobilizon_ctl[1759]: 13:55:55.133 [info] create index participants_event_id_index1289server # [ 881.605154] mobilizon_ctl[1759]: 13:55:55.206 [info] create index participants_actor_id_index1290server # [ 881.720016] mobilizon_ctl[1759]: 13:55:55.273 [info] == Migrated 20190813160207 in 0.7s1291server # [ 881.825953] mobilizon_ctl[1759]: 13:55:55.426 [info] == Running 20190821161658 Mobilizon.Repo.Migrations.AddOriginIdToAddresses.change/0 forward1292server # [ 881.871486] mobilizon_ctl[1759]: 13:55:55.450 [info] alter table addresses1293server # [ 881.919626] mobilizon_ctl[1759]: 13:55:55.514 [info] create index addresses_origin_id_index1294server # [ 882.005851] mobilizon_ctl[1759]: 13:55:55.589 [info] == Migrated 20190821161658 in 0.1s1295server # [ 882.093949] mobilizon_ctl[1759]: 13:55:55.697 [info] == Running 20190823132816 Mobilizon.Repo.Migrations.RemoveEventsWhenDeletingOrganizer.up/0 forward1296server # [ 882.130633] mobilizon_ctl[1759]: 13:55:55.726 [info] drop constraint events_organizer_account_id_fkey from table events1297server # [ 882.198917] mobilizon_ctl[1759]: 13:55:55.794 [info] alter table events1298server # [ 882.370629] mobilizon_ctl[1759]: 13:55:55.950 [info] == Migrated 20190823132816 in 0.2s1299server # [ 882.465250] mobilizon_ctl[1759]: 13:55:56.066 [info] == Running 20190902082656 Mobilizon.Repo.Migrations.AddOptionsToEvent.change/0 forward1300server # [ 882.511567] mobilizon_ctl[1759]: 13:55:56.092 [info] alter table events1301server # [ 882.547380] mobilizon_ctl[1759]: 13:55:56.133 [info] == Migrated 20190902082656 in 0.0s1302server # [ 882.727422] mobilizon_ctl[1759]: 13:55:56.261 [info] == Running 20190929170817 Mobilizon.Storage.Repo.Migrations.RenamePostgresTypes.up/0 forward1303server # [ 883.017125] mobilizon_ctl[1759]: 13:55:56.602 [info] == Migrated 20190929170817 in 0.3s1304server # [ 883.130470] mobilizon_ctl[1759]: 13:55:56.722 [info] == Running 20190929175229 Mobilizon.Storage.Repo.Migrations.AddRejectedToParticipantRole.up/0 forward1305server # [ 883.156831] mobilizon_ctl[1759]: 13:55:56.731 [info] execute "ALTER TYPE public.participant_role ADD VALUE IF NOT EXISTS 'rejected'"1306server # [ 883.179536] mobilizon_ctl[1759]: 13:55:56.773 [info] enum label "rejected" already exists, skipping1307server # [ 883.195051] mobilizon_ctl[1759]: 13:55:56.774 [info] == Migrated 20190929175229 in 0.0s1308server # [ 883.311527] mobilizon_ctl[1759]: 13:55:56.902 [info] == Running 20191001074442 Mobilizon.Storage.Repo.Migrations.AddLocaleToUsers.change/0 forward1309server # [ 883.335358] mobilizon_ctl[1759]: 13:55:56.918 [info] alter table users1310server # [ 883.387516] mobilizon_ctl[1759]: 13:55:56.978 [info] == Migrated 20191001074442 in 0.0s1311server # [ 883.501311] mobilizon_ctl[1759]: 13:55:57.101 [info] == Running 20191002093208 Mobilizon.Storage.Repo.Migrations.AddDraftFlagToEvents.change/0 forward1312server # [ 883.537227] mobilizon_ctl[1759]: 13:55:57.126 [info] alter table events1313server # [ 883.599691] mobilizon_ctl[1759]: 13:55:57.191 [info] == Migrated 20191002093208 in 0.0s1314server # [ 883.759486] mobilizon_ctl[1759]: 13:55:57.327 [info] == Running 20191018075904 Mobilizon.Storage.Repo.Migrations.MoveSQLColumnsFromVarCharToText.up/0 forward1315server # [ 883.796019] mobilizon_ctl[1759]: 13:55:57.359 [info] alter table events1316server # [ 883.972817] mobilizon_ctl[1759]: 13:55:57.572 [info] alter table addresses1317server # [ 883.997838] mobilizon_ctl[1759]: 13:55:57.607 [info] alter table bots1318server # [ 884.041792] mobilizon_ctl[1759]: 13:55:57.642 [info] alter table report_notes1319server # [ 884.140783] mobilizon_ctl[1759]: 13:55:57.721 [info] alter table reports1320server # [ 884.169624] mobilizon_ctl[1759]: 13:55:57.763 [info] alter table sessions1321server # [ 884.230743] mobilizon_ctl[1759]: 13:55:57.828 [info] alter table tracks1322server # [ 884.265918] mobilizon_ctl[1759]: 13:55:57.863 [info] == Migrated 20191018075904 in 0.5s1323server # [ 884.377395] mobilizon_ctl[1759]: 13:55:57.982 [info] == Running 20191022083446 Mobilizon.Storage.Repo.Migrations.AddEagerMaterializedViewForSearchingEvents.up/0 forward1324server # [ 884.411467] mobilizon_ctl[1759]: 13:55:58.003 [info] create table event_search1325server # [ 884.556566] mobilizon_ctl[1759]: 13:55:58.152 [info] create index event_search_document_index1326server # [ 884.608877] mobilizon_ctl[1759]: 13:55:58.212 [info] execute "CREATE INDEX event_search_title_trgm_index ON event_search USING gin (title gin_trgm_ops)"1327server # [ 884.664836] mobilizon_ctl[1759]: 13:55:58.266 [info] create index event_search_id_index1328server # [ 884.771819] mobilizon_ctl[1759]: 13:55:58.317 [info] == Migrated 20191022083446 in 0.3s1329server # [ 884.829663] mobilizon_ctl[1759]: 13:55:58.430 [info] == Running 20191023133805 Mobilizon.Storage.Repo.Migrations.DeleteActorsCascadeToFollowers.up/0 forward1330server # [ 884.857799] mobilizon_ctl[1759]: 13:55:58.458 [info] drop constraint followers_actor_id_fkey from table followers1331server # [ 884.910225] mobilizon_ctl[1759]: 13:55:58.505 [info] drop constraint followers_target_actor_id_fkey from table followers1332server # [ 884.955596] mobilizon_ctl[1759]: 13:55:58.557 [info] alter table followers1333server # [ 885.135790] mobilizon_ctl[1759]: 13:55:58.726 [info] == Migrated 20191023133805 in 0.2s1334server # [ 885.237220] mobilizon_ctl[1759]: 13:55:58.841 [info] == Running 20191023143659 Mobilizon.Storage.Repo.Migrations.MoveParticipantsStatsToEvent.up/0 forward1335server # [ 885.251030] mobilizon_ctl[1759]: 13:55:58.850 [info] alter table events1336server # [ 885.304902] mobilizon_ctl[1759]: 13:55:58.893 [info] == Migrated 20191023143659 in 0.0s1337server # [ 885.398066] mobilizon_ctl[1759]: 13:55:59.008 [info] == Running 20191024204726 Mobilizon.Storage.Repo.Migrations.AddTagsToComments.up/0 forward1338server # [ 885.441257] mobilizon_ctl[1759]: 13:55:59.037 [info] create table comments_tags1339server # [ 885.583347] mobilizon_ctl[1759]: 13:55:59.167 [info] == Migrated 20191024204726 in 0.1s1340server # [ 885.682260] mobilizon_ctl[1759]: 13:55:59.286 [info] == Running 20191025083642 Mobilizon.Storage.Repo.Migrations.AddMentionTables.change/0 forward1341server # [ 885.716854] mobilizon_ctl[1759]: 13:55:59.318 [info] create table mentions1342server # [ 885.991819] mobilizon_ctl[1759]: 13:55:59.538 [info] create index mentions_actor_id_index1343server # [ 886.021447] mobilizon_ctl[1759]: 13:55:59.599 [info] == Migrated 20191025083642 in 0.3s1344server # [ 886.117258] mobilizon_ctl[1759]: 13:55:59.716 [info] == Running 20191025115537 Mobilizon.Storage.Repo.Migrations.AddUniqueIndexOnURLs.change/0 forward1345server # [ 886.144062] mobilizon_ctl[1759]: 13:55:59.735 [info] create index events_url_index1346server # [ 886.227070] mobilizon_ctl[1759]: 13:55:59.810 [info] create index comments_url_index1347server # [ 886.287469] mobilizon_ctl[1759]: 13:55:59.879 [info] == Migrated 20191025115537 in 0.1s1348server # [ 886.383115] mobilizon_ctl[1759]: 13:55:59.992 [info] == Running 20191031142145 Mobilizon.Storage.Repo.Migrations.AddUniqueIndexOnActorAndEventForParticipant.change/0 forward1349server # [ 886.410691] mobilizon_ctl[1759]: 13:56:00.005 [info] create index participants_event_id_actor_id_index1350server # [ 886.468966] mobilizon_ctl[1759]: 13:56:00.062 [info] == Migrated 20191031142145 in 0.0s1351server # [ 886.549447] mobilizon_ctl[1759]: 13:56:00.154 [info] == Running 20191104093447 Mobilizon.Storage.Repo.Migrations.AddObanJobsTable.up/0 forward1352server # [ 888.698887] mobilizon_ctl[1759]: 13:56:02.217 [info] execute "DO $$\nBEGIN\nIF NOT EXISTS (SELECT 1 FROM pg_type\n WHERE typname = 'oban_job_state'\n AND typnamespace = 'public'::regnamespace::oid) THEN\n CREATE TYPE \"public\".oban_job_state AS ENUM (\n 'available',\n 'scheduled',\n 'executing',\n 'retryable',\n 'completed',\n 'discarded'\n );\n END IF;\nEND$$;\n"1353server # [ 888.767542] mobilizon_ctl[1759]: 13:56:02.355 [info] create table if not exists public.oban_jobs1354server # [ 889.050193] mobilizon_ctl[1759]: 13:56:02.626 [info] create index if not exists public.oban_jobs_queue_index1355server # [ 889.121154] mobilizon_ctl[1759]: 13:56:02.718 [info] create index if not exists public.oban_jobs_state_index1356server # [ 889.233798] mobilizon_ctl[1759]: 13:56:02.827 [info] create index if not exists public.oban_jobs_scheduled_at_index1357server # [ 889.307278] mobilizon_ctl[1759]: 13:56:02.902 [info] execute "CREATE OR REPLACE FUNCTION \"public\".oban_jobs_notify() RETURNS trigger AS $$\nDECLARE\n channel text;\n notice json;\nBEGIN\n IF (TG_OP = 'INSERT') THEN\n channel = 'public.oban_insert';\n notice = json_build_object('queue', NEW.queue, 'state', NEW.state);\n\n -- No point triggering for a job that isn't scheduled to run now\n IF NEW.scheduled_at IS NOT NULL AND NEW.scheduled_at > now() AT TIME ZONE 'utc' THEN\n RETURN null;\n END IF;\n ELSE\n channel = 'public.oban_update';\n notice = json_build_object('queue', NEW.queue, 'new_state', NEW.state, 'old_state', OLD.state);\n END IF;\n\n PERFORM pg_notify(channel, notice::text);\n\n RETURN NULL;\nEND;\n$$ LANGUAGE plpgsql;\n"1358server # [ 889.371557] mobilizon_ctl[1759]: 13:56:02.981 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"1359server # [ 889.445036] mobilizon_ctl[1759]: 13:56:03.037 [info] trigger "oban_notify" for relation "public.oban_jobs" does not exist, skipping1360server # [ 889.457445] mobilizon_ctl[1759]: 13:56:03.039 [info] execute "CREATE TRIGGER oban_notify\nAFTER INSERT OR UPDATE OF state ON \"public\".oban_jobs\nFOR EACH ROW EXECUTE PROCEDURE \"public\".oban_jobs_notify();\n"1361server # [ 889.521165] mobilizon_ctl[1759]: 13:56:03.098 [info] drop index if exists public.oban_jobs_scheduled_at_index1362server # [ 889.552948] mobilizon_ctl[1759]: 13:56:03.149 [info] create index public.oban_jobs_scheduled_at_index1363server # [ 889.640922] mobilizon_ctl[1759]: 13:56:03.233 [info] create check constraint worker_length on table public.oban_jobs1364server # [ 889.737524] mobilizon_ctl[1759]: 13:56:03.273 [info] create check constraint queue_length on table public.oban_jobs1365server # [ 889.750644] mobilizon_ctl[1759]: 13:56:03.324 [info] execute "CREATE OR REPLACE FUNCTION \"public\".oban_wrap_id(value bigint) RETURNS int AS $$\nBEGIN\n RETURN (CASE WHEN value > 2147483647 THEN mod(value, 2147483647) ELSE value END)::int;\nEND;\n$$ LANGUAGE plpgsql IMMUTABLE;\n"1366server # [ 889.786081] mobilizon_ctl[1759]: 13:56:03.379 [info] alter table public.oban_jobs1367server # [ 889.828459] mobilizon_ctl[1759]: 13:56:03.427 [info] execute "DROP FUNCTION IF EXISTS \"public\".oban_wrap_id(value bigint)"1368server # [ 889.868706] mobilizon_ctl[1759]: 13:56:03.454 [info] drop index if exists public.oban_jobs_scheduled_at_index1369server # [ 889.889736] mobilizon_ctl[1759]: 13:56:03.478 [info] drop index if exists public.oban_jobs_queue_index1370server # [ 889.938814] mobilizon_ctl[1759]: 13:56:03.525 [info] drop index if exists public.oban_jobs_state_index1371server # [ 889.966103] mobilizon_ctl[1759]: 13:56:03.546 [info] create index if not exists public.oban_jobs_queue_state_scheduled_at_id_index1372server # [ 890.038028] mobilizon_ctl[1759]: 13:56:03.636 [info] create index if not exists public.oban_jobs_attempted_at_id_index1373server # [ 890.136878] mobilizon_ctl[1759]: 13:56:03.709 [info] alter table public.oban_jobs1374server # [ 890.205686] mobilizon_ctl[1759]: 13:56:03.779 [info] alter table public.oban_jobs1375server # [ 890.257783] mobilizon_ctl[1759]: 13:56:03.850 [info] drop index if exists public.oban_jobs_queue_state_scheduled_at_id_index1376server # [ 890.297073] mobilizon_ctl[1759]: 13:56:03.892 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index1377server # [ 890.358919] mobilizon_ctl[1759]: 13:56:03.965 [info] execute "CREATE OR REPLACE FUNCTION \"public\".oban_jobs_notify() RETURNS trigger AS $$\nDECLARE\n channel text;\n notice json;\nBEGIN\n IF NEW.state = 'available' THEN\n channel = 'public.oban_insert';\n notice = json_build_object('queue', NEW.queue);\n\n PERFORM pg_notify(channel, notice::text);\n END IF;\n\n RETURN NULL;\nEND;\n$$ LANGUAGE plpgsql;\n"1378server # [ 890.418896] mobilizon_ctl[1759]: 13:56:04.014 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"1379server # [ 890.453278] mobilizon_ctl[1759]: 13:56:04.053 [info] execute "CREATE TRIGGER oban_notify\nAFTER INSERT ON \"public\".oban_jobs\nFOR EACH ROW EXECUTE PROCEDURE \"public\".oban_jobs_notify();\n"1380server # [ 890.495911] mobilizon_ctl[1759]: 13:56:04.082 [info] alter table public.oban_jobs1381server # [ 891.040247] mobilizon_ctl[1759]: 13:56:04.574 [info] execute "DO $$\nDECLARE\n version int;\n already bool;\nBEGIN\n SELECT current_setting('server_version_num')::int INTO version;\n SELECT '{cancelled}' <@ enum_range(NULL::\"public\".oban_job_state)::text[] INTO already;\n\n IF already THEN\n RETURN;\n ELSIF version >= 120000 THEN\n ALTER TYPE \"public\".oban_job_state ADD VALUE IF NOT EXISTS 'cancelled';\n ELSE\n ALTER TYPE \"public\".oban_job_state RENAME TO old_oban_job_state;\n\n CREATE TYPE \"public\".oban_job_state AS ENUM (\n 'available',\n 'scheduled',\n 'executing',\n 'retryable',\n 'completed',\n 'discarded',\n 'cancelled'\n );\n\n ALTER TABLE \"public\".oban_jobs RENAME column state TO _state;\n ALTER TABLE \"public\".oban_jobs ADD state \"public\".oban_job_state NOT NULL default 'available';\n\n UPDATE \"public\".oban_jobs SET state = _state::text::\"public\".oban_job_state;\n\n ALTER TABLE \"public\".oban_jobs DROP column _state;\n DROP TYPE \"public\".old_oban_job_state;\n END IF;\nEND$$;\n"1382server # [ 891.118713] mobilizon_ctl[1759]: 13:56:04.710 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index1383server # [ 891.171057] mobilizon_ctl[1759]: 13:56:04.760 [info] relation "oban_jobs_state_queue_priority_scheduled_at_id_index" already exists, skipping1384server # [ 891.193870] mobilizon_ctl[1759]: 13:56:04.761 [info] alter table public.oban_jobs1385server # [ 891.335155] mobilizon_ctl[1759]: 13:56:04.924 [info] create check constraint priority_range on table public.oban_jobs1386server # [ 891.393076] mobilizon_ctl[1759]: 13:56:04.989 [info] create check constraint positive_max_attempts on table public.oban_jobs1387server # [ 891.451922] mobilizon_ctl[1759]: 13:56:05.037 [info] create check constraint attempt_range on table public.oban_jobs1388server # [ 891.513406] mobilizon_ctl[1759]: 13:56:05.092 [info] drop index if exists public.oban_jobs_args_vector1389server # [ 891.551240] mobilizon_ctl[1759]: 13:56:05.114 [info] index "oban_jobs_args_vector" does not exist, skipping1390server # [ 891.567430] mobilizon_ctl[1759]: 13:56:05.120 [info] drop index if exists public.oban_jobs_worker_gist1391server # [ 891.596588] mobilizon_ctl[1759]: 13:56:05.151 [info] index "oban_jobs_worker_gist" does not exist, skipping1392server # [ 891.610896] mobilizon_ctl[1759]: 13:56:05.157 [info] drop index if exists public.oban_jobs_attempted_at_id_index1393server # [ 891.641206] mobilizon_ctl[1759]: 13:56:05.223 [info] create index if not exists public.oban_jobs_args_index1394server # [ 891.718207] mobilizon_ctl[1759]: 13:56:05.309 [info] create index if not exists public.oban_jobs_meta_index1395server # [ 891.787716] mobilizon_ctl[1759]: 13:56:05.370 [info] create table if not exists public.oban_peers1396server # [ 891.957186] mobilizon_ctl[1759]: 13:56:05.550 [info] execute "ALTER TABLE \"public\".oban_peers SET UNLOGGED"1397server # [ 892.277546] mobilizon_ctl[1759]: 13:56:05.798 [info] drop constraint priority_range from table public.oban_jobs1398server # [ 892.304855] mobilizon_ctl[1759]: 13:56:05.841 [info] create check constraint non_negative_priority on table public.oban_jobs1399server # [ 892.352997] mobilizon_ctl[1759]: 13:56:05.942 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"1400server # [ 892.425459] mobilizon_ctl[1759]: 13:56:05.987 [info] execute "DROP FUNCTION IF EXISTS \"public\".oban_jobs_notify()"1401server # [ 892.441463] mobilizon_ctl[1759]: 13:56:06.022 [info] create index if not exists public.oban_jobs_state_cancelled_at_index1402server # [ 892.551405] mobilizon_ctl[1759]: 13:56:06.138 [info] create index if not exists public.oban_jobs_state_discarded_at_index1403server # [ 892.650669] mobilizon_ctl[1759]: 13:56:06.218 [info] execute "COMMENT ON TABLE \"public\".oban_jobs IS '13'"1404server # [ 892.675843] mobilizon_ctl[1759]: 13:56:06.247 [info] == Migrated 20191104093447 in 6.0s1405server # [ 892.907793] mobilizon_ctl[1759]: 13:56:06.503 [info] == Running 20191106114524 Mobilizon.Storage.Repo.Migrations.RemoveFloorFromAddresses.up/0 forward1406server # [ 892.935135] mobilizon_ctl[1759]: 13:56:06.513 [info] alter table addresses1407server # [ 892.985944] mobilizon_ctl[1759]: 13:56:06.559 [info] == Migrated 20191106114524 in 0.0s1408server # [ 893.098554] mobilizon_ctl[1759]: 13:56:06.698 [info] == Running 20191106141051 Mobilizon.Storage.Repo.Migrations.AddTypeToAddresses.change/0 forward1409server # [ 893.119608] mobilizon_ctl[1759]: 13:56:06.709 [info] alter table addresses1410server # [ 893.162286] mobilizon_ctl[1759]: 13:56:06.753 [info] == Migrated 20191106141051 in 0.0s1411server # [ 893.319521] mobilizon_ctl[1759]: 13:56:06.891 [info] == Running 20191114155534 Mobilizon.Storage.Repo.Migrations.CascadeCommentDeletion.up/0 forward1412server # [ 893.343918] mobilizon_ctl[1759]: 13:56:06.906 [info] drop constraint comments_in_reply_to_comment_id_fkey from table comments1413server # [ 893.379047] mobilizon_ctl[1759]: 13:56:06.984 [info] drop constraint comments_origin_comment_id_fkey from table comments1414server # [ 893.433587] mobilizon_ctl[1759]: 13:56:07.029 [info] alter table comments1415server # [ 893.571526] mobilizon_ctl[1759]: 13:56:07.173 [info] == Migrated 20191114155534 in 0.2s1416server # [ 893.689639] mobilizon_ctl[1759]: 13:56:07.296 [info] == Running 20191122154018 Mobilizon.Storage.Repo.Migrations.AddDeletedAtOnComments.up/0 forward1417server # [ 893.728910] mobilizon_ctl[1759]: 13:56:07.317 [info] drop constraint if exists comments_actor_id_fkey from table comments1418server # [ 893.760921] mobilizon_ctl[1759]: 13:56:07.356 [info] constraint "comments_actor_id_fkey" of relation "comments" does not exist, skipping1419server # [ 893.770027] mobilizon_ctl[1759]: 13:56:07.357 [info] alter table comments1420server # [ 893.945977] mobilizon_ctl[1759]: 13:56:07.540 [info] == Migrated 20191122154018 in 0.2s1421server # [ 894.041768] mobilizon_ctl[1759]: 13:56:07.642 [info] == Running 20191126173510 Mobilizon.Storage.Repo.Migrations.AddLocalFieldToReports.up/0 forward1422server # [ 894.061049] mobilizon_ctl[1759]: 13:56:07.654 [info] alter table reports1423server # [ 894.109117] mobilizon_ctl[1759]: 13:56:07.705 [info] rename column uri to url on table reports1424server # [ 894.143658] mobilizon_ctl[1759]: 13:56:07.726 [info] == Migrated 20191126173510 in 0.0s1425server # [ 894.221708] mobilizon_ctl[1759]: 13:56:07.823 [info] == Running 20191127163737 Mobilizon.Repo.Migrations.CreateTombstones.change/0 forward1426server # [ 894.254261] mobilizon_ctl[1759]: 13:56:07.852 [info] create table tombstones1427server # [ 894.429210] mobilizon_ctl[1759]: 13:56:07.995 [info] create index tombstones_uri_index1428server # [ 894.461245] mobilizon_ctl[1759]: 13:56:08.061 [info] == Migrated 20191127163737 in 0.2s1429server # [ 894.567390] mobilizon_ctl[1759]: 13:56:08.162 [info] == Running 20191129091227 Mobilizon.Storage.Repo.Migrations.AddTimestampsToFollowers.change/0 forward1430server # [ 894.600982] mobilizon_ctl[1759]: 13:56:08.189 [info] alter table followers1431server # [ 894.643140] mobilizon_ctl[1759]: 13:56:08.244 [info] == Migrated 20191129091227 in 0.0s1432server # [ 894.756970] mobilizon_ctl[1759]: 13:56:08.362 [info] == Running 20191204164224 Mobilizon.Storage.Repo.Migrations.DeleteEventCascadeToComments.up/0 forward1433server # [ 894.793223] mobilizon_ctl[1759]: 13:56:08.382 [info] drop constraint if exists comments_event_id_fkey from table comments1434server # [ 894.849951] mobilizon_ctl[1759]: 13:56:08.444 [info] alter table comments1435server # [ 894.953155] mobilizon_ctl[1759]: 13:56:08.544 [info] == Migrated 20191204164224 in 0.1s1436server # [ 895.054692] mobilizon_ctl[1759]: 13:56:08.658 [info] == Running 20191206144028 Mobilizon.Repo.Migrations.CreateShares.up/0 forward1437server # [ 895.104010] mobilizon_ctl[1759]: 13:56:08.689 [info] create table shares1438server # [ 895.292956] mobilizon_ctl[1759]: 13:56:08.889 [info] create index if not exists shares_uri_actor_id_index1439server # [ 895.344849] mobilizon_ctl[1759]: 13:56:08.941 [info] == Migrated 20191206144028 in 0.2s1440server # [ 895.526236] mobilizon_ctl[1759]: 13:56:09.063 [info] == Running 20191219172017 Mobilizon.Storage.Repo.Migrations.AddMetadataToParticipant.up/0 forward1441server # [ 895.541410] mobilizon_ctl[1759]: 13:56:09.082 [info] drop index if exists participants_event_id_actor_id_index1442server # [ 895.558387] mobilizon_ctl[1759]: 13:56:09.121 [info] alter table participants1443server # [ 895.634983] mobilizon_ctl[1759]: 13:56:09.226 [info] execute "CREATE UNIQUE INDEX participants_metadata_confirmation_token_index ON participants((metadata->>'confirmation_token'))"1444server # [ 895.706144] mobilizon_ctl[1759]: 13:56:09.294 [info] == Migrated 20191219172017 in 0.2s1445server # [ 895.819171] mobilizon_ctl[1759]: 13:56:09.400 [info] == Running 20191223173537 Mobilizon.Storage.Repo.Migrations.AddNotConfirmedStatusToParticipants.up/0 forward1446server # [ 895.868629] mobilizon_ctl[1759]: 13:56:09.416 [info] execute "ALTER TYPE participant_role ADD VALUE IF NOT EXISTS 'not_confirmed'"1447server # [ 895.889308] mobilizon_ctl[1759]: 13:56:09.454 [info] enum label "not_confirmed" already exists, skipping1448server # [ 895.929743] mobilizon_ctl[1759]: 13:56:09.468 [info] == Migrated 20191223173537 in 0.0s1449server # [ 896.012953] mobilizon_ctl[1759]: 13:56:09.612 [info] == Running 20200116095117 Mobilizon.Repo.Migrations.CreateAdminSettings.change/0 forward1450server # [ 896.050316] mobilizon_ctl[1759]: 13:56:09.638 [info] create table admin_settings1451server # [ 896.247318] mobilizon_ctl[1759]: 13:56:09.842 [info] create index admin_settings_group_name_index1452server # [ 896.333041] mobilizon_ctl[1759]: 13:56:09.926 [info] == Migrated 20200116095117 in 0.3s1453server # [ 896.532022] mobilizon_ctl[1759]: 13:56:10.090 [info] == Running 20200210100910 Mobilizon.Storage.Repo.Migrations.AddUnconfirmedEmailToUsersTable.change/0 forward1454server # [ 896.563955] mobilizon_ctl[1759]: 13:56:10.110 [info] alter table users1455server # [ 896.617040] mobilizon_ctl[1759]: 13:56:10.179 [info] == Migrated 20200210100910 in 0.0s1456server # [ 896.747902] mobilizon_ctl[1759]: 13:56:10.339 [info] == Running 20200213154309 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV8.up/0 forward1457server # [ 896.817646] postgres[2182]: [2182] LOG: skipping analyze of "schema_migrations" --- lock not available1458server # [ 896.918035] systemd[1]: mobilizon.service: start-pre operation timed out. Terminating.1459server # [ 896.969815] systemd[1]: mobilizon.service: Control process exited, code=killed, status=15/TERM1460server # [ 897.057205] mobilizon_ctl[1759]: 13:56:10.581 [info] == Migrated 20200213154309 in 0.2s1461server # [ 897.461427] mobilizon_ctl[1759]: 13:56:10.549 [notice] SIGTERM received - shutting down1462server # [ 897.587516] mobilizon_ctl[1759]: 13:56:11.002 [notice] SIGTERM received - shutting down1463server # [ 899.027537] mobilizon_ctl[1759]: ** (RuntimeError) could not find migration runner process for #PID<0.1242.0>1464server # [ 899.049701] mobilizon_ctl[1759]: (ecto_sql 3.13.5) lib/ecto/migration/runner.ex:326: Ecto.Migration.Runner.runner/01465server # [ 899.067880] mobilizon_ctl[1759]: (ecto_sql 3.13.5) lib/ecto/migration/runner.ex:64: Ecto.Migration.Runner.stop/01466server # [ 899.081687] mobilizon_ctl[1759]: (ecto_sql 3.13.5) lib/ecto/migration/runner.ex:28: Ecto.Migration.Runner.run/81467server # [ 899.089865] mobilizon_ctl[1759]: (ecto_sql 3.13.5) lib/ecto/migrator.ex:365: Ecto.Migrator.attempt/81468server # [ 899.106551] mobilizon_ctl[1759]: (ecto_sql 3.13.5) lib/ecto/migrator.ex:283: anonymous fn/5 in Ecto.Migrator.do_up/51469server # [ 899.133956] mobilizon_ctl[1759]: (ecto_sql 3.13.5) lib/ecto/migrator.ex:337: anonymous fn/6 in Ecto.Migrator.async_migrate_maybe_in_transaction/71470server # [ 899.145848] mobilizon_ctl[1759]: (mobilizon 5.2.3) lib/mobilizon/storage/repo.ex:4: anonymous fn/1 in Mobilizon.Storage.Repo.transaction/21471server: Guest root shell did not produce any data yet...1472server: To debug, enter the VM and run 'systemctl status backdoor.service'.1473server # [ 900.004830] systemd[1]: mobilizon.service: Failed with result 'timeout'.1474server # [ 900.029669] systemd[1]: Failed to start Mobilizon federated organization and mobilization platform.1475server # [ 900.068006] systemd[1]: mobilizon.service: Consumed 1min 50.573s CPU time over 5min 2.977s wall clock time, 81M memory peak.1476server # [ 900.587705] systemd[1]: mobilizon.service: Scheduled restart job, restart counter is at 1.1477server # [ 900.790164] systemd[1]: Starting Mobilizon PostgreSQL setup...1478server # [ 901.055233] systemd[1]: Starting Cleanup of Temporary Directories...1479server # [ 904.313765] mobilizon-postgresql-start[2191]: NOTICE: extension "postgis" already exists, skipping1480server # [ 904.323290] mobilizon-postgresql-start[2191]: NOTICE: extension "pg_trgm" already exists, skipping1481server # [ 904.365280] mobilizon-postgresql-start[2191]: NOTICE: extension "unaccent" already exists, skipping1482server # [ 904.381330] mobilizon-postgresql-start[2191]: CREATE EXTENSION1483server # [ 904.383185] mobilizon-postgresql-start[2191]: CREATE EXTENSION1484server # [ 904.417558] mobilizon-postgresql-start[2191]: CREATE EXTENSION1485server # [ 905.899695] mobilizon-postgresql-start[2193]: ALTER DATABASE1486server # [ 906.082608] systemd[1]: mobilizon-postgresql.service: Deactivated successfully.1487server # [ 906.094265] systemd[1]: Finished Mobilizon PostgreSQL setup.1488server # [ 906.276946] systemd[1]: Starting Mobilizon setup secrets...1489server # [ 908.067570] systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully.1490server # [ 908.103424] systemd[1]: Finished Cleanup of Temporary Directories.1491server # [ 910.747348] mobilizon-setup-secrets-start[2201]: + '[' '!' -f /var/lib/mobilizon/secret-env.sh ']'1492server # [ 910.821892] systemd[1]: mobilizon-setup-secrets.service: Deactivated successfully.1493server # [ 910.843019] systemd[1]: Finished Mobilizon setup secrets.1494server # [ 910.881453] systemd[1]: mobilizon-setup-secrets.service: Consumed 1.435s CPU time over 4.532s wall clock time, 1.9M memory peak.1495server # [ 911.067466] systemd[1]: Starting Mobilizon federated organization and mobilization platform...1496server # [ 921.642885] postgres[1389]: [1389] LOG: checkpoint complete: wrote 1941 buffers (11.8%); 0 WAL file(s) added, 0 removed, 1 recycled; write=203.033 s, sync=3.145 s, total=206.438 s; sync files=527, longest=0.049 s, average=0.006 s; distance=13867 kB, estimate=13867 kB; lsn=0/246C878, redo lsn=0/2277C381497server: Guest root shell did not produce any data yet...1498server: To debug, enter the VM and run 'systemctl status backdoor.service'.1499server: Guest root shell did not produce any data yet...1500server: To debug, enter the VM and run 'systemctl status backdoor.service'.1501server # [ 960.897220] mobilizon_ctl[2216]: Loading app..1502server # [ 960.924805] mobilizon_ctl[2216]: Starting dependencies..1503server: Guest root shell did not produce any data yet...1504server: To debug, enter the VM and run 'systemctl status backdoor.service'.1505server # [ 1014.811681] postgres[1389]: [1389] LOG: checkpoint starting: time1506server: Guest root shell did not produce any data yet...1507server: To debug, enter the VM and run 'systemctl status backdoor.service'.1508server # [ 1045.810929] postgres[1389]: [1389] LOG: checkpoint complete: wrote 290 buffers (1.8%); 0 WAL file(s) added, 0 removed, 0 recycled; write=30.384 s, sync=0.343 s, total=31.007 s; sync files=100, longest=0.031 s, average=0.004 s; distance=2034 kB, estimate=12684 kB; lsn=0/24747B0, redo lsn=0/24747201509server: Guest root shell did not produce any data yet...1510server: To debug, enter the VM and run 'systemctl status backdoor.service'.1511server # [ 1059.224551] mobilizon_ctl[2216]: 13:58:52.554 [info] == Running 20200214135231 Mobilizon.Storage.Repo.Migrations.AddLastRefreshedAtToActors.change/0 forward1512server # [ 1059.231308] mobilizon_ctl[2216]: 13:58:52.802 [info] alter table actors1513server # [ 1059.421356] mobilizon_ctl[2216]: 13:58:52.985 [info] == Migrated 20200214135231 in 0.1s1514server # [ 1061.406583] mobilizon_ctl[2216]: 13:58:54.964 [info] == Running 20200218085757 Mobilizon.Storage.Repo.Migrations.AddNewRolesToMember.up/0 forward1515server # [ 1061.524867] mobilizon_ctl[2216]: 13:58:55.101 [info] execute "ALTER TYPE member_role ADD VALUE IF NOT EXISTS 'invited'"1516server # [ 1061.555872] mobilizon_ctl[2216]: 13:58:55.141 [info] enum label "invited" already exists, skipping1517server # [ 1061.603916] mobilizon_ctl[2216]: 13:58:55.142 [info] execute "ALTER TYPE member_role ADD VALUE IF NOT EXISTS 'rejected'"1518server # [ 1061.643710] mobilizon_ctl[2216]: 13:58:55.186 [info] enum label "rejected" already exists, skipping1519server # [ 1061.667686] mobilizon_ctl[2216]: 13:58:55.201 [info] == Migrated 20200218085757 in 0.2s1520server # [ 1061.769704] mobilizon_ctl[2216]: 13:58:55.364 [info] == Running 20200218170232 Mobilizon.Storage.Repo.Migrations.MoveMemberIdToUuid.up/0 forward1521server # [ 1061.791453] mobilizon_ctl[2216]: 13:58:55.381 [info] alter table members1522server # [ 1062.111823] mobilizon_ctl[2216]: 13:58:55.697 [info] == Migrated 20200218170232 in 0.3s1523server # [ 1062.234927] mobilizon_ctl[2216]: 13:58:55.833 [info] == Running 20200219152611 Mobilizon.Storage.Repo.Migrations.AddMembersEndpointToActors.up/0 forward1524server # [ 1062.255846] mobilizon_ctl[2216]: 13:58:55.846 [info] alter table actors1525server # [ 1062.334213] mobilizon_ctl[2216]: 13:58:55.922 [info] == Migrated 20200219152611 in 0.0s1526server # [ 1062.505290] mobilizon_ctl[2216]: 13:58:56.058 [info] == Running 20200221095721 Mobilizon.Repo.Migrations.CreateTodos.change/0 forward1527server # [ 1062.536780] mobilizon_ctl[2216]: 13:58:56.117 [info] create table todo_lists1528server # [ 1062.711724] mobilizon_ctl[2216]: 13:58:56.306 [info] create table todos1529server # [ 1062.937258] mobilizon_ctl[2216]: 13:58:56.537 [info] == Migrated 20200221095721 in 0.4s1530server # [ 1063.029083] mobilizon_ctl[2216]: 13:58:56.630 [info] == Running 20200221160744 Mobilizon.Repo.Migrations.CreateResources.change/0 forward1531server # [ 1063.060827] mobilizon_ctl[2216]: 13:58:56.655 [info] create table resource1532server # [ 1063.343822] mobilizon_ctl[2216]: 13:58:56.941 [info] == Migrated 20200221160744 in 0.3s1533server # [ 1063.440922] mobilizon_ctl[2216]: 13:58:57.041 [info] == Running 20200406115807 Mobilizon.Storage.Repo.Migrations.AddConversations.change/0 forward1534server # [ 1063.510900] mobilizon_ctl[2216]: 13:58:57.073 [info] create table conversations1535server # [ 1063.725267] mobilizon_ctl[2216]: 13:58:57.325 [info] alter table comments1536server # [ 1063.837011] mobilizon_ctl[2216]: 13:58:57.433 [info] == Migrated 20200406115807 in 0.3s1537server # [ 1063.937201] mobilizon_ctl[2216]: 13:58:57.534 [info] == Running 20200410100554 Mobilizon.Storage.Repo.Migrations.AddNumberEditsToComment.change/0 forward1538server # [ 1063.954640] mobilizon_ctl[2216]: 13:58:57.545 [info] alter table comments1539server # [ 1064.009552] mobilizon_ctl[2216]: 13:58:57.608 [info] == Migrated 20200410100554 in 0.0s1540server # [ 1064.114465] mobilizon_ctl[2216]: 13:58:57.721 [info] == Running 20200513142813 Mobilizon.Storage.Repo.Migrations.AddResourcesAndTodosEndpointsToActor.change/0 forward1541server # [ 1064.142425] mobilizon_ctl[2216]: 13:58:57.730 [info] alter table actors1542server # [ 1064.190969] mobilizon_ctl[2216]: 13:58:57.790 [info] == Migrated 20200513142813 in 0.0s1543server # [ 1064.293615] mobilizon_ctl[2216]: 13:58:57.886 [info] == Running 20200514101612 Mobilizon.Storage.Repo.Migrations.AddLocalAttributeToResourcesAndTodos.change/0 forward1544server # [ 1064.318815] mobilizon_ctl[2216]: 13:58:57.901 [info] alter table resource1545server # [ 1064.354772] mobilizon_ctl[2216]: 13:58:57.954 [info] alter table todo_lists1546server # [ 1064.398842] mobilizon_ctl[2216]: 13:58:57.993 [info] alter table todos1547server # [ 1064.459359] mobilizon_ctl[2216]: 13:58:58.050 [info] == Migrated 20200514101612 in 0.1s1548server # [ 1064.598696] mobilizon_ctl[2216]: 13:58:58.162 [info] == Running 20200518084949 Mobilizon.Storage.Repo.Migrations.MoveInviterFromMetadataToMemberTable.change/0 forward1549server # [ 1064.615350] mobilizon_ctl[2216]: 13:58:58.171 [info] alter table members1550server # [ 1064.737207] mobilizon_ctl[2216]: 13:58:58.330 [info] == Migrated 20200518084949 in 0.1s1551server # [ 1064.849494] mobilizon_ctl[2216]: 13:58:58.447 [info] == Running 20200519082820 Mobilizon.Storage.Repo.Migrations.AddUniqueURLContraintToSomeTables.change/0 forward1552server # [ 1064.886422] mobilizon_ctl[2216]: 13:58:58.478 [info] create index members_url_index1553server # [ 1064.956846] mobilizon_ctl[2216]: 13:58:58.549 [info] create index resource_url_index1554server # [ 1065.005969] mobilizon_ctl[2216]: 13:58:58.604 [info] create index todo_lists_url_index1555server # [ 1065.071671] mobilizon_ctl[2216]: 13:58:58.660 [info] create index todos_url_index1556server # [ 1065.135281] mobilizon_ctl[2216]: 13:58:58.725 [info] == Migrated 20200519082820 in 0.2s1557server # [ 1065.226754] mobilizon_ctl[2216]: 13:58:58.826 [info] == Running 20200526131523 Mobilizon.Repo.Migrations.CreateUserSettings.change/0 forward1558server # [ 1065.266406] mobilizon_ctl[2216]: 13:58:58.862 [info] create table user_settings1559server # [ 1065.407715] mobilizon_ctl[2216]: 13:58:58.997 [info] == Migrated 20200526131523 in 0.1s1560server # [ 1065.529524] mobilizon_ctl[2216]: 13:58:59.130 [info] == Running 20200605154921 Mobilizon.Storage.Repo.Migrations.AddNotificationPendingParticipationToSettings.change/0 forward1561server # [ 1065.615607] mobilizon_ctl[2216]: 13:58:59.154 [info] alter table user_settings1562server # [ 1065.634662] mobilizon_ctl[2216]: 13:58:59.214 [info] == Migrated 20200605154921 in 0.0s1563server # [ 1065.793062] mobilizon_ctl[2216]: 13:58:59.378 [info] == Running 20200612155254 Mobilizon.Storage.Repo.Migrations.AddDisabledParameterToUsers.change/0 forward1564server # [ 1065.818188] mobilizon_ctl[2216]: 13:58:59.393 [info] alter table users1565server # [ 1065.865029] mobilizon_ctl[2216]: 13:58:59.462 [info] == Migrated 20200612155254 in 0.0s1566server # [ 1066.002845] mobilizon_ctl[2216]: 13:58:59.604 [info] == Running 20200630123819 Mobilizon.Storage.Repo.Migrations.AddProviderToUserAndMakePasswordMandatory.up/0 forward1567server # [ 1066.046633] mobilizon_ctl[2216]: 13:58:59.618 [info] alter table users1568server # [ 1066.096991] mobilizon_ctl[2216]: 13:58:59.685 [info] == Migrated 20200630123819 in 0.0s1569server # [ 1066.193168] mobilizon_ctl[2216]: 13:58:59.793 [info] == Running 20200708080516 Mobilizon.Repo.Migrations.CreatePosts.up/0 forward1570server # [ 1066.389184] mobilizon_ctl[2216]: 13:58:59.975 [info] execute "CREATE TYPE public.post_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1571server # [ 1066.461096] mobilizon_ctl[2216]: 13:59:00.040 [info] create table posts1572server # [ 1066.811284] mobilizon_ctl[2216]: 13:59:00.358 [info] create table posts_tags1573server # [ 1066.927449] mobilizon_ctl[2216]: 13:59:00.527 [info] alter table actors1574server # [ 1066.991579] mobilizon_ctl[2216]: 13:59:00.578 [info] == Migrated 20200708080516 in 0.7s1575server # [ 1067.105792] mobilizon_ctl[2216]: 13:59:00.708 [info] == Running 20200717142633 Mobilizon.Storage.Repo.Migrations.RenameConversationsToDiscussions.up/0 forward1576server # [ 1067.145006] mobilizon_ctl[2216]: 13:59:00.737 [info] rename table conversations to discussions1577server # [ 1067.187841] mobilizon_ctl[2216]: 13:59:00.781 [info] rename column conversation_id to discussion_id on table comments1578server # [ 1067.233995] mobilizon_ctl[2216]: 13:59:00.821 [info] alter table actors1579server # [ 1067.289043] mobilizon_ctl[2216]: 13:59:00.874 [info] == Migrated 20200717142633 in 0.1s1580server # [ 1067.409060] mobilizon_ctl[2216]: 13:59:01.005 [info] == Running 20200721131521 Mobilizon.Storage.Repo.Migrations.AddUrlToConversation.up/0 forward1581server # [ 1067.461275] mobilizon_ctl[2216]: 13:59:01.026 [info] drop constraint if exists comments_conversation_id_fkey from table comments1582server # [ 1067.525274] mobilizon_ctl[2216]: 13:59:01.114 [info] drop constraint if exists comments_discussion_id_fkey from table comments1583server # [ 1067.559448] mobilizon_ctl[2216]: 13:59:01.129 [info] constraint "comments_discussion_id_fkey" of relation "comments" does not exist, skipping1584server # [ 1067.593191] mobilizon_ctl[2216]: 13:59:01.130 [info] alter table discussions1585server # [ 1067.740409] mobilizon_ctl[2216]: 13:59:01.317 [info] alter table comments1586server # [ 1067.902954] mobilizon_ctl[2216]: 13:59:01.442 [info] == Migrated 20200721131521 in 0.4s1587server # [ 1067.970045] mobilizon_ctl[2216]: 13:59:01.568 [info] == Running 20200805124620 Mobilizon.Storage.Repo.Migrations.AddAddressToActors.change/0 forward1588server # [ 1067.978809] mobilizon_ctl[2216]: 13:59:01.578 [info] alter table actors1589server # [ 1068.147798] mobilizon_ctl[2216]: 13:59:01.741 [info] == Migrated 20200805124620 in 0.1s1590server # [ 1068.229630] mobilizon_ctl[2216]: 13:59:01.836 [info] == Running 20200817080549 Mobilizon.Storage.Repo.Migrations.AddPublishedAtToEntities.up/0 forward1591server # [ 1068.244790] mobilizon_ctl[2216]: 13:59:01.842 [info] alter table comments1592server # [ 1068.371982] mobilizon_ctl[2216]: 13:59:01.957 [info] alter table resource1593server # [ 1068.465209] mobilizon_ctl[2216]: 13:59:02.060 [info] alter table todo_lists1594server # [ 1068.548919] mobilizon_ctl[2216]: 13:59:02.139 [info] alter table todos1595server # [ 1068.621845] mobilizon_ctl[2216]: 13:59:02.217 [info] == Migrated 20200817080549 in 0.3s1596server # [ 1068.741553] mobilizon_ctl[2216]: 13:59:02.339 [info] == Running 20200828071543 Mobilizon.Storage.Repo.Migrations.AddLoginInformationToUser.change/0 forward1597server # [ 1068.780986] mobilizon_ctl[2216]: 13:59:02.354 [info] alter table users1598server # [ 1068.847174] mobilizon_ctl[2216]: 13:59:02.434 [info] == Migrated 20200828071543 in 0.0s1599server # [ 1069.015893] mobilizon_ctl[2216]: 13:59:02.570 [info] == Running 20200901101307 Mobilizon.Storage.Repo.Migrations.FixRelayVisibility.up/0 forward1600server # [ 1069.464045] mobilizon_ctl[2216]: 13:59:03.058 [info] == Migrated 20200901101307 in 0.4s1601server # [ 1069.589272] mobilizon_ctl[2216]: 13:59:03.179 [info] == Running 20200928150725 Mobilizon.Storage.Repo.Migrations.AddContactsToEvents.change/0 forward1602server # [ 1069.609874] mobilizon_ctl[2216]: 13:59:03.199 [info] create table event_contacts1603server # [ 1069.807501] mobilizon_ctl[2216]: 13:59:03.400 [info] == Migrated 20200928150725 in 0.2s1604server # [ 1069.951467] mobilizon_ctl[2216]: 13:59:03.545 [info] == Running 20201006132530 Mobilizon.Storage.Repo.Migrations.ChangeDefaultNotificationPendingParticipation.up/0 forward1605server # [ 1069.963654] mobilizon_ctl[2216]: 13:59:03.557 [info] alter table user_settings1606server # [ 1070.117731] mobilizon_ctl[2216]: 13:59:03.634 [info] == Migrated 20201006132530 in 0.0s1607server # [ 1070.181620] mobilizon_ctl[2216]: 13:59:03.786 [info] == Running 20201103141811 Mobilizon.Storage.Repo.Migrations.AddNotificationPendingMembershipToSettings.change/0 forward1608server # [ 1070.221511] mobilizon_ctl[2216]: 13:59:03.794 [info] alter table user_settings1609server # [ 1070.291314] mobilizon_ctl[2216]: 13:59:03.877 [info] == Migrated 20201103141811 in 0.0s1610server # [ 1070.398500] mobilizon_ctl[2216]: 13:59:03.987 [info] == Running 20201105145719 Mobilizon.Storage.Repo.Migrations.RepairGroupsWithMissingURLs.up/0 forward1611server # [ 1070.483072] mobilizon_ctl[2216]: 13:59:04.066 [info] == Migrated 20201105145719 in 0.0s1612server # [ 1070.634267] mobilizon_ctl[2216]: 13:59:04.215 [info] == Running 20201114174929 Mobilizon.Storage.Repo.Migrations.FixObanMigrationsForOlderPostgreSQLVersions.up/0 forward1613server # [ 1070.666531] mobilizon_ctl[2216]: 13:59:04.238 [info] == Migrated 20201114174929 in 0.0s1614server # [ 1070.759931] mobilizon_ctl[2216]: 13:59:04.351 [info] == Running 20201114175249 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV9.up/0 forward1615server # [ 1071.262462] mobilizon_ctl[2216]: 13:59:04.778 [info] == Migrated 20201114175249 in 0.4s1616server # [ 1071.304817] mobilizon_ctl[2216]: 13:59:04.906 [info] == Running 20201120161229 Mobilizon.Storage.Repo.Migrations.FixPictureDeletion.up/0 forward1617server # [ 1071.321772] mobilizon_ctl[2216]: 13:59:04.922 [info] drop constraint if exists posts_picture_id_fkey from table posts1618server # [ 1071.392868] mobilizon_ctl[2216]: 13:59:04.989 [info] alter table posts1619server # [ 1071.466719] mobilizon_ctl[2216]: 13:59:05.065 [info] drop constraint if exists events_picture_id_fkey from table events1620server # [ 1071.520693] mobilizon_ctl[2216]: 13:59:05.118 [info] alter table events1621server # [ 1071.609567] mobilizon_ctl[2216]: 13:59:05.203 [info] == Migrated 20201120161229 in 0.2s1622server # [ 1072.495481] mobilizon_ctl[2216]: 13:59:06.062 [info] == Running 20201124094655 Mobilizon.Storage.Repo.Migrations.AddMediaTables.change/0 forward1623server # [ 1072.514490] mobilizon_ctl[2216]: 13:59:06.100 [info] rename table pictures to medias1624server # [ 1072.532928] mobilizon_ctl[2216]: 13:59:06.129 [info] create table events_medias1625server # [ 1072.692647] mobilizon_ctl[2216]: 13:59:06.277 [info] create table posts_medias1626server # [ 1072.867210] mobilizon_ctl[2216]: 13:59:06.453 [info] create table comments_medias1627server # [ 1073.017556] mobilizon_ctl[2216]: 13:59:06.613 [info] == Migrated 20201124094655 in 0.5s1628server # [ 1073.161030] mobilizon_ctl[2216]: 13:59:06.754 [info] == Running 20210127153325 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV10.up/0 forward1629server # [ 1073.284568] mobilizon_ctl[2216]: 13:59:06.862 [info] == Migrated 20210127153325 in 0.1s1630server # [ 1073.401018] mobilizon_ctl[2216]: 13:59:06.990 [info] == Running 20210210143432 Mobilizon.Storage.Repo.Migrations.AddLocationSettingsToUser.change/0 forward1631server # [ 1073.429646] mobilizon_ctl[2216]: 13:59:07.004 [info] alter table user_settings1632server # [ 1073.598915] mobilizon_ctl[2216]: 13:59:07.136 [info] == Migrated 20210210143432 in 0.1s1633server # [ 1073.667529] mobilizon_ctl[2216]: 13:59:07.267 [info] == Running 20210215092515 Mobilizon.Storage.Repo.Migrations.AddActivityTable.change/0 forward1634server # [ 1073.709807] mobilizon_ctl[2216]: 13:59:07.309 [info] create table activities1635server # [ 1074.036828] mobilizon_ctl[2216]: 13:59:07.618 [info] == Migrated 20210215092515 in 0.3s1636server # [ 1074.131294] mobilizon_ctl[2216]: 13:59:07.734 [info] == Running 20210217164549 Mobilizon.Storage.Repo.Migrations.AddMemberSinceToMembers.up/0 forward1637server # [ 1074.164815] mobilizon_ctl[2216]: 13:59:07.743 [info] alter table members1638server # [ 1074.258234] mobilizon_ctl[2216]: 13:59:07.839 [info] == Migrated 20210217164549 in 0.1s1639server # [ 1074.370647] mobilizon_ctl[2216]: 13:59:07.962 [info] == Running 20210310084210 Mobilizon.Storage.Repo.Migrations.AddIndexesToActivities.change/0 forward1640server # [ 1074.402917] mobilizon_ctl[2216]: 13:59:07.973 [info] create index activity_group_id1641server # [ 1074.472817] mobilizon_ctl[2216]: 13:59:08.055 [info] create index activity_filter1642server # [ 1074.601630] mobilizon_ctl[2216]: 13:59:08.141 [info] == Migrated 20210310084210 in 0.1s1643server # [ 1074.668979] mobilizon_ctl[2216]: 13:59:08.255 [info] == Running 20210422140923 Mobilizon.Storage.Repo.Migrations.CleanupAddresses.up/0 forward1644server # [ 1074.812094] mobilizon_ctl[2216]: 13:59:08.380 [info] == Migrated 20210422140923 in 0.1s1645server # [ 1074.921979] mobilizon_ctl[2216]: 13:59:08.521 [info] == Running 20210422155913 Mobilizon.Storage.Repo.Migrations.AddIndexToAddresses.up/0 forward1646server # [ 1074.947788] mobilizon_ctl[2216]: 13:59:08.546 [info] create index if not exists addresses_url_index1647server # [ 1075.031648] mobilizon_ctl[2216]: 13:59:08.619 [info] == Migrated 20210422155913 in 0.0s1648server # [ 1075.150159] mobilizon_ctl[2216]: 13:59:08.746 [info] == Running 20210427091034 Mobilizon.Storage.Repo.Migrations.RepairUsersDefaultActors.up/0 forward1649server # [ 1075.305216] mobilizon_ctl[2216]: 13:59:08.878 [info] == Migrated 20210427091034 in 0.1s1650server # [ 1075.390607] mobilizon_ctl[2216]: 13:59:08.991 [info] == Running 20210505172402 Mobilizon.Storage.Repo.Migrations.AddGroupNotificationAndLastNotificationDateSettings.change/0 forward1651server # [ 1075.416903] mobilizon_ctl[2216]: 13:59:09.001 [info] alter table user_settings1652server # [ 1075.461242] mobilizon_ctl[2216]: 13:59:09.060 [info] == Migrated 20210505172402 in 0.0s1653server # [ 1075.548926] mobilizon_ctl[2216]: 13:59:09.150 [info] == Running 20210506080303 Mobilizon.Repo.Migrations.CreateUserPushSubscriptions.change/0 forward1654server # [ 1075.623617] mobilizon_ctl[2216]: 13:59:09.177 [info] create table user_push_subscriptions1655server # [ 1075.746912] mobilizon_ctl[2216]: 13:59:09.348 [info] create index user_push_subscriptions_user_id_digest_index1656server # [ 1075.813203] mobilizon_ctl[2216]: 13:59:09.405 [info] == Migrated 20210506080303 in 0.2s1657server # [ 1075.948796] mobilizon_ctl[2216]: 13:59:09.534 [info] == Running 20210512084824 Mobilizon.Storage.Repo.Migrations.RepairRelayAndAnonymousActors.up/0 forward1658server # [ 1076.074619] mobilizon_ctl[2216]: 13:59:09.641 [info] == Migrated 20210512084824 in 0.1s1659server # [ 1076.181706] mobilizon_ctl[2216]: 13:59:09.788 [info] == Running 20210526203337 Mobilizon.Storage.Repo.Migrations.AddUserActivitySettings.change/0 forward1660server # [ 1076.241550] mobilizon_ctl[2216]: 13:59:09.807 [info] create table user_activity_settings1661server # [ 1076.517552] mobilizon_ctl[2216]: 13:59:10.093 [info] create index user_activity_settings_user_id_key_method_index1662server # [ 1076.678733] mobilizon_ctl[2216]: 13:59:10.190 [info] == Migrated 20210526203337 in 0.4s1663server # [ 1076.777653] mobilizon_ctl[2216]: 13:59:10.366 [info] == Running 20210601082412 Mobilizon.Storage.Repo.Migrations.AddIsAnnouncementToComments.change/0 forward1664server # [ 1076.814903] mobilizon_ctl[2216]: 13:59:10.394 [info] alter table comments1665server # [ 1076.923729] mobilizon_ctl[2216]: 13:59:10.482 [info] == Migrated 20210601082412 in 0.1s1666server # [ 1077.071940] mobilizon_ctl[2216]: 13:59:10.655 [info] == Running 20210601082613 Mobilizon.Storage.Repo.Migrations.FixUserSettingsNullableFields.change/0 forward1667server # [ 1077.112624] mobilizon_ctl[2216]: 13:59:10.671 [info] alter table user_settings1668server # [ 1077.252985] mobilizon_ctl[2216]: 13:59:10.813 [info] == Migrated 20210601082613 in 0.1s1669server # [ 1077.361469] mobilizon_ctl[2216]: 13:59:10.958 [info] == Running 20210608133040 Mobilizon.Storage.Repo.Migrations.AddMetadataToMedia.change/0 forward1670server # [ 1077.393814] mobilizon_ctl[2216]: 13:59:10.985 [info] alter table medias1671server # [ 1077.458333] mobilizon_ctl[2216]: 13:59:11.037 [info] == Migrated 20210608133040 in 0.0s1672server # [ 1077.574307] mobilizon_ctl[2216]: 13:59:11.170 [info] == Running 20210622133516 Mobilizon.Storage.Repo.Migrations.CleanupPosts.up/0 forward1673server # [ 1077.735770] mobilizon_ctl[2216]: 13:59:11.258 [info] == Migrated 20210622133516 in 0.0s1674server # [ 1077.806524] mobilizon_ctl[2216]: 13:59:11.406 [info] == Running 20210622133555 Mobilizon.Storage.Repo.Migrations.AddIndexToPosts.up/0 forward1675server # [ 1077.838340] mobilizon_ctl[2216]: 13:59:11.428 [info] create index if not exists posts_url_index1676server # [ 1077.913685] mobilizon_ctl[2216]: 13:59:11.506 [info] == Migrated 20210622133555 in 0.0s1677server # [ 1078.034574] mobilizon_ctl[2216]: 13:59:11.623 [info] == Running 20210730143924 Mobilizon.Storage.Repo.Migrations.PutUniqueIndexOnParticipantsUrls.up/0 forward1678server # [ 1078.065268] mobilizon_ctl[2216]: 13:59:11.638 [info] create index if not exists participants_url_index1679server # [ 1078.147215] mobilizon_ctl[2216]: 13:59:11.735 [info] == Migrated 20210730143924 in 0.1s1680server # [ 1078.250834] mobilizon_ctl[2216]: 13:59:11.846 [info] == Running 20210805142745 Mobilizon.Storage.Repo.Migrations.AddMetadataForEvents.change/0 forward1681server # [ 1078.287232] mobilizon_ctl[2216]: 13:59:11.858 [info] alter table events1682server # [ 1078.346627] mobilizon_ctl[2216]: 13:59:11.926 [info] == Migrated 20210805142745 in 0.0s1683server # [ 1078.461263] mobilizon_ctl[2216]: 13:59:12.054 [info] == Running 20210819161507 Mobilizon.Storage.Repo.Migrations.AddLanguageToEntities.change/0 forward1684server # [ 1078.482651] mobilizon_ctl[2216]: 13:59:12.078 [info] alter table events1685server # [ 1078.574507] mobilizon_ctl[2216]: 13:59:12.159 [info] alter table comments1686server # [ 1078.636729] mobilizon_ctl[2216]: 13:59:12.231 [info] alter table posts1687server # [ 1078.754981] mobilizon_ctl[2216]: 13:59:12.302 [info] == Migrated 20210819161507 in 0.2s1688server # [ 1078.825769] mobilizon_ctl[2216]: 13:59:12.426 [info] == Running 20211001101902 Mobilizon.Repo.Migrations.CreateExports.change/0 forward1689server # [ 1078.857162] mobilizon_ctl[2216]: 13:59:12.452 [info] create table exports1690server # [ 1079.066618] mobilizon_ctl[2216]: 13:59:12.658 [info] create index exports_file_path_index1691server # [ 1079.139675] mobilizon_ctl[2216]: 13:59:12.729 [info] == Migrated 20211001101902 in 0.3s1692server # [ 1079.211185] mobilizon_ctl[2216]: 13:59:12.822 [info] == Running 20211008084901 Mobilizon.Storage.Repo.Migrations.AddTimezoneToAddresses.change/0 forward1693server # [ 1079.242251] mobilizon_ctl[2216]: 13:59:12.835 [info] alter table addresses1694server # [ 1079.273441] mobilizon_ctl[2216]: 13:59:12.864 [info] == Migrated 20211008084901 in 0.0s1695server # [ 1079.383007] mobilizon_ctl[2216]: 13:59:12.987 [info] == Running 20211013090405 Mobilizon.Storage.Repo.Migrations.AddCodeToParticipants.change/0 forward1696server # [ 1079.433611] mobilizon_ctl[2216]: 13:59:12.991 [info] alter table participants1697server # [ 1079.476601] mobilizon_ctl[2216]: 13:59:13.058 [info] == Migrated 20211013090405 in 0.0s1698server # [ 1079.557602] mobilizon_ctl[2216]: 13:59:13.162 [info] == Running 20211022093530 Mobilizon.Storage.Repo.Migrations.AddNotifyToFollowers.change/0 forward1699server # [ 1079.596883] mobilizon_ctl[2216]: 13:59:13.171 [info] alter table followers1700server # [ 1079.647574] mobilizon_ctl[2216]: 13:59:13.240 [info] == Migrated 20211022093530 in 0.0s1701server # [ 1079.779024] mobilizon_ctl[2216]: 13:59:13.338 [info] == Running 20211223141104 Mobilizon.Storage.Repo.Migrations.AddInstanceMaterializedView.up/0 forward1702server # [ 1079.838940] mobilizon_ctl[2216]: 13:59:13.435 [info] execute "CREATE MATERIALIZED VIEW instances AS\nSELECT\n a.domain,\n COUNT(DISTINCT(p.id)) AS person_count,\n COUNT(DISTINCT(g.id)) AS group_count,\n COUNT(DISTINCT(e.id)) AS event_count,\n COUNT(f1.id) AS followers_count,\n COUNT(f2.id) AS followings_count,\n COUNT(r.id) AS reports_count,\n SUM(COALESCE((m.file->>'size')::int, 0)) AS media_size\nFROM actors a\nLEFT JOIN actors p ON a.id = p.id AND p.type = 'Person'\nLEFT JOIN actors g ON a.id = g.id AND g.type = 'Group'\nLEFT JOIN events e ON a.id = e.organizer_actor_id\nLEFT JOIN followers f1 ON a.id = f1.actor_id\nLEFT JOIN followers f2 ON a.id = f2.target_actor_id\nLEFT JOIN reports r ON r.reported_id = a.id\nLEFT JOIN medias m ON m.actor_id = a.id\nWHERE a.domain IS NOT NULL\nGROUP BY a.domain;\n"1703server: Guest root shell did not produce any data yet...1704server: To debug, enter the VM and run 'systemctl status backdoor.service'.1705server # [ 1080.678424] mobilizon_ctl[2216]: 13:59:14.273 [info] execute "CREATE OR REPLACE FUNCTION refresh_instances()\nRETURNS trigger AS $$\nBEGIN\n REFRESH MATERIALIZED VIEW instances;\n RETURN NULL;\nEND;\n$$ LANGUAGE plpgsql;\n"1706server # [ 1080.791954] mobilizon_ctl[2216]: 13:59:14.346 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger ON actors;\n"1707server # [ 1080.810536] mobilizon_ctl[2216]: 13:59:14.371 [info] trigger "refresh_instances_trigger" for relation "actors" does not exist, skipping1708server # [ 1080.822047] mobilizon_ctl[2216]: 13:59:14.380 [info] execute "CREATE TRIGGER refresh_instances_trigger\nAFTER INSERT OR UPDATE OR DELETE\nON actors\nFOR EACH STATEMENT\nEXECUTE PROCEDURE refresh_instances();\n"1709server # [ 1080.841135] mobilizon_ctl[2216]: 13:59:14.422 [info] create index if not exists instances_domain_index1710server # [ 1081.001632] mobilizon_ctl[2216]: 13:59:14.598 [info] == Migrated 20211223141104 in 1.2s1711server # [ 1081.128711] mobilizon_ctl[2216]: 13:59:14.718 [info] == Running 20220321154138 Mobilizon.Storage.Repo.Migrations.CreateObanPeers.up/0 forward1712server # [ 1081.193950] mobilizon_ctl[2216]: 13:59:14.790 [info] == Migrated 20220321154138 in 0.0s1713server # [ 1081.248954] mobilizon_ctl[2216]: 13:59:14.851 [info] == Running 20220321154142 Mobilizon.Storage.Repo.Migrations.SwapPrimaryObanIndexes.change/0 forward1714server # [ 1081.269416] mobilizon_ctl[2216]: 13:59:14.872 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index1715server # [ 1081.360934] mobilizon_ctl[2216]: 13:59:14.946 [info] relation "oban_jobs_state_queue_priority_scheduled_at_id_index" already exists, skipping1716server # [ 1081.393062] mobilizon_ctl[2216]: 13:59:14.947 [info] drop index if exists public.oban_jobs_queue_state_priority_scheduled_at_id_index1717server # [ 1081.415930] mobilizon_ctl[2216]: 13:59:15.001 [info] index "oban_jobs_queue_state_priority_scheduled_at_id_index" does not exist, skipping1718server # [ 1081.452761] mobilizon_ctl[2216]: 13:59:15.002 [info] == Migrated 20220321154142 in 0.1s1719server # [ 1081.517113] mobilizon_ctl[2216]: 13:59:15.112 [info] == Running 20220328153640 Mobilizon.Storage.Repo.Migrations.SetAllEventsCategoryToMeeting.up/0 forward1720server # [ 1081.545918] mobilizon_ctl[2216]: 13:59:15.115 [info] execute "UPDATE events SET category = 'MEETING'"1721server # [ 1081.570460] mobilizon_ctl[2216]: 13:59:15.134 [info] == Migrated 20220328153640 in 0.0s1722server # [ 1081.651681] mobilizon_ctl[2216]: 13:59:15.251 [info] == Running 20220407083712 Mobilizon.Storage.Repo.Migrations.AddIndexesToAddresses.up/0 forward1723server # [ 1081.679988] mobilizon_ctl[2216]: 13:59:15.262 [info] create index if not exists idx_addresses_geom_x1724server # [ 1081.746891] mobilizon_ctl[2216]: 13:59:15.337 [info] create index if not exists idx_addresses_geom_y1725server # [ 1081.842793] mobilizon_ctl[2216]: 13:59:15.401 [info] == Migrated 20220407083712 in 0.1s1726server # [ 1081.893737] mobilizon_ctl[2216]: 13:59:15.499 [info] == Running 20220505091224 Mobilizon.Storage.Repo.Migrations.ChangeRefreshInstanceTriggerToOnlyExecuteOnRelays.up/0 forward1727server # [ 1081.922213] mobilizon_ctl[2216]: 13:59:15.519 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger ON actors;\n"1728server # [ 1081.958303] mobilizon_ctl[2216]: 13:59:15.554 [info] execute "CREATE TRIGGER refresh_instances_trigger_new\nAFTER INSERT OR UPDATE\nON actors\nFOR EACH ROW\nWHEN (NEW.preferred_username = 'relay')\nEXECUTE PROCEDURE refresh_instances();\n"1729server # [ 1081.992835] mobilizon_ctl[2216]: 13:59:15.589 [info] execute "CREATE TRIGGER refresh_instances_trigger_old\nAFTER DELETE\nON actors\nFOR EACH ROW\nWHEN (OLD.preferred_username = 'relay')\nEXECUTE PROCEDURE refresh_instances();\n"1730server # [ 1082.026158] mobilizon_ctl[2216]: 13:59:15.621 [info] == Migrated 20220505091224 in 0.1s1731server # [ 1082.130596] mobilizon_ctl[2216]: 13:59:15.736 [info] == Running 20220506105150 Mobilizon.Storage.Repo.Migrations.RemoveRefreshInstanceTriggers.up/0 forward1732server # [ 1082.156958] mobilizon_ctl[2216]: 13:59:15.739 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger_new ON actors;\n"1733server # [ 1082.192719] mobilizon_ctl[2216]: 13:59:15.794 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger_old ON actors;\n"1734server # [ 1082.255703] mobilizon_ctl[2216]: 13:59:15.848 [info] == Migrated 20220506105150 in 0.1s1735server # [ 1082.355613] mobilizon_ctl[2216]: 13:59:15.951 [info] == Running 20230208101626 Mobilizon.Repo.Migrations.CreateApplications.change/0 forward1736server # [ 1082.401412] mobilizon_ctl[2216]: 13:59:15.983 [info] create table applications1737server # [ 1082.582697] mobilizon_ctl[2216]: 13:59:16.181 [info] create index applications_owner_id_owner_type_index1738server # [ 1082.696920] mobilizon_ctl[2216]: 13:59:16.265 [info] == Migrated 20230208101626 in 0.3s1739server # [ 1082.831797] mobilizon_ctl[2216]: 13:59:16.398 [info] == Running 20230215125801 Mobilizon.Repo.Migrations.CreateApplicationTokens.change/0 forward1740server # [ 1082.868803] mobilizon_ctl[2216]: 13:59:16.441 [info] create table application_tokens1741server # [ 1083.162801] mobilizon_ctl[2216]: 13:59:16.757 [info] create index application_tokens_user_id_application_id_index1742server # [ 1083.235618] mobilizon_ctl[2216]: 13:59:16.829 [info] == Migrated 20230215125801 in 0.4s1743server # [ 1083.353228] mobilizon_ctl[2216]: 13:59:16.954 [info] == Running 20230217084253 Mobilizon.Repo.Migrations.CreateApplicationDeviceActivation.change/0 forward1744server # [ 1083.401207] mobilizon_ctl[2216]: 13:59:16.982 [info] create table application_device_activation1745server # [ 1083.697622] mobilizon_ctl[2216]: 13:59:17.282 [info] == Migrated 20230217084253 in 0.3s1746server # [ 1083.907350] mobilizon_ctl[2216]: 13:59:17.468 [info] == Running 20230605143534 Mobilizon.Storage.Repo.Migrations.AllowMultipleEventsToBeReported.up/0 forward1747server # [ 1083.931236] mobilizon_ctl[2216]: 13:59:17.478 [info] create table reports_events1748server # [ 1084.006043] mobilizon_ctl[2216]: 13:59:17.600 [info] == Migrated 20230605143534 in 0.1s1749server # [ 1084.137085] mobilizon_ctl[2216]: 13:59:17.726 [info] == Running 20230605153836 Mobilizon.Storage.Repo.Migrations.BackfillReportEventsWithOldEvents.up/0 forward1750server # [ 1084.183921] mobilizon_ctl[2216]: 13:59:17.762 [info] == Migrated 20230605153836 in 0.0s1751server # [ 1084.273394] mobilizon_ctl[2216]: 13:59:17.880 [info] == Running 20230605154106 Mobilizon.Storage.Repo.Migrations.RemoveObsoleteEventIdOnReports.up/0 forward1752server # [ 1084.298271] mobilizon_ctl[2216]: 13:59:17.889 [info] alter table reports1753server # [ 1084.345594] mobilizon_ctl[2216]: 13:59:17.949 [info] == Migrated 20230605154106 in 0.0s1754server # [ 1084.468032] mobilizon_ctl[2216]: 13:59:18.080 [info] == Running 20230831153358 Mobilizon.Storage.Repo.Migrations.RemoveOnDeleteCascadeForReports.up/0 forward1755server # [ 1084.515657] mobilizon_ctl[2216]: 13:59:18.111 [info] execute "DROP MATERIALIZED VIEW IF EXISTS instances;\n"1756server # [ 1084.685722] mobilizon_ctl[2216]: 13:59:18.294 [info] drop constraint reports_reported_id_fkey from table reports1757server # [ 1084.749039] mobilizon_ctl[2216]: 13:59:18.353 [info] drop constraint reports_reporter_id_fkey from table reports1758server # [ 1084.809725] mobilizon_ctl[2216]: 13:59:18.408 [info] drop constraint reports_manager_id_fkey from table reports1759server # [ 1084.858714] mobilizon_ctl[2216]: 13:59:18.458 [info] alter table reports1760server # [ 1085.093175] mobilizon_ctl[2216]: 13:59:18.650 [info] drop constraint report_notes_moderator_id_fkey from table report_notes1761server # [ 1085.117905] mobilizon_ctl[2216]: 13:59:18.715 [info] alter table report_notes1762server # [ 1085.219262] mobilizon_ctl[2216]: 13:59:18.794 [info] execute "CREATE MATERIALIZED VIEW instances AS\nSELECT\n a.domain,\n COUNT(DISTINCT(p.id)) AS person_count,\n COUNT(DISTINCT(g.id)) AS group_count,\n COUNT(DISTINCT(e.id)) AS event_count,\n COUNT(f1.id) AS followers_count,\n COUNT(f2.id) AS followings_count,\n COUNT(r.id) AS reports_count,\n SUM(COALESCE((m.file->>'size')::int, 0)) AS media_size\nFROM actors a\nLEFT JOIN actors p ON a.id = p.id AND p.type = 'Person'\nLEFT JOIN actors g ON a.id = g.id AND g.type = 'Group'\nLEFT JOIN events e ON a.id = e.organizer_actor_id\nLEFT JOIN followers f1 ON a.id = f1.actor_id\nLEFT JOIN followers f2 ON a.id = f2.target_actor_id\nLEFT JOIN reports r ON r.reported_id = a.id\nLEFT JOIN medias m ON m.actor_id = a.id\nWHERE a.domain IS NOT NULL\nGROUP BY a.domain;\n"1763server # [ 1085.875164] mobilizon_ctl[2216]: 13:59:19.464 [info] == Migrated 20230831153358 in 1.3s1764server # [ 1085.991492] mobilizon_ctl[2216]: 13:59:19.593 [info] == Running 20230901160000 Mobilizon.Storage.Repo.Migrations.AddExternalUrlForEvents.up/0 forward1765server # [ 1086.154476] mobilizon_ctl[2216]: 13:59:19.694 [info] alter table events1766server # [ 1086.174084] mobilizon_ctl[2216]: 13:59:19.754 [info] execute "ALTER TABLE events ALTER COLUMN join_options TYPE VARCHAR USING join_options::text"1767server # [ 1086.637190] mobilizon_ctl[2216]: 13:59:20.209 [info] execute "ALTER TABLE events ALTER COLUMN join_options DROP DEFAULT"1768server # [ 1086.674630] mobilizon_ctl[2216]: 13:59:20.242 [info] execute "DROP TYPE public.join_options"1769server # [ 1086.732946] mobilizon_ctl[2216]: 13:59:20.274 [info] execute "CREATE TYPE public.join_options AS ENUM ('free', 'restricted', 'invite', 'external')"1770server # [ 1086.751232] mobilizon_ctl[2216]: 13:59:20.322 [info] execute "ALTER TABLE events ALTER COLUMN join_options TYPE join_options USING join_options::join_options"1771server # [ 1087.073820] mobilizon_ctl[2216]: 13:59:20.658 [info] execute "ALTER TABLE events ALTER COLUMN join_options SET DEFAULT 'free'::join_options"1772server # [ 1087.176031] mobilizon_ctl[2216]: 13:59:20.714 [info] == Migrated 20230901160000 in 1.1s1773server # [ 1087.333606] mobilizon_ctl[2216]: 13:59:20.922 [info] == Running 20230920071502 Mobilizon.Storage.Repo.Migrations.AddActorConversations.up/0 forward1774server # [ 1087.376705] mobilizon_ctl[2216]: 13:59:20.970 [info] create table conversations1775server # [ 1087.623469] mobilizon_ctl[2216]: 13:59:21.213 [info] create table conversation_participants1776server # [ 1087.813849] mobilizon_ctl[2216]: 13:59:21.414 [info] alter table comments1777server # [ 1087.900848] mobilizon_ctl[2216]: 13:59:21.495 [info] == Migrated 20230920071502 in 0.5s1778server # [ 1087.984849] mobilizon_ctl[2216]: 13:59:21.592 [info] == Running 20231220092536 Mobilizon.Storage.Repo.Migrations.AddActorInstances.change/0 forward1779server # [ 1088.014597] mobilizon_ctl[2216]: 13:59:21.609 [info] create table instance_actors1780server # [ 1088.219777] mobilizon_ctl[2216]: 13:59:21.777 [info] create index instance_actors_domain_index1781server # [ 1088.243091] mobilizon_ctl[2216]: 13:59:21.833 [info] == Migrated 20231220092536 in 0.2s1782server # [ 1088.333272] mobilizon_ctl[2216]: 13:59:21.931 [info] == Running 20240104103036 Mobilizon.Storage.Repo.Migrations.ChangeActorInstanceDescriptionTypeToText.up/0 forward1783server # [ 1088.356850] mobilizon_ctl[2216]: 13:59:21.941 [info] alter table instance_actors1784server # [ 1088.381352] mobilizon_ctl[2216]: 13:59:21.973 [info] == Migrated 20240104103036 in 0.0s1785server # [ 1088.457899] mobilizon_ctl[2216]: 13:59:22.066 [info] == Running 20240222121054 Mobilizon.Storage.Repo.Migrations.CreateAdminSettingsMedias.change/0 forward1786server # [ 1088.503910] mobilizon_ctl[2216]: 13:59:22.094 [info] create table admin_settings_medias1787server # [ 1088.676916] mobilizon_ctl[2216]: 13:59:22.274 [info] create index admin_settings_medias_group_name_index1788server # [ 1088.733506] mobilizon_ctl[2216]: 13:59:22.331 [info] == Migrated 20240222121054 in 0.2s1789server # [ 1088.833309] mobilizon_ctl[2216]: 13:59:22.433 [info] == Running 20240725130410 Mobilizon.Storage.Repo.Migrations.AddEventPhysicalAddressIndex.up/0 forward1790server # [ 1088.853097] mobilizon_ctl[2216]: 13:59:22.445 [info] create index events_phys_addr_id1791server # [ 1088.918503] mobilizon_ctl[2216]: 13:59:22.506 [info] == Migrated 20240725130410 in 0.0s1792server # [ 1089.021298] mobilizon_ctl[2216]: 13:59:22.614 [info] == Running 20250521145952 Mobilizon.Storage.Repo.Migrations.AddUuidToMedia.change/0 forward1793server # [ 1089.045931] mobilizon_ctl[2216]: 13:59:22.630 [info] alter table medias1794server # [ 1089.265814] mobilizon_ctl[2216]: 13:59:22.806 [info] create index medias_uuid_index1795server # [ 1089.289872] mobilizon_ctl[2216]: 13:59:22.877 [info] == Migrated 20250521145952 in 0.2s1796server # [ 1089.413097] mobilizon_ctl[2216]: 13:59:23.010 [info] == Running 20250911120910 Mobilizon.Storage.Repo.Migrations.AddModerationToUser.change/0 forward1797server # [ 1089.433129] mobilizon_ctl[2216]: 13:59:23.021 [info] alter table users1798server # [ 1089.484872] mobilizon_ctl[2216]: 13:59:23.081 [info] == Migrated 20250911120910 in 0.0s1799server # [ 1089.569480] mobilizon_ctl[2216]: 13:59:23.176 [info] == Running 20250919143627 Mobilizon.Storage.Repo.Migrations.UpdateRoleToUser.change/0 forward1800server # [ 1089.584843] mobilizon_ctl[2216]: 13:59:23.178 [info] execute "ALTER TYPE user_role ADD VALUE IF NOT EXISTS 'pending';"1801server # [ 1089.611171] mobilizon_ctl[2216]: 13:59:23.213 [info] enum label "pending" already exists, skipping1802server # [ 1089.631008] mobilizon_ctl[2216]: 13:59:23.214 [info] == Migrated 20250919143627 in 0.0s1803server # [ 1089.708980] mobilizon_ctl[2216]: 13:59:23.306 [info] == Running 20251027133219 Mobilizon.Storage.Repo.Migrations.CreateInvitations.change/0 forward1804server # [ 1089.761326] mobilizon_ctl[2216]: 13:59:23.334 [info] create table invitations1805server # [ 1089.957523] mobilizon_ctl[2216]: 13:59:23.557 [info] create index invitations_token_index1806server # [ 1090.028913] mobilizon_ctl[2216]: 13:59:23.618 [info] == Migrated 20251027133219 in 0.3s1807server # [ 1090.109489] mobilizon_ctl[2216]: 13:59:23.719 [info] == Running 20251111093015 Mobilizon.Storage.Repo.Migrations.AddAllowToGroup.change/0 forward1808server # [ 1090.147581] mobilizon_ctl[2216]: 13:59:23.742 [info] alter table actors1809server # [ 1090.199309] mobilizon_ctl[2216]: 13:59:23.790 [info] == Migrated 20251111093015 in 0.0s1810server # [ 1091.007447] systemd[1]: Started Mobilizon federated organization and mobilization platform.1811server # [ 1091.101588] systemd[1]: Reached target Multi-User System.1812server # [ 1091.129590] systemd[1]: Startup finished in 7.279s (kernel) + 1min 47.663s (initrd) + 16min 16.179s (userspace) = 18min 11.122s.1813server: Guest root shell did not produce any data yet...1814server: To debug, enter the VM and run 'systemctl status backdoor.service'.1815server: Guest root shell did not produce any data yet...1816server: To debug, enter the VM and run 'systemctl status backdoor.service'.1817server: Guest root shell did not produce any data yet...1818server: To debug, enter the VM and run 'systemctl status backdoor.service'.1819server: Guest root shell did not produce any data yet...1820server: To debug, enter the VM and run 'systemctl status backdoor.service'.1821server: Guest root shell did not produce any data yet...1822server: To debug, enter the VM and run 'systemctl status backdoor.service'.1823server: Guest root shell did not produce any data yet...1824server: To debug, enter the VM and run 'systemctl status backdoor.service'.1825server: Guest root shell did not produce any data yet...1826server: To debug, enter the VM and run 'systemctl status backdoor.service'.1827server # [ 1314.927277] postgres[1389]: [1389] LOG: checkpoint starting: time1828server: Guest root shell did not produce any data yet...1829server: To debug, enter the VM and run 'systemctl status backdoor.service'.1830server # [ 1342.983189] postgres[1389]: [1389] LOG: checkpoint complete: wrote 260 buffers (1.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=27.097 s, sync=0.741 s, total=28.058 s; sync files=205, longest=0.024 s, average=0.004 s; distance=1882 kB, estimate=11604 kB; lsn=0/264B220, redo lsn=0/264B1901831server: Guest root shell did not produce any data yet...1832server: To debug, enter the VM and run 'systemctl status backdoor.service'.1833server: Guest root shell did not produce any data yet...1834server: To debug, enter the VM and run 'systemctl status backdoor.service'.1835server # [ 1407.124996] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31836server: Guest root shell did not produce any data yet...1837server: To debug, enter the VM and run 'systemctl status backdoor.service'.1838server: Guest root shell did not produce any data yet...1839server: To debug, enter the VM and run 'systemctl status backdoor.service'.1840server: Guest root shell did not produce any data yet...1841server: To debug, enter the VM and run 'systemctl status backdoor.service'.1842server # [ 1488.883266] mobilizon[2504]: 14:06:02.414 [info] No tzdata release files found in custom data dir. Copying release file from tzdata priv dir.1843server # [ 1496.473454] mobilizon[2504]: 14:06:09.947 [info] Source for database :city not found: "/var/lib/mobilizon/geo/GeoLite2-City.mmdb"1844server: Guest root shell did not produce any data yet...1845server: To debug, enter the VM and run 'systemctl status backdoor.service'.1846server # [ 1501.442599] mobilizon[2504]: 14:06:14.995 [info] Loading 122 CA(s) from :otp store1847server # [ 1504.992631] mobilizon[2504]: 14:06:18.528 [info] Running Mobilizon.Web.Endpoint with Bandit 1.10.3 at ::1:41395 (http)1848server # [ 1505.005534] mobilizon[2504]: 14:06:18.572 [info] Access Mobilizon.Web.Endpoint at https://acme.test1849server # [ 1506.877652] mobilizon[2504]: 14:06:20.347 [info] {"message":"notifier only receiving messages from its own node, functionality may be degraded","source":"oban","event":"notifier:switch","connectivity_status":"solitary"}1850server # [ 1506.968667] mobilizon[2504]: 14:06:20.513 [info] {"message":"peer became leader","node":"mobilizon@server","source":"oban","event":"peer:election","leader":true,"was_leader":false}1851server # [ 1508.450649] systemd[1]: Starting Virtual Console Setup...1852server # [ 1509.719497] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1853server # [ 1509.749396] systemd[1]: Stopped Virtual Console Setup.1854server # [ 1510.413811] systemd[1]: Starting Virtual Console Setup...1855server # [ 1519.747678] systemd-vconsole-setup[3145]: Configuration of first virtual console was skipped, ignoring remaining ones.1856server # [ 1519.888028] systemd[1]: Finished Virtual Console Setup.1857server # [ 1526.723323] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1858server: Guest root shell did not produce any data yet...1859server: To debug, enter the VM and run 'systemctl status backdoor.service'.1860server # [ 1535.686326] mobilizon[2504]: 14:06:49.270 [info] {"source":"oban","duration":80489,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1861server # [ 1543.164815] 8021q: 802.1Q VLAN Support v1.81862server # [ 1544.900214] cfg80211: Loading compiled-in X.509 certificates for regulatory database1863server # [ 1545.411832] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1864server # [ 1545.423810] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1865server # [ 1545.488038] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21866server # [ 1545.505041] cfg80211: failed to load regulatory.db1867server # [ 1546.755743] mobilizon[2504]: 14:07:00.334 [info] {"source":"oban","duration":25268,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1868server # [ 1549.545876] (udev-worker)[3245]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1869server # [ 1549.562625] (udev-worker)[3245]: Network interface NamePolicy= disabled on kernel command line.1870server # [ 1550.439648] systemd[1]: Condition check resulted in Virtio network device being skipped.1871server # [ 1550.522155] systemd[1]: Starting Address configuration of eth1...1872server # [ 1552.677030] 8021q: adding VLAN 0 to HW filter on device eth11873server # [ 1552.975374] network-addresses-eth1-start[3249]: adding address 192.168.1.1/24... done1874server # [ 1553.310320] network-addresses-eth1-start[3249]: adding address 2001:db8:1::1/64... done1875server # [ 1553.779943] systemd[1]: Finished Address configuration of eth1.1876server # [ 1556.517172] (udev-worker)[3260]: Network interface NamePolicy= disabled on kernel command line.1877server # [ 1559.032800] 8021q: adding VLAN 0 to HW filter on device eth01878server # [ 1558.942870] dhcpcd[1183]: eth0: waiting for carrier1879server # [ 1558.966465] dhcpcd[1183]: eth0: carrier acquired1880server # [ 1559.301149] dhcpcd[1183]: DUID 00:01:00:01:32:6b:83:90:52:54:00:12:34:561881server # [ 1559.333610] dhcpcd[1183]: eth0: IAID 00:12:34:561882server # [ 1559.350277] dhcpcd[1183]: eth0: adding address fe80::5054:ff:fe12:34561883server # [ 1559.378771] dhcpcd[1183]: eth0: soliciting a DHCP lease1884server # [ 1559.433361] dhcpcd[1183]: eth0: offered 10.0.2.15 from 10.0.2.21885server # [ 1559.453761] dhcpcd[1183]: eth0: probing address 10.0.2.15/241886server: Guest root shell did not produce any data yet...1887server: To debug, enter the VM and run 'systemctl status backdoor.service'.1888server # [ 1561.503590] dhcpcd[1183]: eth0: soliciting an IPv6 router1889server # [ 1561.522696] dhcpcd[1183]: eth0: Router Advertisement from fe80::21890server # [ 1561.548877] dhcpcd[1183]: eth0: adding address fec0::5054:ff:fe12:3456/641891server # [ 1561.592440] dhcpcd[1183]: eth0: adding route to fec0::/641892server # [ 1561.598693] dhcpcd[1183]: eth0: adding default route via fe80::21893server # [ 1564.823390] dhcpcd[1183]: eth0: leased 10.0.2.15 for 86400 seconds1894server # [ 1564.839253] dhcpcd[1183]: eth0: adding route to 10.0.2.0/241895server # [ 1564.851236] dhcpcd[1183]: eth0: adding default route via 10.0.2.21896server # [ 1565.874031] mobilizon[2504]: 14:07:19.394 [info] {"source":"oban","duration":114933,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1897server # [ 1582.995705] systemd-logind[1057]: Watching system buttons on /dev/input/event1 (gpio-keys)1898server: Guest root shell did not produce any data yet...1899server: To debug, enter the VM and run 'systemctl status backdoor.service'.1900server # [ 1592.509421] systemd-logind[1057]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)1901server # [ 1595.906756] mobilizon[2504]: 14:07:49.477 [info] {"source":"oban","duration":78999,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1902server # [ 1606.781171] mobilizon[2504]: 14:08:00.362 [info] {"source":"oban","duration":12964,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1903server # [ 1615.103875] postgres[1389]: [1389] LOG: checkpoint starting: time1904server # [ 1617.614175] postgres[1389]: [1389] LOG: checkpoint complete: wrote 23 buffers (0.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.367 s, sync=0.065 s, total=2.542 s; sync files=17, longest=0.026 s, average=0.004 s; distance=8 kB, estimate=10444 kB; lsn=0/264D408, redo lsn=0/264D3B01905server: Guest root shell did not produce any data yet...1906server: To debug, enter the VM and run 'systemctl status backdoor.service'.1907server # [ 1625.982904] mobilizon[2504]: 14:08:19.553 [info] {"source":"oban","duration":72935,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1908server: Guest root shell did not produce any data yet...1909server: To debug, enter the VM and run 'systemctl status backdoor.service'.1910server # [ 1656.071840] mobilizon[2504]: 14:08:49.628 [info] {"source":"oban","duration":71493,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1911server # [ 1666.795298] mobilizon[2504]: 14:09:00.378 [info] {"source":"oban","duration":12138,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1912server: Guest root shell did not produce any data yet...1913server: To debug, enter the VM and run 'systemctl status backdoor.service'.1914server # [ 1686.121058] mobilizon[2504]: 14:09:19.693 [info] {"source":"oban","duration":62364,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1915server: Guest root shell did not produce any data yet...1916server: To debug, enter the VM and run 'systemctl status backdoor.service'.1917server # [ 1716.220024] mobilizon[2504]: 14:09:49.787 [info] {"source":"oban","duration":86079,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1918server # [ 1726.847451] mobilizon[2504]: 14:10:00.421 [info] {"source":"oban","duration":34086,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1919server: Guest root shell did not produce any data yet...1920server: To debug, enter the VM and run 'systemctl status backdoor.service'.1921server # [ 1746.258817] mobilizon[2504]: 14:10:19.845 [info] {"source":"oban","duration":53583,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1922server # [ 1766.036976] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1923server # [ 1766.051889] systemd[1]: Stopped Virtual Console Setup.1924server # [ 1766.067953] systemd[1]: systemd-vconsole-setup.service: Consumed 1.764s CPU time over 4min 15.616s wall clock time, 2.6M memory peak.1925server # [ 1766.090942] systemd[1]: Stopping Virtual Console Setup...1926server # [ 1766.233412] systemd[1]: Starting Virtual Console Setup...1927server # [ 1767.311780] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1928server # [ 1767.334248] systemd[1]: Stopped Virtual Console Setup.1929server # [ 1767.584559] systemd[1]: Starting Virtual Console Setup...1930server: Guest root shell did not produce any data yet...1931server: To debug, enter the VM and run 'systemctl status backdoor.service'.1932server # [ 1772.846158] systemd-vconsole-setup[4001]: Configuration of first virtual console was skipped, ignoring remaining ones.1933server # [ 1772.955962] systemd[1]: Finished Virtual Console Setup.1934server # [ 1776.347919] mobilizon[2504]: 14:10:49.917 [info] {"source":"oban","duration":64968,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1935server # [ 1778.755615] systemd-logind[1057]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1936server # [ 1781.490872] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1937server # [ 1786.841402] mobilizon[2504]: 14:11:00.437 [info] {"source":"oban","duration":10955,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1938server: Guest root shell did not produce any data yet...1939server: To debug, enter the VM and run 'systemctl status backdoor.service'.1940server # [ 1806.342418] mobilizon[2504]: 14:11:19.943 [info] {"source":"oban","duration":22582,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1941server: Guest root shell did not produce any data yet...1942server: To debug, enter the VM and run 'systemctl status backdoor.service'.1943server # [ 1836.373264] mobilizon[2504]: 14:11:49.973 [info] {"source":"oban","duration":25366,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1944server # [ 1846.853214] mobilizon[2504]: 14:12:00.452 [info] {"source":"oban","duration":9804,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1945server: Guest root shell did not produce any data yet...1946server: To debug, enter the VM and run 'systemctl status backdoor.service'.1947server # [ 1866.405210] mobilizon[2504]: 14:12:20.002 [info] {"source":"oban","duration":24654,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1948server: Guest root shell did not produce any data yet...1949server: To debug, enter the VM and run 'systemctl status backdoor.service'.1950server # [ 1896.426651] mobilizon[2504]: 14:12:50.028 [info] {"source":"oban","duration":23126,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1951server # [ 1906.870504] mobilizon[2504]: 14:13:00.465 [info] {"source":"oban","duration":8779,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1952server # [ 1914.622071] postgres[1389]: [1389] LOG: checkpoint starting: time1953server # [ 1914.732514] postgres[1389]: [1389] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.022 s, sync=0.007 s, total=0.130 s; sync files=1, longest=0.007 s, average=0.007 s; distance=4 kB, estimate=9400 kB; lsn=0/264E4B8, redo lsn=0/264E4601954server: Guest root shell did not produce any data yet...1955server: To debug, enter the VM and run 'systemctl status backdoor.service'.1956server # [ 1926.458071] mobilizon[2504]: 14:13:20.057 [info] {"source":"oban","duration":23967,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1957server: Guest root shell did not produce any data yet...1958server: To debug, enter the VM and run 'systemctl status backdoor.service'.1959server # [ 1956.493752] mobilizon[2504]: 14:13:50.092 [info] {"source":"oban","duration":32135,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1960server # [ 1966.883848] mobilizon[2504]: 14:14:00.485 [info] {"source":"oban","duration":15796,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1961server: Guest root shell did not produce any data yet...1962server: To debug, enter the VM and run 'systemctl status backdoor.service'.1963server # [ 1986.530942] mobilizon[2504]: 14:14:20.132 [info] {"source":"oban","duration":19695,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1964server: Guest root shell did not produce any data yet...1965server: To debug, enter the VM and run 'systemctl status backdoor.service'.1966server # [ 2016.579819] mobilizon[2504]: 14:14:50.181 [info] {"source":"oban","duration":44976,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1967server # [ 2026.898195] mobilizon[2504]: 14:15:00.499 [info] {"source":"oban","duration":9603,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1968server: Guest root shell did not produce any data yet...1969server: To debug, enter the VM and run 'systemctl status backdoor.service'.1970server # [ 2046.619408] mobilizon[2504]: 14:15:20.209 [info] {"source":"oban","duration":24409,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1971server: Guest root shell did not produce any data yet...1972server: To debug, enter the VM and run 'systemctl status backdoor.service'.1973server # [ 2076.633205] mobilizon[2504]: 14:15:50.235 [info] {"source":"oban","duration":22908,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1974server # [ 2086.910471] mobilizon[2504]: 14:16:00.512 [info] {"source":"oban","duration":8908,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1975server: Guest root shell did not produce any data yet...1976server: To debug, enter the VM and run 'systemctl status backdoor.service'.1977server # [ 2106.665120] mobilizon[2504]: 14:16:20.264 [info] {"source":"oban","duration":26068,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1978server: Guest root shell did not produce any data yet...1979server: To debug, enter the VM and run 'systemctl status backdoor.service'.1980server # [ 2136.691550] mobilizon[2504]: 14:16:50.288 [info] {"source":"oban","duration":19734,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1981server # [ 2146.929000] mobilizon[2504]: 14:17:00.530 [info] {"source":"oban","duration":12686,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1982server: Guest root shell did not produce any data yet...1983server: To debug, enter the VM and run 'systemctl status backdoor.service'.1984server # [ 2166.714799] mobilizon[2504]: 14:17:20.317 [info] {"source":"oban","duration":24447,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1985server: Guest root shell did not produce any data yet...1986server: To debug, enter the VM and run 'systemctl status backdoor.service'.1987server # [ 2196.777573] mobilizon[2504]: 14:17:50.378 [info] {"source":"oban","duration":57070,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1988server # [ 2206.943121] mobilizon[2504]: 14:18:00.544 [info] {"source":"oban","duration":9654,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1989server # [ 2214.809586] postgres[1389]: [1389] LOG: checkpoint starting: time1990server # [ 2214.918178] postgres[1389]: [1389] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.027 s, sync=0.012 s, total=0.136 s; sync files=1, longest=0.012 s, average=0.012 s; distance=4 kB, estimate=8461 kB; lsn=0/264F540, redo lsn=0/264F4E81991server: Guest root shell did not produce any data yet...1992server: To debug, enter the VM and run 'systemctl status backdoor.service'.1993server # [ 2226.845580] mobilizon[2504]: 14:18:20.418 [info] {"source":"oban","duration":36396,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1994server: Guest root shell did not produce any data yet...1995server: To debug, enter the VM and run 'systemctl status backdoor.service'.1996server # [ 2256.851029] mobilizon[2504]: 14:18:50.451 [info] {"source":"oban","duration":29308,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1997server # [ 2266.957200] mobilizon[2504]: 14:19:00.557 [info] {"source":"oban","duration":10249,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1998server: Guest root shell did not produce any data yet...1999server: To debug, enter the VM and run 'systemctl status backdoor.service'.2000server # [ 2286.877406] mobilizon[2504]: 14:19:20.479 [info] {"source":"oban","duration":24999,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2001server: Guest root shell did not produce any data yet...2002server: To debug, enter the VM and run 'systemctl status backdoor.service'.2003server # [ 2316.911966] mobilizon[2504]: 14:19:50.509 [info] {"source":"oban","duration":24551,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2004server # [ 2326.976042] mobilizon[2504]: 14:20:00.576 [info] {"source":"oban","duration":14403,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2005server: Guest root shell did not produce any data yet...2006server: To debug, enter the VM and run 'systemctl status backdoor.service'.2007server # [ 2346.935674] mobilizon[2504]: 14:20:20.536 [info] {"source":"oban","duration":22382,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2008server: Guest root shell did not produce any data yet...2009server: To debug, enter the VM and run 'systemctl status backdoor.service'.2010server # [ 2376.969128] mobilizon[2504]: 14:20:50.570 [info] {"source":"oban","duration":30663,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2011server # [ 2386.990052] mobilizon[2504]: 14:21:00.590 [info] {"source":"oban","duration":8715,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2012server: Guest root shell did not produce any data yet...2013server: To debug, enter the VM and run 'systemctl status backdoor.service'.2014server # [ 2406.999024] mobilizon[2504]: 14:21:20.597 [info] {"source":"oban","duration":23912,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2015server: Guest root shell did not produce any data yet...2016server: To debug, enter the VM and run 'systemctl status backdoor.service'.2017server # [ 2437.022326] mobilizon[2504]: 14:21:50.623 [info] {"source":"oban","duration":22185,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2018server # [ 2447.003224] mobilizon[2504]: 14:22:00.605 [info] {"source":"oban","duration":10576,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2019server: Guest root shell did not produce any data yet...2020server: To debug, enter the VM and run 'systemctl status backdoor.service'.2021server # [ 2467.050269] mobilizon[2504]: 14:22:20.651 [info] {"source":"oban","duration":24892,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2022server: Guest root shell did not produce any data yet...2023server: To debug, enter the VM and run 'systemctl status backdoor.service'.2024server # [ 2497.078258] mobilizon[2504]: 14:22:50.677 [info] {"source":"oban","duration":22700,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2025server # [ 2507.017972] mobilizon[2504]: 14:23:00.620 [info] {"source":"oban","duration":9898,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2026server # [ 2514.993083] postgres[1389]: [1389] LOG: checkpoint starting: time2027server # [ 2515.568835] postgres[1389]: [1389] LOG: checkpoint complete: wrote 5 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.436 s, sync=0.045 s, total=0.601 s; sync files=7, longest=0.014 s, average=0.007 s; distance=18 kB, estimate=7616 kB; lsn=0/2653D58, redo lsn=0/2653D002028server: Guest root shell did not produce any data yet...2029server: To debug, enter the VM and run 'systemctl status backdoor.service'.2030server # [ 2527.102519] mobilizon[2504]: 14:23:20.705 [info] {"source":"oban","duration":24323,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2031server: Guest root shell did not produce any data yet...2032server: To debug, enter the VM and run 'systemctl status backdoor.service'.2033server # [ 2557.125476] mobilizon[2504]: 14:23:50.728 [info] {"source":"oban","duration":19997,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2034server # [ 2567.067452] mobilizon[2504]: 14:24:00.645 [info] {"source":"oban","duration":21971,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2035server: Guest root shell did not produce any data yet...2036server: To debug, enter the VM and run 'systemctl status backdoor.service'.2037server # [ 2587.156846] mobilizon[2504]: 14:24:20.755 [info] {"source":"oban","duration":23545,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2038server: Guest root shell did not produce any data yet...2039server: To debug, enter the VM and run 'systemctl status backdoor.service'.2040server # [ 2617.176962] mobilizon[2504]: 14:24:50.780 [info] {"source":"oban","duration":21750,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2041server # [ 2627.061534] mobilizon[2504]: 14:25:00.661 [info] {"source":"oban","duration":10561,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2042server: Guest root shell did not produce any data yet...2043server: To debug, enter the VM and run 'systemctl status backdoor.service'.2044server # [ 2647.208045] mobilizon[2504]: 14:25:20.809 [info] {"source":"oban","duration":25911,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2045server: Guest root shell did not produce any data yet...2046server: To debug, enter the VM and run 'systemctl status backdoor.service'.2047server # [ 2677.233536] mobilizon[2504]: 14:25:50.834 [info] {"source":"oban","duration":21291,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2048server # [ 2687.075529] mobilizon[2504]: 14:26:00.675 [info] {"source":"oban","duration":9940,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2049server: Guest root shell did not produce any data yet...2050server: To debug, enter the VM and run 'systemctl status backdoor.service'.2051server # [ 2707.271056] mobilizon[2504]: 14:26:20.874 [info] {"source":"oban","duration":21024,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2052server: Guest root shell did not produce any data yet...2053server: To debug, enter the VM and run 'systemctl status backdoor.service'.2054server # [ 2737.307853] mobilizon[2504]: 14:26:50.894 [info] {"source":"oban","duration":17026,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2055server # [ 2747.090450] mobilizon[2504]: 14:27:00.692 [info] {"source":"oban","duration":12193,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2056server: Guest root shell did not produce any data yet...2057server: To debug, enter the VM and run 'systemctl status backdoor.service'.2058server # [ 2767.325723] mobilizon[2504]: 14:27:20.924 [info] {"source":"oban","duration":25599,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2059server: Guest root shell did not produce any data yet...2060server: To debug, enter the VM and run 'systemctl status backdoor.service'.2061server # [ 2797.345048] mobilizon[2504]: 14:27:50.947 [info] {"source":"oban","duration":20110,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2062server # [ 2807.107492] mobilizon[2504]: 14:28:00.709 [info] {"source":"oban","duration":11047,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2063server # [ 2814.659107] postgres[1389]: [1389] LOG: checkpoint starting: time2064server # [ 2814.742144] postgres[1389]: [1389] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.021 s, sync=0.009 s, total=0.108 s; sync files=1, longest=0.009 s, average=0.009 s; distance=4 kB, estimate=6855 kB; lsn=0/2654E08, redo lsn=0/2654DB02065server: Guest root shell did not produce any data yet...2066server: To debug, enter the VM and run 'systemctl status backdoor.service'.2067server # [ 2827.365643] mobilizon[2504]: 14:28:20.968 [info] {"source":"oban","duration":17258,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2068server: Guest root shell did not produce any data yet...2069server: To debug, enter the VM and run 'systemctl status backdoor.service'.2070server # [ 2857.397393] mobilizon[2504]: 14:28:50.996 [info] {"source":"oban","duration":24086,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2071server # [ 2867.122008] mobilizon[2504]: 14:29:00.724 [info] {"source":"oban","duration":9717,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2072server: Guest root shell did not produce any data yet...2073server: To debug, enter the VM and run 'systemctl status backdoor.service'.2074server # [ 2887.419719] mobilizon[2504]: 14:29:21.021 [info] {"source":"oban","duration":22356,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2075server: Guest root shell did not produce any data yet...2076server: To debug, enter the VM and run 'systemctl status backdoor.service'.2077server # [ 2917.444667] mobilizon[2504]: 14:29:51.047 [info] {"source":"oban","duration":22188,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2078server # [ 2927.139101] mobilizon[2504]: 14:30:00.740 [info] {"source":"oban","duration":7368,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2079server: Guest root shell did not produce any data yet...2080server: To debug, enter the VM and run 'systemctl status backdoor.service'.2081server # [ 2947.476034] mobilizon[2504]: 14:30:21.074 [info] {"source":"oban","duration":24176,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2082server: Guest root shell did not produce any data yet...2083server: To debug, enter the VM and run 'systemctl status backdoor.service'.2084server # [ 2977.503542] mobilizon[2504]: 14:30:51.106 [info] {"source":"oban","duration":27136,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2085server # [ 2987.182655] mobilizon[2504]: 14:31:00.761 [info] {"source":"oban","duration":15850,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2086server: Guest root shell did not produce any data yet...2087server: To debug, enter the VM and run 'systemctl status backdoor.service'.2088server # [ 3007.531025] mobilizon[2504]: 14:31:21.132 [info] {"source":"oban","duration":22560,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2089server: Guest root shell did not produce any data yet...2090server: To debug, enter the VM and run 'systemctl status backdoor.service'.2091server # [ 3037.554212] mobilizon[2504]: 14:31:51.152 [info] {"source":"oban","duration":16997,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2092server # [ 3047.171686] mobilizon[2504]: 14:32:00.773 [info] {"source":"oban","duration":8127,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2093server: Guest root shell did not produce any data yet...2094server: To debug, enter the VM and run 'systemctl status backdoor.service'.2095server # [ 3067.570890] mobilizon[2504]: 14:32:21.173 [info] {"source":"oban","duration":17754,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2096server: Guest root shell did not produce any data yet...2097server: To debug, enter the VM and run 'systemctl status backdoor.service'.2098server # [ 3097.593683] mobilizon[2504]: 14:32:51.195 [info] {"source":"oban","duration":18215,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2099server # [ 3107.186748] mobilizon[2504]: 14:33:00.789 [info] {"source":"oban","duration":10862,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2100server # [ 3114.821560] postgres[1389]: [1389] LOG: checkpoint starting: time2101server # [ 3115.178668] postgres[1389]: [1389] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.228 s, sync=0.028 s, total=0.381 s; sync files=3, longest=0.016 s, average=0.010 s; distance=16 kB, estimate=6171 kB; lsn=0/2659140, redo lsn=0/26590E82102server: Guest root shell did not produce any data yet...2103server: To debug, enter the VM and run 'systemctl status backdoor.service'.2104server # [ 3127.617771] mobilizon[2504]: 14:33:21.217 [info] {"source":"oban","duration":18616,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2105server: Guest root shell did not produce any data yet...2106server: To debug, enter the VM and run 'systemctl status backdoor.service'.2107server # [ 3157.637758] mobilizon[2504]: 14:33:51.241 [info] {"source":"oban","duration":20482,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2108server # [ 3167.202156] mobilizon[2504]: 14:34:00.802 [info] {"source":"oban","duration":8358,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2109server: Guest root shell did not produce any data yet...2110server: To debug, enter the VM and run 'systemctl status backdoor.service'.2111server # [ 3187.667519] mobilizon[2504]: 14:34:21.265 [info] {"source":"oban","duration":21282,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2112server: Guest root shell did not produce any data yet...2113server: To debug, enter the VM and run 'systemctl status backdoor.service'.2114server # [ 3217.698089] mobilizon[2504]: 14:34:51.294 [info] {"source":"oban","duration":25757,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2115server # [ 3227.221945] mobilizon[2504]: 14:35:00.820 [info] {"source":"oban","duration":12658,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2116server: Guest root shell did not produce any data yet...2117server: To debug, enter the VM and run 'systemctl status backdoor.service'.2118server # [ 3247.716042] mobilizon[2504]: 14:35:21.318 [info] {"source":"oban","duration":21335,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2119server: Guest root shell did not produce any data yet...2120server: To debug, enter the VM and run 'systemctl status backdoor.service'.2121server # [ 3277.737969] mobilizon[2504]: 14:35:51.341 [info] {"source":"oban","duration":19931,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2122server # [ 3287.231909] mobilizon[2504]: 14:36:00.833 [info] {"source":"oban","duration":9231,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2123server: Guest root shell did not produce any data yet...2124server: To debug, enter the VM and run 'systemctl status backdoor.service'.2125server # [ 3307.782505] mobilizon[2504]: 14:36:21.364 [info] {"source":"oban","duration":19238,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2126server: Guest root shell did not produce any data yet...2127server: To debug, enter the VM and run 'systemctl status backdoor.service'.2128server # [ 3337.789650] mobilizon[2504]: 14:36:51.392 [info] {"source":"oban","duration":24284,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2129server # [ 3347.245824] mobilizon[2504]: 14:37:00.847 [info] {"source":"oban","duration":8860,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2130server: Guest root shell did not produce any data yet...2131server: To debug, enter the VM and run 'systemctl status backdoor.service'.2132server # [ 3367.827631] mobilizon[2504]: 14:37:21.426 [info] {"source":"oban","duration":31015,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2133server: Guest root shell did not produce any data yet...2134server: To debug, enter the VM and run 'systemctl status backdoor.service'.2135server # [ 3397.844646] mobilizon[2504]: 14:37:51.444 [info] {"source":"oban","duration":14343,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2136server # [ 3407.259745] mobilizon[2504]: 14:38:00.861 [info] {"source":"oban","duration":10045,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2137server # [ 3415.251948] postgres[1389]: [1389] LOG: checkpoint starting: time2138server # [ 3415.363327] postgres[1389]: [1389] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.024 s, sync=0.016 s, total=0.133 s; sync files=1, longest=0.016 s, average=0.016 s; distance=4 kB, estimate=5554 kB; lsn=0/265A1E0, redo lsn=0/265A1882139server: Guest root shell did not produce any data yet...2140server: To debug, enter the VM and run 'systemctl status backdoor.service'.2141server # [ 3427.869351] mobilizon[2504]: 14:38:21.469 [info] {"source":"oban","duration":21980,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2142server: Guest root shell did not produce any data yet...2143server: To debug, enter the VM and run 'systemctl status backdoor.service'.2144server # [ 3457.896898] mobilizon[2504]: 14:38:51.499 [info] {"source":"oban","duration":25880,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2145server # [ 3467.272061] mobilizon[2504]: 14:39:00.873 [info] {"source":"oban","duration":8750,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2146server: Guest root shell did not produce any data yet...2147server: To debug, enter the VM and run 'systemctl status backdoor.service'.2148server # [ 3487.925213] mobilizon[2504]: 14:39:21.522 [info] {"source":"oban","duration":17320,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2149server: Guest root shell did not produce any data yet...2150server: To debug, enter the VM and run 'systemctl status backdoor.service'.2151server # [ 3517.949923] mobilizon[2504]: 14:39:51.552 [info] {"source":"oban","duration":25934,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2152server # [ 3527.287801] mobilizon[2504]: 14:40:00.889 [info] {"source":"oban","duration":10772,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2153server: Guest root shell did not produce any data yet...2154server: To debug, enter the VM and run 'systemctl status backdoor.service'.2155server # [ 3547.981857] mobilizon[2504]: 14:40:21.581 [info] {"source":"oban","duration":24508,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2156server: Guest root shell did not produce any data yet...2157server: To debug, enter the VM and run 'systemctl status backdoor.service'.2158server # [ 3578.002237] mobilizon[2504]: 14:40:51.600 [info] {"source":"oban","duration":15937,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2159server # [ 3587.303307] mobilizon[2504]: 14:41:00.904 [info] {"source":"oban","duration":11017,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2160timeout reached; test terminating...2161kill QemuMachine (pid 12)2162server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/lqn6mbgzzdrqq2qkwddcmxj9z6amdd86-python3-3.13.13/bin/python3.13)2163cleanup2164kill QemuMachine (pid 12)2165Terminated /nix/store/1lxxsi1vs9gb5ccvv03d1h8aaka0mdl3-nixos-test-driver-mobilizon/bin/nixos-test-driver -o $out