vm-test-run-mobilizon
checks.aarch64-linux.nixosTest
· build #1
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.01 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: QEMU running (pid 12)14server # Disk image does not exist, creating the virtualisation disk image...15server # Formatting '/build/vm-state-server/tmp.H1GFDyepdU', 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: cab1f084-f1d7-4dba-aad5-7b7d667a990120server # 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/rzapljrzcw7dmdpsihfwgckkgfpsxyl6-nixos-system-server-test/init regInfo=/nix/store/dzv5r5x7r2aakvb7rx13jrjyb727xjy5-closure-info/registration console=ttyAMA0,115200n8 console=tty066server # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/dzv5r5x7r2aakvb7rx13jrjyb727xjy5-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.000091] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns106server # [ 0.006638] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)107server # [ 0.035522] Console: colour dummy device 80x25108server # [ 0.039298] printk: legacy console [tty0] enabled109server # [ 0.052824] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)110server # [ 0.053848] pid_max: default: 32768 minimum: 301111server # [ 0.061719] LSM: initializing lsm=capability,landlock,yama,bpf,ima112server # [ 0.064787] landlock: Up and running.113server # [ 0.064925] Yama: becoming mindful.114server # [ 0.067289] LSM support for eBPF active115server # [ 0.073826] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116server # [ 0.074083] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)117server # [ 0.149652] cacheinfo: Unable to detect cache hierarchy for CPU 0118server # [ 0.181931] rcu: Hierarchical SRCU implementation.119server # [ 0.182205] rcu: Max phase no-delay instances is 1000.120server # [ 0.201128] fsl-mc MSI: its@8080000 domain created121server # [ 0.205524] EFI services will not be available.122server # [ 0.208624] smp: Bringing up secondary CPUs ...123server # [ 0.210159] smp: Brought up 1 node, 1 CPU124server # [ 0.210277] SMP: Total of 1 processors activated.125server # [ 0.210372] CPU: All CPU(s) started at EL1126server # [ 0.212040] CPU features: detected: Branch Target Identification127server # [ 0.212254] CPU features: detected: 32-bit EL0 Support128server # [ 0.212343] CPU features: detected: 32-bit EL1 Support129server # [ 0.212406] CPU features: detected: ARMv8.4 Translation Table Level130server # [ 0.212484] CPU features: detected: Instruction cache invalidation not required for I/D coherence131server # [ 0.212577] CPU features: detected: Data cache clean to the PoU not required for I/D coherence132server # [ 0.212736] CPU features: detected: Common not Private translations133server # [ 0.212804] CPU features: detected: CRC32 instructions134server # [ 0.212860] CPU features: detected: Data cache clean to Point of Deep Persistence135server # [ 0.212929] CPU features: detected: Data cache clean to Point of Persistence136server # [ 0.212992] CPU features: detected: Data independent timing control (DIT)137server # [ 0.213053] CPU features: detected: E0PD138server # [ 0.213103] CPU features: detected: Enhanced Counter Virtualization139server # [ 0.213189] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)140server # [ 0.213258] CPU features: detected: Enhanced Privileged Access Never141server # [ 0.213318] CPU features: detected: Enhanced Virtualization Traps142server # [ 0.213390] CPU features: detected: Fine Grained Traps143server # [ 0.213481] CPU features: detected: Guarded Control Stack (GCS)144server # [ 0.213634] CPU features: detected: Generic authentication (IMP DEF algorithm)145server # [ 0.213838] CPU features: detected: RCpc load-acquire (LDAPR)146server # [ 0.213972] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)147server # [ 0.214040] CPU features: detected: LSE atomic instructions148server # [ 0.214097] CPU features: detected: Memory Copy and Memory Set instructions149server # [ 0.214205] CPU features: detected: Privileged Access Never150server # [ 0.214341] CPU features: detected: PMUv3151server # [ 0.214402] CPU features: detected: SCTLR2152server # [ 0.214453] CPU features: detected: RAS Extension Support153server # [ 0.214556] CPU features: detected: RASv1p1 Extension Support154server # [ 0.214615] CPU features: detected: Random Number Generator155server # [ 0.214672] CPU features: detected: Speculation barrier (SB)156server # [ 0.214729] CPU features: detected: Stage-2 Force Write-Back157server # [ 0.214787] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality158server # [ 0.214856] CPU features: detected: TLB range maintenance instructions159server # [ 0.214917] CPU features: detected: WFx with timeout160server # [ 0.215139] CPU features: detected: Scalable Matrix Extension161server # [ 0.215423] CPU features: detected: FA64162server # [ 0.215506] CPU features: detected: SME2163server # [ 0.215568] CPU features: detected: Speculative Store Bypassing Safe (SSBS)164server # [ 0.215665] CPU features: detected: Scalable Vector Extension165server # [ 0.241694] alternatives: applying system-wide alternatives166server # [ 0.269364] CPU features: detected: Hardware dirty bit management on CPU0167server # [ 0.270973] SVE: maximum available vector length 256 bytes per vector168server # [ 0.271192] SVE: default vector length 64 bytes per vector169server # [ 0.273832] SME: minimum available vector length 16 bytes per vector170server # [ 0.273976] SME: maximum available vector length 256 bytes per vector171server # [ 0.274062] SME: default vector length 32 bytes per vector172server # [ 0.309620] Memory: 891048K/1048576K available (24576K kernel code, 7086K rwdata, 26312K rodata, 4864K init, 1102K bss, 116268K reserved, 32768K cma-reserved)173server # [ 0.356301] devtmpfs: initialized174server # [ 0.418687] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns175server # [ 0.420440] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)176server # [ 0.421043] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).177server # [ 0.428312] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL178server # [ 0.428482] 0 pages in range for non-PLT usage179server # [ 0.428584] 508240 pages in range for PLT usage180server # [ 0.435437] pinctrl core: initialized pinctrl subsystem181server # [ 0.463311] DMI not present or invalid.182server # [ 0.498883] NET: Registered PF_NETLINK/PF_ROUTE protocol family183server # [ 0.525322] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations184server # [ 0.528810] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations185server # [ 0.532585] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations186server # [ 0.533433] audit: initializing netlink subsys (disabled)187server # [ 0.538123] audit: type=2000 audit(0.452:1): state=initialized audit_enabled=0 res=1188server # [ 0.554245] thermal_sys: Registered thermal governor 'fair_share'189server # [ 0.554375] thermal_sys: Registered thermal governor 'bang_bang'190server # [ 0.554496] thermal_sys: Registered thermal governor 'step_wise'191server # [ 0.554564] thermal_sys: Registered thermal governor 'user_space'192server # [ 0.554626] thermal_sys: Registered thermal governor 'power_allocator'193server # [ 0.555774] cpuidle: using governor ladder194server # [ 0.556189] cpuidle: using governor menu195server # [ 0.560971] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.196server # [ 0.561896] ASID allocator initialised with 65536 entries197server # [ 0.583056] Serial: AMBA PL011 UART driver198server # [ 0.724716] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1199server # [ 0.730425] printk: console [ttyAMA0] enabled200server # [ 0.892419] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages201server # [ 0.892745] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page202server # [ 0.892869] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages203server # [ 0.892963] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page204server # [ 0.893053] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages205server # [ 0.893141] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page206server # [ 0.893253] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages207server # [ 0.893337] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page208server # [ 0.948560] fbcon: Taking over console209server # [ 0.953640] ACPI: Interpreter disabled.210server # [ 0.995695] iommu: Default domain type: Translated211server # [ 0.995978] iommu: DMA domain TLB invalidation policy: strict mode212server # [ 1.003336] SCSI subsystem initialized213server # [ 1.021592] usbcore: registered new interface driver usbfs214server # [ 1.022970] usbcore: registered new interface driver hub215server # [ 1.024263] usbcore: registered new device driver usb216server # [ 1.035653] pps_core: LinuxPPS API ver. 1 registered217server # [ 1.035962] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>218server # [ 1.044091] PTP clock support registered219server # [ 1.045219] EDAC MC: Ver: 3.0.0220server # [ 1.050174] scmi_core: SCMI protocol bus registered221server # [ 1.069399] FPGA manager framework222server # [ 1.116215] vgaarb: loaded223server # [ 1.132197] clocksource: Switched to clocksource arch_sys_counter224server # [ 1.160815] VFS: Disk quotas dquot_6.6.0225server # [ 1.162022] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)226server # [ 1.167600] netfs: FS-Cache loaded227server # [ 1.176429] pnp: PnP ACPI: disabled228server # [ 1.319489] NET: Registered PF_INET protocol family229server # [ 1.326562] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)230server # [ 1.611818] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)231server # [ 1.612542] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)232server # [ 1.614015] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)233server # [ 1.615683] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)234server # [ 1.616402] TCP: Hash tables configured (established 8192 bind 8192)235server # [ 1.620842] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)236server # [ 1.622559] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)237server # [ 1.624078] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)238server # [ 1.628380] NET: Registered PF_UNIX/PF_LOCAL protocol family239server # [ 1.630040] NET: Registered PF_XDP protocol family240server # [ 1.631208] PCI: CLS 0 bytes, default 64241server # [ 1.641627] Trying to unpack rootfs image as initramfs...242server # [ 1.651484] kvm [1]: HYP mode not available243server # [ 2.319364] Initialise system trusted keyrings244server # [ 2.329366] workingset: timestamp_bits=42 max_order=18 bucket_order=0245server # [ 2.354637] squashfs: version 4.0 (2009/01/31) Phillip Lougher246server # [ 2.361748] 9p: Installing v9fs 9p2000 file system support247server # [ 2.632785] Key type asymmetric registered248server # [ 2.636747] Asymmetric key parser 'x509' registered249server # [ 2.637807] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)250server # [ 2.641083] io scheduler mq-deadline registered251server # [ 2.641427] io scheduler kyber registered252server # [ 2.816119] pl061_gpio 9030000.pl061: PL061 GPIO chip registered253server # [ 2.842723] ledtrig-cpu: registered to indicate activity on CPUs254server # [ 2.861541] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:255server # [ 2.866090] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000256server # [ 2.874391] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000257server # [ 2.875929] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000258server # [ 2.878170] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits259server # [ 2.885336] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]260server # [ 2.890338] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00261server # [ 2.894849] pci_bus 0000:00: root bus resource [bus 00-ff]262server # [ 2.896024] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]263server # [ 2.896887] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]264server # [ 2.897697] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]265server # [ 2.908539] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint266server # [ 2.931410] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint267server # [ 2.932500] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]268server # [ 2.932766] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]269server # [ 2.932965] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]270server # [ 2.933396] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]271server # [ 2.938304] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint272server # [ 2.938641] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]273server # [ 2.938767] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]274server # [ 2.938881] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275server # [ 2.946243] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint276server # [ 2.946596] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]277server # [ 2.946720] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]278server # [ 2.946832] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279server # [ 2.950516] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint280server # [ 2.950859] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]281server # [ 2.950981] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]282server # [ 2.951092] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]283server # [ 2.957256] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint284server # [ 2.957631] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]285server # [ 2.957755] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]286server # [ 2.957862] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]287server # [ 2.961393] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint288server # [ 2.961734] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]289server # [ 2.961856] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]290server # [ 2.961967] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]291server # [ 2.969512] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint292server # [ 2.969886] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]293server # [ 2.970013] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]294server # [ 2.970122] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]295server # [ 2.970250] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]296server # [ 2.977664] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint297server # [ 2.978077] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]298server # [ 2.978359] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]299server # [ 2.981983] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint300server # [ 2.982362] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]301server # [ 2.982503] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]302server # [ 2.990077] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint303server # [ 2.990600] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]304server # [ 2.994108] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint305server # [ 2.994660] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]306server # [ 2.994819] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]307server # [ 2.994932] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]308server # [ 3.001481] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint309server # [ 3.001856] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]310server # [ 3.001979] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]311server # [ 3.002092] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]312server # [ 3.018317] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned313server # [ 3.018898] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned314server # [ 3.019243] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned315server # [ 3.019712] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned316server # [ 3.019976] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned317server # [ 3.020527] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned318server # [ 3.020728] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned319server # [ 3.020882] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned320server # [ 3.021026] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned321server # [ 3.021213] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned322server # [ 3.021380] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned323server # [ 3.021530] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned324server # [ 3.021764] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned325server # [ 3.021984] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned326server # [ 3.022162] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned327server # [ 3.022306] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned328server # [ 3.022429] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned329server # [ 3.022547] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned330server # [ 3.022661] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned331server # [ 3.022777] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned332server # [ 3.022892] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned333server # [ 3.023007] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned334server # [ 3.023122] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned335server # [ 3.023260] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned336server # [ 3.023381] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned337server # [ 3.023521] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned338server # [ 3.023786] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned339server # [ 3.024200] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned340server # [ 3.024345] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned341server # [ 3.024468] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned342server # [ 3.024587] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned343server # [ 3.024698] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned344server # [ 3.024809] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned345server # [ 3.025019] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned346server # [ 3.025802] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]347server # [ 3.025978] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]348server # [ 3.026088] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]349server # [ 3.063658] pci 0000:00:0a.0: enabling device (0000 -> 0002)350server # [ 3.461502] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)351server # [ 3.479360] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)352server # [ 3.488236] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)353server # [ 3.495168] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)354server # [ 3.503231] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)355server # [ 3.518369] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)356server # [ 3.522920] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)357server # [ 3.536256] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)358server # [ 3.541441] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)359server # [ 3.551520] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)360server # [ 3.558505] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)361server # [ 3.669563] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled362server # [ 3.728113] msm_serial: driver initialized363server # [ 3.731394] SuperH (H)SCI(F) driver initialized364server # [ 3.738851] STM32 USART driver initialized365server # [ 3.992364] loop: module loaded366server # [ 3.994137] virtio_blk virtio5: 1/0/0 default/read/poll queues367server # [ 4.014232] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)368server # [ 4.082492] megasas: 07.734.00.00-rc1369server # [ 4.114485] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]370server # [ 4.124955] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000371server # [ 4.128911] Intel/Sharp Extended Query Table at 0x0031372server # [ 4.134821] Using buffer write method373server # [ 4.141050] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]374server # [ 4.147019] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000375server # [ 4.154690] Intel/Sharp Extended Query Table at 0x0031376server # [ 4.158621] Using buffer write method377server # [ 4.164550] Concatenating MTD devices:378server # [ 4.164775] (0): "0.flash"379server # [ 4.164890] (1): "0.flash"380server # [ 4.164986] into device "0.flash"381server # [ 5.460310] Freeing initrd memory: 25352K382server # [ 5.713400] tun: Universal TUN/TAP device driver, 1.6383server # [ 5.746416] thunder_xcv, ver 1.0384server # [ 5.747663] thunder_bgx, ver 1.0385server # [ 5.748860] nicpf, ver 1.0386server # [ 5.758712] e1000: Intel(R) PRO/1000 Network Driver387server # [ 5.758997] e1000: Copyright (c) 1999-2006 Intel Corporation.388server # [ 5.760876] e1000e: Intel(R) PRO/1000 Network Driver389server # [ 5.761116] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.390server # [ 5.762533] igb: Intel(R) Gigabit Ethernet Network Driver391server # [ 5.762732] igb: Copyright (c) 2007-2014 Intel Corporation.392server # [ 5.764399] igbvf: Intel(R) Gigabit Virtual Function Network Driver393server # [ 5.764608] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.394server # [ 5.767698] sky2: driver version 1.30395server # [ 5.795168] ehci-pci 0000:00:0a.0: EHCI Host Controller396server # [ 5.800948] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1397server # [ 5.807280] usbcore: registered new interface driver usb-storage398server # [ 5.811723] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000399server # [ 5.814720] usbcore: registered new interface driver usbserial_generic400server # [ 5.816777] usbserial: USB Serial support registered for generic401server # [ 5.828227] hv_vmbus: registering driver hyperv_keyboard402server # [ 5.829308] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00403server # [ 5.866312] hub 1-0:1.0: USB hub found404server # [ 5.873997] rtc-pl031 9010000.pl031: registered as rtc0405server # [ 5.876081] rtc-pl031 9010000.pl031: setting system clock to 2026-08-30T21:52:29 UTC (1788126749)406server # [ 5.885772] hub 1-0:1.0: 6 ports detected407server # [ 5.892111] i2c_dev: i2c /dev entries driver408server # [ 5.943335] sdhci: Secure Digital Host Controller Interface driver409server # [ 5.943724] sdhci: Copyright(c) Pierre Ossman410server # [ 5.949754] Synopsys Designware Multimedia Card Interface Driver411server # [ 5.956812] sdhci-pltfm: SDHCI platform and OF driver helper412server # [ 5.980768] hid: raw HID events driver (C) Jiri Kosina413server # [ 5.986687] usbcore: registered new interface driver usbhid414server # [ 5.986940] usbhid: USB HID core driver415server # [ 6.009377] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available416server # [ 6.038764] drop_monitor: Initializing network drop monitor service417server # [ 6.043216] NET: Registered PF_INET6 protocol family418server # [ 6.062224] Segment Routing with IPv6419server # [ 6.063368] In-situ OAM (IOAM) with IPv6420server # [ 6.065363] NET: Registered PF_PACKET protocol family421server # [ 6.068182] 9pnet: Installing 9P2000 support422server # [ 6.079430] Key type dns_resolver registered423server # [ 6.156874] usb 1-1: new high-speed USB device number 2 using ehci-pci424server # [ 6.197829] registered taskstats version 1425server # [ 6.204231] Loading compiled-in X.509 certificates426server # [ 6.381983] 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/input0427server # [ 6.401549] Demotion targets for Node 0: null428server # [ 6.404910] Key type .fscrypt registered429server # [ 6.405610] Key type fscrypt-provisioning registered430server # [ 6.412942] ima: No TPM chip found, activating TPM-bypass!431server # [ 6.413865] ima: Allocated hash algorithm: sha1432server # [ 6.419268] ima: No architecture policies found433server # [ 6.437510] input: gpio-keys as /devices/platform/gpio-keys/input/input1434server # [ 6.461340] 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.576419] clk: Disabling unused clocks436server # [ 6.577506] PM: genpd: Disabling unused power domains437server # [ 6.600195] usb 1-2: new high-speed USB device number 3 using ehci-pci438server # [ 7.211079] Freeing unused kernel memory: 4864K439server # [ 7.216578] Run /init as init process440server # [ 7.345040] 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/input2441server # [ 7.349279] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0442server # [ 7.552179] systemd[1]: Successfully made /usr/ read-only.443server # [ 7.928531] 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)444server # [ 7.931567] systemd[1]: Detected virtualization qemu.445server # [ 7.933573] systemd[1]: Detected architecture arm64.446server # [ 7.934810] systemd[1]: Running in initrd.447server # [ 7.953841] systemd[1]: Initializing machine ID from random generator.448server # [ 7.962913] systemd[1]: Hostname set to <server>.449server # [ 10.072355] systemd[1]: Queued start job for default target Initrd Default Target.450server # [ 11.767455] systemd[1]: Created slice Slice /system/modprobe.451server # [ 11.781547] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.452server # [ 11.785785] systemd[1]: Expecting device /dev/disk/by-label/nixos...453server # [ 11.789395] systemd[1]: Reached target Path Units.454server # [ 11.791351] systemd[1]: Reached target Slice Units.455server # [ 11.793603] systemd[1]: Reached target Swaps.456server # [ 11.795289] systemd[1]: Reached target Timer Units.457server # [ 11.805717] systemd[1]: Listening on D-Bus System Message Bus Socket.458server # [ 11.813637] systemd[1]: Listening on Journal Socket (/dev/log).459server # [ 11.819550] systemd[1]: Listening on Journal Sockets.460server # [ 11.825638] systemd[1]: Listening on udev Control Socket.461server # [ 11.830014] systemd[1]: Listening on udev Kernel Socket.462server # [ 11.832291] systemd[1]: Reached target Socket Units.463server # [ 11.923461] systemd[1]: Starting Create List of Static Device Nodes...464server # [ 12.045418] systemd[1]: Starting Load Kernel Module 9pnet_virtio...465server # [ 12.054504] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs466server # [ 12.246187] systemd[1]: Mounting Kernel Configuration File System...467server # [ 12.681920] systemd[1]: Starting Journal Service...468server # [ 12.838005] systemd[1]: Starting Load Kernel Modules...469server # [ 12.845521] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki470server # [ 12.985644] systemd[1]: Starting Coldplug All udev Devices...471server # [ 13.522563] systemd[1]: Finished Create List of Static Device Nodes.472server # [ 13.681031] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.473server # [ 13.815089] systemd[1]: Finished Load Kernel Module 9pnet_virtio.474server # [ 14.093379] systemd[1]: Mounted Kernel Configuration File System.475server # [ 14.257321] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.476server # [ 14.274105] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev477server # [ 14.353789] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...478server # [ 14.564994] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0479server # [ 14.567325] [drm] features: -virgl +edid -resource_blob -host_visible480server # [ 14.567556] [drm] features: -context_init481server # [ 14.593409] [drm] number of scanouts: 1482server # [ 14.596459] [drm] number of cap sets: 0483server # [ 14.656999] systemd-journald[131]: Collecting audit messages is disabled.484server # [ 14.688266] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic485server # [ 14.688620] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0486server # [ 15.052761] Console: switching to colour frame buffer device 160x50487server # [ 15.129243] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device488server # [ 15.329431] systemd[1]: Finished Load Kernel Modules.489server # [ 15.474824] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.490server # [ 15.673908] systemd[1]: Starting Apply Kernel Variables...491server # [ 15.953916] systemd[1]: Starting Create Static Device Nodes in /dev...492server # [ 16.830246] systemd[1]: Finished Apply Kernel Variables.493server # [ 17.013232] systemd[1]: Finished Create Static Device Nodes in /dev.494server # [ 17.043132] systemd[1]: Reached target Preparation for Local File Systems.495server # [ 17.050900] systemd[1]: Reached target Local File Systems.496server # [ 17.242098] systemd[1]: Starting Rule-based Manager for Device Events and Files...497server # [ 19.678503] systemd[1]: Started Journal Service.498server # [ 19.575274] systemd-modules-load[133]: Inserted module 'dm_mod'499server # [ 19.665714] systemd-modules-load[133]: Module 'virtio_balloon' is built in500server # [ 19.687255] systemd-modules-load[133]: Module 'virtio_console' is built in501server # [ 19.714328] systemd-modules-load[133]: Inserted module 'virtio_gpu'502server # [ 19.740958] systemd-modules-load[133]: Module 'virtio_rng' is built in503server # [ 19.793391] systemd-udevd[154]: Using default interface naming scheme 'v260'.504server # [ 19.831176] systemd[1]: Starting Create System Files and Directories...505server # [ 20.773223] systemd[1]: Finished Create System Files and Directories.506server # [ 21.090022] systemd[1]: Started Rule-based Manager for Device Events and Files.507server # [ 22.913789] systemd[1]: Starting Virtual Console Setup...508server # [ 24.582963] systemd-vconsole-setup[179]: Configuration of first virtual console was skipped, ignoring remaining ones.509server # [ 24.753331] systemd[1]: Finished Virtual Console Setup.510server: Guest root shell did not produce any data yet...511server: To debug, enter the VM and run 'systemctl status backdoor.service'.512server # [ 45.185942] systemd[1]: Finished Coldplug All udev Devices.513server # [ 45.206038] systemd[1]: Reached target System Initialization.514server # [ 45.217792] systemd[1]: Reached target Basic System.515server # [ 55.385578] (udev-worker)[211]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.516server # [ 55.407394] (udev-worker)[211]: Network interface NamePolicy= disabled on kernel command line.517server # [ 55.455513] (udev-worker)[210]: Network interface NamePolicy= disabled on kernel command line.518server # [ 57.567393] systemd[1]: Found device /dev/disk/by-label/nixos.519server # [ 57.582672] systemd[1]: Reached target Initrd Root Device.520server # [ 57.671121] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...521server # [ 58.613104] systemd-fsck[234]: nixos: clean, 12/65536 files, 13019/262144 blocks522server # [ 58.803330] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.523server # [ 58.935021] systemd[1]: Mounting /sysroot...524server: Guest root shell did not produce any data yet...525server: To debug, enter the VM and run 'systemctl status backdoor.service'.526server # [ 59.597583] EXT4-fs (vda): mounted filesystem cab1f084-f1d7-4dba-aad5-7b7d667a9901 r/w with ordered data mode. Quota mode: none.527server # [ 59.609308] systemd[1]: Mounted /sysroot.528server # [ 59.654669] systemd[1]: Reached target Initrd Root File System.529server # [ 59.904770] systemd[1]: Mounting /sysroot/nix/.ro-store...530server # [ 60.109216] systemd[1]: Mounting /sysroot/nix/.rw-store...531server # [ 60.415022] systemd[1]: Mounting /sysroot/run...532server # [ 60.715349] systemd[1]: Mounting /sysroot/tmp/shared...533server # [ 60.954510] systemd[1]: Mounting /sysroot/tmp/xchg...534server # [ 61.119244] systemd[1]: Starting Mountpoints Configured in the Real Root...535server # [ 61.882937] systemd-sysroot-fstab-check[255]: /sysroot should be mounted in the initrd, will request daemon-reload.536server # [ 61.899170] systemd[1]: Mounted /sysroot/nix/.ro-store.537server # [ 61.913688] systemd[1]: Mounted /sysroot/nix/.rw-store.538server # [ 61.921636] systemd[1]: Mounted /sysroot/run.539server # [ 61.931671] systemd[1]: Mounted /sysroot/tmp/shared.540server # [ 61.959669] systemd[1]: Mounted /sysroot/tmp/xchg.541server # [ 62.091300] systemd[1]: Reload requested from client PID 255 ('systemd-sysroot') (unit initrd-parse-etc.service)...542server # [ 62.101648] systemd[1]: Reloading...543server # [ 68.649017] systemd[1]: Reloading finished in 6517 ms.544server # [ 69.299594] systemd-sysroot-fstab-check[255]: Requesting initrd-fs.target/start/replace...545server # [ 70.963708] systemd-sysroot-fstab-check[255]: Requesting swap.target/start/replace...546server # [ 71.059276] systemd[1]: Starting rw-sysroot-nix-store.service...547server # [ 71.082077] systemd[1]: initrd-parse-etc.service: Deactivated successfully.548server # [ 71.155990] systemd[1]: Finished Mountpoints Configured in the Real Root.549server # [ 71.163860] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.550server # [ 71.434574] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.551server # [ 71.474748] systemd[1]: Finished rw-sysroot-nix-store.service.552server # [ 71.624906] systemd[1]: Mounting /sysroot/nix/store...553server # [ 72.459805] systemd[1]: Mounted /sysroot/nix/store.554server # [ 72.485213] systemd[1]: Reached target Initrd File Systems.555server # [ 72.611097] systemd[1]: Starting Find NixOS closure...556server # [ 72.801399] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...557server # [ 73.723727] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.558server # [ 73.773445] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.559server # [ 74.078978] systemd[1]: Finished Find NixOS closure.560server # [ 74.113643] systemd[1]: Reached target Initrd Default Target.561server # [ 74.189691] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...562server # [ 74.911328] systemd[1]: Stopped target Initrd Default Target.563server # [ 74.925800] systemd[1]: Stopped target Basic System.564server # [ 74.935071] systemd[1]: Stopped target Initrd Root Device.565server # [ 74.945945] systemd[1]: Stopped target Path Units.566server # [ 74.959888] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.567server # [ 74.995801] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.568server # [ 75.020921] systemd[1]: Stopped target Slice Units.569server # [ 75.034925] systemd[1]: Stopped target Socket Units.570server # [ 75.053980] systemd[1]: Stopped target System Initialization.571server # [ 75.065664] systemd[1]: Stopped target Swaps.572server # [ 75.085464] systemd[1]: Stopped target Timer Units.573server # [ 75.098168] systemd[1]: dbus.socket: Deactivated successfully.574server # [ 75.113165] systemd[1]: Closed D-Bus System Message Bus Socket.575server # [ 75.253384] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.576server # [ 75.269909] systemd[1]: Stopped Find NixOS closure.577server # [ 75.545654] systemd[1]: Starting Load Kernel Module 9pnet_virtio...578server # [ 75.760959] systemd[1]: Starting rw-sysroot-nix-store.service...579server # [ 75.771173] systemd[1]: systemd-sysctl.service: Deactivated successfully.580server # [ 75.793130] systemd[1]: Stopped Apply Kernel Variables.581server # [ 76.080038] systemd[1]: systemd-modules-load.service: Deactivated successfully.582server # [ 76.102805] systemd[1]: Stopped Load Kernel Modules.583server # [ 76.351923] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.584server # [ 76.370793] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.585server # [ 76.529376] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.586server # [ 76.545111] systemd[1]: Stopped Create System Files and Directories.587server # [ 76.694397] systemd[1]: Stopped target Local File Systems.588server # [ 76.706687] systemd[1]: Stopped target Preparation for Local File Systems.589server # [ 76.718542] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.590server # [ 76.731778] systemd[1]: Stopped Coldplug All udev Devices.591server # [ 76.751574] systemd[1]: systemd-udev-trigger.service: Consumed 16.581s CPU time over 1min 3.872s wall clock time, 9.2M memory peak.592server # [ 76.873069] systemd[1]: Stopping Rule-based Manager for Device Events and Files...593server # [ 76.877753] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.594server # [ 76.886745] systemd[1]: Stopped Virtual Console Setup.595server # [ 77.087248] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.596server # [ 77.118913] systemd[1]: Finished Load Kernel Module 9pnet_virtio.597server # [ 77.214117] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.598server # [ 77.257773] systemd[1]: Finished rw-sysroot-nix-store.service.599server # [ 77.437741] systemd[1]: initrd-cleanup.service: Deactivated successfully.600server # [ 77.474010] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.601server # [ 78.386744] systemd[1]: systemd-udevd.service: Deactivated successfully.602server # [ 78.425396] systemd[1]: Stopped Rule-based Manager for Device Events and Files.603server # [ 78.437644] systemd[1]: systemd-udevd.service: Consumed 20.575s CPU time over 1min 1.260s wall clock time, 19.5M memory peak.604server # [ 78.507186] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.605server # [ 78.517288] systemd[1]: Closed udev Control Socket.606server # [ 78.638060] systemd[1]: Starting Cleanup udev Database...607server # [ 78.647044] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.608server # [ 78.654458] systemd[1]: Stopped Create Static Device Nodes in /dev.609server # [ 78.770079] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.610server # [ 78.776825] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.611server # [ 78.913184] systemd[1]: kmod-static-nodes.service: Deactivated successfully.612server # [ 78.918359] systemd[1]: Stopped Create List of Static Device Nodes.613server # [ 79.231421] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.614server # [ 79.275906] systemd[1]: Finished Cleanup udev Database.615server # [ 79.349972] systemd[1]: Reached target Switch Root.616server # [ 79.423280] systemd[1]: Starting NixOS Activation...617server # [ 82.175105] initrd-nixos-activation-start[558]: booting system configuration /nix/store/rzapljrzcw7dmdpsihfwgckkgfpsxyl6-nixos-system-server-test618server # [ 83.270969] initrd-nixos-activation-start[558]: running activation script...619server: Guest root shell did not produce any data yet...620server: To debug, enter the VM and run 'systemctl status backdoor.service'.621server # [ 91.107584] initrd-nixos-activation-start[583]: setting up /etc...622server # [ 95.427347] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.623server # [ 95.464029] systemd[1]: Finished NixOS Activation.624server # [ 95.474436] systemd[1]: initrd-nixos-activation.service: Consumed 9.032s CPU time over 16.008s wall clock time, 22.5M memory peak.625server # [ 95.597050] systemd[1]: Starting Switch Root...626server # [ 95.876873] systemd[1]: Switching root.627server # [ 100.411033] systemd-journald[131]: Received SIGTERM from PID 1 (systemd).628server # [ 103.931420] 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 # [ 103.937952] systemd[1]: Detected virtualization qemu.630server # [ 103.942219] systemd[1]: Detected architecture arm64.631server # [ 103.947379] systemd[1]: Detected first boot.632server # [ 104.008226] systemd[1]: Initializing machine ID from random generator.633server # [ 107.590505] systemd[1]: bpf-restrict-fs: LSM BPF program attached634server # [ 110.571053] systemd[1]: Applying preset policy.635server # [ 118.501767] systemd[1]: Populated /etc with preset unit settings.636server: Guest root shell did not produce any data yet...637server: To debug, enter the VM and run 'systemctl status backdoor.service'.638server # [ 126.868844] systemd[1]: initrd-switch-root.service: Deactivated successfully.639server # [ 126.881420] systemd[1]: Stopped initrd-switch-root.service.640server # [ 126.946506] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.641server # [ 126.985855] systemd[1]: Created slice Slice /system/getty.642server # [ 127.015329] systemd[1]: Created slice User and Session Slice.643server # [ 127.029056] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.644server # [ 127.039007] systemd[1]: Started Forward Password Requests to Wall Directory Watch.645server # [ 127.048503] systemd[1]: Expecting device /dev/hvc0...646server # [ 127.055592] systemd[1]: Expecting device /dev/ttyAMA0...647server # [ 127.067593] systemd[1]: Reached target Local Encrypted Volumes.648server # [ 127.076596] systemd[1]: Stopped target initrd-fs.target.649server # [ 127.084610] systemd[1]: Stopped target initrd-root-fs.target.650server # [ 127.092445] systemd[1]: Stopped target initrd-switch-root.target.651server # [ 127.100446] systemd[1]: Reached target Virtual Machines and Containers.652server # [ 127.108875] systemd[1]: Reached target Path Units.653server # [ 127.116808] systemd[1]: Reached target Remote File Systems.654server # [ 127.127118] systemd[1]: Reached target Slice Units.655server # [ 127.135143] systemd[1]: Reached target Swaps.656server # [ 127.197615] systemd[1]: Listening on Process Core Dump Socket.657server # [ 127.248935] systemd[1]: Listening on Credential Encryption/Decryption.658server # [ 127.333438] systemd[1]: Starting Journal Log Access Socket...659server # [ 127.390005] systemd[1]: Listening on Journal Audit Socket.660server # [ 127.413288] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.661server # [ 127.423025] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki662server # [ 127.435081] systemd[1]: Listening on udev Control Socket.663server # [ 127.511472] systemd[1]: Mounting Huge Pages File System...664server # [ 127.632791] systemd[1]: Mounting POSIX Message Queue File System...665server # [ 127.806866] systemd[1]: Mounting Kernel Debug File System...666server # [ 128.030748] systemd[1]: Mounting Kernel Trace File System...667server # [ 128.318591] systemd[1]: Starting Create List of Static Device Nodes...668server # [ 128.673950] systemd[1]: Starting Load Kernel Module 9pnet_virtio...669server # [ 128.724638] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs670server # [ 129.060030] systemd[1]: Mounting Kernel Configuration File System...671server # [ 129.110944] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm672server # [ 129.209294] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore673server # [ 130.025111] systemd[1]: Starting Load Kernel Module fuse...674server # [ 130.165818] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67675server # [ 130.580683] systemd[1]: Starting Journal Service...676server # [ 130.973903] systemd[1]: Starting Load Kernel Modules...677server # [ 131.725071] fuse: init (API version 7.45)678server # [ 131.735655] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...679server # [ 132.010451] systemd[1]: Starting Remount Root and Kernel File Systems...680server # [ 132.066852] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki681server # [ 132.572853] systemd[1]: Starting Coldplug All udev Devices...682server # [ 133.442311] systemd-journald[792]: Collecting audit messages is enabled.683server # [ 133.593811] systemd[1]: Listening on Journal Log Access Socket.684server # [ 133.691306] systemd[1]: Mounted Huge Pages File System.685server # [ 133.771423] systemd[1]: Mounted POSIX Message Queue File System.686server # [ 133.834697] systemd[1]: Mounted Kernel Debug File System.687server # [ 133.906162] systemd[1]: Mounted Kernel Trace File System.688server # [ 134.003289] systemd[1]: Finished Create List of Static Device Nodes.689server # [ 134.090754] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.690server # [ 134.185419] systemd[1]: Finished Load Kernel Module 9pnet_virtio.691server # [ 134.263311] systemd[1]: Mounted Kernel Configuration File System.692server # [ 134.366746] systemd[1]: modprobe@fuse.service: Deactivated successfully.693server # [ 134.457629] systemd[1]: Finished Load Kernel Module fuse.694server # [ 134.339051] systemd[1]: Queued start job for default target Multi-User System.695server # [ 134.541476] EXT4-fs (vda): re-mounted cab1f084-f1d7-4dba-aad5-7b7d667a9901.696server # [ 134.549907] systemd[1]: Started Journal Service.697server # [ 134.458257] systemd[1]: systemd-journald.service: Deactivated successfully.698server # [ 134.570698] systemd[1]: systemd-journald.service: Consumed 2.572s CPU time over 1min 54.032s wall clock time, 3.9M memory peak.699server # [ 134.658720] systemd-modules-load[793]: Module 'atkbd' is built in700server # [ 134.690130] systemd-modules-load[793]: Module 'loop' is built in701server # [ 134.730379] systemd-modules-load[793]: Inserted module 'tls'702server # [ 134.793210] systemd[1]: Finished Load Kernel Modules.703server # [ 134.884039] systemd[1]: Finished Remount Root and Kernel File Systems.704server # [ 135.691539] systemd[1]: Mounting FUSE Control File System...705server # [ 135.967159] systemd[1]: Starting Firewall...706server # [ 136.341354] systemd[1]: Starting Flush Journal to Persistent Storage...707server # [ 136.367060] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore708server # [ 136.947805] systemd[1]: Starting Load/Save OS Random Seed...709server # [ 137.042141] systemd-oomd[794]: No swap; memory pressure usage will be degraded710server # [ 137.524973] systemd[1]: Starting Apply Kernel Variables...711server # [ 138.370592] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...712server # [ 138.430959] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki713server # [ 139.078321] systemd-journald[792]: Received client request to flush runtime journal.714server # [ 141.577760] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.715server # [ 141.667457] systemd[1]: Mounted FUSE Control File System.716server # [ 141.743718] systemd[1]: Finished Load/Save OS Random Seed.717server # [ 141.837887] systemd[1]: Reached target First Boot Complete.718server # [ 141.883728] systemd[1]: Finished Flush Journal to Persistent Storage.719server # [ 142.561704] systemd[1]: Finished Apply Kernel Variables.720server # [ 143.698770] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.721server # [ 143.926003] systemd[1]: Starting Create Static Device Nodes in /dev...722server # [ 146.258057] systemd[1]: Finished Create Static Device Nodes in /dev.723server # [ 146.349166] systemd[1]: Reached target Preparation for Local File Systems.724server # [ 146.489468] systemd[1]: Mounting /run/wrappers...725server # [ 146.793914] systemd[1]: Starting Rule-based Manager for Device Events and Files...726server # [ 148.419152] systemd[1]: Mounted /run/wrappers.727server # [ 148.467224] systemd[1]: Reached target Local File Systems.728server # [ 148.630127] systemd[1]: Listening on Boot Loader Control Service Socket.729server # [ 148.837804] systemd[1]: Starting register-nix-paths.service...730server # [ 149.176999] systemd[1]: Starting Create SUID/SGID Wrappers...731server # [ 149.233118] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.732server: Guest root shell did not produce any data yet...733server: To debug, enter the VM and run 'systemctl status backdoor.service'.734server # [ 149.955038] systemd[1]: Starting Save Transient machine-id to Disk...735server # [ 150.609214] systemd[1]: Starting Create System Files and Directories...736server # [ 154.001653] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.737server # [ 154.085435] systemd[1]: Finished Save Transient machine-id to Disk.738server # [ 159.455537] systemd[1]: Finished Create System Files and Directories.739server # [ 160.093311] systemd[1]: Starting Rebuild Journal Catalog...740server # [ 160.765968] systemd[1]: Starting Record System Boot/Shutdown in UTMP...741server # [ 164.575447] systemd[1]: Finished Record System Boot/Shutdown in UTMP.742server # [ 168.463532] systemd[1]: Finished Rebuild Journal Catalog.743server # [ 168.848892] systemd[1]: Starting Update is Completed...744server # [ 171.319500] systemd[1]: Finished Update is Completed.745server: Guest root shell did not produce any data yet...746server: To debug, enter the VM and run 'systemctl status backdoor.service'.747server # [ 179.381496] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.748server # [ 179.437980] systemd[1]: Finished Create SUID/SGID Wrappers.749server # [ 179.465366] systemd[1]: suid-sgid-wrappers.service: Consumed 3.776s CPU time over 30.196s wall clock time, 5.2M memory peak.750server # [ 183.435207] systemd[1]: Finished Firewall.751server # [ 186.935890] systemd-udevd[849]: Using default interface naming scheme 'v260'.752server # [ 188.365171] systemd[1]: Finished register-nix-paths.service.753server # [ 190.137865] systemd[1]: Started Rule-based Manager for Device Events and Files.754server: Guest root shell did not produce any data yet...755server: To debug, enter the VM and run 'systemctl status backdoor.service'.756server # [ 209.899104] systemd[1]: Finished Coldplug All udev Devices.757server # [ 209.921792] systemd[1]: Reached target System Initialization.758server # [ 209.953041] systemd[1]: Started Discard unused filesystem blocks once a week.759server # [ 209.964683] systemd[1]: Started Daily Cleanup of Temporary Directories.760server # [ 209.987076] systemd[1]: Reached target Timer Units.761server # [ 209.994710] systemd[1]: Listening on D-Bus System Message Bus Socket.762server # [ 210.013844] systemd[1]: Listening on Nix Daemon Socket.763server # [ 210.026675] systemd[1]: Listening on Hostname Service Socket.764server # [ 210.038422] systemd[1]: Reached target Socket Units.765server # [ 210.047169] systemd[1]: Reached target Basic System.766server # [ 210.125805] systemd[1]: Starting Import lastlog data into lastlog2 database...767server # [ 210.325104] systemd[1]: Starting Name Service Cache Daemon (nsncd)...768server # [ 210.569168] systemd[1]: Starting Post-Boot Actions...769server # [ 211.001373] systemd[1]: Started Reset console on configuration changes.770server # [ 211.637087] systemd[1]: Starting resolvconf update...771server # [ 213.591986] systemd[1]: Starting D-Bus System Message Bus...772server # [ 213.683486] nsncd[1037]: Aug 30 21:55:57.315 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"773server # [ 213.711578] systemd[1]: Started Name Service Cache Daemon (nsncd).774server # [ 213.946367] systemd[1]: Finished Post-Boot Actions.775server # [ 214.694510] systemd[1]: Finished Import lastlog data into lastlog2 database.776server # [ 214.715858] systemd[1]: Reached target Host and Network Name Lookups.777server # [ 214.738909] systemd[1]: Reached target User and Group Name Lookups.778server # [ 215.256988] systemd[1]: Starting User Login Management...779server # [ 218.022009] dbus-broker-launch[1045]: Looking up NSS user entry for 'systemd-timesync'...780server # [ 218.519355] dbus-broker-launch[1045]: NSS returned no entry for 'systemd-timesync'781server # [ 218.543202] dbus-broker-launch[1045]: Invalid user-name in /nix/store/58p5ppvra2m02zkqr4jllpvcas9cspab-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"782server # [ 219.297920] systemd[1]: Started D-Bus System Message Bus.783server # [ 220.781467] systemd-logind[1059]: New seat seat0.784server # [ 220.913317] dbus-broker-launch[1045]: Ready785server # [ 221.039186] systemd[1]: Started User Login Management.786server # [ 221.379871] systemd[1]: Stopped target Host and Network Name Lookups.787server # [ 221.399595] systemd[1]: Stopping Host and Network Name Lookups...788server # [ 221.418499] systemd[1]: Stopped target User and Group Name Lookups.789server # [ 221.430604] systemd[1]: Stopping User and Group Name Lookups...790server # [ 221.558445] systemd[1]: Starting linger-users.service...791server # [ 221.658729] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...792server # [ 221.869264] systemd[1]: nscd.service: Deactivated successfully.793server # [ 221.954763] systemd[1]: Stopped Name Service Cache Daemon (nsncd).794server # [ 222.827499] systemd[1]: Starting Name Service Cache Daemon (nsncd)...795server # [ 223.621181] systemd[1]: linger-users.service: Deactivated successfully.796server # [ 223.686084] systemd[1]: Finished linger-users.service.797server # [ 224.073876] systemd[1]: Finished resolvconf update.798server # [ 224.117763] systemd[1]: Reached target Preparation for Network.799server # [ 224.412772] systemd[1]: Starting DHCP Client...800server # [ 224.634501] nsncd[1110]: Aug 30 21:56:08.358 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"801server # [ 224.677109] systemd[1]: Starting Extra networking commands....802server # [ 224.783179] systemd[1]: Started Name Service Cache Daemon (nsncd).803server # [ 224.877246] systemd[1]: Reached target Host and Network Name Lookups.804server # [ 224.912663] systemd[1]: Reached target User and Group Name Lookups.805server # [ 229.747419] systemd-logind[1059]: Watching system buttons on /dev/input/event1 (gpio-keys)806server # [ 230.477785] systemd[1]: Finished Extra networking commands..807server # [ 230.530254] systemd[1]: Reached target Network.808server # [ 230.814805] systemd[1]: Starting Nginx Web Server...809server # [ 230.906302] dhcpcd[1138]: dhcpcd-10.3.2 starting810server # [ 231.199242] dhcpcd[1186]: dev: loaded udev811server # [ 231.357297] dhcpcd[1186]: no valid interfaces found812server # [ 231.369348] systemd[1]: Starting PostgreSQL Server...813server # [ 231.377473] dhcpcd[1186]: no valid interfaces found814server # [ 231.549992] systemd[1]: Starting Permit User Sessions...815server # [ 233.323724] systemd[1]: Finished Permit User Sessions.816server # [ 233.649945] systemd[1]: Started Getty on tty1.817server # [ 233.775884] systemd[1]: Reached target Login Prompts.818server: Guest root shell did not produce any data yet...819server: To debug, enter the VM and run 'systemctl status backdoor.service'.820server # [ 240.659507] nginx-pre-start[1197]: nginx: the configuration file /nix/store/z7891f9bxfhn8yrz9yaks370dkhgxbyb-nginx.conf syntax is ok821server # [ 240.819628] nginx-pre-start[1197]: nginx: configuration file /nix/store/z7891f9bxfhn8yrz9yaks370dkhgxbyb-nginx.conf test is successful822server # [ 241.395736] systemd[1]: Started Nginx Web Server.823server # [ 241.513645] postgresql-pre-start[1199]: The files belonging to this database system will be owned by user "postgres".824server # [ 241.531321] postgresql-pre-start[1199]: This user must also own the server process.825server # [ 241.739712] postgresql-pre-start[1199]: The database cluster will be initialized with locale "en_US.UTF-8".826server # [ 241.769163] postgresql-pre-start[1199]: The default database encoding has accordingly been set to "UTF8".827server # [ 241.785307] postgresql-pre-start[1199]: The default text search configuration will be set to "english".828server # [ 241.797576] postgresql-pre-start[1199]: Data page checksums are disabled.829server # [ 241.814375] postgresql-pre-start[1199]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok830server # [ 241.940851] postgresql-pre-start[1199]: creating subdirectories ... ok831server # [ 241.981573] postgresql-pre-start[1199]: selecting dynamic shared memory implementation ... posix832server # [ 245.287716] postgresql-pre-start[1199]: selecting default "max_connections" ... 100833server # [ 247.518849] postgresql-pre-start[1199]: selecting default "shared_buffers" ... 128MB834server # [ 261.447393] dhcpcd[1186]: timed out835server # [ 261.493736] dhcpcd[1186]: timed out836server # [ 261.595430] systemd[1]: Started DHCP Client.837server # [ 265.527156] mousedev: PS/2 mouse device common for all mice838server: Guest root shell did not produce any data yet...839server: To debug, enter the VM and run 'systemctl status backdoor.service'.840server # [ 276.623350] systemd-logind[1059]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)841server # [ 290.783987] postgresql-pre-start[1199]: selecting default time zone ... UTC842server # [ 290.882922] postgresql-pre-start[1199]: creating configuration files ... ok843server: Guest root shell did not produce any data yet...844server: To debug, enter the VM and run 'systemctl status backdoor.service'.845server # [ 304.890736] postgresql-pre-start[1199]: running bootstrap script ... ok846server: Guest root shell did not produce any data yet...847server: To debug, enter the VM and run 'systemctl status backdoor.service'.848server # [ 342.662525] postgresql-pre-start[1199]: performing post-bootstrap initialization ... ok849server # [ 348.611219] postgresql-pre-start[1199]: syncing data to disk ... ok850server # [ 348.633706] postgresql-pre-start[1199]: initdb: warning: enabling "trust" authentication for local connections851server # [ 348.647986] postgresql-pre-start[1199]: 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.852server # [ 348.662262] postgresql-pre-start[1199]: Success. You can now start the database server using:853server # [ 348.681429] postgresql-pre-start[1199]: pg_ctl -D /var/lib/postgresql/17 -l logfile start854server # [ 352.530938] postgres[1359]: [1359] LOG: starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit855server # [ 352.753184] postgres[1359]: [1359] LOG: listening on IPv6 address "::1", port 5432856server # [ 352.769410] postgres[1359]: [1359] LOG: listening on IPv4 address "127.0.0.1", port 5432857server # [ 352.799421] postgres[1359]: [1359] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"858server # [ 353.106289] postgres[1375]: [1375] LOG: database system was shut down at 2026-08-30 21:58:06 GMT859server # [ 353.392909] postgres[1359]: [1359] LOG: database system is ready to accept connections860server # [ 353.565028] systemd[1]: Started PostgreSQL Server.861server # [ 353.701431] systemd[1]: Starting PostgreSQL Setup Scripts...862server: Guest root shell did not produce any data yet...863server: To debug, enter the VM and run 'systemctl status backdoor.service'.864server # [ 361.427161] postgresql-setup-start[1386]: CREATE DATABASE865server # [ 363.120533] postgresql-setup-start[1401]: CREATE ROLE866server # [ 363.315127] systemd[1]: Finished PostgreSQL Setup Scripts.867server # [ 363.351177] systemd[1]: Reached target PostgreSQL.868server # [ 363.463898] systemd[1]: Starting Mobilizon PostgreSQL setup...869server: Guest root shell did not produce any data yet...870server: To debug, enter the VM and run 'systemctl status backdoor.service'.871server # [ 399.142643] mobilizon-postgresql-start[1405]: CREATE EXTENSION872server # [ 399.178049] mobilizon-postgresql-start[1405]: CREATE EXTENSION873server # [ 399.201352] mobilizon-postgresql-start[1405]: CREATE EXTENSION874server # [ 400.031042] mobilizon-postgresql-start[1467]: ALTER DATABASE875server # [ 400.150365] systemd[1]: mobilizon-postgresql.service: Deactivated successfully.876server # [ 400.159226] systemd[1]: Finished Mobilizon PostgreSQL setup.877server # [ 400.266835] systemd[1]: Starting Mobilizon setup secrets...878server # [ 401.331496] mobilizon-setup-secrets-start[1470]: + '[' '!' -f /var/lib/mobilizon/secret-env.sh ']'879server # [ 401.351438] mobilizon-setup-secrets-start[1470]: + install -m 600 /dev/null /var/lib/mobilizon/secret-env.sh880server # [ 401.484669] mobilizon-setup-secrets-start[1470]: + cat881server # [ 401.582865] mobilizon-setup-secrets-start[1474]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(:crypto.strong_rand_bytes(64)|> Base.encode64()|> binary_part(0, 64))'882server: Guest root shell did not produce any data yet...883server: To debug, enter the VM and run 'systemctl status backdoor.service'.884server # [ 426.961552] systemd[1]: dev-hvc0.device: Job dev-hvc0.device/start timed out.885server # [ 426.975182] systemd[1]: Timed out waiting for device /dev/hvc0.886server # [ 426.998960] systemd[1]: Dependency failed for backdoor.service.887server # [ 427.014797] systemd[1]: backdoor.service: Job backdoor.service/start failed with result 'dependency'.888server # [ 427.031009] systemd[1]: dev-hvc0.device: Job dev-hvc0.device/start failed with result 'timeout'.889server # [ 427.050698] systemd[1]: Unnecessary job was removed for /dev/ttyAMA0.890server # [ 439.815779] mobilizon-setup-secrets-start[1571]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(:crypto.strong_rand_bytes(64)|> Base.encode64()|> binary_part(0, 64))'891server: Guest root shell did not produce any data yet...892server: To debug, enter the VM and run 'systemctl status backdoor.service'.893server # [ 475.377826] mobilizon-setup-secrets-start[1657]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(Base.encode32(:crypto.strong_rand_bytes(32)))'894server: Guest root shell did not produce any data yet...895server: To debug, enter the VM and run 'systemctl status backdoor.service'.896server: Guest root shell did not produce any data yet...897server: To debug, enter the VM and run 'systemctl status backdoor.service'.898server # [ 510.431157] systemd[1]: mobilizon-setup-secrets.service: Deactivated successfully.899server # [ 510.450170] systemd[1]: Finished Mobilizon setup secrets.900server # [ 510.466548] systemd[1]: mobilizon-setup-secrets.service: Consumed 42.252s CPU time over 1min 50.153s wall clock time, 63.4M memory peak, 4K written to disk.901server # [ 510.609409] systemd[1]: Starting Mobilizon federated organization and mobilization platform...902server: Guest root shell did not produce any data yet...903server: To debug, enter the VM and run 'systemctl status backdoor.service'.904server # [ 569.543743] mobilizon_ctl[1749]: Loading app..905server: Guest root shell did not produce any data yet...906server: To debug, enter the VM and run 'systemctl status backdoor.service'.907server # [ 569.839825] mobilizon_ctl[1749]: Starting dependencies..908server: Guest root shell did not produce any data yet...909server: To debug, enter the VM and run 'systemctl status backdoor.service'.910server: Guest root shell did not produce any data yet...911server: To debug, enter the VM and run 'systemctl status backdoor.service'.912server # [ 653.179205] postgres[1373]: [1373] LOG: checkpoint starting: time913server: Guest root shell did not produce any data yet...914server: To debug, enter the VM and run 'systemctl status backdoor.service'.915server: Guest root shell did not produce any data yet...916server: To debug, enter the VM and run 'systemctl status backdoor.service'.917server: Guest root shell did not produce any data yet...918server: To debug, enter the VM and run 'systemctl status backdoor.service'.919server # [ 748.793307] mobilizon_ctl[1749]: #########################################################920server # [ 748.838695] mobilizon_ctl[1749]: # If the CREATE EXTENSION or DROP EXTENSION calls fail, #921server # [ 748.854558] mobilizon_ctl[1749]: # please manually execute them with an authorized #922server # [ 748.869959] mobilizon_ctl[1749]: # PostgreSQL user with SUPER USER role. #923server # [ 748.884718] mobilizon_ctl[1749]: #########################################################924server # [ 748.897106] mobilizon_ctl[1749]:925server # [ 748.906745] mobilizon_ctl[1749]:926server # [ 748.918320] mobilizon_ctl[1749]: 22:04:52.294 [info] == Running 20180109150000 Mobilizon.Repo.Migrations.Prerequites.up/0 forward927server # [ 748.970163] mobilizon_ctl[1749]: 22:04:52.684 [info] execute "CREATE TYPE datetimetz AS (\n dt timestamptz,\n tz varchar\n);\n"928server # [ 749.051190] mobilizon_ctl[1749]: 22:04:52.774 [info] execute "CREATE EXTENSION IF NOT EXISTS postgis"929server # [ 749.091610] mobilizon_ctl[1749]: 22:04:52.819 [info] extension "postgis" already exists, skipping930server # [ 749.129268] mobilizon_ctl[1749]: 22:04:52.820 [info] execute "CREATE EXTENSION IF NOT EXISTS pg_trgm"931server # [ 749.147866] mobilizon_ctl[1749]: 22:04:52.851 [info] extension "pg_trgm" already exists, skipping932server # [ 749.162708] mobilizon_ctl[1749]: 22:04:52.851 [info] execute "CREATE EXTENSION IF NOT EXISTS unaccent"933server # [ 749.179180] mobilizon_ctl[1749]: 22:04:52.911 [info] extension "unaccent" already exists, skipping934server # [ 749.298616] mobilizon_ctl[1749]: 22:04:53.016 [info] == Migrated 20180109150000 in 0.4s935server: Guest root shell did not produce any data yet...936server: To debug, enter the VM and run 'systemctl status backdoor.service'.937server # [ 751.323461] mobilizon_ctl[1749]: 22:04:55.024 [info] == Running 20180109165738 Mobilizon.Repo.Migrations.CreateAccounts.change/0 forward938server # [ 751.358452] mobilizon_ctl[1749]: 22:04:55.082 [info] create table accounts939server # [ 751.601881] mobilizon_ctl[1749]: 22:04:55.323 [info] create index accounts_username_domain_index940server # [ 751.676903] mobilizon_ctl[1749]: 22:04:55.402 [info] == Migrated 20180109165738 in 0.3s941server # [ 751.772931] mobilizon_ctl[1749]: 22:04:55.508 [info] == Running 20180109170315 Mobilizon.Repo.Migrations.CreateUsers.change/0 forward942server # [ 751.809043] mobilizon_ctl[1749]: 22:04:55.535 [info] create table users943server # [ 752.094538] mobilizon_ctl[1749]: 22:04:55.827 [info] create index users_email_index944server # [ 752.161123] mobilizon_ctl[1749]: 22:04:55.886 [info] == Migrated 20180109170315 in 0.3s945server # [ 752.260924] mobilizon_ctl[1749]: 22:04:55.996 [info] == Running 20180110091747 Mobilizon.Repo.Migrations.CreateCategories.change/0 forward946server # [ 752.291562] mobilizon_ctl[1749]: 22:04:56.020 [info] create table categories947server # [ 752.489401] mobilizon_ctl[1749]: 22:04:56.171 [info] create index categories_title_index948server # [ 752.533133] mobilizon_ctl[1749]: 22:04:56.247 [info] == Migrated 20180110091747 in 0.2s949server # [ 752.621900] mobilizon_ctl[1749]: 22:04:56.360 [info] == Running 20180110092400 Mobilizon.Repo.Migrations.CreateAddresses.change/0 forward950server # [ 752.657595] mobilizon_ctl[1749]: 22:04:56.389 [info] create table addresses951server # [ 752.818972] mobilizon_ctl[1749]: 22:04:56.538 [info] == Migrated 20180110092400 in 0.1s952server # [ 752.925207] mobilizon_ctl[1749]: 22:04:56.648 [info] == Running 20180110092500 Mobilizon.Repo.Migrations.CreateGroups.change/0 forward953server # [ 752.970021] mobilizon_ctl[1749]: 22:04:56.687 [info] create table groups954server # [ 753.213055] mobilizon_ctl[1749]: 22:04:56.938 [info] create index groups_slug_index955server # [ 753.275721] mobilizon_ctl[1749]: 22:04:57.003 [info] == Migrated 20180110092500 in 0.3s956server # [ 753.414772] mobilizon_ctl[1749]: 22:04:57.142 [info] == Running 20180110092600 Mobilizon.Repo.Migrations.CreateEvents.change/0 forward957server # [ 753.544038] mobilizon_ctl[1749]: 22:04:57.217 [info] create table events958server # [ 753.935360] mobilizon_ctl[1749]: 22:04:57.648 [info] create index events_organizer_account_id_index959server # [ 754.013041] mobilizon_ctl[1749]: 22:04:57.735 [info] create index events_organizer_group_id_index960server # [ 754.087809] mobilizon_ctl[1749]: 22:04:57.808 [info] create index events_slug_index961server # [ 754.157935] mobilizon_ctl[1749]: 22:04:57.881 [info] == Migrated 20180110092600 in 0.7s962server # [ 754.273443] mobilizon_ctl[1749]: 22:04:57.999 [info] == Running 20180110093905 Mobilizon.Repo.Migrations.CreateTags.change/0 forward963server # [ 754.320006] mobilizon_ctl[1749]: 22:04:58.040 [info] create table tags964server # [ 754.553489] mobilizon_ctl[1749]: 22:04:58.230 [info] create index tags_slug_index965server # [ 754.589995] mobilizon_ctl[1749]: 22:04:58.303 [info] == Migrated 20180110093905 in 0.3s966server # [ 754.708921] mobilizon_ctl[1749]: 22:04:58.442 [info] == Running 20180110093906 Mobilizon.Repo.Migrations.CreateEventsTags.change/0 forward967server # [ 754.722143] mobilizon_ctl[1749]: 22:04:58.459 [info] create table events_tags968server # [ 754.833405] mobilizon_ctl[1749]: 22:04:58.559 [info] == Migrated 20180110093906 in 0.1s969server # [ 754.952687] mobilizon_ctl[1749]: 22:04:58.682 [info] == Running 20180110094301 Mobilizon.Repo.Migrations.CreateParticipants.change/0 forward970server # [ 754.986490] mobilizon_ctl[1749]: 22:04:58.713 [info] create table participants971server # [ 755.150862] mobilizon_ctl[1749]: 22:04:58.870 [info] create index participants_event_id_index972server # [ 755.193619] mobilizon_ctl[1749]: 22:04:58.927 [info] create index participants_account_id_index973server # [ 755.289888] mobilizon_ctl[1749]: 22:04:58.995 [info] == Migrated 20180110094301 in 0.3s974server # [ 755.397093] mobilizon_ctl[1749]: 22:04:59.127 [info] == Running 20180110094502 Mobilizon.Repo.Migrations.CreateEventRequests.change/0 forward975server # [ 755.437962] mobilizon_ctl[1749]: 22:04:59.156 [info] create table event_requests976server # [ 755.617910] mobilizon_ctl[1749]: 22:04:59.305 [info] create index event_requests_event_id_index977server # [ 755.656882] mobilizon_ctl[1749]: 22:04:59.362 [info] create index event_requests_account_id_index978server # [ 755.688837] mobilizon_ctl[1749]: 22:04:59.420 [info] == Migrated 20180110094502 in 0.2s979server # [ 755.780911] mobilizon_ctl[1749]: 22:04:59.514 [info] == Running 20180110095317 Mobilizon.Repo.Migrations.CreateMembers.change/0 forward980server # [ 755.804793] mobilizon_ctl[1749]: 22:04:59.532 [info] create table members981server # [ 755.934674] mobilizon_ctl[1749]: 22:04:59.663 [info] create index members_group_id_index982server # [ 755.998516] mobilizon_ctl[1749]: 22:04:59.720 [info] create index members_account_id_index983server # [ 756.045544] mobilizon_ctl[1749]: 22:04:59.770 [info] == Migrated 20180110095317 in 0.2s984server # [ 756.128853] mobilizon_ctl[1749]: 22:04:59.864 [info] == Running 20180110095515 Mobilizon.Repo.Migrations.CreateGroupRequests.change/0 forward985server # [ 756.149485] mobilizon_ctl[1749]: 22:04:59.887 [info] create table group_requests986server # [ 756.335196] mobilizon_ctl[1749]: 22:05:00.056 [info] create index group_requests_group_id_index987server # [ 756.396933] mobilizon_ctl[1749]: 22:05:00.118 [info] create index group_requests_account_id_index988server # [ 756.450881] mobilizon_ctl[1749]: 22:05:00.174 [info] == Migrated 20180110095515 in 0.3s989server # [ 756.568858] mobilizon_ctl[1749]: 22:05:00.299 [info] == Running 20180110165029 Mobilizon.Repo.Migrations.Guardian.DB.change/0 forward990server # [ 756.639297] mobilizon_ctl[1749]: 22:05:00.326 [info] create table guardian_tokens991server # [ 756.790775] mobilizon_ctl[1749]: 22:05:00.519 [info] == Migrated 20180110165029 in 0.2s992server # [ 756.953105] mobilizon_ctl[1749]: 22:05:00.682 [info] == Running 20180112100000 Mobilizon.Repo.Migrations.CreateTracks.change/0 forward993server # [ 756.983575] mobilizon_ctl[1749]: 22:05:00.719 [info] create table tracks994server # [ 757.229825] mobilizon_ctl[1749]: 22:05:00.939 [info] == Migrated 20180112100000 in 0.2s995server # [ 757.329710] mobilizon_ctl[1749]: 22:05:01.074 [info] == Running 20180112105354 Mobilizon.Repo.Migrations.CreateSessions.change/0 forward996server # [ 757.373787] mobilizon_ctl[1749]: 22:05:01.100 [info] create table sessions997server # [ 757.770268] mobilizon_ctl[1749]: 22:05:01.432 [info] == Migrated 20180112105354 in 0.3s998server # [ 757.845161] mobilizon_ctl[1749]: 22:05:01.576 [info] == Running 20180430071244 Mobilizon.Repo.Migrations.RemoveUri.up/0 forward999server # [ 757.902410] mobilizon_ctl[1749]: 22:05:01.613 [info] alter table accounts1000server # [ 757.998209] mobilizon_ctl[1749]: 22:05:01.712 [info] alter table groups1001server # [ 758.036022] mobilizon_ctl[1749]: 22:05:01.755 [info] == Migrated 20180430071244 in 0.1s1002server # [ 758.177046] mobilizon_ctl[1749]: 22:05:01.907 [info] == Running 20180515091106 Mobilizon.Repo.Migrations.AddUrlFieldToEvents.up/0 forward1003server # [ 758.206498] mobilizon_ctl[1749]: 22:05:01.920 [info] alter table events1004server # [ 758.294196] mobilizon_ctl[1749]: 22:05:02.000 [info] == Migrated 20180515091106 in 0.0s1005server # [ 758.423351] mobilizon_ctl[1749]: 22:05:02.151 [info] == Running 20180515100237 Mobilizon.Repo.Migrations.CreateComments.change/0 forward1006server # [ 758.486874] mobilizon_ctl[1749]: 22:05:02.191 [info] create table comments1007server # [ 758.915805] mobilizon_ctl[1749]: 22:05:02.591 [info] == Migrated 20180515100237 in 0.4s1008server # [ 758.971725] mobilizon_ctl[1749]: 22:05:02.715 [info] == Running 20180515142835 Mobilizon.Repo.Migrations.AlterEventTimestampsToDateTimeWithTimeZone.up/0 forward1009server # [ 759.010961] mobilizon_ctl[1749]: 22:05:02.727 [info] alter table events1010server # [ 759.078584] mobilizon_ctl[1749]: 22:05:02.799 [info] == Migrated 20180515142835 in 0.0s1011server # [ 759.173908] mobilizon_ctl[1749]: 22:05:02.908 [info] == Running 20180515145438 Mobilizon.Repo.Migrations.AddLocalAttributeToEventsAndComments.up/0 forward1012server # [ 759.213059] mobilizon_ctl[1749]: 22:05:02.933 [info] alter table events1013server # [ 759.269986] mobilizon_ctl[1749]: 22:05:02.996 [info] alter table comments1014server # [ 759.316615] mobilizon_ctl[1749]: 22:05:03.040 [info] == Migrated 20180515145438 in 0.1s1015server # [ 759.408950] mobilizon_ctl[1749]: 22:05:03.147 [info] == Running 20180517100700 Mobilizon.Repo.Migrations.MoveFromAccountToActor.up/0 forward1016server # [ 759.571430] mobilizon_ctl[1749]: 22:05:03.291 [info] drop table event_requests1017server # [ 759.719255] mobilizon_ctl[1749]: 22:05:03.458 [info] drop table group_requests1018server # [ 759.853665] mobilizon_ctl[1749]: 22:05:03.563 [info] alter table events1019server # [ 759.963922] mobilizon_ctl[1749]: 22:05:03.639 [info] rename column account_id to actor_id on table members1020server # [ 759.989168] mobilizon_ctl[1749]: 22:05:03.663 [info] alter table members1021server # [ 760.126615] mobilizon_ctl[1749]: 22:05:03.855 [info] drop table groups1022server # [ 760.237358] mobilizon_ctl[1749]: 22:05:03.966 [info] rename table accounts to actors1023server # [ 760.293956] mobilizon_ctl[1749]: 22:05:04.000 [info] execute "CREATE TYPE public.actor_type AS ENUM ('Person', 'Application', 'Group', 'Organization', 'Service')"1024server # [ 760.394047] mobilizon_ctl[1749]: 22:05:04.110 [info] rename column username to name on table actors1025server # [ 760.421807] mobilizon_ctl[1749]: 22:05:04.127 [info] rename column description to summary on table actors1026server # [ 760.463670] mobilizon_ctl[1749]: 22:05:04.151 [info] rename column display_name to preferred_username on table actors1027server # [ 760.478397] mobilizon_ctl[1749]: 22:05:04.182 [info] alter table actors1028server # [ 760.662751] mobilizon_ctl[1749]: 22:05:04.390 [info] create index actors_preferred_username_domain_index1029server # [ 760.734975] mobilizon_ctl[1749]: 22:05:04.468 [info] rename column organizer_account_id to organizer_actor_id on table events1030server # [ 760.789099] mobilizon_ctl[1749]: 22:05:04.499 [info] rename column account_id to actor_id on table participants1031server # [ 760.806753] mobilizon_ctl[1749]: 22:05:04.522 [info] create table followers1032server # [ 760.999447] mobilizon_ctl[1749]: 22:05:04.687 [info] rename column account_id to actor_id on table comments1033server # [ 761.027348] mobilizon_ctl[1749]: 22:05:04.719 [info] rename column account_id to actor_id on table users1034server # [ 761.053426] mobilizon_ctl[1749]: 22:05:04.764 [info] == Migrated 20180517100700 in 1.6s1035server # [ 761.253693] mobilizon_ctl[1749]: 22:05:04.986 [info] == Running 20180522133844 Mobilizon.Repo.Migrations.AddBotsTable.up/0 forward1036server # [ 761.278860] mobilizon_ctl[1749]: 22:05:05.011 [info] create table bots1037server # [ 761.541242] mobilizon_ctl[1749]: 22:05:05.261 [info] == Migrated 20180522133844 in 0.2s1038server # [ 761.643317] mobilizon_ctl[1749]: 22:05:05.377 [info] == Running 20180530125159 Mobilizon.Repo.Migrations.AddAvatarAndBannerToActor.up/0 forward1039server # [ 761.669960] mobilizon_ctl[1749]: 22:05:05.386 [info] alter table actors1040server # [ 761.734584] mobilizon_ctl[1749]: 22:05:05.446 [info] == Migrated 20180530125159 in 0.0s1041server # [ 761.845771] mobilizon_ctl[1749]: 22:05:05.576 [info] == Running 20180530152323 Mobilizon.Repo.Migrations.MakeActorPublicKeyNullable.up/0 forward1042server # [ 761.876388] mobilizon_ctl[1749]: 22:05:05.591 [info] alter table actors1043server # [ 761.925275] mobilizon_ctl[1749]: 22:05:05.647 [info] == Migrated 20180530152323 in 0.0s1044server # [ 762.086900] mobilizon_ctl[1749]: 22:05:05.777 [info] == Running 20180530170808 Mobilizon.Repo.Migrations.AddUUIDIdToEvents.up/0 forward1045server # [ 762.115247] mobilizon_ctl[1749]: 22:05:05.783 [info] alter table events1046server # [ 762.137040] mobilizon_ctl[1749]: 22:05:05.854 [info] == Migrated 20180530170808 in 0.0s1047server # [ 762.246063] mobilizon_ctl[1749]: 22:05:05.979 [info] == Running 20180531162301 Mobilizon.Repo.Migrations.AddApprovedStatusToMember.up/0 forward1048server # [ 762.272937] mobilizon_ctl[1749]: 22:05:05.987 [info] alter table members1049server # [ 762.330537] mobilizon_ctl[1749]: 22:05:06.050 [info] == Migrated 20180531162301 in 0.0s1050server # [ 762.425616] mobilizon_ctl[1749]: 22:05:06.156 [info] == Running 20180607095732 Mobilizon.Repo.Migrations.AddUUIDToComments.up/0 forward1051server # [ 762.453486] mobilizon_ctl[1749]: 22:05:06.175 [info] alter table comments1052server # [ 762.498850] mobilizon_ctl[1749]: 22:05:06.216 [info] == Migrated 20180607095732 in 0.0s1053server # [ 762.601677] mobilizon_ctl[1749]: 22:05:06.344 [info] == Running 20180614094653 Mobilizon.Repo.Migrations.MakeSharedInboxUrlNullable.up/0 forward1054server # [ 762.642268] mobilizon_ctl[1749]: 22:05:06.366 [info] alter table actors1055server # [ 762.737248] mobilizon_ctl[1749]: 22:05:06.455 [info] == Migrated 20180614094653 in 0.1s1056server # [ 762.841383] mobilizon_ctl[1749]: 22:05:06.579 [info] == Running 20180614104618 Mobilizon.Repo.Migrations.FusionPublicPrivateKeyIntoKeysColumn.up/0 forward1057server # [ 762.878088] mobilizon_ctl[1749]: 22:05:06.588 [info] rename column private_key to keys on table actors1058server # [ 762.922954] mobilizon_ctl[1749]: 22:05:06.643 [info] alter table actors1059server # [ 762.973825] mobilizon_ctl[1749]: 22:05:06.684 [info] == Migrated 20180614104618 in 0.1s1060server # [ 763.143247] mobilizon_ctl[1749]: 22:05:06.817 [info] == Running 20180614131328 Mobilizon.Repo.Migrations.AddAttributedToFieldToEventsAndComments.up/0 forward1061server # [ 763.159664] mobilizon_ctl[1749]: 22:05:06.839 [info] alter table events1062server # [ 763.291790] mobilizon_ctl[1749]: 22:05:07.019 [info] alter table comments1063server # [ 763.413790] mobilizon_ctl[1749]: 22:05:07.121 [info] == Migrated 20180614131328 in 0.2s1064server # [ 763.516968] mobilizon_ctl[1749]: 22:05:07.254 [info] == Running 20180628091920 :"Elixir.Mobilizon.Repo.Migrations.Add-user-confirm-email-fields".up/0 forward1065server # [ 763.544981] mobilizon_ctl[1749]: 22:05:07.268 [info] alter table users1066server # [ 763.604979] mobilizon_ctl[1749]: 22:05:07.328 [info] create index index_unique_users_confirmation_token1067server # [ 763.659911] mobilizon_ctl[1749]: 22:05:07.382 [info] == Migrated 20180628091920 in 0.1s1068server # [ 763.776976] mobilizon_ctl[1749]: 22:05:07.516 [info] == Running 20180628130746 :"Elixir.Mobilizon.Repo.Migrations.Add-user-password-reset-fields".up/0 forward1069server # [ 763.842528] mobilizon_ctl[1749]: 22:05:07.548 [info] alter table users1070server # [ 763.877181] mobilizon_ctl[1749]: 22:05:07.603 [info] create index index_unique_users_reset_password_token1071server # [ 763.959021] mobilizon_ctl[1749]: 22:05:07.675 [info] == Migrated 20180628130746 in 0.1s1072server # [ 764.142942] mobilizon_ctl[1749]: 22:05:07.811 [info] == Running 20180628142835 Mobilizon.Repo.Migrations.RevertAlterEventTimestampsToDateTimeWithTimeZone.up/0 forward1073server # [ 764.175424] mobilizon_ctl[1749]: 22:05:07.820 [info] alter table events1074server # [ 764.192760] mobilizon_ctl[1749]: 22:05:07.902 [info] == Migrated 20180628142835 in 0.0s1075server # [ 764.329546] mobilizon_ctl[1749]: 22:05:08.063 [info] == Running 20180702150922 Mobilizon.Repo.Migrations.AddAddressType.up/0 forward1076server # [ 764.367735] mobilizon_ctl[1749]: 22:05:08.091 [info] alter table events1077server # [ 764.421089] mobilizon_ctl[1749]: 22:05:08.139 [info] drop constraint events_address_id_fkey from table events1078server # [ 764.473011] mobilizon_ctl[1749]: 22:05:08.190 [info] rename column address_id to physical_address_id on table events1079server # [ 764.485170] mobilizon_ctl[1749]: 22:05:08.210 [info] alter table events1080server # [ 764.561233] mobilizon_ctl[1749]: 22:05:08.288 [info] == Migrated 20180702150922 in 0.2s1081server # [ 764.673979] mobilizon_ctl[1749]: 22:05:08.407 [info] == Running 20180702154630 Mobilizon.Repo.Migrations.RemoveSlugForEvent.up/0 forward1082server # [ 764.685801] mobilizon_ctl[1749]: 22:05:08.416 [info] alter table events1083server # [ 764.736756] mobilizon_ctl[1749]: 22:05:08.467 [info] == Migrated 20180702154630 in 0.0s1084server # [ 764.823497] mobilizon_ctl[1749]: 22:05:08.554 [info] == Running 20180706082208 Mobilizon.Repo.Migrations.AlterEventsSetDescriptionAsText.up/0 forward1085server # [ 764.843863] mobilizon_ctl[1749]: 22:05:08.566 [info] alter table events1086server # [ 764.893013] mobilizon_ctl[1749]: 22:05:08.605 [info] == Migrated 20180706082208 in 0.0s1087server # [ 764.982804] mobilizon_ctl[1749]: 22:05:08.720 [info] == Running 20180709154152 Mobilizon.Repo.Migrations.AllowMultipleAccountsForUser.up/0 forward1088server # [ 765.018449] mobilizon_ctl[1749]: 22:05:08.740 [info] alter table actors1089server # [ 765.139777] mobilizon_ctl[1749]: 22:05:08.844 [info] alter table users1090server # [ 765.179045] mobilizon_ctl[1749]: 22:05:08.908 [info] == Migrated 20180709154152 in 0.1s1091server # [ 765.263480] mobilizon_ctl[1749]: 22:05:08.999 [info] == Running 20180802133951 Mobilizon.Repo.Migrations.MakeFollowerTableUnique.up/0 forward1092server # [ 765.288618] mobilizon_ctl[1749]: 22:05:09.010 [info] create index followers_actor_target_actor_unique_index1093server # [ 765.346499] mobilizon_ctl[1749]: 22:05:09.075 [info] == Migrated 20180802133951 in 0.0s1094server # [ 765.450207] mobilizon_ctl[1749]: 22:05:09.183 [info] == Running 20180816093446 Mobilizon.Repo.Migrations.AddPrimaryKeyToMember.up/0 forward1095server # [ 765.503243] mobilizon_ctl[1749]: 22:05:09.208 [info] execute "ALTER TABLE members DROP CONSTRAINT IF EXISTS members_pkey"1096server # [ 765.545553] mobilizon_ctl[1749]: 22:05:09.235 [info] constraint "members_pkey" of relation "members" does not exist, skipping1097server # [ 765.570196] mobilizon_ctl[1749]: 22:05:09.236 [info] drop index if exists members_members_account_id_index_index1098server # [ 765.577806] mobilizon_ctl[1749]: 22:05:09.281 [info] index "members_members_account_id_index_index" does not exist, skipping1099server # [ 765.598728] mobilizon_ctl[1749]: 22:05:09.286 [info] create index members_actor_parent_unique_index1100server # [ 765.649093] mobilizon_ctl[1749]: 22:05:09.370 [info] alter table members1101server # [ 765.901804] mobilizon_ctl[1749]: 22:05:09.623 [info] == Migrated 20180816093446 in 0.4s1102server # [ 766.031869] mobilizon_ctl[1749]: 22:05:09.762 [info] == Running 20181021161324 Mobilizon.Repo.Migrations.AddDefaultActorUserField.up/0 forward1103server # [ 766.061703] mobilizon_ctl[1749]: 22:05:09.774 [info] alter table users1104server # [ 766.095067] mobilizon_ctl[1749]: 22:05:09.819 [info] == Migrated 20181021161324 in 0.0s1105server # [ 766.215861] mobilizon_ctl[1749]: 22:05:09.930 [info] == Running 20181108165151 Mobilizon.Repo.Migrations.FixCommentsReferences.up/0 forward1106server # [ 766.242921] mobilizon_ctl[1749]: 22:05:09.948 [info] drop constraint comments_in_reply_to_comment_id_fkey from table comments1107server # [ 766.293742] mobilizon_ctl[1749]: 22:05:10.022 [info] drop constraint comments_origin_comment_id_fkey from table comments1108server # [ 766.365686] mobilizon_ctl[1749]: 22:05:10.083 [info] alter table comments1109server # [ 766.513731] mobilizon_ctl[1749]: 22:05:10.229 [info] == Migrated 20181108165151 in 0.2s1110server # [ 766.609254] mobilizon_ctl[1749]: 22:05:10.344 [info] == Running 20181203092128 Mobilizon.Repo.Migrations.ChangeActorsIndexes.up/0 forward1111server # [ 766.648873] mobilizon_ctl[1749]: 22:05:10.383 [info] drop index actors_preferred_username_domain_index1112server # [ 766.713073] mobilizon_ctl[1749]: 22:05:10.442 [info] drop index accounts_username_domain_index1113server # [ 766.749095] mobilizon_ctl[1749]: 22:05:10.471 [info] execute "ALTER INDEX accounts_pkey RENAME TO actors_pkey"1114server # [ 766.781876] mobilizon_ctl[1749]: 22:05:10.515 [info] create index actors_preferred_username_domain_type_index1115server # [ 766.901878] mobilizon_ctl[1749]: 22:05:10.623 [info] create index actors_url_index1116server # [ 766.970351] mobilizon_ctl[1749]: 22:05:10.698 [info] == Migrated 20181203092128 in 0.3s1117server # [ 767.125509] mobilizon_ctl[1749]: 22:05:10.855 [info] == Running 20190103150805 Mobilizon.Repo.Migrations.FixEventVisibility.up/0 forward1118server # [ 767.174973] mobilizon_ctl[1749]: 22:05:10.876 [info] execute "CREATE TYPE public.event_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1119server # [ 767.230688] mobilizon_ctl[1749]: 22:05:10.911 [info] execute "CREATE TYPE public.event_status AS ENUM ('tentative', 'confirmed', 'cancelled')"1120server # [ 767.255824] mobilizon_ctl[1749]: 22:05:10.946 [info] execute "CREATE TYPE public.comment_visibility AS ENUM ('public', 'unlisted', 'private', 'moderated', 'invite')"1121server # [ 767.277948] mobilizon_ctl[1749]: 22:05:10.978 [info] alter table events1122server # [ 767.347202] mobilizon_ctl[1749]: 22:05:11.067 [info] alter table comments1123server # [ 767.385750] mobilizon_ctl[1749]: 22:05:11.110 [info] == Migrated 20190103150805 in 0.2s1124server # [ 767.504860] mobilizon_ctl[1749]: 22:05:11.240 [info] == Running 20190114162055 Mobilizon.Repo.Migrations.RemoveAddressType.up/0 forward1125server # [ 767.521074] mobilizon_ctl[1749]: 22:05:11.255 [info] execute "ALTER TABLE \"events\" DROP COLUMN IF EXISTS address_type"1126server # [ 767.576610] mobilizon_ctl[1749]: 22:05:11.299 [info] column "address_type" of relation "events" does not exist, skipping1127server # [ 767.586716] mobilizon_ctl[1749]: 22:05:11.300 [info] execute "DROP TYPE IF EXISTS address_type"1128server # [ 767.613135] mobilizon_ctl[1749]: 22:05:11.336 [info] type "address_type" does not exist, skipping1129server # [ 767.629901] mobilizon_ctl[1749]: 22:05:11.345 [info] rename column phone to phone_address on table events1130server # [ 767.682180] mobilizon_ctl[1749]: 22:05:11.387 [info] == Migrated 20190114162055 in 0.1s1131server # [ 767.777942] mobilizon_ctl[1749]: 22:05:11.522 [info] == Running 20190125143718 Mobilizon.Repo.Migrations.GroupDeleteMembersCascade.change/0 forward1132server # [ 767.813729] mobilizon_ctl[1749]: 22:05:11.536 [info] drop constraint members_account_id_fkey from table members1133server # [ 767.881844] mobilizon_ctl[1749]: 22:05:11.603 [info] drop constraint members_parent_id_fkey from table members1134server # [ 767.934670] mobilizon_ctl[1749]: 22:05:11.667 [info] alter table members1135server # [ 768.135607] mobilizon_ctl[1749]: 22:05:11.859 [info] == Migrated 20190125143718 in 0.3s1136server # [ 768.273178] mobilizon_ctl[1749]: 22:05:11.955 [info] == Running 20190130151607 Mobilizon.Repo.Migrations.SplitEventVisibilityAndJoinOptions.up/0 forward1137server # [ 768.287932] mobilizon_ctl[1749]: 22:05:11.978 [info] execute "ALTER TABLE events ALTER COLUMN visibility TYPE VARCHAR USING visibility::text"1138server # [ 768.467722] mobilizon_ctl[1749]: 22:05:12.203 [info] execute "DROP TYPE public.event_visibility"1139server # [ 768.513671] mobilizon_ctl[1749]: 22:05:12.232 [info] execute "CREATE TYPE public.event_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1140server # [ 768.554899] mobilizon_ctl[1749]: 22:05:12.284 [info] execute "ALTER TABLE events ALTER COLUMN visibility TYPE event_visibility USING visibility::event_visibility"1141server # [ 768.724907] mobilizon_ctl[1749]: 22:05:12.455 [info] execute "CREATE TYPE public.join_options AS ENUM ('free', 'restricted', 'invite', 'external')"1142server # [ 768.758661] mobilizon_ctl[1749]: 22:05:12.486 [info] alter table events1143server # [ 768.799168] mobilizon_ctl[1749]: 22:05:12.536 [info] execute "UPDATE events SET visibility = 'public' WHERE visibility IS NULL"1144server # [ 768.855003] mobilizon_ctl[1749]: 22:05:12.590 [info] alter table events1145server # [ 768.907561] mobilizon_ctl[1749]: 22:05:12.632 [info] == Migrated 20190130151607 in 0.6s1146server # [ 769.054019] mobilizon_ctl[1749]: 22:05:12.788 [info] == Running 20190207134142 Mobilizon.Repo.Migrations.MoveParticipantRoleToEnum.up/0 forward1147server # [ 769.079994] mobilizon_ctl[1749]: 22:05:12.814 [info] execute "CREATE TYPE public.participant_role AS ENUM ('not_approved', 'not_confirmed', 'rejected', 'participant', 'moderator', 'administrator', 'creator')"1148server # [ 769.130293] mobilizon_ctl[1749]: 22:05:12.851 [info] alter table participants1149server # [ 769.190934] mobilizon_ctl[1749]: 22:05:12.916 [info] execute "UPDATE participants set role_tmp = 'not_approved' where role = 0"1150server # [ 769.290808] mobilizon_ctl[1749]: 22:05:12.970 [info] execute "UPDATE participants set role_tmp = 'participant' where role = 1"1151server # [ 769.311119] mobilizon_ctl[1749]: 22:05:12.986 [info] execute "UPDATE participants set role_tmp = 'moderator' where role = 2"1152server # [ 769.329735] mobilizon_ctl[1749]: 22:05:13.014 [info] execute "UPDATE participants set role_tmp = 'administrator' where role = 3"1153server # [ 769.353289] mobilizon_ctl[1749]: 22:05:13.039 [info] execute "UPDATE participants set role_tmp = 'creator' where role = 4"1154server # [ 769.385503] mobilizon_ctl[1749]: 22:05:13.079 [info] alter table participants1155server # [ 769.419023] mobilizon_ctl[1749]: 22:05:13.115 [info] rename column role_tmp to role on table participants1156server # [ 769.435716] mobilizon_ctl[1749]: 22:05:13.147 [info] == Migrated 20190207134142 in 0.3s1157server # [ 769.513149] mobilizon_ctl[1749]: 22:05:13.246 [info] == Running 20190208141700 Mobilizon.Repo.Migrations.TagsRelations.up/0 forward1158server # [ 769.543897] mobilizon_ctl[1749]: 22:05:13.270 [info] create table tag_relations1159server # [ 769.686026] mobilizon_ctl[1749]: 22:05:13.411 [info] create check constraint no_self_loops_check on table tag_relations1160server # [ 769.725814] mobilizon_ctl[1749]: 22:05:13.451 [info] create index index_tag_relations_tag_id1161server # [ 769.783493] mobilizon_ctl[1749]: 22:05:13.510 [info] create index index_tag_relations_link_id1162server # [ 769.837433] mobilizon_ctl[1749]: 22:05:13.566 [info] == Migrated 20190208141700 in 0.3s1163server # [ 769.932886] mobilizon_ctl[1749]: 22:05:13.663 [info] == Running 20190214100858 Mobilizon.Repo.Migrations.DropDatetimetz.up/0 forward1164server # [ 769.987717] mobilizon_ctl[1749]: 22:05:13.702 [info] alter table events1165server # [ 770.050002] mobilizon_ctl[1749]: 22:05:13.785 [info] alter table sessions1166server # [ 770.099164] mobilizon_ctl[1749]: 22:05:13.830 [info] execute "DROP TYPE datetimetz"1167server # [ 770.139818] mobilizon_ctl[1749]: 22:05:13.859 [info] == Migrated 20190214100858 in 0.1s1168server # [ 770.230267] mobilizon_ctl[1749]: #########################################################1169server # [ 770.241614] mobilizon_ctl[1749]: # If the CREATE EXTENSION or DROP EXTENSION calls fail, #1170server # [ 770.245659] mobilizon_ctl[1749]: # please manually execute them with an authorized #1171server # [ 770.301831] mobilizon_ctl[1749]: # PostgreSQL user with SUPER USER role. #1172server # [ 770.311745] mobilizon_ctl[1749]: #########################################################1173server # [ 770.321466] mobilizon_ctl[1749]:1174server # [ 770.333410] mobilizon_ctl[1749]:1175server # [ 770.342865] mobilizon_ctl[1749]: 22:05:13.971 [info] == Running 20190219164310 Mobilizon.Repo.Migrations.CreateSearchIndexes.change/0 forward1176server # [ 770.358302] mobilizon_ctl[1749]: 22:05:13.992 [info] execute "CREATE EXTENSION IF NOT EXISTS pg_trgm"1177server # [ 770.383191] mobilizon_ctl[1749]: 22:05:14.023 [info] extension "pg_trgm" already exists, skipping1178server # [ 770.403741] mobilizon_ctl[1749]: 22:05:14.024 [info] execute "CREATE EXTENSION IF NOT EXISTS unaccent"1179server # [ 770.410946] mobilizon_ctl[1749]: 22:05:14.068 [info] extension "unaccent" already exists, skipping1180server # [ 770.426626] mobilizon_ctl[1749]: 22:05:14.082 [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;"1181server # [ 770.439041] mobilizon_ctl[1749]: 22:05:14.154 [info] execute "CREATE INDEX \"event_title_trigram\" ON \"events\" USING GIN (f_unaccent(title) gin_trgm_ops)"1182server # [ 770.544835] mobilizon_ctl[1749]: 22:05:14.279 [info] execute "CREATE INDEX \"actor_preferred_username_trigram\" ON \"actors\"\n USING GIN (f_unaccent(preferred_username) gin_trgm_ops)"1183server # [ 770.614834] mobilizon_ctl[1749]: 22:05:14.343 [info] execute "CREATE INDEX \"actor_name_trigram\" ON \"actors\"\n USING GIN (f_unaccent(name) gin_trgm_ops)"1184server # [ 770.689142] mobilizon_ctl[1749]: 22:05:14.415 [info] == Migrated 20190219164310 in 0.4s1185server # [ 770.828898] mobilizon_ctl[1749]: 22:05:14.531 [info] == Running 20190222151449 Mobilizon.Repo.Migrations.DropCategories.up/0 forward1186server # [ 770.853007] mobilizon_ctl[1749]: 22:05:14.555 [info] alter table events1187server # [ 770.950550] mobilizon_ctl[1749]: 22:05:14.660 [info] drop table categories1188server # [ 771.073944] mobilizon_ctl[1749]: 22:05:14.785 [info] == Migrated 20190222151449 in 0.2s1189server # [ 771.226505] mobilizon_ctl[1749]: 22:05:14.951 [info] == Running 20190301141830 Mobilizon.Repo.Migrations.MoveMemberRoleToEnum.up/0 forward1190server # [ 771.323618] mobilizon_ctl[1749]: 22:05:14.983 [info] execute "CREATE TYPE public.member_role AS ENUM ('invited', 'not_approved', 'member', 'moderator', 'administrator', 'creator', 'rejected')"1191server # [ 771.346085] mobilizon_ctl[1749]: 22:05:15.041 [info] alter table members1192server # [ 771.381918] mobilizon_ctl[1749]: 22:05:15.088 [info] execute "UPDATE members set role_tmp = 'member' where role = 0"1193server # [ 771.465703] mobilizon_ctl[1749]: 22:05:15.170 [info] execute "UPDATE members set role_tmp = 'moderator' where role = 1"1194server # [ 771.522056] mobilizon_ctl[1749]: 22:05:15.195 [info] execute "UPDATE members set role_tmp = 'creator' where role = 2"1195server # [ 771.534883] mobilizon_ctl[1749]: 22:05:15.239 [info] execute "UPDATE members set role_tmp = 'not_approved' where approved is false"1196server # [ 771.578802] mobilizon_ctl[1749]: 22:05:15.292 [info] alter table members1197server # [ 771.649920] mobilizon_ctl[1749]: 22:05:15.361 [info] rename column role_tmp to role on table members1198server # [ 771.671473] mobilizon_ctl[1749]: 22:05:15.384 [info] == Migrated 20190301141830 in 0.4s1199server # [ 771.809704] mobilizon_ctl[1749]: 22:05:15.548 [info] == Running 20190301143831 Mobilizon.Repo.Migrations.ActorGroupOpenness.up/0 forward1200server # [ 771.973044] mobilizon_ctl[1749]: 22:05:15.686 [info] execute "CREATE TYPE public.actor_openness AS ENUM ('invite_only', 'moderated', 'open')"1201server # [ 772.004947] mobilizon_ctl[1749]: 22:05:15.723 [info] alter table actors1202server # [ 772.063226] mobilizon_ctl[1749]: 22:05:15.787 [info] == Migrated 20190301143831 in 0.2s1203server # [ 772.197233] mobilizon_ctl[1749]: 22:05:15.918 [info] == Running 20190307125009 Mobilizon.Repo.Migrations.MoveUserRoleToEnum.up/0 forward1204server # [ 772.228886] mobilizon_ctl[1749]: 22:05:15.948 [info] execute "CREATE TYPE public.user_role AS ENUM ('administrator', 'moderator', 'user', 'pending')"1205server # [ 772.329356] mobilizon_ctl[1749]: 22:05:15.986 [info] alter table users1206server # [ 772.358897] mobilizon_ctl[1749]: 22:05:16.058 [info] execute "UPDATE users set role_tmp = 'user' where role = 0"1207server # [ 772.409123] mobilizon_ctl[1749]: 22:05:16.127 [info] execute "UPDATE users set role_tmp = 'moderator' where role = 1"1208server # [ 772.445045] mobilizon_ctl[1749]: 22:05:16.166 [info] execute "UPDATE users set role_tmp = 'administrator' where role = 2"1209server # [ 772.480851] mobilizon_ctl[1749]: 22:05:16.196 [info] alter table users1210server # [ 772.515906] mobilizon_ctl[1749]: 22:05:16.251 [info] rename column role_tmp to role on table users1211server # [ 772.594027] mobilizon_ctl[1749]: 22:05:16.292 [info] == Migrated 20190307125009 in 0.3s1212server # [ 772.694867] mobilizon_ctl[1749]: 22:05:16.420 [info] == Running 20190307133518 Mobilizon.Repo.Migrations.FeedTokenTable.change/0 forward1213server # [ 773.003621] mobilizon_ctl[1749]: 22:05:16.717 [info] create table feed_tokens1214server # [ 773.145164] mobilizon_ctl[1749]: 22:05:16.867 [info] == Migrated 20190307133518 in 0.4s1215server # [ 773.346769] mobilizon_ctl[1749]: 22:05:17.012 [info] == Running 20190322143724 Mobilizon.Repo.Migrations.RenameAddressFields.change/0 forward1216server # [ 773.369761] mobilizon_ctl[1749]: 22:05:17.074 [info] rename column addressCountry to country on table addresses1217server # [ 773.397231] mobilizon_ctl[1749]: 22:05:17.110 [info] rename column addressLocality to locality on table addresses1218server # [ 773.427092] mobilizon_ctl[1749]: 22:05:17.154 [info] rename column addressRegion to region on table addresses1219server # [ 773.469279] mobilizon_ctl[1749]: 22:05:17.183 [info] rename column postalCode to postal_code on table addresses1220server # [ 773.487009] mobilizon_ctl[1749]: 22:05:17.213 [info] rename column streetAddress to street on table addresses1221server # [ 773.511340] mobilizon_ctl[1749]: 22:05:17.242 [info] == Migrated 20190322143724 in 0.2s1222server # [ 773.633898] mobilizon_ctl[1749]: 22:05:17.354 [info] == Running 20190411124138 Mobilizon.Repo.Migrations.EventAddDescriptionSlug.change/0 forward1223server # [ 773.668926] mobilizon_ctl[1749]: 22:05:17.383 [info] alter table events1224server # [ 773.721093] mobilizon_ctl[1749]: 22:05:17.436 [info] == Migrated 20190411124138 in 0.0s1225server # [ 773.859510] mobilizon_ctl[1749]: 22:05:17.575 [info] == Running 20190411161557 Mobilizon.Repo.Migrations.EventEventTagOnDelete.up/0 forward1226server # [ 773.876893] mobilizon_ctl[1749]: 22:05:17.600 [info] drop constraint events_tags_event_id_fkey from table events_tags1227server # [ 773.977146] mobilizon_ctl[1749]: 22:05:17.684 [info] drop constraint events_tags_tag_id_fkey from table events_tags1228server # [ 774.017929] mobilizon_ctl[1749]: 22:05:17.750 [info] alter table events_tags1229server # [ 774.290072] mobilizon_ctl[1749]: 22:05:17.995 [info] == Migrated 20190411161557 in 0.4s1230server # [ 774.486496] mobilizon_ctl[1749]: 22:05:18.140 [info] == Running 20190425075451 Mobilizon.Repo.Migrations.AddVisibilityToActor.up/0 forward1231server # [ 774.507263] mobilizon_ctl[1749]: 22:05:18.159 [info] execute "CREATE TYPE public.actor_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1232server # [ 774.522306] mobilizon_ctl[1749]: 22:05:18.207 [info] alter table actors1233server # [ 774.569533] mobilizon_ctl[1749]: 22:05:18.288 [info] == Migrated 20190425075451 in 0.1s1234server # [ 774.627293] postgres[2162]: [2162] LOG: skipping analyze of "schema_migrations" --- lock not available1235server # [ 774.720942] mobilizon_ctl[1749]: 22:05:18.432 [info] == Running 20190426093202 Mobilizon.Repo.Migrations.CreatePictures.up/0 forward1236server # [ 774.758758] mobilizon_ctl[1749]: 22:05:18.472 [info] create table pictures1237server # [ 775.015625] mobilizon_ctl[1749]: 22:05:18.727 [info] alter table actors1238server # [ 775.124971] mobilizon_ctl[1749]: 22:05:18.837 [info] alter table events1239server # [ 775.329886] mobilizon_ctl[1749]: 22:05:19.034 [info] == Migrated 20190426093202 in 0.5s1240server # [ 775.526890] mobilizon_ctl[1749]: 22:05:19.197 [info] == Running 20190531131458 :"Elixir.Mobilizon.Repo.Migrations.Attach-pictures-to-actors".change/0 forward1241server # [ 775.542719] mobilizon_ctl[1749]: 22:05:19.223 [info] alter table pictures1242server # [ 775.723796] mobilizon_ctl[1749]: 22:05:19.439 [info] == Migrated 20190531131458 in 0.2s1243server # [ 775.879728] mobilizon_ctl[1749]: 22:05:19.602 [info] == Running 20190712125833 Mobilizon.Repo.Migrations.CreateReports.up/0 forward1244server # [ 776.143391] mobilizon_ctl[1749]: 22:05:19.871 [info] execute "CREATE TYPE public.report_status AS ENUM ('open', 'closed', 'resolved')"1245server # [ 776.236898] mobilizon_ctl[1749]: 22:05:19.927 [info] create table reports1246server # [ 776.861807] mobilizon_ctl[1749]: 22:05:20.508 [info] create table reports_comments1247server # [ 776.979624] mobilizon_ctl[1749]: 22:05:20.692 [info] == Migrated 20190712125833 in 1.0s1248server # [ 777.129453] mobilizon_ctl[1749]: 22:05:20.856 [info] == Running 20190712132438 Mobilizon.Repo.Migrations.CreateReportNotes.change/0 forward1249server # [ 777.192731] mobilizon_ctl[1749]: 22:05:20.895 [info] create table report_notes1250server # [ 777.528851] mobilizon_ctl[1749]: 22:05:21.251 [info] == Migrated 20190712132438 in 0.3s1251server # [ 777.653164] mobilizon_ctl[1749]: 22:05:21.376 [info] == Running 20190718122556 Mobilizon.Repo.Migrations.CreateAdminActionLogs.change/0 forward1252server # [ 777.714826] mobilizon_ctl[1749]: 22:05:21.434 [info] create table admin_action_logs1253server # [ 778.014170] mobilizon_ctl[1749]: 22:05:21.699 [info] == Migrated 20190718122556 in 0.3s1254server # [ 778.065931] mobilizon_ctl[1749]: 22:05:21.803 [info] == Running 20190729125532 :"Elixir.Mobilizon.Repo.Migrations.Add-url-to-addresses".change/0 forward1255server # [ 778.097108] mobilizon_ctl[1749]: 22:05:21.822 [info] alter table addresses1256server # [ 778.127161] mobilizon_ctl[1749]: 22:05:21.859 [info] == Migrated 20190729125532 in 0.0s1257server # [ 778.223898] mobilizon_ctl[1749]: 22:05:21.959 [info] == Running 20190806131306 Mobilizon.Repo.Migrations.AddUrlToFollowsAndMoveToUuid.up/0 forward1258server # [ 778.249897] mobilizon_ctl[1749]: 22:05:21.968 [info] alter table followers1259server # [ 778.353799] mobilizon_ctl[1749]: 22:05:22.082 [info] == Migrated 20190806131306 in 0.1s1260server # [ 778.456829] mobilizon_ctl[1749]: 22:05:22.187 [info] == Running 20190813160207 Mobilizon.Repo.Migrations.AddUrlAndUuidToParticipants.up/0 forward1261server # [ 778.495281] mobilizon_ctl[1749]: 22:05:22.223 [info] drop index participants_event_id_index1262server # [ 778.537503] mobilizon_ctl[1749]: 22:05:22.265 [info] drop index if exists participants_account_id_index1263server # [ 778.557708] mobilizon_ctl[1749]: 22:05:22.292 [info] drop index if exists participants_actor_id_index1264server # [ 778.579671] mobilizon_ctl[1749]: 22:05:22.318 [info] index "participants_actor_id_index" does not exist, skipping1265server # [ 778.605900] mobilizon_ctl[1749]: 22:05:22.319 [info] drop constraint participants_event_id_fkey from table participants1266server # [ 778.646837] mobilizon_ctl[1749]: 22:05:22.379 [info] drop constraint if exists participants_account_id_fkey from table participants1267server # [ 778.700428] mobilizon_ctl[1749]: 22:05:22.419 [info] drop constraint if exists participants_actor_id_fkey from table participants1268server # [ 778.719352] mobilizon_ctl[1749]: 22:05:22.439 [info] constraint "participants_actor_id_fkey" of relation "participants" does not exist, skipping1269server # [ 778.731020] mobilizon_ctl[1749]: 22:05:22.439 [info] drop constraint participants_pkey from table participants1270server # [ 778.761654] mobilizon_ctl[1749]: 22:05:22.486 [info] alter table participants1271server # [ 778.925832] mobilizon_ctl[1749]: 22:05:22.659 [info] create index participants_event_id_index1272server # [ 779.022818] mobilizon_ctl[1749]: 22:05:22.710 [info] create index participants_actor_id_index1273server # [ 779.048700] mobilizon_ctl[1749]: 22:05:22.771 [info] == Migrated 20190813160207 in 0.5s1274server # [ 779.141772] mobilizon_ctl[1749]: 22:05:22.878 [info] == Running 20190821161658 Mobilizon.Repo.Migrations.AddOriginIdToAddresses.change/0 forward1275server # [ 779.156738] mobilizon_ctl[1749]: 22:05:22.888 [info] alter table addresses1276server # [ 779.209021] mobilizon_ctl[1749]: 22:05:22.935 [info] create index addresses_origin_id_index1277server # [ 779.268845] mobilizon_ctl[1749]: 22:05:23.002 [info] == Migrated 20190821161658 in 0.1s1278server # [ 779.361725] mobilizon_ctl[1749]: 22:05:23.087 [info] == Running 20190823132816 Mobilizon.Repo.Migrations.RemoveEventsWhenDeletingOrganizer.up/0 forward1279server # [ 779.382680] mobilizon_ctl[1749]: 22:05:23.103 [info] drop constraint events_organizer_account_id_fkey from table events1280server # [ 779.433856] mobilizon_ctl[1749]: 22:05:23.156 [info] alter table events1281server # [ 779.539439] mobilizon_ctl[1749]: 22:05:23.269 [info] == Migrated 20190823132816 in 0.1s1282server # [ 779.644969] mobilizon_ctl[1749]: 22:05:23.371 [info] == Running 20190902082656 Mobilizon.Repo.Migrations.AddOptionsToEvent.change/0 forward1283server # [ 779.658188] mobilizon_ctl[1749]: 22:05:23.387 [info] alter table events1284server # [ 779.707148] mobilizon_ctl[1749]: 22:05:23.430 [info] == Migrated 20190902082656 in 0.0s1285server: Guest root shell did not produce any data yet...1286server: To debug, enter the VM and run 'systemctl status backdoor.service'.1287server # [ 779.796805] mobilizon_ctl[1749]: 22:05:23.527 [info] == Running 20190929170817 Mobilizon.Storage.Repo.Migrations.RenamePostgresTypes.up/0 forward1288server # [ 780.078776] mobilizon_ctl[1749]: 22:05:23.759 [info] == Migrated 20190929170817 in 0.2s1289server # [ 780.134134] mobilizon_ctl[1749]: 22:05:23.869 [info] == Running 20190929175229 Mobilizon.Storage.Repo.Migrations.AddRejectedToParticipantRole.up/0 forward1290server # [ 780.164776] mobilizon_ctl[1749]: 22:05:23.877 [info] execute "ALTER TYPE public.participant_role ADD VALUE IF NOT EXISTS 'rejected'"1291server # [ 780.187863] mobilizon_ctl[1749]: 22:05:23.915 [info] enum label "rejected" already exists, skipping1292server # [ 780.207215] mobilizon_ctl[1749]: 22:05:23.926 [info] == Migrated 20190929175229 in 0.0s1293server # [ 780.294712] mobilizon_ctl[1749]: 22:05:24.030 [info] == Running 20191001074442 Mobilizon.Storage.Repo.Migrations.AddLocaleToUsers.change/0 forward1294server # [ 780.322619] mobilizon_ctl[1749]: 22:05:24.039 [info] alter table users1295server # [ 780.370237] mobilizon_ctl[1749]: 22:05:24.087 [info] == Migrated 20191001074442 in 0.0s1296server # [ 780.448508] mobilizon_ctl[1749]: 22:05:24.184 [info] == Running 20191002093208 Mobilizon.Storage.Repo.Migrations.AddDraftFlagToEvents.change/0 forward1297server # [ 780.467147] mobilizon_ctl[1749]: 22:05:24.192 [info] alter table events1298server # [ 780.515608] mobilizon_ctl[1749]: 22:05:24.248 [info] == Migrated 20191002093208 in 0.0s1299server # [ 780.605422] mobilizon_ctl[1749]: 22:05:24.335 [info] == Running 20191018075904 Mobilizon.Storage.Repo.Migrations.MoveSQLColumnsFromVarCharToText.up/0 forward1300server # [ 780.652815] mobilizon_ctl[1749]: 22:05:24.383 [info] alter table events1301server # [ 780.834962] mobilizon_ctl[1749]: 22:05:24.563 [info] alter table addresses1302server # [ 780.885155] mobilizon_ctl[1749]: 22:05:24.606 [info] alter table bots1303server # [ 780.918384] mobilizon_ctl[1749]: 22:05:24.647 [info] alter table report_notes1304server # [ 781.017115] mobilizon_ctl[1749]: 22:05:24.747 [info] alter table reports1305server # [ 781.111240] mobilizon_ctl[1749]: 22:05:24.784 [info] alter table sessions1306server # [ 781.153204] mobilizon_ctl[1749]: 22:05:24.871 [info] alter table tracks1307server # [ 781.208853] mobilizon_ctl[1749]: 22:05:24.923 [info] == Migrated 20191018075904 in 0.5s1308server # [ 781.345476] mobilizon_ctl[1749]: 22:05:25.083 [info] == Running 20191022083446 Mobilizon.Storage.Repo.Migrations.AddEagerMaterializedViewForSearchingEvents.up/0 forward1309server # [ 781.393472] mobilizon_ctl[1749]: 22:05:25.104 [info] create table event_search1310server # [ 781.578693] mobilizon_ctl[1749]: 22:05:25.300 [info] create index event_search_document_index1311server # [ 781.649648] mobilizon_ctl[1749]: 22:05:25.376 [info] execute "CREATE INDEX event_search_title_trgm_index ON event_search USING gin (title gin_trgm_ops)"1312server # [ 781.709793] mobilizon_ctl[1749]: 22:05:25.438 [info] create index event_search_id_index1313server # [ 781.779060] mobilizon_ctl[1749]: 22:05:25.492 [info] == Migrated 20191022083446 in 0.4s1314server # [ 781.881682] mobilizon_ctl[1749]: 22:05:25.616 [info] == Running 20191023133805 Mobilizon.Storage.Repo.Migrations.DeleteActorsCascadeToFollowers.up/0 forward1315server # [ 781.906590] mobilizon_ctl[1749]: 22:05:25.640 [info] drop constraint followers_actor_id_fkey from table followers1316server # [ 781.988952] mobilizon_ctl[1749]: 22:05:25.717 [info] drop constraint followers_target_actor_id_fkey from table followers1317server # [ 782.049055] mobilizon_ctl[1749]: 22:05:25.775 [info] alter table followers1318server # [ 782.299707] mobilizon_ctl[1749]: 22:05:25.970 [info] == Migrated 20191023133805 in 0.3s1319server # [ 782.358110] mobilizon_ctl[1749]: 22:05:26.087 [info] == Running 20191023143659 Mobilizon.Storage.Repo.Migrations.MoveParticipantsStatsToEvent.up/0 forward1320server # [ 782.380576] mobilizon_ctl[1749]: 22:05:26.103 [info] alter table events1321server # [ 782.445991] mobilizon_ctl[1749]: 22:05:26.171 [info] == Migrated 20191023143659 in 0.0s1322server # [ 782.571571] mobilizon_ctl[1749]: 22:05:26.305 [info] == Running 20191024204726 Mobilizon.Storage.Repo.Migrations.AddTagsToComments.up/0 forward1323server # [ 782.594673] mobilizon_ctl[1749]: 22:05:26.315 [info] create table comments_tags1324server # [ 782.748844] mobilizon_ctl[1749]: 22:05:26.471 [info] == Migrated 20191024204726 in 0.1s1325server # [ 782.860886] mobilizon_ctl[1749]: 22:05:26.597 [info] == Running 20191025083642 Mobilizon.Storage.Repo.Migrations.AddMentionTables.change/0 forward1326server # [ 782.910308] mobilizon_ctl[1749]: 22:05:26.637 [info] create table mentions1327server # [ 783.202233] mobilizon_ctl[1749]: 22:05:26.923 [info] create index mentions_actor_id_index1328server # [ 783.287560] mobilizon_ctl[1749]: 22:05:26.982 [info] == Migrated 20191025083642 in 0.3s1329server # [ 783.368483] mobilizon_ctl[1749]: 22:05:27.099 [info] == Running 20191025115537 Mobilizon.Storage.Repo.Migrations.AddUniqueIndexOnURLs.change/0 forward1330server # [ 783.378596] mobilizon_ctl[1749]: 22:05:27.111 [info] create index events_url_index1331server # [ 783.466894] mobilizon_ctl[1749]: 22:05:27.198 [info] create index comments_url_index1332server # [ 783.531554] mobilizon_ctl[1749]: 22:05:27.263 [info] == Migrated 20191025115537 in 0.1s1333server # [ 783.610103] mobilizon_ctl[1749]: 22:05:27.347 [info] == Running 20191031142145 Mobilizon.Storage.Repo.Migrations.AddUniqueIndexOnActorAndEventForParticipant.change/0 forward1334server # [ 783.632754] mobilizon_ctl[1749]: 22:05:27.359 [info] create index participants_event_id_actor_id_index1335server # [ 783.679123] mobilizon_ctl[1749]: 22:05:27.412 [info] == Migrated 20191031142145 in 0.0s1336server # [ 783.779033] mobilizon_ctl[1749]: 22:05:27.522 [info] == Running 20191104093447 Mobilizon.Storage.Repo.Migrations.AddObanJobsTable.up/0 forward1337server # [ 785.724907] mobilizon_ctl[1749]: 22:05:29.379 [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"1338server # [ 785.793057] mobilizon_ctl[1749]: 22:05:29.510 [info] create table if not exists public.oban_jobs1339server # [ 786.020773] mobilizon_ctl[1749]: 22:05:29.747 [info] create index if not exists public.oban_jobs_queue_index1340server # [ 786.107297] mobilizon_ctl[1749]: 22:05:29.827 [info] create index if not exists public.oban_jobs_state_index1341server # [ 786.177966] mobilizon_ctl[1749]: 22:05:29.903 [info] create index if not exists public.oban_jobs_scheduled_at_index1342server # [ 786.257128] mobilizon_ctl[1749]: 22:05:29.975 [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"1343server # [ 786.350473] mobilizon_ctl[1749]: 22:05:30.061 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"1344server # [ 786.378315] mobilizon_ctl[1749]: 22:05:30.083 [info] trigger "oban_notify" for relation "public.oban_jobs" does not exist, skipping1345server # [ 786.397848] mobilizon_ctl[1749]: 22:05:30.087 [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"1346server # [ 786.477246] mobilizon_ctl[1749]: 22:05:30.170 [info] drop index if exists public.oban_jobs_scheduled_at_index1347server # [ 786.527209] mobilizon_ctl[1749]: 22:05:30.235 [info] create index public.oban_jobs_scheduled_at_index1348server # [ 786.633322] mobilizon_ctl[1749]: 22:05:30.348 [info] create check constraint worker_length on table public.oban_jobs1349server # [ 786.658877] mobilizon_ctl[1749]: 22:05:30.398 [info] create check constraint queue_length on table public.oban_jobs1350server # [ 786.746938] mobilizon_ctl[1749]: 22:05:30.436 [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"1351server # [ 786.772280] mobilizon_ctl[1749]: 22:05:30.483 [info] alter table public.oban_jobs1352server # [ 786.794734] mobilizon_ctl[1749]: 22:05:30.527 [info] execute "DROP FUNCTION IF EXISTS \"public\".oban_wrap_id(value bigint)"1353server # [ 786.840482] mobilizon_ctl[1749]: 22:05:30.559 [info] drop index if exists public.oban_jobs_scheduled_at_index1354server # [ 786.871541] mobilizon_ctl[1749]: 22:05:30.603 [info] drop index if exists public.oban_jobs_queue_index1355server # [ 786.913725] mobilizon_ctl[1749]: 22:05:30.630 [info] drop index if exists public.oban_jobs_state_index1356server # [ 786.936834] mobilizon_ctl[1749]: 22:05:30.658 [info] create index if not exists public.oban_jobs_queue_state_scheduled_at_id_index1357server # [ 786.992978] mobilizon_ctl[1749]: 22:05:30.723 [info] create index if not exists public.oban_jobs_attempted_at_id_index1358server # [ 787.060860] mobilizon_ctl[1749]: 22:05:30.795 [info] alter table public.oban_jobs1359server # [ 787.125229] mobilizon_ctl[1749]: 22:05:30.851 [info] alter table public.oban_jobs1360server # [ 787.177730] mobilizon_ctl[1749]: 22:05:30.902 [info] drop index if exists public.oban_jobs_queue_state_scheduled_at_id_index1361server # [ 787.200615] mobilizon_ctl[1749]: 22:05:30.927 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index1362server # [ 787.277571] mobilizon_ctl[1749]: 22:05:31.006 [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"1363server # [ 787.308820] mobilizon_ctl[1749]: 22:05:31.039 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"1364server # [ 787.342945] mobilizon_ctl[1749]: 22:05:31.067 [info] execute "CREATE TRIGGER oban_notify\nAFTER INSERT ON \"public\".oban_jobs\nFOR EACH ROW EXECUTE PROCEDURE \"public\".oban_jobs_notify();\n"1365server # [ 787.365039] mobilizon_ctl[1749]: 22:05:31.090 [info] alter table public.oban_jobs1366server # [ 787.759253] mobilizon_ctl[1749]: 22:05:31.452 [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"1367server # [ 787.808812] mobilizon_ctl[1749]: 22:05:31.550 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index1368server # [ 787.834463] mobilizon_ctl[1749]: 22:05:31.570 [info] relation "oban_jobs_state_queue_priority_scheduled_at_id_index" already exists, skipping1369server # [ 787.854186] mobilizon_ctl[1749]: 22:05:31.571 [info] alter table public.oban_jobs1370server # [ 787.963734] mobilizon_ctl[1749]: 22:05:31.692 [info] create check constraint priority_range on table public.oban_jobs1371server # [ 788.021522] mobilizon_ctl[1749]: 22:05:31.750 [info] create check constraint positive_max_attempts on table public.oban_jobs1372server # [ 788.056859] mobilizon_ctl[1749]: 22:05:31.787 [info] create check constraint attempt_range on table public.oban_jobs1373server # [ 788.099848] mobilizon_ctl[1749]: 22:05:31.823 [info] drop index if exists public.oban_jobs_args_vector1374server # [ 788.121611] mobilizon_ctl[1749]: 22:05:31.843 [info] index "oban_jobs_args_vector" does not exist, skipping1375server # [ 788.153367] mobilizon_ctl[1749]: 22:05:31.844 [info] drop index if exists public.oban_jobs_worker_gist1376server # [ 788.184935] mobilizon_ctl[1749]: 22:05:31.883 [info] index "oban_jobs_worker_gist" does not exist, skipping1377server # [ 788.213112] mobilizon_ctl[1749]: 22:05:31.884 [info] drop index if exists public.oban_jobs_attempted_at_id_index1378server # [ 788.225966] mobilizon_ctl[1749]: 22:05:31.941 [info] create index if not exists public.oban_jobs_args_index1379server # [ 788.279563] mobilizon_ctl[1749]: 22:05:32.012 [info] create index if not exists public.oban_jobs_meta_index1380server # [ 788.349741] mobilizon_ctl[1749]: 22:05:32.083 [info] create table if not exists public.oban_peers1381server # [ 788.481074] mobilizon_ctl[1749]: 22:05:32.214 [info] execute "ALTER TABLE \"public\".oban_peers SET UNLOGGED"1382server # [ 788.647928] mobilizon_ctl[1749]: 22:05:32.379 [info] drop constraint priority_range from table public.oban_jobs1383server # [ 788.681903] mobilizon_ctl[1749]: 22:05:32.411 [info] create check constraint non_negative_priority on table public.oban_jobs1384server # [ 788.774133] mobilizon_ctl[1749]: 22:05:32.450 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"1385server # [ 788.786007] mobilizon_ctl[1749]: 22:05:32.486 [info] execute "DROP FUNCTION IF EXISTS \"public\".oban_jobs_notify()"1386server # [ 788.809634] mobilizon_ctl[1749]: 22:05:32.517 [info] create index if not exists public.oban_jobs_state_cancelled_at_index1387server # [ 788.865726] mobilizon_ctl[1749]: 22:05:32.598 [info] create index if not exists public.oban_jobs_state_discarded_at_index1388server # [ 788.934701] mobilizon_ctl[1749]: 22:05:32.647 [info] execute "COMMENT ON TABLE \"public\".oban_jobs IS '13'"1389server # [ 788.961193] mobilizon_ctl[1749]: 22:05:32.668 [info] == Migrated 20191104093447 in 5.1s1390server # [ 789.091402] mobilizon_ctl[1749]: 22:05:32.823 [info] == Running 20191106114524 Mobilizon.Storage.Repo.Migrations.RemoveFloorFromAddresses.up/0 forward1391server # [ 789.126224] mobilizon_ctl[1749]: 22:05:32.836 [info] alter table addresses1392server # [ 789.158545] mobilizon_ctl[1749]: 22:05:32.869 [info] == Migrated 20191106114524 in 0.0s1393server # [ 789.249221] mobilizon_ctl[1749]: 22:05:32.983 [info] == Running 20191106141051 Mobilizon.Storage.Repo.Migrations.AddTypeToAddresses.change/0 forward1394server # [ 789.266316] mobilizon_ctl[1749]: 22:05:32.991 [info] alter table addresses1395server # [ 789.303490] mobilizon_ctl[1749]: 22:05:33.027 [info] == Migrated 20191106141051 in 0.0s1396server # [ 789.388713] mobilizon_ctl[1749]: 22:05:33.123 [info] == Running 20191114155534 Mobilizon.Storage.Repo.Migrations.CascadeCommentDeletion.up/0 forward1397server # [ 789.413038] mobilizon_ctl[1749]: 22:05:33.138 [info] drop constraint comments_in_reply_to_comment_id_fkey from table comments1398server # [ 789.481623] mobilizon_ctl[1749]: 22:05:33.205 [info] drop constraint comments_origin_comment_id_fkey from table comments1399server # [ 789.531843] mobilizon_ctl[1749]: 22:05:33.254 [info] alter table comments1400server # [ 789.670926] mobilizon_ctl[1749]: 22:05:33.396 [info] == Migrated 20191114155534 in 0.2s1401server # [ 789.833618] mobilizon_ctl[1749]: 22:05:33.508 [info] == Running 20191122154018 Mobilizon.Storage.Repo.Migrations.AddDeletedAtOnComments.up/0 forward1402server # [ 789.855293] mobilizon_ctl[1749]: 22:05:33.531 [info] drop constraint if exists comments_actor_id_fkey from table comments1403server # [ 789.869958] mobilizon_ctl[1749]: 22:05:33.566 [info] constraint "comments_actor_id_fkey" of relation "comments" does not exist, skipping1404server # [ 789.882248] mobilizon_ctl[1749]: 22:05:33.567 [info] alter table comments1405server # [ 790.075254] mobilizon_ctl[1749]: 22:05:33.787 [info] == Migrated 20191122154018 in 0.2s1406server # [ 790.183495] mobilizon_ctl[1749]: 22:05:33.915 [info] == Running 20191126173510 Mobilizon.Storage.Repo.Migrations.AddLocalFieldToReports.up/0 forward1407server # [ 790.213153] mobilizon_ctl[1749]: 22:05:33.934 [info] alter table reports1408server # [ 790.274654] mobilizon_ctl[1749]: 22:05:33.989 [info] rename column uri to url on table reports1409server # [ 790.290464] mobilizon_ctl[1749]: 22:05:34.013 [info] == Migrated 20191126173510 in 0.0s1410server # [ 790.403395] mobilizon_ctl[1749]: 22:05:34.137 [info] == Running 20191127163737 Mobilizon.Repo.Migrations.CreateTombstones.change/0 forward1411server # [ 790.441887] mobilizon_ctl[1749]: 22:05:34.164 [info] create table tombstones1412server # [ 790.609879] mobilizon_ctl[1749]: 22:05:34.323 [info] create index tombstones_uri_index1413server # [ 790.663720] mobilizon_ctl[1749]: 22:05:34.390 [info] == Migrated 20191127163737 in 0.2s1414server # [ 790.777036] mobilizon_ctl[1749]: 22:05:34.508 [info] == Running 20191129091227 Mobilizon.Storage.Repo.Migrations.AddTimestampsToFollowers.change/0 forward1415server # [ 790.862957] mobilizon_ctl[1749]: 22:05:34.528 [info] alter table followers1416server # [ 790.889164] mobilizon_ctl[1749]: 22:05:34.600 [info] == Migrated 20191129091227 in 0.0s1417server # [ 790.992820] mobilizon_ctl[1749]: 22:05:34.723 [info] == Running 20191204164224 Mobilizon.Storage.Repo.Migrations.DeleteEventCascadeToComments.up/0 forward1418server # [ 791.025889] mobilizon_ctl[1749]: 22:05:34.751 [info] drop constraint if exists comments_event_id_fkey from table comments1419server # [ 791.084758] mobilizon_ctl[1749]: 22:05:34.814 [info] alter table comments1420server # [ 791.201046] mobilizon_ctl[1749]: 22:05:34.920 [info] == Migrated 20191204164224 in 0.1s1421server # [ 791.297506] mobilizon_ctl[1749]: 22:05:35.027 [info] == Running 20191206144028 Mobilizon.Repo.Migrations.CreateShares.up/0 forward1422server # [ 791.341367] mobilizon_ctl[1749]: 22:05:35.058 [info] create table shares1423server # [ 791.533200] mobilizon_ctl[1749]: 22:05:35.255 [info] create index if not exists shares_uri_actor_id_index1424server # [ 791.606910] mobilizon_ctl[1749]: 22:05:35.334 [info] == Migrated 20191206144028 in 0.3s1425server # [ 791.729056] mobilizon_ctl[1749]: 22:05:35.458 [info] == Running 20191219172017 Mobilizon.Storage.Repo.Migrations.AddMetadataToParticipant.up/0 forward1426server # [ 791.757562] mobilizon_ctl[1749]: 22:05:35.480 [info] drop index if exists participants_event_id_actor_id_index1427server # [ 791.803861] mobilizon_ctl[1749]: 22:05:35.530 [info] alter table participants1428server # [ 791.951714] mobilizon_ctl[1749]: 22:05:35.644 [info] execute "CREATE UNIQUE INDEX participants_metadata_confirmation_token_index ON participants((metadata->>'confirmation_token'))"1429server # [ 791.985136] mobilizon_ctl[1749]: 22:05:35.707 [info] == Migrated 20191219172017 in 0.2s1430server # [ 792.069368] mobilizon_ctl[1749]: 22:05:35.805 [info] == Running 20191223173537 Mobilizon.Storage.Repo.Migrations.AddNotConfirmedStatusToParticipants.up/0 forward1431server # [ 792.087141] mobilizon_ctl[1749]: 22:05:35.808 [info] execute "ALTER TYPE participant_role ADD VALUE IF NOT EXISTS 'not_confirmed'"1432server # [ 792.111090] mobilizon_ctl[1749]: 22:05:35.847 [info] enum label "not_confirmed" already exists, skipping1433server # [ 792.126963] mobilizon_ctl[1749]: 22:05:35.848 [info] == Migrated 20191223173537 in 0.0s1434server # [ 792.207381] mobilizon_ctl[1749]: 22:05:35.950 [info] == Running 20200116095117 Mobilizon.Repo.Migrations.CreateAdminSettings.change/0 forward1435server # [ 792.248051] mobilizon_ctl[1749]: 22:05:35.981 [info] create table admin_settings1436server # [ 792.384865] mobilizon_ctl[1749]: 22:05:36.120 [info] create index admin_settings_group_name_index1437server # [ 792.466365] mobilizon_ctl[1749]: 22:05:36.181 [info] == Migrated 20200116095117 in 0.2s1438server # [ 792.545632] mobilizon_ctl[1749]: 22:05:36.283 [info] == Running 20200210100910 Mobilizon.Storage.Repo.Migrations.AddUnconfirmedEmailToUsersTable.change/0 forward1439server # [ 792.576873] mobilizon_ctl[1749]: 22:05:36.296 [info] alter table users1440server # [ 792.608829] mobilizon_ctl[1749]: 22:05:36.340 [info] == Migrated 20200210100910 in 0.0s1441server # [ 792.692368] mobilizon_ctl[1749]: 22:05:36.423 [info] == Running 20200213154309 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV8.up/0 forward1442server # [ 792.777790] mobilizon_ctl[1749]: 22:05:36.499 [info] == Migrated 20200213154309 in 0.0s1443server # [ 792.873137] mobilizon_ctl[1749]: 22:05:36.599 [info] == Running 20200214135231 Mobilizon.Storage.Repo.Migrations.AddLastRefreshedAtToActors.change/0 forward1444server # [ 792.892498] mobilizon_ctl[1749]: 22:05:36.611 [info] alter table actors1445server # [ 792.985816] mobilizon_ctl[1749]: 22:05:36.659 [info] == Migrated 20200214135231 in 0.0s1446server # [ 793.053181] mobilizon_ctl[1749]: 22:05:36.776 [info] == Running 20200218085757 Mobilizon.Storage.Repo.Migrations.AddNewRolesToMember.up/0 forward1447server # [ 793.077005] mobilizon_ctl[1749]: 22:05:36.790 [info] execute "ALTER TYPE member_role ADD VALUE IF NOT EXISTS 'invited'"1448server # [ 793.100795] mobilizon_ctl[1749]: 22:05:36.827 [info] enum label "invited" already exists, skipping1449server # [ 793.133457] mobilizon_ctl[1749]: 22:05:36.835 [info] execute "ALTER TYPE member_role ADD VALUE IF NOT EXISTS 'rejected'"1450server # [ 793.153588] mobilizon_ctl[1749]: 22:05:36.863 [info] enum label "rejected" already exists, skipping1451server # [ 793.169537] mobilizon_ctl[1749]: 22:05:36.864 [info] == Migrated 20200218085757 in 0.0s1452server # [ 793.264854] mobilizon_ctl[1749]: 22:05:36.992 [info] == Running 20200218170232 Mobilizon.Storage.Repo.Migrations.MoveMemberIdToUuid.up/0 forward1453server # [ 793.298101] mobilizon_ctl[1749]: 22:05:37.008 [info] alter table members1454server # [ 793.464922] mobilizon_ctl[1749]: 22:05:37.182 [info] == Migrated 20200218170232 in 0.1s1455server # [ 793.593006] mobilizon_ctl[1749]: 22:05:37.319 [info] == Running 20200219152611 Mobilizon.Storage.Repo.Migrations.AddMembersEndpointToActors.up/0 forward1456server # [ 793.618462] mobilizon_ctl[1749]: 22:05:37.338 [info] alter table actors1457server # [ 793.679108] mobilizon_ctl[1749]: 22:05:37.398 [info] == Migrated 20200219152611 in 0.0s1458server # [ 793.821855] mobilizon_ctl[1749]: 22:05:37.543 [info] == Running 20200221095721 Mobilizon.Repo.Migrations.CreateTodos.change/0 forward1459server # [ 793.901401] mobilizon_ctl[1749]: 22:05:37.618 [info] create table todo_lists1460server # [ 794.242318] mobilizon_ctl[1749]: 22:05:37.899 [info] create table todos1461server # [ 794.488870] mobilizon_ctl[1749]: 22:05:38.191 [info] == Migrated 20200221095721 in 0.6s1462server # [ 794.571381] mobilizon_ctl[1749]: 22:05:38.307 [info] == Running 20200221160744 Mobilizon.Repo.Migrations.CreateResources.change/0 forward1463server # [ 794.613407] mobilizon_ctl[1749]: 22:05:38.340 [info] create table resource1464server # [ 794.883569] mobilizon_ctl[1749]: 22:05:38.603 [info] == Migrated 20200221160744 in 0.2s1465server # [ 795.012880] mobilizon_ctl[1749]: 22:05:38.735 [info] == Running 20200406115807 Mobilizon.Storage.Repo.Migrations.AddConversations.change/0 forward1466server # [ 795.051794] mobilizon_ctl[1749]: 22:05:38.764 [info] create table conversations1467server # [ 795.389830] mobilizon_ctl[1749]: 22:05:39.082 [info] alter table comments1468server # [ 795.436389] mobilizon_ctl[1749]: 22:05:39.163 [info] == Migrated 20200406115807 in 0.4s1469server # [ 795.532846] mobilizon_ctl[1749]: 22:05:39.267 [info] == Running 20200410100554 Mobilizon.Storage.Repo.Migrations.AddNumberEditsToComment.change/0 forward1470server # [ 795.552883] mobilizon_ctl[1749]: 22:05:39.275 [info] alter table comments1471server # [ 795.598305] mobilizon_ctl[1749]: 22:05:39.326 [info] == Migrated 20200410100554 in 0.0s1472server # [ 795.687287] mobilizon_ctl[1749]: 22:05:39.416 [info] == Running 20200513142813 Mobilizon.Storage.Repo.Migrations.AddResourcesAndTodosEndpointsToActor.change/0 forward1473server # [ 795.705898] mobilizon_ctl[1749]: 22:05:39.426 [info] alter table actors1474server # [ 795.756915] mobilizon_ctl[1749]: 22:05:39.483 [info] == Migrated 20200513142813 in 0.0s1475server # [ 795.836851] mobilizon_ctl[1749]: 22:05:39.572 [info] == Running 20200514101612 Mobilizon.Storage.Repo.Migrations.AddLocalAttributeToResourcesAndTodos.change/0 forward1476server # [ 795.862174] mobilizon_ctl[1749]: 22:05:39.587 [info] alter table resource1477server # [ 795.899052] mobilizon_ctl[1749]: 22:05:39.631 [info] alter table todo_lists1478server # [ 795.946678] mobilizon_ctl[1749]: 22:05:39.678 [info] alter table todos1479server # [ 795.997115] mobilizon_ctl[1749]: 22:05:39.728 [info] == Migrated 20200514101612 in 0.1s1480server # [ 796.090882] mobilizon_ctl[1749]: 22:05:39.823 [info] == Running 20200518084949 Mobilizon.Storage.Repo.Migrations.MoveInviterFromMetadataToMemberTable.change/0 forward1481server # [ 796.105914] mobilizon_ctl[1749]: 22:05:39.835 [info] alter table members1482server # [ 796.225229] mobilizon_ctl[1749]: 22:05:39.953 [info] == Migrated 20200518084949 in 0.1s1483server # [ 796.301233] mobilizon_ctl[1749]: 22:05:40.043 [info] == Running 20200519082820 Mobilizon.Storage.Repo.Migrations.AddUniqueURLContraintToSomeTables.change/0 forward1484server # [ 796.334219] mobilizon_ctl[1749]: 22:05:40.063 [info] create index members_url_index1485server # [ 796.434867] mobilizon_ctl[1749]: 22:05:40.120 [info] create index resource_url_index1486server # [ 796.458406] mobilizon_ctl[1749]: 22:05:40.183 [info] create index todo_lists_url_index1487server # [ 796.510463] mobilizon_ctl[1749]: 22:05:40.247 [info] create index todos_url_index1488server # [ 796.574294] mobilizon_ctl[1749]: 22:05:40.299 [info] == Migrated 20200519082820 in 0.2s1489server # [ 796.685571] mobilizon_ctl[1749]: 22:05:40.416 [info] == Running 20200526131523 Mobilizon.Repo.Migrations.CreateUserSettings.change/0 forward1490server # [ 796.725905] mobilizon_ctl[1749]: 22:05:40.443 [info] create table user_settings1491server # [ 796.841521] mobilizon_ctl[1749]: 22:05:40.555 [info] == Migrated 20200526131523 in 0.1s1492server # [ 796.936903] mobilizon_ctl[1749]: 22:05:40.678 [info] == Running 20200605154921 Mobilizon.Storage.Repo.Migrations.AddNotificationPendingParticipationToSettings.change/0 forward1493server # [ 796.965795] mobilizon_ctl[1749]: 22:05:40.698 [info] alter table user_settings1494server # [ 797.014028] mobilizon_ctl[1749]: 22:05:40.742 [info] == Migrated 20200605154921 in 0.0s1495server # [ 797.110395] mobilizon_ctl[1749]: 22:05:40.835 [info] == Running 20200612155254 Mobilizon.Storage.Repo.Migrations.AddDisabledParameterToUsers.change/0 forward1496server # [ 797.137035] mobilizon_ctl[1749]: 22:05:40.851 [info] alter table users1497server # [ 797.197514] mobilizon_ctl[1749]: 22:05:40.907 [info] == Migrated 20200612155254 in 0.0s1498server # [ 797.297151] mobilizon_ctl[1749]: 22:05:41.032 [info] == Running 20200630123819 Mobilizon.Storage.Repo.Migrations.AddProviderToUserAndMakePasswordMandatory.up/0 forward1499server # [ 797.332649] mobilizon_ctl[1749]: 22:05:41.044 [info] alter table users1500server # [ 797.387091] mobilizon_ctl[1749]: 22:05:41.119 [info] == Migrated 20200630123819 in 0.0s1501server # [ 797.523872] mobilizon_ctl[1749]: 22:05:41.211 [info] == Running 20200708080516 Mobilizon.Repo.Migrations.CreatePosts.up/0 forward1502server # [ 797.634292] mobilizon_ctl[1749]: 22:05:41.371 [info] execute "CREATE TYPE public.post_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"1503server # [ 797.686319] mobilizon_ctl[1749]: 22:05:41.410 [info] create table posts1504server # [ 797.927481] mobilizon_ctl[1749]: 22:05:41.651 [info] create table posts_tags1505server # [ 798.031165] mobilizon_ctl[1749]: 22:05:41.760 [info] alter table actors1506server # [ 798.078243] mobilizon_ctl[1749]: 22:05:41.814 [info] == Migrated 20200708080516 in 0.5s1507server # [ 798.183346] mobilizon_ctl[1749]: 22:05:41.920 [info] == Running 20200717142633 Mobilizon.Storage.Repo.Migrations.RenameConversationsToDiscussions.up/0 forward1508server # [ 798.228690] mobilizon_ctl[1749]: 22:05:41.949 [info] rename table conversations to discussions1509server # [ 798.281065] mobilizon_ctl[1749]: 22:05:41.979 [info] rename column conversation_id to discussion_id on table comments1510server # [ 798.301796] mobilizon_ctl[1749]: 22:05:42.007 [info] alter table actors1511server # [ 798.351306] mobilizon_ctl[1749]: 22:05:42.075 [info] == Migrated 20200717142633 in 0.1s1512server # [ 798.473425] mobilizon_ctl[1749]: 22:05:42.200 [info] == Running 20200721131521 Mobilizon.Storage.Repo.Migrations.AddUrlToConversation.up/0 forward1513server # [ 798.574223] mobilizon_ctl[1749]: 22:05:42.240 [info] drop constraint if exists comments_conversation_id_fkey from table comments1514server # [ 798.605556] mobilizon_ctl[1749]: 22:05:42.329 [info] drop constraint if exists comments_discussion_id_fkey from table comments1515server # [ 798.638752] mobilizon_ctl[1749]: 22:05:42.347 [info] constraint "comments_discussion_id_fkey" of relation "comments" does not exist, skipping1516server # [ 798.662086] mobilizon_ctl[1749]: 22:05:42.348 [info] alter table discussions1517server # [ 798.781069] mobilizon_ctl[1749]: 22:05:42.504 [info] alter table comments1518server # [ 798.901763] mobilizon_ctl[1749]: 22:05:42.619 [info] == Migrated 20200721131521 in 0.4s1519server # [ 799.026856] mobilizon_ctl[1749]: 22:05:42.764 [info] == Running 20200805124620 Mobilizon.Storage.Repo.Migrations.AddAddressToActors.change/0 forward1520server # [ 799.065242] mobilizon_ctl[1749]: 22:05:42.786 [info] alter table actors1521server # [ 799.196549] mobilizon_ctl[1749]: 22:05:42.919 [info] == Migrated 20200805124620 in 0.1s1522server # [ 799.294882] mobilizon_ctl[1749]: 22:05:43.027 [info] == Running 20200817080549 Mobilizon.Storage.Repo.Migrations.AddPublishedAtToEntities.up/0 forward1523server # [ 799.321906] mobilizon_ctl[1749]: 22:05:43.035 [info] alter table comments1524server # [ 799.412941] mobilizon_ctl[1749]: 22:05:43.124 [info] alter table resource1525server # [ 799.509801] mobilizon_ctl[1749]: 22:05:43.221 [info] alter table todo_lists1526server # [ 799.646927] mobilizon_ctl[1749]: 22:05:43.318 [info] alter table todos1527server # [ 799.714068] mobilizon_ctl[1749]: 22:05:43.424 [info] == Migrated 20200817080549 in 0.3s1528server # [ 799.825794] mobilizon_ctl[1749]: 22:05:43.552 [info] == Running 20200828071543 Mobilizon.Storage.Repo.Migrations.AddLoginInformationToUser.change/0 forward1529server # [ 799.851269] mobilizon_ctl[1749]: 22:05:43.567 [info] alter table users1530server # [ 799.915041] mobilizon_ctl[1749]: 22:05:43.642 [info] == Migrated 20200828071543 in 0.0s1531server # [ 800.051119] mobilizon_ctl[1749]: 22:05:43.780 [info] == Running 20200901101307 Mobilizon.Storage.Repo.Migrations.FixRelayVisibility.up/0 forward1532server # [ 800.255599] mobilizon_ctl[1749]: 22:05:43.968 [info] == Migrated 20200901101307 in 0.1s1533server # [ 800.365351] mobilizon_ctl[1749]: 22:05:44.099 [info] == Running 20200928150725 Mobilizon.Storage.Repo.Migrations.AddContactsToEvents.change/0 forward1534server # [ 800.397231] mobilizon_ctl[1749]: 22:05:44.112 [info] create table event_contacts1535server # [ 800.563875] mobilizon_ctl[1749]: 22:05:44.277 [info] == Migrated 20200928150725 in 0.1s1536server # [ 800.737238] mobilizon_ctl[1749]: 22:05:44.397 [info] == Running 20201006132530 Mobilizon.Storage.Repo.Migrations.ChangeDefaultNotificationPendingParticipation.up/0 forward1537server # [ 800.754170] mobilizon_ctl[1749]: 22:05:44.400 [info] alter table user_settings1538server # [ 800.793355] mobilizon_ctl[1749]: 22:05:44.515 [info] == Migrated 20201006132530 in 0.1s1539server # [ 800.889841] mobilizon_ctl[1749]: 22:05:44.620 [info] == Running 20201103141811 Mobilizon.Storage.Repo.Migrations.AddNotificationPendingMembershipToSettings.change/0 forward1540server # [ 800.903434] mobilizon_ctl[1749]: 22:05:44.630 [info] alter table user_settings1541server # [ 800.952633] mobilizon_ctl[1749]: 22:05:44.677 [info] == Migrated 20201103141811 in 0.0s1542server # [ 801.057993] mobilizon_ctl[1749]: 22:05:44.780 [info] == Running 20201105145719 Mobilizon.Storage.Repo.Migrations.RepairGroupsWithMissingURLs.up/0 forward1543server # [ 801.443049] mobilizon_ctl[1749]: 22:05:45.164 [info] == Migrated 20201105145719 in 0.3s1544server # [ 801.554562] mobilizon_ctl[1749]: 22:05:45.282 [info] == Running 20201114174929 Mobilizon.Storage.Repo.Migrations.FixObanMigrationsForOlderPostgreSQLVersions.up/0 forward1545server # [ 801.594990] mobilizon_ctl[1749]: 22:05:45.319 [info] == Migrated 20201114174929 in 0.0s1546server # [ 801.755300] mobilizon_ctl[1749]: 22:05:45.423 [info] == Running 20201114175249 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV9.up/0 forward1547server # [ 801.842395] mobilizon_ctl[1749]: 22:05:45.559 [info] == Migrated 20201114175249 in 0.1s1548server # [ 801.934940] mobilizon_ctl[1749]: 22:05:45.671 [info] == Running 20201120161229 Mobilizon.Storage.Repo.Migrations.FixPictureDeletion.up/0 forward1549server # [ 801.974277] mobilizon_ctl[1749]: 22:05:45.703 [info] drop constraint if exists posts_picture_id_fkey from table posts1550server # [ 802.050845] mobilizon_ctl[1749]: 22:05:45.775 [info] alter table posts1551server # [ 802.126825] mobilizon_ctl[1749]: 22:05:45.866 [info] drop constraint if exists events_picture_id_fkey from table events1552server # [ 802.189558] mobilizon_ctl[1749]: 22:05:45.919 [info] alter table events1553server # [ 802.306373] mobilizon_ctl[1749]: 22:05:46.019 [info] == Migrated 20201120161229 in 0.3s1554server # [ 802.401105] mobilizon_ctl[1749]: 22:05:46.131 [info] == Running 20201124094655 Mobilizon.Storage.Repo.Migrations.AddMediaTables.change/0 forward1555server # [ 802.435119] mobilizon_ctl[1749]: 22:05:46.172 [info] rename table pictures to medias1556server # [ 802.493342] mobilizon_ctl[1749]: 22:05:46.219 [info] create table events_medias1557server # [ 802.685920] mobilizon_ctl[1749]: 22:05:46.401 [info] create table posts_medias1558server # [ 802.914196] mobilizon_ctl[1749]: 22:05:46.586 [info] create table comments_medias1559server # [ 803.045764] mobilizon_ctl[1749]: 22:05:46.764 [info] == Migrated 20201124094655 in 0.6s1560server # [ 803.181063] mobilizon_ctl[1749]: 22:05:46.900 [info] == Running 20210127153325 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV10.up/0 forward1561server # [ 803.264188] mobilizon_ctl[1749]: 22:05:46.986 [info] == Migrated 20210127153325 in 0.0s1562server # [ 803.365475] mobilizon_ctl[1749]: 22:05:47.099 [info] == Running 20210210143432 Mobilizon.Storage.Repo.Migrations.AddLocationSettingsToUser.change/0 forward1563server # [ 803.409132] mobilizon_ctl[1749]: 22:05:47.123 [info] alter table user_settings1564server # [ 803.533836] mobilizon_ctl[1749]: 22:05:47.243 [info] == Migrated 20210210143432 in 0.1s1565server # [ 803.634555] mobilizon_ctl[1749]: 22:05:47.369 [info] == Running 20210215092515 Mobilizon.Storage.Repo.Migrations.AddActivityTable.change/0 forward1566server # [ 803.673247] mobilizon_ctl[1749]: 22:05:47.406 [info] create table activities1567server # [ 804.038096] mobilizon_ctl[1749]: 22:05:47.722 [info] == Migrated 20210215092515 in 0.3s1568server # [ 804.137103] mobilizon_ctl[1749]: 22:05:47.870 [info] == Running 20210217164549 Mobilizon.Storage.Repo.Migrations.AddMemberSinceToMembers.up/0 forward1569server # [ 804.169369] mobilizon_ctl[1749]: 22:05:47.879 [info] alter table members1570server # [ 804.315385] mobilizon_ctl[1749]: 22:05:48.039 [info] == Migrated 20210217164549 in 0.1s1571server # [ 804.388802] mobilizon_ctl[1749]: 22:05:48.123 [info] == Running 20210310084210 Mobilizon.Storage.Repo.Migrations.AddIndexesToActivities.change/0 forward1572server # [ 804.409546] mobilizon_ctl[1749]: 22:05:48.131 [info] create index activity_group_id1573server # [ 804.446902] mobilizon_ctl[1749]: 22:05:48.185 [info] create index activity_filter1574server # [ 804.500785] mobilizon_ctl[1749]: 22:05:48.236 [info] == Migrated 20210310084210 in 0.1s1575server # [ 804.595072] mobilizon_ctl[1749]: 22:05:48.328 [info] == Running 20210422140923 Mobilizon.Storage.Repo.Migrations.CleanupAddresses.up/0 forward1576server # [ 804.686051] mobilizon_ctl[1749]: 22:05:48.411 [info] == Migrated 20210422140923 in 0.0s1577server # [ 804.766564] mobilizon_ctl[1749]: 22:05:48.513 [info] == Running 20210422155913 Mobilizon.Storage.Repo.Migrations.AddIndexToAddresses.up/0 forward1578server # [ 804.795901] mobilizon_ctl[1749]: 22:05:48.525 [info] create index if not exists addresses_url_index1579server # [ 804.855018] mobilizon_ctl[1749]: 22:05:48.579 [info] == Migrated 20210422155913 in 0.0s1580server # [ 804.934890] mobilizon_ctl[1749]: 22:05:48.668 [info] == Running 20210427091034 Mobilizon.Storage.Repo.Migrations.RepairUsersDefaultActors.up/0 forward1581server # [ 805.043167] mobilizon_ctl[1749]: 22:05:48.724 [info] == Migrated 20210427091034 in 0.0s1582server # [ 805.101034] mobilizon_ctl[1749]: 22:05:48.831 [info] == Running 20210505172402 Mobilizon.Storage.Repo.Migrations.AddGroupNotificationAndLastNotificationDateSettings.change/0 forward1583server # [ 805.122031] mobilizon_ctl[1749]: 22:05:48.846 [info] alter table user_settings1584server # [ 805.165068] mobilizon_ctl[1749]: 22:05:48.897 [info] == Migrated 20210505172402 in 0.0s1585server # [ 805.250457] mobilizon_ctl[1749]: 22:05:48.983 [info] == Running 20210506080303 Mobilizon.Repo.Migrations.CreateUserPushSubscriptions.change/0 forward1586server # [ 805.281675] mobilizon_ctl[1749]: 22:05:49.007 [info] create table user_push_subscriptions1587server # [ 805.423702] mobilizon_ctl[1749]: 22:05:49.158 [info] create index user_push_subscriptions_user_id_digest_index1588server # [ 805.491582] mobilizon_ctl[1749]: 22:05:49.218 [info] == Migrated 20210506080303 in 0.2s1589server # [ 805.593798] mobilizon_ctl[1749]: 22:05:49.319 [info] == Running 20210512084824 Mobilizon.Storage.Repo.Migrations.RepairRelayAndAnonymousActors.up/0 forward1590server # [ 805.660765] mobilizon_ctl[1749]: 22:05:49.383 [info] == Migrated 20210512084824 in 0.0s1591server # [ 805.744953] mobilizon_ctl[1749]: 22:05:49.472 [info] == Running 20210526203337 Mobilizon.Storage.Repo.Migrations.AddUserActivitySettings.change/0 forward1592server # [ 805.770302] mobilizon_ctl[1749]: 22:05:49.496 [info] create table user_activity_settings1593server # [ 805.939162] mobilizon_ctl[1749]: 22:05:49.673 [info] create index user_activity_settings_user_id_key_method_index1594server # [ 806.054844] mobilizon_ctl[1749]: 22:05:49.741 [info] == Migrated 20210526203337 in 0.2s1595server # [ 806.101533] mobilizon_ctl[1749]: 22:05:49.831 [info] == Running 20210601082412 Mobilizon.Storage.Repo.Migrations.AddIsAnnouncementToComments.change/0 forward1596server # [ 806.117097] mobilizon_ctl[1749]: 22:05:49.843 [info] alter table comments1597server # [ 806.160795] mobilizon_ctl[1749]: 22:05:49.894 [info] == Migrated 20210601082412 in 0.0s1598server # [ 806.270967] mobilizon_ctl[1749]: 22:05:49.995 [info] == Running 20210601082613 Mobilizon.Storage.Repo.Migrations.FixUserSettingsNullableFields.change/0 forward1599server # [ 806.300899] mobilizon_ctl[1749]: 22:05:50.020 [info] alter table user_settings1600server # [ 806.353158] mobilizon_ctl[1749]: 22:05:50.087 [info] == Migrated 20210601082613 in 0.0s1601server # [ 806.440685] mobilizon_ctl[1749]: 22:05:50.175 [info] == Running 20210608133040 Mobilizon.Storage.Repo.Migrations.AddMetadataToMedia.change/0 forward1602server # [ 806.462918] mobilizon_ctl[1749]: 22:05:50.184 [info] alter table medias1603server # [ 806.500981] mobilizon_ctl[1749]: 22:05:50.228 [info] == Migrated 20210608133040 in 0.0s1604server # [ 806.605729] mobilizon_ctl[1749]: 22:05:50.335 [info] == Running 20210622133516 Mobilizon.Storage.Repo.Migrations.CleanupPosts.up/0 forward1605server # [ 806.667533] mobilizon_ctl[1749]: 22:05:50.405 [info] == Migrated 20210622133516 in 0.0s1606server # [ 806.763008] mobilizon_ctl[1749]: 22:05:50.506 [info] == Running 20210622133555 Mobilizon.Storage.Repo.Migrations.AddIndexToPosts.up/0 forward1607server # [ 806.785287] mobilizon_ctl[1749]: 22:05:50.523 [info] create index if not exists posts_url_index1608server # [ 806.849918] mobilizon_ctl[1749]: 22:05:50.579 [info] == Migrated 20210622133555 in 0.0s1609server # [ 806.976963] mobilizon_ctl[1749]: 22:05:50.708 [info] == Running 20210730143924 Mobilizon.Storage.Repo.Migrations.PutUniqueIndexOnParticipantsUrls.up/0 forward1610server # [ 806.993604] mobilizon_ctl[1749]: 22:05:50.720 [info] create index if not exists participants_url_index1611server # [ 807.130240] mobilizon_ctl[1749]: 22:05:50.799 [info] == Migrated 20210730143924 in 0.0s1612server # [ 807.209012] mobilizon_ctl[1749]: 22:05:50.936 [info] == Running 20210805142745 Mobilizon.Storage.Repo.Migrations.AddMetadataForEvents.change/0 forward1613server # [ 807.223886] mobilizon_ctl[1749]: 22:05:50.951 [info] alter table events1614server # [ 807.284698] mobilizon_ctl[1749]: 22:05:50.999 [info] == Migrated 20210805142745 in 0.0s1615server # [ 807.388929] mobilizon_ctl[1749]: 22:05:51.124 [info] == Running 20210819161507 Mobilizon.Storage.Repo.Migrations.AddLanguageToEntities.change/0 forward1616server # [ 807.420629] mobilizon_ctl[1749]: 22:05:51.140 [info] alter table events1617server # [ 807.487063] mobilizon_ctl[1749]: 22:05:51.207 [info] alter table comments1618server # [ 807.538264] mobilizon_ctl[1749]: 22:05:51.264 [info] alter table posts1619server # [ 807.628958] mobilizon_ctl[1749]: 22:05:51.352 [info] == Migrated 20210819161507 in 0.2s1620server # [ 807.731051] mobilizon_ctl[1749]: 22:05:51.474 [info] == Running 20211001101902 Mobilizon.Repo.Migrations.CreateExports.change/0 forward1621server # [ 807.783356] mobilizon_ctl[1749]: 22:05:51.504 [info] create table exports1622server # [ 807.991577] mobilizon_ctl[1749]: 22:05:51.711 [info] create index exports_file_path_index1623server # [ 808.053130] mobilizon_ctl[1749]: 22:05:51.772 [info] == Migrated 20211001101902 in 0.2s1624server # [ 808.195367] mobilizon_ctl[1749]: 22:05:51.884 [info] == Running 20211008084901 Mobilizon.Storage.Repo.Migrations.AddTimezoneToAddresses.change/0 forward1625server # [ 808.210156] mobilizon_ctl[1749]: 22:05:51.896 [info] alter table addresses1626server # [ 808.229801] mobilizon_ctl[1749]: 22:05:51.931 [info] == Migrated 20211008084901 in 0.0s1627server # [ 808.334209] mobilizon_ctl[1749]: 22:05:52.067 [info] == Running 20211013090405 Mobilizon.Storage.Repo.Migrations.AddCodeToParticipants.change/0 forward1628server # [ 808.361617] mobilizon_ctl[1749]: 22:05:52.076 [info] alter table participants1629server # [ 808.401413] mobilizon_ctl[1749]: 22:05:52.132 [info] == Migrated 20211013090405 in 0.0s1630server # [ 808.523623] mobilizon_ctl[1749]: 22:05:52.255 [info] == Running 20211022093530 Mobilizon.Storage.Repo.Migrations.AddNotifyToFollowers.change/0 forward1631server # [ 808.554128] mobilizon_ctl[1749]: 22:05:52.267 [info] alter table followers1632server # [ 808.610680] mobilizon_ctl[1749]: 22:05:52.331 [info] == Migrated 20211022093530 in 0.0s1633server # [ 808.722502] mobilizon_ctl[1749]: 22:05:52.459 [info] == Running 20211223141104 Mobilizon.Storage.Repo.Migrations.AddInstanceMaterializedView.up/0 forward1634server # [ 808.840778] mobilizon_ctl[1749]: 22:05:52.559 [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"1635server: Guest root shell did not produce any data yet...1636server: To debug, enter the VM and run 'systemctl status backdoor.service'.1637server # [ 809.890799] mobilizon_ctl[1749]: 22:05:53.580 [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"1638server # [ 809.916929] mobilizon_ctl[1749]: 22:05:53.627 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger ON actors;\n"1639server # [ 809.953474] mobilizon_ctl[1749]: 22:05:53.659 [info] trigger "refresh_instances_trigger" for relation "actors" does not exist, skipping1640server # [ 809.970592] mobilizon_ctl[1749]: 22:05:53.660 [info] execute "CREATE TRIGGER refresh_instances_trigger\nAFTER INSERT OR UPDATE OR DELETE\nON actors\nFOR EACH STATEMENT\nEXECUTE PROCEDURE refresh_instances();\n"1641server # [ 809.984006] mobilizon_ctl[1749]: 22:05:53.687 [info] create index if not exists instances_domain_index1642server # [ 810.124028] mobilizon_ctl[1749]: 22:05:53.839 [info] == Migrated 20211223141104 in 1.3s1643server # [ 810.242298] mobilizon_ctl[1749]: 22:05:53.963 [info] == Running 20220321154138 Mobilizon.Storage.Repo.Migrations.CreateObanPeers.up/0 forward1644server # [ 810.309110] mobilizon_ctl[1749]: 22:05:54.035 [info] == Migrated 20220321154138 in 0.0s1645server # [ 810.397605] mobilizon_ctl[1749]: 22:05:54.134 [info] == Running 20220321154142 Mobilizon.Storage.Repo.Migrations.SwapPrimaryObanIndexes.change/0 forward1646server # [ 810.427303] mobilizon_ctl[1749]: 22:05:54.148 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index1647server # [ 810.466170] mobilizon_ctl[1749]: 22:05:54.202 [info] relation "oban_jobs_state_queue_priority_scheduled_at_id_index" already exists, skipping1648server # [ 810.505039] mobilizon_ctl[1749]: 22:05:54.203 [info] drop index if exists public.oban_jobs_queue_state_priority_scheduled_at_id_index1649server # [ 810.529131] mobilizon_ctl[1749]: 22:05:54.236 [info] index "oban_jobs_queue_state_priority_scheduled_at_id_index" does not exist, skipping1650server # [ 810.546096] mobilizon_ctl[1749]: 22:05:54.246 [info] == Migrated 20220321154142 in 0.1s1651server # [ 810.597193] systemd[1]: mobilizon.service: start-pre operation timed out. Terminating.1652server # [ 810.617420] systemd[1]: mobilizon.service: Control process exited, code=killed, status=15/TERM1653server # [ 810.963922] mobilizon_ctl[1749]: 22:05:54.348 [notice] SIGTERM received - shutting down1654server # [ 810.997693] mobilizon_ctl[1749]: 22:05:54.628 [notice] SIGTERM received - shutting down1655server # [ 811.110003] mobilizon_ctl[1749]: 22:05:54.648 [info] == Running 20220328153640 Mobilizon.Storage.Repo.Migrations.SetAllEventsCategoryToMeeting.up/0 forward1656server # [ 811.429789] mobilizon_ctl[1749]: 22:05:54.709 [info] execute "UPDATE events SET category = 'MEETING'"1657server # [ 811.720684] mobilizon_ctl[1749]: ** (EXIT from #PID<0.94.0>) shutdown1658server # [ 811.862353] postgres[1888]: [1888] LOG: unexpected EOF on client connection with an open transaction1659server # [ 811.943842] postgres[1887]: [1887] LOG: could not receive data from client: Connection reset by peer1660server # [ 811.971199] postgres[1887]: [1887] LOG: unexpected EOF on client connection with an open transaction1661server # [ 812.417291] systemd[1]: mobilizon.service: Failed with result 'timeout'.1662server # [ 812.437718] systemd[1]: Failed to start Mobilizon federated organization and mobilization platform.1663server # [ 812.471887] systemd[1]: mobilizon.service: Consumed 1min 47.888s CPU time over 5min 1.785s wall clock time, 80.7M memory peak.1664server # [ 812.938910] systemd[1]: mobilizon.service: Scheduled restart job, restart counter is at 1.1665server # [ 813.141955] systemd[1]: Starting Mobilizon PostgreSQL setup...1666server # [ 820.606023] mobilizon-postgresql-start[2218]: NOTICE: extension "postgis" already exists, skipping1667server # [ 820.629892] mobilizon-postgresql-start[2218]: NOTICE: extension "pg_trgm" already exists, skipping1668server # [ 820.631990] mobilizon-postgresql-start[2218]: NOTICE: extension "unaccent" already exists, skipping1669server # [ 820.642856] mobilizon-postgresql-start[2218]: CREATE EXTENSION1670server # [ 820.673791] mobilizon-postgresql-start[2218]: CREATE EXTENSION1671server # [ 820.683380] mobilizon-postgresql-start[2218]: CREATE EXTENSION1672server # [ 822.066102] mobilizon-postgresql-start[2223]: ALTER DATABASE1673server # [ 822.143155] systemd[1]: mobilizon-postgresql.service: Deactivated successfully.1674server # [ 822.159220] systemd[1]: Finished Mobilizon PostgreSQL setup.1675server # [ 822.184019] systemd[1]: mobilizon-postgresql.service: Consumed 2.378s CPU time over 8.988s wall clock time, 1.9M memory peak.1676server # [ 822.389428] systemd[1]: Starting Mobilizon setup secrets...1677server # [ 823.311113] mobilizon-setup-secrets-start[2226]: + '[' '!' -f /var/lib/mobilizon/secret-env.sh ']'1678server # [ 823.351453] systemd[1]: mobilizon-setup-secrets.service: Deactivated successfully.1679server # [ 823.362391] systemd[1]: Finished Mobilizon setup secrets.1680server # [ 823.475872] systemd[1]: Starting Mobilizon federated organization and mobilization platform...1681server: Guest root shell did not produce any data yet...1682server: To debug, enter the VM and run 'systemctl status backdoor.service'.1683server # [ 857.555056] postgres[1373]: [1373] LOG: checkpoint complete: wrote 1941 buffers (11.8%); 0 WAL file(s) added, 0 removed, 1 recycled; write=202.244 s, sync=1.917 s, total=204.371 s; sync files=600, longest=0.027 s, average=0.004 s; distance=13867 kB, estimate=13867 kB; lsn=0/25160D8, redo lsn=0/2277C501684server # [ 869.089286] mobilizon_ctl[2235]: Loading app..1685server # [ 869.104556] mobilizon_ctl[2235]: Starting dependencies..1686server: Guest root shell did not produce any data yet...1687server: To debug, enter the VM and run 'systemctl status backdoor.service'.1688server: Guest root shell did not produce any data yet...1689server: To debug, enter the VM and run 'systemctl status backdoor.service'.1690server # [ 900.677505] systemd[1]: Starting Cleanup of Temporary Directories...1691server # [ 906.753749] systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully.1692server # [ 906.771090] systemd[1]: Finished Cleanup of Temporary Directories.1693server # [ 918.599266] mobilizon_ctl[2235]: 22:07:42.102 [info] == Running 20220328153640 Mobilizon.Storage.Repo.Migrations.SetAllEventsCategoryToMeeting.up/0 forward1694server # [ 918.694913] mobilizon_ctl[2235]: 22:07:42.419 [info] execute "UPDATE events SET category = 'MEETING'"1695server # [ 918.835174] mobilizon_ctl[2235]: 22:07:42.564 [info] == Migrated 20220328153640 in 0.2s1696server # [ 921.407539] mobilizon_ctl[2235]: 22:07:45.092 [info] == Running 20220407083712 Mobilizon.Storage.Repo.Migrations.AddIndexesToAddresses.up/0 forward1697server # [ 921.445132] mobilizon_ctl[2235]: 22:07:45.107 [info] create index if not exists idx_addresses_geom_x1698server # [ 921.545003] mobilizon_ctl[2235]: 22:07:45.274 [info] create index if not exists idx_addresses_geom_y1699server # [ 921.617646] mobilizon_ctl[2235]: 22:07:45.336 [info] == Migrated 20220407083712 in 0.2s1700server # [ 921.751064] mobilizon_ctl[2235]: 22:07:45.483 [info] == Running 20220505091224 Mobilizon.Storage.Repo.Migrations.ChangeRefreshInstanceTriggerToOnlyExecuteOnRelays.up/0 forward1701server # [ 921.780786] mobilizon_ctl[2235]: 22:07:45.492 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger ON actors;\n"1702server # [ 921.831766] mobilizon_ctl[2235]: 22:07:45.569 [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"1703server # [ 921.914993] mobilizon_ctl[2235]: 22:07:45.639 [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"1704server # [ 921.953445] mobilizon_ctl[2235]: 22:07:45.675 [info] == Migrated 20220505091224 in 0.1s1705server # [ 922.069634] mobilizon_ctl[2235]: 22:07:45.798 [info] == Running 20220506105150 Mobilizon.Storage.Repo.Migrations.RemoveRefreshInstanceTriggers.up/0 forward1706server # [ 922.094450] mobilizon_ctl[2235]: 22:07:45.811 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger_new ON actors;\n"1707server # [ 922.142528] mobilizon_ctl[2235]: 22:07:45.874 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger_old ON actors;\n"1708server # [ 922.187002] mobilizon_ctl[2235]: 22:07:45.907 [info] == Migrated 20220506105150 in 0.1s1709server # [ 922.281188] mobilizon_ctl[2235]: 22:07:46.016 [info] == Running 20230208101626 Mobilizon.Repo.Migrations.CreateApplications.change/0 forward1710server # [ 922.333468] mobilizon_ctl[2235]: 22:07:46.064 [info] create table applications1711server # [ 922.654383] mobilizon_ctl[2235]: 22:07:46.343 [info] create index applications_owner_id_owner_type_index1712server # [ 922.702616] mobilizon_ctl[2235]: 22:07:46.428 [info] == Migrated 20230208101626 in 0.4s1713server # [ 922.841245] mobilizon_ctl[2235]: 22:07:46.568 [info] == Running 20230215125801 Mobilizon.Repo.Migrations.CreateApplicationTokens.change/0 forward1714server # [ 922.913264] mobilizon_ctl[2235]: 22:07:46.612 [info] create table application_tokens1715server # [ 923.250303] mobilizon_ctl[2235]: 22:07:46.984 [info] create index application_tokens_user_id_application_id_index1716server # [ 923.329024] mobilizon_ctl[2235]: 22:07:47.058 [info] == Migrated 20230215125801 in 0.4s1717server # [ 923.455561] mobilizon_ctl[2235]: 22:07:47.183 [info] == Running 20230217084253 Mobilizon.Repo.Migrations.CreateApplicationDeviceActivation.change/0 forward1718server # [ 923.492880] mobilizon_ctl[2235]: 22:07:47.223 [info] create table application_device_activation1719server # [ 923.762468] mobilizon_ctl[2235]: 22:07:47.459 [info] == Migrated 20230217084253 in 0.2s1720server # [ 923.832859] mobilizon_ctl[2235]: 22:07:47.566 [info] == Running 20230605143534 Mobilizon.Storage.Repo.Migrations.AllowMultipleEventsToBeReported.up/0 forward1721server # [ 923.851036] mobilizon_ctl[2235]: 22:07:47.583 [info] create table reports_events1722server # [ 923.956836] mobilizon_ctl[2235]: 22:07:47.683 [info] == Migrated 20230605143534 in 0.1s1723server # [ 924.063334] mobilizon_ctl[2235]: 22:07:47.787 [info] == Running 20230605153836 Mobilizon.Storage.Repo.Migrations.BackfillReportEventsWithOldEvents.up/0 forward1724server # [ 924.098084] mobilizon_ctl[2235]: 22:07:47.824 [info] == Migrated 20230605153836 in 0.0s1725server # [ 924.189107] mobilizon_ctl[2235]: 22:07:47.927 [info] == Running 20230605154106 Mobilizon.Storage.Repo.Migrations.RemoveObsoleteEventIdOnReports.up/0 forward1726server # [ 924.218129] mobilizon_ctl[2235]: 22:07:47.936 [info] alter table reports1727server # [ 924.271574] mobilizon_ctl[2235]: 22:07:48.005 [info] == Migrated 20230605154106 in 0.0s1728server # [ 924.357278] mobilizon_ctl[2235]: 22:07:48.091 [info] == Running 20230831153358 Mobilizon.Storage.Repo.Migrations.RemoveOnDeleteCascadeForReports.up/0 forward1729server # [ 924.451327] mobilizon_ctl[2235]: 22:07:48.179 [info] execute "DROP MATERIALIZED VIEW IF EXISTS instances;\n"1730server # [ 924.584801] mobilizon_ctl[2235]: 22:07:48.311 [info] drop constraint reports_reported_id_fkey from table reports1731server # [ 924.637332] mobilizon_ctl[2235]: 22:07:48.365 [info] drop constraint reports_reporter_id_fkey from table reports1732server # [ 924.676910] mobilizon_ctl[2235]: 22:07:48.414 [info] drop constraint reports_manager_id_fkey from table reports1733server # [ 924.774045] mobilizon_ctl[2235]: 22:07:48.460 [info] alter table reports1734server # [ 924.965716] mobilizon_ctl[2235]: 22:07:48.691 [info] drop constraint report_notes_moderator_id_fkey from table report_notes1735server # [ 925.012897] mobilizon_ctl[2235]: 22:07:48.738 [info] alter table report_notes1736server # [ 925.117762] mobilizon_ctl[2235]: 22:07:48.846 [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"1737server # [ 925.938408] mobilizon_ctl[2235]: 22:07:49.626 [info] == Migrated 20230831153358 in 1.5s1738server # [ 926.032055] mobilizon_ctl[2235]: 22:07:49.763 [info] == Running 20230901160000 Mobilizon.Storage.Repo.Migrations.AddExternalUrlForEvents.up/0 forward1739server # [ 926.170044] mobilizon_ctl[2235]: 22:07:49.887 [info] alter table events1740server # [ 926.215261] mobilizon_ctl[2235]: 22:07:49.944 [info] execute "ALTER TABLE events ALTER COLUMN join_options TYPE VARCHAR USING join_options::text"1741server # [ 926.693076] mobilizon_ctl[2235]: 22:07:50.428 [info] execute "ALTER TABLE events ALTER COLUMN join_options DROP DEFAULT"1742server # [ 926.773044] mobilizon_ctl[2235]: 22:07:50.478 [info] execute "DROP TYPE public.join_options"1743server # [ 926.790124] mobilizon_ctl[2235]: 22:07:50.509 [info] execute "CREATE TYPE public.join_options AS ENUM ('free', 'restricted', 'invite', 'external')"1744server # [ 926.834545] mobilizon_ctl[2235]: 22:07:50.564 [info] execute "ALTER TABLE events ALTER COLUMN join_options TYPE join_options USING join_options::join_options"1745server # [ 927.173993] mobilizon_ctl[2235]: 22:07:50.849 [info] execute "ALTER TABLE events ALTER COLUMN join_options SET DEFAULT 'free'::join_options"1746server # [ 927.185997] mobilizon_ctl[2235]: 22:07:50.878 [info] == Migrated 20230901160000 in 1.1s1747server # [ 927.377253] mobilizon_ctl[2235]: 22:07:51.103 [info] == Running 20230920071502 Mobilizon.Storage.Repo.Migrations.AddActorConversations.up/0 forward1748server # [ 927.422545] mobilizon_ctl[2235]: 22:07:51.144 [info] create table conversations1749server # [ 927.672795] mobilizon_ctl[2235]: 22:07:51.387 [info] create table conversation_participants1750server # [ 927.856858] mobilizon_ctl[2235]: 22:07:51.574 [info] alter table comments1751server # [ 927.966474] mobilizon_ctl[2235]: 22:07:51.686 [info] == Migrated 20230920071502 in 0.5s1752server # [ 928.057813] mobilizon_ctl[2235]: 22:07:51.787 [info] == Running 20231220092536 Mobilizon.Storage.Repo.Migrations.AddActorInstances.change/0 forward1753server # [ 928.107256] mobilizon_ctl[2235]: 22:07:51.830 [info] create table instance_actors1754server # [ 928.386792] mobilizon_ctl[2235]: 22:07:52.070 [info] create index instance_actors_domain_index1755server # [ 928.398357] mobilizon_ctl[2235]: 22:07:52.119 [info] == Migrated 20231220092536 in 0.3s1756server # [ 928.482753] mobilizon_ctl[2235]: 22:07:52.219 [info] == Running 20240104103036 Mobilizon.Storage.Repo.Migrations.ChangeActorInstanceDescriptionTypeToText.up/0 forward1757server # [ 928.502135] mobilizon_ctl[2235]: 22:07:52.227 [info] alter table instance_actors1758server # [ 928.544768] mobilizon_ctl[2235]: 22:07:52.275 [info] == Migrated 20240104103036 in 0.0s1759server # [ 928.626134] mobilizon_ctl[2235]: 22:07:52.367 [info] == Running 20240222121054 Mobilizon.Storage.Repo.Migrations.CreateAdminSettingsMedias.change/0 forward1760server # [ 928.660839] mobilizon_ctl[2235]: 22:07:52.388 [info] create table admin_settings_medias1761server # [ 928.835601] mobilizon_ctl[2235]: 22:07:52.558 [info] create index admin_settings_medias_group_name_index1762server # [ 928.898157] mobilizon_ctl[2235]: 22:07:52.631 [info] == Migrated 20240222121054 in 0.2s1763server # [ 928.979793] mobilizon_ctl[2235]: 22:07:52.719 [info] == Running 20240725130410 Mobilizon.Storage.Repo.Migrations.AddEventPhysicalAddressIndex.up/0 forward1764server # [ 929.002275] mobilizon_ctl[2235]: 22:07:52.730 [info] create index events_phys_addr_id1765server # [ 929.053318] mobilizon_ctl[2235]: 22:07:52.787 [info] == Migrated 20240725130410 in 0.0s1766server # [ 929.136993] mobilizon_ctl[2235]: 22:07:52.870 [info] == Running 20250521145952 Mobilizon.Storage.Repo.Migrations.AddUuidToMedia.change/0 forward1767server # [ 929.167035] mobilizon_ctl[2235]: 22:07:52.879 [info] alter table medias1768server # [ 929.386627] mobilizon_ctl[2235]: 22:07:53.068 [info] create index medias_uuid_index1769server # [ 929.422364] mobilizon_ctl[2235]: 22:07:53.146 [info] == Migrated 20250521145952 in 0.2s1770server # [ 929.549498] mobilizon_ctl[2235]: 22:07:53.289 [info] == Running 20250911120910 Mobilizon.Storage.Repo.Migrations.AddModerationToUser.change/0 forward1771server # [ 929.578159] mobilizon_ctl[2235]: 22:07:53.300 [info] alter table users1772server # [ 929.638381] mobilizon_ctl[2235]: 22:07:53.360 [info] == Migrated 20250911120910 in 0.0s1773server # [ 929.765346] mobilizon_ctl[2235]: 22:07:53.491 [info] == Running 20250919143627 Mobilizon.Storage.Repo.Migrations.UpdateRoleToUser.change/0 forward1774server # [ 929.793322] mobilizon_ctl[2235]: 22:07:53.498 [info] execute "ALTER TYPE user_role ADD VALUE IF NOT EXISTS 'pending';"1775server # [ 929.811214] mobilizon_ctl[2235]: 22:07:53.523 [info] enum label "pending" already exists, skipping1776server # [ 929.834259] mobilizon_ctl[2235]: 22:07:53.529 [info] == Migrated 20250919143627 in 0.0s1777server: Guest root shell did not produce any data yet...1778server: To debug, enter the VM and run 'systemctl status backdoor.service'.1779server # [ 929.933382] mobilizon_ctl[2235]: 22:07:53.660 [info] == Running 20251027133219 Mobilizon.Storage.Repo.Migrations.CreateInvitations.change/0 forward1780server # [ 929.969004] mobilizon_ctl[2235]: 22:07:53.706 [info] create table invitations1781server # [ 930.215923] mobilizon_ctl[2235]: 22:07:53.942 [info] create index invitations_token_index1782server # [ 930.276937] mobilizon_ctl[2235]: 22:07:54.002 [info] == Migrated 20251027133219 in 0.3s1783server # [ 930.431487] mobilizon_ctl[2235]: 22:07:54.114 [info] == Running 20251111093015 Mobilizon.Storage.Repo.Migrations.AddAllowToGroup.change/0 forward1784server # [ 930.445940] mobilizon_ctl[2235]: 22:07:54.126 [info] alter table actors1785server # [ 930.495116] mobilizon_ctl[2235]: 22:07:54.215 [info] == Migrated 20251111093015 in 0.0s1786server # [ 931.191236] systemd[1]: Started Mobilizon federated organization and mobilization platform.1787server # [ 931.343225] systemd[1]: Reached target Multi-User System.1788server # [ 931.395029] systemd[1]: Startup finished in 7.157s (kernel) + 1min 34.964s (initrd) + 13min 49.266s (userspace) = 15min 31.388s.1789server # [ 952.717769] postgres[1373]: [1373] LOG: checkpoint starting: time1790server: Guest root shell did not produce any data yet...1791server: To debug, enter the VM and run 'systemctl status backdoor.service'.1792server: Guest root shell did not produce any data yet...1793server: To debug, enter the VM and run 'systemctl status backdoor.service'.1794server # [ 992.775690] postgres[1373]: [1373] LOG: checkpoint complete: wrote 373 buffers (2.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=39.042 s, sync=0.640 s, total=40.054 s; sync files=174, longest=0.024 s, average=0.004 s; distance=3134 kB, estimate=12794 kB; lsn=0/2603220, redo lsn=0/25876981795server: Guest root shell did not produce any data yet...1796server: To debug, enter the VM and run 'systemctl status backdoor.service'.1797server: Guest root shell did not produce any data yet...1798server: To debug, enter the VM and run 'systemctl status backdoor.service'.1799server: Guest root shell did not produce any data yet...1800server: To debug, enter the VM and run 'systemctl status backdoor.service'.1801server: Guest root shell did not produce any data yet...1802server: To debug, enter the VM and run 'systemctl status backdoor.service'.1803server: Guest root shell did not produce any data yet...1804server: To debug, enter the VM and run 'systemctl status backdoor.service'.1805server: Guest root shell did not produce any data yet...1806server: To debug, enter the VM and run 'systemctl status backdoor.service'.1807server: Guest root shell did not produce any data yet...1808server: To debug, enter the VM and run 'systemctl status backdoor.service'.1809server # [ 1228.024581] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31810server: Guest root shell did not produce any data yet...1811server: To debug, enter the VM and run 'systemctl status backdoor.service'.1812server # [ 1252.903832] postgres[1373]: [1373] LOG: checkpoint starting: time1813server # [ 1257.518140] postgres[1373]: [1373] LOG: checkpoint complete: wrote 43 buffers (0.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=4.414 s, sync=0.056 s, total=4.606 s; sync files=9, longest=0.023 s, average=0.007 s; distance=507 kB, estimate=11565 kB; lsn=0/2606510, redo lsn=0/26064801814server: Guest root shell did not produce any data yet...1815server: To debug, enter the VM and run 'systemctl status backdoor.service'.1816server: Guest root shell did not produce any data yet...1817server: To debug, enter the VM and run 'systemctl status backdoor.service'.1818server # [ 1313.713548] mobilizon[2451]: 22:14:17.347 [info] No tzdata release files found in custom data dir. Copying release file from tzdata priv dir.1819server: Guest root shell did not produce any data yet...1820server: To debug, enter the VM and run 'systemctl status backdoor.service'.1821server # [ 1321.682997] mobilizon[2451]: 22:14:24.755 [info] Source for database :city not found: "/var/lib/mobilizon/geo/GeoLite2-City.mmdb"1822server # [ 1321.783140] systemd[1]: Starting Virtual Console Setup...1823server # [ 1322.613197] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1824server # [ 1322.658117] systemd[1]: Stopped Virtual Console Setup.1825server # [ 1323.370691] systemd[1]: Starting Virtual Console Setup...1826server # [ 1329.525948] mobilizon[2451]: 22:14:33.174 [info] Loading 122 CA(s) from :otp store1827server # [ 1334.698192] systemd-vconsole-setup[3087]: Configuration of first virtual console was skipped, ignoring remaining ones.1828server # [ 1334.837557] systemd[1]: Finished Virtual Console Setup.1829server # [ 1335.139200] mobilizon[2451]: 22:14:38.816 [info] Running Mobilizon.Web.Endpoint with Bandit 1.10.3 at ::1:41395 (http)1830server # [ 1335.182733] mobilizon[2451]: 22:14:38.859 [info] Access Mobilizon.Web.Endpoint at https://acme.test1831server # [ 1336.259026] mobilizon[2451]: 22:14:39.936 [info] {"message":"notifier only receiving messages from its own node, functionality may be degraded","source":"oban","event":"notifier:switch","connectivity_status":"solitary"}1832server # [ 1336.296995] mobilizon[2451]: 22:14:40.030 [info] {"message":"peer became leader","node":"mobilizon@server","source":"oban","event":"peer:election","leader":true,"was_leader":false}1833server # [ 1348.479312] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1834server: Guest root shell did not produce any data yet...1835server: To debug, enter the VM and run 'systemctl status backdoor.service'.1836server # [ 1356.480024] mobilizon[2451]: 22:15:00.171 [info] {"source":"oban","duration":47627,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1837server # [ 1365.911218] mobilizon[2451]: 22:15:09.627 [info] {"source":"oban","duration":74275,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1838server # [ 1368.121490] 8021q: 802.1Q VLAN Support v1.81839server # [ 1370.200738] cfg80211: Loading compiled-in X.509 certificates for regulatory database1840server # [ 1370.702453] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1841server # [ 1370.710774] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1842server # [ 1370.764870] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -21843server # [ 1370.768798] cfg80211: failed to load regulatory.db1844server # [ 1374.721959] (udev-worker)[3215]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.1845server # [ 1374.731528] (udev-worker)[3215]: Network interface NamePolicy= disabled on kernel command line.1846server # [ 1374.751549] (udev-worker)[3217]: Network interface NamePolicy= disabled on kernel command line.1847server # [ 1375.897679] systemd[1]: Condition check resulted in Virtio network device being skipped.1848server # [ 1376.142307] systemd[1]: Starting Address configuration of eth1...1849server # [ 1378.865927] 8021q: adding VLAN 0 to HW filter on device eth01850server # [ 1378.824493] dhcpcd[1186]: eth0: waiting for carrier1851server # [ 1378.875354] dhcpcd[1186]: eth0: carrier acquired1852server # [ 1379.064570] 8021q: adding VLAN 0 to HW filter on device eth11853server # [ 1379.429666] dhcpcd[1186]: DUID 00:01:00:01:32:27:67:fb:52:54:00:12:34:561854server # [ 1379.472873] dhcpcd[1186]: eth0: IAID 00:12:34:561855server # [ 1379.475925] dhcpcd[1186]: eth0: adding address fe80::5054:ff:fe12:34561856server # [ 1379.587419] network-addresses-eth1-start[3228]: adding address 192.168.1.1/24... done1857server # [ 1379.869050] network-addresses-eth1-start[3228]: adding address 2001:db8:1::1/64... done1858server: Guest root shell did not produce any data yet...1859server: To debug, enter the VM and run 'systemctl status backdoor.service'.1860server # [ 1380.388535] dhcpcd[1186]: eth0: soliciting a DHCP lease1861server # [ 1380.455938] systemd[1]: Finished Address configuration of eth1.1862server # [ 1380.495112] dhcpcd[1186]: eth0: offered 10.0.2.15 from 10.0.2.21863server # [ 1380.519129] dhcpcd[1186]: eth0: probing address 10.0.2.15/241864server # [ 1382.072032] dhcpcd[1186]: eth0: soliciting an IPv6 router1865server # [ 1382.088771] dhcpcd[1186]: eth0: Router Advertisement from fe80::21866server # [ 1382.112644] dhcpcd[1186]: eth0: adding address fec0::5054:ff:fe12:3456/641867server # [ 1382.166727] dhcpcd[1186]: eth0: adding route to fec0::/641868server # [ 1382.183996] dhcpcd[1186]: eth0: adding default route via fe80::21869server # [ 1385.691507] dhcpcd[1186]: eth0: leased 10.0.2.15 for 86400 seconds1870server # [ 1385.717013] dhcpcd[1186]: eth0: adding route to 10.0.2.0/241871server # [ 1385.727449] dhcpcd[1186]: eth0: adding default route via 10.0.2.21872server # [ 1395.979507] mobilizon[2451]: 22:15:39.698 [info] {"source":"oban","duration":66252,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1873server # [ 1402.994589] systemd-logind[1059]: Watching system buttons on /dev/input/event1 (gpio-keys)1874server: Guest root shell did not produce any data yet...1875server: To debug, enter the VM and run 'systemctl status backdoor.service'.1876server # [ 1412.509573] systemd-logind[1059]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)1877server # [ 1416.510830] mobilizon[2451]: 22:16:00.222 [info] {"source":"oban","duration":29338,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1878server # [ 1426.046751] mobilizon[2451]: 22:16:09.766 [info] {"source":"oban","duration":66534,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1879server: Guest root shell did not produce any data yet...1880server: To debug, enter the VM and run 'systemctl status backdoor.service'.1881server # [ 1456.137988] mobilizon[2451]: 22:16:39.831 [info] {"source":"oban","duration":59745,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1882server: Guest root shell did not produce any data yet...1883server: To debug, enter the VM and run 'systemctl status backdoor.service'.1884server # [ 1476.543452] mobilizon[2451]: 22:17:00.251 [info] {"source":"oban","duration":23170,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1885server # [ 1486.183508] mobilizon[2451]: 22:17:09.896 [info] {"source":"oban","duration":57979,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1886server: Guest root shell did not produce any data yet...1887server: To debug, enter the VM and run 'systemctl status backdoor.service'.1888server # [ 1516.283015] mobilizon[2451]: 22:17:39.991 [info] {"source":"oban","duration":87879,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1889server: Guest root shell did not produce any data yet...1890server: To debug, enter the VM and run 'systemctl status backdoor.service'.1891server # [ 1536.574341] mobilizon[2451]: 22:18:00.279 [info] {"source":"oban","duration":23111,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1892server # [ 1546.350433] mobilizon[2451]: 22:18:10.062 [info] {"source":"oban","duration":67284,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1893server # [ 1552.615841] postgres[1373]: [1373] LOG: checkpoint starting: time1894server # [ 1554.866610] postgres[1373]: [1373] LOG: checkpoint complete: wrote 21 buffers (0.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.100 s, sync=0.056 s, total=2.264 s; sync files=17, longest=0.019 s, average=0.004 s; distance=9 kB, estimate=10410 kB; lsn=0/2608CC0, redo lsn=0/2608C401895server: Guest root shell did not produce any data yet...1896server: To debug, enter the VM and run 'systemctl status backdoor.service'.1897server # [ 1568.530256] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1898server # [ 1568.553906] systemd[1]: Stopped Virtual Console Setup.1899server # [ 1568.570363] systemd[1]: systemd-vconsole-setup.service: Consumed 1.876s CPU time over 4min 5.200s wall clock time, 2.5M memory peak.1900server # [ 1568.583112] systemd[1]: Stopping Virtual Console Setup...1901server # [ 1568.725168] systemd[1]: Starting Virtual Console Setup...1902server # [ 1569.567909] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1903server # [ 1569.597056] systemd[1]: Stopped Virtual Console Setup.1904server # [ 1569.993115] systemd[1]: Starting Virtual Console Setup...1905server # [ 1575.382948] systemd-vconsole-setup[3921]: Configuration of first virtual console was skipped, ignoring remaining ones.1906server # [ 1575.527470] systemd[1]: Finished Virtual Console Setup.1907server # [ 1576.421385] mobilizon[2451]: 22:18:40.124 [info] {"source":"oban","duration":59513,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1908server # [ 1583.897977] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1909server # [ 1584.163174] systemd-logind[1059]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1910server: Guest root shell did not produce any data yet...1911server: To debug, enter the VM and run 'systemctl status backdoor.service'.1912server # [ 1596.566912] mobilizon[2451]: 22:19:00.299 [info] {"source":"oban","duration":15473,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1913server # [ 1606.423474] mobilizon[2451]: 22:19:10.154 [info] {"source":"oban","duration":21418,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1914server: Guest root shell did not produce any data yet...1915server: To debug, enter the VM and run 'systemctl status backdoor.service'.1916server # [ 1636.448965] mobilizon[2451]: 22:19:40.182 [info] {"source":"oban","duration":24739,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1917server: Guest root shell did not produce any data yet...1918server: To debug, enter the VM and run 'systemctl status backdoor.service'.1919server # [ 1656.582761] mobilizon[2451]: 22:20:00.313 [info] {"source":"oban","duration":9127,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1920server # [ 1666.487861] mobilizon[2451]: 22:20:10.220 [info] {"source":"oban","duration":34478,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1921server: Guest root shell did not produce any data yet...1922server: To debug, enter the VM and run 'systemctl status backdoor.service'.1923server # [ 1696.525448] mobilizon[2451]: 22:20:40.258 [info] {"source":"oban","duration":29558,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1924server: Guest root shell did not produce any data yet...1925server: To debug, enter the VM and run 'systemctl status backdoor.service'.1926server # [ 1716.594410] mobilizon[2451]: 22:21:00.330 [info] {"source":"oban","duration":11909,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1927server # [ 1726.575312] mobilizon[2451]: 22:21:10.308 [info] {"source":"oban","duration":45407,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1928server: Guest root shell did not produce any data yet...1929server: To debug, enter the VM and run 'systemctl status backdoor.service'.1930server # [ 1756.609516] mobilizon[2451]: 22:21:40.341 [info] {"source":"oban","duration":28144,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1931server: Guest root shell did not produce any data yet...1932server: To debug, enter the VM and run 'systemctl status backdoor.service'.1933server # [ 1776.606108] mobilizon[2451]: 22:22:00.342 [info] {"source":"oban","duration":8198,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1934server # [ 1786.634579] mobilizon[2451]: 22:22:10.368 [info] {"source":"oban","duration":24236,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1935server: Guest root shell did not produce any data yet...1936server: To debug, enter the VM and run 'systemctl status backdoor.service'.1937server # [ 1816.662599] mobilizon[2451]: 22:22:40.397 [info] {"source":"oban","duration":24667,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1938server: Guest root shell did not produce any data yet...1939server: To debug, enter the VM and run 'systemctl status backdoor.service'.1940server # [ 1836.643228] mobilizon[2451]: 22:23:00.355 [info] {"source":"oban","duration":8605,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1941server # [ 1846.700716] mobilizon[2451]: 22:23:10.426 [info] {"source":"oban","duration":24145,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1942server # [ 1852.970730] postgres[1373]: [1373] LOG: checkpoint starting: time1943server # [ 1853.089115] postgres[1373]: [1373] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.022 s, sync=0.008 s, total=0.134 s; sync files=1, longest=0.008 s, average=0.008 s; distance=4 kB, estimate=9369 kB; lsn=0/2609D30, redo lsn=0/2609CD81944server: Guest root shell did not produce any data yet...1945server: To debug, enter the VM and run 'systemctl status backdoor.service'.1946server # [ 1876.726976] mobilizon[2451]: 22:23:40.461 [info] {"source":"oban","duration":30446,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1947server: Guest root shell did not produce any data yet...1948server: To debug, enter the VM and run 'systemctl status backdoor.service'.1949server # [ 1896.636621] mobilizon[2451]: 22:24:00.370 [info] {"source":"oban","duration":8724,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1950server # [ 1906.757237] mobilizon[2451]: 22:24:10.492 [info] {"source":"oban","duration":28309,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1951server: Guest root shell did not produce any data yet...1952server: To debug, enter the VM and run 'systemctl status backdoor.service'.1953server # [ 1936.785719] mobilizon[2451]: 22:24:40.519 [info] {"source":"oban","duration":23262,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1954server: Guest root shell did not produce any data yet...1955server: To debug, enter the VM and run 'systemctl status backdoor.service'.1956server # [ 1956.652019] mobilizon[2451]: 22:25:00.383 [info] {"source":"oban","duration":9199,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1957server # [ 1966.814196] mobilizon[2451]: 22:25:10.549 [info] {"source":"oban","duration":25658,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1958server: Guest root shell did not produce any data yet...1959server: To debug, enter the VM and run 'systemctl status backdoor.service'.1960server # [ 1996.849876] mobilizon[2451]: 22:25:40.586 [info] {"source":"oban","duration":32156,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1961server: Guest root shell did not produce any data yet...1962server: To debug, enter the VM and run 'systemctl status backdoor.service'.1963server # [ 2016.668924] mobilizon[2451]: 22:26:00.399 [info] {"source":"oban","duration":10803,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1964server # [ 2026.890446] mobilizon[2451]: 22:26:10.621 [info] {"source":"oban","duration":28971,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1965server: Guest root shell did not produce any data yet...1966server: To debug, enter the VM and run 'systemctl status backdoor.service'.1967server # [ 2056.936613] mobilizon[2451]: 22:26:40.666 [info] {"source":"oban","duration":43167,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1968server: Guest root shell did not produce any data yet...1969server: To debug, enter the VM and run 'systemctl status backdoor.service'.1970server # [ 2076.677910] mobilizon[2451]: 22:27:00.412 [info] {"source":"oban","duration":9822,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1971server # [ 2086.979292] mobilizon[2451]: 22:27:10.697 [info] {"source":"oban","duration":26397,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1972server: Guest root shell did not produce any data yet...1973server: To debug, enter the VM and run 'systemctl status backdoor.service'.1974server # [ 2116.989645] mobilizon[2451]: 22:27:40.722 [info] {"source":"oban","duration":23134,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1975server: Guest root shell did not produce any data yet...1976server: To debug, enter the VM and run 'systemctl status backdoor.service'.1977server # [ 2136.723652] mobilizon[2451]: 22:28:00.423 [info] {"source":"oban","duration":7830,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1978server # [ 2147.014794] mobilizon[2451]: 22:28:10.750 [info] {"source":"oban","duration":24126,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1979server # [ 2153.166805] postgres[1373]: [1373] LOG: checkpoint starting: time1980server # [ 2153.712975] postgres[1373]: [1373] LOG: checkpoint complete: wrote 5 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.434 s, sync=0.042 s, total=0.571 s; sync files=7, longest=0.016 s, average=0.006 s; distance=18 kB, estimate=8434 kB; lsn=0/260E748, redo lsn=0/260E6F01981server: Guest root shell did not produce any data yet...1982server: To debug, enter the VM and run 'systemctl status backdoor.service'.1983server # [ 2177.054419] mobilizon[2451]: 22:28:40.788 [info] {"source":"oban","duration":34213,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1984server: Guest root shell did not produce any data yet...1985server: To debug, enter the VM and run 'systemctl status backdoor.service'.1986server # [ 2196.707028] mobilizon[2451]: 22:29:00.439 [info] {"source":"oban","duration":12021,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1987server # [ 2207.086804] mobilizon[2451]: 22:29:10.816 [info] {"source":"oban","duration":24047,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1988server: Guest root shell did not produce any data yet...1989server: To debug, enter the VM and run 'systemctl status backdoor.service'.1990server # [ 2237.105900] mobilizon[2451]: 22:29:40.842 [info] {"source":"oban","duration":22017,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1991server: Guest root shell did not produce any data yet...1992server: To debug, enter the VM and run 'systemctl status backdoor.service'.1993server # [ 2256.725258] mobilizon[2451]: 22:30:00.458 [info] {"source":"oban","duration":13469,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}1994server # [ 2267.133362] mobilizon[2451]: 22:30:10.869 [info] {"source":"oban","duration":23435,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1995server: Guest root shell did not produce any data yet...1996server: To debug, enter the VM and run 'systemctl status backdoor.service'.1997server # [ 2297.167293] mobilizon[2451]: 22:30:40.898 [info] {"source":"oban","duration":25846,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}1998server: Guest root shell did not produce any data yet...1999server: To debug, enter the VM and run 'systemctl status backdoor.service'.2000server # [ 2316.741096] mobilizon[2451]: 22:31:00.474 [info] {"source":"oban","duration":11170,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2001server # [ 2327.203378] mobilizon[2451]: 22:31:10.926 [info] {"source":"oban","duration":24526,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2002server: Guest root shell did not produce any data yet...2003server: To debug, enter the VM and run 'systemctl status backdoor.service'.2004server # [ 2357.221883] mobilizon[2451]: 22:31:40.955 [info] {"source":"oban","duration":25749,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2005server: Guest root shell did not produce any data yet...2006server: To debug, enter the VM and run 'systemctl status backdoor.service'.2007server # [ 2376.771278] mobilizon[2451]: 22:32:00.500 [info] {"source":"oban","duration":21419,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2008server # [ 2387.246083] mobilizon[2451]: 22:32:10.978 [info] {"source":"oban","duration":18633,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2009server: Guest root shell did not produce any data yet...2010server: To debug, enter the VM and run 'systemctl status backdoor.service'.2011server # [ 2417.270883] mobilizon[2451]: 22:32:41.003 [info] {"source":"oban","duration":22364,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2012server: Guest root shell did not produce any data yet...2013server: To debug, enter the VM and run 'systemctl status backdoor.service'.2014server # [ 2436.782449] mobilizon[2451]: 22:33:00.518 [info] {"source":"oban","duration":13278,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2015server # [ 2447.306920] mobilizon[2451]: 22:33:11.042 [info] {"source":"oban","duration":32134,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2016server # [ 2452.822198] postgres[1373]: [1373] LOG: checkpoint starting: time2017server # [ 2452.913723] postgres[1373]: [1373] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.020 s, sync=0.009 s, total=0.115 s; sync files=1, longest=0.009 s, average=0.009 s; distance=4 kB, estimate=7591 kB; lsn=0/260F7E0, redo lsn=0/260F7882018server: Guest root shell did not produce any data yet...2019server: To debug, enter the VM and run 'systemctl status backdoor.service'.2020server # [ 2477.345438] mobilizon[2451]: 22:33:41.075 [info] {"source":"oban","duration":29160,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2021server: Guest root shell did not produce any data yet...2022server: To debug, enter the VM and run 'systemctl status backdoor.service'.2023server # [ 2496.798310] mobilizon[2451]: 22:34:00.533 [info] {"source":"oban","duration":9710,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2024server # [ 2507.369571] mobilizon[2451]: 22:34:11.105 [info] {"source":"oban","duration":26435,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2025server: Guest root shell did not produce any data yet...2026server: To debug, enter the VM and run 'systemctl status backdoor.service'.2027server # [ 2537.397208] mobilizon[2451]: 22:34:41.133 [info] {"source":"oban","duration":23508,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2028server: Guest root shell did not produce any data yet...2029server: To debug, enter the VM and run 'systemctl status backdoor.service'.2030server # [ 2556.834083] mobilizon[2451]: 22:35:00.549 [info] {"source":"oban","duration":10291,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2031server # [ 2567.424574] mobilizon[2451]: 22:35:11.157 [info] {"source":"oban","duration":18792,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2032server: Guest root shell did not produce any data yet...2033server: To debug, enter the VM and run 'systemctl status backdoor.service'.2034server # [ 2597.446648] mobilizon[2451]: 22:35:41.182 [info] {"source":"oban","duration":21206,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2035server: Guest root shell did not produce any data yet...2036server: To debug, enter the VM and run 'systemctl status backdoor.service'.2037server # [ 2616.829580] mobilizon[2451]: 22:36:00.565 [info] {"source":"oban","duration":11087,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2038server # [ 2627.467083] mobilizon[2451]: 22:36:11.203 [info] {"source":"oban","duration":17642,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2039server: Guest root shell did not produce any data yet...2040server: To debug, enter the VM and run 'systemctl status backdoor.service'.2041server # [ 2657.498582] mobilizon[2451]: 22:36:41.230 [info] {"source":"oban","duration":24014,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2042server: Guest root shell did not produce any data yet...2043server: To debug, enter the VM and run 'systemctl status backdoor.service'.2044server # [ 2676.845979] mobilizon[2451]: 22:37:00.581 [info] {"source":"oban","duration":9891,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2045server # [ 2687.523724] mobilizon[2451]: 22:37:11.259 [info] {"source":"oban","duration":26762,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2046server: Guest root shell did not produce any data yet...2047server: To debug, enter the VM and run 'systemctl status backdoor.service'.2048server # [ 2717.553629] mobilizon[2451]: 22:37:41.289 [info] {"source":"oban","duration":25519,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2049server: Guest root shell did not produce any data yet...2050server: To debug, enter the VM and run 'systemctl status backdoor.service'.2051server # [ 2736.878983] mobilizon[2451]: 22:38:00.595 [info] {"source":"oban","duration":7964,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2052server # [ 2747.581345] mobilizon[2451]: 22:38:11.312 [info] {"source":"oban","duration":19883,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2053server # [ 2752.985071] postgres[1373]: [1373] LOG: checkpoint starting: time2054server # [ 2753.069505] postgres[1373]: [1373] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.018 s, sync=0.007 s, total=0.107 s; sync files=1, longest=0.007 s, average=0.007 s; distance=4 kB, estimate=6832 kB; lsn=0/26108B8, redo lsn=0/26108602055server: Guest root shell did not produce any data yet...2056server: To debug, enter the VM and run 'systemctl status backdoor.service'.2057server # [ 2777.603039] mobilizon[2451]: 22:38:41.338 [info] {"source":"oban","duration":23094,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2058server: Guest root shell did not produce any data yet...2059server: To debug, enter the VM and run 'systemctl status backdoor.service'.2060server # [ 2796.877057] mobilizon[2451]: 22:39:00.610 [info] {"source":"oban","duration":10807,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2061server # [ 2807.633808] mobilizon[2451]: 22:39:11.368 [info] {"source":"oban","duration":25947,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2062server: Guest root shell did not produce any data yet...2063server: To debug, enter the VM and run 'systemctl status backdoor.service'.2064server # [ 2837.660942] mobilizon[2451]: 22:39:41.393 [info] {"source":"oban","duration":20811,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2065server: Guest root shell did not produce any data yet...2066server: To debug, enter the VM and run 'systemctl status backdoor.service'.2067server # [ 2856.890797] mobilizon[2451]: 22:40:00.625 [info] {"source":"oban","duration":10890,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2068server # [ 2867.683085] mobilizon[2451]: 22:40:11.418 [info] {"source":"oban","duration":22443,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2069server: Guest root shell did not produce any data yet...2070server: To debug, enter the VM and run 'systemctl status backdoor.service'.2071server # [ 2897.710467] mobilizon[2451]: 22:40:41.445 [info] {"source":"oban","duration":22764,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2072server: Guest root shell did not produce any data yet...2073server: To debug, enter the VM and run 'systemctl status backdoor.service'.2074server # [ 2916.930361] mobilizon[2451]: 22:41:00.650 [info] {"source":"oban","duration":16237,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2075server # [ 2927.741080] mobilizon[2451]: 22:41:11.473 [info] {"source":"oban","duration":24745,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2076server: Guest root shell did not produce any data yet...2077server: To debug, enter the VM and run 'systemctl status backdoor.service'.2078server # [ 2957.761406] mobilizon[2451]: 22:41:41.493 [info] {"source":"oban","duration":17338,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2079server: Guest root shell did not produce any data yet...2080server: To debug, enter the VM and run 'systemctl status backdoor.service'.2081server # [ 2976.935806] mobilizon[2451]: 22:42:00.667 [info] {"source":"oban","duration":11835,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2082server # [ 2987.777625] mobilizon[2451]: 22:42:11.514 [info] {"source":"oban","duration":15873,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2083server: Guest root shell did not produce any data yet...2084server: To debug, enter the VM and run 'systemctl status backdoor.service'.2085server # [ 3017.812040] mobilizon[2451]: 22:42:41.544 [info] {"source":"oban","duration":26428,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2086server: Guest root shell did not produce any data yet...2087server: To debug, enter the VM and run 'systemctl status backdoor.service'.2088server # [ 3036.954655] mobilizon[2451]: 22:43:00.681 [info] {"source":"oban","duration":9230,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2089server # [ 3047.838892] mobilizon[2451]: 22:43:11.570 [info] {"source":"oban","duration":22317,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2090server # [ 3053.182745] postgres[1373]: [1373] LOG: checkpoint starting: time2091server # [ 3053.503659] postgres[1373]: [1373] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.225 s, sync=0.025 s, total=0.354 s; sync files=3, longest=0.013 s, average=0.009 s; distance=17 kB, estimate=6151 kB; lsn=0/2614D88, redo lsn=0/2614D302092server: Guest root shell did not produce any data yet...2093server: To debug, enter the VM and run 'systemctl status backdoor.service'.2094server # [ 3077.858563] mobilizon[2451]: 22:43:41.593 [info] {"source":"oban","duration":19756,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2095server: Guest root shell did not produce any data yet...2096server: To debug, enter the VM and run 'systemctl status backdoor.service'.2097server # [ 3096.969246] mobilizon[2451]: 22:44:00.695 [info] {"source":"oban","duration":10215,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2098server # [ 3107.879761] mobilizon[2451]: 22:44:11.612 [info] {"source":"oban","duration":13804,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2099server: Guest root shell did not produce any data yet...2100server: To debug, enter the VM and run 'systemctl status backdoor.service'.2101server # [ 3137.902239] mobilizon[2451]: 22:44:41.638 [info] {"source":"oban","duration":22095,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2102server: Guest root shell did not produce any data yet...2103server: To debug, enter the VM and run 'systemctl status backdoor.service'.2104server # [ 3156.982732] mobilizon[2451]: 22:45:00.711 [info] {"source":"oban","duration":10404,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2105server # [ 3167.921808] mobilizon[2451]: 22:45:11.658 [info] {"source":"oban","duration":16696,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2106server: Guest root shell did not produce any data yet...2107server: To debug, enter the VM and run 'systemctl status backdoor.service'.2108server # [ 3197.964963] mobilizon[2451]: 22:45:41.691 [info] {"source":"oban","duration":29069,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2109server: Guest root shell did not produce any data yet...2110server: To debug, enter the VM and run 'systemctl status backdoor.service'.2111server # [ 3216.990513] mobilizon[2451]: 22:46:00.725 [info] {"source":"oban","duration":9842,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2112server # [ 3227.981963] mobilizon[2451]: 22:46:11.713 [info] {"source":"oban","duration":19132,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2113server: Guest root shell did not produce any data yet...2114server: To debug, enter the VM and run 'systemctl status backdoor.service'.2115server # [ 3258.001510] mobilizon[2451]: 22:46:41.737 [info] {"source":"oban","duration":20968,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2116server: Guest root shell did not produce any data yet...2117server: To debug, enter the VM and run 'systemctl status backdoor.service'.2118server # [ 3277.006456] mobilizon[2451]: 22:47:00.742 [info] {"source":"oban","duration":12040,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2119server # [ 3288.041439] mobilizon[2451]: 22:47:11.773 [info] {"source":"oban","duration":31258,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2120server: Guest root shell did not produce any data yet...2121server: To debug, enter the VM and run 'systemctl status backdoor.service'.2122server # [ 3318.056720] mobilizon[2451]: 22:47:41.793 [info] {"source":"oban","duration":14406,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2123server: Guest root shell did not produce any data yet...2124server: To debug, enter the VM and run 'systemctl status backdoor.service'.2125server # [ 3337.042746] mobilizon[2451]: 22:48:00.759 [info] {"source":"oban","duration":13377,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2126server # [ 3348.099761] mobilizon[2451]: 22:48:11.822 [info] {"source":"oban","duration":26305,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2127server # [ 3352.537777] postgres[1373]: [1373] LOG: checkpoint starting: time2128server # [ 3352.643558] postgres[1373]: [1373] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.021 s, sync=0.011 s, total=0.130 s; sync files=1, longest=0.011 s, average=0.011 s; distance=4 kB, estimate=5536 kB; lsn=0/2615E20, redo lsn=0/2615DC82129server: Guest root shell did not produce any data yet...2130server: To debug, enter the VM and run 'systemctl status backdoor.service'.2131server # [ 3378.110852] mobilizon[2451]: 22:48:41.842 [info] {"source":"oban","duration":16315,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2132server: Guest root shell did not produce any data yet...2133server: To debug, enter the VM and run 'systemctl status backdoor.service'.2134server # [ 3397.042169] mobilizon[2451]: 22:49:00.778 [info] {"source":"oban","duration":13476,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2135server # [ 3408.149200] mobilizon[2451]: 22:49:11.883 [info] {"source":"oban","duration":37029,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2136server: Guest root shell did not produce any data yet...2137server: To debug, enter the VM and run 'systemctl status backdoor.service'.2138server # [ 3438.182625] mobilizon[2451]: 22:49:41.901 [info] {"source":"oban","duration":14966,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2139server: Guest root shell did not produce any data yet...2140server: To debug, enter the VM and run 'systemctl status backdoor.service'.2141server # [ 3457.057133] mobilizon[2451]: 22:50:00.793 [info] {"source":"oban","duration":9365,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2142server # [ 3468.188383] mobilizon[2451]: 22:50:11.921 [info] {"source":"oban","duration":15874,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2143server: Guest root shell did not produce any data yet...2144server: To debug, enter the VM and run 'systemctl status backdoor.service'.2145server # [ 3498.205307] mobilizon[2451]: 22:50:41.941 [info] {"source":"oban","duration":15448,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2146server: Guest root shell did not produce any data yet...2147server: To debug, enter the VM and run 'systemctl status backdoor.service'.2148server # [ 3517.073148] mobilizon[2451]: 22:51:00.809 [info] {"source":"oban","duration":10632,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2149server # [ 3528.222512] mobilizon[2451]: 22:51:11.959 [info] {"source":"oban","duration":14607,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2150server: Guest root shell did not produce any data yet...2151server: To debug, enter the VM and run 'systemctl status backdoor.service'.2152server # [ 3558.254940] mobilizon[2451]: 22:51:41.986 [info] {"source":"oban","duration":23400,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2153server: Guest root shell did not produce any data yet...2154server: To debug, enter the VM and run 'systemctl status backdoor.service'.2155server # [ 3577.094998] mobilizon[2451]: 22:52:00.830 [info] {"source":"oban","duration":14373,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}2156server # [ 3588.273252] mobilizon[2451]: 22:52:12.009 [info] {"source":"oban","duration":19861,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}2157timeout reached; test terminating...2158kill QemuMachine (pid 12)2159server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/lqn6mbgzzdrqq2qkwddcmxj9z6amdd86-python3-3.13.13/bin/python3.13)2160cleanup2161kill QemuMachine (pid 12)2162Terminated /nix/store/ai81aac95a6vphah91kz7nj49ackc2gv-nixos-test-driver-mobilizon/bin/nixos-test-driver -o $out