Machine state will be reset. To keep it, pass --keep-machine-state
start all VLans
(finished: start all VLans, in 0.00 seconds)
Test will time out and terminate in 3600 seconds
run the VM test script
additionally exposed symbols:
    server,
    vlan1,
    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_ssh
server: waiting for unit mobilizon.service
server: waiting for the VM to finish booting
server: starting vm
server # Disk image does not exist, creating the virtualisation disk image...
server: QEMU running (pid 12)
server # Formatting '/build/vm-state-server/tmp.Fq4zwAjkXQ', fmt=raw size=1073741824
server # mke2fs 1.47.3 (8-Jul-2025)
server # Discarding device blocks:      0/262144             done
server # Creating filesystem with 262144 4k blocks and 65536 inodes
server # Filesystem UUID: 0a4227d5-a7b8-4c9d-8b71-221b89298b19
server # Superblock backups stored on blocks:
server # 	32768, 98304, 163840, 229376
server # 
server # Allocating group tables: 0/8   done
server # Writing inode tables: 0/8   done
server # Creating journal (8192 blocks): done
server # Writing superblocks and filesystem accounting information: 0/8   done
server # 
server # Virtualisation disk image created.
server # qemu-system-aarch64: Could not access KVM kernel module: No such file or directory
server # qemu-system-aarch64: failed to initialize kvm: No such file or directory
server # qemu-system-aarch64: falling back to tcg
server # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x000f0510]
server # [    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 2026
server # [    0.000000] KASLR enabled
server # [    0.000000] random: crng init done
server # [    0.000000] Machine model: linux,dummy-virt
server # [    0.000000] efi: UEFI not found.
server # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT
server # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] NODE_DATA(0) allocated [mem 0x7fc06740-0x7fc09ebf]
server # [    0.000000] Zone ranges:
server # [    0.000000]   DMA      [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000]   DMA32    empty
server # [    0.000000]   Normal   empty
server # [    0.000000]   Device   empty
server # [    0.000000] Movable zone start for each node
server # [    0.000000] Early memory node ranges
server # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]
server # [    0.000000] cma: Reserved 32 MiB at 0x000000007ca00000
server # [    0.000000] psci: probing for conduit method from DT.
server # [    0.000000] psci: PSCIv1.1 detected in firmware.
server # [    0.000000] psci: Using standard PSCI v0.2 function IDs
server # [    0.000000] psci: Trusted OS migration not required
server # [    0.000000] psci: SMC Calling Convention v1.0
server # [    0.000000] percpu: Embedded 76 pages/cpu s185688 r8192 d117416 u311296
server # [    0.000000] Detected PIPT I-cache on CPU0
server # [    0.000000] CPU features: detected: Address authentication (IMP DEF algorithm)
server # [    0.000000] CPU features: detected: GICv3 CPU interface
server # [    0.000000] CPU features: detected: HCRX_EL2 register
server # [    0.000000] CPU features: detected: Stage-1 Permission Indirection Extension (S1PIE)
server # [    0.000000] CPU features: detected: 52-bit Virtual Addressing (LPA2)
server # [    0.000000] CPU features: detected: Spectre-v4
server # [    0.000000] alternatives: applying boot alternatives
server # [    0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/h8mjayp97swrv476s970jm93miz3jdsm-nixos-system-server-test/init regInfo=/nix/store/6vgby84q736fzm3cydn99fvna4x2yb0p-closure-info/registration console=ttyAMA0,115200n8 console=tty0
server # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/6vgby84q736fzm3cydn99fvna4x2yb0p-closure-info/registration", will be passed to user space.
server # [    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes
server # [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
server # [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
server # [    0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB
server # [    0.000000] software IO TLB: area num 1.
server # [    0.000000] software IO TLB: mapped [mem 0x000000007c732000-0x000000007c832000] (1MB)
server # [    0.000000] Fallback order for Node 0: 0
server # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 262144
server # [    0.000000] Policy zone: DMA
server # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
server # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
server # [    0.000000] allocated 2097152 bytes of page_ext
server # [    0.000000] ftrace: allocating 74729 entries in 292 pages
server # [    0.000000] ftrace: allocated 292 pages with 3 groups
server # [    0.000000] rcu: Hierarchical RCU implementation.
server # [    0.000000] rcu: 	RCU event tracing is enabled.
server # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.
server # [    0.000000] 	Rude variant of Tasks RCU enabled.
server # [    0.000000] 	Tracing variant of Tasks RCU enabled.
server # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
server # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
server # [    0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.
server # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
server # [    0.000000] GICv3: 256 SPIs implemented
server # [    0.000000] GICv3: 0 Extended SPIs implemented
server # [    0.000000] Root IRQ handler: gic_handle_irq
server # [    0.000000] GICv3: GICv3 features: 16 PPIs
server # [    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0
server # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000
server # [    0.000000] ITS [mem 0x08080000-0x0809ffff]
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)
server # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)
server # [    0.000000] GICv3: using LPI property table @0x0000000044d00000
server # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000
server # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
server # [    0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).
server # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
server # [    0.000093] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns
server # [    0.006576] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
server # [    0.037105] Console: colour dummy device 80x25
server # [    0.041410] printk: legacy console [tty0] enabled
server # [    0.056200] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)
server # [    0.057860] pid_max: default: 32768 minimum: 301
server # [    0.065944] LSM: initializing lsm=capability,landlock,yama,bpf,ima
server # [    0.069449] landlock: Up and running.
server # [    0.069623] Yama: becoming mindful.
server # [    0.072292] LSM support for eBPF active
server # [    0.079662] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
server # [    0.079970] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
server # [    0.160296] cacheinfo: Unable to detect cache hierarchy for CPU 0
server # [    0.195790] rcu: Hierarchical SRCU implementation.
server # [    0.195999] rcu: 	Max phase no-delay instances is 1000.
server # [    0.216504] fsl-mc MSI: its@8080000 domain created
server # [    0.221208] EFI services will not be available.
server # [    0.224648] smp: Bringing up secondary CPUs ...
server # [    0.226375] smp: Brought up 1 node, 1 CPU
server # [    0.226537] SMP: Total of 1 processors activated.
server # [    0.226642] CPU: All CPU(s) started at EL1
server # [    0.227397] CPU features: detected: Branch Target Identification
server # [    0.227613] CPU features: detected: 32-bit EL0 Support
server # [    0.227699] CPU features: detected: 32-bit EL1 Support
server # [    0.228740] CPU features: detected: ARMv8.4 Translation Table Level
server # [    0.228852] CPU features: detected: Instruction cache invalidation not required for I/D coherence
server # [    0.228985] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
server # [    0.229165] CPU features: detected: Common not Private translations
server # [    0.229238] CPU features: detected: CRC32 instructions
server # [    0.229294] CPU features: detected: Data cache clean to Point of Deep Persistence
server # [    0.229366] CPU features: detected: Data cache clean to Point of Persistence
server # [    0.229506] CPU features: detected: Data independent timing control (DIT)
server # [    0.229574] CPU features: detected: E0PD
server # [    0.229625] CPU features: detected: Enhanced Counter Virtualization
server # [    0.229685] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)
server # [    0.229748] CPU features: detected: Enhanced Privileged Access Never
server # [    0.229808] CPU features: detected: Enhanced Virtualization Traps
server # [    0.229886] CPU features: detected: Fine Grained Traps
server # [    0.229968] CPU features: detected: Guarded Control Stack (GCS)
server # [    0.230125] CPU features: detected: Generic authentication (IMP DEF algorithm)
server # [    0.230333] CPU features: detected: RCpc load-acquire (LDAPR)
server # [    0.230516] CPU features: detected: 52-bit Virtual Addressing for KVM (LPA2)
server # [    0.230598] CPU features: detected: LSE atomic instructions
server # [    0.230656] CPU features: detected: Memory Copy and Memory Set instructions
server # [    0.230720] CPU features: detected: Privileged Access Never
server # [    0.230841] CPU features: detected: PMUv3
server # [    0.230901] CPU features: detected: SCTLR2
server # [    0.230952] CPU features: detected: RAS Extension Support
server # [    0.231059] CPU features: detected: RASv1p1 Extension Support
server # [    0.231118] CPU features: detected: Random Number Generator
server # [    0.231175] CPU features: detected: Speculation barrier (SB)
server # [    0.231232] CPU features: detected: Stage-2 Force Write-Back
server # [    0.231290] CPU features: detected: Trap EL0 IMPLEMENTATION DEFINED functionality
server # [    0.231360] CPU features: detected: TLB range maintenance instructions
server # [    0.231420] CPU features: detected: WFx with timeout
server # [    0.231654] CPU features: detected: Scalable Matrix Extension
server # [    0.231724] CPU features: detected: FA64
server # [    0.231775] CPU features: detected: SME2
server # [    0.231825] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
server # [    0.231890] CPU features: detected: Scalable Vector Extension
server # [    0.259340] alternatives: applying system-wide alternatives
server # [    0.288843] CPU features: detected: Hardware dirty bit management on CPU0
server # [    0.290527] SVE: maximum available vector length 256 bytes per vector
server # [    0.290723] SVE: default vector length 64 bytes per vector
server # [    0.293392] SME: minimum available vector length 16 bytes per vector
server # [    0.293561] SME: maximum available vector length 256 bytes per vector
server # [    0.293648] SME: default vector length 32 bytes per vector
server # [    0.328996] Memory: 891080K/1048576K available (24576K kernel code, 7086K rwdata, 26312K rodata, 4864K init, 1102K bss, 116268K reserved, 32768K cma-reserved)
server # [    0.377440] devtmpfs: initialized
server # [    0.441744] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
server # [    0.444531] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)
server # [    0.445272] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).
server # [    0.452769] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL
server # [    0.452993] 0 pages in range for non-PLT usage
server # [    0.453113] 508240 pages in range for PLT usage
server # [    0.460120] pinctrl core: initialized pinctrl subsystem
server # [    0.488137] DMI not present or invalid.
server # [    0.526797] NET: Registered PF_NETLINK/PF_ROUTE protocol family
server # [    0.555279] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
server # [    0.558885] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
server # [    0.563005] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
server # [    0.563946] audit: initializing netlink subsys (disabled)
server # [    0.568515] audit: type=2000 audit(0.472:1): state=initialized audit_enabled=0 res=1
server # [    0.586077] thermal_sys: Registered thermal governor 'fair_share'
server # [    0.586233] thermal_sys: Registered thermal governor 'bang_bang'
server # [    0.586359] thermal_sys: Registered thermal governor 'step_wise'
server # [    0.586425] thermal_sys: Registered thermal governor 'user_space'
server # [    0.586511] thermal_sys: Registered thermal governor 'power_allocator'
server # [    0.587671] cpuidle: using governor ladder
server # [    0.588106] cpuidle: using governor menu
server # [    0.593132] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
server # [    0.594329] ASID allocator initialised with 65536 entries
server # [    0.616169] Serial: AMBA PL011 UART driver
server # [    0.761051] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1
server # [    0.766405] printk: console [ttyAMA0] enabled
server # [    0.947479] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
server # [    0.947782] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
server # [    0.947958] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
server # [    0.948053] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
server # [    0.948145] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
server # [    0.948231] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
server # [    0.948368] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
server # [    0.948618] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
server # [    1.003169] fbcon: Taking over console
server # [    1.008304] ACPI: Interpreter disabled.
server # [    1.046161] iommu: Default domain type: Translated
server # [    1.046462] iommu: DMA domain TLB invalidation policy: strict mode
server # [    1.063580] SCSI subsystem initialized
server # [    1.070678] usbcore: registered new interface driver usbfs
server # [    1.076767] usbcore: registered new interface driver hub
server # [    1.078174] usbcore: registered new device driver usb
server # [    1.090751] pps_core: LinuxPPS API ver. 1 registered
server # [    1.091016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
server # [    1.101336] PTP clock support registered
server # [    1.102701] EDAC MC: Ver: 3.0.0
server # [    1.110736] scmi_core: SCMI protocol bus registered
server # [    1.125748] FPGA manager framework
server # [    1.178839] vgaarb: loaded
server # [    1.207998] clocksource: Switched to clocksource arch_sys_counter
server # [    1.327617] VFS: Disk quotas dquot_6.6.0
server # [    1.328730] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
server # [    1.333663] netfs: FS-Cache loaded
server # [    1.341400] pnp: PnP ACPI: disabled
server # [    1.412889] NET: Registered PF_INET protocol family
server # [    1.416191] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
server # [    1.671728] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
server # [    1.672233] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
server # [    1.673802] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
server # [    1.675336] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)
server # [    1.675842] TCP: Hash tables configured (established 8192 bind 8192)
server # [    1.680226] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)
server # [    1.681766] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)
server # [    1.683353] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)
server # [    1.687699] NET: Registered PF_UNIX/PF_LOCAL protocol family
server # [    1.689419] NET: Registered PF_XDP protocol family
server # [    1.691394] PCI: CLS 0 bytes, default 64
server # [    1.700760] Trying to unpack rootfs image as initramfs...
server # [    1.710119] kvm [1]: HYP mode not available
server # [    2.378382] Initialise system trusted keyrings
server # [    2.385915] workingset: timestamp_bits=42 max_order=18 bucket_order=0
server # [    2.417079] squashfs: version 4.0 (2009/01/31) Phillip Lougher
server # [    2.423919] 9p: Installing v9fs 9p2000 file system support
server # [    2.688386] Key type asymmetric registered
server # [    2.689385] Asymmetric key parser 'x509' registered
server # [    2.691346] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)
server # [    2.696970] io scheduler mq-deadline registered
server # [    2.697320] io scheduler kyber registered
server # [    2.867830] pl061_gpio 9030000.pl061: PL061 GPIO chip registered
server # [    2.895597] ledtrig-cpu: registered to indicate activity on CPUs
server # [    2.916632] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:
server # [    2.919015] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000
server # [    2.923568] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000
server # [    2.924758] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000
server # [    2.931635] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits
server # [    2.934233] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]
server # [    2.944795] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00
server # [    2.946879] pci_bus 0000:00: root bus resource [bus 00-ff]
server # [    2.947854] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
server # [    2.948731] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]
server # [    2.949523] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]
server # [    2.958202] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint
server # [    2.974764] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    2.975694] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]
server # [    2.975956] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    2.976126] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    2.976370] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    2.985179] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
server # [    2.985584] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]
server # [    2.985731] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    2.985848] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    2.989591] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
server # [    2.989905] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]
server # [    2.990021] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    2.990133] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    2.997567] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
server # [    2.997894] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]
server # [    2.998018] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    2.998127] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.004742] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint
server # [    3.005112] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]
server # [    3.005274] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    3.005390] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.012916] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint
server # [    3.013291] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]
server # [    3.013417] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    3.013755] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.017844] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
server # [    3.018180] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]
server # [    3.018304] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    3.018418] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.018581] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]
server # [    3.024150] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint
server # [    3.024622] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    3.024765] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.032100] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint
server # [    3.032432] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    3.032594] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.036153] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint
server # [    3.036459] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]
server # [    3.040509] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
server # [    3.040956] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]
server # [    3.041108] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    3.041226] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.048142] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
server # [    3.048466] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]
server # [    3.048619] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]
server # [    3.048730] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]
server # [    3.061884] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned
server # [    3.062822] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned
server # [    3.063172] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned
server # [    3.063670] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned
server # [    3.063935] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned
server # [    3.064106] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned
server # [    3.064258] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned
server # [    3.064403] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned
server # [    3.064576] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned
server # [    3.064742] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned
server # [    3.064892] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned
server # [    3.065080] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned
server # [    3.065309] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned
server # [    3.065523] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned
server # [    3.065708] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned
server # [    3.065872] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned
server # [    3.066006] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned
server # [    3.066137] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned
server # [    3.066262] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned
server # [    3.066386] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned
server # [    3.066557] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned
server # [    3.066877] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned
server # [    3.067045] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned
server # [    3.067175] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned
server # [    3.067297] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned
server # [    3.067451] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned
server # [    3.067654] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned
server # [    3.067788] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned
server # [    3.067908] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned
server # [    3.068019] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned
server # [    3.068135] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned
server # [    3.068252] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned
server # [    3.068363] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned
server # [    3.068495] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned
server # [    3.069221] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
server # [    3.069425] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]
server # [    3.069599] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]
server # [    3.109931] pci 0000:00:0a.0: enabling device (0000 -> 0002)
server # [    3.119689] pci 0000:00:0a.0: quirk_usb_early_handoff+0x0/0xa58 took 10171 usecs
server # [    3.488265] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
server # [    3.505990] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
server # [    3.513220] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)
server # [    3.522619] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)
server # [    3.529212] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)
server # [    3.537566] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)
server # [    3.553897] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)
server # [    3.561198] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)
server # [    3.578498] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)
server # [    3.585651] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)
server # [    3.600718] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)
server # [    3.703733] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
server # [    3.762524] msm_serial: driver initialized
server # [    3.769221] SuperH (H)SCI(F) driver initialized
server # [    3.776152] STM32 USART driver initialized
server # [    4.043539] loop: module loaded
server # [    4.045449] virtio_blk virtio5: 1/0/0 default/read/poll queues
server # [    4.064602] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)
server # [    4.145330] megasas: 07.734.00.00-rc1
server # [    4.173458] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]
server # [    4.184135] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    4.188516] Intel/Sharp Extended Query Table at 0x0031
server # [    4.193766] Using buffer write method
server # [    4.200212] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]
server # [    4.205009] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000
server # [    4.211534] Intel/Sharp Extended Query Table at 0x0031
server # [    4.214226] Using buffer write method
server # [    4.219416] Concatenating MTD devices:
server # [    4.219650] (0): "0.flash"
server # [    4.219764] (1): "0.flash"
server # [    4.219843] into device "0.flash"
server # [    5.602398] Freeing initrd memory: 25352K
server # [    5.858318] tun: Universal TUN/TAP device driver, 1.6
server # [    5.891696] thunder_xcv, ver 1.0
server # [    5.892796] thunder_bgx, ver 1.0
server # [    5.893810] nicpf, ver 1.0
server # [    5.904251] e1000: Intel(R) PRO/1000 Network Driver
server # [    5.904582] e1000: Copyright (c) 1999-2006 Intel Corporation.
server # [    5.906164] e1000e: Intel(R) PRO/1000 Network Driver
server # [    5.906364] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
server # [    5.908367] igb: Intel(R) Gigabit Ethernet Network Driver
server # [    5.908588] igb: Copyright (c) 2007-2014 Intel Corporation.
server # [    5.910229] igbvf: Intel(R) Gigabit Virtual Function Network Driver
server # [    5.910424] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
server # [    5.914459] sky2: driver version 1.30
server # [    5.937824] ehci-pci 0000:00:0a.0: EHCI Host Controller
server # [    5.943728] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1
server # [    5.953217] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000
server # [    5.956863] usbcore: registered new interface driver usb-storage
server # [    5.958964] usbcore: registered new interface driver usbserial_generic
server # [    5.960256] usbserial: USB Serial support registered for generic
server # [    5.967874] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00
server # [    5.976525] hv_vmbus: registering driver hyperv_keyboard
server # [    6.001537] hub 1-0:1.0: USB hub found
server # [    6.006599] hub 1-0:1.0: 6 ports detected
server # [    6.021361] rtc-pl031 9010000.pl031: registered as rtc0
server # [    6.028378] rtc-pl031 9010000.pl031: setting system clock to 2026-10-21T13:41:19 UTC (1792590079)
server # [    6.037691] i2c_dev: i2c /dev entries driver
server # [    6.080079] sdhci: Secure Digital Host Controller Interface driver
server # [    6.080356] sdhci: Copyright(c) Pierre Ossman
server # [    6.085418] Synopsys Designware Multimedia Card Interface Driver
server # [    6.092124] sdhci-pltfm: SDHCI platform and OF driver helper
server # [    6.114172] hid: raw HID events driver (C) Jiri Kosina
server # [    6.120356] usbcore: registered new interface driver usbhid
server # [    6.120621] usbhid: USB HID core driver
server # [    6.142034] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available
server # [    6.172270] drop_monitor: Initializing network drop monitor service
server # [    6.176344] NET: Registered PF_INET6 protocol family
server # [    6.195912] Segment Routing with IPv6
server # [    6.197212] In-situ OAM (IOAM) with IPv6
server # [    6.199146] NET: Registered PF_PACKET protocol family
server # [    6.201974] 9pnet: Installing 9P2000 support
server # [    6.213489] Key type dns_resolver registered
server # [    6.283631] usb 1-1: new high-speed USB device number 2 using ehci-pci
server # [    6.336528] registered taskstats version 1
server # [    6.343017] Loading compiled-in X.509 certificates
server # [    6.515246] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input0
server # [    6.547196] Demotion targets for Node 0: null
server # [    6.550533] Key type .fscrypt registered
server # [    6.551405] Key type fscrypt-provisioning registered
server # [    6.560038] ima: No TPM chip found, activating TPM-bypass!
server # [    6.561001] ima: Allocated hash algorithm: sha1
server # [    6.567335] ima: No architecture policies found
server # [    6.595410] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0
server # [    6.601443] input: gpio-keys as /devices/platform/gpio-keys/input/input1
server # [    6.721035] clk: Disabling unused clocks
server # [    6.722240] PM: genpd: Disabling unused power domains
server # [    6.731300] usb 1-2: new high-speed USB device number 3 using ehci-pci
server # [    7.353722] Freeing unused kernel memory: 4864K
server # [    7.359017] Run /init as init process
server # [    7.481201] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2
server # [    7.488667] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0
server # [    7.697714] systemd[1]: Successfully made /usr/ read-only.
server # [    8.073981] systemd[1]: systemd 260.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)
server # [    8.077433] systemd[1]: Detected virtualization qemu.
server # [    8.079699] systemd[1]: Detected architecture arm64.
server # [    8.080805] systemd[1]: Running in initrd.
server # [    8.101513] systemd[1]: Initializing machine ID from random generator.
server # [    8.111662] systemd[1]: Hostname set to <server>.
server # [   10.206060] systemd[1]: Queued start job for default target Initrd Default Target.
server # [   11.907382] systemd[1]: Created slice Slice /system/modprobe.
server # [   11.919947] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
server # [   11.923900] systemd[1]: Expecting device /dev/disk/by-label/nixos...
server # [   11.926576] systemd[1]: Reached target Path Units.
server # [   11.928700] systemd[1]: Reached target Slice Units.
server # [   11.930389] systemd[1]: Reached target Swaps.
server # [   11.932631] systemd[1]: Reached target Timer Units.
server # [   11.942464] systemd[1]: Listening on D-Bus System Message Bus Socket.
server # [   11.949700] systemd[1]: Listening on Journal Socket (/dev/log).
server # [   11.955862] systemd[1]: Listening on Journal Sockets.
server # [   11.961112] systemd[1]: Listening on udev Control Socket.
server # [   11.965532] systemd[1]: Listening on udev Kernel Socket.
server # [   11.967939] systemd[1]: Reached target Socket Units.
server # [   12.060997] systemd[1]: Starting Create List of Static Device Nodes...
server # [   12.177011] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [   12.185457] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [   12.358583] systemd[1]: Mounting Kernel Configuration File System...
server # [   12.845014] systemd[1]: Starting Journal Service...
server # [   13.004754] systemd[1]: Starting Load Kernel Modules...
server # [   13.008429] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
server # [   13.165072] systemd[1]: Starting Coldplug All udev Devices...
server # [   13.720398] systemd[1]: Finished Create List of Static Device Nodes.
server # [   13.873065] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [   14.022570] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
server # [   14.317972] systemd[1]: Mounted Kernel Configuration File System.
server # [   14.496502] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
server # [   14.513274] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev
server # [   14.576830] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
server # [   14.804919] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0
server # [   14.812195] [drm] features: -virgl +edid -resource_blob -host_visible
server # [   14.812439] [drm] features: -context_init
server # [   14.831067] [drm] number of scanouts: 1
server # [   14.843454] [drm] number of cap sets: 0
server # [   14.919222] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic
server # [   14.919585] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0
server # [   14.930310] systemd-journald[131]: Collecting audit messages is disabled.
server # [   15.315061] Console: switching to colour frame buffer device 160x50
server # [   15.395436] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device
server # [   15.601520] systemd[1]: Finished Load Kernel Modules.
server # [   15.756258] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [   15.945145] systemd[1]: Starting Apply Kernel Variables...
server # [   16.221022] systemd[1]: Starting Create Static Device Nodes in /dev...
server # [   17.154499] systemd[1]: Finished Apply Kernel Variables.
server # [   17.368283] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [   17.403683] systemd[1]: Reached target Preparation for Local File Systems.
server # [   17.407187] systemd[1]: Reached target Local File Systems.
server # [   17.589031] systemd[1]: Starting Rule-based Manager for Device Events and Files...
server # [   20.405797] systemd[1]: Started Journal Service.
server # [   20.283602] systemd-modules-load[133]: Inserted module 'dm_mod'
server # [   20.376930] systemd-modules-load[133]: Module 'virtio_balloon' is built in
server # [   20.395184] systemd-modules-load[133]: Module 'virtio_console' is built in
server # [   20.422942] systemd-modules-load[133]: Inserted module 'virtio_gpu'
server # [   20.451142] systemd-modules-load[133]: Module 'virtio_rng' is built in
server # [   20.506430] systemd-udevd[154]: Using default interface naming scheme 'v260'.
server # [   20.545355] systemd[1]: Starting Create System Files and Directories...
server # [   21.559756] systemd[1]: Finished Create System Files and Directories.
server # [   21.745474] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [   23.565574] systemd[1]: Starting Virtual Console Setup...
server # [   25.270329] systemd-vconsole-setup[179]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [   25.447765] systemd[1]: Finished Virtual Console Setup.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [   48.605846] systemd[1]: Finished Coldplug All udev Devices.
server # [   48.615472] systemd[1]: Reached target System Initialization.
server # [   48.639380] systemd[1]: Reached target Basic System.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [   60.311314] (udev-worker)[207]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
server # [   60.354053] (udev-worker)[213]: Network interface NamePolicy= disabled on kernel command line.
server # [   60.361647] (udev-worker)[207]: Network interface NamePolicy= disabled on kernel command line.
server # [   61.843565] systemd[1]: Found device /dev/disk/by-label/nixos.
server # [   61.855276] systemd[1]: Reached target Initrd Root Device.
server # [   61.969208] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...
server # [   62.955959] systemd-fsck[232]: nixos: clean, 12/65536 files, 13019/262144 blocks
server # [   63.165717] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.
server # [   63.295388] systemd[1]: Mounting /sysroot...
server # [   64.298302] EXT4-fs (vda): mounted filesystem 0a4227d5-a7b8-4c9d-8b71-221b89298b19 r/w with ordered data mode. Quota mode: none.
server # [   64.256891] systemd[1]: Mounted /sysroot.
server # [   64.289634] systemd[1]: Reached target Initrd Root File System.
server # [   64.428947] systemd[1]: Mounting /sysroot/nix/.ro-store...
server # [   64.563551] systemd[1]: Mounting /sysroot/nix/.rw-store...
server # [   64.723110] systemd[1]: Mounting /sysroot/run...
server # [   64.891412] systemd[1]: Mounting /sysroot/tmp/shared...
server # [   65.101400] systemd[1]: Mounting /sysroot/tmp/xchg...
server # [   65.311495] systemd[1]: Starting Mountpoints Configured in the Real Root...
server # [   65.981209] systemd[1]: Mounted /sysroot/nix/.ro-store.
server # [   66.277791] systemd-sysroot-fstab-check[253]: /sysroot should be mounted in the initrd, will request daemon-reload.
server # [   66.316183] systemd[1]: Mounted /sysroot/nix/.rw-store.
server # [   66.327446] systemd[1]: Mounted /sysroot/run.
server # [   66.335670] systemd[1]: Mounted /sysroot/tmp/shared.
server # [   66.355175] systemd[1]: Mounted /sysroot/tmp/xchg.
server # [   66.525349] systemd[1]: Reload requested from client PID 253 ('systemd-sysroot') (unit initrd-parse-etc.service)...
server # [   66.534294] systemd[1]: Reloading...
server # [   74.991874] systemd[1]: Reloading finished in 8425 ms.
server # [   75.847948] systemd-sysroot-fstab-check[253]: Requesting initrd-fs.target/start/replace...
server # [   77.981651] systemd-sysroot-fstab-check[253]: Requesting swap.target/start/replace...
server # [   78.051741] systemd[1]: initrd-parse-etc.service: Deactivated successfully.
server # [   78.107353] systemd[1]: Finished Mountpoints Configured in the Real Root.
server # [   78.122416] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.
server # [   78.333782] systemd[1]: Starting rw-sysroot-nix-store.service...
server # [   78.903549] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
server # [   79.099675] systemd[1]: Finished rw-sysroot-nix-store.service.
server # [   79.540120] systemd[1]: Mounting /sysroot/nix/store...
server # [   80.436007] systemd[1]: Mounted /sysroot/nix/store.
server # [   80.471357] systemd[1]: Reached target Initrd File Systems.
server # [   80.549136] systemd[1]: Starting Find NixOS closure...
server # [   80.695882] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...
server # [   81.935165] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.
server # [   81.993475] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.
server # [   82.897568] systemd[1]: Finished Find NixOS closure.
server # [   82.969377] systemd[1]: Reached target Initrd Default Target.
server # [   83.097239] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...
server # [   84.221178] systemd[1]: initrd-cleanup.service: Deactivated successfully.
server # [   84.286129] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.
server # [   84.402133] systemd[1]: Stopped target Initrd Default Target.
server # [   84.418457] systemd[1]: Stopped target Basic System.
server # [   84.431709] systemd[1]: Stopped target Initrd Root Device.
server # [   84.447146] systemd[1]: Stopped target Path Units.
server # [   84.466239] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
server # [   84.474421] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
server # [   84.483078] systemd[1]: Stopped target Slice Units.
server # [   84.498321] systemd[1]: Stopped target Socket Units.
server # [   84.507725] systemd[1]: Stopped target System Initialization.
server # [   84.518397] systemd[1]: Stopped target Swaps.
server # [   84.531321] systemd[1]: Stopped target Timer Units.
server # [   84.537859] systemd[1]: dbus.socket: Deactivated successfully.
server # [   84.550087] systemd[1]: Closed D-Bus System Message Bus Socket.
server # [   84.596808] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.
server # [   84.606211] systemd[1]: Stopped Find NixOS closure.
server # [   84.751902] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [   84.889617] systemd[1]: Starting rw-sysroot-nix-store.service...
server # [   84.902529] systemd[1]: systemd-sysctl.service: Deactivated successfully.
server # [   84.917222] systemd[1]: Stopped Apply Kernel Variables.
server # [   85.085411] systemd[1]: systemd-modules-load.service: Deactivated successfully.
server # [   85.111000] systemd[1]: Stopped Load Kernel Modules.
server # [   85.347714] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.
server # [   85.374410] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.
server # [   85.513290] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
server # [   85.525222] systemd[1]: Stopped Create System Files and Directories.
server # [   85.595484] systemd[1]: Stopped target Local File Systems.
server # [   85.603741] systemd[1]: Stopped target Preparation for Local File Systems.
server # [   85.615861] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
server # [   85.630622] systemd[1]: Stopped Coldplug All udev Devices.
server # [   85.642320] systemd[1]: systemd-udev-trigger.service: Consumed 18.224s CPU time over 1min 12.581s wall clock time, 9.2M memory peak.
server # [   85.708765] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [   85.722220] systemd[1]: Stopped Virtual Console Setup.
server # [   85.817346] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [   85.869542] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
server # [   85.947077] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.
server # [   85.995059] systemd[1]: Finished rw-sysroot-nix-store.service.
server # [   87.011481] systemd[1]: systemd-udevd.service: Deactivated successfully.
server # [   87.058720] systemd[1]: Stopped Rule-based Manager for Device Events and Files.
server # [   87.070109] systemd[1]: systemd-udevd.service: Consumed 22.062s CPU time over 1min 9.565s wall clock time, 19.1M memory peak.
server # [   87.150516] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
server # [   87.161881] systemd[1]: Closed udev Control Socket.
server # [   87.285409] systemd[1]: Starting Cleanup udev Database...
server # [   87.295447] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
server # [   87.303226] systemd[1]: Stopped Create Static Device Nodes in /dev.
server # [   87.430441] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
server # [   87.438637] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.
server # [   87.570900] systemd[1]: kmod-static-nodes.service: Deactivated successfully.
server # [   87.582589] systemd[1]: Stopped Create List of Static Device Nodes.
server # [   87.901498] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
server # [   87.951841] systemd[1]: Finished Cleanup udev Database.
server # [   88.045799] systemd[1]: Reached target Switch Root.
server # [   88.101188] systemd[1]: Starting NixOS Activation...
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [   91.343385] initrd-nixos-activation-start[556]: booting system configuration /nix/store/h8mjayp97swrv476s970jm93miz3jdsm-nixos-system-server-test
server # [   92.673524] initrd-nixos-activation-start[556]: running activation script...
server # [  102.437653] initrd-nixos-activation-start[581]: setting up /etc...
server # [  107.568900] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.
server # [  107.610829] systemd[1]: Finished NixOS Activation.
server # [  107.622782] systemd[1]: initrd-nixos-activation.service: Consumed 11.346s CPU time over 19.452s wall clock time, 22.4M memory peak.
server # [  107.755313] systemd[1]: Starting Switch Root...
server # [  108.075125] systemd[1]: Switching root.
server # [  112.938259] systemd-journald[131]: Received SIGTERM from PID 1 (systemd).
server # [  117.482116] systemd[1]: systemd 260.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)
server # [  117.489865] systemd[1]: Detected virtualization qemu.
server # [  117.494424] systemd[1]: Detected architecture arm64.
server # [  117.501406] systemd[1]: Detected first boot.
server # [  117.572367] systemd[1]: Initializing machine ID from random generator.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  121.669683] systemd[1]: bpf-restrict-fs: LSM BPF program attached
server # [  125.067944] systemd[1]: Applying preset policy.
server # [  133.795465] systemd[1]: Populated /etc with preset unit settings.
server # [  143.414437] systemd[1]: initrd-switch-root.service: Deactivated successfully.
server # [  143.429159] systemd[1]: Stopped initrd-switch-root.service.
server # [  143.495478] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
server # [  143.538350] systemd[1]: Created slice Slice /system/getty.
server # [  143.568999] systemd[1]: Created slice User and Session Slice.
server # [  143.585127] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
server # [  143.595780] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
server # [  143.605108] systemd[1]: Expecting device /dev/hvc0...
server # [  143.613107] systemd[1]: Expecting device /dev/ttyAMA0...
server # [  143.620079] systemd[1]: Reached target Local Encrypted Volumes.
server # [  143.629461] systemd[1]: Stopped target initrd-fs.target.
server # [  143.638499] systemd[1]: Stopped target initrd-root-fs.target.
server # [  143.648031] systemd[1]: Stopped target initrd-switch-root.target.
server # [  143.658118] systemd[1]: Reached target Virtual Machines and Containers.
server # [  143.667087] systemd[1]: Reached target Path Units.
server # [  143.675236] systemd[1]: Reached target Remote File Systems.
server # [  143.682997] systemd[1]: Reached target Slice Units.
server # [  143.691100] systemd[1]: Reached target Swaps.
server # [  143.760823] systemd[1]: Listening on Process Core Dump Socket.
server # [  143.812877] systemd[1]: Listening on Credential Encryption/Decryption.
server # [  143.901390] systemd[1]: Starting Journal Log Access Socket...
server # [  143.941896] systemd[1]: Listening on Journal Audit Socket.
server # [  143.989561] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.
server # [  144.005622] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki
server # [  144.019079] systemd[1]: Listening on udev Control Socket.
server # [  144.110056] systemd[1]: Mounting Huge Pages File System...
server # [  144.237057] systemd[1]: Mounting POSIX Message Queue File System...
server # [  144.412815] systemd[1]: Mounting Kernel Debug File System...
server # [  144.632767] systemd[1]: Mounting Kernel Trace File System...
server # [  144.933363] systemd[1]: Starting Create List of Static Device Nodes...
server # [  145.281478] systemd[1]: Starting Load Kernel Module 9pnet_virtio...
server # [  145.324701] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server # [  145.764761] systemd[1]: Mounting Kernel Configuration File System...
server # [  145.860857] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm
server # [  145.907963] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore
server # [  147.337186] systemd[1]: Starting Load Kernel Module fuse...
server # [  147.370400] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67
server # [  147.700663] systemd[1]: Starting Journal Service...
server # [  148.097859] systemd[1]: Starting Load Kernel Modules...
server # [  148.625300] fuse: init (API version 7.45)
server # [  148.697032] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...
server # [  148.950496] systemd[1]: Starting Remount Root and Kernel File Systems...
server # [  148.996394] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  149.536874] systemd[1]: Starting Coldplug All udev Devices...
server # [  150.606235] systemd[1]: Listening on Journal Log Access Socket.
server # [  150.739132] systemd[1]: Mounted Huge Pages File System.
server # [  150.781456] systemd-journald[790]: Collecting audit messages is enabled.
server # [  150.821980] systemd[1]: Mounted POSIX Message Queue File System.
server # [  150.886568] systemd[1]: Mounted Kernel Debug File System.
server # [  150.949301] systemd[1]: Mounted Kernel Trace File System.
server # [  151.084732] systemd[1]: Finished Create List of Static Device Nodes.
server # [  151.193507] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.
server # [  151.293732] systemd[1]: Finished Load Kernel Module 9pnet_virtio.
server # [  151.365289] systemd[1]: Mounted Kernel Configuration File System.
server # [  151.466180] systemd[1]: modprobe@fuse.service: Deactivated successfully.
server # [  151.576725] systemd[1]: Finished Load Kernel Module fuse.
server # [  151.740588] systemd[1]: Finished Load Kernel Modules.
server # [  151.836750] EXT4-fs (vda): re-mounted 0a4227d5-a7b8-4c9d-8b71-221b89298b19.
server # [  151.913428] systemd[1]: Queued start job for default target Multi-User System.
server # [  152.077716] systemd[1]: Started Journal Service.
server # [  152.001896] systemd[1]: systemd-journald.service: Deactivated successfully.
server # [  152.055445] systemd[1]: systemd-journald.service: Consumed 2.867s CPU time over 2min 10.388s wall clock time, 3.9M memory peak.
server # [  152.128424] systemd-modules-load[791]: Module 'atkbd' is built in
server # [  152.185756] systemd-modules-load[791]: Module 'loop' is built in
server # [  152.233622] systemd-modules-load[791]: Inserted module 'tls'
server # [  152.267641] systemd[1]: Finished Remount Root and Kernel File Systems.
server # [  153.009149] systemd[1]: Mounting FUSE Control File System...
server # [  153.253080] systemd[1]: Starting Firewall...
server # [  153.639328] systemd[1]: Starting Flush Journal to Persistent Storage...
server # [  153.673095] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore
server # [  154.276029] systemd[1]: Starting Load/Save OS Random Seed...
server # [  154.365702] systemd-oomd[792]: No swap; memory pressure usage will be degraded
server # [  154.903281] systemd[1]: Starting Apply Kernel Variables...
server # [  155.749983] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...
server # [  155.777327] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki
server # [  156.345894] systemd-journald[790]: Received client request to flush runtime journal.
server # [  159.301657] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.
server # [  159.355355] systemd[1]: Mounted FUSE Control File System.
server # [  159.431817] systemd[1]: Finished Load/Save OS Random Seed.
server # [  159.522209] systemd[1]: Reached target First Boot Complete.
server # [  159.585902] systemd[1]: Finished Flush Journal to Persistent Storage.
server # [  160.778763] systemd[1]: Finished Apply Kernel Variables.
server # [  161.890263] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.
server # [  162.137336] systemd[1]: Starting Create Static Device Nodes in /dev...
server # [  165.049683] systemd[1]: Finished Create Static Device Nodes in /dev.
server # [  165.133383] systemd[1]: Reached target Preparation for Local File Systems.
server # [  165.305000] systemd[1]: Mounting /run/wrappers...
server # [  165.629822] systemd[1]: Starting Rule-based Manager for Device Events and Files...
server # [  167.482041] systemd[1]: Mounted /run/wrappers.
server # [  167.533274] systemd[1]: Reached target Local File Systems.
server # [  167.737227] systemd[1]: Listening on Boot Loader Control Service Socket.
server # [  167.911515] systemd[1]: Starting register-nix-paths.service...
server # [  168.297794] systemd[1]: Starting Create SUID/SGID Wrappers...
server # [  168.331160] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.
server # [  168.975165] systemd[1]: Starting Save Transient machine-id to Disk...
server # [  169.737135] systemd[1]: Starting Create System Files and Directories...
server # [  173.465870] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
server # [  173.591745] systemd[1]: Finished Save Transient machine-id to Disk.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  179.667222] systemd[1]: Finished Create System Files and Directories.
server # [  180.229306] systemd[1]: Starting Rebuild Journal Catalog...
server # [  180.893524] systemd[1]: Starting Record System Boot/Shutdown in UTMP...
server # [  185.161591] systemd[1]: Finished Record System Boot/Shutdown in UTMP.
server # [  189.351050] systemd[1]: Finished Rebuild Journal Catalog.
server # [  189.702486] systemd[1]: Starting Update is Completed...
server # [  192.614561] systemd[1]: Finished Update is Completed.
server # [  202.127426] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.
server # [  202.201811] systemd[1]: Finished Create SUID/SGID Wrappers.
server # [  202.238733] systemd[1]: suid-sgid-wrappers.service: Consumed 4.222s CPU time over 33.815s wall clock time, 5.2M memory peak.
server # [  207.065798] systemd[1]: Finished Firewall.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  211.221307] systemd-udevd[847]: Using default interface naming scheme 'v260'.
server # [  212.043803] systemd[1]: Finished register-nix-paths.service.
server # [  214.626659] systemd[1]: Started Rule-based Manager for Device Events and Files.
server # [  236.122049] systemd[1]: Finished Coldplug All udev Devices.
server # [  236.141871] systemd[1]: Reached target System Initialization.
server # [  236.173054] systemd[1]: Started Discard unused filesystem blocks once a week.
server # [  236.194542] systemd[1]: Started Daily Cleanup of Temporary Directories.
server # [  236.210780] systemd[1]: Reached target Timer Units.
server # [  236.226616] systemd[1]: Listening on D-Bus System Message Bus Socket.
server # [  236.238321] systemd[1]: Listening on Nix Daemon Socket.
server # [  236.255378] systemd[1]: Listening on Hostname Service Socket.
server # [  236.273485] systemd[1]: Reached target Socket Units.
server # [  236.286593] systemd[1]: Reached target Basic System.
server # [  236.354188] systemd[1]: Starting Import lastlog data into lastlog2 database...
server # [  236.561335] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [  236.825418] systemd[1]: Starting Post-Boot Actions...
server # [  237.287346] systemd[1]: Started Reset console on configuration changes.
server # [  237.855027] systemd[1]: Starting resolvconf update...
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  240.045316] systemd[1]: Starting D-Bus System Message Bus...
server # [  240.382381] nsncd[1035]: Oct 21 13:45:13.888 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
server # [  240.521682] systemd[1]: Finished Post-Boot Actions.
server # [  240.603900] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [  241.183502] systemd[1]: Finished Import lastlog data into lastlog2 database.
server # [  241.329314] systemd[1]: Reached target Host and Network Name Lookups.
server # [  241.382438] systemd[1]: Reached target User and Group Name Lookups.
server # [  241.964435] systemd[1]: Starting User Login Management...
server # [  245.195549] dbus-broker-launch[1042]: Looking up NSS user entry for 'systemd-timesync'...
server # [  245.789934] dbus-broker-launch[1042]: NSS returned no entry for 'systemd-timesync'
server # [  245.808954] dbus-broker-launch[1042]: Invalid user-name in /nix/store/60d582rv76ykld4d2wlnzxjq4sfspcl5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"
server # [  246.726947] systemd[1]: Started D-Bus System Message Bus.
server # [  247.822402] systemd-logind[1057]: New seat seat0.
server # [  248.113455] dbus-broker-launch[1042]: Ready
server # [  248.278882] systemd[1]: Started User Login Management.
server # [  248.629428] systemd[1]: Stopped target Host and Network Name Lookups.
server # [  248.643754] systemd[1]: Stopping Host and Network Name Lookups...
server # [  248.662783] systemd[1]: Stopped target User and Group Name Lookups.
server # [  248.686830] systemd[1]: Stopping User and Group Name Lookups...
server # [  248.875067] systemd[1]: Starting linger-users.service...
server # [  249.009172] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...
server # [  249.201461] systemd[1]: nscd.service: Deactivated successfully.
server # [  249.299682] systemd[1]: Stopped Name Service Cache Daemon (nsncd).
server # [  250.384098] systemd[1]: Starting Name Service Cache Daemon (nsncd)...
server # [  251.274459] systemd[1]: linger-users.service: Deactivated successfully.
server # [  251.327851] systemd[1]: Finished linger-users.service.
server # [  251.685883] systemd[1]: Finished resolvconf update.
server # [  251.737153] systemd[1]: Reached target Preparation for Network.
server # [  251.956780] systemd[1]: Starting DHCP Client...
server # [  252.151736] nsncd[1108]: Oct 21 13:45:25.740 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"
server # [  252.215392] systemd[1]: Starting Extra networking commands....
server # [  252.327768] systemd[1]: Started Name Service Cache Daemon (nsncd).
server # [  252.438211] systemd[1]: Reached target Host and Network Name Lookups.
server # [  252.478725] systemd[1]: Reached target User and Group Name Lookups.
server # [  259.429704] systemd[1]: Finished Extra networking commands..
server # [  259.559472] dhcpcd[1140]: dhcpcd-10.3.2 starting
server # [  259.571781] systemd[1]: Reached target Network.
server # [  259.861992] systemd[1]: Starting Nginx Web Server...
server # [  260.089688] dhcpcd[1183]: dev: loaded udev
server # [  260.388916] dhcpcd[1183]: no valid interfaces found
server # [  260.401940] dhcpcd[1183]: no valid interfaces found
server # [  260.502873] systemd[1]: Starting PostgreSQL Server...
server # [  260.789779] systemd[1]: Starting Permit User Sessions...
server # [  263.219878] systemd[1]: Finished Permit User Sessions.
server # [  263.495932] systemd[1]: Started Getty on tty1.
server # [  263.573922] systemd[1]: Reached target Login Prompts.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  273.171536] postgresql-pre-start[1202]: The files belonging to this database system will be owned by user "postgres".
server # [  273.263632] postgresql-pre-start[1202]: This user must also own the server process.
server # [  273.437116] postgresql-pre-start[1202]: The database cluster will be initialized with locale "en_US.UTF-8".
server # [  273.449937] postgresql-pre-start[1202]: The default database encoding has accordingly been set to "UTF8".
server # [  273.466915] postgresql-pre-start[1202]: The default text search configuration will be set to "english".
server # [  273.494447] postgresql-pre-start[1202]: Data page checksums are disabled.
server # [  273.506072] postgresql-pre-start[1202]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok
server # [  273.640779] postgresql-pre-start[1202]: creating subdirectories ... ok
server # [  273.673693] postgresql-pre-start[1202]: selecting dynamic shared memory implementation ... posix
server # [  273.937875] nginx-pre-start[1195]: nginx: the configuration file /nix/store/ikrbs31amb4hnw5f24wff1xrag8dsmr3-nginx.conf syntax is ok
server # [  274.045691] nginx-pre-start[1195]: nginx: configuration file /nix/store/ikrbs31amb4hnw5f24wff1xrag8dsmr3-nginx.conf test is successful
server # [  274.546187] systemd[1]: Started Nginx Web Server.
server # [  277.843017] postgresql-pre-start[1202]: selecting default "max_connections" ... 100
server # [  280.697643] postgresql-pre-start[1202]: selecting default "shared_buffers" ... 128MB
server # [  290.497391] dhcpcd[1183]: timed out
server # [  290.507520] dhcpcd[1183]: timed out
server # [  290.642748] systemd[1]: Started DHCP Client.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  303.286609] systemd-logind[1057]: Watching system buttons on /dev/input/event1 (gpio-keys)
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  332.596649] mousedev: PS/2 mouse device common for all mice
server # [  337.489356] postgresql-pre-start[1202]: selecting default time zone ... UTC
server # [  337.645157] postgresql-pre-start[1202]: creating configuration files ... ok
server # [  343.029918] systemd-logind[1057]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)
server # [  353.934687] postgresql-pre-start[1202]: running bootstrap script ... ok
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  379.539723] systemd[1]: postgresql.service: start-pre operation timed out. Terminating.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  397.563849] postgresql-pre-start[1202]: performing post-bootstrap initialization ... ok
server # [  404.975401] postgresql-pre-start[1202]: syncing data to disk ... ok
server # [  404.998337] postgresql-pre-start[1202]: initdb: warning: enabling "trust" authentication for local connections
server # [  405.015432] postgresql-pre-start[1202]: initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.
server # [  405.031383] postgresql-pre-start[1202]: Success. You can now start the database server using:
server # [  405.075615] postgresql-pre-start[1202]:     pg_ctl -D /var/lib/postgresql/17 -l logfile start
server # [  405.670873] systemd[1]: postgresql.service: Failed with result 'timeout'.
server # [  405.707904] systemd[1]: Failed to start PostgreSQL Server.
server # [  405.738642] systemd[1]: Dependency failed for PostgreSQL Setup Scripts.
server # [  405.755521] systemd[1]: Dependency failed for PostgreSQL.
server # [  405.775183] systemd[1]: postgresql.target: Job postgresql.target/start failed with result 'dependency'.
server # [  405.784118] systemd[1]: postgresql-setup.service: Job postgresql-setup.service/start failed with result 'dependency'.
server # [  405.814622] systemd[1]: postgresql.service: Consumed 46.804s CPU time over 2min 25.183s wall clock time, 97.9M memory peak, 48.9M written to disk.
server # [  405.972217] systemd[1]: postgresql.service: Scheduled restart job, restart counter is at 1.
server # [  406.190157] systemd[1]: Starting PostgreSQL Server...
server # [  414.242724] postgres[1385]: [1385] LOG:  starting PostgreSQL 17.10 on aarch64-unknown-linux-gnu, compiled by clang version 21.1.8, 64-bit
server # [  414.654480] postgres[1385]: [1385] LOG:  listening on IPv6 address "::1", port 5432
server # [  414.693314] postgres[1385]: [1385] LOG:  listening on IPv4 address "127.0.0.1", port 5432
server # [  414.737104] postgres[1385]: [1385] LOG:  listening on Unix socket "/run/postgresql/.s.PGSQL.5432"
server # [  415.173389] postgres[1391]: [1391] LOG:  database system was shut down at 2026-10-21 13:47:50 GMT
server # [  415.583649] postgres[1385]: [1385] LOG:  database system is ready to accept connections
server # [  415.747299] systemd[1]: Started PostgreSQL Server.
server # [  415.918094] systemd[1]: Starting PostgreSQL Setup Scripts...
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  425.639975] postgresql-setup-start[1412]: CREATE DATABASE
server # [  427.263685] postgresql-setup-start[1427]: CREATE ROLE
server # [  427.445452] systemd[1]: Finished PostgreSQL Setup Scripts.
server # [  427.476784] systemd[1]: Reached target PostgreSQL.
server # [  427.620985] systemd[1]: Starting Mobilizon PostgreSQL setup...
server # [  443.665492] systemd[1]: dev-hvc0.device: Job dev-hvc0.device/start timed out.
server # [  443.682993] systemd[1]: Timed out waiting for device /dev/hvc0.
server # [  443.714912] systemd[1]: Dependency failed for backdoor.service.
server # [  443.739639] systemd[1]: backdoor.service: Job backdoor.service/start failed with result 'dependency'.
server # [  443.750690] systemd[1]: dev-hvc0.device: Job dev-hvc0.device/start failed with result 'timeout'.
server # [  443.767147] systemd[1]: Unnecessary job was removed for /dev/ttyAMA0.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  470.101596] mobilizon-postgresql-start[1431]: CREATE EXTENSION
server # [  470.197785] mobilizon-postgresql-start[1431]: CREATE EXTENSION
server # [  470.215590] mobilizon-postgresql-start[1431]: CREATE EXTENSION
server # [  471.239077] mobilizon-postgresql-start[1484]: ALTER DATABASE
server # [  471.399378] systemd[1]: mobilizon-postgresql.service: Deactivated successfully.
server # [  471.414460] systemd[1]: Finished Mobilizon PostgreSQL setup.
server # [  471.536001] systemd[1]: Starting Mobilizon setup secrets...
server # [  472.779508] mobilizon-setup-secrets-start[1488]: + '[' '!' -f /var/lib/mobilizon/secret-env.sh ']'
server # [  472.806544] mobilizon-setup-secrets-start[1488]: + install -m 600 /dev/null /var/lib/mobilizon/secret-env.sh
server # [  473.003453] mobilizon-setup-secrets-start[1488]: + cat
server # [  473.124150] mobilizon-setup-secrets-start[1492]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(:crypto.strong_rand_bytes(64)|> Base.encode64()|> binary_part(0, 64))'
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  518.743571] mobilizon-setup-secrets-start[1581]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(:crypto.strong_rand_bytes(64)|> Base.encode64()|> binary_part(0, 64))'
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  558.986781] mobilizon-setup-secrets-start[1666]: ++ /nix/store/l8rv77qmqmcq0yzw5qw2w5n0y099x0ih-elixir-1.18.4/bin/elixir --eval 'IO.puts(Base.encode32(:crypto.strong_rand_bytes(32)))'
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  596.761661] systemd[1]: mobilizon-setup-secrets.service: Deactivated successfully.
server # [  596.801107] systemd[1]: Finished Mobilizon setup secrets.
server # [  596.818535] systemd[1]: mobilizon-setup-secrets.service: Consumed 47.227s CPU time over 2min 5.203s wall clock time, 63.4M memory peak, 4K written to disk.
server # [  597.003592] systemd[1]: Starting Mobilizon federated organization and mobilization platform...
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  655.791313] mobilizon_ctl[1759]: Loading app..
server # [  656.296723] mobilizon_ctl[1759]: Starting dependencies..
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  715.205996] postgres[1389]: [1389] LOG:  checkpoint starting: time
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  852.109823] mobilizon_ctl[1759]:     #########################################################
server # [  852.202928] mobilizon_ctl[1759]:     # If the CREATE EXTENSION or DROP EXTENSION calls fail, #
server # [  852.218405] mobilizon_ctl[1759]:     # please manually execute them with an authorized       #
server # [  852.231662] mobilizon_ctl[1759]:     # PostgreSQL user with SUPER USER role.                 #
server # [  852.254709] mobilizon_ctl[1759]:     #########################################################
server # [  852.266397] mobilizon_ctl[1759]:
server # [  852.282807] mobilizon_ctl[1759]:
server # [  852.298960] mobilizon_ctl[1759]: 13:55:25.524 [info] == Running 20180109150000 Mobilizon.Repo.Migrations.Prerequites.up/0 forward
server # [  852.342751] mobilizon_ctl[1759]: 13:55:25.923 [info] execute "CREATE TYPE datetimetz AS (\n    dt timestamptz,\n    tz varchar\n);\n"
server # [  852.402024] mobilizon_ctl[1759]: 13:55:25.995 [info] execute "CREATE EXTENSION IF NOT EXISTS postgis"
server # [  852.459302] mobilizon_ctl[1759]: 13:55:26.056 [info] extension "postgis" already exists, skipping
server # [  852.497487] mobilizon_ctl[1759]: 13:55:26.057 [info] execute "CREATE EXTENSION IF NOT EXISTS pg_trgm"
server # [  852.510216] mobilizon_ctl[1759]: 13:55:26.104 [info] extension "pg_trgm" already exists, skipping
server # [  852.541730] mobilizon_ctl[1759]: 13:55:26.106 [info] execute "CREATE EXTENSION IF NOT EXISTS unaccent"
server # [  852.569691] mobilizon_ctl[1759]: 13:55:26.148 [info] extension "unaccent" already exists, skipping
server # [  852.715443] mobilizon_ctl[1759]: 13:55:26.301 [info] == Migrated 20180109150000 in 0.4s
server # [  855.062924] mobilizon_ctl[1759]: 13:55:28.626 [info] == Running 20180109165738 Mobilizon.Repo.Migrations.CreateAccounts.change/0 forward
server # [  855.110519] mobilizon_ctl[1759]: 13:55:28.692 [info] create table accounts
server # [  855.461824] mobilizon_ctl[1759]: 13:55:29.043 [info] create index accounts_username_domain_index
server # [  855.525072] mobilizon_ctl[1759]: 13:55:29.114 [info] == Migrated 20180109165738 in 0.4s
server # [  855.656995] mobilizon_ctl[1759]: 13:55:29.255 [info] == Running 20180109170315 Mobilizon.Repo.Migrations.CreateUsers.change/0 forward
server # [  855.690036] mobilizon_ctl[1759]: 13:55:29.281 [info] create table users
server # [  856.187031] mobilizon_ctl[1759]: 13:55:29.690 [info] create index users_email_index
server # [  856.223652] mobilizon_ctl[1759]: 13:55:29.786 [info] == Migrated 20180109170315 in 0.5s
server # [  856.333350] mobilizon_ctl[1759]: 13:55:29.938 [info] == Running 20180110091747 Mobilizon.Repo.Migrations.CreateCategories.change/0 forward
server # [  856.377555] mobilizon_ctl[1759]: 13:55:29.970 [info] create table categories
server # [  856.516866] mobilizon_ctl[1759]: 13:55:30.121 [info] create index categories_title_index
server # [  856.580878] mobilizon_ctl[1759]: 13:55:30.175 [info] == Migrated 20180110091747 in 0.2s
server # [  856.697537] mobilizon_ctl[1759]: 13:55:30.296 [info] == Running 20180110092400 Mobilizon.Repo.Migrations.CreateAddresses.change/0 forward
server # [  856.735590] mobilizon_ctl[1759]: 13:55:30.313 [info] create table addresses
server # [  856.891595] mobilizon_ctl[1759]: 13:55:30.485 [info] == Migrated 20180110092400 in 0.1s
server # [  856.990408] mobilizon_ctl[1759]: 13:55:30.592 [info] == Running 20180110092500 Mobilizon.Repo.Migrations.CreateGroups.change/0 forward
server # [  857.038061] mobilizon_ctl[1759]: 13:55:30.620 [info] create table groups
server # [  857.282021] mobilizon_ctl[1759]: 13:55:30.837 [info] create index groups_slug_index
server # [  857.317164] mobilizon_ctl[1759]: 13:55:30.919 [info] == Migrated 20180110092500 in 0.3s
server # [  857.416994] mobilizon_ctl[1759]: 13:55:31.015 [info] == Running 20180110092600 Mobilizon.Repo.Migrations.CreateEvents.change/0 forward
server # [  857.463497] mobilizon_ctl[1759]: 13:55:31.054 [info] create table events
server # [  857.730319] mobilizon_ctl[1759]: 13:55:31.332 [info] create index events_organizer_account_id_index
server # [  857.818513] mobilizon_ctl[1759]: 13:55:31.413 [info] create index events_organizer_group_id_index
server # [  857.877194] mobilizon_ctl[1759]: 13:55:31.470 [info] create index events_slug_index
server # [  857.935725] mobilizon_ctl[1759]: 13:55:31.522 [info] == Migrated 20180110092600 in 0.5s
server # [  858.027311] mobilizon_ctl[1759]: 13:55:31.626 [info] == Running 20180110093905 Mobilizon.Repo.Migrations.CreateTags.change/0 forward
server # [  858.064965] mobilizon_ctl[1759]: 13:55:31.649 [info] create table tags
server # [  858.225653] mobilizon_ctl[1759]: 13:55:31.817 [info] create index tags_slug_index
server # [  858.319802] mobilizon_ctl[1759]: 13:55:31.882 [info] == Migrated 20180110093905 in 0.2s
server # [  858.388792] mobilizon_ctl[1759]: 13:55:31.993 [info] == Running 20180110093906 Mobilizon.Repo.Migrations.CreateEventsTags.change/0 forward
server # [  858.420372] mobilizon_ctl[1759]: 13:55:31.998 [info] create table events_tags
server # [  858.510696] mobilizon_ctl[1759]: 13:55:32.108 [info] == Migrated 20180110093906 in 0.1s
server # [  858.629001] mobilizon_ctl[1759]: 13:55:32.220 [info] == Running 20180110094301 Mobilizon.Repo.Migrations.CreateParticipants.change/0 forward
server # [  858.666824] mobilizon_ctl[1759]: 13:55:32.241 [info] create table participants
server # [  858.808732] mobilizon_ctl[1759]: 13:55:32.397 [info] create index participants_event_id_index
server # [  858.861811] mobilizon_ctl[1759]: 13:55:32.457 [info] create index participants_account_id_index
server # [  858.909286] mobilizon_ctl[1759]: 13:55:32.513 [info] == Migrated 20180110094301 in 0.2s
server # [  859.010157] mobilizon_ctl[1759]: 13:55:32.606 [info] == Running 20180110094502 Mobilizon.Repo.Migrations.CreateEventRequests.change/0 forward
server # [  859.036927] mobilizon_ctl[1759]: 13:55:32.627 [info] create table event_requests
server # [  859.194071] mobilizon_ctl[1759]: 13:55:32.781 [info] create index event_requests_event_id_index
server # [  859.245731] mobilizon_ctl[1759]: 13:55:32.851 [info] create index event_requests_account_id_index
server # [  859.367503] mobilizon_ctl[1759]: 13:55:32.914 [info] == Migrated 20180110094502 in 0.3s
server # [  859.474167] mobilizon_ctl[1759]: 13:55:33.082 [info] == Running 20180110095317 Mobilizon.Repo.Migrations.CreateMembers.change/0 forward
server # [  859.535429] mobilizon_ctl[1759]: 13:55:33.125 [info] create table members
server # [  859.678620] mobilizon_ctl[1759]: 13:55:33.263 [info] create index members_group_id_index
server # [  859.742473] mobilizon_ctl[1759]: 13:55:33.331 [info] create index members_account_id_index
server # [  859.813051] mobilizon_ctl[1759]: 13:55:33.391 [info] == Migrated 20180110095317 in 0.3s
server # [  859.915697] mobilizon_ctl[1759]: 13:55:33.510 [info] == Running 20180110095515 Mobilizon.Repo.Migrations.CreateGroupRequests.change/0 forward
server # [  859.981076] mobilizon_ctl[1759]: 13:55:33.562 [info] create table group_requests
server # [  860.228886] mobilizon_ctl[1759]: 13:55:33.803 [info] create index group_requests_group_id_index
server # [  860.294003] mobilizon_ctl[1759]: 13:55:33.881 [info] create index group_requests_account_id_index
server # [  860.427774] mobilizon_ctl[1759]: 13:55:33.956 [info] == Migrated 20180110095515 in 0.4s
server # [  860.489995] mobilizon_ctl[1759]: 13:55:34.090 [info] == Running 20180110165029 Mobilizon.Repo.Migrations.Guardian.DB.change/0 forward
server # [  860.533432] mobilizon_ctl[1759]: 13:55:34.121 [info] create table guardian_tokens
server # [  860.695681] mobilizon_ctl[1759]: 13:55:34.287 [info] == Migrated 20180110165029 in 0.1s
server # [  860.806498] mobilizon_ctl[1759]: 13:55:34.404 [info] == Running 20180112100000 Mobilizon.Repo.Migrations.CreateTracks.change/0 forward
server # [  860.843757] mobilizon_ctl[1759]: 13:55:34.430 [info] create table tracks
server # [  861.065639] mobilizon_ctl[1759]: 13:55:34.655 [info] == Migrated 20180112100000 in 0.2s
server # [  861.173019] mobilizon_ctl[1759]: 13:55:34.762 [info] == Running 20180112105354 Mobilizon.Repo.Migrations.CreateSessions.change/0 forward
server # [  861.223771] mobilizon_ctl[1759]: 13:55:34.805 [info] create table sessions
server # [  861.553257] mobilizon_ctl[1759]: 13:55:35.101 [info] == Migrated 20180112105354 in 0.3s
server # [  861.671000] mobilizon_ctl[1759]: 13:55:35.234 [info] == Running 20180430071244 Mobilizon.Repo.Migrations.RemoveUri.up/0 forward
server # [  861.708071] mobilizon_ctl[1759]: 13:55:35.263 [info] alter table accounts
server # [  861.771373] mobilizon_ctl[1759]: 13:55:35.369 [info] alter table groups
server # [  861.805601] mobilizon_ctl[1759]: 13:55:35.404 [info] == Migrated 20180430071244 in 0.1s
server # [  861.901897] mobilizon_ctl[1759]: 13:55:35.506 [info] == Running 20180515091106 Mobilizon.Repo.Migrations.AddUrlFieldToEvents.up/0 forward
server # [  861.935712] mobilizon_ctl[1759]: 13:55:35.529 [info] alter table events
server # [  862.006401] mobilizon_ctl[1759]: 13:55:35.586 [info] == Migrated 20180515091106 in 0.0s
server # [  862.097528] mobilizon_ctl[1759]: 13:55:35.704 [info] == Running 20180515100237 Mobilizon.Repo.Migrations.CreateComments.change/0 forward
server # [  862.130885] mobilizon_ctl[1759]: 13:55:35.728 [info] create table comments
server # [  862.435419] mobilizon_ctl[1759]: 13:55:36.022 [info] == Migrated 20180515100237 in 0.3s
server # [  862.566958] mobilizon_ctl[1759]: 13:55:36.137 [info] == Running 20180515142835 Mobilizon.Repo.Migrations.AlterEventTimestampsToDateTimeWithTimeZone.up/0 forward
server # [  862.590024] mobilizon_ctl[1759]: 13:55:36.146 [info] alter table events
server # [  862.612701] mobilizon_ctl[1759]: 13:55:36.206 [info] == Migrated 20180515142835 in 0.0s
server # [  862.713879] mobilizon_ctl[1759]: 13:55:36.310 [info] == Running 20180515145438 Mobilizon.Repo.Migrations.AddLocalAttributeToEventsAndComments.up/0 forward
server # [  862.746557] mobilizon_ctl[1759]: 13:55:36.322 [info] alter table events
server # [  862.800908] mobilizon_ctl[1759]: 13:55:36.393 [info] alter table comments
server # [  862.837073] mobilizon_ctl[1759]: 13:55:36.430 [info] == Migrated 20180515145438 in 0.1s
server # [  862.957134] mobilizon_ctl[1759]: 13:55:36.554 [info] == Running 20180517100700 Mobilizon.Repo.Migrations.MoveFromAccountToActor.up/0 forward
server # [  863.145125] mobilizon_ctl[1759]: 13:55:36.729 [info] drop table event_requests
server # [  863.329312] mobilizon_ctl[1759]: 13:55:36.923 [info] drop table group_requests
server # [  863.466666] mobilizon_ctl[1759]: 13:55:37.061 [info] alter table events
server # [  863.594013] mobilizon_ctl[1759]: 13:55:37.144 [info] rename column account_id to actor_id on table members
server # [  863.610981] mobilizon_ctl[1759]: 13:55:37.177 [info] alter table members
server # [  863.802054] mobilizon_ctl[1759]: 13:55:37.394 [info] drop table groups
server # [  863.919580] mobilizon_ctl[1759]: 13:55:37.521 [info] rename table accounts to actors
server # [  863.985287] mobilizon_ctl[1759]: 13:55:37.570 [info] execute "CREATE TYPE public.actor_type AS ENUM ('Person', 'Application', 'Group', 'Organization', 'Service')"
server # [  864.077252] mobilizon_ctl[1759]: 13:55:37.668 [info] rename column username to name on table actors
server # [  864.136988] mobilizon_ctl[1759]: 13:55:37.703 [info] rename column description to summary on table actors
server # [  864.177305] mobilizon_ctl[1759]: 13:55:37.726 [info] rename column display_name to preferred_username on table actors
server # [  864.193518] mobilizon_ctl[1759]: 13:55:37.759 [info] alter table actors
server # [  864.397150] mobilizon_ctl[1759]: 13:55:37.985 [info] create index actors_preferred_username_domain_index
server # [  864.461047] mobilizon_ctl[1759]: 13:55:38.046 [info] rename column organizer_account_id to organizer_actor_id on table events
server # [  864.503588] mobilizon_ctl[1759]: 13:55:38.074 [info] rename column account_id to actor_id on table participants
server # [  864.529036] mobilizon_ctl[1759]: 13:55:38.102 [info] create table followers
server # [  864.809017] mobilizon_ctl[1759]: 13:55:38.321 [info] rename column account_id to actor_id on table comments
server # [  864.819769] mobilizon_ctl[1759]: 13:55:38.351 [info] rename column account_id to actor_id on table users
server # [  864.846388] mobilizon_ctl[1759]: 13:55:38.394 [info] == Migrated 20180517100700 in 1.8s
server # [  865.078031] mobilizon_ctl[1759]: 13:55:38.671 [info] == Running 20180522133844 Mobilizon.Repo.Migrations.AddBotsTable.up/0 forward
server # [  865.125150] mobilizon_ctl[1759]: 13:55:38.703 [info] create table bots
server # [  865.392897] mobilizon_ctl[1759]: 13:55:38.987 [info] == Migrated 20180522133844 in 0.3s
server # [  865.484747] mobilizon_ctl[1759]: 13:55:39.086 [info] == Running 20180530125159 Mobilizon.Repo.Migrations.AddAvatarAndBannerToActor.up/0 forward
server # [  865.515037] mobilizon_ctl[1759]: 13:55:39.101 [info] alter table actors
server # [  865.558018] mobilizon_ctl[1759]: 13:55:39.155 [info] == Migrated 20180530125159 in 0.0s
server # [  865.661833] mobilizon_ctl[1759]: 13:55:39.264 [info] == Running 20180530152323 Mobilizon.Repo.Migrations.MakeActorPublicKeyNullable.up/0 forward
server # [  865.684945] mobilizon_ctl[1759]: 13:55:39.276 [info] alter table actors
server # [  865.721164] mobilizon_ctl[1759]: 13:55:39.321 [info] == Migrated 20180530152323 in 0.0s
server # [  865.873825] mobilizon_ctl[1759]: 13:55:39.432 [info] == Running 20180530170808 Mobilizon.Repo.Migrations.AddUUIDIdToEvents.up/0 forward
server # [  865.883746] mobilizon_ctl[1759]: 13:55:39.441 [info] alter table events
server # [  865.898154] mobilizon_ctl[1759]: 13:55:39.485 [info] == Migrated 20180530170808 in 0.0s
server # [  865.978570] mobilizon_ctl[1759]: 13:55:39.584 [info] == Running 20180531162301 Mobilizon.Repo.Migrations.AddApprovedStatusToMember.up/0 forward
server # [  866.000014] mobilizon_ctl[1759]: 13:55:39.593 [info] alter table members
server # [  866.031218] mobilizon_ctl[1759]: 13:55:39.629 [info] == Migrated 20180531162301 in 0.0s
server # [  866.118556] mobilizon_ctl[1759]: 13:55:39.722 [info] == Running 20180607095732 Mobilizon.Repo.Migrations.AddUUIDToComments.up/0 forward
server # [  866.149683] mobilizon_ctl[1759]: 13:55:39.730 [info] alter table comments
server # [  866.178258] mobilizon_ctl[1759]: 13:55:39.760 [info] == Migrated 20180607095732 in 0.0s
server # [  866.241502] mobilizon_ctl[1759]: 13:55:39.850 [info] == Running 20180614094653 Mobilizon.Repo.Migrations.MakeSharedInboxUrlNullable.up/0 forward
server # [  866.269525] mobilizon_ctl[1759]: 13:55:39.869 [info] alter table actors
server # [  866.345028] mobilizon_ctl[1759]: 13:55:39.938 [info] == Migrated 20180614094653 in 0.0s
server # [  866.417522] mobilizon_ctl[1759]: 13:55:40.027 [info] == Running 20180614104618 Mobilizon.Repo.Migrations.FusionPublicPrivateKeyIntoKeysColumn.up/0 forward
server # [  866.453065] mobilizon_ctl[1759]: 13:55:40.048 [info] rename column private_key to keys on table actors
server # [  866.474840] mobilizon_ctl[1759]: 13:55:40.069 [info] alter table actors
server # [  866.512763] mobilizon_ctl[1759]: 13:55:40.102 [info] == Migrated 20180614104618 in 0.0s
server # [  866.596942] mobilizon_ctl[1759]: 13:55:40.198 [info] == Running 20180614131328 Mobilizon.Repo.Migrations.AddAttributedToFieldToEventsAndComments.up/0 forward
server # [  866.639771] mobilizon_ctl[1759]: 13:55:40.232 [info] alter table events
server # [  866.757727] mobilizon_ctl[1759]: 13:55:40.354 [info] alter table comments
server # [  866.878709] mobilizon_ctl[1759]: 13:55:40.445 [info] == Migrated 20180614131328 in 0.2s
server # [  866.961199] mobilizon_ctl[1759]: 13:55:40.562 [info] == Running 20180628091920 :"Elixir.Mobilizon.Repo.Migrations.Add-user-confirm-email-fields".up/0 forward
server # [  866.986704] mobilizon_ctl[1759]: 13:55:40.578 [info] alter table users
server # [  867.045778] mobilizon_ctl[1759]: 13:55:40.641 [info] create index index_unique_users_confirmation_token
server # [  867.103931] mobilizon_ctl[1759]: 13:55:40.694 [info] == Migrated 20180628091920 in 0.1s
server # [  867.205639] mobilizon_ctl[1759]: 13:55:40.806 [info] == Running 20180628130746 :"Elixir.Mobilizon.Repo.Migrations.Add-user-password-reset-fields".up/0 forward
server # [  867.223646] mobilizon_ctl[1759]: 13:55:40.818 [info] alter table users
server # [  867.280487] mobilizon_ctl[1759]: 13:55:40.880 [info] create index index_unique_users_reset_password_token
server # [  867.350749] mobilizon_ctl[1759]: 13:55:40.942 [info] == Migrated 20180628130746 in 0.1s
server # [  867.473002] mobilizon_ctl[1759]: 13:55:41.070 [info] == Running 20180628142835 Mobilizon.Repo.Migrations.RevertAlterEventTimestampsToDateTimeWithTimeZone.up/0 forward
server # [  867.494021] mobilizon_ctl[1759]: 13:55:41.081 [info] alter table events
server # [  867.526584] mobilizon_ctl[1759]: 13:55:41.122 [info] == Migrated 20180628142835 in 0.0s
server # [  867.649283] mobilizon_ctl[1759]: 13:55:41.239 [info] == Running 20180702150922 Mobilizon.Repo.Migrations.AddAddressType.up/0 forward
server # [  867.682964] mobilizon_ctl[1759]: 13:55:41.267 [info] alter table events
server # [  867.748541] mobilizon_ctl[1759]: 13:55:41.327 [info] drop constraint events_address_id_fkey from table events
server # [  867.818825] mobilizon_ctl[1759]: 13:55:41.400 [info] rename column address_id to physical_address_id on table events
server # [  867.834966] mobilizon_ctl[1759]: 13:55:41.416 [info] alter table events
server # [  867.961583] mobilizon_ctl[1759]: 13:55:41.516 [info] == Migrated 20180702150922 in 0.2s
server # [  868.019724] mobilizon_ctl[1759]: 13:55:41.619 [info] == Running 20180702154630 Mobilizon.Repo.Migrations.RemoveSlugForEvent.up/0 forward
server # [  868.038830] mobilizon_ctl[1759]: 13:55:41.627 [info] alter table events
server # [  868.081806] mobilizon_ctl[1759]: 13:55:41.681 [info] == Migrated 20180702154630 in 0.0s
server # [  868.214741] mobilizon_ctl[1759]: 13:55:41.818 [info] == Running 20180706082208 Mobilizon.Repo.Migrations.AlterEventsSetDescriptionAsText.up/0 forward
server # [  868.261134] mobilizon_ctl[1759]: 13:55:41.844 [info] alter table events
server # [  868.290837] mobilizon_ctl[1759]: 13:55:41.878 [info] == Migrated 20180706082208 in 0.0s
server # [  868.411331] mobilizon_ctl[1759]: 13:55:42.007 [info] == Running 20180709154152 Mobilizon.Repo.Migrations.AllowMultipleAccountsForUser.up/0 forward
server # [  868.440824] mobilizon_ctl[1759]: 13:55:42.023 [info] alter table actors
server # [  868.601781] mobilizon_ctl[1759]: 13:55:42.173 [info] alter table users
server # [  868.677416] mobilizon_ctl[1759]: 13:55:42.261 [info] == Migrated 20180709154152 in 0.2s
server # [  868.781441] mobilizon_ctl[1759]: 13:55:42.383 [info] == Running 20180802133951 Mobilizon.Repo.Migrations.MakeFollowerTableUnique.up/0 forward
server # [  868.823496] mobilizon_ctl[1759]: 13:55:42.387 [info] create index followers_actor_target_actor_unique_index
server # [  868.905226] mobilizon_ctl[1759]: 13:55:42.482 [info] == Migrated 20180802133951 in 0.0s
server # [  869.063976] mobilizon_ctl[1759]: 13:55:42.617 [info] == Running 20180816093446 Mobilizon.Repo.Migrations.AddPrimaryKeyToMember.up/0 forward
server # [  869.079296] mobilizon_ctl[1759]: 13:55:42.646 [info] execute "ALTER TABLE members DROP CONSTRAINT IF EXISTS members_pkey"
server # [  869.096046] mobilizon_ctl[1759]: 13:55:42.685 [info] constraint "members_pkey" of relation "members" does not exist, skipping
server # [  869.144003] mobilizon_ctl[1759]: 13:55:42.686 [info] drop index if exists members_members_account_id_index_index
server # [  869.158541] mobilizon_ctl[1759]: 13:55:42.734 [info] index "members_members_account_id_index_index" does not exist, skipping
server # [  869.177992] mobilizon_ctl[1759]: 13:55:42.734 [info] create index members_actor_parent_unique_index
server # [  869.244766] mobilizon_ctl[1759]: 13:55:42.838 [info] alter table members
server # [  869.523461] mobilizon_ctl[1759]: 13:55:43.099 [info] == Migrated 20180816093446 in 0.4s
server # [  869.665180] mobilizon_ctl[1759]: 13:55:43.270 [info] == Running 20181021161324 Mobilizon.Repo.Migrations.AddDefaultActorUserField.up/0 forward
server # [  869.694998] mobilizon_ctl[1759]: 13:55:43.288 [info] alter table users
server # [  869.739307] mobilizon_ctl[1759]: 13:55:43.327 [info] == Migrated 20181021161324 in 0.0s
server # [  869.850200] mobilizon_ctl[1759]: 13:55:43.449 [info] == Running 20181108165151 Mobilizon.Repo.Migrations.FixCommentsReferences.up/0 forward
server # [  869.893131] mobilizon_ctl[1759]: 13:55:43.473 [info] drop constraint comments_in_reply_to_comment_id_fkey from table comments
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  869.965228] mobilizon_ctl[1759]: 13:55:43.550 [info] drop constraint comments_origin_comment_id_fkey from table comments
server # [  870.079012] mobilizon_ctl[1759]: 13:55:43.618 [info] alter table comments
server # [  870.204927] mobilizon_ctl[1759]: 13:55:43.796 [info] == Migrated 20181108165151 in 0.3s
server # [  870.353619] mobilizon_ctl[1759]: 13:55:43.946 [info] == Running 20181203092128 Mobilizon.Repo.Migrations.ChangeActorsIndexes.up/0 forward
server # [  870.387233] mobilizon_ctl[1759]: 13:55:43.976 [info] drop index actors_preferred_username_domain_index
server # [  870.424537] mobilizon_ctl[1759]: 13:55:44.017 [info] drop index accounts_username_domain_index
server # [  870.471774] mobilizon_ctl[1759]: 13:55:44.054 [info] execute "ALTER INDEX accounts_pkey RENAME TO actors_pkey"
server # [  870.513198] mobilizon_ctl[1759]: 13:55:44.102 [info] create index actors_preferred_username_domain_type_index
server # [  870.579058] mobilizon_ctl[1759]: 13:55:44.178 [info] create index actors_url_index
server # [  870.685619] mobilizon_ctl[1759]: 13:55:44.261 [info] == Migrated 20181203092128 in 0.3s
server # [  870.839948] mobilizon_ctl[1759]: 13:55:44.398 [info] == Running 20190103150805 Mobilizon.Repo.Migrations.FixEventVisibility.up/0 forward
server # [  870.876917] mobilizon_ctl[1759]: 13:55:44.446 [info] execute "CREATE TYPE public.event_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"
server # [  870.940932] mobilizon_ctl[1759]: 13:55:44.514 [info] execute "CREATE TYPE public.event_status AS ENUM ('tentative', 'confirmed', 'cancelled')"
server # [  870.964880] mobilizon_ctl[1759]: 13:55:44.538 [info] execute "CREATE TYPE public.comment_visibility AS ENUM ('public', 'unlisted', 'private', 'moderated', 'invite')"
server # [  870.982224] mobilizon_ctl[1759]: 13:55:44.554 [info] alter table events
server # [  871.066215] mobilizon_ctl[1759]: 13:55:44.637 [info] alter table comments
server # [  871.097232] mobilizon_ctl[1759]: 13:55:44.677 [info] == Migrated 20190103150805 in 0.2s
server # [  871.201345] mobilizon_ctl[1759]: 13:55:44.799 [info] == Running 20190114162055 Mobilizon.Repo.Migrations.RemoveAddressType.up/0 forward
server # [  871.235813] mobilizon_ctl[1759]: 13:55:44.809 [info] execute "ALTER TABLE \"events\" DROP COLUMN IF EXISTS address_type"
server # [  871.268868] mobilizon_ctl[1759]: 13:55:44.835 [info] column "address_type" of relation "events" does not exist, skipping
server # [  871.290987] mobilizon_ctl[1759]: 13:55:44.841 [info] execute "DROP TYPE IF EXISTS address_type"
server # [  871.315425] mobilizon_ctl[1759]: 13:55:44.875 [info] type "address_type" does not exist, skipping
server # [  871.325855] mobilizon_ctl[1759]: 13:55:44.876 [info] rename column phone to phone_address on table events
server # [  871.345288] mobilizon_ctl[1759]: 13:55:44.900 [info] == Migrated 20190114162055 in 0.0s
server # [  871.424943] mobilizon_ctl[1759]: 13:55:45.026 [info] == Running 20190125143718 Mobilizon.Repo.Migrations.GroupDeleteMembersCascade.change/0 forward
server # [  871.444953] mobilizon_ctl[1759]: 13:55:45.042 [info] drop constraint members_account_id_fkey from table members
server # [  871.499118] mobilizon_ctl[1759]: 13:55:45.101 [info] drop constraint members_parent_id_fkey from table members
server # [  871.552823] mobilizon_ctl[1759]: 13:55:45.150 [info] alter table members
server # [  871.770055] mobilizon_ctl[1759]: 13:55:45.368 [info] == Migrated 20190125143718 in 0.3s
server # [  871.853802] mobilizon_ctl[1759]: 13:55:45.457 [info] == Running 20190130151607 Mobilizon.Repo.Migrations.SplitEventVisibilityAndJoinOptions.up/0 forward
server # [  871.880476] mobilizon_ctl[1759]: 13:55:45.480 [info] execute "ALTER TABLE events ALTER COLUMN visibility TYPE VARCHAR USING visibility::text"
server # [  872.210960] mobilizon_ctl[1759]: 13:55:45.753 [info] execute "DROP TYPE public.event_visibility"
server # [  872.236772] mobilizon_ctl[1759]: 13:55:45.790 [info] execute "CREATE TYPE public.event_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"
server # [  872.271070] mobilizon_ctl[1759]: 13:55:45.850 [info] execute "ALTER TABLE events ALTER COLUMN visibility TYPE event_visibility USING visibility::event_visibility"
server # [  872.529845] mobilizon_ctl[1759]: 13:55:46.102 [info] execute "CREATE TYPE public.join_options AS ENUM ('free', 'restricted', 'invite', 'external')"
server # [  872.543545] mobilizon_ctl[1759]: 13:55:46.126 [info] alter table events
server # [  872.611425] mobilizon_ctl[1759]: 13:55:46.202 [info] execute "UPDATE events SET visibility = 'public' WHERE visibility IS NULL"
server # [  872.655463] mobilizon_ctl[1759]: 13:55:46.254 [info] alter table events
server # [  872.725587] mobilizon_ctl[1759]: 13:55:46.315 [info] == Migrated 20190130151607 in 0.8s
server # [  872.910220] mobilizon_ctl[1759]: 13:55:46.505 [info] == Running 20190207134142 Mobilizon.Repo.Migrations.MoveParticipantRoleToEnum.up/0 forward
server # [  872.944956] mobilizon_ctl[1759]: 13:55:46.533 [info] execute "CREATE TYPE public.participant_role AS ENUM ('not_approved', 'not_confirmed', 'rejected', 'participant', 'moderator', 'administrator', 'creator')"
server # [  872.989137] mobilizon_ctl[1759]: 13:55:46.561 [info] alter table participants
server # [  873.036941] mobilizon_ctl[1759]: 13:55:46.638 [info] execute "UPDATE participants set role_tmp = 'not_approved' where role = 0"
server # [  873.113196] mobilizon_ctl[1759]: 13:55:46.702 [info] execute "UPDATE participants set role_tmp = 'participant' where role = 1"
server # [  873.211532] mobilizon_ctl[1759]: 13:55:46.736 [info] execute "UPDATE participants set role_tmp = 'moderator' where role = 2"
server # [  873.238393] mobilizon_ctl[1759]: 13:55:46.760 [info] execute "UPDATE participants set role_tmp = 'administrator' where role = 3"
server # [  873.254669] mobilizon_ctl[1759]: 13:55:46.781 [info] execute "UPDATE participants set role_tmp = 'creator' where role = 4"
server # [  873.299795] mobilizon_ctl[1759]: 13:55:46.821 [info] alter table participants
server # [  873.328916] mobilizon_ctl[1759]: 13:55:46.877 [info] rename column role_tmp to role on table participants
server # [  873.357308] mobilizon_ctl[1759]: 13:55:46.898 [info] == Migrated 20190207134142 in 0.3s
server # [  873.433808] mobilizon_ctl[1759]: 13:55:47.027 [info] == Running 20190208141700 Mobilizon.Repo.Migrations.TagsRelations.up/0 forward
server # [  873.472780] mobilizon_ctl[1759]: 13:55:47.061 [info] create table tag_relations
server # [  873.647034] mobilizon_ctl[1759]: 13:55:47.230 [info] create check constraint no_self_loops_check on table tag_relations
server # [  873.699279] mobilizon_ctl[1759]: 13:55:47.286 [info] create index index_tag_relations_tag_id
server # [  873.770449] mobilizon_ctl[1759]: 13:55:47.366 [info] create index index_tag_relations_link_id
server # [  873.872831] mobilizon_ctl[1759]: 13:55:47.465 [info] == Migrated 20190208141700 in 0.4s
server # [  873.971436] mobilizon_ctl[1759]: 13:55:47.574 [info] == Running 20190214100858 Mobilizon.Repo.Migrations.DropDatetimetz.up/0 forward
server # [  874.017685] mobilizon_ctl[1759]: 13:55:47.603 [info] alter table events
server # [  874.105696] mobilizon_ctl[1759]: 13:55:47.695 [info] alter table sessions
server # [  874.237399] mobilizon_ctl[1759]: 13:55:47.775 [info] execute "DROP TYPE datetimetz"
server # [  874.258263] mobilizon_ctl[1759]: 13:55:47.831 [info] == Migrated 20190214100858 in 0.2s
server # [  874.361455] mobilizon_ctl[1759]:     #########################################################
server # [  874.374258] mobilizon_ctl[1759]:     # If the CREATE EXTENSION or DROP EXTENSION calls fail, #
server # [  874.381690] mobilizon_ctl[1759]:     # please manually execute them with an authorized       #
server # [  874.394180] mobilizon_ctl[1759]:     # PostgreSQL user with SUPER USER role.                 #
server # [  874.406134] mobilizon_ctl[1759]:     #########################################################
server # [  874.417866] mobilizon_ctl[1759]:
server # [  874.430750] mobilizon_ctl[1759]:
server # [  874.442935] mobilizon_ctl[1759]: 13:55:47.973 [info] == Running 20190219164310 Mobilizon.Repo.Migrations.CreateSearchIndexes.change/0 forward
server # [  874.461658] mobilizon_ctl[1759]: 13:55:47.990 [info] execute "CREATE EXTENSION IF NOT EXISTS pg_trgm"
server # [  874.476978] mobilizon_ctl[1759]: 13:55:48.041 [info] extension "pg_trgm" already exists, skipping
server # [  874.531173] mobilizon_ctl[1759]: 13:55:48.042 [info] execute "CREATE EXTENSION IF NOT EXISTS unaccent"
server # [  874.550016] mobilizon_ctl[1759]: 13:55:48.102 [info] extension "unaccent" already exists, skipping
server # [  874.566021] mobilizon_ctl[1759]: 13:55:48.111 [info] execute "CREATE OR REPLACE FUNCTION public.f_unaccent(text)\n  RETURNS text AS\n$func$\nSELECT public.unaccent('public.unaccent', $1)\n$func$  LANGUAGE sql IMMUTABLE;"
server # [  874.601075] mobilizon_ctl[1759]: 13:55:48.180 [info] execute "CREATE INDEX \"event_title_trigram\" ON \"events\" USING GIN (f_unaccent(title) gin_trgm_ops)"
server # [  874.757840] mobilizon_ctl[1759]: 13:55:48.359 [info] execute "CREATE INDEX \"actor_preferred_username_trigram\" ON \"actors\"\n    USING GIN (f_unaccent(preferred_username) gin_trgm_ops)"
server # [  874.833643] mobilizon_ctl[1759]: 13:55:48.428 [info] execute "CREATE INDEX \"actor_name_trigram\" ON \"actors\"\n    USING GIN (f_unaccent(name) gin_trgm_ops)"
server # [  874.896929] mobilizon_ctl[1759]: 13:55:48.495 [info] == Migrated 20190219164310 in 0.5s
server # [  874.994658] mobilizon_ctl[1759]: 13:55:48.597 [info] == Running 20190222151449 Mobilizon.Repo.Migrations.DropCategories.up/0 forward
server # [  875.009011] mobilizon_ctl[1759]: 13:55:48.606 [info] alter table events
server # [  875.066478] mobilizon_ctl[1759]: 13:55:48.673 [info] drop table categories
server # [  875.144819] mobilizon_ctl[1759]: 13:55:48.742 [info] == Migrated 20190222151449 in 0.1s
server # [  875.307951] mobilizon_ctl[1759]: 13:55:48.874 [info] == Running 20190301141830 Mobilizon.Repo.Migrations.MoveMemberRoleToEnum.up/0 forward
server # [  875.331911] mobilizon_ctl[1759]: 13:55:48.905 [info] execute "CREATE TYPE public.member_role AS ENUM ('invited', 'not_approved', 'member', 'moderator', 'administrator', 'creator', 'rejected')"
server # [  875.357780] mobilizon_ctl[1759]: 13:55:48.937 [info] alter table members
server # [  875.379766] mobilizon_ctl[1759]: 13:55:48.989 [info] execute "UPDATE members set role_tmp = 'member' where role = 0"
server # [  875.430578] mobilizon_ctl[1759]: 13:55:49.035 [info] execute "UPDATE members set role_tmp = 'moderator' where role = 1"
server # [  875.465998] mobilizon_ctl[1759]: 13:55:49.072 [info] execute "UPDATE members set role_tmp = 'creator' where role = 2"
server # [  875.500844] mobilizon_ctl[1759]: 13:55:49.102 [info] execute "UPDATE members set role_tmp = 'not_approved' where approved is false"
server # [  875.535448] mobilizon_ctl[1759]: 13:55:49.129 [info] alter table members
server # [  875.587416] mobilizon_ctl[1759]: 13:55:49.177 [info] rename column role_tmp to role on table members
server # [  875.604814] mobilizon_ctl[1759]: 13:55:49.191 [info] == Migrated 20190301141830 in 0.3s
server # [  875.679683] mobilizon_ctl[1759]: 13:55:49.289 [info] == Running 20190301143831 Mobilizon.Repo.Migrations.ActorGroupOpenness.up/0 forward
server # [  875.807821] mobilizon_ctl[1759]: 13:55:49.381 [info] execute "CREATE TYPE public.actor_openness AS ENUM ('invite_only', 'moderated', 'open')"
server # [  875.829197] mobilizon_ctl[1759]: 13:55:49.405 [info] alter table actors
server # [  875.905182] mobilizon_ctl[1759]: 13:55:49.487 [info] == Migrated 20190301143831 in 0.1s
server # [  876.025046] mobilizon_ctl[1759]: 13:55:49.618 [info] == Running 20190307125009 Mobilizon.Repo.Migrations.MoveUserRoleToEnum.up/0 forward
server # [  876.092943] mobilizon_ctl[1759]: 13:55:49.643 [info] execute "CREATE TYPE public.user_role AS ENUM ('administrator', 'moderator', 'user', 'pending')"
server # [  876.103023] mobilizon_ctl[1759]: 13:55:49.673 [info] alter table users
server # [  876.163382] mobilizon_ctl[1759]: 13:55:49.749 [info] execute "UPDATE users set role_tmp = 'user' where role = 0"
server # [  876.244890] mobilizon_ctl[1759]: 13:55:49.817 [info] execute "UPDATE users set role_tmp = 'moderator' where role = 1"
server # [  876.271502] mobilizon_ctl[1759]: 13:55:49.845 [info] execute "UPDATE users set role_tmp = 'administrator' where role = 2"
server # [  876.362320] mobilizon_ctl[1759]: 13:55:49.889 [info] alter table users
server # [  876.375021] mobilizon_ctl[1759]: 13:55:49.945 [info] rename column role_tmp to role on table users
server # [  876.400711] mobilizon_ctl[1759]: 13:55:49.970 [info] == Migrated 20190307125009 in 0.3s
server # [  876.498403] mobilizon_ctl[1759]: 13:55:50.102 [info] == Running 20190307133518 Mobilizon.Repo.Migrations.FeedTokenTable.change/0 forward
server # [  876.737792] mobilizon_ctl[1759]: 13:55:50.329 [info] create table feed_tokens
server # [  876.886971] mobilizon_ctl[1759]: 13:55:50.477 [info] == Migrated 20190307133518 in 0.3s
server # [  876.977060] mobilizon_ctl[1759]: 13:55:50.572 [info] == Running 20190322143724 Mobilizon.Repo.Migrations.RenameAddressFields.change/0 forward
server # [  877.009458] mobilizon_ctl[1759]: 13:55:50.582 [info] rename column addressCountry to country on table addresses
server # [  877.052935] mobilizon_ctl[1759]: 13:55:50.637 [info] rename column addressLocality to locality on table addresses
server # [  877.063066] mobilizon_ctl[1759]: 13:55:50.657 [info] rename column addressRegion to region on table addresses
server # [  877.116853] mobilizon_ctl[1759]: 13:55:50.693 [info] rename column postalCode to postal_code on table addresses
server # [  877.148796] mobilizon_ctl[1759]: 13:55:50.711 [info] rename column streetAddress to street on table addresses
server # [  877.157931] mobilizon_ctl[1759]: 13:55:50.734 [info] == Migrated 20190322143724 in 0.1s
server # [  877.239281] mobilizon_ctl[1759]: 13:55:50.838 [info] == Running 20190411124138 Mobilizon.Repo.Migrations.EventAddDescriptionSlug.change/0 forward
server # [  877.262163] mobilizon_ctl[1759]: 13:55:50.851 [info] alter table events
server # [  877.301655] mobilizon_ctl[1759]: 13:55:50.894 [info] == Migrated 20190411124138 in 0.0s
server # [  877.455707] mobilizon_ctl[1759]: 13:55:51.023 [info] == Running 20190411161557 Mobilizon.Repo.Migrations.EventEventTagOnDelete.up/0 forward
server # [  877.481009] mobilizon_ctl[1759]: 13:55:51.034 [info] drop constraint events_tags_event_id_fkey from table events_tags
server # [  877.503805] mobilizon_ctl[1759]: 13:55:51.097 [info] drop constraint events_tags_tag_id_fkey from table events_tags
server # [  877.554464] mobilizon_ctl[1759]: 13:55:51.151 [info] alter table events_tags
server # [  877.693226] mobilizon_ctl[1759]: 13:55:51.286 [info] == Migrated 20190411161557 in 0.2s
server # [  877.805405] mobilizon_ctl[1759]: 13:55:51.401 [info] == Running 20190425075451 Mobilizon.Repo.Migrations.AddVisibilityToActor.up/0 forward
server # [  877.818451] mobilizon_ctl[1759]: 13:55:51.414 [info] execute "CREATE TYPE public.actor_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"
server # [  877.859146] mobilizon_ctl[1759]: 13:55:51.462 [info] alter table actors
server # [  877.937391] mobilizon_ctl[1759]: 13:55:51.525 [info] == Migrated 20190425075451 in 0.1s
server # [  878.043158] mobilizon_ctl[1759]: 13:55:51.642 [info] == Running 20190426093202 Mobilizon.Repo.Migrations.CreatePictures.up/0 forward
server # [  878.086186] mobilizon_ctl[1759]: 13:55:51.683 [info] create table pictures
server # [  878.284778] mobilizon_ctl[1759]: 13:55:51.888 [info] alter table actors
server # [  878.367839] mobilizon_ctl[1759]: 13:55:51.958 [info] alter table events
server # [  878.559511] mobilizon_ctl[1759]: 13:55:52.100 [info] == Migrated 20190426093202 in 0.4s
server # [  878.619118] mobilizon_ctl[1759]: 13:55:52.223 [info] == Running 20190531131458 :"Elixir.Mobilizon.Repo.Migrations.Attach-pictures-to-actors".change/0 forward
server # [  878.658737] mobilizon_ctl[1759]: 13:55:52.246 [info] alter table pictures
server # [  878.797757] mobilizon_ctl[1759]: 13:55:52.384 [info] == Migrated 20190531131458 in 0.1s
server # [  878.906412] mobilizon_ctl[1759]: 13:55:52.499 [info] == Running 20190712125833 Mobilizon.Repo.Migrations.CreateReports.up/0 forward
server # [  879.098939] mobilizon_ctl[1759]: 13:55:52.658 [info] execute "CREATE TYPE public.report_status AS ENUM ('open', 'closed', 'resolved')"
server # [  879.110495] mobilizon_ctl[1759]: 13:55:52.694 [info] create table reports
server # [  879.470979] mobilizon_ctl[1759]: 13:55:53.058 [info] create table reports_comments
server # [  879.682949] mobilizon_ctl[1759]: 13:55:53.193 [info] == Migrated 20190712125833 in 0.6s
server # [  879.753574] mobilizon_ctl[1759]: 13:55:53.350 [info] == Running 20190712132438 Mobilizon.Repo.Migrations.CreateReportNotes.change/0 forward
server # [  879.779933] mobilizon_ctl[1759]: 13:55:53.370 [info] create table report_notes
server # [  880.003284] mobilizon_ctl[1759]: 13:55:53.590 [info] == Migrated 20190712132438 in 0.2s
server # [  880.147698] mobilizon_ctl[1759]: 13:55:53.744 [info] == Running 20190718122556 Mobilizon.Repo.Migrations.CreateAdminActionLogs.change/0 forward
server # [  880.177944] mobilizon_ctl[1759]: 13:55:53.768 [info] create table admin_action_logs
server # [  880.380844] mobilizon_ctl[1759]: 13:55:53.974 [info] == Migrated 20190718122556 in 0.2s
server # [  880.466943] mobilizon_ctl[1759]: 13:55:54.070 [info] == Running 20190729125532 :"Elixir.Mobilizon.Repo.Migrations.Add-url-to-addresses".change/0 forward
server # [  880.493033] mobilizon_ctl[1759]: 13:55:54.078 [info] alter table addresses
server # [  880.523500] mobilizon_ctl[1759]: 13:55:54.122 [info] == Migrated 20190729125532 in 0.0s
server # [  880.618959] mobilizon_ctl[1759]: 13:55:54.215 [info] == Running 20190806131306 Mobilizon.Repo.Migrations.AddUrlToFollowsAndMoveToUuid.up/0 forward
server # [  880.693855] mobilizon_ctl[1759]: 13:55:54.230 [info] alter table followers
server # [  880.819383] mobilizon_ctl[1759]: 13:55:54.413 [info] == Migrated 20190806131306 in 0.1s
server # [  880.937078] mobilizon_ctl[1759]: 13:55:54.534 [info] == Running 20190813160207 Mobilizon.Repo.Migrations.AddUrlAndUuidToParticipants.up/0 forward
server # [  880.978256] mobilizon_ctl[1759]: 13:55:54.574 [info] drop index participants_event_id_index
server # [  881.009055] mobilizon_ctl[1759]: 13:55:54.610 [info] drop index if exists participants_account_id_index
server # [  881.047402] mobilizon_ctl[1759]: 13:55:54.637 [info] drop index if exists participants_actor_id_index
server # [  881.063252] mobilizon_ctl[1759]: 13:55:54.654 [info] index "participants_actor_id_index" does not exist, skipping
server # [  881.089502] mobilizon_ctl[1759]: 13:55:54.654 [info] drop constraint participants_event_id_fkey from table participants
server # [  881.122268] mobilizon_ctl[1759]: 13:55:54.726 [info] drop constraint if exists participants_account_id_fkey from table participants
server # [  881.188842] mobilizon_ctl[1759]: 13:55:54.790 [info] drop constraint if exists participants_actor_id_fkey from table participants
server # [  881.227218] mobilizon_ctl[1759]: 13:55:54.822 [info] constraint "participants_actor_id_fkey" of relation "participants" does not exist, skipping
server # [  881.243990] mobilizon_ctl[1759]: 13:55:54.823 [info] drop constraint participants_pkey from table participants
server # [  881.287433] mobilizon_ctl[1759]: 13:55:54.869 [info] alter table participants
server # [  881.538452] mobilizon_ctl[1759]: 13:55:55.133 [info] create index participants_event_id_index
server # [  881.605154] mobilizon_ctl[1759]: 13:55:55.206 [info] create index participants_actor_id_index
server # [  881.720016] mobilizon_ctl[1759]: 13:55:55.273 [info] == Migrated 20190813160207 in 0.7s
server # [  881.825953] mobilizon_ctl[1759]: 13:55:55.426 [info] == Running 20190821161658 Mobilizon.Repo.Migrations.AddOriginIdToAddresses.change/0 forward
server # [  881.871486] mobilizon_ctl[1759]: 13:55:55.450 [info] alter table addresses
server # [  881.919626] mobilizon_ctl[1759]: 13:55:55.514 [info] create index addresses_origin_id_index
server # [  882.005851] mobilizon_ctl[1759]: 13:55:55.589 [info] == Migrated 20190821161658 in 0.1s
server # [  882.093949] mobilizon_ctl[1759]: 13:55:55.697 [info] == Running 20190823132816 Mobilizon.Repo.Migrations.RemoveEventsWhenDeletingOrganizer.up/0 forward
server # [  882.130633] mobilizon_ctl[1759]: 13:55:55.726 [info] drop constraint events_organizer_account_id_fkey from table events
server # [  882.198917] mobilizon_ctl[1759]: 13:55:55.794 [info] alter table events
server # [  882.370629] mobilizon_ctl[1759]: 13:55:55.950 [info] == Migrated 20190823132816 in 0.2s
server # [  882.465250] mobilizon_ctl[1759]: 13:55:56.066 [info] == Running 20190902082656 Mobilizon.Repo.Migrations.AddOptionsToEvent.change/0 forward
server # [  882.511567] mobilizon_ctl[1759]: 13:55:56.092 [info] alter table events
server # [  882.547380] mobilizon_ctl[1759]: 13:55:56.133 [info] == Migrated 20190902082656 in 0.0s
server # [  882.727422] mobilizon_ctl[1759]: 13:55:56.261 [info] == Running 20190929170817 Mobilizon.Storage.Repo.Migrations.RenamePostgresTypes.up/0 forward
server # [  883.017125] mobilizon_ctl[1759]: 13:55:56.602 [info] == Migrated 20190929170817 in 0.3s
server # [  883.130470] mobilizon_ctl[1759]: 13:55:56.722 [info] == Running 20190929175229 Mobilizon.Storage.Repo.Migrations.AddRejectedToParticipantRole.up/0 forward
server # [  883.156831] mobilizon_ctl[1759]: 13:55:56.731 [info] execute "ALTER TYPE public.participant_role ADD VALUE IF NOT EXISTS 'rejected'"
server # [  883.179536] mobilizon_ctl[1759]: 13:55:56.773 [info] enum label "rejected" already exists, skipping
server # [  883.195051] mobilizon_ctl[1759]: 13:55:56.774 [info] == Migrated 20190929175229 in 0.0s
server # [  883.311527] mobilizon_ctl[1759]: 13:55:56.902 [info] == Running 20191001074442 Mobilizon.Storage.Repo.Migrations.AddLocaleToUsers.change/0 forward
server # [  883.335358] mobilizon_ctl[1759]: 13:55:56.918 [info] alter table users
server # [  883.387516] mobilizon_ctl[1759]: 13:55:56.978 [info] == Migrated 20191001074442 in 0.0s
server # [  883.501311] mobilizon_ctl[1759]: 13:55:57.101 [info] == Running 20191002093208 Mobilizon.Storage.Repo.Migrations.AddDraftFlagToEvents.change/0 forward
server # [  883.537227] mobilizon_ctl[1759]: 13:55:57.126 [info] alter table events
server # [  883.599691] mobilizon_ctl[1759]: 13:55:57.191 [info] == Migrated 20191002093208 in 0.0s
server # [  883.759486] mobilizon_ctl[1759]: 13:55:57.327 [info] == Running 20191018075904 Mobilizon.Storage.Repo.Migrations.MoveSQLColumnsFromVarCharToText.up/0 forward
server # [  883.796019] mobilizon_ctl[1759]: 13:55:57.359 [info] alter table events
server # [  883.972817] mobilizon_ctl[1759]: 13:55:57.572 [info] alter table addresses
server # [  883.997838] mobilizon_ctl[1759]: 13:55:57.607 [info] alter table bots
server # [  884.041792] mobilizon_ctl[1759]: 13:55:57.642 [info] alter table report_notes
server # [  884.140783] mobilizon_ctl[1759]: 13:55:57.721 [info] alter table reports
server # [  884.169624] mobilizon_ctl[1759]: 13:55:57.763 [info] alter table sessions
server # [  884.230743] mobilizon_ctl[1759]: 13:55:57.828 [info] alter table tracks
server # [  884.265918] mobilizon_ctl[1759]: 13:55:57.863 [info] == Migrated 20191018075904 in 0.5s
server # [  884.377395] mobilizon_ctl[1759]: 13:55:57.982 [info] == Running 20191022083446 Mobilizon.Storage.Repo.Migrations.AddEagerMaterializedViewForSearchingEvents.up/0 forward
server # [  884.411467] mobilizon_ctl[1759]: 13:55:58.003 [info] create table event_search
server # [  884.556566] mobilizon_ctl[1759]: 13:55:58.152 [info] create index event_search_document_index
server # [  884.608877] mobilizon_ctl[1759]: 13:55:58.212 [info] execute "CREATE INDEX event_search_title_trgm_index ON event_search USING gin (title gin_trgm_ops)"
server # [  884.664836] mobilizon_ctl[1759]: 13:55:58.266 [info] create index event_search_id_index
server # [  884.771819] mobilizon_ctl[1759]: 13:55:58.317 [info] == Migrated 20191022083446 in 0.3s
server # [  884.829663] mobilizon_ctl[1759]: 13:55:58.430 [info] == Running 20191023133805 Mobilizon.Storage.Repo.Migrations.DeleteActorsCascadeToFollowers.up/0 forward
server # [  884.857799] mobilizon_ctl[1759]: 13:55:58.458 [info] drop constraint followers_actor_id_fkey from table followers
server # [  884.910225] mobilizon_ctl[1759]: 13:55:58.505 [info] drop constraint followers_target_actor_id_fkey from table followers
server # [  884.955596] mobilizon_ctl[1759]: 13:55:58.557 [info] alter table followers
server # [  885.135790] mobilizon_ctl[1759]: 13:55:58.726 [info] == Migrated 20191023133805 in 0.2s
server # [  885.237220] mobilizon_ctl[1759]: 13:55:58.841 [info] == Running 20191023143659 Mobilizon.Storage.Repo.Migrations.MoveParticipantsStatsToEvent.up/0 forward
server # [  885.251030] mobilizon_ctl[1759]: 13:55:58.850 [info] alter table events
server # [  885.304902] mobilizon_ctl[1759]: 13:55:58.893 [info] == Migrated 20191023143659 in 0.0s
server # [  885.398066] mobilizon_ctl[1759]: 13:55:59.008 [info] == Running 20191024204726 Mobilizon.Storage.Repo.Migrations.AddTagsToComments.up/0 forward
server # [  885.441257] mobilizon_ctl[1759]: 13:55:59.037 [info] create table comments_tags
server # [  885.583347] mobilizon_ctl[1759]: 13:55:59.167 [info] == Migrated 20191024204726 in 0.1s
server # [  885.682260] mobilizon_ctl[1759]: 13:55:59.286 [info] == Running 20191025083642 Mobilizon.Storage.Repo.Migrations.AddMentionTables.change/0 forward
server # [  885.716854] mobilizon_ctl[1759]: 13:55:59.318 [info] create table mentions
server # [  885.991819] mobilizon_ctl[1759]: 13:55:59.538 [info] create index mentions_actor_id_index
server # [  886.021447] mobilizon_ctl[1759]: 13:55:59.599 [info] == Migrated 20191025083642 in 0.3s
server # [  886.117258] mobilizon_ctl[1759]: 13:55:59.716 [info] == Running 20191025115537 Mobilizon.Storage.Repo.Migrations.AddUniqueIndexOnURLs.change/0 forward
server # [  886.144062] mobilizon_ctl[1759]: 13:55:59.735 [info] create index events_url_index
server # [  886.227070] mobilizon_ctl[1759]: 13:55:59.810 [info] create index comments_url_index
server # [  886.287469] mobilizon_ctl[1759]: 13:55:59.879 [info] == Migrated 20191025115537 in 0.1s
server # [  886.383115] mobilizon_ctl[1759]: 13:55:59.992 [info] == Running 20191031142145 Mobilizon.Storage.Repo.Migrations.AddUniqueIndexOnActorAndEventForParticipant.change/0 forward
server # [  886.410691] mobilizon_ctl[1759]: 13:56:00.005 [info] create index participants_event_id_actor_id_index
server # [  886.468966] mobilizon_ctl[1759]: 13:56:00.062 [info] == Migrated 20191031142145 in 0.0s
server # [  886.549447] mobilizon_ctl[1759]: 13:56:00.154 [info] == Running 20191104093447 Mobilizon.Storage.Repo.Migrations.AddObanJobsTable.up/0 forward
server # [  888.698887] mobilizon_ctl[1759]: 13:56:02.217 [info] execute "DO $$\nBEGIN\nIF NOT EXISTS (SELECT 1 FROM pg_type\n               WHERE typname = 'oban_job_state'\n                 AND typnamespace = 'public'::regnamespace::oid) THEN\n    CREATE TYPE \"public\".oban_job_state AS ENUM (\n      'available',\n      'scheduled',\n      'executing',\n      'retryable',\n      'completed',\n      'discarded'\n    );\n  END IF;\nEND$$;\n"
server # [  888.767542] mobilizon_ctl[1759]: 13:56:02.355 [info] create table if not exists public.oban_jobs
server # [  889.050193] mobilizon_ctl[1759]: 13:56:02.626 [info] create index if not exists public.oban_jobs_queue_index
server # [  889.121154] mobilizon_ctl[1759]: 13:56:02.718 [info] create index if not exists public.oban_jobs_state_index
server # [  889.233798] mobilizon_ctl[1759]: 13:56:02.827 [info] create index if not exists public.oban_jobs_scheduled_at_index
server # [  889.307278] mobilizon_ctl[1759]: 13:56:02.902 [info] execute "CREATE OR REPLACE FUNCTION \"public\".oban_jobs_notify() RETURNS trigger AS $$\nDECLARE\n  channel text;\n  notice json;\nBEGIN\n  IF (TG_OP = 'INSERT') THEN\n    channel = 'public.oban_insert';\n    notice = json_build_object('queue', NEW.queue, 'state', NEW.state);\n\n    -- No point triggering for a job that isn't scheduled to run now\n    IF NEW.scheduled_at IS NOT NULL AND NEW.scheduled_at > now() AT TIME ZONE 'utc' THEN\n      RETURN null;\n    END IF;\n  ELSE\n    channel = 'public.oban_update';\n    notice = json_build_object('queue', NEW.queue, 'new_state', NEW.state, 'old_state', OLD.state);\n  END IF;\n\n  PERFORM pg_notify(channel, notice::text);\n\n  RETURN NULL;\nEND;\n$$ LANGUAGE plpgsql;\n"
server # [  889.371557] mobilizon_ctl[1759]: 13:56:02.981 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"
server # [  889.445036] mobilizon_ctl[1759]: 13:56:03.037 [info] trigger "oban_notify" for relation "public.oban_jobs" does not exist, skipping
server # [  889.457445] mobilizon_ctl[1759]: 13:56:03.039 [info] execute "CREATE TRIGGER oban_notify\nAFTER INSERT OR UPDATE OF state ON \"public\".oban_jobs\nFOR EACH ROW EXECUTE PROCEDURE \"public\".oban_jobs_notify();\n"
server # [  889.521165] mobilizon_ctl[1759]: 13:56:03.098 [info] drop index if exists public.oban_jobs_scheduled_at_index
server # [  889.552948] mobilizon_ctl[1759]: 13:56:03.149 [info] create index public.oban_jobs_scheduled_at_index
server # [  889.640922] mobilizon_ctl[1759]: 13:56:03.233 [info] create check constraint worker_length on table public.oban_jobs
server # [  889.737524] mobilizon_ctl[1759]: 13:56:03.273 [info] create check constraint queue_length on table public.oban_jobs
server # [  889.750644] mobilizon_ctl[1759]: 13:56:03.324 [info] execute "CREATE OR REPLACE FUNCTION \"public\".oban_wrap_id(value bigint) RETURNS int AS $$\nBEGIN\n  RETURN (CASE WHEN value > 2147483647 THEN mod(value, 2147483647) ELSE value END)::int;\nEND;\n$$ LANGUAGE plpgsql IMMUTABLE;\n"
server # [  889.786081] mobilizon_ctl[1759]: 13:56:03.379 [info] alter table public.oban_jobs
server # [  889.828459] mobilizon_ctl[1759]: 13:56:03.427 [info] execute "DROP FUNCTION IF EXISTS \"public\".oban_wrap_id(value bigint)"
server # [  889.868706] mobilizon_ctl[1759]: 13:56:03.454 [info] drop index if exists public.oban_jobs_scheduled_at_index
server # [  889.889736] mobilizon_ctl[1759]: 13:56:03.478 [info] drop index if exists public.oban_jobs_queue_index
server # [  889.938814] mobilizon_ctl[1759]: 13:56:03.525 [info] drop index if exists public.oban_jobs_state_index
server # [  889.966103] mobilizon_ctl[1759]: 13:56:03.546 [info] create index if not exists public.oban_jobs_queue_state_scheduled_at_id_index
server # [  890.038028] mobilizon_ctl[1759]: 13:56:03.636 [info] create index if not exists public.oban_jobs_attempted_at_id_index
server # [  890.136878] mobilizon_ctl[1759]: 13:56:03.709 [info] alter table public.oban_jobs
server # [  890.205686] mobilizon_ctl[1759]: 13:56:03.779 [info] alter table public.oban_jobs
server # [  890.257783] mobilizon_ctl[1759]: 13:56:03.850 [info] drop index if exists public.oban_jobs_queue_state_scheduled_at_id_index
server # [  890.297073] mobilizon_ctl[1759]: 13:56:03.892 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index
server # [  890.358919] mobilizon_ctl[1759]: 13:56:03.965 [info] execute "CREATE OR REPLACE FUNCTION \"public\".oban_jobs_notify() RETURNS trigger AS $$\nDECLARE\n  channel text;\n  notice json;\nBEGIN\n  IF NEW.state = 'available' THEN\n    channel = 'public.oban_insert';\n    notice = json_build_object('queue', NEW.queue);\n\n    PERFORM pg_notify(channel, notice::text);\n  END IF;\n\n  RETURN NULL;\nEND;\n$$ LANGUAGE plpgsql;\n"
server # [  890.418896] mobilizon_ctl[1759]: 13:56:04.014 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"
server # [  890.453278] mobilizon_ctl[1759]: 13:56:04.053 [info] execute "CREATE TRIGGER oban_notify\nAFTER INSERT ON \"public\".oban_jobs\nFOR EACH ROW EXECUTE PROCEDURE \"public\".oban_jobs_notify();\n"
server # [  890.495911] mobilizon_ctl[1759]: 13:56:04.082 [info] alter table public.oban_jobs
server # [  891.040247] mobilizon_ctl[1759]: 13:56:04.574 [info] execute "DO $$\nDECLARE\n  version int;\n  already bool;\nBEGIN\n  SELECT current_setting('server_version_num')::int INTO version;\n  SELECT '{cancelled}' <@ enum_range(NULL::\"public\".oban_job_state)::text[] INTO already;\n\n  IF already THEN\n    RETURN;\n  ELSIF version >= 120000 THEN\n    ALTER TYPE \"public\".oban_job_state ADD VALUE IF NOT EXISTS 'cancelled';\n  ELSE\n    ALTER TYPE \"public\".oban_job_state RENAME TO old_oban_job_state;\n\n    CREATE TYPE \"public\".oban_job_state AS ENUM (\n      'available',\n      'scheduled',\n      'executing',\n      'retryable',\n      'completed',\n      'discarded',\n      'cancelled'\n    );\n\n    ALTER TABLE \"public\".oban_jobs RENAME column state TO _state;\n    ALTER TABLE \"public\".oban_jobs ADD state \"public\".oban_job_state NOT NULL default 'available';\n\n    UPDATE \"public\".oban_jobs SET state = _state::text::\"public\".oban_job_state;\n\n    ALTER TABLE \"public\".oban_jobs DROP column _state;\n    DROP TYPE \"public\".old_oban_job_state;\n  END IF;\nEND$$;\n"
server # [  891.118713] mobilizon_ctl[1759]: 13:56:04.710 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index
server # [  891.171057] mobilizon_ctl[1759]: 13:56:04.760 [info] relation "oban_jobs_state_queue_priority_scheduled_at_id_index" already exists, skipping
server # [  891.193870] mobilizon_ctl[1759]: 13:56:04.761 [info] alter table public.oban_jobs
server # [  891.335155] mobilizon_ctl[1759]: 13:56:04.924 [info] create check constraint priority_range on table public.oban_jobs
server # [  891.393076] mobilizon_ctl[1759]: 13:56:04.989 [info] create check constraint positive_max_attempts on table public.oban_jobs
server # [  891.451922] mobilizon_ctl[1759]: 13:56:05.037 [info] create check constraint attempt_range on table public.oban_jobs
server # [  891.513406] mobilizon_ctl[1759]: 13:56:05.092 [info] drop index if exists public.oban_jobs_args_vector
server # [  891.551240] mobilizon_ctl[1759]: 13:56:05.114 [info] index "oban_jobs_args_vector" does not exist, skipping
server # [  891.567430] mobilizon_ctl[1759]: 13:56:05.120 [info] drop index if exists public.oban_jobs_worker_gist
server # [  891.596588] mobilizon_ctl[1759]: 13:56:05.151 [info] index "oban_jobs_worker_gist" does not exist, skipping
server # [  891.610896] mobilizon_ctl[1759]: 13:56:05.157 [info] drop index if exists public.oban_jobs_attempted_at_id_index
server # [  891.641206] mobilizon_ctl[1759]: 13:56:05.223 [info] create index if not exists public.oban_jobs_args_index
server # [  891.718207] mobilizon_ctl[1759]: 13:56:05.309 [info] create index if not exists public.oban_jobs_meta_index
server # [  891.787716] mobilizon_ctl[1759]: 13:56:05.370 [info] create table if not exists public.oban_peers
server # [  891.957186] mobilizon_ctl[1759]: 13:56:05.550 [info] execute "ALTER TABLE \"public\".oban_peers SET UNLOGGED"
server # [  892.277546] mobilizon_ctl[1759]: 13:56:05.798 [info] drop constraint priority_range from table public.oban_jobs
server # [  892.304855] mobilizon_ctl[1759]: 13:56:05.841 [info] create check constraint non_negative_priority on table public.oban_jobs
server # [  892.352997] mobilizon_ctl[1759]: 13:56:05.942 [info] execute "DROP TRIGGER IF EXISTS oban_notify ON \"public\".oban_jobs"
server # [  892.425459] mobilizon_ctl[1759]: 13:56:05.987 [info] execute "DROP FUNCTION IF EXISTS \"public\".oban_jobs_notify()"
server # [  892.441463] mobilizon_ctl[1759]: 13:56:06.022 [info] create index if not exists public.oban_jobs_state_cancelled_at_index
server # [  892.551405] mobilizon_ctl[1759]: 13:56:06.138 [info] create index if not exists public.oban_jobs_state_discarded_at_index
server # [  892.650669] mobilizon_ctl[1759]: 13:56:06.218 [info] execute "COMMENT ON TABLE \"public\".oban_jobs IS '13'"
server # [  892.675843] mobilizon_ctl[1759]: 13:56:06.247 [info] == Migrated 20191104093447 in 6.0s
server # [  892.907793] mobilizon_ctl[1759]: 13:56:06.503 [info] == Running 20191106114524 Mobilizon.Storage.Repo.Migrations.RemoveFloorFromAddresses.up/0 forward
server # [  892.935135] mobilizon_ctl[1759]: 13:56:06.513 [info] alter table addresses
server # [  892.985944] mobilizon_ctl[1759]: 13:56:06.559 [info] == Migrated 20191106114524 in 0.0s
server # [  893.098554] mobilizon_ctl[1759]: 13:56:06.698 [info] == Running 20191106141051 Mobilizon.Storage.Repo.Migrations.AddTypeToAddresses.change/0 forward
server # [  893.119608] mobilizon_ctl[1759]: 13:56:06.709 [info] alter table addresses
server # [  893.162286] mobilizon_ctl[1759]: 13:56:06.753 [info] == Migrated 20191106141051 in 0.0s
server # [  893.319521] mobilizon_ctl[1759]: 13:56:06.891 [info] == Running 20191114155534 Mobilizon.Storage.Repo.Migrations.CascadeCommentDeletion.up/0 forward
server # [  893.343918] mobilizon_ctl[1759]: 13:56:06.906 [info] drop constraint comments_in_reply_to_comment_id_fkey from table comments
server # [  893.379047] mobilizon_ctl[1759]: 13:56:06.984 [info] drop constraint comments_origin_comment_id_fkey from table comments
server # [  893.433587] mobilizon_ctl[1759]: 13:56:07.029 [info] alter table comments
server # [  893.571526] mobilizon_ctl[1759]: 13:56:07.173 [info] == Migrated 20191114155534 in 0.2s
server # [  893.689639] mobilizon_ctl[1759]: 13:56:07.296 [info] == Running 20191122154018 Mobilizon.Storage.Repo.Migrations.AddDeletedAtOnComments.up/0 forward
server # [  893.728910] mobilizon_ctl[1759]: 13:56:07.317 [info] drop constraint if exists comments_actor_id_fkey from table comments
server # [  893.760921] mobilizon_ctl[1759]: 13:56:07.356 [info] constraint "comments_actor_id_fkey" of relation "comments" does not exist, skipping
server # [  893.770027] mobilizon_ctl[1759]: 13:56:07.357 [info] alter table comments
server # [  893.945977] mobilizon_ctl[1759]: 13:56:07.540 [info] == Migrated 20191122154018 in 0.2s
server # [  894.041768] mobilizon_ctl[1759]: 13:56:07.642 [info] == Running 20191126173510 Mobilizon.Storage.Repo.Migrations.AddLocalFieldToReports.up/0 forward
server # [  894.061049] mobilizon_ctl[1759]: 13:56:07.654 [info] alter table reports
server # [  894.109117] mobilizon_ctl[1759]: 13:56:07.705 [info] rename column uri to url on table reports
server # [  894.143658] mobilizon_ctl[1759]: 13:56:07.726 [info] == Migrated 20191126173510 in 0.0s
server # [  894.221708] mobilizon_ctl[1759]: 13:56:07.823 [info] == Running 20191127163737 Mobilizon.Repo.Migrations.CreateTombstones.change/0 forward
server # [  894.254261] mobilizon_ctl[1759]: 13:56:07.852 [info] create table tombstones
server # [  894.429210] mobilizon_ctl[1759]: 13:56:07.995 [info] create index tombstones_uri_index
server # [  894.461245] mobilizon_ctl[1759]: 13:56:08.061 [info] == Migrated 20191127163737 in 0.2s
server # [  894.567390] mobilizon_ctl[1759]: 13:56:08.162 [info] == Running 20191129091227 Mobilizon.Storage.Repo.Migrations.AddTimestampsToFollowers.change/0 forward
server # [  894.600982] mobilizon_ctl[1759]: 13:56:08.189 [info] alter table followers
server # [  894.643140] mobilizon_ctl[1759]: 13:56:08.244 [info] == Migrated 20191129091227 in 0.0s
server # [  894.756970] mobilizon_ctl[1759]: 13:56:08.362 [info] == Running 20191204164224 Mobilizon.Storage.Repo.Migrations.DeleteEventCascadeToComments.up/0 forward
server # [  894.793223] mobilizon_ctl[1759]: 13:56:08.382 [info] drop constraint if exists comments_event_id_fkey from table comments
server # [  894.849951] mobilizon_ctl[1759]: 13:56:08.444 [info] alter table comments
server # [  894.953155] mobilizon_ctl[1759]: 13:56:08.544 [info] == Migrated 20191204164224 in 0.1s
server # [  895.054692] mobilizon_ctl[1759]: 13:56:08.658 [info] == Running 20191206144028 Mobilizon.Repo.Migrations.CreateShares.up/0 forward
server # [  895.104010] mobilizon_ctl[1759]: 13:56:08.689 [info] create table shares
server # [  895.292956] mobilizon_ctl[1759]: 13:56:08.889 [info] create index if not exists shares_uri_actor_id_index
server # [  895.344849] mobilizon_ctl[1759]: 13:56:08.941 [info] == Migrated 20191206144028 in 0.2s
server # [  895.526236] mobilizon_ctl[1759]: 13:56:09.063 [info] == Running 20191219172017 Mobilizon.Storage.Repo.Migrations.AddMetadataToParticipant.up/0 forward
server # [  895.541410] mobilizon_ctl[1759]: 13:56:09.082 [info] drop index if exists participants_event_id_actor_id_index
server # [  895.558387] mobilizon_ctl[1759]: 13:56:09.121 [info] alter table participants
server # [  895.634983] mobilizon_ctl[1759]: 13:56:09.226 [info] execute "CREATE UNIQUE INDEX participants_metadata_confirmation_token_index ON participants((metadata->>'confirmation_token'))"
server # [  895.706144] mobilizon_ctl[1759]: 13:56:09.294 [info] == Migrated 20191219172017 in 0.2s
server # [  895.819171] mobilizon_ctl[1759]: 13:56:09.400 [info] == Running 20191223173537 Mobilizon.Storage.Repo.Migrations.AddNotConfirmedStatusToParticipants.up/0 forward
server # [  895.868629] mobilizon_ctl[1759]: 13:56:09.416 [info] execute "ALTER TYPE participant_role ADD VALUE IF NOT EXISTS 'not_confirmed'"
server # [  895.889308] mobilizon_ctl[1759]: 13:56:09.454 [info] enum label "not_confirmed" already exists, skipping
server # [  895.929743] mobilizon_ctl[1759]: 13:56:09.468 [info] == Migrated 20191223173537 in 0.0s
server # [  896.012953] mobilizon_ctl[1759]: 13:56:09.612 [info] == Running 20200116095117 Mobilizon.Repo.Migrations.CreateAdminSettings.change/0 forward
server # [  896.050316] mobilizon_ctl[1759]: 13:56:09.638 [info] create table admin_settings
server # [  896.247318] mobilizon_ctl[1759]: 13:56:09.842 [info] create index admin_settings_group_name_index
server # [  896.333041] mobilizon_ctl[1759]: 13:56:09.926 [info] == Migrated 20200116095117 in 0.3s
server # [  896.532022] mobilizon_ctl[1759]: 13:56:10.090 [info] == Running 20200210100910 Mobilizon.Storage.Repo.Migrations.AddUnconfirmedEmailToUsersTable.change/0 forward
server # [  896.563955] mobilizon_ctl[1759]: 13:56:10.110 [info] alter table users
server # [  896.617040] mobilizon_ctl[1759]: 13:56:10.179 [info] == Migrated 20200210100910 in 0.0s
server # [  896.747902] mobilizon_ctl[1759]: 13:56:10.339 [info] == Running 20200213154309 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV8.up/0 forward
server # [  896.817646] postgres[2182]: [2182] LOG:  skipping analyze of "schema_migrations" --- lock not available
server # [  896.918035] systemd[1]: mobilizon.service: start-pre operation timed out. Terminating.
server # [  896.969815] systemd[1]: mobilizon.service: Control process exited, code=killed, status=15/TERM
server # [  897.057205] mobilizon_ctl[1759]: 13:56:10.581 [info] == Migrated 20200213154309 in 0.2s
server # [  897.461427] mobilizon_ctl[1759]: 13:56:10.549 [notice] SIGTERM received - shutting down
server # [  897.587516] mobilizon_ctl[1759]: 13:56:11.002 [notice] SIGTERM received - shutting down
server # [  899.027537] mobilizon_ctl[1759]: ** (RuntimeError) could not find migration runner process for #PID<0.1242.0>
server # [  899.049701] mobilizon_ctl[1759]:     (ecto_sql 3.13.5) lib/ecto/migration/runner.ex:326: Ecto.Migration.Runner.runner/0
server # [  899.067880] mobilizon_ctl[1759]:     (ecto_sql 3.13.5) lib/ecto/migration/runner.ex:64: Ecto.Migration.Runner.stop/0
server # [  899.081687] mobilizon_ctl[1759]:     (ecto_sql 3.13.5) lib/ecto/migration/runner.ex:28: Ecto.Migration.Runner.run/8
server # [  899.089865] mobilizon_ctl[1759]:     (ecto_sql 3.13.5) lib/ecto/migrator.ex:365: Ecto.Migrator.attempt/8
server # [  899.106551] mobilizon_ctl[1759]:     (ecto_sql 3.13.5) lib/ecto/migrator.ex:283: anonymous fn/5 in Ecto.Migrator.do_up/5
server # [  899.133956] mobilizon_ctl[1759]:     (ecto_sql 3.13.5) lib/ecto/migrator.ex:337: anonymous fn/6 in Ecto.Migrator.async_migrate_maybe_in_transaction/7
server # [  899.145848] mobilizon_ctl[1759]:     (mobilizon 5.2.3) lib/mobilizon/storage/repo.ex:4: anonymous fn/1 in Mobilizon.Storage.Repo.transaction/2
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  900.004830] systemd[1]: mobilizon.service: Failed with result 'timeout'.
server # [  900.029669] systemd[1]: Failed to start Mobilizon federated organization and mobilization platform.
server # [  900.068006] systemd[1]: mobilizon.service: Consumed 1min 50.573s CPU time over 5min 2.977s wall clock time, 81M memory peak.
server # [  900.587705] systemd[1]: mobilizon.service: Scheduled restart job, restart counter is at 1.
server # [  900.790164] systemd[1]: Starting Mobilizon PostgreSQL setup...
server # [  901.055233] systemd[1]: Starting Cleanup of Temporary Directories...
server # [  904.313765] mobilizon-postgresql-start[2191]: NOTICE:  extension "postgis" already exists, skipping
server # [  904.323290] mobilizon-postgresql-start[2191]: NOTICE:  extension "pg_trgm" already exists, skipping
server # [  904.365280] mobilizon-postgresql-start[2191]: NOTICE:  extension "unaccent" already exists, skipping
server # [  904.381330] mobilizon-postgresql-start[2191]: CREATE EXTENSION
server # [  904.383185] mobilizon-postgresql-start[2191]: CREATE EXTENSION
server # [  904.417558] mobilizon-postgresql-start[2191]: CREATE EXTENSION
server # [  905.899695] mobilizon-postgresql-start[2193]: ALTER DATABASE
server # [  906.082608] systemd[1]: mobilizon-postgresql.service: Deactivated successfully.
server # [  906.094265] systemd[1]: Finished Mobilizon PostgreSQL setup.
server # [  906.276946] systemd[1]: Starting Mobilizon setup secrets...
server # [  908.067570] systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully.
server # [  908.103424] systemd[1]: Finished Cleanup of Temporary Directories.
server # [  910.747348] mobilizon-setup-secrets-start[2201]: + '[' '!' -f /var/lib/mobilizon/secret-env.sh ']'
server # [  910.821892] systemd[1]: mobilizon-setup-secrets.service: Deactivated successfully.
server # [  910.843019] systemd[1]: Finished Mobilizon setup secrets.
server # [  910.881453] systemd[1]: mobilizon-setup-secrets.service: Consumed 1.435s CPU time over 4.532s wall clock time, 1.9M memory peak.
server # [  911.067466] systemd[1]: Starting Mobilizon federated organization and mobilization platform...
server # [  921.642885] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 1941 buffers (11.8%); 0 WAL file(s) added, 0 removed, 1 recycled; write=203.033 s, sync=3.145 s, total=206.438 s; sync files=527, longest=0.049 s, average=0.006 s; distance=13867 kB, estimate=13867 kB; lsn=0/246C878, redo lsn=0/2277C38
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [  960.897220] mobilizon_ctl[2216]: Loading app..
server # [  960.924805] mobilizon_ctl[2216]: Starting dependencies..
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1014.811681] postgres[1389]: [1389] LOG:  checkpoint starting: time
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1045.810929] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 290 buffers (1.8%); 0 WAL file(s) added, 0 removed, 0 recycled; write=30.384 s, sync=0.343 s, total=31.007 s; sync files=100, longest=0.031 s, average=0.004 s; distance=2034 kB, estimate=12684 kB; lsn=0/24747B0, redo lsn=0/2474720
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1059.224551] mobilizon_ctl[2216]: 13:58:52.554 [info] == Running 20200214135231 Mobilizon.Storage.Repo.Migrations.AddLastRefreshedAtToActors.change/0 forward
server # [ 1059.231308] mobilizon_ctl[2216]: 13:58:52.802 [info] alter table actors
server # [ 1059.421356] mobilizon_ctl[2216]: 13:58:52.985 [info] == Migrated 20200214135231 in 0.1s
server # [ 1061.406583] mobilizon_ctl[2216]: 13:58:54.964 [info] == Running 20200218085757 Mobilizon.Storage.Repo.Migrations.AddNewRolesToMember.up/0 forward
server # [ 1061.524867] mobilizon_ctl[2216]: 13:58:55.101 [info] execute "ALTER TYPE member_role ADD VALUE IF NOT EXISTS 'invited'"
server # [ 1061.555872] mobilizon_ctl[2216]: 13:58:55.141 [info] enum label "invited" already exists, skipping
server # [ 1061.603916] mobilizon_ctl[2216]: 13:58:55.142 [info] execute "ALTER TYPE member_role ADD VALUE IF NOT EXISTS 'rejected'"
server # [ 1061.643710] mobilizon_ctl[2216]: 13:58:55.186 [info] enum label "rejected" already exists, skipping
server # [ 1061.667686] mobilizon_ctl[2216]: 13:58:55.201 [info] == Migrated 20200218085757 in 0.2s
server # [ 1061.769704] mobilizon_ctl[2216]: 13:58:55.364 [info] == Running 20200218170232 Mobilizon.Storage.Repo.Migrations.MoveMemberIdToUuid.up/0 forward
server # [ 1061.791453] mobilizon_ctl[2216]: 13:58:55.381 [info] alter table members
server # [ 1062.111823] mobilizon_ctl[2216]: 13:58:55.697 [info] == Migrated 20200218170232 in 0.3s
server # [ 1062.234927] mobilizon_ctl[2216]: 13:58:55.833 [info] == Running 20200219152611 Mobilizon.Storage.Repo.Migrations.AddMembersEndpointToActors.up/0 forward
server # [ 1062.255846] mobilizon_ctl[2216]: 13:58:55.846 [info] alter table actors
server # [ 1062.334213] mobilizon_ctl[2216]: 13:58:55.922 [info] == Migrated 20200219152611 in 0.0s
server # [ 1062.505290] mobilizon_ctl[2216]: 13:58:56.058 [info] == Running 20200221095721 Mobilizon.Repo.Migrations.CreateTodos.change/0 forward
server # [ 1062.536780] mobilizon_ctl[2216]: 13:58:56.117 [info] create table todo_lists
server # [ 1062.711724] mobilizon_ctl[2216]: 13:58:56.306 [info] create table todos
server # [ 1062.937258] mobilizon_ctl[2216]: 13:58:56.537 [info] == Migrated 20200221095721 in 0.4s
server # [ 1063.029083] mobilizon_ctl[2216]: 13:58:56.630 [info] == Running 20200221160744 Mobilizon.Repo.Migrations.CreateResources.change/0 forward
server # [ 1063.060827] mobilizon_ctl[2216]: 13:58:56.655 [info] create table resource
server # [ 1063.343822] mobilizon_ctl[2216]: 13:58:56.941 [info] == Migrated 20200221160744 in 0.3s
server # [ 1063.440922] mobilizon_ctl[2216]: 13:58:57.041 [info] == Running 20200406115807 Mobilizon.Storage.Repo.Migrations.AddConversations.change/0 forward
server # [ 1063.510900] mobilizon_ctl[2216]: 13:58:57.073 [info] create table conversations
server # [ 1063.725267] mobilizon_ctl[2216]: 13:58:57.325 [info] alter table comments
server # [ 1063.837011] mobilizon_ctl[2216]: 13:58:57.433 [info] == Migrated 20200406115807 in 0.3s
server # [ 1063.937201] mobilizon_ctl[2216]: 13:58:57.534 [info] == Running 20200410100554 Mobilizon.Storage.Repo.Migrations.AddNumberEditsToComment.change/0 forward
server # [ 1063.954640] mobilizon_ctl[2216]: 13:58:57.545 [info] alter table comments
server # [ 1064.009552] mobilizon_ctl[2216]: 13:58:57.608 [info] == Migrated 20200410100554 in 0.0s
server # [ 1064.114465] mobilizon_ctl[2216]: 13:58:57.721 [info] == Running 20200513142813 Mobilizon.Storage.Repo.Migrations.AddResourcesAndTodosEndpointsToActor.change/0 forward
server # [ 1064.142425] mobilizon_ctl[2216]: 13:58:57.730 [info] alter table actors
server # [ 1064.190969] mobilizon_ctl[2216]: 13:58:57.790 [info] == Migrated 20200513142813 in 0.0s
server # [ 1064.293615] mobilizon_ctl[2216]: 13:58:57.886 [info] == Running 20200514101612 Mobilizon.Storage.Repo.Migrations.AddLocalAttributeToResourcesAndTodos.change/0 forward
server # [ 1064.318815] mobilizon_ctl[2216]: 13:58:57.901 [info] alter table resource
server # [ 1064.354772] mobilizon_ctl[2216]: 13:58:57.954 [info] alter table todo_lists
server # [ 1064.398842] mobilizon_ctl[2216]: 13:58:57.993 [info] alter table todos
server # [ 1064.459359] mobilizon_ctl[2216]: 13:58:58.050 [info] == Migrated 20200514101612 in 0.1s
server # [ 1064.598696] mobilizon_ctl[2216]: 13:58:58.162 [info] == Running 20200518084949 Mobilizon.Storage.Repo.Migrations.MoveInviterFromMetadataToMemberTable.change/0 forward
server # [ 1064.615350] mobilizon_ctl[2216]: 13:58:58.171 [info] alter table members
server # [ 1064.737207] mobilizon_ctl[2216]: 13:58:58.330 [info] == Migrated 20200518084949 in 0.1s
server # [ 1064.849494] mobilizon_ctl[2216]: 13:58:58.447 [info] == Running 20200519082820 Mobilizon.Storage.Repo.Migrations.AddUniqueURLContraintToSomeTables.change/0 forward
server # [ 1064.886422] mobilizon_ctl[2216]: 13:58:58.478 [info] create index members_url_index
server # [ 1064.956846] mobilizon_ctl[2216]: 13:58:58.549 [info] create index resource_url_index
server # [ 1065.005969] mobilizon_ctl[2216]: 13:58:58.604 [info] create index todo_lists_url_index
server # [ 1065.071671] mobilizon_ctl[2216]: 13:58:58.660 [info] create index todos_url_index
server # [ 1065.135281] mobilizon_ctl[2216]: 13:58:58.725 [info] == Migrated 20200519082820 in 0.2s
server # [ 1065.226754] mobilizon_ctl[2216]: 13:58:58.826 [info] == Running 20200526131523 Mobilizon.Repo.Migrations.CreateUserSettings.change/0 forward
server # [ 1065.266406] mobilizon_ctl[2216]: 13:58:58.862 [info] create table user_settings
server # [ 1065.407715] mobilizon_ctl[2216]: 13:58:58.997 [info] == Migrated 20200526131523 in 0.1s
server # [ 1065.529524] mobilizon_ctl[2216]: 13:58:59.130 [info] == Running 20200605154921 Mobilizon.Storage.Repo.Migrations.AddNotificationPendingParticipationToSettings.change/0 forward
server # [ 1065.615607] mobilizon_ctl[2216]: 13:58:59.154 [info] alter table user_settings
server # [ 1065.634662] mobilizon_ctl[2216]: 13:58:59.214 [info] == Migrated 20200605154921 in 0.0s
server # [ 1065.793062] mobilizon_ctl[2216]: 13:58:59.378 [info] == Running 20200612155254 Mobilizon.Storage.Repo.Migrations.AddDisabledParameterToUsers.change/0 forward
server # [ 1065.818188] mobilizon_ctl[2216]: 13:58:59.393 [info] alter table users
server # [ 1065.865029] mobilizon_ctl[2216]: 13:58:59.462 [info] == Migrated 20200612155254 in 0.0s
server # [ 1066.002845] mobilizon_ctl[2216]: 13:58:59.604 [info] == Running 20200630123819 Mobilizon.Storage.Repo.Migrations.AddProviderToUserAndMakePasswordMandatory.up/0 forward
server # [ 1066.046633] mobilizon_ctl[2216]: 13:58:59.618 [info] alter table users
server # [ 1066.096991] mobilizon_ctl[2216]: 13:58:59.685 [info] == Migrated 20200630123819 in 0.0s
server # [ 1066.193168] mobilizon_ctl[2216]: 13:58:59.793 [info] == Running 20200708080516 Mobilizon.Repo.Migrations.CreatePosts.up/0 forward
server # [ 1066.389184] mobilizon_ctl[2216]: 13:58:59.975 [info] execute "CREATE TYPE public.post_visibility AS ENUM ('public', 'unlisted', 'restricted', 'private')"
server # [ 1066.461096] mobilizon_ctl[2216]: 13:59:00.040 [info] create table posts
server # [ 1066.811284] mobilizon_ctl[2216]: 13:59:00.358 [info] create table posts_tags
server # [ 1066.927449] mobilizon_ctl[2216]: 13:59:00.527 [info] alter table actors
server # [ 1066.991579] mobilizon_ctl[2216]: 13:59:00.578 [info] == Migrated 20200708080516 in 0.7s
server # [ 1067.105792] mobilizon_ctl[2216]: 13:59:00.708 [info] == Running 20200717142633 Mobilizon.Storage.Repo.Migrations.RenameConversationsToDiscussions.up/0 forward
server # [ 1067.145006] mobilizon_ctl[2216]: 13:59:00.737 [info] rename table conversations to discussions
server # [ 1067.187841] mobilizon_ctl[2216]: 13:59:00.781 [info] rename column conversation_id to discussion_id on table comments
server # [ 1067.233995] mobilizon_ctl[2216]: 13:59:00.821 [info] alter table actors
server # [ 1067.289043] mobilizon_ctl[2216]: 13:59:00.874 [info] == Migrated 20200717142633 in 0.1s
server # [ 1067.409060] mobilizon_ctl[2216]: 13:59:01.005 [info] == Running 20200721131521 Mobilizon.Storage.Repo.Migrations.AddUrlToConversation.up/0 forward
server # [ 1067.461275] mobilizon_ctl[2216]: 13:59:01.026 [info] drop constraint if exists comments_conversation_id_fkey from table comments
server # [ 1067.525274] mobilizon_ctl[2216]: 13:59:01.114 [info] drop constraint if exists comments_discussion_id_fkey from table comments
server # [ 1067.559448] mobilizon_ctl[2216]: 13:59:01.129 [info] constraint "comments_discussion_id_fkey" of relation "comments" does not exist, skipping
server # [ 1067.593191] mobilizon_ctl[2216]: 13:59:01.130 [info] alter table discussions
server # [ 1067.740409] mobilizon_ctl[2216]: 13:59:01.317 [info] alter table comments
server # [ 1067.902954] mobilizon_ctl[2216]: 13:59:01.442 [info] == Migrated 20200721131521 in 0.4s
server # [ 1067.970045] mobilizon_ctl[2216]: 13:59:01.568 [info] == Running 20200805124620 Mobilizon.Storage.Repo.Migrations.AddAddressToActors.change/0 forward
server # [ 1067.978809] mobilizon_ctl[2216]: 13:59:01.578 [info] alter table actors
server # [ 1068.147798] mobilizon_ctl[2216]: 13:59:01.741 [info] == Migrated 20200805124620 in 0.1s
server # [ 1068.229630] mobilizon_ctl[2216]: 13:59:01.836 [info] == Running 20200817080549 Mobilizon.Storage.Repo.Migrations.AddPublishedAtToEntities.up/0 forward
server # [ 1068.244790] mobilizon_ctl[2216]: 13:59:01.842 [info] alter table comments
server # [ 1068.371982] mobilizon_ctl[2216]: 13:59:01.957 [info] alter table resource
server # [ 1068.465209] mobilizon_ctl[2216]: 13:59:02.060 [info] alter table todo_lists
server # [ 1068.548919] mobilizon_ctl[2216]: 13:59:02.139 [info] alter table todos
server # [ 1068.621845] mobilizon_ctl[2216]: 13:59:02.217 [info] == Migrated 20200817080549 in 0.3s
server # [ 1068.741553] mobilizon_ctl[2216]: 13:59:02.339 [info] == Running 20200828071543 Mobilizon.Storage.Repo.Migrations.AddLoginInformationToUser.change/0 forward
server # [ 1068.780986] mobilizon_ctl[2216]: 13:59:02.354 [info] alter table users
server # [ 1068.847174] mobilizon_ctl[2216]: 13:59:02.434 [info] == Migrated 20200828071543 in 0.0s
server # [ 1069.015893] mobilizon_ctl[2216]: 13:59:02.570 [info] == Running 20200901101307 Mobilizon.Storage.Repo.Migrations.FixRelayVisibility.up/0 forward
server # [ 1069.464045] mobilizon_ctl[2216]: 13:59:03.058 [info] == Migrated 20200901101307 in 0.4s
server # [ 1069.589272] mobilizon_ctl[2216]: 13:59:03.179 [info] == Running 20200928150725 Mobilizon.Storage.Repo.Migrations.AddContactsToEvents.change/0 forward
server # [ 1069.609874] mobilizon_ctl[2216]: 13:59:03.199 [info] create table event_contacts
server # [ 1069.807501] mobilizon_ctl[2216]: 13:59:03.400 [info] == Migrated 20200928150725 in 0.2s
server # [ 1069.951467] mobilizon_ctl[2216]: 13:59:03.545 [info] == Running 20201006132530 Mobilizon.Storage.Repo.Migrations.ChangeDefaultNotificationPendingParticipation.up/0 forward
server # [ 1069.963654] mobilizon_ctl[2216]: 13:59:03.557 [info] alter table user_settings
server # [ 1070.117731] mobilizon_ctl[2216]: 13:59:03.634 [info] == Migrated 20201006132530 in 0.0s
server # [ 1070.181620] mobilizon_ctl[2216]: 13:59:03.786 [info] == Running 20201103141811 Mobilizon.Storage.Repo.Migrations.AddNotificationPendingMembershipToSettings.change/0 forward
server # [ 1070.221511] mobilizon_ctl[2216]: 13:59:03.794 [info] alter table user_settings
server # [ 1070.291314] mobilizon_ctl[2216]: 13:59:03.877 [info] == Migrated 20201103141811 in 0.0s
server # [ 1070.398500] mobilizon_ctl[2216]: 13:59:03.987 [info] == Running 20201105145719 Mobilizon.Storage.Repo.Migrations.RepairGroupsWithMissingURLs.up/0 forward
server # [ 1070.483072] mobilizon_ctl[2216]: 13:59:04.066 [info] == Migrated 20201105145719 in 0.0s
server # [ 1070.634267] mobilizon_ctl[2216]: 13:59:04.215 [info] == Running 20201114174929 Mobilizon.Storage.Repo.Migrations.FixObanMigrationsForOlderPostgreSQLVersions.up/0 forward
server # [ 1070.666531] mobilizon_ctl[2216]: 13:59:04.238 [info] == Migrated 20201114174929 in 0.0s
server # [ 1070.759931] mobilizon_ctl[2216]: 13:59:04.351 [info] == Running 20201114175249 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV9.up/0 forward
server # [ 1071.262462] mobilizon_ctl[2216]: 13:59:04.778 [info] == Migrated 20201114175249 in 0.4s
server # [ 1071.304817] mobilizon_ctl[2216]: 13:59:04.906 [info] == Running 20201120161229 Mobilizon.Storage.Repo.Migrations.FixPictureDeletion.up/0 forward
server # [ 1071.321772] mobilizon_ctl[2216]: 13:59:04.922 [info] drop constraint if exists posts_picture_id_fkey from table posts
server # [ 1071.392868] mobilizon_ctl[2216]: 13:59:04.989 [info] alter table posts
server # [ 1071.466719] mobilizon_ctl[2216]: 13:59:05.065 [info] drop constraint if exists events_picture_id_fkey from table events
server # [ 1071.520693] mobilizon_ctl[2216]: 13:59:05.118 [info] alter table events
server # [ 1071.609567] mobilizon_ctl[2216]: 13:59:05.203 [info] == Migrated 20201120161229 in 0.2s
server # [ 1072.495481] mobilizon_ctl[2216]: 13:59:06.062 [info] == Running 20201124094655 Mobilizon.Storage.Repo.Migrations.AddMediaTables.change/0 forward
server # [ 1072.514490] mobilizon_ctl[2216]: 13:59:06.100 [info] rename table pictures to medias
server # [ 1072.532928] mobilizon_ctl[2216]: 13:59:06.129 [info] create table events_medias
server # [ 1072.692647] mobilizon_ctl[2216]: 13:59:06.277 [info] create table posts_medias
server # [ 1072.867210] mobilizon_ctl[2216]: 13:59:06.453 [info] create table comments_medias
server # [ 1073.017556] mobilizon_ctl[2216]: 13:59:06.613 [info] == Migrated 20201124094655 in 0.5s
server # [ 1073.161030] mobilizon_ctl[2216]: 13:59:06.754 [info] == Running 20210127153325 Mobilizon.Storage.Repo.Migrations.UpgradeObanJobsToV10.up/0 forward
server # [ 1073.284568] mobilizon_ctl[2216]: 13:59:06.862 [info] == Migrated 20210127153325 in 0.1s
server # [ 1073.401018] mobilizon_ctl[2216]: 13:59:06.990 [info] == Running 20210210143432 Mobilizon.Storage.Repo.Migrations.AddLocationSettingsToUser.change/0 forward
server # [ 1073.429646] mobilizon_ctl[2216]: 13:59:07.004 [info] alter table user_settings
server # [ 1073.598915] mobilizon_ctl[2216]: 13:59:07.136 [info] == Migrated 20210210143432 in 0.1s
server # [ 1073.667529] mobilizon_ctl[2216]: 13:59:07.267 [info] == Running 20210215092515 Mobilizon.Storage.Repo.Migrations.AddActivityTable.change/0 forward
server # [ 1073.709807] mobilizon_ctl[2216]: 13:59:07.309 [info] create table activities
server # [ 1074.036828] mobilizon_ctl[2216]: 13:59:07.618 [info] == Migrated 20210215092515 in 0.3s
server # [ 1074.131294] mobilizon_ctl[2216]: 13:59:07.734 [info] == Running 20210217164549 Mobilizon.Storage.Repo.Migrations.AddMemberSinceToMembers.up/0 forward
server # [ 1074.164815] mobilizon_ctl[2216]: 13:59:07.743 [info] alter table members
server # [ 1074.258234] mobilizon_ctl[2216]: 13:59:07.839 [info] == Migrated 20210217164549 in 0.1s
server # [ 1074.370647] mobilizon_ctl[2216]: 13:59:07.962 [info] == Running 20210310084210 Mobilizon.Storage.Repo.Migrations.AddIndexesToActivities.change/0 forward
server # [ 1074.402917] mobilizon_ctl[2216]: 13:59:07.973 [info] create index activity_group_id
server # [ 1074.472817] mobilizon_ctl[2216]: 13:59:08.055 [info] create index activity_filter
server # [ 1074.601630] mobilizon_ctl[2216]: 13:59:08.141 [info] == Migrated 20210310084210 in 0.1s
server # [ 1074.668979] mobilizon_ctl[2216]: 13:59:08.255 [info] == Running 20210422140923 Mobilizon.Storage.Repo.Migrations.CleanupAddresses.up/0 forward
server # [ 1074.812094] mobilizon_ctl[2216]: 13:59:08.380 [info] == Migrated 20210422140923 in 0.1s
server # [ 1074.921979] mobilizon_ctl[2216]: 13:59:08.521 [info] == Running 20210422155913 Mobilizon.Storage.Repo.Migrations.AddIndexToAddresses.up/0 forward
server # [ 1074.947788] mobilizon_ctl[2216]: 13:59:08.546 [info] create index if not exists addresses_url_index
server # [ 1075.031648] mobilizon_ctl[2216]: 13:59:08.619 [info] == Migrated 20210422155913 in 0.0s
server # [ 1075.150159] mobilizon_ctl[2216]: 13:59:08.746 [info] == Running 20210427091034 Mobilizon.Storage.Repo.Migrations.RepairUsersDefaultActors.up/0 forward
server # [ 1075.305216] mobilizon_ctl[2216]: 13:59:08.878 [info] == Migrated 20210427091034 in 0.1s
server # [ 1075.390607] mobilizon_ctl[2216]: 13:59:08.991 [info] == Running 20210505172402 Mobilizon.Storage.Repo.Migrations.AddGroupNotificationAndLastNotificationDateSettings.change/0 forward
server # [ 1075.416903] mobilizon_ctl[2216]: 13:59:09.001 [info] alter table user_settings
server # [ 1075.461242] mobilizon_ctl[2216]: 13:59:09.060 [info] == Migrated 20210505172402 in 0.0s
server # [ 1075.548926] mobilizon_ctl[2216]: 13:59:09.150 [info] == Running 20210506080303 Mobilizon.Repo.Migrations.CreateUserPushSubscriptions.change/0 forward
server # [ 1075.623617] mobilizon_ctl[2216]: 13:59:09.177 [info] create table user_push_subscriptions
server # [ 1075.746912] mobilizon_ctl[2216]: 13:59:09.348 [info] create index user_push_subscriptions_user_id_digest_index
server # [ 1075.813203] mobilizon_ctl[2216]: 13:59:09.405 [info] == Migrated 20210506080303 in 0.2s
server # [ 1075.948796] mobilizon_ctl[2216]: 13:59:09.534 [info] == Running 20210512084824 Mobilizon.Storage.Repo.Migrations.RepairRelayAndAnonymousActors.up/0 forward
server # [ 1076.074619] mobilizon_ctl[2216]: 13:59:09.641 [info] == Migrated 20210512084824 in 0.1s
server # [ 1076.181706] mobilizon_ctl[2216]: 13:59:09.788 [info] == Running 20210526203337 Mobilizon.Storage.Repo.Migrations.AddUserActivitySettings.change/0 forward
server # [ 1076.241550] mobilizon_ctl[2216]: 13:59:09.807 [info] create table user_activity_settings
server # [ 1076.517552] mobilizon_ctl[2216]: 13:59:10.093 [info] create index user_activity_settings_user_id_key_method_index
server # [ 1076.678733] mobilizon_ctl[2216]: 13:59:10.190 [info] == Migrated 20210526203337 in 0.4s
server # [ 1076.777653] mobilizon_ctl[2216]: 13:59:10.366 [info] == Running 20210601082412 Mobilizon.Storage.Repo.Migrations.AddIsAnnouncementToComments.change/0 forward
server # [ 1076.814903] mobilizon_ctl[2216]: 13:59:10.394 [info] alter table comments
server # [ 1076.923729] mobilizon_ctl[2216]: 13:59:10.482 [info] == Migrated 20210601082412 in 0.1s
server # [ 1077.071940] mobilizon_ctl[2216]: 13:59:10.655 [info] == Running 20210601082613 Mobilizon.Storage.Repo.Migrations.FixUserSettingsNullableFields.change/0 forward
server # [ 1077.112624] mobilizon_ctl[2216]: 13:59:10.671 [info] alter table user_settings
server # [ 1077.252985] mobilizon_ctl[2216]: 13:59:10.813 [info] == Migrated 20210601082613 in 0.1s
server # [ 1077.361469] mobilizon_ctl[2216]: 13:59:10.958 [info] == Running 20210608133040 Mobilizon.Storage.Repo.Migrations.AddMetadataToMedia.change/0 forward
server # [ 1077.393814] mobilizon_ctl[2216]: 13:59:10.985 [info] alter table medias
server # [ 1077.458333] mobilizon_ctl[2216]: 13:59:11.037 [info] == Migrated 20210608133040 in 0.0s
server # [ 1077.574307] mobilizon_ctl[2216]: 13:59:11.170 [info] == Running 20210622133516 Mobilizon.Storage.Repo.Migrations.CleanupPosts.up/0 forward
server # [ 1077.735770] mobilizon_ctl[2216]: 13:59:11.258 [info] == Migrated 20210622133516 in 0.0s
server # [ 1077.806524] mobilizon_ctl[2216]: 13:59:11.406 [info] == Running 20210622133555 Mobilizon.Storage.Repo.Migrations.AddIndexToPosts.up/0 forward
server # [ 1077.838340] mobilizon_ctl[2216]: 13:59:11.428 [info] create index if not exists posts_url_index
server # [ 1077.913685] mobilizon_ctl[2216]: 13:59:11.506 [info] == Migrated 20210622133555 in 0.0s
server # [ 1078.034574] mobilizon_ctl[2216]: 13:59:11.623 [info] == Running 20210730143924 Mobilizon.Storage.Repo.Migrations.PutUniqueIndexOnParticipantsUrls.up/0 forward
server # [ 1078.065268] mobilizon_ctl[2216]: 13:59:11.638 [info] create index if not exists participants_url_index
server # [ 1078.147215] mobilizon_ctl[2216]: 13:59:11.735 [info] == Migrated 20210730143924 in 0.1s
server # [ 1078.250834] mobilizon_ctl[2216]: 13:59:11.846 [info] == Running 20210805142745 Mobilizon.Storage.Repo.Migrations.AddMetadataForEvents.change/0 forward
server # [ 1078.287232] mobilizon_ctl[2216]: 13:59:11.858 [info] alter table events
server # [ 1078.346627] mobilizon_ctl[2216]: 13:59:11.926 [info] == Migrated 20210805142745 in 0.0s
server # [ 1078.461263] mobilizon_ctl[2216]: 13:59:12.054 [info] == Running 20210819161507 Mobilizon.Storage.Repo.Migrations.AddLanguageToEntities.change/0 forward
server # [ 1078.482651] mobilizon_ctl[2216]: 13:59:12.078 [info] alter table events
server # [ 1078.574507] mobilizon_ctl[2216]: 13:59:12.159 [info] alter table comments
server # [ 1078.636729] mobilizon_ctl[2216]: 13:59:12.231 [info] alter table posts
server # [ 1078.754981] mobilizon_ctl[2216]: 13:59:12.302 [info] == Migrated 20210819161507 in 0.2s
server # [ 1078.825769] mobilizon_ctl[2216]: 13:59:12.426 [info] == Running 20211001101902 Mobilizon.Repo.Migrations.CreateExports.change/0 forward
server # [ 1078.857162] mobilizon_ctl[2216]: 13:59:12.452 [info] create table exports
server # [ 1079.066618] mobilizon_ctl[2216]: 13:59:12.658 [info] create index exports_file_path_index
server # [ 1079.139675] mobilizon_ctl[2216]: 13:59:12.729 [info] == Migrated 20211001101902 in 0.3s
server # [ 1079.211185] mobilizon_ctl[2216]: 13:59:12.822 [info] == Running 20211008084901 Mobilizon.Storage.Repo.Migrations.AddTimezoneToAddresses.change/0 forward
server # [ 1079.242251] mobilizon_ctl[2216]: 13:59:12.835 [info] alter table addresses
server # [ 1079.273441] mobilizon_ctl[2216]: 13:59:12.864 [info] == Migrated 20211008084901 in 0.0s
server # [ 1079.383007] mobilizon_ctl[2216]: 13:59:12.987 [info] == Running 20211013090405 Mobilizon.Storage.Repo.Migrations.AddCodeToParticipants.change/0 forward
server # [ 1079.433611] mobilizon_ctl[2216]: 13:59:12.991 [info] alter table participants
server # [ 1079.476601] mobilizon_ctl[2216]: 13:59:13.058 [info] == Migrated 20211013090405 in 0.0s
server # [ 1079.557602] mobilizon_ctl[2216]: 13:59:13.162 [info] == Running 20211022093530 Mobilizon.Storage.Repo.Migrations.AddNotifyToFollowers.change/0 forward
server # [ 1079.596883] mobilizon_ctl[2216]: 13:59:13.171 [info] alter table followers
server # [ 1079.647574] mobilizon_ctl[2216]: 13:59:13.240 [info] == Migrated 20211022093530 in 0.0s
server # [ 1079.779024] mobilizon_ctl[2216]: 13:59:13.338 [info] == Running 20211223141104 Mobilizon.Storage.Repo.Migrations.AddInstanceMaterializedView.up/0 forward
server # [ 1079.838940] mobilizon_ctl[2216]: 13:59:13.435 [info] execute "CREATE MATERIALIZED VIEW instances AS\nSELECT\n  a.domain,\n  COUNT(DISTINCT(p.id)) AS person_count,\n  COUNT(DISTINCT(g.id)) AS group_count,\n  COUNT(DISTINCT(e.id)) AS event_count,\n  COUNT(f1.id) AS followers_count,\n  COUNT(f2.id) AS followings_count,\n  COUNT(r.id) AS reports_count,\n  SUM(COALESCE((m.file->>'size')::int, 0)) AS media_size\nFROM actors a\nLEFT JOIN actors p ON a.id = p.id AND p.type = 'Person'\nLEFT JOIN actors g ON a.id = g.id AND g.type = 'Group'\nLEFT JOIN events e ON a.id = e.organizer_actor_id\nLEFT JOIN followers f1 ON a.id = f1.actor_id\nLEFT JOIN followers f2 ON a.id = f2.target_actor_id\nLEFT JOIN reports r ON r.reported_id = a.id\nLEFT JOIN medias m ON m.actor_id = a.id\nWHERE a.domain IS NOT NULL\nGROUP BY a.domain;\n"
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1080.678424] mobilizon_ctl[2216]: 13:59:14.273 [info] execute "CREATE OR REPLACE FUNCTION refresh_instances()\nRETURNS trigger AS $$\nBEGIN\n  REFRESH MATERIALIZED VIEW instances;\n  RETURN NULL;\nEND;\n$$ LANGUAGE plpgsql;\n"
server # [ 1080.791954] mobilizon_ctl[2216]: 13:59:14.346 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger ON actors;\n"
server # [ 1080.810536] mobilizon_ctl[2216]: 13:59:14.371 [info] trigger "refresh_instances_trigger" for relation "actors" does not exist, skipping
server # [ 1080.822047] mobilizon_ctl[2216]: 13:59:14.380 [info] execute "CREATE TRIGGER refresh_instances_trigger\nAFTER INSERT OR UPDATE OR DELETE\nON actors\nFOR EACH STATEMENT\nEXECUTE PROCEDURE refresh_instances();\n"
server # [ 1080.841135] mobilizon_ctl[2216]: 13:59:14.422 [info] create index if not exists instances_domain_index
server # [ 1081.001632] mobilizon_ctl[2216]: 13:59:14.598 [info] == Migrated 20211223141104 in 1.2s
server # [ 1081.128711] mobilizon_ctl[2216]: 13:59:14.718 [info] == Running 20220321154138 Mobilizon.Storage.Repo.Migrations.CreateObanPeers.up/0 forward
server # [ 1081.193950] mobilizon_ctl[2216]: 13:59:14.790 [info] == Migrated 20220321154138 in 0.0s
server # [ 1081.248954] mobilizon_ctl[2216]: 13:59:14.851 [info] == Running 20220321154142 Mobilizon.Storage.Repo.Migrations.SwapPrimaryObanIndexes.change/0 forward
server # [ 1081.269416] mobilizon_ctl[2216]: 13:59:14.872 [info] create index if not exists public.oban_jobs_state_queue_priority_scheduled_at_id_index
server # [ 1081.360934] mobilizon_ctl[2216]: 13:59:14.946 [info] relation "oban_jobs_state_queue_priority_scheduled_at_id_index" already exists, skipping
server # [ 1081.393062] mobilizon_ctl[2216]: 13:59:14.947 [info] drop index if exists public.oban_jobs_queue_state_priority_scheduled_at_id_index
server # [ 1081.415930] mobilizon_ctl[2216]: 13:59:15.001 [info] index "oban_jobs_queue_state_priority_scheduled_at_id_index" does not exist, skipping
server # [ 1081.452761] mobilizon_ctl[2216]: 13:59:15.002 [info] == Migrated 20220321154142 in 0.1s
server # [ 1081.517113] mobilizon_ctl[2216]: 13:59:15.112 [info] == Running 20220328153640 Mobilizon.Storage.Repo.Migrations.SetAllEventsCategoryToMeeting.up/0 forward
server # [ 1081.545918] mobilizon_ctl[2216]: 13:59:15.115 [info] execute "UPDATE events SET category = 'MEETING'"
server # [ 1081.570460] mobilizon_ctl[2216]: 13:59:15.134 [info] == Migrated 20220328153640 in 0.0s
server # [ 1081.651681] mobilizon_ctl[2216]: 13:59:15.251 [info] == Running 20220407083712 Mobilizon.Storage.Repo.Migrations.AddIndexesToAddresses.up/0 forward
server # [ 1081.679988] mobilizon_ctl[2216]: 13:59:15.262 [info] create index if not exists idx_addresses_geom_x
server # [ 1081.746891] mobilizon_ctl[2216]: 13:59:15.337 [info] create index if not exists idx_addresses_geom_y
server # [ 1081.842793] mobilizon_ctl[2216]: 13:59:15.401 [info] == Migrated 20220407083712 in 0.1s
server # [ 1081.893737] mobilizon_ctl[2216]: 13:59:15.499 [info] == Running 20220505091224 Mobilizon.Storage.Repo.Migrations.ChangeRefreshInstanceTriggerToOnlyExecuteOnRelays.up/0 forward
server # [ 1081.922213] mobilizon_ctl[2216]: 13:59:15.519 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger ON actors;\n"
server # [ 1081.958303] mobilizon_ctl[2216]: 13:59:15.554 [info] execute "CREATE TRIGGER refresh_instances_trigger_new\nAFTER INSERT OR UPDATE\nON actors\nFOR EACH ROW\nWHEN (NEW.preferred_username = 'relay')\nEXECUTE PROCEDURE refresh_instances();\n"
server # [ 1081.992835] mobilizon_ctl[2216]: 13:59:15.589 [info] execute "CREATE TRIGGER refresh_instances_trigger_old\nAFTER DELETE\nON actors\nFOR EACH ROW\nWHEN (OLD.preferred_username = 'relay')\nEXECUTE PROCEDURE refresh_instances();\n"
server # [ 1082.026158] mobilizon_ctl[2216]: 13:59:15.621 [info] == Migrated 20220505091224 in 0.1s
server # [ 1082.130596] mobilizon_ctl[2216]: 13:59:15.736 [info] == Running 20220506105150 Mobilizon.Storage.Repo.Migrations.RemoveRefreshInstanceTriggers.up/0 forward
server # [ 1082.156958] mobilizon_ctl[2216]: 13:59:15.739 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger_new ON actors;\n"
server # [ 1082.192719] mobilizon_ctl[2216]: 13:59:15.794 [info] execute "DROP TRIGGER IF EXISTS refresh_instances_trigger_old ON actors;\n"
server # [ 1082.255703] mobilizon_ctl[2216]: 13:59:15.848 [info] == Migrated 20220506105150 in 0.1s
server # [ 1082.355613] mobilizon_ctl[2216]: 13:59:15.951 [info] == Running 20230208101626 Mobilizon.Repo.Migrations.CreateApplications.change/0 forward
server # [ 1082.401412] mobilizon_ctl[2216]: 13:59:15.983 [info] create table applications
server # [ 1082.582697] mobilizon_ctl[2216]: 13:59:16.181 [info] create index applications_owner_id_owner_type_index
server # [ 1082.696920] mobilizon_ctl[2216]: 13:59:16.265 [info] == Migrated 20230208101626 in 0.3s
server # [ 1082.831797] mobilizon_ctl[2216]: 13:59:16.398 [info] == Running 20230215125801 Mobilizon.Repo.Migrations.CreateApplicationTokens.change/0 forward
server # [ 1082.868803] mobilizon_ctl[2216]: 13:59:16.441 [info] create table application_tokens
server # [ 1083.162801] mobilizon_ctl[2216]: 13:59:16.757 [info] create index application_tokens_user_id_application_id_index
server # [ 1083.235618] mobilizon_ctl[2216]: 13:59:16.829 [info] == Migrated 20230215125801 in 0.4s
server # [ 1083.353228] mobilizon_ctl[2216]: 13:59:16.954 [info] == Running 20230217084253 Mobilizon.Repo.Migrations.CreateApplicationDeviceActivation.change/0 forward
server # [ 1083.401207] mobilizon_ctl[2216]: 13:59:16.982 [info] create table application_device_activation
server # [ 1083.697622] mobilizon_ctl[2216]: 13:59:17.282 [info] == Migrated 20230217084253 in 0.3s
server # [ 1083.907350] mobilizon_ctl[2216]: 13:59:17.468 [info] == Running 20230605143534 Mobilizon.Storage.Repo.Migrations.AllowMultipleEventsToBeReported.up/0 forward
server # [ 1083.931236] mobilizon_ctl[2216]: 13:59:17.478 [info] create table reports_events
server # [ 1084.006043] mobilizon_ctl[2216]: 13:59:17.600 [info] == Migrated 20230605143534 in 0.1s
server # [ 1084.137085] mobilizon_ctl[2216]: 13:59:17.726 [info] == Running 20230605153836 Mobilizon.Storage.Repo.Migrations.BackfillReportEventsWithOldEvents.up/0 forward
server # [ 1084.183921] mobilizon_ctl[2216]: 13:59:17.762 [info] == Migrated 20230605153836 in 0.0s
server # [ 1084.273394] mobilizon_ctl[2216]: 13:59:17.880 [info] == Running 20230605154106 Mobilizon.Storage.Repo.Migrations.RemoveObsoleteEventIdOnReports.up/0 forward
server # [ 1084.298271] mobilizon_ctl[2216]: 13:59:17.889 [info] alter table reports
server # [ 1084.345594] mobilizon_ctl[2216]: 13:59:17.949 [info] == Migrated 20230605154106 in 0.0s
server # [ 1084.468032] mobilizon_ctl[2216]: 13:59:18.080 [info] == Running 20230831153358 Mobilizon.Storage.Repo.Migrations.RemoveOnDeleteCascadeForReports.up/0 forward
server # [ 1084.515657] mobilizon_ctl[2216]: 13:59:18.111 [info] execute "DROP MATERIALIZED VIEW IF EXISTS instances;\n"
server # [ 1084.685722] mobilizon_ctl[2216]: 13:59:18.294 [info] drop constraint reports_reported_id_fkey from table reports
server # [ 1084.749039] mobilizon_ctl[2216]: 13:59:18.353 [info] drop constraint reports_reporter_id_fkey from table reports
server # [ 1084.809725] mobilizon_ctl[2216]: 13:59:18.408 [info] drop constraint reports_manager_id_fkey from table reports
server # [ 1084.858714] mobilizon_ctl[2216]: 13:59:18.458 [info] alter table reports
server # [ 1085.093175] mobilizon_ctl[2216]: 13:59:18.650 [info] drop constraint report_notes_moderator_id_fkey from table report_notes
server # [ 1085.117905] mobilizon_ctl[2216]: 13:59:18.715 [info] alter table report_notes
server # [ 1085.219262] mobilizon_ctl[2216]: 13:59:18.794 [info] execute "CREATE MATERIALIZED VIEW instances AS\nSELECT\n  a.domain,\n  COUNT(DISTINCT(p.id)) AS person_count,\n  COUNT(DISTINCT(g.id)) AS group_count,\n  COUNT(DISTINCT(e.id)) AS event_count,\n  COUNT(f1.id) AS followers_count,\n  COUNT(f2.id) AS followings_count,\n  COUNT(r.id) AS reports_count,\n  SUM(COALESCE((m.file->>'size')::int, 0)) AS media_size\nFROM actors a\nLEFT JOIN actors p ON a.id = p.id AND p.type = 'Person'\nLEFT JOIN actors g ON a.id = g.id AND g.type = 'Group'\nLEFT JOIN events e ON a.id = e.organizer_actor_id\nLEFT JOIN followers f1 ON a.id = f1.actor_id\nLEFT JOIN followers f2 ON a.id = f2.target_actor_id\nLEFT JOIN reports r ON r.reported_id = a.id\nLEFT JOIN medias m ON m.actor_id = a.id\nWHERE a.domain IS NOT NULL\nGROUP BY a.domain;\n"
server # [ 1085.875164] mobilizon_ctl[2216]: 13:59:19.464 [info] == Migrated 20230831153358 in 1.3s
server # [ 1085.991492] mobilizon_ctl[2216]: 13:59:19.593 [info] == Running 20230901160000 Mobilizon.Storage.Repo.Migrations.AddExternalUrlForEvents.up/0 forward
server # [ 1086.154476] mobilizon_ctl[2216]: 13:59:19.694 [info] alter table events
server # [ 1086.174084] mobilizon_ctl[2216]: 13:59:19.754 [info] execute "ALTER TABLE events ALTER COLUMN join_options TYPE VARCHAR USING join_options::text"
server # [ 1086.637190] mobilizon_ctl[2216]: 13:59:20.209 [info] execute "ALTER TABLE events ALTER COLUMN join_options DROP DEFAULT"
server # [ 1086.674630] mobilizon_ctl[2216]: 13:59:20.242 [info] execute "DROP TYPE public.join_options"
server # [ 1086.732946] mobilizon_ctl[2216]: 13:59:20.274 [info] execute "CREATE TYPE public.join_options AS ENUM ('free', 'restricted', 'invite', 'external')"
server # [ 1086.751232] mobilizon_ctl[2216]: 13:59:20.322 [info] execute "ALTER TABLE events ALTER COLUMN join_options TYPE join_options USING join_options::join_options"
server # [ 1087.073820] mobilizon_ctl[2216]: 13:59:20.658 [info] execute "ALTER TABLE events ALTER COLUMN join_options SET DEFAULT 'free'::join_options"
server # [ 1087.176031] mobilizon_ctl[2216]: 13:59:20.714 [info] == Migrated 20230901160000 in 1.1s
server # [ 1087.333606] mobilizon_ctl[2216]: 13:59:20.922 [info] == Running 20230920071502 Mobilizon.Storage.Repo.Migrations.AddActorConversations.up/0 forward
server # [ 1087.376705] mobilizon_ctl[2216]: 13:59:20.970 [info] create table conversations
server # [ 1087.623469] mobilizon_ctl[2216]: 13:59:21.213 [info] create table conversation_participants
server # [ 1087.813849] mobilizon_ctl[2216]: 13:59:21.414 [info] alter table comments
server # [ 1087.900848] mobilizon_ctl[2216]: 13:59:21.495 [info] == Migrated 20230920071502 in 0.5s
server # [ 1087.984849] mobilizon_ctl[2216]: 13:59:21.592 [info] == Running 20231220092536 Mobilizon.Storage.Repo.Migrations.AddActorInstances.change/0 forward
server # [ 1088.014597] mobilizon_ctl[2216]: 13:59:21.609 [info] create table instance_actors
server # [ 1088.219777] mobilizon_ctl[2216]: 13:59:21.777 [info] create index instance_actors_domain_index
server # [ 1088.243091] mobilizon_ctl[2216]: 13:59:21.833 [info] == Migrated 20231220092536 in 0.2s
server # [ 1088.333272] mobilizon_ctl[2216]: 13:59:21.931 [info] == Running 20240104103036 Mobilizon.Storage.Repo.Migrations.ChangeActorInstanceDescriptionTypeToText.up/0 forward
server # [ 1088.356850] mobilizon_ctl[2216]: 13:59:21.941 [info] alter table instance_actors
server # [ 1088.381352] mobilizon_ctl[2216]: 13:59:21.973 [info] == Migrated 20240104103036 in 0.0s
server # [ 1088.457899] mobilizon_ctl[2216]: 13:59:22.066 [info] == Running 20240222121054 Mobilizon.Storage.Repo.Migrations.CreateAdminSettingsMedias.change/0 forward
server # [ 1088.503910] mobilizon_ctl[2216]: 13:59:22.094 [info] create table admin_settings_medias
server # [ 1088.676916] mobilizon_ctl[2216]: 13:59:22.274 [info] create index admin_settings_medias_group_name_index
server # [ 1088.733506] mobilizon_ctl[2216]: 13:59:22.331 [info] == Migrated 20240222121054 in 0.2s
server # [ 1088.833309] mobilizon_ctl[2216]: 13:59:22.433 [info] == Running 20240725130410 Mobilizon.Storage.Repo.Migrations.AddEventPhysicalAddressIndex.up/0 forward
server # [ 1088.853097] mobilizon_ctl[2216]: 13:59:22.445 [info] create index events_phys_addr_id
server # [ 1088.918503] mobilizon_ctl[2216]: 13:59:22.506 [info] == Migrated 20240725130410 in 0.0s
server # [ 1089.021298] mobilizon_ctl[2216]: 13:59:22.614 [info] == Running 20250521145952 Mobilizon.Storage.Repo.Migrations.AddUuidToMedia.change/0 forward
server # [ 1089.045931] mobilizon_ctl[2216]: 13:59:22.630 [info] alter table medias
server # [ 1089.265814] mobilizon_ctl[2216]: 13:59:22.806 [info] create index medias_uuid_index
server # [ 1089.289872] mobilizon_ctl[2216]: 13:59:22.877 [info] == Migrated 20250521145952 in 0.2s
server # [ 1089.413097] mobilizon_ctl[2216]: 13:59:23.010 [info] == Running 20250911120910 Mobilizon.Storage.Repo.Migrations.AddModerationToUser.change/0 forward
server # [ 1089.433129] mobilizon_ctl[2216]: 13:59:23.021 [info] alter table users
server # [ 1089.484872] mobilizon_ctl[2216]: 13:59:23.081 [info] == Migrated 20250911120910 in 0.0s
server # [ 1089.569480] mobilizon_ctl[2216]: 13:59:23.176 [info] == Running 20250919143627 Mobilizon.Storage.Repo.Migrations.UpdateRoleToUser.change/0 forward
server # [ 1089.584843] mobilizon_ctl[2216]: 13:59:23.178 [info] execute "ALTER TYPE user_role ADD VALUE IF NOT EXISTS 'pending';"
server # [ 1089.611171] mobilizon_ctl[2216]: 13:59:23.213 [info] enum label "pending" already exists, skipping
server # [ 1089.631008] mobilizon_ctl[2216]: 13:59:23.214 [info] == Migrated 20250919143627 in 0.0s
server # [ 1089.708980] mobilizon_ctl[2216]: 13:59:23.306 [info] == Running 20251027133219 Mobilizon.Storage.Repo.Migrations.CreateInvitations.change/0 forward
server # [ 1089.761326] mobilizon_ctl[2216]: 13:59:23.334 [info] create table invitations
server # [ 1089.957523] mobilizon_ctl[2216]: 13:59:23.557 [info] create index invitations_token_index
server # [ 1090.028913] mobilizon_ctl[2216]: 13:59:23.618 [info] == Migrated 20251027133219 in 0.3s
server # [ 1090.109489] mobilizon_ctl[2216]: 13:59:23.719 [info] == Running 20251111093015 Mobilizon.Storage.Repo.Migrations.AddAllowToGroup.change/0 forward
server # [ 1090.147581] mobilizon_ctl[2216]: 13:59:23.742 [info] alter table actors
server # [ 1090.199309] mobilizon_ctl[2216]: 13:59:23.790 [info] == Migrated 20251111093015 in 0.0s
server # [ 1091.007447] systemd[1]: Started Mobilizon federated organization and mobilization platform.
server # [ 1091.101588] systemd[1]: Reached target Multi-User System.
server # [ 1091.129590] systemd[1]: Startup finished in 7.279s (kernel) + 1min 47.663s (initrd) + 16min 16.179s (userspace) = 18min 11.122s.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1314.927277] postgres[1389]: [1389] LOG:  checkpoint starting: time
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1342.983189] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 260 buffers (1.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=27.097 s, sync=0.741 s, total=28.058 s; sync files=205, longest=0.024 s, average=0.004 s; distance=1882 kB, estimate=11604 kB; lsn=0/264B220, redo lsn=0/264B190
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1407.124996] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1488.883266] mobilizon[2504]: 14:06:02.414 [info] No tzdata release files found in custom data dir. Copying release file from tzdata priv dir.
server # [ 1496.473454] mobilizon[2504]: 14:06:09.947 [info] Source for database :city not found: "/var/lib/mobilizon/geo/GeoLite2-City.mmdb"
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1501.442599] mobilizon[2504]: 14:06:14.995 [info] Loading 122 CA(s) from :otp store
server # [ 1504.992631] mobilizon[2504]: 14:06:18.528 [info] Running Mobilizon.Web.Endpoint with Bandit 1.10.3 at ::1:41395 (http)
server # [ 1505.005534] mobilizon[2504]: 14:06:18.572 [info] Access Mobilizon.Web.Endpoint at https://acme.test
server # [ 1506.877652] mobilizon[2504]: 14:06:20.347 [info] {"message":"notifier only receiving messages from its own node, functionality may be degraded","source":"oban","event":"notifier:switch","connectivity_status":"solitary"}
server # [ 1506.968667] mobilizon[2504]: 14:06:20.513 [info] {"message":"peer became leader","node":"mobilizon@server","source":"oban","event":"peer:election","leader":true,"was_leader":false}
server # [ 1508.450649] systemd[1]: Starting Virtual Console Setup...
server # [ 1509.719497] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [ 1509.749396] systemd[1]: Stopped Virtual Console Setup.
server # [ 1510.413811] systemd[1]: Starting Virtual Console Setup...
server # [ 1519.747678] systemd-vconsole-setup[3145]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [ 1519.888028] systemd[1]: Finished Virtual Console Setup.
server # [ 1526.723323] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1535.686326] mobilizon[2504]: 14:06:49.270 [info] {"source":"oban","duration":80489,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1543.164815] 8021q: 802.1Q VLAN Support v1.8
server # [ 1544.900214] cfg80211: Loading compiled-in X.509 certificates for regulatory database
server # [ 1545.411832] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
server # [ 1545.423810] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
server # [ 1545.488038] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2
server # [ 1545.505041] cfg80211: failed to load regulatory.db
server # [ 1546.755743] mobilizon[2504]: 14:07:00.334 [info] {"source":"oban","duration":25268,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 1549.545876] (udev-worker)[3245]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.
server # [ 1549.562625] (udev-worker)[3245]: Network interface NamePolicy= disabled on kernel command line.
server # [ 1550.439648] systemd[1]: Condition check resulted in Virtio network device being skipped.
server # [ 1550.522155] systemd[1]: Starting Address configuration of eth1...
server # [ 1552.677030] 8021q: adding VLAN 0 to HW filter on device eth1
server # [ 1552.975374] network-addresses-eth1-start[3249]: adding address 192.168.1.1/24... done
server # [ 1553.310320] network-addresses-eth1-start[3249]: adding address 2001:db8:1::1/64... done
server # [ 1553.779943] systemd[1]: Finished Address configuration of eth1.
server # [ 1556.517172] (udev-worker)[3260]: Network interface NamePolicy= disabled on kernel command line.
server # [ 1559.032800] 8021q: adding VLAN 0 to HW filter on device eth0
server # [ 1558.942870] dhcpcd[1183]: eth0: waiting for carrier
server # [ 1558.966465] dhcpcd[1183]: eth0: carrier acquired
server # [ 1559.301149] dhcpcd[1183]: DUID 00:01:00:01:32:6b:83:90:52:54:00:12:34:56
server # [ 1559.333610] dhcpcd[1183]: eth0: IAID 00:12:34:56
server # [ 1559.350277] dhcpcd[1183]: eth0: adding address fe80::5054:ff:fe12:3456
server # [ 1559.378771] dhcpcd[1183]: eth0: soliciting a DHCP lease
server # [ 1559.433361] dhcpcd[1183]: eth0: offered 10.0.2.15 from 10.0.2.2
server # [ 1559.453761] dhcpcd[1183]: eth0: probing address 10.0.2.15/24
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1561.503590] dhcpcd[1183]: eth0: soliciting an IPv6 router
server # [ 1561.522696] dhcpcd[1183]: eth0: Router Advertisement from fe80::2
server # [ 1561.548877] dhcpcd[1183]: eth0: adding address fec0::5054:ff:fe12:3456/64
server # [ 1561.592440] dhcpcd[1183]: eth0: adding route to fec0::/64
server # [ 1561.598693] dhcpcd[1183]: eth0: adding default route via fe80::2
server # [ 1564.823390] dhcpcd[1183]: eth0: leased 10.0.2.15 for 86400 seconds
server # [ 1564.839253] dhcpcd[1183]: eth0: adding route to 10.0.2.0/24
server # [ 1564.851236] dhcpcd[1183]: eth0: adding default route via 10.0.2.2
server # [ 1565.874031] mobilizon[2504]: 14:07:19.394 [info] {"source":"oban","duration":114933,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1582.995705] systemd-logind[1057]: Watching system buttons on /dev/input/event1 (gpio-keys)
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1592.509421] systemd-logind[1057]: Watching system buttons on /dev/input/event0 (QEMU QEMU USB Keyboard)
server # [ 1595.906756] mobilizon[2504]: 14:07:49.477 [info] {"source":"oban","duration":78999,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1606.781171] mobilizon[2504]: 14:08:00.362 [info] {"source":"oban","duration":12964,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 1615.103875] postgres[1389]: [1389] LOG:  checkpoint starting: time
server # [ 1617.614175] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 23 buffers (0.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.367 s, sync=0.065 s, total=2.542 s; sync files=17, longest=0.026 s, average=0.004 s; distance=8 kB, estimate=10444 kB; lsn=0/264D408, redo lsn=0/264D3B0
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1625.982904] mobilizon[2504]: 14:08:19.553 [info] {"source":"oban","duration":72935,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1656.071840] mobilizon[2504]: 14:08:49.628 [info] {"source":"oban","duration":71493,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1666.795298] mobilizon[2504]: 14:09:00.378 [info] {"source":"oban","duration":12138,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1686.121058] mobilizon[2504]: 14:09:19.693 [info] {"source":"oban","duration":62364,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1716.220024] mobilizon[2504]: 14:09:49.787 [info] {"source":"oban","duration":86079,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1726.847451] mobilizon[2504]: 14:10:00.421 [info] {"source":"oban","duration":34086,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1746.258817] mobilizon[2504]: 14:10:19.845 [info] {"source":"oban","duration":53583,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1766.036976] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [ 1766.051889] systemd[1]: Stopped Virtual Console Setup.
server # [ 1766.067953] systemd[1]: systemd-vconsole-setup.service: Consumed 1.764s CPU time over 4min 15.616s wall clock time, 2.6M memory peak.
server # [ 1766.090942] systemd[1]: Stopping Virtual Console Setup...
server # [ 1766.233412] systemd[1]: Starting Virtual Console Setup...
server # [ 1767.311780] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
server # [ 1767.334248] systemd[1]: Stopped Virtual Console Setup.
server # [ 1767.584559] systemd[1]: Starting Virtual Console Setup...
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1772.846158] systemd-vconsole-setup[4001]: Configuration of first virtual console was skipped, ignoring remaining ones.
server # [ 1772.955962] systemd[1]: Finished Virtual Console Setup.
server # [ 1776.347919] mobilizon[2504]: 14:10:49.917 [info] {"source":"oban","duration":64968,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1778.755615] systemd-logind[1057]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)
server # [ 1781.490872] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
server # [ 1786.841402] mobilizon[2504]: 14:11:00.437 [info] {"source":"oban","duration":10955,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1806.342418] mobilizon[2504]: 14:11:19.943 [info] {"source":"oban","duration":22582,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1836.373264] mobilizon[2504]: 14:11:49.973 [info] {"source":"oban","duration":25366,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1846.853214] mobilizon[2504]: 14:12:00.452 [info] {"source":"oban","duration":9804,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1866.405210] mobilizon[2504]: 14:12:20.002 [info] {"source":"oban","duration":24654,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1896.426651] mobilizon[2504]: 14:12:50.028 [info] {"source":"oban","duration":23126,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1906.870504] mobilizon[2504]: 14:13:00.465 [info] {"source":"oban","duration":8779,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 1914.622071] postgres[1389]: [1389] LOG:  checkpoint starting: time
server # [ 1914.732514] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.022 s, sync=0.007 s, total=0.130 s; sync files=1, longest=0.007 s, average=0.007 s; distance=4 kB, estimate=9400 kB; lsn=0/264E4B8, redo lsn=0/264E460
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1926.458071] mobilizon[2504]: 14:13:20.057 [info] {"source":"oban","duration":23967,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1956.493752] mobilizon[2504]: 14:13:50.092 [info] {"source":"oban","duration":32135,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 1966.883848] mobilizon[2504]: 14:14:00.485 [info] {"source":"oban","duration":15796,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 1986.530942] mobilizon[2504]: 14:14:20.132 [info] {"source":"oban","duration":19695,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2016.579819] mobilizon[2504]: 14:14:50.181 [info] {"source":"oban","duration":44976,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2026.898195] mobilizon[2504]: 14:15:00.499 [info] {"source":"oban","duration":9603,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2046.619408] mobilizon[2504]: 14:15:20.209 [info] {"source":"oban","duration":24409,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2076.633205] mobilizon[2504]: 14:15:50.235 [info] {"source":"oban","duration":22908,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2086.910471] mobilizon[2504]: 14:16:00.512 [info] {"source":"oban","duration":8908,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2106.665120] mobilizon[2504]: 14:16:20.264 [info] {"source":"oban","duration":26068,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2136.691550] mobilizon[2504]: 14:16:50.288 [info] {"source":"oban","duration":19734,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2146.929000] mobilizon[2504]: 14:17:00.530 [info] {"source":"oban","duration":12686,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2166.714799] mobilizon[2504]: 14:17:20.317 [info] {"source":"oban","duration":24447,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2196.777573] mobilizon[2504]: 14:17:50.378 [info] {"source":"oban","duration":57070,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2206.943121] mobilizon[2504]: 14:18:00.544 [info] {"source":"oban","duration":9654,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 2214.809586] postgres[1389]: [1389] LOG:  checkpoint starting: time
server # [ 2214.918178] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.027 s, sync=0.012 s, total=0.136 s; sync files=1, longest=0.012 s, average=0.012 s; distance=4 kB, estimate=8461 kB; lsn=0/264F540, redo lsn=0/264F4E8
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2226.845580] mobilizon[2504]: 14:18:20.418 [info] {"source":"oban","duration":36396,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2256.851029] mobilizon[2504]: 14:18:50.451 [info] {"source":"oban","duration":29308,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2266.957200] mobilizon[2504]: 14:19:00.557 [info] {"source":"oban","duration":10249,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2286.877406] mobilizon[2504]: 14:19:20.479 [info] {"source":"oban","duration":24999,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2316.911966] mobilizon[2504]: 14:19:50.509 [info] {"source":"oban","duration":24551,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2326.976042] mobilizon[2504]: 14:20:00.576 [info] {"source":"oban","duration":14403,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2346.935674] mobilizon[2504]: 14:20:20.536 [info] {"source":"oban","duration":22382,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2376.969128] mobilizon[2504]: 14:20:50.570 [info] {"source":"oban","duration":30663,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2386.990052] mobilizon[2504]: 14:21:00.590 [info] {"source":"oban","duration":8715,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2406.999024] mobilizon[2504]: 14:21:20.597 [info] {"source":"oban","duration":23912,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2437.022326] mobilizon[2504]: 14:21:50.623 [info] {"source":"oban","duration":22185,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2447.003224] mobilizon[2504]: 14:22:00.605 [info] {"source":"oban","duration":10576,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2467.050269] mobilizon[2504]: 14:22:20.651 [info] {"source":"oban","duration":24892,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2497.078258] mobilizon[2504]: 14:22:50.677 [info] {"source":"oban","duration":22700,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2507.017972] mobilizon[2504]: 14:23:00.620 [info] {"source":"oban","duration":9898,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 2514.993083] postgres[1389]: [1389] LOG:  checkpoint starting: time
server # [ 2515.568835] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 5 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.436 s, sync=0.045 s, total=0.601 s; sync files=7, longest=0.014 s, average=0.007 s; distance=18 kB, estimate=7616 kB; lsn=0/2653D58, redo lsn=0/2653D00
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2527.102519] mobilizon[2504]: 14:23:20.705 [info] {"source":"oban","duration":24323,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2557.125476] mobilizon[2504]: 14:23:50.728 [info] {"source":"oban","duration":19997,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2567.067452] mobilizon[2504]: 14:24:00.645 [info] {"source":"oban","duration":21971,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2587.156846] mobilizon[2504]: 14:24:20.755 [info] {"source":"oban","duration":23545,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2617.176962] mobilizon[2504]: 14:24:50.780 [info] {"source":"oban","duration":21750,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2627.061534] mobilizon[2504]: 14:25:00.661 [info] {"source":"oban","duration":10561,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2647.208045] mobilizon[2504]: 14:25:20.809 [info] {"source":"oban","duration":25911,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2677.233536] mobilizon[2504]: 14:25:50.834 [info] {"source":"oban","duration":21291,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2687.075529] mobilizon[2504]: 14:26:00.675 [info] {"source":"oban","duration":9940,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2707.271056] mobilizon[2504]: 14:26:20.874 [info] {"source":"oban","duration":21024,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2737.307853] mobilizon[2504]: 14:26:50.894 [info] {"source":"oban","duration":17026,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2747.090450] mobilizon[2504]: 14:27:00.692 [info] {"source":"oban","duration":12193,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2767.325723] mobilizon[2504]: 14:27:20.924 [info] {"source":"oban","duration":25599,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2797.345048] mobilizon[2504]: 14:27:50.947 [info] {"source":"oban","duration":20110,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2807.107492] mobilizon[2504]: 14:28:00.709 [info] {"source":"oban","duration":11047,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 2814.659107] postgres[1389]: [1389] LOG:  checkpoint starting: time
server # [ 2814.742144] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.021 s, sync=0.009 s, total=0.108 s; sync files=1, longest=0.009 s, average=0.009 s; distance=4 kB, estimate=6855 kB; lsn=0/2654E08, redo lsn=0/2654DB0
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2827.365643] mobilizon[2504]: 14:28:20.968 [info] {"source":"oban","duration":17258,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2857.397393] mobilizon[2504]: 14:28:50.996 [info] {"source":"oban","duration":24086,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2867.122008] mobilizon[2504]: 14:29:00.724 [info] {"source":"oban","duration":9717,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2887.419719] mobilizon[2504]: 14:29:21.021 [info] {"source":"oban","duration":22356,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2917.444667] mobilizon[2504]: 14:29:51.047 [info] {"source":"oban","duration":22188,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2927.139101] mobilizon[2504]: 14:30:00.740 [info] {"source":"oban","duration":7368,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2947.476034] mobilizon[2504]: 14:30:21.074 [info] {"source":"oban","duration":24176,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 2977.503542] mobilizon[2504]: 14:30:51.106 [info] {"source":"oban","duration":27136,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 2987.182655] mobilizon[2504]: 14:31:00.761 [info] {"source":"oban","duration":15850,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3007.531025] mobilizon[2504]: 14:31:21.132 [info] {"source":"oban","duration":22560,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3037.554212] mobilizon[2504]: 14:31:51.152 [info] {"source":"oban","duration":16997,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3047.171686] mobilizon[2504]: 14:32:00.773 [info] {"source":"oban","duration":8127,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3067.570890] mobilizon[2504]: 14:32:21.173 [info] {"source":"oban","duration":17754,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3097.593683] mobilizon[2504]: 14:32:51.195 [info] {"source":"oban","duration":18215,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3107.186748] mobilizon[2504]: 14:33:00.789 [info] {"source":"oban","duration":10862,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 3114.821560] postgres[1389]: [1389] LOG:  checkpoint starting: time
server # [ 3115.178668] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.228 s, sync=0.028 s, total=0.381 s; sync files=3, longest=0.016 s, average=0.010 s; distance=16 kB, estimate=6171 kB; lsn=0/2659140, redo lsn=0/26590E8
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3127.617771] mobilizon[2504]: 14:33:21.217 [info] {"source":"oban","duration":18616,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3157.637758] mobilizon[2504]: 14:33:51.241 [info] {"source":"oban","duration":20482,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3167.202156] mobilizon[2504]: 14:34:00.802 [info] {"source":"oban","duration":8358,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3187.667519] mobilizon[2504]: 14:34:21.265 [info] {"source":"oban","duration":21282,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3217.698089] mobilizon[2504]: 14:34:51.294 [info] {"source":"oban","duration":25757,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3227.221945] mobilizon[2504]: 14:35:00.820 [info] {"source":"oban","duration":12658,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3247.716042] mobilizon[2504]: 14:35:21.318 [info] {"source":"oban","duration":21335,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3277.737969] mobilizon[2504]: 14:35:51.341 [info] {"source":"oban","duration":19931,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3287.231909] mobilizon[2504]: 14:36:00.833 [info] {"source":"oban","duration":9231,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3307.782505] mobilizon[2504]: 14:36:21.364 [info] {"source":"oban","duration":19238,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3337.789650] mobilizon[2504]: 14:36:51.392 [info] {"source":"oban","duration":24284,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3347.245824] mobilizon[2504]: 14:37:00.847 [info] {"source":"oban","duration":8860,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3367.827631] mobilizon[2504]: 14:37:21.426 [info] {"source":"oban","duration":31015,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3397.844646] mobilizon[2504]: 14:37:51.444 [info] {"source":"oban","duration":14343,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3407.259745] mobilizon[2504]: 14:38:00.861 [info] {"source":"oban","duration":10045,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server # [ 3415.251948] postgres[1389]: [1389] LOG:  checkpoint starting: time
server # [ 3415.363327] postgres[1389]: [1389] LOG:  checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.024 s, sync=0.016 s, total=0.133 s; sync files=1, longest=0.016 s, average=0.016 s; distance=4 kB, estimate=5554 kB; lsn=0/265A1E0, redo lsn=0/265A188
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3427.869351] mobilizon[2504]: 14:38:21.469 [info] {"source":"oban","duration":21980,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3457.896898] mobilizon[2504]: 14:38:51.499 [info] {"source":"oban","duration":25880,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3467.272061] mobilizon[2504]: 14:39:00.873 [info] {"source":"oban","duration":8750,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3487.925213] mobilizon[2504]: 14:39:21.522 [info] {"source":"oban","duration":17320,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3517.949923] mobilizon[2504]: 14:39:51.552 [info] {"source":"oban","duration":25934,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3527.287801] mobilizon[2504]: 14:40:00.889 [info] {"source":"oban","duration":10772,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3547.981857] mobilizon[2504]: 14:40:21.581 [info] {"source":"oban","duration":24508,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server: Guest root shell did not produce any data yet...
server:   To debug, enter the VM and run 'systemctl status backdoor.service'.
server # [ 3578.002237] mobilizon[2504]: 14:40:51.600 [info] {"source":"oban","duration":15937,"event":"plugin:stop","plugin":"Oban.Plugins.Pruner","pruned_count":0}
server # [ 3587.303307] mobilizon[2504]: 14:41:00.904 [info] {"source":"oban","duration":11017,"event":"plugin:stop","plugin":"Oban.Plugins.Cron","jobs":[]}
timeout reached; test terminating...
kill QemuMachine (pid 12)
server # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/lqn6mbgzzdrqq2qkwddcmxj9z6amdd86-python3-3.13.13/bin/python3.13)
cleanup
kill QemuMachine (pid 12)
Terminated                 /nix/store/1lxxsi1vs9gb5ccvv03d1h8aaka0mdl3-nixos-test-driver-mobilizon/bin/nixos-test-driver -o $out