vm-test-run-horae-e2e-oidc
checks.x86_64-linux.e2e-oidc
· build #801
· raw
1bld1: building 045zqmpmb1xk6mgmicngsj9j4zlhzfyb-vm-test-run-horae-e2e-oidc.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600 seconds6run the VM test script7additionally exposed symbols:8 server,9 vlan1,10 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_ssh11server: starting vm12server # Disk image does not exist, creating the virtualisation disk image...13server # Formatting '/build/vm-state-server/tmp.y6k85f4z0e', fmt=raw size=107374182414server # mke2fs 1.47.4 (6-Mar-2025)15server # Discarding device blocks: 0/262144 done16server # Creating filesystem with 262144 4k blocks and 65536 inodes17server: QEMU running (pid 12)18server # Filesystem UUID: 3f9f0d91-3136-478b-8b38-a6ff9d73070519server # Superblock backups stored on blocks:20server # 32768, 98304, 163840, 22937621server # 22server # Allocating group tables: 0/8 done23server # Writing inode tables: 0/8 done24server # Creating journal (8192 blocks): done25server # Writing superblocks and filesystem accounting information: 0/8 done26server # 27server # Virtualisation disk image created.28server # Creating Nix store image...29server # Created Nix store image.30server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)31server # 32server # 33server # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+3EFD1830+3EF31830 CA0034server # Press Ctrl-B to configure iPXE (PCI 00:03.0)...35server # 36server # 37server # 38server # 39server # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 3EFD1830 3EF31830 CB0040server # Press Ctrl-B to configure iPXE (PCI 00:09.0)...41server # 42server # 43server # Booting from ROM...44server # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.38 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Sat Jul 4 11:44:22 UTC 202645server # [ 0.000000] Command line: console=ttyS0 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/im1cwbkvfvjcxa475bx91wi6062xxpx6-nixos-system-server-test/init regInfo=/nix/store/3bmxs6n9lx8rvy69gf9i92nxx7v3c5cy-closure-info/registration console=ttyS0,115200n8 console=tty046server # [ 0.000000] x86/split lock detection: #DB: warning on user-space bus_locks47server # [ 0.000000] BIOS-provided physical RAM map:48server # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable49server # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved50server # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved51server # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable52server # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved53server # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved54server # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved55server # [ 0.000000] NX (Execute Disable) protection: active56server # [ 0.000000] APIC: Static calls initialized57server # [ 0.000000] SMBIOS 2.8 present.58server # [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201459server # [ 0.000000] DMI: Memory slots populated: 1/160server # [ 0.000000] Hypervisor detected: KVM61server # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000062server # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0063server # [ 0.000001] kvm-clock: using sched offset of 542818077 cycles64server # [ 0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns65server # [ 0.000011] tsc: Detected 2100.000 MHz processor66server # [ 0.001161] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000067server # [ 0.001206] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs68server # [ 0.001209] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT69server # [ 0.011426] found SMP MP-table at [mem 0x000f5470-0x000f547f]70server # [ 0.011450] Using GB pages for direct mapping71server # [ 0.011516] RAMDISK: [mem 0x3e4dd000-0x3ffcffff]72server # [ 0.011522] ACPI: Early table checksum verification disabled73server # [ 0.011526] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )74server # [ 0.011534] ACPI: RSDT 0x000000003FFE23CC 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001)75server # [ 0.011545] ACPI: FACP 0x000000003FFE2280 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001)76server # [ 0.011553] ACPI: DSDT 0x000000003FFE0040 002240 (v01 BOCHS BXPC 00000001 BXPC 00000001)77server # [ 0.011557] ACPI: FACS 0x000000003FFE0000 00004078server # [ 0.011560] ACPI: APIC 0x000000003FFE22F4 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)79server # [ 0.011564] ACPI: HPET 0x000000003FFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)80server # [ 0.011569] ACPI: WAET 0x000000003FFE23A4 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)81server # [ 0.011572] ACPI: Reserving FACP table memory at [mem 0x3ffe2280-0x3ffe22f3]82server # [ 0.011573] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe227f]83server # [ 0.011574] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]84server # [ 0.011575] ACPI: Reserving APIC table memory at [mem 0x3ffe22f4-0x3ffe236b]85server # [ 0.011575] ACPI: Reserving HPET table memory at [mem 0x3ffe236c-0x3ffe23a3]86server # [ 0.011576] ACPI: Reserving WAET table memory at [mem 0x3ffe23a4-0x3ffe23cb]87server # [ 0.011899] No NUMA configuration found88server # [ 0.011899] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]89server # [ 0.011902] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]90server # [ 0.014089] Zone ranges:91server # [ 0.014091] DMA [mem 0x0000000000001000-0x0000000000ffffff]92server # [ 0.014093] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]93server # [ 0.014094] Normal empty94server # [ 0.014095] Device empty95server # [ 0.014096] Movable zone start for each node96server # [ 0.014096] Early memory node ranges97server # [ 0.014097] node 0: [mem 0x0000000000001000-0x000000000009efff]98server # [ 0.014098] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]99server # [ 0.014100] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]100server # [ 0.014160] On node 0, zone DMA: 1 pages in unavailable ranges101server # [ 0.014411] On node 0, zone DMA: 97 pages in unavailable ranges102server # [ 0.029928] On node 0, zone DMA32: 40 pages in unavailable ranges103server # [ 0.030497] ACPI: PM-Timer IO Port: 0x608104server # [ 0.030526] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])105server # [ 0.030577] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23106server # [ 0.030581] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)107server # [ 0.030582] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)108server # [ 0.030584] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)109server # [ 0.030585] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)110server # [ 0.030590] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)111server # [ 0.030600] ACPI: Using ACPI (MADT) for SMP configuration information112server # [ 0.030608] ACPI: HPET id: 0x8086a201 base: 0xfed00000113server # [ 0.030622] TSC deadline timer available114server # [ 0.030626] CPU topo: Max. logical packages: 1115server # [ 0.030627] CPU topo: Max. logical dies: 1116server # [ 0.030627] CPU topo: Max. dies per package: 1117server # [ 0.030630] CPU topo: Max. threads per core: 1118server # [ 0.030631] CPU topo: Num. cores per package: 1119server # [ 0.030632] CPU topo: Num. threads per package: 1120server # [ 0.030632] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs121server # [ 0.030654] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()122server # [ 0.030727] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]123server # [ 0.030737] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]124server # [ 0.030745] [mem 0x40000000-0xfeffbfff] available for PCI devices125server # [ 0.030748] Booting paravirtualized kernel on KVM126server # [ 0.030758] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns127server # [ 0.038546] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1128server # [ 0.042137] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152129server # [ 0.042194] kvm-guest: PV spinlocks disabled, single CPU130server # [ 0.042196] Kernel command line: console=ttyS0 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/im1cwbkvfvjcxa475bx91wi6062xxpx6-nixos-system-server-test/init regInfo=/nix/store/3bmxs6n9lx8rvy69gf9i92nxx7v3c5cy-closure-info/registration console=ttyS0,115200n8 console=tty0131server # [ 0.042541] Unknown kernel command line parameters "regInfo=/nix/store/3bmxs6n9lx8rvy69gf9i92nxx7v3c5cy-closure-info/registration", will be passed to user space.132server # [ 0.042562] random: crng init done133server # [ 0.042563] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes134server # [ 0.042612] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135server # [ 0.043127] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136server # [ 0.043214] Fallback order for Node 0: 0137server # [ 0.043217] Built 1 zonelists, mobility grouping on. Total pages: 262006138server # [ 0.043218] Policy zone: DMA32139server # [ 0.045653] mem auto-init: stack:all(zero), heap alloc:on, heap free:off140server # [ 0.050146] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1141server # [ 0.052548] allocated 2097152 bytes of page_ext142server # [ 0.071454] ftrace: allocating 48598 entries in 192 pages143server # [ 0.071466] ftrace: allocated 192 pages with 2 groups144server # [ 0.073053] Dynamic Preempt: lazy145server # [ 0.073258] rcu: Preemptible hierarchical RCU implementation.146server # [ 0.073258] rcu: RCU event tracing is enabled.147server # [ 0.073259] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.148server # [ 0.073260] Trampoline variant of Tasks RCU enabled.149server # [ 0.073263] Rude variant of Tasks RCU enabled.150server # [ 0.073263] Tracing variant of Tasks RCU enabled.151server # [ 0.073265] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.152server # [ 0.073266] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1153server # [ 0.073288] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154server # [ 0.073290] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155server # [ 0.073291] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156server # [ 0.082003] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16157server # [ 0.082441] rcu: srcu_init: Setting srcu_struct sizes based on contention.158server # [ 0.082661] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)159server # [ 0.087551] Console: colour VGA+ 80x25160server # [ 0.087555] printk: legacy console [tty0] enabled161server # [ 0.116663] printk: legacy console [ttyS0] enabled162server # [ 0.197757] ACPI: Core revision 20250807163server # [ 0.198546] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns164server # [ 0.199899] APIC: Switch to symmetric I/O mode setup165server # [ 0.200694] x2apic enabled166server # [ 0.201355] APIC: Switched APIC routing to: physical x2apic167server # [ 0.202983] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1168server # [ 0.203732] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1e4530a99b6, max_idle_ns: 440795257976 ns169server # [ 0.205031] Calibrating delay loop (skipped) preset value.. 4200.00 BogoMIPS (lpj=2100000)170server # [ 0.206174] x86/cpu: User Mode Instruction Prevention (UMIP) activated171server # [ 0.207129] CET detected: Indirect Branch Tracking enabled172server # [ 0.208026] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0173server # [ 0.209024] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0174server # [ 0.209888] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto175server # [ 0.211025] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl176server # [ 0.212025] Spectre V2 : Mitigation: Enhanced / Automatic IBRS177server # [ 0.213025] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization178server # [ 0.214026] Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT179server # [ 0.215026] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier180server # [ 0.216057] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'181server # [ 0.217024] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'182server # [ 0.218024] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'183server # [ 0.220024] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'184server # [ 0.221024] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'185server # [ 0.222024] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'186server # [ 0.223024] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'187server # [ 0.224024] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'188server # [ 0.225024] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'189server # [ 0.226024] x86/fpu: Supporting XSAVE feature 0x20000: 'AMX Tile config'190server # [ 0.227024] x86/fpu: Supporting XSAVE feature 0x40000: 'AMX Tile data'191server # [ 0.228024] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256192server # [ 0.229024] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64193server # [ 0.230024] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512194server # [ 0.230826] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024195server # [ 0.231610] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8196server # [ 0.232024] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16197server # [ 0.233024] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24198server # [ 0.234024] x86/fpu: xstate_offset[17]: 2496, xstate_sizes[17]: 64199server # [ 0.235024] x86/fpu: xstate_offset[18]: 2560, xstate_sizes[18]: 8192200server # [ 0.235845] x86/fpu: Enabled xstate features 0x61ae7, context size is 10752 bytes, using 'compacted' format.201server # [ 0.263668] Freeing SMP alternatives memory: 44K202server # [ 0.264026] pid_max: default: 32768 minimum: 301203server # [ 0.265046] LSM: initializing lsm=capability,landlock,yama,bpf,ima204server # [ 0.265841] landlock: Up and running.205server # [ 0.266354] Yama: becoming mindful.206server # [ 0.266995] LSM support for eBPF active207server # [ 0.267472] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)208server # [ 0.268035] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)209server # [ 0.270400] smpboot: CPU0: Intel(R) Xeon(R) Gold 5412U (family: 0x6, model: 0x8f, stepping: 0x8)210server # [ 0.271412] Performance Events: PEBS fmt0-, Sapphire Rapids events, full-width counters, Intel PMU driver.211server # [ 0.272029] ... version: 2212server # [ 0.272619] ... bit width: 48213server # [ 0.273025] ... generic counters: 8214server # [ 0.273641] ... generic bitmap: 00000000000000ff215server # [ 0.274025] ... fixed-purpose counters: 3216server # [ 0.274630] ... fixed-purpose bitmap: 0000000000000007217server # [ 0.275025] ... value mask: 0000ffffffffffff218server # [ 0.275758] ... max period: 00007fffffffffff219server # [ 0.276025] ... global_ctrl mask: 00000007000000ff220server # [ 0.276910] signal: max sigframe size: 11952221server # [ 0.277523] rcu: Hierarchical SRCU implementation.222server # [ 0.278027] rcu: Max phase no-delay instances is 400.223server # [ 0.279376] smp: Bringing up secondary CPUs ...224server # [ 0.280005] smp: Brought up 1 node, 1 CPU225server # [ 0.280416] smpboot: Total of 1 processors activated (4200.00 BogoMIPS)226server # [ 0.281160] Memory: 942520K/1048024K available (17159K kernel code, 2721K rwdata, 13560K rodata, 3636K init, 3020K bss, 98120K reserved, 0K cma-reserved)227server # [ 0.282207] devtmpfs: initialized228server # [ 0.282841] x86/mm: Memory block size: 128MB229server # [ 0.283697] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns230server # [ 0.284375] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)231server # [ 0.285038] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).232server # [ 0.286090] pinctrl core: initialized pinctrl subsystem233server # [ 0.286939] PM: RTC time: 00:25:42, date: 2026-11-07234server # [ 0.289245] NET: Registered PF_NETLINK/PF_ROUTE protocol family235server # [ 0.290147] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations236server # [ 0.290896] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations237server # [ 0.291663] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations238server # [ 0.292033] audit: initializing netlink subsys (disabled)239server # [ 0.292840] thermal_sys: Registered thermal governor 'fair_share'240server # [ 0.292841] thermal_sys: Registered thermal governor 'bang_bang'241server # [ 0.293026] audit: type=2000 audit(1794011142.758:1): state=initialized audit_enabled=0 res=1242server # [ 0.294026] thermal_sys: Registered thermal governor 'step_wise'243server # [ 0.294026] thermal_sys: Registered thermal governor 'user_space'244server # [ 0.294726] thermal_sys: Registered thermal governor 'power_allocator'245server # [ 0.295038] cpuidle: using governor menu246server # [ 0.296130] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5247server # [ 0.297176] PCI: Using configuration type 1 for base access248server # [ 0.298097] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.249server # [ 0.303097] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages250server # [ 0.303982] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page251server # [ 0.304651] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages252server # [ 0.309025] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page253server # [ 0.314309] ACPI: Added _OSI(Module Device)254server # [ 0.314898] ACPI: Added _OSI(Processor Device)255server # [ 0.315478] ACPI: Added _OSI(Processor Aggregator Device)256server # [ 0.320879] ACPI: 1 ACPI AML tables successfully acquired and loaded257server # [ 0.326291] ACPI: Interpreter enabled258server # [ 0.326794] ACPI: PM: (supports S0 S3 S4 S5)259server # [ 0.327026] ACPI: Using IOAPIC for interrupt routing260server # [ 0.327707] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug261server # [ 0.332025] PCI: Using E820 reservations for host bridge windows262server # [ 0.332932] ACPI: Enabled 2 GPEs in block 00 to 0F263server # [ 0.337477] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])264server # [ 0.340029] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]265server # [ 0.341026] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]266server # [ 0.344033] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge267server # [ 0.345336] acpiphp: Slot [3] registered268server # [ 0.345925] acpiphp: Slot [4] registered269server # [ 0.349041] acpiphp: Slot [5] registered270server # [ 0.349628] acpiphp: Slot [6] registered271server # [ 0.350037] acpiphp: Slot [7] registered272server # [ 0.350619] acpiphp: Slot [8] registered273server # [ 0.353039] acpiphp: Slot [9] registered274server # [ 0.353614] acpiphp: Slot [10] registered275server # [ 0.354038] acpiphp: Slot [11] registered276server # [ 0.354609] acpiphp: Slot [12] registered277server # [ 0.357039] acpiphp: Slot [13] registered278server # [ 0.357611] acpiphp: Slot [14] registered279server # [ 0.358042] acpiphp: Slot [15] registered280server # [ 0.358642] acpiphp: Slot [16] registered281server # [ 0.361039] acpiphp: Slot [17] registered282server # [ 0.361612] acpiphp: Slot [18] registered283server # [ 0.362039] acpiphp: Slot [19] registered284server # [ 0.362614] acpiphp: Slot [20] registered285server # [ 0.363746] acpiphp: Slot [21] registered286server # [ 0.364035] acpiphp: Slot [22] registered287server # [ 0.364543] acpiphp: Slot [23] registered288server # [ 0.365035] acpiphp: Slot [24] registered289server # [ 0.365549] acpiphp: Slot [25] registered290server # [ 0.366038] acpiphp: Slot [26] registered291server # [ 0.366559] acpiphp: Slot [27] registered292server # [ 0.367036] acpiphp: Slot [28] registered293server # [ 0.367567] acpiphp: Slot [29] registered294server # [ 0.368036] acpiphp: Slot [30] registered295server # [ 0.368515] acpiphp: Slot [31] registered296server # [ 0.369036] PCI host bridge to bus 0000:00297server # [ 0.369566] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]298server # [ 0.370025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]299server # [ 0.370902] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]300server # [ 0.371718] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]301server # [ 0.372024] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]302server # [ 0.372820] pci_bus 0000:00: root bus resource [bus 00-ff]303server # [ 0.373076] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint304server # [ 0.374292] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint305server # [ 0.375363] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint306server # [ 0.376940] pci 0000:00:01.1: BAR 4 [io 0xc220-0xc22f]307server # [ 0.377436] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk308server # [ 0.378025] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk309server # [ 0.378798] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk310server # [ 0.379025] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk311server # [ 0.380018] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint312server # [ 0.381483] pci 0000:00:01.2: BAR 4 [io 0xc140-0xc15f]313server # [ 0.382502] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint314server # [ 0.383316] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI315server # [ 0.384031] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB316server # [ 0.385175] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint317server # [ 0.387839] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]318server # [ 0.388036] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]319server # [ 0.388650] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]320server # [ 0.389167] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]321server # [ 0.390578] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint322server # [ 0.392127] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]323server # [ 0.393029] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]324server # [ 0.393626] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]325server # [ 0.394029] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]326server # [ 0.395408] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint327server # [ 0.397032] pci 0000:00:04.0: BAR 0 [io 0xc180-0xc19f]328server # [ 0.397601] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]329server # [ 0.398059] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]330server # [ 0.400441] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint331server # [ 0.402033] pci 0000:00:05.0: BAR 0 [io 0xc1a0-0xc1bf]332server # [ 0.402723] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]333server # [ 0.403046] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]334server # [ 0.404822] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint335server # [ 0.407000] pci 0000:00:06.0: BAR 0 [io 0xc1c0-0xc1df]336server # [ 0.407504] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]337server # [ 0.408046] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]338server # [ 0.409742] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint339server # [ 0.411698] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]340server # [ 0.412030] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]341server # [ 0.412678] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]342server # [ 0.413831] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint343server # [ 0.416651] pci 0000:00:08.0: BAR 0 [io 0xc080-0xc0ff]344server # [ 0.417032] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]345server # [ 0.417834] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]346server # [ 0.419544] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint347server # [ 0.421061] pci 0000:00:09.0: BAR 0 [io 0xc1e0-0xc1ff]348server # [ 0.421647] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]349server # [ 0.422045] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]350server # [ 0.422847] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]351server # [ 0.424304] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint352server # [ 0.426510] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]353server # [ 0.427049] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]354server # [ 0.428856] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint355server # [ 0.430674] pci 0000:00:0b.0: BAR 0 [io 0xc100-0xc13f]356server # [ 0.431030] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]357server # [ 0.431627] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]358server # [ 0.432739] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint359server # [ 0.434056] pci 0000:00:0c.0: BAR 0 [io 0xc200-0xc21f]360server # [ 0.434800] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]361server # [ 0.435049] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]362server # [ 0.439011] ACPI: PCI: Interrupt link LNKA configured for IRQ 10363server # [ 0.439540] ACPI: PCI: Interrupt link LNKB configured for IRQ 10364server # [ 0.440074] ACPI: PCI: Interrupt link LNKC configured for IRQ 11365server # [ 0.440730] ACPI: PCI: Interrupt link LNKD configured for IRQ 11366server # [ 0.441058] ACPI: PCI: Interrupt link LNKS configured for IRQ 9367server # [ 0.442793] iommu: Default domain type: Translated368server # [ 0.443032] iommu: DMA domain TLB invalidation policy: lazy mode369server # [ 0.444123] ACPI: bus type USB registered370server # [ 0.444770] usbcore: registered new interface driver usbfs371server # [ 0.445046] usbcore: registered new interface driver hub372server # [ 0.445713] usbcore: registered new device driver usb373server # [ 0.446675] NetLabel: Initializing374server # [ 0.447027] NetLabel: domain hash size = 128375server # [ 0.447587] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO376server # [ 0.448068] NetLabel: unlabeled traffic allowed by default377server # [ 0.448809] PCI: Using ACPI for IRQ routing378server # [ 0.449376] pci 0000:00:02.0: vgaarb: setting as boot VGA device379server # [ 0.450023] pci 0000:00:02.0: vgaarb: bridge control possible380server # [ 0.450023] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none381server # [ 0.450026] vgaarb: loaded382server # [ 0.450607] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0383server # [ 0.451026] hpet0: 3 comparators, 64-bit 100.000000 MHz counter384server # [ 0.455094] clocksource: Switched to clocksource kvm-clock385server # [ 0.457435] VFS: Disk quotas dquot_6.6.0386server # [ 0.458033] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)387server # [ 0.459334] pnp: PnP ACPI init388server # [ 0.460163] pnp: PnP ACPI: found 6 devices389server # [ 0.466530] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns390server # [ 0.467727] clocksource: Switched to clocksource acpi_pm391server # [ 0.468536] NET: Registered PF_INET protocol family392server # [ 0.469346] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)393server # [ 0.484514] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)394server # [ 0.485830] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)395server # [ 0.486924] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)396server # [ 0.488027] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)397server # [ 0.489048] TCP: Hash tables configured (established 8192 bind 8192)398server # [ 0.490001] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)399server # [ 0.491065] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)400server # [ 0.491971] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)401server # [ 0.492977] NET: Registered PF_UNIX/PF_LOCAL protocol family402server # [ 0.493792] NET: Registered PF_XDP protocol family403server # [ 0.494487] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]404server # [ 0.495337] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]405server # [ 0.496157] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]406server # [ 0.497052] pci_bus 0000:00: resource 7 [mem 0x40000000-0xfebfffff window]407server # [ 0.497984] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]408server # [ 0.499011] pci 0000:00:01.0: PIIX3: Enabling Passive Release409server # [ 0.499843] pci 0000:00:00.0: Limiting direct PCI/PCI transfers410server # [ 0.501405] ACPI: \_SB_.LNKD: Enabled at IRQ 11411server # [ 0.503091] PCI: CLS 0 bytes, default 64412server # [ 0.503814] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e4530a99b6, max_idle_ns: 440795257976 ns413server # [ 0.505218] Trying to unpack rootfs image as initramfs...414server # [ 0.541894] Initialise system trusted keyrings415server # [ 0.544624] workingset: timestamp_bits=40 max_order=18 bucket_order=0416server # [ 0.561729] Key type asymmetric registered417server # [ 0.562350] Asymmetric key parser 'x509' registered418server # [ 0.563063] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)419server # [ 0.567666] io scheduler mq-deadline registered420server # [ 0.568148] io scheduler kyber registered421server # [ 0.569011] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled422server # [ 0.569824] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A423server # [ 0.573224] Linux agpgart interface v0.103424server # [ 0.573844] ACPI: bus type drm_connector registered425server # [ 0.576743] usbcore: registered new interface driver usbserial_generic426server # [ 0.577429] usbserial: USB Serial support registered for generic427server # [ 0.578045] intel_pstate: CPU model not supported428server # [ 0.580680] drop_monitor: Initializing network drop monitor service429server # [ 0.581674] NET: Registered PF_INET6 protocol family430server # [ 0.584906] Segment Routing with IPv6431server # [ 0.585478] In-situ OAM (IOAM) with IPv6432server # [ 0.588849] IPI shorthand broadcast: enabled433server # [ 0.592959] sched_clock: Marking stable (475013414, 117562196)->(637198061, -44622451)434server # [ 0.595661] registered taskstats version 1435server # [ 0.596263] Loading compiled-in X.509 certificates436server # [ 0.606619] Demotion targets for Node 0: null437server # [ 0.607317] Key type .fscrypt registered438server # [ 0.607860] Key type fscrypt-provisioning registered439server # [ 0.608626] ima: No TPM chip found, activating TPM-bypass!440server # [ 0.609364] ima: Allocated hash algorithm: sha1441server # [ 0.613606] ima: No architecture policies found442server # [ 0.614210] PM: Magic number: 2:926:404443server # [ 0.615525] RAS: Correctable Errors collector initialized.444server # [ 0.625738] clk: Disabling unused clocks445server # [ 0.626354] PM: genpd: Disabling unused power domains446server # [ 0.761713] Freeing initrd memory: 27596K447server # [ 0.777433] Freeing unused decrypted memory: 2028K448server # [ 0.779665] Freeing unused kernel image (initmem) memory: 3636K449server # [ 0.780596] Write protecting the kernel read-only data: 32768k450server # [ 0.782193] Freeing unused kernel image (text/rodata gap) memory: 1272K451server # [ 0.783498] Freeing unused kernel image (rodata/data gap) memory: 776K452server # [ 0.793185] x86/mm: Checked W+X mappings: passed, no W+X pages found.453server # [ 0.794067] Run /init as init process454server # [ 0.802594] systemd[1]: Inserted module 'autofs4'455server # [ 0.818745] fuse: init (API version 7.45)456server # [ 0.824902] ACPI: \_SB_.LNKC: Enabled at IRQ 10457server # [ 0.830250] ACPI: \_SB_.LNKA: Enabled at IRQ 10458server # [ 0.832504] ACPI: \_SB_.LNKB: Enabled at IRQ 11459server # [ 0.852919] systemd[1]: Successfully made /usr/ read-only.460server # [ 1.190479] systemd[1]: systemd 260.2 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)461server # [ 1.198031] systemd[1]: Detected virtualization kvm.462server # [ 1.198999] systemd[1]: Detected architecture x86-64.463server # [ 1.199968] systemd[1]: Running in initrd.464server # [ 1.201177] systemd[1]: Initializing machine ID from random generator.465server # [ 1.203067] systemd[1]: Hostname set to <server>.466server # [ 1.269979] systemd[1]: Queued start job for default target Initrd Default Target.467server # [ 1.274283] systemd[1]: Created slice Slice /system/modprobe.468server # [ 1.275773] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.469server # [ 1.277478] systemd[1]: Expecting device /dev/disk/by-label/nix-store...470server # [ 1.278886] systemd[1]: Expecting device /dev/disk/by-label/nixos...471server # [ 1.280339] systemd[1]: Reached target Path Units.472server # [ 1.281404] systemd[1]: Reached target Slice Units.473server # [ 1.282510] systemd[1]: Reached target Swaps.474server # [ 1.283493] systemd[1]: Reached target Timer Units.475server # [ 1.284825] systemd[1]: Listening on D-Bus System Message Bus Socket.476server # [ 1.286356] systemd[1]: Listening on Journal Socket (/dev/log).477server # [ 1.287729] systemd[1]: Listening on Journal Sockets.478server # [ 1.288913] systemd[1]: Listening on udev Control Socket.479server # [ 1.290161] systemd[1]: Listening on udev Kernel Socket.480server # [ 1.291395] systemd[1]: Reached target Socket Units.481server # [ 1.294702] systemd[1]: Starting Create List of Static Device Nodes...482server # [ 1.300932] systemd[1]: Starting Load Kernel Module 9pnet_virtio...483server # [ 1.312759] systemd[1]: Starting Load Kernel Module configfs...484server # [ 1.342271] systemd[1]: Starting Journal Service...485server # [ 1.354665] systemd[1]: Starting Load Kernel Modules...486server # [ 1.361833] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki487server # [ 1.388847] systemd[1]: Starting Coldplug All udev Devices...488server # [ 1.395417] netfs: FS-Cache loaded489server # [ 1.412786] systemd[1]: Finished Create List of Static Device Nodes.490server # [ 1.419711] 9pnet: Installing 9P2000 support491server # [ 1.430889] systemd[1]: modprobe@configfs.service: Deactivated successfully.492server # [ 1.437853] systemd[1]: Finished Load Kernel Module configfs.493server # [ 1.443258] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config494server # [ 1.451951] systemd-journald[67]: Collecting audit messages is disabled.495server # [ 1.455839] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...496server # [ 1.471009] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.497server # [ 1.483080] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.498server # [ 1.487831] systemd[1]: Finished Load Kernel Module 9pnet_virtio.499server # [ 1.492618] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev500server # [ 1.521740] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.501server # [ 1.527790] systemd[1]: Starting Create Static Device Nodes in /dev...502server # [ 1.538643] systemd[1]: Finished Load Kernel Modules.503server # [ 1.547676] systemd[1]: Starting Apply Kernel Variables...504server # [ 1.564856] systemd[1]: Finished Create Static Device Nodes in /dev.505server # [ 1.568866] systemd[1]: Reached target Preparation for Local File Systems.506server # [ 1.455473] systemd-modules-load[68]: Inserted module 'dm_mod'[ 1.573810] systemd[1]: Reached target Local File Systems.507server # 508server # [ 1.459124] systemd-modules-load[68]: Inserted module 'virtio_balloon'509server # [ 1.459825] systemd-modules-load[68]: Inserted module 'virtio_gpu'510server # [ 1.579755] systemd[1]: Starting Rule-based Manager for Device Events and Files...511server # [ 1.583742] systemd[1]: Started Journal Service.512server # [ 1.473174] systemd[1]: Finished Apply Kernel Variables.513server # [ 1.483385] systemd[1]: Starting Create System Files and Directories...514server # [ 1.506223] systemd[1]: Finished Create System Files and Directories.515server # [ 1.518158] systemd-udevd[74]: Using default interface naming scheme 'v260'.516server # [ 1.527594] systemd[1]: Finished Coldplug All udev Devices.517server # [ 1.533593] systemd[1]: Started Rule-based Manager for Device Events and Files.518server # [ 1.534363] systemd[1]: Reached target System Initialization.519server # [ 1.535070] systemd[1]: Reached target Basic System.520server # [ 1.808165] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12521server # [ 1.819127] virtio_blk virtio4: 1/0/0 default/read/poll queues522server # [ 1.826204] serio: i8042 KBD port at 0x60,0x64 irq 1523server # [ 1.835919] serio: i8042 AUX port at 0x60,0x64 irq 12524server # [ 1.842279] virtio_blk virtio4: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)525server # [ 1.860764] virtio_blk virtio5: 1/0/0 default/read/poll queues526server # [ 1.867999] virtio_blk virtio5: [vdb] 2555832 512-byte logical blocks (1.31 GB/1.22 GiB)527server # [ 1.872202] uhci_hcd 0000:00:01.2: UHCI Host Controller528server # [ 1.872769] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1529server # [ 1.880274] uhci_hcd 0000:00:01.2: detected 2 ports530server # [ 1.880916] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c140531server # [ 1.889279] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18532server # [ 1.891587] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1533server # [ 1.892385] usb usb1: Product: UHCI Host Controller534server # [ 1.901123] SCSI subsystem initialized535server # [ 1.902040] usb usb1: Manufacturer: Linux 6.18.38 uhci_hcd536server # [ 1.904721] usb usb1: SerialNumber: 0000:00:01.2537server # [ 1.789976] (udev-worker)[92]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.538server # [ 1.795263] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.539server # [ 1.803063] systemd[1]: Starting Virtual Console Setup...540server # [ 1.921676] hub 1-0:1.0: USB hub found541server # [ 1.922951] hub 1-0:1.0: 2 ports detected542server # [ 1.808045] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.543server # [ 1.933859] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0544server # [ 1.829941] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.545server # [ 1.830957] systemd[1]: Finished Virtual Console Setup.546server # [ 1.844935] systemd[1]: Found device /dev/disk/by-label/nix-store.547server # [ 1.850108] systemd[1]: Found device /dev/disk/by-label/nixos.548server # [ 1.852280] systemd[1]: Reached target Initrd Root Device.549server # [ 1.854060] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...550server # [ 1.881763] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks551server # [ 2.004617] scsi host0: ata_piix552server # [ 1.887986] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.553server # [ 2.007910] scsi host1: ata_piix554server # [ 2.008403] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc220 irq 14 lpm-pol 0555server # [ 2.010291] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc228 irq 15 lpm-pol 0556server # [ 2.160731] usb 1-1: new full-speed USB device number 2 using uhci_hcd557server # [ 2.168781] ata2: found unknown device (class 0)558server # [ 2.170087] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100559server # [ 2.173079] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5560server # [ 2.199074] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray561server # [ 2.207873] cdrom: Uniform CD-ROM driver Revision: 3.20562server # [ 2.328134] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00563server # [ 2.329394] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10564server # [ 2.330588] usb 1-1: Product: QEMU USB Tablet565server # [ 2.331272] usb 1-1: Manufacturer: QEMU566server # [ 2.331892] usb 1-1: SerialNumber: 28754-0000:00:01.2-1567server # [ 2.352858] hid: raw HID events driver (C) Jiri Kosina568server # [ 2.363181] usbcore: registered new interface driver usbhid569server # [ 2.365012] usbhid: USB HID core driver570server # [ 2.369520] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2571server # [ 2.371401] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0572server # [ 2.285042] systemd[1]: Mounting /sysroot...573server # [ 2.498722] EXT4-fs (vda): mounted filesystem 3f9f0d91-3136-478b-8b38-a6ff9d730705 r/w with ordered data mode. Quota mode: none.574server # [ 2.385507] systemd[1]: Mounted /sysroot.575server # [ 2.386650] systemd[1]: Reached target Initrd Root File System.576server # [ 2.388435] systemd[1]: Starting Mountpoints Configured in the Real Root...577server # [ 2.400674] systemd-sysroot-fstab-check[126]: /sysroot should be mounted in the initrd, will request daemon-reload.578server # [ 2.405105] systemd[1]: Reload requested from client PID 126 ('systemd-sysroot') (unit initrd-parse-etc.service)...579server # [ 2.406756] systemd[1]: Reloading...580server # [ 2.498210] systemd[1]: Reloading finished in 93 ms.581server # [ 2.503880] systemd-sysroot-fstab-check[126]: Requesting initrd-fs.target/start/replace...582server # [ 2.506957] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio583server # [ 2.508433] systemd-sysroot-fstab-check[126]: Requesting swap.target/start/replace...584server # [ 2.510841] systemd[1]: initrd-parse-etc.service: Deactivated successfully.585server # [ 2.513086] systemd[1]: Finished Mountpoints Configured in the Real Root.586server # [ 2.514119] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.587server # [ 3.288131] systemd[1]: Mounting /sysroot/nix/.ro-store...588server # [ 3.292125] systemd[1]: Mounting /sysroot/nix/.rw-store...589server # [ 3.297949] systemd[1]: Mounting /sysroot/run...590server # [ 3.302958] systemd[1]: Mounting /sysroot/tmp/shared...591server # [ 3.309703] systemd[1]: Mounting /sysroot/tmp/xchg...592server # [ 3.340554] systemd[1]: Mounted /sysroot/nix/.rw-store.593server # [ 3.349554] systemd[1]: Mounted /sysroot/run.594server # [ 3.471311] erofs (device vdb): mounted with root inode @ nid 36.595server # [ 3.476861] 9p: Installing v9fs 9p2000 file system support596server # [ 3.362200] systemd[1]: Starting rw-sysroot-nix-store.service...597server # [ 3.365873] systemd[1]: Mounted /sysroot/nix/.ro-store.598server # [ 3.374280] systemd[1]: Mounted /sysroot/tmp/shared.599server # [ 3.375645] systemd[1]: Mounted /sysroot/tmp/xchg.600server # [ 3.381997] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.601server # [ 3.383577] systemd[1]: Finished rw-sysroot-nix-store.service.602server # [ 4.286649] systemd[1]: Mounting /sysroot/nix/store...603server # [ 4.311090] systemd[1]: Mounted /sysroot/nix/store.604server # [ 4.313082] systemd[1]: Reached target Initrd File Systems.605server # [ 4.314796] systemd[1]: Starting Find NixOS closure...606server # [ 4.317849] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...607server # [ 4.339340] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.608server # [ 4.343600] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.609server # [ 4.350286] systemd[1]: Finished Find NixOS closure.610server # [ 4.351707] systemd[1]: Reached target Initrd Default Target.611server # [ 4.353717] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...612server # [ 4.371060] systemd[1]: Stopped target Initrd Default Target.613server # [ 4.372393] systemd[1]: Stopped target Basic System.614server # [ 4.373352] systemd[1]: Stopped target Initrd Root Device.615server # [ 4.375146] systemd[1]: Stopped target Path Units.616server # [ 4.375911] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.617server # [ 4.376960] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.618server # [ 4.378119] systemd[1]: Stopped target Slice Units.619server # [ 4.379061] systemd[1]: Stopped target Socket Units.620server # [ 4.380126] systemd[1]: Stopped target System Initialization.621server # [ 4.381308] systemd[1]: Stopped target Swaps.622server # [ 4.382270] systemd[1]: Stopped target Timer Units.623server # [ 4.383127] systemd[1]: dbus.socket: Deactivated successfully.624server # [ 4.384221] systemd[1]: Closed D-Bus System Message Bus Socket.625server # [ 4.386240] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.626server # [ 4.387322] systemd[1]: Stopped Find NixOS closure.627server # [ 4.388068] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio628server # [ 4.390110] systemd[1]: Starting rw-sysroot-nix-store.service...629server # [ 4.392067] systemd[1]: systemd-sysctl.service: Deactivated successfully.630server # [ 4.393335] systemd[1]: Stopped Apply Kernel Variables.631server # [ 4.394524] systemd[1]: systemd-modules-load.service: Deactivated successfully.632server # [ 4.395733] systemd[1]: Stopped Load Kernel Modules.633server # [ 4.396828] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.634server # [ 4.398162] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.635server # [ 4.399384] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.636server # [ 4.401421] systemd[1]: Stopped Create System Files and Directories.637server # [ 4.403073] systemd[1]: Stopped target Local File Systems.638server # [ 4.403848] systemd[1]: Stopped target Preparation for Local File Systems.639server # [ 4.404760] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.640server # [ 4.405716] systemd[1]: Stopped Coldplug All udev Devices.641server # [ 4.409508] systemd[1]: Stopping Rule-based Manager for Device Events and Files...642server # [ 4.410828] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.643server # [ 4.413159] systemd[1]: Stopped Virtual Console Setup.644server # [ 4.419372] systemd[1]: systemd-udevd.service: Deactivated successfully.645server # [ 4.422242] systemd[1]: Stopped Rule-based Manager for Device Events and Files.646server # [ 4.428281] systemd[1]: initrd-cleanup.service: Deactivated successfully.647server # [ 4.430097] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.648server # [ 4.431743] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.649server # [ 4.432886] systemd[1]: Finished rw-sysroot-nix-store.service.650server # [ 4.436741] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.651server # [ 4.438222] systemd[1]: Closed udev Control Socket.652server # [ 4.439611] systemd[1]: Starting Cleanup udev Database...653server # [ 4.441067] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.654server # [ 4.442166] systemd[1]: Stopped Create Static Device Nodes in /dev.655server # [ 4.443080] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.656server # [ 4.445188] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.657server # [ 4.446241] systemd[1]: kmod-static-nodes.service: Deactivated successfully.658server # [ 4.447206] systemd[1]: Stopped Create List of Static Device Nodes.659server # [ 4.460354] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.660server # [ 4.461907] systemd[1]: Finished Cleanup udev Database.661server # [ 4.463328] systemd[1]: Reached target Switch Root.662server # [ 4.465095] systemd[1]: Starting NixOS Activation...663server # [ 4.541771] initrd-nixos-activation-start[177]: booting system configuration /nix/store/im1cwbkvfvjcxa475bx91wi6062xxpx6-nixos-system-server-test664server # [ 4.565147] initrd-nixos-activation-start[177]: running activation script...665server # [ 4.746545] initrd-nixos-activation-start[200]: setting up /etc...666server # [ 4.816145] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.667server # [ 4.817030] systemd[1]: Finished NixOS Activation.668server # [ 4.818060] systemd[1]: Starting Switch Root...669server # [ 4.829195] systemd[1]: Switching root.670server # [ 4.959203] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).671server # [ 5.029811] NET: Registered PF_VSOCK protocol family672server # [ 5.383643] systemd[1]: systemd 260.2 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)673server # [ 5.388492] systemd[1]: Detected virtualization kvm.674server # [ 5.389506] systemd[1]: Detected architecture x86-64.675server # [ 5.390629] systemd[1]: Detected first boot.676server # [ 5.392026] systemd[1]: Initializing machine ID from random generator.677server # [ 5.591466] systemd[1]: bpf-restrict-fs: LSM BPF program attached678server # [ 5.676707] systemd[1]: Applying preset policy.679server # [ 5.771825] systemd[1]: Populated /etc with preset unit settings.680server # [ 5.872414] systemd[1]: initrd-switch-root.service: Deactivated successfully.681server # [ 5.874535] systemd[1]: Stopped initrd-switch-root.service.682server # [ 5.878276] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.683server # [ 5.881479] systemd[1]: Created slice Slice /system/getty.684server # [ 5.883744] systemd[1]: Created slice User and Session Slice.685server # [ 5.885207] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.686server # [ 5.887144] systemd[1]: Started Forward Password Requests to Wall Directory Watch.687server # [ 5.888902] systemd[1]: Expecting device /dev/hvc0...688server # [ 5.890119] systemd[1]: Expecting device /dev/ttyS0...689server # [ 5.891387] systemd[1]: Reached target Local Encrypted Volumes.690server # [ 5.892790] systemd[1]: Stopped target initrd-fs.target.691server # [ 5.894007] systemd[1]: Stopped target initrd-root-fs.target.692server # [ 5.895256] systemd[1]: Stopped target initrd-switch-root.target.693server # [ 5.896751] systemd[1]: Reached target Virtual Machines and Containers.694server # [ 5.897993] systemd[1]: Reached target Path Units.695server # [ 5.898809] systemd[1]: Reached target Remote File Systems.696server # [ 5.899711] systemd[1]: Reached target Slice Units.697server # [ 5.900541] systemd[1]: Reached target Swaps.698server # [ 5.902385] systemd[1]: Listening on Query the User Interactively for a Password.699server # [ 5.904704] systemd[1]: Listening on Process Core Dump Socket.700server # [ 5.906299] systemd[1]: Listening on Credential Encryption/Decryption.701server # [ 5.908210] systemd[1]: Listening on Factory Reset Management.702server # [ 5.911056] systemd[1]: Starting Journal Log Access Socket...703server # [ 5.912364] systemd[1]: Listening on Journal Audit Socket.704server # [ 5.915124] systemd[1]: Listening on Console Output Muting Service Socket.705server # [ 5.916357] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.706server # [ 5.917611] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-uki707server # [ 5.919033] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki708server # [ 5.921994] systemd[1]: Listening on Disk Repartitioning Service Socket.709server # [ 5.923118] systemd[1]: Listening on udev Control Socket.710server # [ 5.924174] systemd[1]: Listening on udev Varlink Socket.711server # [ 5.926507] systemd[1]: Mounting Huge Pages File System...712server # [ 5.934684] systemd[1]: Mounting POSIX Message Queue File System...713server # [ 5.941682] systemd[1]: Mounting Kernel Debug File System...714server # [ 5.946762] systemd[1]: Mounting Kernel Trace File System...715server # [ 5.956209] systemd[1]: Starting Create List of Static Device Nodes...716server # [ 5.960180] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio717server # [ 5.983809] systemd[1]: Starting Load Kernel Module configfs...718server # [ 5.985646] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm719server # [ 5.990721] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore720server # [ 5.994040] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse721server # [ 5.999880] systemd[1]: Mounting FUSE Control File System...722server # [ 6.004359] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67723server # [ 6.020709] systemd[1]: Starting Journal Service...724server # [ 6.027705] systemd[1]: Starting Load Kernel Modules...725server # [ 6.037335] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...726server # [ 6.054702] systemd[1]: Starting Remount Root and Kernel File Systems...727server # [ 6.063409] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki728server # [ 6.075922] loop: module loaded729server # [ 6.083168] systemd[1]: Starting Coldplug All udev Devices...730server # [ 6.086796] systemd-journald[269]: Collecting audit messages is enabled.731server # [ 6.108296] systemd[1]: Listening on Journal Log Access Socket.732server # [ 6.112793] systemd[1]: Mounted Huge Pages File System.733server # [ 6.000878] systemd[1]: Queued start job for default target Multi-User System.[ 6.119560] systemd[1]: Started Journal Service.734server # [ 6.120429] EXT4-fs (vda): re-mounted 3f9f0d91-3136-478b-8b38-a6ff9d730705.735server # 736server # [ 6.006538] systemd[1]: Unnecessary job was removed for /dev/vdb.737server # [ 6.007993] systemd[1]: systemd-journald.service: Deactivated successfully.738server # [ 6.014528] systemd-modules-load[270]: Inserted module 'loop'739server # [ 6.016423] systemd[1]: Mounted POSIX Message Queue File System.740server # [ 6.018407] systemd[1]: Mounted Kernel Debug File System.741server # [ 6.021438] systemd[1]: Mounted Kernel Trace File System.742server # [ 6.023483] systemd[1]: Finished Create List of Static Device Nodes.743server # [ 6.027345] systemd[1]: modprobe@configfs.service: Deactivated successfully.744server # [ 6.028527] systemd[1]: Finished Load Kernel Module configfs.745server # [ 6.031344] systemd[1]: Mounted FUSE Control File System.746server # [ 6.033357] systemd[1]: Finished Load Kernel Modules.747server # [ 6.036371] systemd[1]: Finished Remount Root and Kernel File Systems.748server # [ 6.056097] systemd[1]: Listening on Disk Image Download Service Socket.749server # [ 6.061105] systemd[1]: Mounting Kernel Configuration File System...750server # [ 6.069040] systemd[1]: Starting Firewall...751server # [ 6.082134] systemd[1]: Starting Flush Journal to Persistent Storage...752server # [ 6.083153] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore753server # [ 6.094296] systemd-oomd[271]: No swap; memory pressure usage will be degraded754server # [ 6.098953] systemd[1]: Starting Load/Save OS Random Seed...755server # [ 6.111192] systemd[1]: Starting Apply Kernel Variables...756server # [ 6.140311] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...757server # [ 6.143101] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki758server # [ 6.144874] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.759server # [ 6.294792] systemd-journald[269]: Received client request to flush runtime journal.760server # [ 6.351319] systemd[1]: Mounted Kernel Configuration File System.761server # [ 6.353856] systemd[1]: Finished Load/Save OS Random Seed.762server # [ 6.355340] systemd[1]: Reached target First Boot Complete.763server # [ 6.358513] systemd[1]: Finished Apply Kernel Variables.764server # [ 6.360398] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.765server # [ 6.362962] systemd[1]: Starting Create Static Device Nodes in /dev...766server # [ 6.365211] systemd[1]: Finished Create Static Device Nodes in /dev.767server # [ 6.369203] systemd[1]: Reached target Preparation for Local File Systems.768server # [ 6.370189] systemd[1]: Starting Rule-based Manager for Device Events and Files...769server # [ 6.371198] systemd[1]: Finished Flush Journal to Persistent Storage.770server # [ 6.407202] systemd[1]: Finished Coldplug All udev Devices.771server # [ 6.441258] systemd-udevd[298]: Using default interface naming scheme 'v260'.772server # [ 6.492179] systemd[1]: Started Rule-based Manager for Device Events and Files.773server # [ 6.700523] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse774server # [ 6.751091] systemd[1]: Finished Firewall.775server # [ 6.758456] systemd[1]: Mounting /run/wrappers...776server # [ 6.782216] systemd[1]: Mounted /run/wrappers.777server # [ 6.785404] systemd[1]: Reached target Local File Systems.778server # [ 6.787183] systemd[1]: Listening on Boot Loader Control Service Socket.779server # [ 6.791215] systemd[1]: Starting register-nix-paths.service...780server # [ 6.797259] systemd[1]: Starting Create SUID/SGID Wrappers...781server # [ 6.798227] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.782server # [ 6.810289] systemd[1]: Starting Save Transient machine-id to Disk...783server # [ 6.822087] systemd[1]: Starting Create System Files and Directories...784server # [ 6.853318] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.785server # [ 6.907282] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.786server # [ 6.918227] systemd[1]: Finished Save Transient machine-id to Disk.787server # [ 6.939738] systemd[1]: Finished Create System Files and Directories.788server # [ 6.947843] systemd[1]: Starting Rebuild Journal Catalog...789server # [ 6.959255] systemd[1]: Starting Record System Boot/Shutdown in UTMP...790server # [ 6.988380] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.791server # [ 6.991466] (udev-worker)[374]: Network interface NamePolicy= disabled on kernel command line.792server # [ 7.067178] (udev-worker)[366]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.793server # [ 7.077912] (udev-worker)[366]: Network interface NamePolicy= disabled on kernel command line.794server # [ 7.085582] systemd[1]: Finished Record System Boot/Shutdown in UTMP.795server # [ 7.129362] systemd[1]: Finished Rebuild Journal Catalog.796server # [ 7.142799] systemd[1]: Starting Update is Completed...797server # [ 7.207942] systemd[1]: Finished Update is Completed.798server # [ 7.318677] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.799server # [ 7.323458] systemd[1]: Finished Create SUID/SGID Wrappers.800server # [ 7.351227] systemd[1]: Condition check resulted in Virtio network device being skipped.801server # [ 7.353384] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore802server # [ 7.359074] systemd[1]: Starting Create SUID/SGID Wrappers...803server # [ 7.359854] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.804server # [ 7.361468] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67805server # [ 7.365938] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore806server # [ 7.367156] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki807server # [ 7.368149] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki808server # [ 7.634187] systemd[1]: Finished register-nix-paths.service.809server # [ 7.760621] mousedev: PS/2 mouse device common for all mice810server # [ 7.772902] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3811server # [ 7.656919] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.812server # [ 7.658083] systemd[1]: Finished Create SUID/SGID Wrappers.813server # [ 7.658870] systemd[1]: Reached target System Initialization.814server # [ 7.660083] systemd[1]: Started Discard unused filesystem blocks once a week.815server # [ 7.660925] systemd[1]: Started postgresqlBackup-horae.timer.816server # [ 7.662421] systemd[1]: Started Daily Cleanup of Temporary Directories.817server # [ 7.663168] systemd[1]: Reached target Timer Units.818server # [ 7.664056] systemd[1]: Listening on D-Bus System Message Bus Socket.819server # [ 7.664728] systemd[1]: Listening on Nix Daemon Socket.820server # [ 7.666155] systemd[1]: Listening on Hostname Service Socket.821server # [ 7.666800] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.822server # [ 7.667787] systemd[1]: Reached target Socket Units.823server # [ 7.669552] systemd[1]: Reached target Basic System.824server # [ 7.670919] systemd[1]: Started backdoor.service.825server # [ 7.679066] systemd[1]: Starting Import lastlog data into lastlog2 database...826server # [ 7.685137] systemd[1]: Starting Name Service Cache Daemon (nsncd)...827server # [ 7.695074] systemd[1]: Starting Post-Boot Actions...828server # [ 7.703855] systemd[1]: Started Reset console on configuration changes.829server # [ 7.716736] systemd[1]: Starting resolvconf update...830server # [ 7.836177] rtc_cmos 00:05: RTC can wake from S4831server # [ 7.730791] systemd[1]: Started test-console-diagnostics.service.832server # connecting to host...833server # [ 7.888869] ACPI: button: Power Button [PWRF]834server # [ 7.778136] systemd[1]: Finished Post-Boot Actions.835server # [ 7.783357] systemd[1]: Started Name Service Cache Daemon (nsncd).836server # [ 7.786573] nsncd[551]: Nov 07 00:25:50.367 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"837server # [ 7.797242] systemd[1]: Reached target Host and Network Name Lookups.838server # [ 7.798227] systemd[1]: Reached target User and Group Name Lookups.839server # [ 7.812090] systemd[1]: Starting D-Bus System Message Bus...840server # [ 7.949713] parport_pc 00:03: reported by Plug and Play ACPI841server # [ 7.835310] systemd[1]: Starting User Login Management...842server # [ 7.847050] systemd[1]: Finished Import lastlog data into lastlog2 database.843server # [ 7.997769] Floppy drive(s): fd0 is 2.88M AMI BIOS844server # [ 7.896996] systemd[1]: test-console-diagnostics.service: Deactivated successfully.845server # [ 7.903139] systemd[1]: test-console-diagnostics.service: Consumed 18ms CPU time over 168ms wall clock time, 8.3M memory peak, 6.3M read from disk.846server # [ 8.028985] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]847server # [ 7.927685] dbus-broker-launch[576]: Looking up NSS user entry for 'systemd-timesync'...848server # [ 7.932310] dbus-broker-launch[576]: NSS returned no entry for 'systemd-timesync'849server # [ 7.933359] dbus-broker-launch[576]: Invalid user-name in /nix/store/fwicaqwljqw4aj70vkadv0ns4mmsfcgf-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"850server # [ 7.941247] systemd[1]: Started D-Bus System Message Bus.851server # [ 8.062997] FDC 0 is a S82078B852server # [ 7.978609] dbus-broker-launch[576]: Ready853server # [ 8.100612] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console854server # [ 8.111778] rtc_cmos 00:05: registered as rtc0855server # [ 8.111864] rtc_cmos 00:05: setting system clock to 2026-11-07T00:25:50 UTC (1794011150)856server # [ 8.111965] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs857server # [ 8.114347] systemd-journald[269]: Time jumped backwards, rotating.858server # [ 8.129545] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0859server # [ 8.129638] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD860server # [ 8.229504] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4861server # [ 8.275672] Console: switching to colour dummy device 80x25862server # [ 8.044574] systemd[1]: Stopped target Host and Network Name Lookups.863server # [ 8.251572] dhcpcd[642]: dhcpcd-10.3.2 starting864server # [ 8.253296] systemd[1]: Stopping Host and Network Name Lookups...865server # [ 8.256454] dhcpcd[647]: dev: loaded udev866server # [ 8.259291] systemd[1]: Stopped target User and Group Name Lookups.867server # [ 8.260716] systemd[1]: Stopping User and Group Name Lookups...868server # [ 8.264214] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...869server # [ 8.266298] systemd[1]: nscd.service: Deactivated successfully.870server # [ 8.267285] systemd[1]: Stopped Name Service Cache Daemon (nsncd).871server # [ 8.268126] systemd[1]: nscd.service: Consumed 15ms CPU time over 346ms wall clock time, 7.6M memory peak, 5.7M read from disk.872server # [ 8.269652] systemd[1]: Starting Name Service Cache Daemon (nsncd)...873server # [ 8.270510] systemd[1]: Finished resolvconf update.874server # [ 8.271236] systemd[1]: Reached target Preparation for Network.875server # [ 8.273041] systemd[1]: Starting DHCP Client...876server # [ 8.273734] systemd[1]: Starting Address configuration of eth1...877server # [ 8.274602] systemd[1]: Starting Extra networking commands....878server # [ 8.276681] systemd-logind[578]: New seat seat0.879server # [ 8.277477] systemd[1]: Started User Login Management.880server # [ 8.396452] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6881server # [ 8.397353] 8021q: 802.1Q VLAN Support v1.8882server # [ 8.398253] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5883server # [ 8.401740] [drm] Found bochs VGA, ID 0xb0c5.884server # [ 8.402186] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.885server: waiting for unit register-nix-paths.service886server: waiting for the VM to finish booting887server: Guest shell says: b'Spawning backdoor root shell...\n'888server: connected to guest root shell889server: (connecting took 0.00 seconds)890server: (finished: waiting for the VM to finish booting, in 0.00 seconds)891server # [ 8.421734] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic892server # [ 8.422393] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0893server # [ 8.330088] systemd[1]: Starting linger-users.service...894server # [ 8.472149] 8021q: adding VLAN 0 to HW filter on device eth1895server # [ 8.381380] systemd[1]: Starting Virtual Console Setup...896server # [ 8.389643] systemd[1]: Started Name Service Cache Daemon (nsncd).897server # [ 8.394152] nsncd[623]: Nov 07 00:25:50.896 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"898server # [ 8.397671] network-addresses-eth1-start[637]: adding address 192.168.1.1/24... done899server # [ 8.422664] network-addresses-eth1-start[637]: adding address 2001:db8:1::1/64... done900server # [ 8.446282] systemd[1]: Finished Address configuration of eth1.901server # [ 8.456932] systemd[1]: linger-users.service: Deactivated successfully.902server # [ 8.462136] systemd[1]: Finished linger-users.service.903server # [ 8.495140] systemd-logind[578]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)904server # [ 8.499263] systemd[1]: Reached target Host and Network Name Lookups.905server # [ 8.504378] systemd[1]: Reached target User and Group Name Lookups.906server: (finished: waiting for unit register-nix-paths.service, in 0.21 seconds)907server: waiting for unit postgresql.service908server # [ 8.633673] RAPL PMU: API unit is 2^-32 Joules, 1 fixed counters, 10737418240 ms ovfl timer909server # [ 8.634489] RAPL PMU: hw unit of domain psys 2^-0 Joules910server # [ 8.684646] fbcon: bochs-drmdrmfb (fb0) is primary device911server # [ 8.791442] cfg80211: Loading compiled-in X.509 certificates for regulatory database912server # [ 8.800438] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'913server # [ 8.800639] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'914server # [ 8.801078] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2915server # [ 8.801086] cfg80211: failed to load regulatory.db916server # [ 8.818427] ppdev: user-space parallel port driver917server # [ 8.865002] 8021q: adding VLAN 0 to HW filter on device eth0918server # [ 9.084129] Console: switching to colour frame buffer device 160x50919server # [ 9.133611] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device920server # [ 8.591351] systemd-vconsole-setup[662]: Configuration of first virtual console was skipped, ignoring remaining ones.921server # [ 9.018705] dhcpcd[647]: eth0: waiting for carrier922server # [ 9.019428] systemd-logind[578]: Watching system buttons on /dev/input/event2 (Power Button)923server # [ 9.020873] dhcpcd[647]: eth0: carrier acquired924server # [ 9.021545] systemd[1]: Finished Extra networking commands..925server # [ 9.022579] dhcpcd[647]: DUID 00:01:00:01:32:81:2c:8f:52:54:00:12:34:56926server # [ 9.025084] systemd[1]: Reached target Network.927server # [ 9.026324] postgresql-pre-start[752]: The files belonging to this database system will be owned by user "postgres".928server # [ 9.028209] postgresql-pre-start[752]: This user must also own the server process.929server # [ 9.030931] postgresql-pre-start[752]: The database cluster will be initialized with locale "en_US.UTF-8".930server # [ 9.032207] postgresql-pre-start[752]: The default database encoding has accordingly been set to "UTF8".931server # [ 9.033633] postgresql-pre-start[752]: The default text search configuration will be set to "english".932server # [ 9.038733] postgresql-pre-start[752]: Data page checksums are disabled.933server # [ 9.039701] postgresql-pre-start[752]: fixing permissions on existing directory /var/lib/postgresql/17 ... ok934server # [ 9.040874] postgresql-pre-start[752]: creating subdirectories ... ok935server # [ 9.041684] postgresql-pre-start[752]: selecting dynamic shared memory implementation ... posix936server # [ 9.048201] dhcpcd[647]: eth0: IAID 00:12:34:56937server # [ 9.049210] systemd[1]: Starting dex identity provider...938server # [ 9.052175] dhcpcd[647]: eth0: adding address fe80::5054:ff:fe12:3456939server # [ 9.054231] systemd[1]: Starting PostgreSQL Server...940server # [ 9.060124] systemd[1]: Starting Permit User Sessions...941server # [ 9.060997] systemd-logind[578]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)942server # [ 9.062237] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.943server # [ 9.063594] systemd[1]: Started dex identity provider.944server # [ 9.070184] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.945server # [ 9.071204] systemd[1]: Stopped Virtual Console Setup.946server # [ 9.071938] systemd[1]: systemd-vconsole-setup.service: Consumed 25ms CPU time over 654ms wall clock time, 3.3M memory peak, 1M read from disk.947server # [ 9.082238] systemd[1]: Starting Virtual Console Setup...948server # [ 9.096147] systemd[1]: Finished Permit User Sessions.949server # [ 9.099175] postgresql-pre-start[752]: selecting default "max_connections" ... 100950server # [ 9.121619] systemd[1]: Started Getty on tty1.951server # [ 9.122425] systemd[1]: Reached target Login Prompts.952server # [ 9.190462] systemd-vconsole-setup[764]: Configuration of first virtual console was skipped, ignoring remaining ones.953server # [ 9.203232] systemd[1]: Finished Virtual Console Setup.954server # [ 9.237258] postgresql-pre-start[752]: selecting default "shared_buffers" ... 128MB955server # [ 9.314403] dhcpcd[647]: eth0: soliciting a DHCP lease956server # [ 9.317425] dex[756]: time=2026-11-07T00:25:51.822Z level=INFO msg="Version info" dex_version=v2.45.1 go.version=go1.26.4 go.os=linux go.arch=amd64957server # [ 9.322794] dex[756]: time=2026-11-07T00:25:51.825Z level=INFO msg="config issuer" issuer=http://127.0.0.1:5556/dex958server # [ 9.324176] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="config storage" storage_type=memory959server # [ 9.325298] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="config static client" client_name=Horae960server # [ 9.326466] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="config static client" client_name="Horae without email_verified"961server # [ 9.327832] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="config connector" connector_id=mock962server # [ 9.329416] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="config skipping approval screen"963server # [ 9.331124] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="continue on connector failure feature flag enabled"964server # [ 9.332384] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="signer configured" type=local keys_rotation_period=6h965server # [ 9.333640] dex[756]: time=2026-11-07T00:25:51.827Z level=INFO msg="config refresh tokens rotation" enabled=true966server # [ 9.336577] dex[756]: time=2026-11-07T00:25:51.840Z level=ERROR msg="failed to get signing algorithm" err="no signing key found"967server # [ 9.339240] dex[756]: time=2026-11-07T00:25:51.841Z level=INFO msg="keys expired, rotating"968server # [ 9.464152] NET: Registered PF_PACKET protocol family969server # [ 9.354673] dhcpcd[647]: eth0: offered 10.0.2.15 from 10.0.2.2970server # [ 9.357168] dhcpcd[647]: eth0: probing address 10.0.2.15/24971server # [ 9.632866] Key type encrypted registered972server # [ 9.686834] EDAC MC: Ver: 3.0.0973server # [ 9.605370] dex[756]: time=2026-11-07T00:25:52.111Z level=INFO msg="keys rotated" next_rotation=2026-11-07T06:25:52.110Z974server # [ 9.607614] dex[756]: time=2026-11-07T00:25:52.112Z level=INFO msg="listening on" server=http address=127.0.0.1:5556975server # [ 9.764327] postgresql-pre-start[752]: selecting default time zone ... UTC976server # [ 9.766308] postgresql-pre-start[752]: creating configuration files ... ok977server # [ 9.885703] postgresql-pre-start[752]: running bootstrap script ... ok978server # [ 10.260454] postgresql-pre-start[752]: performing post-bootstrap initialization ... ok979server # [ 10.335022] postgresql-pre-start[752]: syncing data to disk ... ok980server # [ 10.336214] postgresql-pre-start[752]: initdb: warning: enabling "trust" authentication for local connections981server # [ 10.337059] postgresql-pre-start[752]: 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.982server # [ 10.338300] postgresql-pre-start[752]: Success. You can now start the database server using:983server # [ 10.339016] postgresql-pre-start[752]: pg_ctl -D /var/lib/postgresql/17 -l logfile start984server # [ 10.386113] postgres[788]: [788] LOG: starting PostgreSQL 17.10 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit985server # [ 10.387780] postgres[788]: [788] LOG: listening on IPv6 address "::1", port 5432986server # [ 10.388598] postgres[788]: [788] LOG: listening on IPv4 address "127.0.0.1", port 5432987server # [ 10.391253] postgres[788]: [788] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"988server # [ 10.396446] postgres[795]: [795] LOG: database system was shut down at 2026-11-07 00:25:52 GMT989server # [ 10.401608] postgres[788]: [788] LOG: database system is ready to accept connections990server # [ 10.405603] systemd[1]: Started PostgreSQL Server.991server # [ 10.408298] systemd[1]: Starting PostgreSQL Setup Scripts...992server # [ 10.488241] postgresql-setup-start[806]: CREATE DATABASE993server # [ 10.516264] postgresql-setup-start[811]: CREATE ROLE994server # [ 10.527956] postgresql-setup-start[813]: ALTER ROLE995server # [ 10.538750] postgresql-setup-start[815]: ALTER DATABASE996server # [ 10.543637] systemd[1]: Finished PostgreSQL Setup Scripts.997server # [ 10.547100] systemd[1]: Reached target PostgreSQL.998server # [ 10.551564] systemd[1]: Starting Horae time tracking server...999server: (finished: waiting for unit postgresql.service, in 2.30 seconds)1000server: waiting for unit dex.service1001server: (finished: waiting for unit dex.service, in 0.04 seconds)1002server: waiting for unit horae.service1003server # [ 10.855907] horae[818]: 2026-11-07T00:25:53.360438Z INFO horae: Running migrations...1004server # [ 10.990619] dhcpcd[647]: eth0: soliciting an IPv6 router1005server # [ 10.991544] dhcpcd[647]: eth0: Router Advertisement from fe80::21006server # [ 10.992857] dhcpcd[647]: eth0: adding address fec0::5054:ff:fe12:3456/641007server # [ 10.993880] dhcpcd[647]: eth0: adding route to fec0::/641008server # [ 10.995145] dhcpcd[647]: eth0: adding default route via fe80::21009server # [ 11.160964] horae[818]: 2026-11-07T00:25:53.665784Z INFO horae: Migrations complete.1010server # [ 11.167976] systemd[1]: Started Horae time tracking server.1011server # [ 11.192206] horae[837]: 2026-11-07T00:25:53.697549Z INFO horae: Starting Horae on 127.0.0.1:30001012server # [ 11.201118] horae[837]: 2026-11-07T00:25:53.706751Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1013server # [ 11.211631] horae[837]: 2026-11-07T00:25:53.717091Z INFO horae::plugin::registry: plugins directory does not exist: plugins1014server # [ 11.228085] horae[837]: 2026-11-07T00:25:53.732088Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181015server # [ 11.230033] horae[837]: 2026-11-07T00:25:53.732123Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181016server # [ 11.231819] horae[837]: 2026-11-07T00:25:53.732138Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181017server # [ 11.233572] horae[837]: 2026-11-07T00:25:53.732151Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181018server # [ 11.235337] horae[837]: 2026-11-07T00:25:53.732172Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181019server # [ 11.237598] horae[837]: 2026-11-07T00:25:53.732186Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181020server # [ 11.239360] horae[837]: 2026-11-07T00:25:53.732199Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181021server # [ 11.241159] horae[837]: 2026-11-07T00:25:53.732213Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181022server # [ 11.242837] horae[837]: 2026-11-07T00:25:53.732240Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_entries76905535698631861851023server # [ 11.244732] horae[837]: 2026-11-07T00:25:53.732260Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_groups76905535698631861851024server # [ 11.246582] horae[837]: 2026-11-07T00:25:53.732274Z INFO dioxus_server::server: Registering: POST /api/get_time_report_access76905535698631861851025server # [ 11.248548] horae[837]: 2026-11-07T00:25:53.732287Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851026server # [ 11.251804] horae[837]: 2026-11-07T00:25:53.732300Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851027server # [ 11.253626] horae[837]: 2026-11-07T00:25:53.732314Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851028server # [ 11.255352] horae[837]: 2026-11-07T00:25:53.732329Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071029server # [ 11.257330] horae[837]: 2026-11-07T00:25:53.732363Z INFO dioxus_server::server: Registering: POST /api/list_permission_audit161443592707961243071030server # [ 11.259368] horae[837]: 2026-11-07T00:25:53.732376Z INFO dioxus_server::server: Registering: POST /api/get_permission_audit161443592707961243071031server # [ 11.261750] horae[837]: 2026-11-07T00:25:53.732389Z INFO dioxus_server::server: Registering: POST /api/get_my_permissions161443592707961243071032server # [ 11.263551] horae[837]: 2026-11-07T00:25:53.732402Z INFO dioxus_server::server: Registering: POST /api/preview_permission_template_deletion115797103093460273031033server # [ 11.265558] horae[837]: 2026-11-07T00:25:53.732415Z INFO dioxus_server::server: Registering: POST /api/preview_person_permissions115797103093460273031034server # [ 11.267840] horae[837]: 2026-11-07T00:25:53.732428Z INFO dioxus_server::server: Registering: POST /api/save_permission_template115797103093460273031035server # [ 11.269744] horae[837]: 2026-11-07T00:25:53.732441Z INFO dioxus_server::server: Registering: POST /api/list_permission_subjects115797103093460273031036server # [ 11.271561] horae[837]: 2026-11-07T00:25:53.732454Z INFO dioxus_server::server: Registering: POST /api/save_person_permissions115797103093460273031037server # [ 11.273348] horae[837]: 2026-11-07T00:25:53.732474Z INFO dioxus_server::server: Registering: POST /api/load_permission_editor115797103093460273031038server # [ 11.275251] horae[837]: 2026-11-07T00:25:53.732487Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_entries163860635615838339551039server # [ 11.277345] horae[837]: 2026-11-07T00:25:53.732501Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551040server # [ 11.280158] horae[837]: 2026-11-07T00:25:53.732513Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_tracking163860635615838339551041server # [ 11.282034] horae[837]: 2026-11-07T00:25:53.732526Z INFO dioxus_server::server: Registering: POST /api/apply_timesheet_command163860635615838339551042server # [ 11.283827] horae[837]: 2026-11-07T00:25:53.732547Z INFO dioxus_server::server: Registering: POST /api/list_timesheet_people163860635615838339551043server # [ 11.285582] horae[837]: 2026-11-07T00:25:53.732592Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_page163860635615838339551044server # [ 11.287326] horae[837]: 2026-11-07T00:25:53.732606Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551045server # [ 11.289265] horae[837]: 2026-11-07T00:25:53.732619Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551046server # [ 11.291707] horae[837]: 2026-11-07T00:25:53.732632Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551047server # [ 11.293426] horae[837]: 2026-11-07T00:25:53.732645Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1048server # [ 11.295109] horae[837]: 2026-11-07T00:25:53.732658Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1049server # [ 11.296747] horae[837]: 2026-11-07T00:25:53.732672Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1050server # [ 11.298430] horae[837]: 2026-11-07T00:25:53.732686Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1051server # [ 11.300504] horae[837]: 2026-11-07T00:25:53.732699Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry1052server # [ 11.302154] horae[837]: 2026-11-07T00:25:53.732737Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history1053server # [ 11.303814] horae[837]: 2026-11-07T00:25:53.732753Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client1054server # [ 11.305456] horae[837]: 2026-11-07T00:25:53.732767Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993931055server # [ 11.307271] horae[837]: 2026-11-07T00:25:53.732784Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status1056server # [ 11.310664] horae[837]: 2026-11-07T00:25:53.732801Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/remove1057server # [ 11.312474] horae[837]: 2026-11-07T00:25:53.732815Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993931058server # [ 11.314310] horae[837]: 2026-11-07T00:25:53.732836Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/save1059server # [ 11.315985] horae[837]: 2026-11-07T00:25:53.732849Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993931060server # [ 11.317742] horae[837]: 2026-11-07T00:25:53.732862Z INFO dioxus_server::server: Registering: POST /api/load_task_catalog122563778109868174721061server # [ 11.319503] horae[837]: 2026-11-07T00:25:53.732876Z INFO dioxus_server::server: Registering: POST /api/get_project_detail_view79311481232659985111062server # [ 11.324335] horae[837]: 2026-11-07T00:25:53.732889Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634961063server # [ 11.326131] horae[837]: 2026-11-07T00:25:53.732901Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634961064server # [ 11.327837] horae[837]: 2026-11-07T00:25:53.732923Z INFO dioxus_server::server: Registering: POST /api/list_people137396755887194634961065server # [ 11.329486] horae[837]: 2026-11-07T00:25:53.732936Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634961066server # [ 11.331185] horae[837]: 2026-11-07T00:25:53.732948Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634961067server # [ 11.332882] horae[837]: 2026-11-07T00:25:53.732961Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732061068server # [ 11.338242] horae[837]: 2026-11-07T00:25:53.732974Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732061069server # [ 11.340088] horae[837]: 2026-11-07T00:25:53.732988Z INFO dioxus_server::server: Registering: POST /api/get_project_overview139632003752584732061070server # [ 11.341841] horae[837]: 2026-11-07T00:25:53.733015Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732061071server # [ 11.343560] horae[837]: 2026-11-07T00:25:53.733096Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732061072server # [ 11.345293] horae[837]: 2026-11-07T00:25:53.733110Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732061073server # [ 11.347057] horae[837]: 2026-11-07T00:25:53.733124Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732061074server # [ 11.350132] horae[837]: 2026-11-07T00:25:53.733136Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732061075server # [ 11.351911] horae[837]: 2026-11-07T00:25:53.733149Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732061076server # [ 11.353623] horae[837]: 2026-11-07T00:25:53.733161Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732061077server # [ 11.355397] horae[837]: 2026-11-07T00:25:53.733174Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732061078server # [ 11.357127] horae[837]: 2026-11-07T00:25:53.733187Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732061079server # [ 11.358836] horae[837]: 2026-11-07T00:25:53.733205Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732061080server # [ 11.362156] horae[837]: 2026-11-07T00:25:53.733224Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732061081server # [ 11.365183] horae[837]: 2026-11-07T00:25:53.733236Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732061082server # [ 11.366952] horae[837]: 2026-11-07T00:25:53.733253Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486541083server # [ 11.368706] horae[837]: 2026-11-07T00:25:53.733266Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486541084server # [ 11.370409] horae[837]: 2026-11-07T00:25:53.733279Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486541085server # [ 11.372153] horae[837]: 2026-11-07T00:25:53.733292Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486541086server # [ 11.373885] horae[837]: 2026-11-07T00:25:53.733304Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486541087server # [ 11.375571] horae[837]: 2026-11-07T00:25:53.733320Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218001088server # [ 11.377304] horae[837]: 2026-11-07T00:25:53.733335Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915711089server # [ 11.379148] horae[837]: 2026-11-07T00:25:53.733347Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915711090server # [ 11.380924] horae[837]: 2026-11-07T00:25:53.733360Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915711091server # [ 11.382677] horae[837]: 2026-11-07T00:25:53.733372Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915711092server # [ 11.385312] horae[837]: 2026-11-07T00:25:53.733385Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915711093server # [ 11.387137] horae[837]: 2026-11-07T00:25:53.733398Z INFO dioxus_server::server: Registering: POST /api/client_detail_view104851358257774915711094server # [ 11.388847] horae[837]: 2026-11-07T00:25:53.733411Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915711095server # [ 11.390576] horae[837]: 2026-11-07T00:25:53.733423Z INFO dioxus_server::server: Registering: POST /api/client_overview104851358257774915711096server # [ 11.392271] horae[837]: 2026-11-07T00:25:53.733436Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915711097server # [ 11.393949] horae[837]: 2026-11-07T00:25:53.733449Z INFO dioxus_server::server: Registering: POST /api/save_project_managers114747469634461219341098server # [ 11.396146] horae[837]: 2026-11-07T00:25:53.733462Z INFO dioxus_server::server: Registering: POST /api/load_project_managers114747469634461219341099server # [ 11.397934] horae[837]: 2026-11-07T00:25:53.733475Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829191100server # [ 11.400765] horae[837]: 2026-11-07T00:25:53.733488Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829191101server # [ 11.402516] horae[837]: 2026-11-07T00:25:53.733502Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829191102server # [ 11.404249] horae[837]: 2026-11-07T00:25:53.733517Z INFO dioxus_server::server: Registering: POST /api/project_editor_catalog16044791136740829191103server # [ 11.406049] horae[837]: 2026-11-07T00:25:53.733535Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829191104server # [ 11.408119] horae[837]: 2026-11-07T00:25:53.733550Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829191105server # [ 11.409848] horae[837]: 2026-11-07T00:25:53.733565Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829191106server # [ 11.411648] horae[837]: 2026-11-07T00:25:53.733581Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829191107server # [ 11.413366] horae[837]: 2026-11-07T00:25:53.733598Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829191108server # [ 11.415103] horae[837]: 2026-11-07T00:25:53.733614Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829191109server # [ 11.416841] horae[837]: 2026-11-07T00:25:53.733632Z INFO dioxus_server::server: Registering: POST /api/project_people16044791136740829191110server # [ 11.419976] horae[837]: 2026-11-07T00:25:53.733650Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770261111server # [ 11.421813] horae[837]: 2026-11-07T00:25:53.733664Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770261112server # [ 11.423483] horae[837]: 2026-11-07T00:25:53.733678Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770261113server # [ 11.425179] horae[837]: 2026-11-07T00:25:53.733690Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770261114server # [ 11.426850] horae[837]: 2026-11-07T00:25:53.797352Z INFO horae: Listening on 127.0.0.1:30001115server: (finished: waiting for unit horae.service, in 1.07 seconds)1116server: waiting for TCP port 5556 on localhost1117server # Connection to localhost (127.0.0.1) 5556 port [tcp/freeciv] succeeded!1118server: (finished: waiting for TCP port 5556 on localhost, in 0.03 seconds)1119server: waiting for TCP port 3000 on localhost1120server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!1121server: (finished: waiting for TCP port 3000 on localhost, in 0.02 seconds)1122server: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok1123server: (finished: must succeed: curl -sf http://127.0.0.1:3000/health | grep -q ok, in 0.03 seconds)1124server: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null1125server: (finished: must succeed: curl -sf http://127.0.0.1:5556/dex/.well-known/openid-configuration >/dev/null, in 0.02 seconds)1126server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed1127server # [ 12.049438] sudo[868]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; ENV=DATABASE_URL=postgres:///horae ; COMMAND=/run/current-system/sw/bin/horae seed1128server # [ 12.069245] sudo[868]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1129server # [ 12.127233] sudo[868]: pam_unix(sudo:session): session closed for user horae1130server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae seed, in 0.11 seconds)1131server: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member1132server # [ 12.143624] sudo[877]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; ENV=DATABASE_URL=postgres:///horae ; COMMAND=/run/current-system/sw/bin/horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member1133server # [ 12.164401] sudo[877]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1134server # [ 12.184803] postgres[884]: [884] LOG: could not receive data from client: Connection reset by peer1135server # [ 12.187823] sudo[877]: pam_unix(sudo:session): session closed for user horae1136server: (finished: must succeed: sudo -u horae DATABASE_URL=postgres:///horae horae user create --email kilgore@kilgore.trout --name 'Kilgore Trout' --role member, in 0.06 seconds)1137server: must succeed: curl -s -c /tmp/jar.txt -b /tmp/jar.txt -L http://127.0.0.1:3000/auth/oidc/start -o /dev/null1138server # [ 12.215919] dex[756]: time=2026-11-07T00:25:54.721Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=54147c2e-fa0f-4f62-8f92-016205c3a2161139server: (finished: must succeed: curl -s -c /tmp/jar.txt -b /tmp/jar.txt -L http://127.0.0.1:3000/auth/oidc/start -o /dev/null, in 0.06 seconds)1140server: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries1141server: (finished: must succeed: curl -s -b /tmp/jar.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.04 seconds)1142server: must succeed: sudo -u horae psql horae -tAc 'SELECT id FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"''1143server # [ 12.303929] sudo[892]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT id FROM users WHERE email = \'kilgore@kilgore.trout\''1144server # [ 12.327069] sudo[892]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1145server # [ 12.341364] sudo[892]: pam_unix(sudo:session): session closed for user horae1146server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT id FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.06 seconds)1147server: must succeed: dex-id-token horae horae-e2e-secret1148server # [ 12.718454] dex[756]: time=2026-11-07T00:25:55.223Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=c440484b-cd92-4773-9503-cfed4e959c1f1149server: (finished: must succeed: dex-id-token horae horae-e2e-secret, in 0.41 seconds)1150server # [ 12.769325] sudo[906]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/env DATABASE_URL=postgres:///horae HORAE_OIDC_ISSUER=http://127.0.0.1:5556/dex horae user link-oidc --user-id 01a1b365-c8fd-7721-9f12-09c8883e0a16 --issuer http://127.0.0.1:5556/dex --subject Cg0wLTM4NS0yODA4OS0wEgRtb2Nr1151server # [ 12.790099] sudo[906]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1152server # [ 12.806793] postgres[911]: [911] LOG: could not receive data from client: Connection reset by peer1153server # [ 12.810769] sudo[906]: pam_unix(sudo:session): session closed for user horae1154server: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'"1155server # [ 12.826762] sudo[912]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -c 'UPDATE users SET active = false WHERE email = \'kilgore@kilgore.trout\''1156server # [ 12.845943] sudo[912]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1157server # [ 12.859244] sudo[912]: pam_unix(sudo:session): session closed for user horae1158server: (finished: must succeed: sudo -u horae psql horae -c "UPDATE users SET active = false WHERE email = 'kilgore@kilgore.trout'", in 0.05 seconds)1159server # [ 12.886179] dex[756]: time=2026-11-07T00:25:55.391Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=2d552cb4-8398-4e07-88da-3ce9e74ce2171160server # [ 12.898345] horae[837]: 2026-11-07T00:25:55.403680Z WARN call: horae::auth::oidc: OIDC login denied: account is deactivated1161server: must succeed: curl -s -o /dev/null -w '%{http_code}' -b /tmp/jar2.txt http://127.0.0.1:3000/harvest/v2/time_entries1162server: (finished: must succeed: curl -s -o /dev/null -w '%{http_code}' -b /tmp/jar2.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.02 seconds)1163server: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET active = true, oidc_issuer = NULL, oidc_subject = NULL WHERE email = '"'"'kilgore@kilgore.trout'"'"''1164server # [ 12.937527] sudo[926]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'UPDATE users SET active = true, oidc_issuer = NULL, oidc_subject = NULL WHERE email = \'kilgore@kilgore.trout\''1165server # [ 12.957100] sudo[926]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1166server # [ 12.971446] sudo[926]: pam_unix(sudo:session): session closed for user horae1167server: (finished: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET active = true, oidc_issuer = NULL, oidc_subject = NULL WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1168server: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' 'Environment=HORAE_OIDC_CLIENT_ID=horae-omit-email-verified' 'Environment=HORAE_OIDC_CLIENT_SECRET=horae-omit-e2e-secret' > /run/systemd/system/horae.service.d/omit-email-verified.conf1169server: (finished: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' 'Environment=HORAE_OIDC_CLIENT_ID=horae-omit-email-verified' 'Environment=HORAE_OIDC_CLIENT_SECRET=horae-omit-e2e-secret' > /run/systemd/system/horae.service.d/omit-email-verified.conf, in 0.02 seconds)1170server: must succeed: systemctl daemon-reload && systemctl restart horae.service1171server # [ 13.010214] systemd[1]: Reload requested from client PID 945 ('systemctl') (unit backdoor.service)...1172server # [ 13.011584] systemd[1]: Reloading...1173server # [ 13.215834] systemd[1]: Reloading finished in 205 ms.1174server # [ 13.254497] systemd[1]: Stopping Horae time tracking server...1175server # [ 13.265634] systemd[1]: horae.service: Deactivated successfully.1176server # [ 13.267271] systemd[1]: Stopped Horae time tracking server.1177server # [ 13.269254] systemd[1]: horae.service: Consumed 226ms CPU time over 2.715s wall clock time, 97.3M memory peak, 91.2M read from disk, 16.7K incoming IP traffic, 24.4K outgoing IP traffic.1178server # [ 13.276494] systemd[1]: Starting Horae time tracking server...1179server # [ 13.307844] horae[984]: 2026-11-07T00:25:55.813267Z INFO horae: Running migrations...1180server # [ 13.310640] horae[984]: 2026-11-07T00:25:55.816298Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1181server # [ 13.323131] horae[984]: 2026-11-07T00:25:55.826573Z INFO horae: Migrations complete.1182server # [ 13.324693] postgres[989]: [989] LOG: could not send data to client: Broken pipe1183server # [ 13.325627] postgres[989]: [989] FATAL: connection to client lost1184server # [ 13.329175] systemd[1]: Started Horae time tracking server.1185server: (finished: must succeed: systemctl daemon-reload && systemctl restart horae.service, in 0.35 seconds)1186server: waiting for TCP port 3000 on localhost1187server # [ 13.367566] horae[990]: 2026-11-07T00:25:55.872981Z INFO horae: Starting Horae on 127.0.0.1:30001188server # [ 13.377143] horae[990]: 2026-11-07T00:25:55.882791Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1189server # [ 13.387576] horae[990]: 2026-11-07T00:25:55.893204Z INFO horae::plugin::registry: plugins directory does not exist: plugins1190server # [ 13.390815] horae[990]: 2026-11-07T00:25:55.895517Z INFO sqlx::postgres::notice: schema "tower_sessions" already exists, skipping1191server # [ 13.392750] horae[990]: 2026-11-07T00:25:55.898385Z INFO sqlx::postgres::notice: relation "session" already exists, skipping1192server # [ 13.396070] horae[990]: 2026-11-07T00:25:55.900276Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181193server # [ 13.397856] horae[990]: 2026-11-07T00:25:55.900299Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181194server # [ 13.399627] horae[990]: 2026-11-07T00:25:55.900314Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181195server # [ 13.401523] horae[990]: 2026-11-07T00:25:55.900327Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181196server # [ 13.403301] horae[990]: 2026-11-07T00:25:55.900344Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181197server # [ 13.406144] horae[990]: 2026-11-07T00:25:55.900358Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181198server # [ 13.407876] horae[990]: 2026-11-07T00:25:55.900372Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181199server # [ 13.409552] horae[990]: 2026-11-07T00:25:55.900386Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181200server # [ 13.411256] horae[990]: 2026-11-07T00:25:55.900406Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_entries76905535698631861851201server # [ 13.413146] horae[990]: 2026-11-07T00:25:55.900420Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_groups76905535698631861851202server # [ 13.414985] horae[990]: 2026-11-07T00:25:55.900434Z INFO dioxus_server::server: Registering: POST /api/get_time_report_access76905535698631861851203server # [ 13.417507] horae[990]: 2026-11-07T00:25:55.900448Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851204server # [ 13.419304] horae[990]: 2026-11-07T00:25:55.900460Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851205server # [ 13.421149] horae[990]: 2026-11-07T00:25:55.900474Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851206server # [ 13.422846] horae[990]: 2026-11-07T00:25:55.900488Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071207server # [ 13.425796] horae[990]: 2026-11-07T00:25:55.900510Z INFO dioxus_server::server: Registering: POST /api/list_permission_audit161443592707961243071208server # [ 13.429065] horae[990]: 2026-11-07T00:25:55.900522Z INFO dioxus_server::server: Registering: POST /api/get_permission_audit161443592707961243071209server # [ 13.430879] horae[990]: 2026-11-07T00:25:55.900536Z INFO dioxus_server::server: Registering: POST /api/get_my_permissions161443592707961243071210server # [ 13.432637] horae[990]: 2026-11-07T00:25:55.900549Z INFO dioxus_server::server: Registering: POST /api/preview_permission_template_deletion115797103093460273031211server # [ 13.434539] horae[990]: 2026-11-07T00:25:55.900562Z INFO dioxus_server::server: Registering: POST /api/preview_person_permissions115797103093460273031212server # [ 13.436342] horae[990]: 2026-11-07T00:25:55.900576Z INFO dioxus_server::server: Registering: POST /api/save_permission_template115797103093460273031213server # [ 13.438138] horae[990]: 2026-11-07T00:25:55.900589Z INFO dioxus_server::server: Registering: POST /api/list_permission_subjects115797103093460273031214server # [ 13.441264] horae[990]: 2026-11-07T00:25:55.900603Z INFO dioxus_server::server: Registering: POST /api/save_person_permissions115797103093460273031215server # [ 13.444139] horae[990]: 2026-11-07T00:25:55.900618Z INFO dioxus_server::server: Registering: POST /api/load_permission_editor115797103093460273031216server # [ 13.445949] horae[990]: 2026-11-07T00:25:55.900631Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_entries163860635615838339551217server # [ 13.447740] horae[990]: 2026-11-07T00:25:55.900645Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551218server # [ 13.449549] horae[990]: 2026-11-07T00:25:55.900657Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_tracking163860635615838339551219server # [ 13.452662] horae[990]: 2026-11-07T00:25:55.900669Z INFO dioxus_server::server: Registering: POST /api/apply_timesheet_command163860635615838339551220server # [ 13.454458] horae[990]: 2026-11-07T00:25:55.900686Z INFO dioxus_server::server: Registering: POST /api/list_timesheet_people163860635615838339551221server # [ 13.456256] horae[990]: 2026-11-07T00:25:55.900737Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_page163860635615838339551222server # [ 13.458033] horae[990]: 2026-11-07T00:25:55.900752Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551223server # [ 13.459776] horae[990]: 2026-11-07T00:25:55.900764Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551224server # [ 13.461531] horae[990]: 2026-11-07T00:25:55.900778Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551225server # [ 13.466860] horae[990]: 2026-11-07T00:25:55.900791Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1226server # [ 13.468502] horae[990]: 2026-11-07T00:25:55.900804Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1227server # [ 13.470167] horae[990]: 2026-11-07T00:25:55.900817Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1228server # [ 13.471916] horae[990]: 2026-11-07T00:25:55.900832Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1229server # [ 13.473504] horae[990]: 2026-11-07T00:25:55.900845Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry1230server # [ 13.476690] horae[990]: 2026-11-07T00:25:55.900856Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history1231server # [ 13.478303] horae[990]: 2026-11-07T00:25:55.900869Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client1232server # [ 13.479986] horae[990]: 2026-11-07T00:25:55.900881Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993931233server # [ 13.481815] horae[990]: 2026-11-07T00:25:55.900894Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status1234server # [ 13.483409] horae[990]: 2026-11-07T00:25:55.900906Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/remove1235server # [ 13.485096] horae[990]: 2026-11-07T00:25:55.900919Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993931236server # [ 13.489142] horae[990]: 2026-11-07T00:25:55.900935Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/save1237server # [ 13.491193] horae[990]: 2026-11-07T00:25:55.900948Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993931238server # [ 13.492957] horae[990]: 2026-11-07T00:25:55.900961Z INFO dioxus_server::server: Registering: POST /api/load_task_catalog122563778109868174721239server # [ 13.494711] horae[990]: 2026-11-07T00:25:55.900974Z INFO dioxus_server::server: Registering: POST /api/get_project_detail_view79311481232659985111240server # [ 13.496470] horae[990]: 2026-11-07T00:25:55.900986Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634961241server # [ 13.498217] horae[990]: 2026-11-07T00:25:55.900998Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634961242server # [ 13.502130] horae[990]: 2026-11-07T00:25:55.901012Z INFO dioxus_server::server: Registering: POST /api/list_people137396755887194634961243server # [ 13.504495] horae[990]: 2026-11-07T00:25:55.901025Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634961244server # [ 13.506225] horae[990]: 2026-11-07T00:25:55.901037Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634961245server # [ 13.508043] horae[990]: 2026-11-07T00:25:55.901049Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732061246server # [ 13.510090] horae[990]: 2026-11-07T00:25:55.901063Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732061247server # [ 13.513486] horae[990]: 2026-11-07T00:25:55.901075Z INFO dioxus_server::server: Registering: POST /api/get_project_overview139632003752584732061248server # [ 13.515374] horae[990]: 2026-11-07T00:25:55.901094Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732061249server # [ 13.517234] horae[990]: 2026-11-07T00:25:55.901136Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732061250server # [ 13.519077] horae[990]: 2026-11-07T00:25:55.901150Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732061251server # [ 13.520973] horae[990]: 2026-11-07T00:25:55.901164Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732061252server # [ 13.525929] horae[990]: 2026-11-07T00:25:55.901176Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732061253server # [ 13.527689] horae[990]: 2026-11-07T00:25:55.901189Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732061254server # [ 13.529426] horae[990]: 2026-11-07T00:25:55.901201Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732061255server # [ 13.531205] horae[990]: 2026-11-07T00:25:55.901214Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732061256server # [ 13.532973] horae[990]: 2026-11-07T00:25:55.901226Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732061257server # [ 13.537250] horae[990]: 2026-11-07T00:25:55.901238Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732061258server # [ 13.539215] horae[990]: 2026-11-07T00:25:55.901259Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732061259server # [ 13.540960] horae[990]: 2026-11-07T00:25:55.901271Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732061260server # [ 13.542635] horae[990]: 2026-11-07T00:25:55.901286Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486541261server # [ 13.544381] horae[990]: 2026-11-07T00:25:55.901299Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486541262server # [ 13.546283] horae[990]: 2026-11-07T00:25:55.901312Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486541263server # [ 13.548054] horae[990]: 2026-11-07T00:25:55.901324Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486541264server # [ 13.549773] horae[990]: 2026-11-07T00:25:55.901336Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486541265server # [ 13.551453] horae[990]: 2026-11-07T00:25:55.901351Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218001266server # [ 13.553230] horae[990]: 2026-11-07T00:25:55.901363Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915711267server # [ 13.555030] horae[990]: 2026-11-07T00:25:55.901375Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915711268server # [ 13.556792] horae[990]: 2026-11-07T00:25:55.901387Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915711269server # [ 13.558528] horae[990]: 2026-11-07T00:25:55.901400Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915711270server # [ 13.560297] horae[990]: 2026-11-07T00:25:55.901413Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915711271server # [ 13.562094] horae[990]: 2026-11-07T00:25:55.901426Z INFO dioxus_server::server: Registering: POST /api/client_detail_view104851358257774915711272server # [ 13.563827] horae[990]: 2026-11-07T00:25:55.901439Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915711273server # [ 13.565553] horae[990]: 2026-11-07T00:25:55.901451Z INFO dioxus_server::server: Registering: POST /api/client_overview104851358257774915711274server # [ 13.567257] horae[990]: 2026-11-07T00:25:55.901464Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915711275server # [ 13.568937] horae[990]: 2026-11-07T00:25:55.901477Z INFO dioxus_server::server: Registering: POST /api/save_project_managers114747469634461219341276server # [ 13.570682] horae[990]: 2026-11-07T00:25:55.901489Z INFO dioxus_server::server: Registering: POST /api/load_project_managers114747469634461219341277server # [ 13.572417] horae[990]: 2026-11-07T00:25:55.901501Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829191278server # [ 13.574208] horae[990]: 2026-11-07T00:25:55.901514Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829191279server # [ 13.576029] horae[990]: 2026-11-07T00:25:55.901527Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829191280server # [ 13.577860] horae[990]: 2026-11-07T00:25:55.901539Z INFO dioxus_server::server: Registering: POST /api/project_editor_catalog16044791136740829191281server # [ 13.579710] horae[990]: 2026-11-07T00:25:55.901552Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829191282server # [ 13.581478] horae[990]: 2026-11-07T00:25:55.901564Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829191283server # [ 13.583235] horae[990]: 2026-11-07T00:25:55.901576Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829191284server # [ 13.585103] horae[990]: 2026-11-07T00:25:55.901589Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829191285server # [ 13.586822] horae[990]: 2026-11-07T00:25:55.901601Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829191286server # [ 13.588559] horae[990]: 2026-11-07T00:25:55.901614Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829191287server # [ 13.590280] horae[990]: 2026-11-07T00:25:55.901626Z INFO dioxus_server::server: Registering: POST /api/project_people16044791136740829191288server # [ 13.592042] horae[990]: 2026-11-07T00:25:55.901639Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770261289server # [ 13.593867] horae[990]: 2026-11-07T00:25:55.901651Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770261290server # [ 13.595695] horae[990]: 2026-11-07T00:25:55.901663Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770261291server # [ 13.597408] horae[990]: 2026-11-07T00:25:55.901675Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770261292server # [ 13.599106] horae[990]: 2026-11-07T00:25:55.957248Z INFO horae: Listening on 127.0.0.1:30001293server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!1294server: (finished: waiting for TCP port 3000 on localhost, in 1.04 seconds)1295server: must succeed: dex-id-token horae-omit-email-verified horae-omit-e2e-secret1296server # [ 14.577379] dex[756]: time=2026-11-07T00:25:57.082Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=ec58af20-40c9-4aa8-903f-fa25cac2cfed1297server: (finished: must succeed: dex-id-token horae-omit-email-verified horae-omit-e2e-secret, in 0.23 seconds)1298server: must succeed: curl -sS -c /tmp/jar3.txt -b /tmp/jar3.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null1299server # [ 14.632772] dex[756]: time=2026-11-07T00:25:57.138Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=13ad5973-899d-4ad2-b01c-c6fc7a2fa6331300server # [ 14.646322] horae[990]: 2026-11-07T00:25:57.151604Z WARN call: horae::auth::oidc: OIDC login denied: no account for subject/email1301server: (finished: must succeed: curl -sS -c /tmp/jar3.txt -b /tmp/jar3.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null, in 0.04 seconds)1302server: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar3.txt http://127.0.0.1:3000/harvest/v2/time_entries1303server: (finished: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar3.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.02 seconds)1304server: must succeed: sudo -u horae psql horae -tAc 'SELECT oidc_issuer IS NULL AND oidc_subject IS NULL FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"''1305server # [ 14.685759] sudo[1019]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT oidc_issuer IS NULL AND oidc_subject IS NULL FROM users WHERE email = \'kilgore@kilgore.trout\''1306server # [ 14.705457] sudo[1019]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1307server # [ 14.718040] sudo[1019]: pam_unix(sudo:session): session closed for user horae1308server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT oidc_issuer IS NULL AND oidc_subject IS NULL FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1309server # [ 14.738563] sudo[1030]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/env DATABASE_URL=postgres:///horae HORAE_OIDC_ISSUER=http://127.0.0.1:5556/dex horae user link-oidc --user-id 01a1b365-c8fd-7721-9f12-09c8883e0a16 --issuer http://127.0.0.1:5556/dex --subject Cg0wLTM4NS0yODA4OS0wEgRtb2Nr1310server # [ 14.760160] sudo[1030]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1311server # [ 14.781473] sudo[1030]: pam_unix(sudo:session): session closed for user horae1312server # [ 14.802726] sudo[1040]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/env DATABASE_URL=postgres:///horae HORAE_OIDC_ISSUER=http://127.0.0.1:5556/dex horae user link-oidc --user-id 01a1b365-c8fd-7721-9f12-09c8883e0a16 --issuer http://127.0.0.1:5556/dex --subject Cg0wLTM4NS0yODA4OS0wEgRtb2Nr1313server # [ 14.825483] sudo[1040]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1314server # [ 14.844494] sudo[1040]: pam_unix(sudo:session): session closed for user horae1315server # [ 14.864858] sudo[1050]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/env DATABASE_URL=postgres:///horae HORAE_OIDC_ISSUER=http://127.0.0.1:5556/dex horae user create --email other@kilgore.trout --name Other --role member1316server # [ 14.889851] sudo[1050]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1317server # [ 14.912910] postgres[1055]: [1055] LOG: could not receive data from client: Connection reset by peer1318server # [ 14.916747] sudo[1050]: pam_unix(sudo:session): session closed for user horae1319server: must succeed: sudo -u horae psql horae -tAc 'SELECT id FROM users WHERE email = '"'"'other@kilgore.trout'"'"''1320server # [ 14.934381] sudo[1056]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT id FROM users WHERE email = \'other@kilgore.trout\''1321server # [ 14.959707] sudo[1056]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1322server # [ 14.973251] sudo[1056]: pam_unix(sudo:session): session closed for user horae1323server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT id FROM users WHERE email = '"'"'other@kilgore.trout'"'"'', in 0.06 seconds)1324server # [ 14.991941] sudo[1067]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/env DATABASE_URL=postgres:///horae HORAE_OIDC_ISSUER=http://127.0.0.1:5556/dex horae user link-oidc --user-id 01a1b365-d3a5-7751-b7a3-cf017c138dea --issuer http://127.0.0.1:5556/dex --subject Cg0wLTM4NS0yODA4OS0wEgRtb2Nr1325server # [ 15.013169] sudo[1067]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1326server # [ 15.031744] postgres[1072]: [1072] LOG: could not send data to client: Broken pipe1327server # [ 15.032764] postgres[1072]: [1072] FATAL: connection to client lost1328server # [ 15.033611] sudo[1067]: pam_unix(sudo:session): session closed for user horae1329server: must succeed: curl -sS -c /tmp/jar4.txt -b /tmp/jar4.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null1330server # [ 15.068080] dex[756]: time=2026-11-07T00:25:57.573Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=6e2e50a8-97b3-4e29-9ee9-5880764461e11331server # [ 15.081982] dhcpcd[647]: eth0: leased 10.0.2.15 for 86400 seconds1332server # [ 15.083930] dhcpcd[647]: eth0: adding route to 10.0.2.0/241333server # [ 15.084700] dhcpcd[647]: eth0: adding default route via 10.0.2.21334server: (finished: must succeed: curl -sS -c /tmp/jar4.txt -b /tmp/jar4.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null, in 0.11 seconds)1335server: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar4.txt http://127.0.0.1:3000/harvest/v2/time_entries1336server # [ 15.152272] systemd[1]: Started DHCP Client.1337server # [ 15.154817] systemd[1]: Reached target Multi-User System.1338server # [ 15.155784] systemd[1]: Startup finished in 679ms (kernel) + 4.208s (initrd) + 10.266s (userspace) = 15.154s.1339server: (finished: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar4.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.06 seconds)1340server: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET email = '"'"'kilgore-linked@kilgore.trout'"'"', oidc_issuer = NULL, oidc_subject = NULL WHERE email = '"'"'kilgore@kilgore.trout'"'"''1341server # [ 15.212243] sudo[1126]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'UPDATE users SET email = \'kilgore-linked@kilgore.trout\', oidc_issuer = NULL, oidc_subject = NULL WHERE email = \'kilgore@kilgore.trout\''1342server # [ 15.232796] sudo[1126]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1343server # [ 15.248680] sudo[1126]: pam_unix(sudo:session): session closed for user horae1344server: (finished: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET email = '"'"'kilgore-linked@kilgore.trout'"'"', oidc_issuer = NULL, oidc_subject = NULL WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1345server: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae Environment=HORAE_OIDC_CLIENT_SECRET=horae-e2e-secret > /run/systemd/system/horae.service.d/omit-email-verified.conf1346server: (finished: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae Environment=HORAE_OIDC_CLIENT_SECRET=horae-e2e-secret > /run/systemd/system/horae.service.d/omit-email-verified.conf, in 0.01 seconds)1347server: must succeed: systemctl daemon-reload && systemctl restart horae.service1348server # [ 15.273084] systemd[1]: Reload requested from client PID 1140 ('systemctl') (unit backdoor.service)...1349server # [ 15.274867] systemd[1]: Reloading...1350server # [ 15.482846] systemd[1]: Reloading finished in 210 ms.1351server # [ 15.523694] systemd[1]: Stopping Horae time tracking server...1352server # [ 15.530900] systemd[1]: horae.service: Deactivated successfully.1353server # [ 15.534203] systemd[1]: Stopped Horae time tracking server.1354server # [ 15.535220] systemd[1]: horae.service: Consumed 70ms CPU time over 2.253s wall clock time, 5.7M memory peak, 16.4K incoming IP traffic, 24.2K outgoing IP traffic.1355server # [ 15.546524] systemd[1]: Starting Horae time tracking server...1356server # [ 15.581616] horae[1178]: 2026-11-07T00:25:58.086281Z INFO horae: Running migrations...1357server # [ 15.583987] horae[1178]: 2026-11-07T00:25:58.089627Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1358server # [ 15.593758] horae[1178]: 2026-11-07T00:25:58.099274Z INFO horae: Migrations complete.1359server # [ 15.598434] postgres[1183]: [1183] LOG: could not receive data from client: Connection reset by peer1360server # [ 15.602982] systemd[1]: Started Horae time tracking server.1361server: (finished: must succeed: systemctl daemon-reload && systemctl restart horae.service, in 0.35 seconds)1362server: waiting for TCP port 3000 on localhost1363server # [ 15.640683] horae[1184]: 2026-11-07T00:25:58.146177Z INFO horae: Starting Horae on 127.0.0.1:30001364server # [ 15.646454] horae[1184]: 2026-11-07T00:25:58.152125Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1365server # [ 15.653789] horae[1184]: 2026-11-07T00:25:58.159380Z INFO horae::plugin::registry: plugins directory does not exist: plugins1366server # [ 15.661021] horae[1184]: 2026-11-07T00:25:58.166436Z INFO sqlx::postgres::notice: schema "tower_sessions" already exists, skipping1367server # [ 15.662339] horae[1184]: 2026-11-07T00:25:58.166643Z INFO sqlx::postgres::notice: relation "session" already exists, skipping1368server # [ 15.665049] horae[1184]: 2026-11-07T00:25:58.169454Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181369server # [ 15.666415] horae[1184]: 2026-11-07T00:25:58.169473Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181370server # [ 15.667925] horae[1184]: 2026-11-07T00:25:58.169484Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181371server # [ 15.669225] horae[1184]: 2026-11-07T00:25:58.169493Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181372server # [ 15.670537] horae[1184]: 2026-11-07T00:25:58.169505Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181373server # [ 15.671776] horae[1184]: 2026-11-07T00:25:58.169516Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181374server # [ 15.673053] horae[1184]: 2026-11-07T00:25:58.169525Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181375server # [ 15.674290] horae[1184]: 2026-11-07T00:25:58.169535Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181376server # [ 15.675498] horae[1184]: 2026-11-07T00:25:58.169548Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_entries76905535698631861851377server # [ 15.676876] horae[1184]: 2026-11-07T00:25:58.169558Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_groups76905535698631861851378server # [ 15.678204] horae[1184]: 2026-11-07T00:25:58.169568Z INFO dioxus_server::server: Registering: POST /api/get_time_report_access76905535698631861851379server # [ 15.679482] horae[1184]: 2026-11-07T00:25:58.169579Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851380server # [ 15.680776] horae[1184]: 2026-11-07T00:25:58.169589Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851381server # [ 15.682105] horae[1184]: 2026-11-07T00:25:58.169599Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851382server # [ 15.683322] horae[1184]: 2026-11-07T00:25:58.169609Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071383server # [ 15.684514] horae[1184]: 2026-11-07T00:25:58.169624Z INFO dioxus_server::server: Registering: POST /api/list_permission_audit161443592707961243071384server # [ 15.685845] horae[1184]: 2026-11-07T00:25:58.169634Z INFO dioxus_server::server: Registering: POST /api/get_permission_audit161443592707961243071385server # [ 15.687130] horae[1184]: 2026-11-07T00:25:58.169643Z INFO dioxus_server::server: Registering: POST /api/get_my_permissions161443592707961243071386server # [ 15.688401] horae[1184]: 2026-11-07T00:25:58.169652Z INFO dioxus_server::server: Registering: POST /api/preview_permission_template_deletion115797103093460273031387server # [ 15.689808] horae[1184]: 2026-11-07T00:25:58.169662Z INFO dioxus_server::server: Registering: POST /api/preview_person_permissions115797103093460273031388server # [ 15.691122] horae[1184]: 2026-11-07T00:25:58.169672Z INFO dioxus_server::server: Registering: POST /api/save_permission_template115797103093460273031389server # [ 15.692429] horae[1184]: 2026-11-07T00:25:58.169682Z INFO dioxus_server::server: Registering: POST /api/list_permission_subjects115797103093460273031390server # [ 15.694579] horae[1184]: 2026-11-07T00:25:58.169691Z INFO dioxus_server::server: Registering: POST /api/save_person_permissions115797103093460273031391server # [ 15.695930] horae[1184]: 2026-11-07T00:25:58.169701Z INFO dioxus_server::server: Registering: POST /api/load_permission_editor115797103093460273031392server # [ 15.697223] horae[1184]: 2026-11-07T00:25:58.169730Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_entries163860635615838339551393server # [ 15.698531] horae[1184]: 2026-11-07T00:25:58.169740Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551394server # [ 15.699924] horae[1184]: 2026-11-07T00:25:58.169749Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_tracking163860635615838339551395server # [ 15.701244] horae[1184]: 2026-11-07T00:25:58.169759Z INFO dioxus_server::server: Registering: POST /api/apply_timesheet_command163860635615838339551396server # [ 15.702577] horae[1184]: 2026-11-07T00:25:58.169770Z INFO dioxus_server::server: Registering: POST /api/list_timesheet_people163860635615838339551397server # [ 15.703925] horae[1184]: 2026-11-07T00:25:58.169790Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_page163860635615838339551398server # [ 15.707788] horae[1184]: 2026-11-07T00:25:58.169800Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551399server # [ 15.709107] horae[1184]: 2026-11-07T00:25:58.169809Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551400server # [ 15.712297] horae[1184]: 2026-11-07T00:25:58.169819Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551401server # [ 15.714113] horae[1184]: 2026-11-07T00:25:58.169829Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1402server # [ 15.718965] horae[1184]: 2026-11-07T00:25:58.169838Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1403server # [ 15.720657] horae[1184]: 2026-11-07T00:25:58.169847Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1404server # [ 15.722340] horae[1184]: 2026-11-07T00:25:58.169858Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1405server # [ 15.724033] horae[1184]: 2026-11-07T00:25:58.169869Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry1406server # [ 15.725671] horae[1184]: 2026-11-07T00:25:58.169878Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history1407server # [ 15.727297] horae[1184]: 2026-11-07T00:25:58.169887Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client1408server # [ 15.729830] horae[1184]: 2026-11-07T00:25:58.169896Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993931409server # [ 15.732621] horae[1184]: 2026-11-07T00:25:58.169906Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status1410server # [ 15.734295] horae[1184]: 2026-11-07T00:25:58.169915Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/remove1411server # [ 15.736074] horae[1184]: 2026-11-07T00:25:58.169924Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993931412server # [ 15.737861] horae[1184]: 2026-11-07T00:25:58.169934Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/save1413server # [ 15.739501] horae[1184]: 2026-11-07T00:25:58.169945Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993931414server # [ 15.741478] horae[1184]: 2026-11-07T00:25:58.169955Z INFO dioxus_server::server: Registering: POST /api/load_task_catalog122563778109868174721415server # [ 15.743242] horae[1184]: 2026-11-07T00:25:58.169964Z INFO dioxus_server::server: Registering: POST /api/get_project_detail_view79311481232659985111416server # [ 15.745135] horae[1184]: 2026-11-07T00:25:58.169973Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634961417server # [ 15.746908] horae[1184]: 2026-11-07T00:25:58.169982Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634961418server # [ 15.748600] horae[1184]: 2026-11-07T00:25:58.169991Z INFO dioxus_server::server: Registering: POST /api/list_people137396755887194634961419server # [ 15.750308] horae[1184]: 2026-11-07T00:25:58.170002Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634961420server # [ 15.752032] horae[1184]: 2026-11-07T00:25:58.170011Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634961421server # [ 15.753788] horae[1184]: 2026-11-07T00:25:58.170020Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732061422server # [ 15.755601] horae[1184]: 2026-11-07T00:25:58.170030Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732061423server # [ 15.757397] horae[1184]: 2026-11-07T00:25:58.170039Z INFO dioxus_server::server: Registering: POST /api/get_project_overview139632003752584732061424server # [ 15.759192] horae[1184]: 2026-11-07T00:25:58.170053Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732061425server # [ 15.761063] horae[1184]: 2026-11-07T00:25:58.170089Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732061426server # [ 15.762851] horae[1184]: 2026-11-07T00:25:58.170100Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732061427server # [ 15.764676] horae[1184]: 2026-11-07T00:25:58.170110Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732061428server # [ 15.766430] horae[1184]: 2026-11-07T00:25:58.170119Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732061429server # [ 15.768195] horae[1184]: 2026-11-07T00:25:58.170129Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732061430server # [ 15.769969] horae[1184]: 2026-11-07T00:25:58.170137Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732061431server # [ 15.771693] horae[1184]: 2026-11-07T00:25:58.170146Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732061432server # [ 15.773387] horae[1184]: 2026-11-07T00:25:58.170155Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732061433server # [ 15.775134] horae[1184]: 2026-11-07T00:25:58.170165Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732061434server # [ 15.776893] horae[1184]: 2026-11-07T00:25:58.170174Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732061435server # [ 15.778575] horae[1184]: 2026-11-07T00:25:58.170183Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732061436server # [ 15.780264] horae[1184]: 2026-11-07T00:25:58.170192Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486541437server # [ 15.782096] horae[1184]: 2026-11-07T00:25:58.170201Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486541438server # [ 15.783842] horae[1184]: 2026-11-07T00:25:58.170211Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486541439server # [ 15.785572] horae[1184]: 2026-11-07T00:25:58.170219Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486541440server # [ 15.787288] horae[1184]: 2026-11-07T00:25:58.170228Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486541441server # [ 15.788996] horae[1184]: 2026-11-07T00:25:58.170239Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218001442server # [ 15.790697] horae[1184]: 2026-11-07T00:25:58.170254Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915711443server # [ 15.792451] horae[1184]: 2026-11-07T00:25:58.170263Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915711444server # [ 15.794269] horae[1184]: 2026-11-07T00:25:58.170272Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915711445server # [ 15.796083] horae[1184]: 2026-11-07T00:25:58.170282Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915711446server # [ 15.797815] horae[1184]: 2026-11-07T00:25:58.170291Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915711447server # [ 15.799568] horae[1184]: 2026-11-07T00:25:58.170300Z INFO dioxus_server::server: Registering: POST /api/client_detail_view104851358257774915711448server # [ 15.801294] horae[1184]: 2026-11-07T00:25:58.170309Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915711449server # [ 15.803041] horae[1184]: 2026-11-07T00:25:58.170318Z INFO dioxus_server::server: Registering: POST /api/client_overview104851358257774915711450server # [ 15.804714] horae[1184]: 2026-11-07T00:25:58.170330Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915711451server # [ 15.806384] horae[1184]: 2026-11-07T00:25:58.170339Z INFO dioxus_server::server: Registering: POST /api/save_project_managers114747469634461219341452server # [ 15.808149] horae[1184]: 2026-11-07T00:25:58.170348Z INFO dioxus_server::server: Registering: POST /api/load_project_managers114747469634461219341453server # [ 15.809935] horae[1184]: 2026-11-07T00:25:58.170357Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829191454server # [ 15.811885] horae[1184]: 2026-11-07T00:25:58.170367Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829191455server # [ 15.813659] horae[1184]: 2026-11-07T00:25:58.170376Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829191456server # [ 15.815444] horae[1184]: 2026-11-07T00:25:58.170385Z INFO dioxus_server::server: Registering: POST /api/project_editor_catalog16044791136740829191457server # [ 15.817226] horae[1184]: 2026-11-07T00:25:58.170394Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829191458server # [ 15.819050] horae[1184]: 2026-11-07T00:25:58.170403Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829191459server # [ 15.820812] horae[1184]: 2026-11-07T00:25:58.170412Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829191460server # [ 15.822572] horae[1184]: 2026-11-07T00:25:58.170421Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829191461server # [ 15.824339] horae[1184]: 2026-11-07T00:25:58.170431Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829191462server # [ 15.826125] horae[1184]: 2026-11-07T00:25:58.170439Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829191463server # [ 15.827885] horae[1184]: 2026-11-07T00:25:58.170450Z INFO dioxus_server::server: Registering: POST /api/project_people16044791136740829191464server # [ 15.829595] horae[1184]: 2026-11-07T00:25:58.170459Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770261465server # [ 15.831373] horae[1184]: 2026-11-07T00:25:58.170468Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770261466server # [ 15.833148] horae[1184]: 2026-11-07T00:25:58.170477Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770261467server # [ 15.834876] horae[1184]: 2026-11-07T00:25:58.170486Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770261468server # [ 15.836770] horae[1184]: 2026-11-07T00:25:58.211165Z INFO horae: Listening on 127.0.0.1:30001469server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!1470server: (finished: waiting for TCP port 3000 on localhost, in 1.05 seconds)1471server: must succeed: curl -sS -c /tmp/jar5.txt -b /tmp/jar5.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start1472server # [ 16.680395] dex[756]: time=2026-11-07T00:25:59.185Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=9e5fe81f-1d77-41cf-94c6-2d1281571aec1473server # [ 16.697157] horae[1184]: 2026-11-07T00:25:59.202414Z WARN call: horae::auth::oidc: OIDC login denied: no account for subject/email1474server: (finished: must succeed: curl -sS -c /tmp/jar5.txt -b /tmp/jar5.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start, in 0.05 seconds)1475server: must succeed: curl -sS -c /tmp/jar5.txt -b /tmp/jar5.txt http://127.0.0.1:3000/auth/login1476server: (finished: must succeed: curl -sS -c /tmp/jar5.txt -b /tmp/jar5.txt http://127.0.0.1:3000/auth/login, in 0.02 seconds)1477server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0'1478server # [ 16.741896] sudo[1208]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to(\'kilgore@kilgore.trout\', \'UTF8\') in data) > 0'1479server # [ 16.761577] sudo[1208]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1480server # [ 16.774088] sudo[1208]: pam_unix(sudo:session): session closed for user horae1481server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0', in 0.05 seconds)1482server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"''1483server # [ 16.791735] sudo[1215]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = \'kilgore@kilgore.trout\''1484server # [ 16.811200] sudo[1215]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1485server # [ 16.825143] sudo[1215]: pam_unix(sudo:session): session closed for user horae1486server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1487server: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae Environment=HORAE_OIDC_CLIENT_SECRET=horae-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=example.com > /run/systemd/system/horae.service.d/omit-email-verified.conf1488server: (finished: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae Environment=HORAE_OIDC_CLIENT_SECRET=horae-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=example.com > /run/systemd/system/horae.service.d/omit-email-verified.conf, in 0.01 seconds)1489server: must succeed: systemctl daemon-reload && systemctl restart horae.service1490server # [ 16.849118] systemd[1]: Reload requested from client PID 1229 ('systemctl') (unit backdoor.service)...1491server # [ 16.850527] systemd[1]: Reloading...1492server # [ 17.051319] systemd[1]: Reloading finished in 199 ms.1493server # [ 17.091397] systemd[1]: Stopping Horae time tracking server...1494server # [ 17.097216] systemd[1]: horae.service: Deactivated successfully.1495server # [ 17.100969] systemd[1]: Stopped Horae time tracking server.1496server # [ 17.101789] systemd[1]: horae.service: Consumed 57ms CPU time over 1.548s wall clock time, 5.4M memory peak, 8.2K incoming IP traffic, 18K outgoing IP traffic.1497server # [ 17.109346] systemd[1]: Starting Horae time tracking server...1498server # [ 17.140201] horae[1267]: 2026-11-07T00:25:59.645567Z INFO horae: Running migrations...1499server # [ 17.142872] horae[1267]: 2026-11-07T00:25:59.648533Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1500server # [ 17.152650] horae[1267]: 2026-11-07T00:25:59.658189Z INFO horae: Migrations complete.1501server # [ 17.158194] systemd[1]: Started Horae time tracking server.1502server: (finished: must succeed: systemctl daemon-reload && systemctl restart horae.service, in 0.33 seconds)1503server: waiting for TCP port 3000 on localhost1504server # [ 17.194951] horae[1273]: 2026-11-07T00:25:59.700387Z INFO horae: Starting Horae on 127.0.0.1:30001505server # [ 17.202844] horae[1273]: 2026-11-07T00:25:59.708498Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1506server # [ 17.213469] horae[1273]: 2026-11-07T00:25:59.718289Z INFO horae::plugin::registry: plugins directory does not exist: plugins1507server # [ 17.221520] horae[1273]: 2026-11-07T00:25:59.726976Z INFO sqlx::postgres::notice: schema "tower_sessions" already exists, skipping1508server # [ 17.223557] horae[1273]: 2026-11-07T00:25:59.729176Z INFO sqlx::postgres::notice: relation "session" already exists, skipping1509server # [ 17.226058] horae[1273]: 2026-11-07T00:25:59.731135Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181510server # [ 17.227840] horae[1273]: 2026-11-07T00:25:59.731159Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181511server # [ 17.229560] horae[1273]: 2026-11-07T00:25:59.731174Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181512server # [ 17.231289] horae[1273]: 2026-11-07T00:25:59.731188Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181513server # [ 17.233276] horae[1273]: 2026-11-07T00:25:59.731203Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181514server # [ 17.234989] horae[1273]: 2026-11-07T00:25:59.731218Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181515server # [ 17.236686] horae[1273]: 2026-11-07T00:25:59.731231Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181516server # [ 17.238365] horae[1273]: 2026-11-07T00:25:59.731251Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181517server # [ 17.240050] horae[1273]: 2026-11-07T00:25:59.731271Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_entries76905535698631861851518server # [ 17.241906] horae[1273]: 2026-11-07T00:25:59.731284Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_groups76905535698631861851519server # [ 17.243716] horae[1273]: 2026-11-07T00:25:59.731298Z INFO dioxus_server::server: Registering: POST /api/get_time_report_access76905535698631861851520server # [ 17.248324] horae[1273]: 2026-11-07T00:25:59.731311Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851521server # [ 17.250119] horae[1273]: 2026-11-07T00:25:59.731323Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851522server # [ 17.251848] horae[1273]: 2026-11-07T00:25:59.731337Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851523server # [ 17.253512] horae[1273]: 2026-11-07T00:25:59.731351Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071524server # [ 17.255144] horae[1273]: 2026-11-07T00:25:59.731371Z INFO dioxus_server::server: Registering: POST /api/list_permission_audit161443592707961243071525server # [ 17.256918] horae[1273]: 2026-11-07T00:25:59.731384Z INFO dioxus_server::server: Registering: POST /api/get_permission_audit161443592707961243071526server # [ 17.258962] horae[1273]: 2026-11-07T00:25:59.731397Z INFO dioxus_server::server: Registering: POST /api/get_my_permissions161443592707961243071527server # [ 17.260702] horae[1273]: 2026-11-07T00:25:59.731410Z INFO dioxus_server::server: Registering: POST /api/preview_permission_template_deletion115797103093460273031528server # [ 17.262568] horae[1273]: 2026-11-07T00:25:59.731424Z INFO dioxus_server::server: Registering: POST /api/preview_person_permissions115797103093460273031529server # [ 17.264351] horae[1273]: 2026-11-07T00:25:59.731437Z INFO dioxus_server::server: Registering: POST /api/save_permission_template115797103093460273031530server # [ 17.266148] horae[1273]: 2026-11-07T00:25:59.731450Z INFO dioxus_server::server: Registering: POST /api/list_permission_subjects115797103093460273031531server # [ 17.270113] horae[1273]: 2026-11-07T00:25:59.731464Z INFO dioxus_server::server: Registering: POST /api/save_person_permissions115797103093460273031532server # [ 17.271917] horae[1273]: 2026-11-07T00:25:59.731477Z INFO dioxus_server::server: Registering: POST /api/load_permission_editor115797103093460273031533server # [ 17.273667] horae[1273]: 2026-11-07T00:25:59.731491Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_entries163860635615838339551534server # [ 17.275441] horae[1273]: 2026-11-07T00:25:59.731505Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551535server # [ 17.277274] horae[1273]: 2026-11-07T00:25:59.731517Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_tracking163860635615838339551536server # [ 17.279492] horae[1273]: 2026-11-07T00:25:59.731530Z INFO dioxus_server::server: Registering: POST /api/apply_timesheet_command163860635615838339551537server # [ 17.281307] horae[1273]: 2026-11-07T00:25:59.731546Z INFO dioxus_server::server: Registering: POST /api/list_timesheet_people163860635615838339551538server # [ 17.286691] horae[1273]: 2026-11-07T00:25:59.731576Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_page163860635615838339551539server # [ 17.288467] horae[1273]: 2026-11-07T00:25:59.731589Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551540server # [ 17.290228] horae[1273]: 2026-11-07T00:25:59.731602Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551541server # [ 17.291950] horae[1273]: 2026-11-07T00:25:59.731614Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551542server # [ 17.293613] horae[1273]: 2026-11-07T00:25:59.731627Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1543server # [ 17.295246] horae[1273]: 2026-11-07T00:25:59.731640Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1544server # [ 17.299289] horae[1273]: 2026-11-07T00:25:59.731654Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1545server # [ 17.301044] horae[1273]: 2026-11-07T00:25:59.731668Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1546server # [ 17.302668] horae[1273]: 2026-11-07T00:25:59.731681Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry1547server # [ 17.304279] horae[1273]: 2026-11-07T00:25:59.731693Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history1548server # [ 17.305914] horae[1273]: 2026-11-07T00:25:59.731707Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client1549server # [ 17.307594] horae[1273]: 2026-11-07T00:25:59.751260Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993931550server # [ 17.312515] horae[1273]: 2026-11-07T00:25:59.751274Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status1551server # [ 17.314189] horae[1273]: 2026-11-07T00:25:59.751288Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/remove1552server # [ 17.315890] horae[1273]: 2026-11-07T00:25:59.751302Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993931553server # [ 17.317638] horae[1273]: 2026-11-07T00:25:59.751315Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/save1554server # [ 17.319303] horae[1273]: 2026-11-07T00:25:59.751332Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993931555server # [ 17.321110] horae[1273]: 2026-11-07T00:25:59.751346Z INFO dioxus_server::server: Registering: POST /api/load_task_catalog122563778109868174721556server # [ 17.322997] horae[1273]: 2026-11-07T00:25:59.751359Z INFO dioxus_server::server: Registering: POST /api/get_project_detail_view79311481232659985111557server # [ 17.325577] horae[1273]: 2026-11-07T00:25:59.751372Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634961558server # [ 17.327330] horae[1273]: 2026-11-07T00:25:59.751384Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634961559server # [ 17.329071] horae[1273]: 2026-11-07T00:25:59.751397Z INFO dioxus_server::server: Registering: POST /api/list_people137396755887194634961560server # [ 17.330743] horae[1273]: 2026-11-07T00:25:59.751413Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634961561server # [ 17.332422] horae[1273]: 2026-11-07T00:25:59.751426Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634961562server # [ 17.335286] horae[1273]: 2026-11-07T00:25:59.751440Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732061563server # [ 17.337166] horae[1273]: 2026-11-07T00:25:59.751458Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732061564server # [ 17.339400] horae[1273]: 2026-11-07T00:25:59.751472Z INFO dioxus_server::server: Registering: POST /api/get_project_overview139632003752584732061565server # [ 17.341200] horae[1273]: 2026-11-07T00:25:59.751492Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732061566server # [ 17.342974] horae[1273]: 2026-11-07T00:25:59.751544Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732061567server # [ 17.344735] horae[1273]: 2026-11-07T00:25:59.751559Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732061568server # [ 17.347103] horae[1273]: 2026-11-07T00:25:59.751573Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732061569server # [ 17.348857] horae[1273]: 2026-11-07T00:25:59.751586Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732061570server # [ 17.350571] horae[1273]: 2026-11-07T00:25:59.751599Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732061571server # [ 17.352327] horae[1273]: 2026-11-07T00:25:59.751611Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732061572server # [ 17.354091] horae[1273]: 2026-11-07T00:25:59.751624Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732061573server # [ 17.355809] horae[1273]: 2026-11-07T00:25:59.751637Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732061574server # [ 17.357494] horae[1273]: 2026-11-07T00:25:59.751649Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732061575server # [ 17.360173] horae[1273]: 2026-11-07T00:25:59.751662Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732061576server # [ 17.361892] horae[1273]: 2026-11-07T00:25:59.751675Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732061577server # [ 17.363544] horae[1273]: 2026-11-07T00:25:59.751688Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486541578server # [ 17.365324] horae[1273]: 2026-11-07T00:25:59.751701Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486541579server # [ 17.367115] horae[1273]: 2026-11-07T00:25:59.751739Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486541580server # [ 17.368872] horae[1273]: 2026-11-07T00:25:59.751752Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486541581server # [ 17.373080] horae[1273]: 2026-11-07T00:25:59.751764Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486541582server # [ 17.374817] horae[1273]: 2026-11-07T00:25:59.751780Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218001583server # [ 17.376500] horae[1273]: 2026-11-07T00:25:59.751796Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915711584server # [ 17.378310] horae[1273]: 2026-11-07T00:25:59.751809Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915711585server # [ 17.380123] horae[1273]: 2026-11-07T00:25:59.751821Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915711586server # [ 17.381898] horae[1273]: 2026-11-07T00:25:59.751834Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915711587server # [ 17.383737] horae[1273]: 2026-11-07T00:25:59.751847Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915711588server # [ 17.385503] horae[1273]: 2026-11-07T00:25:59.751859Z INFO dioxus_server::server: Registering: POST /api/client_detail_view104851358257774915711589server # [ 17.387265] horae[1273]: 2026-11-07T00:25:59.751872Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915711590server # [ 17.388998] horae[1273]: 2026-11-07T00:25:59.751885Z INFO dioxus_server::server: Registering: POST /api/client_overview104851358257774915711591server # [ 17.390702] horae[1273]: 2026-11-07T00:25:59.751897Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915711592server # [ 17.392357] horae[1273]: 2026-11-07T00:25:59.751911Z INFO dioxus_server::server: Registering: POST /api/save_project_managers114747469634461219341593server # [ 17.394141] horae[1273]: 2026-11-07T00:25:59.751925Z INFO dioxus_server::server: Registering: POST /api/load_project_managers114747469634461219341594server # [ 17.395917] horae[1273]: 2026-11-07T00:25:59.751938Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829191595server # [ 17.397722] horae[1273]: 2026-11-07T00:25:59.751951Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829191596server # [ 17.399480] horae[1273]: 2026-11-07T00:25:59.751964Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829191597server # [ 17.401287] horae[1273]: 2026-11-07T00:25:59.751976Z INFO dioxus_server::server: Registering: POST /api/project_editor_catalog16044791136740829191598server # [ 17.403088] horae[1273]: 2026-11-07T00:25:59.751990Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829191599server # [ 17.404851] horae[1273]: 2026-11-07T00:25:59.752002Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829191600server # [ 17.406596] horae[1273]: 2026-11-07T00:25:59.752015Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829191601server # [ 17.408383] horae[1273]: 2026-11-07T00:25:59.752028Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829191602server # [ 17.410142] horae[1273]: 2026-11-07T00:25:59.752044Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829191603server # [ 17.411893] horae[1273]: 2026-11-07T00:25:59.752057Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829191604server # [ 17.413619] horae[1273]: 2026-11-07T00:25:59.752069Z INFO dioxus_server::server: Registering: POST /api/project_people16044791136740829191605server # [ 17.415331] horae[1273]: 2026-11-07T00:25:59.752082Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770261606server # [ 17.417151] horae[1273]: 2026-11-07T00:25:59.752094Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770261607server # [ 17.418891] horae[1273]: 2026-11-07T00:25:59.752107Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770261608server # [ 17.420792] horae[1273]: 2026-11-07T00:25:59.752119Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770261609server # [ 17.422463] horae[1273]: 2026-11-07T00:25:59.785041Z INFO horae: Listening on 127.0.0.1:30001610server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!1611server: (finished: waiting for TCP port 3000 on localhost, in 1.04 seconds)1612server: must succeed: curl -sS -c /tmp/jar6.txt -b /tmp/jar6.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start1613server # [ 18.235331] dex[756]: time=2026-11-07T00:26:00.740Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=6fa98a93-e5e4-41f9-9989-ba8ba828355c1614server # [ 18.253577] horae[1273]: 2026-11-07T00:26:00.758766Z WARN call: horae::auth::oidc: OIDC login denied: no account, and the email's domain is not listed1615server: (finished: must succeed: curl -sS -c /tmp/jar6.txt -b /tmp/jar6.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start, in 0.05 seconds)1616server: must succeed: curl -sS -c /tmp/jar6.txt -b /tmp/jar6.txt http://127.0.0.1:3000/auth/login1617server: (finished: must succeed: curl -sS -c /tmp/jar6.txt -b /tmp/jar6.txt http://127.0.0.1:3000/auth/login, in 0.02 seconds)1618server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0'1619server # [ 18.296707] sudo[1300]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to(\'kilgore@kilgore.trout\', \'UTF8\') in data) > 0'1620server # [ 18.317217] sudo[1300]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1621server # [ 18.330235] sudo[1300]: pam_unix(sudo:session): session closed for user horae1622server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0', in 0.05 seconds)1623server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"''1624server # [ 18.346784] sudo[1307]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = \'kilgore@kilgore.trout\''1625server # [ 18.366440] sudo[1307]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1626server # [ 18.381159] sudo[1307]: pam_unix(sudo:session): session closed for user horae1627server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1628server: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae Environment=HORAE_OIDC_CLIENT_SECRET=horae-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=Kilgore.Trout > /run/systemd/system/horae.service.d/omit-email-verified.conf1629server: (finished: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae Environment=HORAE_OIDC_CLIENT_SECRET=horae-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=Kilgore.Trout > /run/systemd/system/horae.service.d/omit-email-verified.conf, in 0.01 seconds)1630server: must succeed: systemctl daemon-reload && systemctl restart horae.service1631server # [ 18.404126] systemd[1]: Reload requested from client PID 1321 ('systemctl') (unit backdoor.service)...1632server # [ 18.407145] systemd[1]: Reloading...1633server # [ 18.601599] systemd[1]: Reloading finished in 195 ms.1634server # [ 18.639856] systemd[1]: Stopping Horae time tracking server...1635server # [ 18.646530] systemd[1]: horae.service: Deactivated successfully.1636server # [ 18.652111] systemd[1]: Stopped Horae time tracking server.1637server # [ 18.652937] systemd[1]: horae.service: Consumed 58ms CPU time over 1.535s wall clock time, 5.5M memory peak, 8.2K incoming IP traffic, 18.1K outgoing IP traffic.1638server # [ 18.661573] systemd[1]: Starting Horae time tracking server...1639server # [ 18.692494] horae[1359]: 2026-11-07T00:26:01.197921Z INFO horae: Running migrations...1640server # [ 18.695222] horae[1359]: 2026-11-07T00:26:01.200865Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1641server # [ 18.706693] horae[1359]: 2026-11-07T00:26:01.210703Z INFO horae: Migrations complete.1642server # [ 18.708302] postgres[1364]: [1364] LOG: could not receive data from client: Connection reset by peer1643server # [ 18.714398] systemd[1]: Started Horae time tracking server.1644server: (finished: must succeed: systemctl daemon-reload && systemctl restart horae.service, in 0.33 seconds)1645server: waiting for TCP port 3000 on localhost1646server # [ 18.751384] horae[1366]: 2026-11-07T00:26:01.256497Z INFO horae: Starting Horae on 127.0.0.1:30001647server # [ 18.759520] horae[1366]: 2026-11-07T00:26:01.265171Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1648server # [ 18.769787] horae[1366]: 2026-11-07T00:26:01.275190Z INFO horae::plugin::registry: plugins directory does not exist: plugins1649server # [ 18.778548] horae[1366]: 2026-11-07T00:26:01.284091Z INFO sqlx::postgres::notice: schema "tower_sessions" already exists, skipping1650server # [ 18.780630] horae[1366]: 2026-11-07T00:26:01.286274Z INFO sqlx::postgres::notice: relation "session" already exists, skipping1651server # [ 18.784064] horae[1366]: 2026-11-07T00:26:01.289067Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181652server # [ 18.785878] horae[1366]: 2026-11-07T00:26:01.289092Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181653server # [ 18.787640] horae[1366]: 2026-11-07T00:26:01.289107Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181654server # [ 18.789401] horae[1366]: 2026-11-07T00:26:01.289123Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181655server # [ 18.791156] horae[1366]: 2026-11-07T00:26:01.289139Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181656server # [ 18.792856] horae[1366]: 2026-11-07T00:26:01.289153Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181657server # [ 18.794541] horae[1366]: 2026-11-07T00:26:01.289166Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181658server # [ 18.799135] horae[1366]: 2026-11-07T00:26:01.289180Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181659server # [ 18.800842] horae[1366]: 2026-11-07T00:26:01.289199Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_entries76905535698631861851660server # [ 18.802684] horae[1366]: 2026-11-07T00:26:01.289213Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_groups76905535698631861851661server # [ 18.804511] horae[1366]: 2026-11-07T00:26:01.289226Z INFO dioxus_server::server: Registering: POST /api/get_time_report_access76905535698631861851662server # [ 18.806323] horae[1366]: 2026-11-07T00:26:01.289239Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851663server # [ 18.808106] horae[1366]: 2026-11-07T00:26:01.289259Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851664server # [ 18.809865] horae[1366]: 2026-11-07T00:26:01.289272Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851665server # [ 18.811524] horae[1366]: 2026-11-07T00:26:01.289286Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071666server # [ 18.813201] horae[1366]: 2026-11-07T00:26:01.289307Z INFO dioxus_server::server: Registering: POST /api/list_permission_audit161443592707961243071667server # [ 18.814994] horae[1366]: 2026-11-07T00:26:01.289320Z INFO dioxus_server::server: Registering: POST /api/get_permission_audit161443592707961243071668server # [ 18.816803] horae[1366]: 2026-11-07T00:26:01.289333Z INFO dioxus_server::server: Registering: POST /api/get_my_permissions161443592707961243071669server # [ 18.818528] horae[1366]: 2026-11-07T00:26:01.289346Z INFO dioxus_server::server: Registering: POST /api/preview_permission_template_deletion115797103093460273031670server # [ 18.820448] horae[1366]: 2026-11-07T00:26:01.289360Z INFO dioxus_server::server: Registering: POST /api/preview_person_permissions115797103093460273031671server # [ 18.823918] horae[1366]: 2026-11-07T00:26:01.289373Z INFO dioxus_server::server: Registering: POST /api/save_permission_template115797103093460273031672server # [ 18.825723] horae[1366]: 2026-11-07T00:26:01.289386Z INFO dioxus_server::server: Registering: POST /api/list_permission_subjects115797103093460273031673server # [ 18.827721] horae[1366]: 2026-11-07T00:26:01.289399Z INFO dioxus_server::server: Registering: POST /api/save_person_permissions115797103093460273031674server # [ 18.829490] horae[1366]: 2026-11-07T00:26:01.289412Z INFO dioxus_server::server: Registering: POST /api/load_permission_editor115797103093460273031675server # [ 18.831286] horae[1366]: 2026-11-07T00:26:01.289427Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_entries163860635615838339551676server # [ 18.833128] horae[1366]: 2026-11-07T00:26:01.289441Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551677server # [ 18.837923] horae[1366]: 2026-11-07T00:26:01.289453Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_tracking163860635615838339551678server # [ 18.839751] horae[1366]: 2026-11-07T00:26:01.289465Z INFO dioxus_server::server: Registering: POST /api/apply_timesheet_command163860635615838339551679server # [ 18.841522] horae[1366]: 2026-11-07T00:26:01.289482Z INFO dioxus_server::server: Registering: POST /api/list_timesheet_people163860635615838339551680server # [ 18.843310] horae[1366]: 2026-11-07T00:26:01.289510Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_page163860635615838339551681server # [ 18.845098] horae[1366]: 2026-11-07T00:26:01.289524Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551682server # [ 18.846839] horae[1366]: 2026-11-07T00:26:01.289537Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551683server # [ 18.852028] horae[1366]: 2026-11-07T00:26:01.289550Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551684server # [ 18.853749] horae[1366]: 2026-11-07T00:26:01.289563Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1685server # [ 18.855388] horae[1366]: 2026-11-07T00:26:01.289576Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1686server # [ 18.857087] horae[1366]: 2026-11-07T00:26:01.289589Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1687server # [ 18.858728] horae[1366]: 2026-11-07T00:26:01.289603Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1688server # [ 18.860293] horae[1366]: 2026-11-07T00:26:01.289616Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry1689server # [ 18.865085] horae[1366]: 2026-11-07T00:26:01.289628Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history1690server # [ 18.866731] horae[1366]: 2026-11-07T00:26:01.289641Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client1691server # [ 18.869100] horae[1366]: 2026-11-07T00:26:01.289653Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993931692server # [ 18.870939] horae[1366]: 2026-11-07T00:26:01.289666Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status1693server # [ 18.872557] horae[1366]: 2026-11-07T00:26:01.289679Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/remove1694server # [ 18.875029] horae[1366]: 2026-11-07T00:26:01.289692Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993931695server # [ 18.878287] horae[1366]: 2026-11-07T00:26:01.289705Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/save1696server # [ 18.879993] horae[1366]: 2026-11-07T00:26:01.302459Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993931697server # [ 18.881745] horae[1366]: 2026-11-07T00:26:01.302473Z INFO dioxus_server::server: Registering: POST /api/load_task_catalog122563778109868174721698server # [ 18.883479] horae[1366]: 2026-11-07T00:26:01.302486Z INFO dioxus_server::server: Registering: POST /api/get_project_detail_view79311481232659985111699server # [ 18.885289] horae[1366]: 2026-11-07T00:26:01.302499Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634961700server # [ 18.887181] horae[1366]: 2026-11-07T00:26:01.302512Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634961701server # [ 18.888914] horae[1366]: 2026-11-07T00:26:01.302525Z INFO dioxus_server::server: Registering: POST /api/list_people137396755887194634961702server # [ 18.890602] horae[1366]: 2026-11-07T00:26:01.302540Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634961703server # [ 18.892314] horae[1366]: 2026-11-07T00:26:01.302552Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634961704server # [ 18.894082] horae[1366]: 2026-11-07T00:26:01.302565Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732061705server # [ 18.895931] horae[1366]: 2026-11-07T00:26:01.302579Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732061706server # [ 18.898815] horae[1366]: 2026-11-07T00:26:01.302592Z INFO dioxus_server::server: Registering: POST /api/get_project_overview139632003752584732061707server # [ 18.900959] horae[1366]: 2026-11-07T00:26:01.302612Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732061708server # [ 18.903075] horae[1366]: 2026-11-07T00:26:01.302662Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732061709server # [ 18.904842] horae[1366]: 2026-11-07T00:26:01.302677Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732061710server # [ 18.906566] horae[1366]: 2026-11-07T00:26:01.302690Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732061711server # [ 18.909636] horae[1366]: 2026-11-07T00:26:01.302707Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732061712server # [ 18.911380] horae[1366]: 2026-11-07T00:26:01.302744Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732061713server # [ 18.913153] horae[1366]: 2026-11-07T00:26:01.302756Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732061714server # [ 18.914869] horae[1366]: 2026-11-07T00:26:01.302768Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732061715server # [ 18.916548] horae[1366]: 2026-11-07T00:26:01.302781Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732061716server # [ 18.918244] horae[1366]: 2026-11-07T00:26:01.302794Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732061717server # [ 18.919980] horae[1366]: 2026-11-07T00:26:01.302807Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732061718server # [ 18.921657] horae[1366]: 2026-11-07T00:26:01.302818Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732061719server # [ 18.923421] horae[1366]: 2026-11-07T00:26:01.302831Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486541720server # [ 18.925168] horae[1366]: 2026-11-07T00:26:01.302845Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486541721server # [ 18.926904] horae[1366]: 2026-11-07T00:26:01.302858Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486541722server # [ 18.928630] horae[1366]: 2026-11-07T00:26:01.302870Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486541723server # [ 18.930336] horae[1366]: 2026-11-07T00:26:01.302882Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486541724server # [ 18.932180] horae[1366]: 2026-11-07T00:26:01.302894Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218001725server # [ 18.933882] horae[1366]: 2026-11-07T00:26:01.302906Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915711726server # [ 18.935635] horae[1366]: 2026-11-07T00:26:01.302919Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915711727server # [ 18.937405] horae[1366]: 2026-11-07T00:26:01.302932Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915711728server # [ 18.939183] horae[1366]: 2026-11-07T00:26:01.302945Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915711729server # [ 18.940966] horae[1366]: 2026-11-07T00:26:01.302957Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915711730server # [ 18.942842] horae[1366]: 2026-11-07T00:26:01.302970Z INFO dioxus_server::server: Registering: POST /api/client_detail_view104851358257774915711731server # [ 18.944728] horae[1366]: 2026-11-07T00:26:01.302982Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915711732server # [ 18.946563] horae[1366]: 2026-11-07T00:26:01.302995Z INFO dioxus_server::server: Registering: POST /api/client_overview104851358257774915711733server # [ 18.948386] horae[1366]: 2026-11-07T00:26:01.303008Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915711734server # [ 18.950215] horae[1366]: 2026-11-07T00:26:01.303021Z INFO dioxus_server::server: Registering: POST /api/save_project_managers114747469634461219341735server # [ 18.952145] horae[1366]: 2026-11-07T00:26:01.303035Z INFO dioxus_server::server: Registering: POST /api/load_project_managers114747469634461219341736server # [ 18.954064] horae[1366]: 2026-11-07T00:26:01.303047Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829191737server # [ 18.956043] horae[1366]: 2026-11-07T00:26:01.303061Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829191738server # [ 18.957968] horae[1366]: 2026-11-07T00:26:01.303073Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829191739server # [ 18.959892] horae[1366]: 2026-11-07T00:26:01.303085Z INFO dioxus_server::server: Registering: POST /api/project_editor_catalog16044791136740829191740server # [ 18.961818] horae[1366]: 2026-11-07T00:26:01.303098Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829191741server # [ 18.963688] horae[1366]: 2026-11-07T00:26:01.303109Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829191742server # [ 18.965633] horae[1366]: 2026-11-07T00:26:01.303122Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829191743server # [ 18.967558] horae[1366]: 2026-11-07T00:26:01.303135Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829191744server # [ 18.969413] horae[1366]: 2026-11-07T00:26:01.303148Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829191745server # [ 18.971304] horae[1366]: 2026-11-07T00:26:01.303160Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829191746server # [ 18.973192] horae[1366]: 2026-11-07T00:26:01.303173Z INFO dioxus_server::server: Registering: POST /api/project_people16044791136740829191747server # [ 18.975061] horae[1366]: 2026-11-07T00:26:01.303186Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770261748server # [ 18.977024] horae[1366]: 2026-11-07T00:26:01.303198Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770261749server # [ 18.978894] horae[1366]: 2026-11-07T00:26:01.303210Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770261750server # [ 18.980717] horae[1366]: 2026-11-07T00:26:01.303222Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770261751server # [ 18.982546] horae[1366]: 2026-11-07T00:26:01.354171Z INFO horae: Listening on 127.0.0.1:30001752server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!1753server: (finished: waiting for TCP port 3000 on localhost, in 1.04 seconds)1754server: must succeed: curl -sS -c /tmp/jar7.txt -b /tmp/jar7.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null1755server # [ 19.785874] dex[756]: time=2026-11-07T00:26:02.291Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=b5be519f-2871-4031-ba16-b7a0f8da217f1756server # [ 19.810438] horae[1366]: 2026-11-07T00:26:02.315702Z INFO call: horae::auth::oidc: OIDC sign-in created an account user_id=01a1b365-e6c8-71b3-8232-ca77c142c64d1757server: (finished: must succeed: curl -sS -c /tmp/jar7.txt -b /tmp/jar7.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null, in 0.10 seconds)1758server: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar7.txt http://127.0.0.1:3000/harvest/v2/time_entries1759server: (finished: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar7.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.05 seconds)1760server: must succeed: sudo -u horae psql horae -tAc 'SELECT org_role, active, cost_rate_cents IS NULL AND billable_rate_cents IS NULL, oidc_issuer, oidc_subject FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"''1761server # [ 19.932748] sudo[1391]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT org_role, active, cost_rate_cents IS NULL AND billable_rate_cents IS NULL, oidc_issuer, oidc_subject FROM users WHERE email = \'kilgore@kilgore.trout\''1762server # [ 19.956374] sudo[1391]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1763server # [ 19.972742] sudo[1391]: pam_unix(sudo:session): session closed for user horae1764server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT org_role, active, cost_rate_cents IS NULL AND billable_rate_cents IS NULL, oidc_issuer, oidc_subject FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.06 seconds)1765server: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET active = false WHERE email = '"'"'kilgore@kilgore.trout'"'"''1766server # [ 19.988918] sudo[1398]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'UPDATE users SET active = false WHERE email = \'kilgore@kilgore.trout\''1767server # [ 20.008453] sudo[1398]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1768server # [ 20.023697] sudo[1398]: pam_unix(sudo:session): session closed for user horae1769server: (finished: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET active = false WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1770server: must succeed: curl -sS -c /tmp/jar8.txt -b /tmp/jar8.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start1771server # [ 20.047312] dex[756]: time=2026-11-07T00:26:02.552Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=f0661184-c3ec-4330-99a5-595e5aac93b21772server # [ 20.061255] horae[1366]: 2026-11-07T00:26:02.566841Z WARN call: horae::auth::oidc: OIDC login denied: account is deactivated1773server: (finished: must succeed: curl -sS -c /tmp/jar8.txt -b /tmp/jar8.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start, in 0.04 seconds)1774server: must succeed: curl -sS -c /tmp/jar8.txt -b /tmp/jar8.txt http://127.0.0.1:3000/auth/login1775server: (finished: must succeed: curl -sS -c /tmp/jar8.txt -b /tmp/jar8.txt http://127.0.0.1:3000/auth/login, in 0.02 seconds)1776server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0'1777server # [ 20.101429] sudo[1411]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to(\'kilgore@kilgore.trout\', \'UTF8\') in data) > 0'1778server # [ 20.121208] sudo[1411]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1779server # [ 20.132952] sudo[1411]: pam_unix(sudo:session): session closed for user horae1780server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0', in 0.05 seconds)1781server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"''1782server # [ 20.149327] sudo[1418]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = \'kilgore@kilgore.trout\''1783server # [ 20.168747] sudo[1418]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1784server # [ 20.183265] sudo[1418]: pam_unix(sudo:session): session closed for user horae1785server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1786server: must succeed: sudo -u horae psql horae -tAc 'SELECT active FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"''1787server # [ 20.199366] sudo[1425]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT active FROM users WHERE email = \'kilgore@kilgore.trout\''1788server # [ 20.218415] sudo[1425]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1789server # [ 20.231149] sudo[1425]: pam_unix(sudo:session): session closed for user horae1790server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT active FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1791server: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET email = '"'"'kilgore-provisioned@kilgore.trout'"'"', oidc_issuer = NULL, oidc_subject = NULL WHERE email = '"'"'kilgore@kilgore.trout'"'"''1792server # [ 20.247356] sudo[1432]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'UPDATE users SET email = \'kilgore-provisioned@kilgore.trout\', oidc_issuer = NULL, oidc_subject = NULL WHERE email = \'kilgore@kilgore.trout\''1793server # [ 20.267436] sudo[1432]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1794server # [ 20.282083] sudo[1432]: pam_unix(sudo:session): session closed for user horae1795server: (finished: must succeed: sudo -u horae psql horae -tAc 'UPDATE users SET email = '"'"'kilgore-provisioned@kilgore.trout'"'"', oidc_issuer = NULL, oidc_subject = NULL WHERE email = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1796server: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae-omit-email-verified Environment=HORAE_OIDC_CLIENT_SECRET=horae-omit-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=kilgore.trout > /run/systemd/system/horae.service.d/omit-email-verified.conf1797server: (finished: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae-omit-email-verified Environment=HORAE_OIDC_CLIENT_SECRET=horae-omit-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=kilgore.trout > /run/systemd/system/horae.service.d/omit-email-verified.conf, in 0.01 seconds)1798server: must succeed: systemctl daemon-reload && systemctl restart horae.service1799server # [ 20.306241] systemd[1]: Reload requested from client PID 1446 ('systemctl') (unit backdoor.service)...1800server # [ 20.307582] systemd[1]: Reloading...1801server # [ 20.502664] systemd[1]: Reloading finished in 194 ms.1802server # [ 20.540238] systemd[1]: Stopping Horae time tracking server...1803server # [ 20.552207] systemd[1]: horae.service: Deactivated successfully.1804server # [ 20.554117] systemd[1]: Stopped Horae time tracking server.1805server # [ 20.554993] systemd[1]: horae.service: Consumed 72ms CPU time over 1.886s wall clock time, 5.8M memory peak, 16.4K incoming IP traffic, 31K outgoing IP traffic.1806server # [ 20.563881] systemd[1]: Starting Horae time tracking server...1807server # [ 20.594953] horae[1484]: 2026-11-07T00:26:03.100324Z INFO horae: Running migrations...1808server # [ 20.597775] horae[1484]: 2026-11-07T00:26:03.103433Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1809server # [ 20.611570] horae[1484]: 2026-11-07T00:26:03.113555Z INFO horae: Migrations complete.1810server # [ 20.614746] systemd[1]: Started Horae time tracking server.1811server: (finished: must succeed: systemctl daemon-reload && systemctl restart horae.service, in 0.33 seconds)1812server: waiting for TCP port 3000 on localhost1813server # [ 20.652964] horae[1491]: 2026-11-07T00:26:03.158380Z INFO horae: Starting Horae on 127.0.0.1:30001814server # [ 20.661306] horae[1491]: 2026-11-07T00:26:03.166957Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1815server # [ 20.671426] horae[1491]: 2026-11-07T00:26:03.177073Z INFO horae::plugin::registry: plugins directory does not exist: plugins1816server # [ 20.673849] horae[1491]: 2026-11-07T00:26:03.179435Z INFO sqlx::postgres::notice: schema "tower_sessions" already exists, skipping1817server # [ 20.678064] horae[1491]: 2026-11-07T00:26:03.182433Z INFO sqlx::postgres::notice: relation "session" already exists, skipping1818server # [ 20.679716] horae[1491]: 2026-11-07T00:26:03.182681Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181819server # [ 20.681466] horae[1491]: 2026-11-07T00:26:03.182701Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181820server # [ 20.683237] horae[1491]: 2026-11-07T00:26:03.182743Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181821server # [ 20.684986] horae[1491]: 2026-11-07T00:26:03.182757Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181822server # [ 20.686717] horae[1491]: 2026-11-07T00:26:03.182774Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181823server # [ 20.688411] horae[1491]: 2026-11-07T00:26:03.182788Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181824server # [ 20.691115] horae[1491]: 2026-11-07T00:26:03.182801Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181825server # [ 20.692827] horae[1491]: 2026-11-07T00:26:03.182814Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181826server # [ 20.694457] horae[1491]: 2026-11-07T00:26:03.182834Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_entries76905535698631861851827server # [ 20.696301] horae[1491]: 2026-11-07T00:26:03.182848Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_groups76905535698631861851828server # [ 20.698156] horae[1491]: 2026-11-07T00:26:03.182861Z INFO dioxus_server::server: Registering: POST /api/get_time_report_access76905535698631861851829server # [ 20.699933] horae[1491]: 2026-11-07T00:26:03.182875Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851830server # [ 20.703776] horae[1491]: 2026-11-07T00:26:03.182888Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851831server # [ 20.705495] horae[1491]: 2026-11-07T00:26:03.182902Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851832server # [ 20.707198] horae[1491]: 2026-11-07T00:26:03.182916Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071833server # [ 20.708865] horae[1491]: 2026-11-07T00:26:03.182936Z INFO dioxus_server::server: Registering: POST /api/list_permission_audit161443592707961243071834server # [ 20.710608] horae[1491]: 2026-11-07T00:26:03.182949Z INFO dioxus_server::server: Registering: POST /api/get_permission_audit161443592707961243071835server # [ 20.712398] horae[1491]: 2026-11-07T00:26:03.182962Z INFO dioxus_server::server: Registering: POST /api/get_my_permissions161443592707961243071836server # [ 20.714204] horae[1491]: 2026-11-07T00:26:03.182975Z INFO dioxus_server::server: Registering: POST /api/preview_permission_template_deletion115797103093460273031837server # [ 20.716144] horae[1491]: 2026-11-07T00:26:03.182989Z INFO dioxus_server::server: Registering: POST /api/preview_person_permissions115797103093460273031838server # [ 20.718131] horae[1491]: 2026-11-07T00:26:03.183003Z INFO dioxus_server::server: Registering: POST /api/save_permission_template115797103093460273031839server # [ 20.719960] horae[1491]: 2026-11-07T00:26:03.183016Z INFO dioxus_server::server: Registering: POST /api/list_permission_subjects115797103093460273031840server # [ 20.721725] horae[1491]: 2026-11-07T00:26:03.183029Z INFO dioxus_server::server: Registering: POST /api/save_person_permissions115797103093460273031841server # [ 20.725515] horae[1491]: 2026-11-07T00:26:03.183044Z INFO dioxus_server::server: Registering: POST /api/load_permission_editor115797103093460273031842server # [ 20.727360] horae[1491]: 2026-11-07T00:26:03.183057Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_entries163860635615838339551843server # [ 20.729249] horae[1491]: 2026-11-07T00:26:03.183071Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551844server # [ 20.731083] horae[1491]: 2026-11-07T00:26:03.183084Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_tracking163860635615838339551845server # [ 20.732929] horae[1491]: 2026-11-07T00:26:03.183097Z INFO dioxus_server::server: Registering: POST /api/apply_timesheet_command163860635615838339551846server # [ 20.734688] horae[1491]: 2026-11-07T00:26:03.183113Z INFO dioxus_server::server: Registering: POST /api/list_timesheet_people163860635615838339551847server # [ 20.736464] horae[1491]: 2026-11-07T00:26:03.183142Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_page163860635615838339551848server # [ 20.740811] horae[1491]: 2026-11-07T00:26:03.183156Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551849server # [ 20.742634] horae[1491]: 2026-11-07T00:26:03.183171Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551850server # [ 20.744402] horae[1491]: 2026-11-07T00:26:03.183184Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551851server # [ 20.746120] horae[1491]: 2026-11-07T00:26:03.183197Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1852server # [ 20.747729] horae[1491]: 2026-11-07T00:26:03.183210Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1853server # [ 20.749320] horae[1491]: 2026-11-07T00:26:03.183223Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1854server # [ 20.753107] horae[1491]: 2026-11-07T00:26:03.183238Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1855server # [ 20.754731] horae[1491]: 2026-11-07T00:26:03.183258Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry1856server # [ 20.756389] horae[1491]: 2026-11-07T00:26:03.183270Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history1857server # [ 20.758132] horae[1491]: 2026-11-07T00:26:03.183283Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client1858server # [ 20.759725] horae[1491]: 2026-11-07T00:26:03.183295Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993931859server # [ 20.761474] horae[1491]: 2026-11-07T00:26:03.183309Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status1860server # [ 20.765232] horae[1491]: 2026-11-07T00:26:03.183322Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/remove1861server # [ 20.766943] horae[1491]: 2026-11-07T00:26:03.183335Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993931862server # [ 20.768699] horae[1491]: 2026-11-07T00:26:03.183351Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/save1863server # [ 20.770322] horae[1491]: 2026-11-07T00:26:03.183364Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993931864server # [ 20.772142] horae[1491]: 2026-11-07T00:26:03.183378Z INFO dioxus_server::server: Registering: POST /api/load_task_catalog122563778109868174721865server # [ 20.773899] horae[1491]: 2026-11-07T00:26:03.183391Z INFO dioxus_server::server: Registering: POST /api/get_project_detail_view79311481232659985111866server # [ 20.777384] horae[1491]: 2026-11-07T00:26:03.183404Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634961867server # [ 20.779155] horae[1491]: 2026-11-07T00:26:03.183416Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634961868server # [ 20.780888] horae[1491]: 2026-11-07T00:26:03.183431Z INFO dioxus_server::server: Registering: POST /api/list_people137396755887194634961869server # [ 20.782586] horae[1491]: 2026-11-07T00:26:03.183444Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634961870server # [ 20.784288] horae[1491]: 2026-11-07T00:26:03.183456Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634961871server # [ 20.789170] horae[1491]: 2026-11-07T00:26:03.183469Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732061872server # [ 20.791114] horae[1491]: 2026-11-07T00:26:03.183483Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732061873server # [ 20.792952] horae[1491]: 2026-11-07T00:26:03.183497Z INFO dioxus_server::server: Registering: POST /api/get_project_overview139632003752584732061874server # [ 20.794700] horae[1491]: 2026-11-07T00:26:03.183517Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732061875server # [ 20.796454] horae[1491]: 2026-11-07T00:26:03.183556Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732061876server # [ 20.798249] horae[1491]: 2026-11-07T00:26:03.183570Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732061877server # [ 20.800189] horae[1491]: 2026-11-07T00:26:03.183584Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732061878server # [ 20.801992] horae[1491]: 2026-11-07T00:26:03.183597Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732061879server # [ 20.803840] horae[1491]: 2026-11-07T00:26:03.183610Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732061880server # [ 20.805602] horae[1491]: 2026-11-07T00:26:03.183621Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732061881server # [ 20.807327] horae[1491]: 2026-11-07T00:26:03.183638Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732061882server # [ 20.809058] horae[1491]: 2026-11-07T00:26:03.183650Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732061883server # [ 20.810725] horae[1491]: 2026-11-07T00:26:03.183663Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732061884server # [ 20.812413] horae[1491]: 2026-11-07T00:26:03.183675Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732061885server # [ 20.817084] horae[1491]: 2026-11-07T00:26:03.183688Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732061886server # [ 20.818810] horae[1491]: 2026-11-07T00:26:03.183703Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486541887server # [ 20.820547] horae[1491]: 2026-11-07T00:26:03.207441Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486541888server # [ 20.822312] horae[1491]: 2026-11-07T00:26:03.207458Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486541889server # [ 20.824097] horae[1491]: 2026-11-07T00:26:03.207472Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486541890server # [ 20.825814] horae[1491]: 2026-11-07T00:26:03.207485Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486541891server # [ 20.829493] horae[1491]: 2026-11-07T00:26:03.207499Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218001892server # [ 20.831237] horae[1491]: 2026-11-07T00:26:03.207511Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915711893server # [ 20.833056] horae[1491]: 2026-11-07T00:26:03.207524Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915711894server # [ 20.834794] horae[1491]: 2026-11-07T00:26:03.207537Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915711895server # [ 20.836528] horae[1491]: 2026-11-07T00:26:03.207550Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915711896server # [ 20.838390] horae[1491]: 2026-11-07T00:26:03.207563Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915711897server # [ 20.841098] horae[1491]: 2026-11-07T00:26:03.207576Z INFO dioxus_server::server: Registering: POST /api/client_detail_view104851358257774915711898server # [ 20.842849] horae[1491]: 2026-11-07T00:26:03.207590Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915711899server # [ 20.844550] horae[1491]: 2026-11-07T00:26:03.207603Z INFO dioxus_server::server: Registering: POST /api/client_overview104851358257774915711900server # [ 20.846232] horae[1491]: 2026-11-07T00:26:03.207616Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915711901server # [ 20.847916] horae[1491]: 2026-11-07T00:26:03.207629Z INFO dioxus_server::server: Registering: POST /api/save_project_managers114747469634461219341902server # [ 20.849660] horae[1491]: 2026-11-07T00:26:03.207647Z INFO dioxus_server::server: Registering: POST /api/load_project_managers114747469634461219341903server # [ 20.851501] horae[1491]: 2026-11-07T00:26:03.207660Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829191904server # [ 20.853584] horae[1491]: 2026-11-07T00:26:03.207673Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829191905server # [ 20.855465] horae[1491]: 2026-11-07T00:26:03.207687Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829191906server # [ 20.857339] horae[1491]: 2026-11-07T00:26:03.207699Z INFO dioxus_server::server: Registering: POST /api/project_editor_catalog16044791136740829191907server # [ 20.859263] horae[1491]: 2026-11-07T00:26:03.207736Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829191908server # [ 20.861130] horae[1491]: 2026-11-07T00:26:03.207750Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829191909server # [ 20.862993] horae[1491]: 2026-11-07T00:26:03.207763Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829191910server # [ 20.864920] horae[1491]: 2026-11-07T00:26:03.207777Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829191911server # [ 20.866738] horae[1491]: 2026-11-07T00:26:03.207790Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829191912server # [ 20.868504] horae[1491]: 2026-11-07T00:26:03.207802Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829191913server # [ 20.870278] horae[1491]: 2026-11-07T00:26:03.207815Z INFO dioxus_server::server: Registering: POST /api/project_people16044791136740829191914server # [ 20.871964] horae[1491]: 2026-11-07T00:26:03.207828Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770261915server # [ 20.873705] horae[1491]: 2026-11-07T00:26:03.207841Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770261916server # [ 20.875407] horae[1491]: 2026-11-07T00:26:03.207853Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770261917server # [ 20.877150] horae[1491]: 2026-11-07T00:26:03.207865Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770261918server # [ 20.878852] horae[1491]: 2026-11-07T00:26:03.248262Z INFO horae: Listening on 127.0.0.1:30001919server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!1920server: (finished: waiting for TCP port 3000 on localhost, in 1.04 seconds)1921server: must succeed: curl -sS -c /tmp/jar9.txt -b /tmp/jar9.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start1922server # [ 21.694530] dex[756]: time=2026-11-07T00:26:04.199Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=94092eed-e646-4591-b04c-f629931d42ca1923server # [ 21.712884] horae[1491]: 2026-11-07T00:26:04.217919Z WARN call: horae::auth::oidc: OIDC login denied: no account, and the email is not verified1924server: (finished: must succeed: curl -sS -c /tmp/jar9.txt -b /tmp/jar9.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start, in 0.06 seconds)1925server: must succeed: curl -sS -c /tmp/jar9.txt -b /tmp/jar9.txt http://127.0.0.1:3000/auth/login1926server: (finished: must succeed: curl -sS -c /tmp/jar9.txt -b /tmp/jar9.txt http://127.0.0.1:3000/auth/login, in 0.02 seconds)1927server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0'1928server # [ 21.757366] sudo[1516]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to(\'kilgore@kilgore.trout\', \'UTF8\') in data) > 0'1929server # [ 21.778299] sudo[1516]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1930server # [ 21.791060] sudo[1516]: pam_unix(sudo:session): session closed for user horae1931server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM tower_sessions.session WHERE position(convert_to('"'"'kilgore@kilgore.trout'"'"', '"'"'UTF8'"'"') in data) > 0', in 0.05 seconds)1932server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"''1933server # [ 21.809368] sudo[1523]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = \'kilgore@kilgore.trout\''1934server # [ 21.830314] sudo[1523]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)1935server # [ 21.846121] sudo[1523]: pam_unix(sudo:session): session closed for user horae1936server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE lower(email) = '"'"'kilgore@kilgore.trout'"'"'', in 0.05 seconds)1937server: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae-omit-email-verified Environment=HORAE_OIDC_CLIENT_SECRET=horae-omit-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=kilgore.trout Environment=HORAE_OIDC_TRUST_UNVERIFIED_EMAIL=1 > /run/systemd/system/horae.service.d/omit-email-verified.conf1938server: (finished: must succeed: mkdir -p /run/systemd/system/horae.service.d && printf '%s\n' '[Service]' Environment=HORAE_OIDC_CLIENT_ID=horae-omit-email-verified Environment=HORAE_OIDC_CLIENT_SECRET=horae-omit-e2e-secret Environment=HORAE_OIDC_AUTO_PROVISION_DOMAINS=kilgore.trout Environment=HORAE_OIDC_TRUST_UNVERIFIED_EMAIL=1 > /run/systemd/system/horae.service.d/omit-email-verified.conf, in 0.01 seconds)1939server: must succeed: systemctl daemon-reload && systemctl restart horae.service1940server # [ 21.869981] systemd[1]: Reload requested from client PID 1537 ('systemctl') (unit backdoor.service)...1941server # [ 21.872287] systemd[1]: Reloading...1942server # [ 22.068478] systemd[1]: Reloading finished in 196 ms.1943server # [ 22.106332] systemd[1]: Stopping Horae time tracking server...1944server # [ 22.115075] systemd[1]: horae.service: Deactivated successfully.1945server # [ 22.118112] systemd[1]: Stopped Horae time tracking server.1946server # [ 22.118944] systemd[1]: horae.service: Consumed 59ms CPU time over 1.550s wall clock time, 5.4M memory peak, 8.2K incoming IP traffic, 18.2K outgoing IP traffic.1947server # [ 22.126406] systemd[1]: Starting Horae time tracking server...1948server # [ 22.158191] horae[1575]: 2026-11-07T00:26:04.663540Z INFO horae: Running migrations...1949server # [ 22.160997] horae[1575]: 2026-11-07T00:26:04.666658Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1950server # [ 22.173098] horae[1575]: 2026-11-07T00:26:04.676881Z INFO horae: Migrations complete.1951server # [ 22.174680] postgres[1580]: [1580] LOG: could not receive data from client: Connection reset by peer1952server # [ 22.180222] systemd[1]: Started Horae time tracking server.1953server: (finished: must succeed: systemctl daemon-reload && systemctl restart horae.service, in 0.33 seconds)1954server: waiting for TCP port 3000 on localhost1955server # [ 22.219858] horae[1582]: 2026-11-07T00:26:04.725237Z INFO horae: Starting Horae on 127.0.0.1:30001956server # [ 22.228261] horae[1582]: 2026-11-07T00:26:04.733909Z INFO sqlx::postgres::notice: relation "_sqlx_migrations" already exists, skipping1957server # [ 22.239066] horae[1582]: 2026-11-07T00:26:04.744413Z INFO horae::plugin::registry: plugins directory does not exist: plugins1958server # [ 22.247625] horae[1582]: 2026-11-07T00:26:04.753250Z INFO sqlx::postgres::notice: schema "tower_sessions" already exists, skipping1959server # [ 22.250346] horae[1582]: 2026-11-07T00:26:04.755967Z INFO sqlx::postgres::notice: relation "session" already exists, skipping1960server # [ 22.252303] horae[1582]: 2026-11-07T00:26:04.757971Z INFO dioxus_server::server: Registering: POST /api/update_invoice_status20640419902238648181961server # [ 22.256086] horae[1582]: 2026-11-07T00:26:04.760846Z INFO dioxus_server::server: Registering: POST /api/review_invoice_edit20640419902238648181962server # [ 22.257880] horae[1582]: 2026-11-07T00:26:04.760868Z INFO dioxus_server::server: Registering: POST /api/save_invoice_draft20640419902238648181963server # [ 22.259713] horae[1582]: 2026-11-07T00:26:04.760883Z INFO dioxus_server::server: Registering: POST /api/get_invoice_editor20640419902238648181964server # [ 22.261428] horae[1582]: 2026-11-07T00:26:04.760901Z INFO dioxus_server::server: Registering: POST /api/generate_invoice20640419902238648181965server # [ 22.263154] horae[1582]: 2026-11-07T00:26:04.760916Z INFO dioxus_server::server: Registering: POST /api/prepare_invoice20640419902238648181966server # [ 22.264928] horae[1582]: 2026-11-07T00:26:04.760930Z INFO dioxus_server::server: Registering: POST /api/list_invoices20640419902238648181967server # [ 22.269116] horae[1582]: 2026-11-07T00:26:04.760943Z INFO dioxus_server::server: Registering: POST /api/get_invoice20640419902238648181968server # [ 22.270861] horae[1582]: 2026-11-07T00:26:04.760966Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_entries76905535698631861851969server # [ 22.272738] horae[1582]: 2026-11-07T00:26:04.760980Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_report_groups76905535698631861851970server # [ 22.274553] horae[1582]: 2026-11-07T00:26:04.760993Z INFO dioxus_server::server: Registering: POST /api/get_time_report_access76905535698631861851971server # [ 22.276302] horae[1582]: 2026-11-07T00:26:04.761007Z INFO dioxus_server::server: Registering: POST /api/get_plugin_widgets76905535698631861851972server # [ 22.278875] horae[1582]: 2026-11-07T00:26:04.761019Z INFO dioxus_server::server: Registering: POST /api/report_detailed76905535698631861851973server # [ 22.281793] horae[1582]: 2026-11-07T00:26:04.761032Z INFO dioxus_server::server: Registering: POST /api/report_time76905535698631861851974server # [ 22.283550] horae[1582]: 2026-11-07T00:26:04.761047Z INFO dioxus_server::server: Registering: POST /api/get_me161443592707961243071975server # [ 22.285210] horae[1582]: 2026-11-07T00:26:04.761068Z INFO dioxus_server::server: Registering: POST /api/list_permission_audit161443592707961243071976server # [ 22.286994] horae[1582]: 2026-11-07T00:26:04.761081Z INFO dioxus_server::server: Registering: POST /api/get_permission_audit161443592707961243071977server # [ 22.288773] horae[1582]: 2026-11-07T00:26:04.761094Z INFO dioxus_server::server: Registering: POST /api/get_my_permissions161443592707961243071978server # [ 22.290528] horae[1582]: 2026-11-07T00:26:04.761108Z INFO dioxus_server::server: Registering: POST /api/preview_permission_template_deletion115797103093460273031979server # [ 22.293418] horae[1582]: 2026-11-07T00:26:04.761121Z INFO dioxus_server::server: Registering: POST /api/preview_person_permissions115797103093460273031980server # [ 22.295285] horae[1582]: 2026-11-07T00:26:04.761134Z INFO dioxus_server::server: Registering: POST /api/save_permission_template115797103093460273031981server # [ 22.297109] horae[1582]: 2026-11-07T00:26:04.761148Z INFO dioxus_server::server: Registering: POST /api/list_permission_subjects115797103093460273031982server # [ 22.298930] horae[1582]: 2026-11-07T00:26:04.761161Z INFO dioxus_server::server: Registering: POST /api/save_person_permissions115797103093460273031983server # [ 22.300706] horae[1582]: 2026-11-07T00:26:04.761175Z INFO dioxus_server::server: Registering: POST /api/load_permission_editor115797103093460273031984server # [ 22.302448] horae[1582]: 2026-11-07T00:26:04.761191Z INFO dioxus_server::server: Registering: POST /api/list_visible_time_entries163860635615838339551985server # [ 22.306119] horae[1582]: 2026-11-07T00:26:04.761204Z INFO dioxus_server::server: Registering: POST /api/list_time_entry_contexts163860635615838339551986server # [ 22.307964] horae[1582]: 2026-11-07T00:26:04.761216Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_tracking163860635615838339551987server # [ 22.309821] horae[1582]: 2026-11-07T00:26:04.761254Z INFO dioxus_server::server: Registering: POST /api/apply_timesheet_command163860635615838339551988server # [ 22.311582] horae[1582]: 2026-11-07T00:26:04.761274Z INFO dioxus_server::server: Registering: POST /api/list_timesheet_people163860635615838339551989server # [ 22.313362] horae[1582]: 2026-11-07T00:26:04.761301Z INFO dioxus_server::server: Registering: POST /api/load_timesheet_page163860635615838339551990server # [ 22.317556] horae[1582]: 2026-11-07T00:26:04.761315Z INFO dioxus_server::server: Registering: POST /api/get_current_timer163860635615838339551991server # [ 22.319341] horae[1582]: 2026-11-07T00:26:04.761327Z INFO dioxus_server::server: Registering: POST /api/start_timer163860635615838339551992server # [ 22.321048] horae[1582]: 2026-11-07T00:26:04.761341Z INFO dioxus_server::server: Registering: POST /api/stop_timer163860635615838339551993server # [ 22.322711] horae[1582]: 2026-11-07T00:26:04.761355Z INFO dioxus_server::server: Registering: POST /api/import/harvest/connection1994server # [ 22.326115] horae[1582]: 2026-11-07T00:26:04.761367Z INFO dioxus_server::server: Registering: POST /api/import/harvest/cancel1995server # [ 22.327752] horae[1582]: 2026-11-07T00:26:04.761381Z INFO dioxus_server::server: Registering: POST /api/import/harvest/csv-job/{mode}1996server # [ 22.330425] horae[1582]: 2026-11-07T00:26:04.761401Z INFO dioxus_server::server: Registering: POST /api/import/harvest/start1997server # [ 22.332110] horae[1582]: 2026-11-07T00:26:04.761417Z INFO dioxus_server::server: Registering: POST /api/import/harvest/retry1998server # [ 22.333696] horae[1582]: 2026-11-07T00:26:04.761430Z INFO dioxus_server::server: Registering: POST /api/import/harvest/history1999server # [ 22.335280] horae[1582]: 2026-11-07T00:26:04.761442Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client2000server # [ 22.336885] horae[1582]: 2026-11-07T00:26:04.761454Z INFO dioxus_server::server: Registering: POST /api/harvest_change_account14792234983801993932001server # [ 22.338723] horae[1582]: 2026-11-07T00:26:04.761467Z INFO dioxus_server::server: Registering: POST /api/import/harvest/status2002server # [ 22.342863] horae[1582]: 2026-11-07T00:26:04.761482Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/remove2003server # [ 22.344523] horae[1582]: 2026-11-07T00:26:04.761495Z INFO dioxus_server::server: Registering: POST /api/harvest_connect_start14792234983801993932004server # [ 22.346292] horae[1582]: 2026-11-07T00:26:04.761508Z INFO dioxus_server::server: Registering: POST /api/import/harvest/client/save2005server # [ 22.347948] horae[1582]: 2026-11-07T00:26:04.761524Z INFO dioxus_server::server: Registering: POST /api/harvest_disconnect14792234983801993932006server # [ 22.349665] horae[1582]: 2026-11-07T00:26:04.761537Z INFO dioxus_server::server: Registering: POST /api/load_task_catalog122563778109868174722007server # [ 22.351391] horae[1582]: 2026-11-07T00:26:04.761550Z INFO dioxus_server::server: Registering: POST /api/get_project_detail_view79311481232659985112008server # [ 22.354976] horae[1582]: 2026-11-07T00:26:04.761564Z INFO dioxus_server::server: Registering: POST /api/set_user_active137396755887194634962009server # [ 22.356728] horae[1582]: 2026-11-07T00:26:04.761577Z INFO dioxus_server::server: Registering: POST /api/set_user_role137396755887194634962010server # [ 22.358403] horae[1582]: 2026-11-07T00:26:04.761590Z INFO dioxus_server::server: Registering: POST /api/list_people137396755887194634962011server # [ 22.360097] horae[1582]: 2026-11-07T00:26:04.761605Z INFO dioxus_server::server: Registering: POST /api/create_user137396755887194634962012server # [ 22.361782] horae[1582]: 2026-11-07T00:26:04.761617Z INFO dioxus_server::server: Registering: POST /api/list_users137396755887194634962013server # [ 22.363447] horae[1582]: 2026-11-07T00:26:04.761630Z INFO dioxus_server::server: Registering: POST /api/list_project_budget_progress139632003752584732062014server # [ 22.365454] horae[1582]: 2026-11-07T00:26:04.761644Z INFO dioxus_server::server: Registering: POST /api/list_tracking_projects139632003752584732062015server # [ 22.367254] horae[1582]: 2026-11-07T00:26:04.761657Z INFO dioxus_server::server: Registering: POST /api/get_project_overview139632003752584732062016server # [ 22.369038] horae[1582]: 2026-11-07T00:26:04.761676Z INFO dioxus_server::server: Registering: POST /api/set_projects_active139632003752584732062017server # [ 22.370818] horae[1582]: 2026-11-07T00:26:04.772941Z INFO dioxus_server::server: Registering: POST /api/list_tracking_tasks139632003752584732062018server # [ 22.372559] horae[1582]: 2026-11-07T00:26:04.772964Z INFO dioxus_server::server: Registering: POST /api/set_project_active139632003752584732062019server # [ 22.374347] horae[1582]: 2026-11-07T00:26:04.772978Z INFO dioxus_server::server: Registering: POST /api/list_project_spend139632003752584732062020server # [ 22.376118] horae[1582]: 2026-11-07T00:26:04.772993Z INFO dioxus_server::server: Registering: POST /api/list_project_tags139632003752584732062021server # [ 22.377853] horae[1582]: 2026-11-07T00:26:04.773006Z INFO dioxus_server::server: Registering: POST /api/delete_assignment139632003752584732062022server # [ 22.379574] horae[1582]: 2026-11-07T00:26:04.773018Z INFO dioxus_server::server: Registering: POST /api/create_assignment139632003752584732062023server # [ 22.381303] horae[1582]: 2026-11-07T00:26:04.773031Z INFO dioxus_server::server: Registering: POST /api/set_task_active139632003752584732062024server # [ 22.383023] horae[1582]: 2026-11-07T00:26:04.773043Z INFO dioxus_server::server: Registering: POST /api/list_projects139632003752584732062025server # [ 22.384698] horae[1582]: 2026-11-07T00:26:04.773057Z INFO dioxus_server::server: Registering: POST /api/update_task139632003752584732062026server # [ 22.386368] horae[1582]: 2026-11-07T00:26:04.773069Z INFO dioxus_server::server: Registering: POST /api/create_task139632003752584732062027server # [ 22.388099] horae[1582]: 2026-11-07T00:26:04.773081Z INFO dioxus_server::server: Registering: POST /api/list_tasks139632003752584732062028server # [ 22.389769] horae[1582]: 2026-11-07T00:26:04.773094Z INFO dioxus_server::server: Registering: POST /api/approve_submissions135900591888008486542029server # [ 22.391508] horae[1582]: 2026-11-07T00:26:04.773107Z INFO dioxus_server::server: Registering: POST /api/approve_submission135900591888008486542030server # [ 22.393253] horae[1582]: 2026-11-07T00:26:04.773121Z INFO dioxus_server::server: Registering: POST /api/reject_submission135900591888008486542031server # [ 22.394984] horae[1582]: 2026-11-07T00:26:04.773133Z INFO dioxus_server::server: Registering: POST /api/list_approvals135900591888008486542032server # [ 22.396678] horae[1582]: 2026-11-07T00:26:04.773146Z INFO dioxus_server::server: Registering: POST /api/submit_week135900591888008486542033server # [ 22.398366] horae[1582]: 2026-11-07T00:26:04.773162Z INFO dioxus_server::server: Registering: POST /api/list_plugins152655463789645218002034server # [ 22.400072] horae[1582]: 2026-11-07T00:26:04.773178Z INFO dioxus_server::server: Registering: POST /api/update_client_profile104851358257774915712035server # [ 22.401821] horae[1582]: 2026-11-07T00:26:04.773191Z INFO dioxus_server::server: Registering: POST /api/create_project_client104851358257774915712036server # [ 22.403565] horae[1582]: 2026-11-07T00:26:04.773204Z INFO dioxus_server::server: Registering: POST /api/create_client_profile104851358257774915712037server # [ 22.405336] horae[1582]: 2026-11-07T00:26:04.773218Z INFO dioxus_server::server: Registering: POST /api/list_client_invoices104851358257774915712038server # [ 22.407132] horae[1582]: 2026-11-07T00:26:04.773230Z INFO dioxus_server::server: Registering: POST /api/get_client_details104851358257774915712039server # [ 22.408869] horae[1582]: 2026-11-07T00:26:04.773253Z INFO dioxus_server::server: Registering: POST /api/client_detail_view104851358257774915712040server # [ 22.410637] horae[1582]: 2026-11-07T00:26:04.773269Z INFO dioxus_server::server: Registering: POST /api/set_client_active104851358257774915712041server # [ 22.412389] horae[1582]: 2026-11-07T00:26:04.773282Z INFO dioxus_server::server: Registering: POST /api/client_overview104851358257774915712042server # [ 22.414138] horae[1582]: 2026-11-07T00:26:04.773294Z INFO dioxus_server::server: Registering: POST /api/list_clients104851358257774915712043server # [ 22.415824] horae[1582]: 2026-11-07T00:26:04.773308Z INFO dioxus_server::server: Registering: POST /api/save_project_managers114747469634461219342044server # [ 22.417613] horae[1582]: 2026-11-07T00:26:04.773321Z INFO dioxus_server::server: Registering: POST /api/load_project_managers114747469634461219342045server # [ 22.419424] horae[1582]: 2026-11-07T00:26:04.773334Z INFO dioxus_server::server: Registering: POST /api/project_creation_selection16044791136740829192046server # [ 22.421232] horae[1582]: 2026-11-07T00:26:04.773347Z INFO dioxus_server::server: Registering: POST /api/project_creation_options16044791136740829192047server # [ 22.423058] horae[1582]: 2026-11-07T00:26:04.773360Z INFO dioxus_server::server: Registering: POST /api/project_creation_client16044791136740829192048server # [ 22.424808] horae[1582]: 2026-11-07T00:26:04.773373Z INFO dioxus_server::server: Registering: POST /api/project_editor_catalog16044791136740829192049server # [ 22.426591] horae[1582]: 2026-11-07T00:26:04.773388Z INFO dioxus_server::server: Registering: POST /api/finalize_project_draft16044791136740829192050server # [ 22.428399] horae[1582]: 2026-11-07T00:26:04.773405Z INFO dioxus_server::server: Registering: POST /api/discard_project_draft16044791136740829192051server # [ 22.430170] horae[1582]: 2026-11-07T00:26:04.773422Z INFO dioxus_server::server: Registering: POST /api/save_project_editor16044791136740829192052server # [ 22.431960] horae[1582]: 2026-11-07T00:26:04.773435Z INFO dioxus_server::server: Registering: POST /api/load_project_editor16044791136740829192053server # [ 22.433778] horae[1582]: 2026-11-07T00:26:04.773447Z INFO dioxus_server::server: Registering: POST /api/save_project_draft16044791136740829192054server # [ 22.435491] horae[1582]: 2026-11-07T00:26:04.773463Z INFO dioxus_server::server: Registering: POST /api/load_project_draft16044791136740829192055server # [ 22.437223] horae[1582]: 2026-11-07T00:26:04.773475Z INFO dioxus_server::server: Registering: POST /api/project_people16044791136740829192056server # [ 22.438923] horae[1582]: 2026-11-07T00:26:04.773488Z INFO dioxus_server::server: Registering: POST /api/update_org_branding130471720523972770262057server # [ 22.440662] horae[1582]: 2026-11-07T00:26:04.773501Z INFO dioxus_server::server: Registering: POST /api/get_org_branding130471720523972770262058server # [ 22.442384] horae[1582]: 2026-11-07T00:26:04.773513Z INFO dioxus_server::server: Registering: POST /api/get_week_start130471720523972770262059server # [ 22.444148] horae[1582]: 2026-11-07T00:26:04.773525Z INFO dioxus_server::server: Registering: POST /api/get_org_name130471720523972770262060server # [ 22.445873] horae[1582]: 2026-11-07T00:26:04.787394Z INFO horae: Listening on 127.0.0.1:30002061server # Connection to localhost (127.0.0.1) 3000 port [tcp/hbci] succeeded!2062server: (finished: waiting for TCP port 3000 on localhost, in 1.05 seconds)2063server: must succeed: curl -sS -c /tmp/jar10.txt -b /tmp/jar10.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null2064server # [ 23.260742] dex[756]: time=2026-11-07T00:26:05.765Z level=INFO msg="login successful" connector_id=mock username="Kilgore Trout" preferred_username="" email=kilgore@kilgore.trout groups=[authors] request_id=08a19ed1-534b-4c75-8c00-0a036188bc292065server # [ 23.283381] horae[1582]: 2026-11-07T00:26:05.788339Z INFO call: horae::auth::oidc: OIDC sign-in created an account user_id=01a1b365-f458-7b81-8a6c-c0a6ef3cb3ed2066server: (finished: must succeed: curl -sS -c /tmp/jar10.txt -b /tmp/jar10.txt -L --max-redirs 8 http://127.0.0.1:3000/auth/oidc/start -o /dev/null, in 0.10 seconds)2067server: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar10.txt http://127.0.0.1:3000/harvest/v2/time_entries2068server: (finished: must succeed: curl -sS -o /dev/null -w '%{http_code}' -b /tmp/jar10.txt http://127.0.0.1:3000/harvest/v2/time_entries, in 0.04 seconds)2069server: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"' AND active AND org_role = '"'"'member'"'"''2070server # [ 23.392869] sudo[1606]: root : TTY=hvc0 ; PWD=/tmp ; USER=horae ; COMMAND=/run/current-system/sw/bin/psql horae -tAc 'SELECT count(*) FROM users WHERE email = \'kilgore@kilgore.trout\' AND active AND org_role = \'member\''2071server # [ 23.413363] sudo[1606]: pam_unix(sudo:session): session opened for user horae(uid=63319) by (uid=0)2072server # [ 23.427345] sudo[1606]: pam_unix(sudo:session): session closed for user horae2073server: (finished: must succeed: sudo -u horae psql horae -tAc 'SELECT count(*) FROM users WHERE email = '"'"'kilgore@kilgore.trout'"'"' AND active AND org_role = '"'"'member'"'"'', in 0.05 seconds)2074server # [ 23.442819] systemd[1]: Reload requested from client PID 1617 ('systemctl') (unit backdoor.service)...2075server # [ 23.445194] systemd[1]: Reloading...2076server # [ 23.650854] systemd[1]: Reloading finished in 206 ms.2077server # [ 23.691093] systemd[1]: Stopping Horae time tracking server...2078server # [ 23.698083] systemd[1]: horae.service: Deactivated successfully.2079server # [ 23.702974] systemd[1]: Stopped Horae time tracking server.2080server # [ 23.703828] systemd[1]: horae.service: Consumed 66ms CPU time over 1.571s wall clock time, 5.6M memory peak, 8.3K incoming IP traffic, 13K outgoing IP traffic.2081server # [ 23.711298] systemd[1]: Starting Horae time tracking server...2082server # [ 23.735684] horae[1655]: Error: HORAE_OIDC_AUTO_PROVISION_DOMAINS must be a comma-separated list of email domains (e.g. example.com,example.org); "*" is not a domain2083server # [ 23.738452] systemd[1]: horae.service: Control process exited, code=exited, status=1/FAILURE2084server # [ 23.739742] systemd[1]: horae.service: Failed with result 'exit-code'.2085server # [ 23.741580] systemd[1]: Failed to start Horae time tracking server.2086server # Job for horae.service failed because the control process exited with error code.2087server # See "systemctl status horae.service" and "journalctl -xeu horae.service" for details.2088server: waiting for failure: systemctl is-active --quiet horae.service2089server: (finished: waiting for failure: systemctl is-active --quiet horae.service, in 0.02 seconds)2090server: must succeed: journalctl -u horae.service --no-pager -n 502091server: (finished: must succeed: journalctl -u horae.service --no-pager -n 50, in 0.01 seconds)2092(finished: run the VM test script, in 30.65 seconds)2093test script finished in 30.68s2094cleanup2095kill QemuMachine (pid 12)2096server # qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/l9k0anq0z7zz81zcwy035jfwap9ga6rl-python3-3.13.13/bin/python3.13)2097(finished: cleanup, in 0.10 seconds)