vm-test-run-url-media-archive-keep-failed-temp
checks.x86_64-linux.url-media-archive-keep-failed-temp
· build #25
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600.0 seconds5run the VM test script6additionally exposed symbols:7 machine,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10machine: starting vm11machine: QEMU running (pid 45)12machine # Disk image does not exist, creating the virtualisation disk image...13machine: waiting for unit postgresql.service14machine # Formatting '/build/vm-state-machine/tmp.sdGd5UtCDe', fmt=raw size=107374182415machine: waiting for the VM to finish booting16machine # mke2fs 1.47.4 (6-Mar-2025)17machine # Discarding device blocks: 0/262144 done18machine # Creating filesystem with 262144 4k blocks and 65536 inodes19machine # Filesystem UUID: f1321948-1fe8-44d3-a9ac-8b6959077f7120machine # Superblock backups stored on blocks:21machine # 32768, 98304, 163840, 22937622machine # 23machine # Allocating group tables: 0/8 done24machine # Writing inode tables: 0/8 done25machine # Creating journal (8192 blocks): done26machine # Writing superblocks and filesystem accounting information: 0/8 done27machine # 28machine # Virtualisation disk image created.29machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)30machine # 31machine # 32machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0033machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...34machine # 35machine # 36machine # 37machine # 38machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0039machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)...40machine # 41machine # 42machine # Booting from ROM...43machine # Probing EDD (edd=off to disable)... ok44machine # [ 0.000000] Linux version 6.18.47 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Thu Aug 27 12:32:55 UTC 202645machine # [ 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/r80g44sbsasf7isr3bff6fi04qmnd5f9-nixos-system-machine-test/init regInfo=/nix/store/hxszj9mwnk1hwabhcs9asi3m4gnzihpg-closure-info/registration console=ttyS0,115200n8 console=tty046machine # [ 0.000000] x86 CPU feature dependency check failure: CPU0 has '18*32+31' enabled but '18*32+26' disabled. Kernel might be fine, but no guarantees.47machine # [ 0.000000] BIOS-provided physical RAM map:48machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable49machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved50machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved51machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable52machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved53machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved54machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved55machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved56machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved57machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved58machine # [ 0.000000] NX (Execute Disable) protection: active59machine # [ 0.000000] APIC: Static calls initialized60machine # [ 0.000000] SMBIOS 2.8 present.61machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201462machine # [ 0.000000] DMI: Memory slots populated: 1/163machine # [ 0.000000] Hypervisor detected: KVM64machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x40000000065machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0066machine # [ 0.000000] kvm-clock: using sched offset of 458542997 cycles67machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns68machine # [ 0.000004] tsc: Detected 3792.874 MHz processor69machine # [ 0.000761] last_pfn = 0x3ffd8 max_arch_pfn = 0x40000000070machine # [ 0.000795] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs71machine # [ 0.000798] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT72machine # [ 0.002422] found SMP MP-table at [mem 0x000f5450-0x000f545f]73machine # [ 0.002433] Using GB pages for direct mapping74machine # [ 0.002565] RAMDISK: [mem 0x3e368000-0x3ffcffff]75machine # [ 0.002572] ACPI: Early table checksum verification disabled76machine # [ 0.002574] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )77machine # [ 0.002578] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002582] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002588] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002590] ACPI: FACS 0x000000003FFE0000 00004081machine # [ 0.002591] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)82machine # [ 0.002593] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)83machine # [ 0.002594] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)84machine # [ 0.002595] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)85machine # [ 0.002597] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]86machine # [ 0.002598] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]87machine # [ 0.002598] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]88machine # [ 0.002598] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]89machine # [ 0.002599] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]90machine # [ 0.002599] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]91machine # [ 0.002600] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]92machine # [ 0.002979] No NUMA configuration found93machine # [ 0.002980] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]94machine # [ 0.002983] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]95machine # [ 0.003080] Zone ranges:96machine # [ 0.003081] DMA [mem 0x0000000000001000-0x0000000000ffffff]97machine # [ 0.003083] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]98machine # [ 0.003084] Normal empty99machine # [ 0.003085] Device empty100machine # [ 0.003086] Movable zone start for each node101machine # [ 0.003087] Early memory node ranges102machine # [ 0.003087] node 0: [mem 0x0000000000001000-0x000000000009efff]103machine # [ 0.003088] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]104machine # [ 0.003090] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]105machine # [ 0.003107] On node 0, zone DMA: 1 pages in unavailable ranges106machine # [ 0.003369] On node 0, zone DMA: 97 pages in unavailable ranges107machine # [ 0.018045] On node 0, zone DMA32: 40 pages in unavailable ranges108machine # [ 0.018926] ACPI: PM-Timer IO Port: 0x608109machine # [ 0.018938] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])110machine # [ 0.018962] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23111machine # [ 0.018964] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)112machine # [ 0.018965] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)113machine # [ 0.018966] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)114machine # [ 0.018967] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)115machine # [ 0.018968] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)116machine # [ 0.018970] ACPI: Using ACPI (MADT) for SMP configuration information117machine # [ 0.018970] ACPI: HPET id: 0x8086a201 base: 0xfed00000118machine # [ 0.018975] TSC deadline timer available119machine # [ 0.018979] CPU topo: Max. logical packages: 1120machine # [ 0.018980] CPU topo: Max. logical dies: 1121machine # [ 0.018980] CPU topo: Max. dies per package: 1122machine # [ 0.018983] CPU topo: Max. threads per core: 1123machine # [ 0.018984] CPU topo: Num. cores per package: 1124machine # [ 0.018984] CPU topo: Num. threads per package: 1125machine # [ 0.018984] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs126machine # [ 0.019001] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()127machine # [ 0.019030] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]128machine # [ 0.019031] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]129machine # [ 0.019032] [mem 0x40000000-0xafffffff] available for PCI devices130machine # [ 0.019033] Booting paravirtualized kernel on KVM131machine # [ 0.019036] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns132machine # [ 0.022766] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1133machine # [ 0.024443] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152134machine # [ 0.024479] kvm-guest: PV spinlocks disabled, single CPU135machine # [ 0.024480] 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/r80g44sbsasf7isr3bff6fi04qmnd5f9-nixos-system-machine-test/init regInfo=/nix/store/hxszj9mwnk1hwabhcs9asi3m4gnzihpg-closure-info/registration console=ttyS0,115200n8 console=tty0136machine # [ 0.024559] Unknown kernel command line parameters "regInfo=/nix/store/hxszj9mwnk1hwabhcs9asi3m4gnzihpg-closure-info/registration", will be passed to user space.137machine # [ 0.024577] random: crng init done138machine # [ 0.024578] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes139machine # [ 0.025397] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)140machine # [ 0.025410] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)141machine # [ 0.025437] Fallback order for Node 0: 0142machine # [ 0.025439] Built 1 zonelists, mobility grouping on. Total pages: 262006143machine # [ 0.025440] Policy zone: DMA32144machine # [ 0.027408] mem auto-init: stack:all(zero), heap alloc:on, heap free:off145machine # [ 0.030438] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1146machine # [ 0.032158] allocated 2097152 bytes of page_ext147machine # [ 0.041172] ftrace: allocating 48733 entries in 192 pages148machine # [ 0.041173] ftrace: allocated 192 pages with 2 groups149machine # [ 0.041853] Dynamic Preempt: lazy150machine # [ 0.041970] rcu: Preemptible hierarchical RCU implementation.151machine # [ 0.041970] rcu: RCU event tracing is enabled.152machine # [ 0.041971] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.153machine # [ 0.041972] Trampoline variant of Tasks RCU enabled.154machine # [ 0.041973] Rude variant of Tasks RCU enabled.155machine # [ 0.041973] Tracing variant of Tasks RCU enabled.156machine # [ 0.041973] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.157machine # [ 0.041974] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1158machine # [ 0.042019] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.159machine # [ 0.042021] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.160machine # [ 0.042022] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.161machine # [ 0.045779] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16162machine # [ 0.046015] rcu: srcu_init: Setting srcu_struct sizes based on contention.163machine # [ 0.046021] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns164machine # [ 0.046117] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)165machine # [ 0.052752] Console: colour VGA+ 80x25166machine # [ 0.052756] printk: legacy console [tty0] enabled167machine # [ 0.089753] printk: legacy console [ttyS0] enabled168machine # [ 0.242584] ACPI: Core revision 20250807169machine # [ 0.243863] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns170machine # [ 0.245990] APIC: Switch to symmetric I/O mode setup171machine # [ 0.247295] x2apic enabled172machine # [ 0.248415] APIC: Switched APIC routing to: physical x2apic173machine # [ 0.250765] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1174machine # [ 0.252268] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x6d5818a734c, max_idle_ns: 881590694765 ns175machine # [ 0.254930] Calibrating delay loop (skipped) preset value.. 7585.74 BogoMIPS (lpj=3792874)176machine # [ 0.257015] x86/cpu: User Mode Instruction Prevention (UMIP) activated177machine # [ 0.258080] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127178machine # [ 0.259929] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0179machine # [ 0.260934] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto180machine # [ 0.261929] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl181machine # [ 0.263929] Spectre V2 : Mitigation: Retpolines182machine # [ 0.265929] Speculative Return Stack Overflow: Mitigation: Safe RET183machine # [ 0.266929] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization184machine # [ 0.268929] Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT185machine # [ 0.270929] Spectre V2 : Enabling Restricted Speculation for firmware calls186machine # [ 0.272934] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier187machine # [ 0.274931] active return thunk: srso_alias_return_thunk188machine # [ 0.276945] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'189machine # [ 0.277929] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'190machine # [ 0.278929] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'191machine # [ 0.280929] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'192machine # [ 0.281928] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'193machine # [ 0.283928] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'194machine # [ 0.284929] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256195machine # [ 0.286929] x86/fpu: xstate_offset[9]: 832, xstate_sizes[9]: 8196machine # [ 0.287928] x86/fpu: xstate_offset[11]: 840, xstate_sizes[11]: 16197machine # [ 0.288930] x86/fpu: xstate_offset[12]: 856, xstate_sizes[12]: 24198machine # [ 0.289928] x86/fpu: Enabled xstate features 0x1a07, context size is 880 bytes, using 'compacted' format.199machine # [ 0.324526] Freeing SMP alternatives memory: 44K200machine # [ 0.324933] pid_max: default: 32768 minimum: 301201machine # [ 0.326034] LSM: initializing lsm=capability,landlock,yama,bpf,ima202machine # [ 0.327046] landlock: Up and running.203machine # [ 0.327929] Yama: becoming mindful.204machine # [ 0.329179] LSM support for eBPF active205machine # [ 0.330739] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)206machine # [ 0.331963] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)207machine # [ 0.335226] smpboot: CPU0: AMD Ryzen Threadripper PRO 5965WX 24-Cores (family: 0x19, model: 0x8, stepping: 0x2)208machine # [ 0.336611] Performance Events: Fam17h+ core perfctr, AMD PMU driver.209machine # [ 0.336939] ... version: 0210machine # [ 0.337931] ... bit width: 48211machine # [ 0.338931] ... generic counters: 6212machine # [ 0.339930] ... generic bitmap: 000000000000003f213machine # [ 0.340930] ... fixed-purpose counters: 0214machine # [ 0.341931] ... fixed-purpose bitmap: 0000000000000000215machine # [ 0.342931] ... value mask: 0000ffffffffffff216machine # [ 0.343931] ... max period: 00007fffffffffff217machine # [ 0.344931] ... global_ctrl mask: 000000000000003f218machine # [ 0.346085] signal: max sigframe size: 3376219machine # [ 0.347035] rcu: Hierarchical SRCU implementation.220machine # [ 0.347934] rcu: Max phase no-delay instances is 400.221machine # [ 0.353246] smp: Bringing up secondary CPUs ...222machine # [ 0.353949] smp: Brought up 1 node, 1 CPU223machine # [ 0.354933] smpboot: Total of 1 processors activated (7585.74 BogoMIPS)224machine # [ 0.356158] Memory: 943012K/1048024K available (17215K kernel code, 2726K rwdata, 13580K rodata, 3644K init, 2988K bss, 97592K reserved, 0K cma-reserved)225machine # [ 0.357182] devtmpfs: initialized226machine # [ 0.358160] x86/mm: Memory block size: 128MB227machine # [ 0.359823] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)228machine # [ 0.360967] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).229machine # [ 0.362036] pinctrl core: initialized pinctrl subsystem230machine # [ 0.363249] PM: RTC time: 18:07:40, date: 2026-09-02231machine # [ 0.366479] NET: Registered PF_NETLINK/PF_ROUTE protocol family232machine # [ 0.367368] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations233machine # [ 0.367955] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations234machine # [ 0.369087] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations235machine # [ 0.369949] audit: initializing netlink subsys (disabled)236machine # [ 0.371366] thermal_sys: Registered thermal governor 'fair_share'237machine # [ 0.371368] thermal_sys: Registered thermal governor 'bang_bang'238machine # [ 0.371932] thermal_sys: Registered thermal governor 'step_wise'239machine # [ 0.372934] audit: type=2000 audit(1788372461.573:1): state=initialized audit_enabled=0 res=1240machine # [ 0.374935] thermal_sys: Registered thermal governor 'user_space'241machine # [ 0.374937] thermal_sys: Registered thermal governor 'power_allocator'242machine # [ 0.375959] cpuidle: using governor menu243machine # [ 0.379093] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5244machine # [ 0.380264] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]245machine # [ 0.380933] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry246machine # [ 0.381949] PCI: Using configuration type 1 for base access247machine # [ 0.383226] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.248machine # [ 0.388325] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages249machine # [ 0.388930] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page250machine # [ 0.389933] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages251machine # [ 0.396934] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page252machine # [ 0.404327] ACPI: Added _OSI(Module Device)253machine # [ 0.404931] ACPI: Added _OSI(Processor Device)254machine # [ 0.409930] ACPI: Added _OSI(Processor Aggregator Device)255machine # [ 0.419059] ACPI: 1 ACPI AML tables successfully acquired and loaded256machine # [ 0.423335] ACPI: Interpreter enabled257machine # [ 0.423953] ACPI: PM: (supports S0 S3 S4 S5)258machine # [ 0.426931] ACPI: Using IOAPIC for interrupt routing259machine # [ 0.428014] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug260machine # [ 0.430693] PCI: Using E820 reservations for host bridge windows261machine # [ 0.432144] ACPI: Enabled 2 GPEs in block 00 to 3F262machine # [ 0.438099] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])263machine # [ 0.438936] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]264machine # [ 0.440016] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]265machine # [ 0.441051] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]266machine # [ 0.442421] PCI host bridge to bus 0000:00267machine # [ 0.442936] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]268machine # [ 0.444000] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]269machine # [ 0.444931] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]270machine # [ 0.445931] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]271machine # [ 0.446933] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]272machine # [ 0.447932] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]273machine # [ 0.448932] pci_bus 0000:00: root bus resource [bus 00-ff]274machine # [ 0.450116] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint275machine # [ 0.451750] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint276machine # [ 0.454989] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]277machine # [ 0.455952] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]278machine # [ 0.456974] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]279machine # [ 0.458111] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]280machine # [ 0.459747] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint281machine # [ 0.462941] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]282machine # [ 0.463942] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]283machine # [ 0.464976] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]284machine # [ 0.465942] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]285machine # [ 0.468040] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint286machine # [ 0.470930] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]287machine # [ 0.471942] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]288machine # [ 0.472980] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]289machine # [ 0.475069] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint290machine # [ 0.477826] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]291machine # [ 0.478949] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]292machine # [ 0.479975] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]293machine # [ 0.481903] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint294machine # [ 0.484931] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]295machine # [ 0.485943] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]296machine # [ 0.486981] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]297machine # [ 0.489069] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint298machine # [ 0.490966] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]299machine # [ 0.491943] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]300machine # [ 0.492978] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]301machine # [ 0.494941] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint302machine # [ 0.496944] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]303machine # [ 0.497945] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]304machine # [ 0.498978] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]305machine # [ 0.500992] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint306machine # [ 0.502942] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]307machine # [ 0.503943] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]308machine # [ 0.504980] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]309machine # [ 0.506636] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]310machine # [ 0.507952] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint311machine # [ 0.509952] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]312machine # [ 0.510978] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]313machine # [ 0.513124] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint314machine # [ 0.514968] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]315machine # [ 0.515942] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]316machine # [ 0.516986] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]317machine # [ 0.519066] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint318machine # [ 0.521584] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]319machine # [ 0.521943] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]320machine # [ 0.522985] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]321machine # [ 0.524954] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint322machine # [ 0.526752] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]323machine # [ 0.528239] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint324machine # [ 0.530665] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]325machine # [ 0.531232] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint326machine # [ 0.532652] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]327machine # [ 0.533255] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint328machine # [ 0.534636] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]329machine # [ 0.535288] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint330machine # [ 0.536468] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO331machine # [ 0.537313] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint332machine # [ 0.538896] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]333machine # [ 0.539889] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]334machine # [ 0.541464] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint335machine # [ 0.542961] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]336machine # [ 0.550798] ACPI: PCI: Interrupt link LNKA configured for IRQ 10337machine # [ 0.552168] ACPI: PCI: Interrupt link LNKB configured for IRQ 10338machine # [ 0.553137] ACPI: PCI: Interrupt link LNKC configured for IRQ 11339machine # [ 0.554142] ACPI: PCI: Interrupt link LNKD configured for IRQ 11340machine # [ 0.555146] ACPI: PCI: Interrupt link LNKE configured for IRQ 10341machine # [ 0.556101] ACPI: PCI: Interrupt link LNKF configured for IRQ 10342machine # [ 0.557100] ACPI: PCI: Interrupt link LNKG configured for IRQ 11343machine # [ 0.558096] ACPI: PCI: Interrupt link LNKH configured for IRQ 11344machine # [ 0.558992] ACPI: PCI: Interrupt link GSIA configured for IRQ 16345machine # [ 0.559957] ACPI: PCI: Interrupt link GSIB configured for IRQ 17346machine # [ 0.560957] ACPI: PCI: Interrupt link GSIC configured for IRQ 18347machine # [ 0.561951] ACPI: PCI: Interrupt link GSID configured for IRQ 19348machine # [ 0.562961] ACPI: PCI: Interrupt link GSIE configured for IRQ 20349machine # [ 0.563952] ACPI: PCI: Interrupt link GSIF configured for IRQ 21350machine # [ 0.564952] ACPI: PCI: Interrupt link GSIG configured for IRQ 22351machine # [ 0.565954] ACPI: PCI: Interrupt link GSIH configured for IRQ 23352machine # [ 0.568142] iommu: Default domain type: Translated353machine # [ 0.568942] iommu: DMA domain TLB invalidation policy: lazy mode354machine # [ 0.570254] ACPI: bus type USB registered355machine # [ 0.571025] usbcore: registered new interface driver usbfs356machine # [ 0.571957] usbcore: registered new interface driver hub357machine # [ 0.572959] usbcore: registered new device driver usb358machine # [ 0.574939] NetLabel: Initializing359machine # [ 0.575930] NetLabel: domain hash size = 128360machine # [ 0.576931] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO361machine # [ 0.578005] NetLabel: unlabeled traffic allowed by default362machine # [ 0.578944] PCI: Using ACPI for IRQ routing363machine # [ 0.679749] pci 0000:00:01.0: vgaarb: setting as boot VGA device364machine # [ 0.679926] pci 0000:00:01.0: vgaarb: bridge control possible365machine # [ 0.679926] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none366machine # [ 0.679932] vgaarb: loaded367machine # [ 0.680764] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0368machine # [ 0.681782] hpet0: 3 comparators, 64-bit 100.000000 MHz counter369machine # [ 0.687005] clocksource: Switched to clocksource kvm-clock370machine # [ 0.688836] VFS: Disk quotas dquot_6.6.0371machine # [ 0.691273] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)372machine # [ 0.692962] pnp: PnP ACPI init373machine # [ 0.693942] ACPI: IRQ 4 override to edge(!), high(!)374machine # [ 0.695208] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved375machine # [ 0.697204] pnp: PnP ACPI: found 6 devices376machine # [ 0.704772] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns377machine # [ 0.706704] clocksource: Switched to clocksource acpi_pm378machine # [ 0.708025] NET: Registered PF_INET protocol family379machine # [ 0.709322] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)380machine # [ 0.725136] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)381machine # [ 0.727163] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)382machine # [ 0.728997] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)383machine # [ 0.731233] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)384machine # [ 0.733502] TCP: Hash tables configured (established 8192 bind 8192)385machine # [ 0.735822] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)386machine # [ 0.738556] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)387machine # [ 0.741120] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)388machine # [ 0.743438] NET: Registered PF_UNIX/PF_LOCAL protocol family389machine # [ 0.745279] NET: Registered PF_XDP protocol family390machine # [ 0.746899] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]391machine # [ 0.748645] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]392machine # [ 0.750545] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]393machine # [ 0.752258] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]394machine # [ 0.753851] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]395machine # [ 0.755363] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]396machine # [ 0.757525] ACPI: \_SB_.GSIA: Enabled at IRQ 16397machine # [ 0.759775] ACPI: \_SB_.GSIB: Enabled at IRQ 17398machine # [ 0.761996] ACPI: \_SB_.GSIC: Enabled at IRQ 18399machine # [ 0.764164] ACPI: \_SB_.GSID: Enabled at IRQ 19400machine # [ 0.766076] PCI: CLS 0 bytes, default 64401machine # [ 0.767207] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x6d5818a734c, max_idle_ns: 881590694765 ns402machine # [ 0.769411] Trying to unpack rootfs image as initramfs...403machine # [ 0.813325] Initialise system trusted keyrings404machine # [ 0.815984] workingset: timestamp_bits=40 max_order=18 bucket_order=0405machine # [ 0.836679] Key type asymmetric registered406machine # [ 0.837673] Asymmetric key parser 'x509' registered407machine # [ 0.840985] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)408machine # [ 0.846020] io scheduler mq-deadline registered409machine # [ 0.847119] io scheduler kyber registered410machine # [ 0.850455] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled411machine # [ 0.855198] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A412machine # [ 0.860867] Linux agpgart interface v0.103413machine # [ 0.861993] ACPI: bus type drm_connector registered414machine # [ 0.866068] usbcore: registered new interface driver usbserial_generic415machine # [ 0.867530] usbserial: USB Serial support registered for generic416machine # [ 0.870954] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled417machine # [ 0.872724] drop_monitor: Initializing network drop monitor service418machine # [ 0.878060] NET: Registered PF_INET6 protocol family419machine # [ 0.879629] Segment Routing with IPv6420machine # [ 0.882957] In-situ OAM (IOAM) with IPv6421machine # [ 0.884221] IPI shorthand broadcast: enabled422machine # [ 0.892391] sched_clock: Marking stable (688025109, 203905397)->(1039271433, -147340927)423machine # [ 0.898038] registered taskstats version 1424machine # [ 0.899232] Loading compiled-in X.509 certificates425machine # [ 0.915947] Demotion targets for Node 0: null426machine # [ 0.918992] Key type .fscrypt registered427machine # [ 0.919950] Key type fscrypt-provisioning registered428machine # [ 0.923056] ima: No TPM chip found, activating TPM-bypass!429machine # [ 0.924312] ima: Allocated hash algorithm: sha1430machine # [ 0.928972] ima: No architecture policies found431machine # [ 0.931943] PM: Magic number: 10:143:139432machine # [ 0.932982] acpi device:10: hash matches433machine # [ 0.936860] RAS: Correctable Errors collector initialized.434machine # [ 0.947583] clk: Disabling unused clocks435machine # [ 0.949944] PM: genpd: Disabling unused power domains436machine # [ 1.078345] Freeing initrd memory: 29088K437machine # [ 1.081473] Freeing unused decrypted memory: 2028K438machine # [ 1.084216] Freeing unused kernel image (initmem) memory: 3644K439machine # [ 1.085958] Write protecting the kernel read-only data: 32768k440machine # [ 1.088178] Freeing unused kernel image (text/rodata gap) memory: 1216K441machine # [ 1.090053] Freeing unused kernel image (rodata/data gap) memory: 756K442machine # [ 1.131355] x86/mm: Checked W+X mappings: passed, no W+X pages found.443machine # [ 1.132860] Run /init as init process444machine # [ 1.142057] systemd[1]: Inserted module 'autofs4'445machine # [ 1.156461] fuse: init (API version 7.45)446machine # [ 1.161843] ACPI: \_SB_.GSIG: Enabled at IRQ 22447machine # [ 1.164711] ACPI: \_SB_.GSIH: Enabled at IRQ 23448machine # [ 1.168360] ACPI: \_SB_.GSIE: Enabled at IRQ 20449machine # [ 1.171295] ACPI: \_SB_.GSIF: Enabled at IRQ 21450machine # [ 1.202805] systemd[1]: Successfully made /usr/ read-only.451machine # [ 1.540182] systemd[1]: systemd 261.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)452machine # [ 1.549272] systemd[1]: Detected virtualization kvm.453machine # [ 1.550864] systemd[1]: Detected architecture x86-64.454machine # [ 1.552487] systemd[1]: Running in initrd.455machine # [ 1.554120] systemd[1]: Initializing machine ID from random generator.456machine # [ 1.556062] systemd[1]: Hostname set to <machine>.457machine # [ 1.641471] systemd[1]: bpf-restrict-fs: LSM BPF program attached458machine # [ 1.678340] systemd[1]: Queued start job for default target Initrd Default Target.459machine # [ 1.682607] systemd[1]: Created slice Slice /system/modprobe.460machine # [ 1.684304] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.461machine # [ 1.686597] systemd[1]: Expecting device /dev/disk/by-label/nixos...462machine # [ 1.688133] systemd[1]: Reached target Path Units.463machine # [ 1.689327] systemd[1]: Reached target Slice Units.464machine # [ 1.690541] systemd[1]: Reached target Swaps.465machine # [ 1.691688] systemd[1]: Reached target Timer Units.466machine # [ 1.693040] systemd[1]: Listening on D-Bus System Message Bus Socket.467machine # [ 1.694662] systemd[1]: Listening on Journal Socket (/dev/log).468machine # [ 1.696241] systemd[1]: Listening on Journal Sockets.469machine # [ 1.697613] systemd[1]: Listening on udev Control Socket.470machine # [ 1.699041] systemd[1]: Listening on udev Kernel Socket.471machine # [ 1.700335] systemd[1]: Reached target Socket Units.472machine # [ 1.702541] systemd[1]: Starting Create List of Static Device Nodes...473machine # [ 1.706990] systemd[1]: Starting Load Kernel Module 9pnet_virtio...474machine # [ 1.713618] systemd[1]: Starting Load Kernel Module configfs...475machine # [ 1.729101] systemd[1]: Starting Journal Service...476machine # [ 1.742994] systemd[1]: Starting Load Kernel Modules...477machine # [ 1.757989] netfs: FS-Cache loaded478machine # [ 1.762985] 9pnet: Installing 9P2000 support479machine # [ 1.772059] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os480machine # [ 1.784108] systemd[1]: Starting Coldplug All udev Devices...481machine # [ 1.799002] systemd-journald[67]: Collecting audit messages is disabled.482machine # [ 1.801426] systemd[1]: Finished Create List of Static Device Nodes.483machine # [ 1.810152] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.484machine # [ 1.813720] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.485machine # [ 1.821442] systemd[1]: Finished Load Kernel Module 9pnet_virtio.486machine # [ 1.826431] systemd[1]: modprobe@configfs.service: Deactivated successfully.487machine # [ 1.829057] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev488machine # [ 1.835457] systemd[1]: Finished Load Kernel Module configfs.489machine # [ 1.844536] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config490machine # [ 1.859166] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...491machine # [ 1.875045] systemd[1]: Finished Load Kernel Modules.492machine # [ 1.884107] systemd[1]: Starting Apply Kernel Variables...493machine # [ 1.903998] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.494machine # [ 1.915483] systemd[1]: Starting Create Static Device Nodes in /dev...495machine # [ 1.928135] systemd[1]: Finished Apply Kernel Variables.496machine # [ 1.946659] systemd[1]: Finished Create Static Device Nodes in /dev.497machine # [ 1.952205] systemd[1]: Reached target Preparation for Local File Systems.498machine # [ 1.958019] systemd[1]: Reached target Local File Systems.499machine # [ 1.756199] systemd-modules-load[68]: Inserted module 'dm_mod'500machine # [ 1.758477] systemd-modules-load[68]: Inserted module 'virtio_balloon'501machine # [ 1.761373] systemd-modules-load[68]: Inserted module 'virtio_gpu'502machine # [ 1.968135] systemd[1]: Starting Rule-based Manager for Device Events and Files...503machine # [ 1.975104] systemd[1]: Started Journal Service.504machine # [ 1.791174] systemd[1]: Starting Create System Files and Directories...505machine # [ 1.823757] systemd[1]: Finished Create System Files and Directories.506machine # [ 1.831256] systemd-udevd[76]: Using default interface naming scheme 'v261'.507machine # [ 1.859131] systemd[1]: Started Rule-based Manager for Device Events and Files.508machine # [ 1.879217] systemd[1]: Finished Coldplug All udev Devices.509machine # [ 1.881904] systemd[1]: Reached target System Initialization.510machine # [ 1.884612] systemd[1]: Reached target Basic System.511machine # [ 2.342162] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12512machine # [ 2.361215] virtio_blk virtio5: 1/0/0 default/read/poll queues513machine # [ 2.371135] serio: i8042 KBD port at 0x60,0x64 irq 1514machine # [ 2.372238] serio: i8042 AUX port at 0x60,0x64 irq 12515machine # [ 2.388689] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)516machine # [ 2.400193] ehci-pci 0000:00:1d.7: EHCI Host Controller517machine # [ 2.403068] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1518machine # [ 2.408275] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000519machine # [ 2.418435] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00520machine # [ 2.419595] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18521machine # [ 2.421058] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1522machine # [ 2.425834] usb usb1: Product: EHCI Host Controller523machine # [ 2.427927] usb usb1: Manufacturer: Linux 6.18.47 ehci_hcd524machine # [ 2.430922] usb usb1: SerialNumber: 0000:00:1d.7525machine # [ 2.433236] hub 1-0:1.0: USB hub found526machine # [ 2.435390] hub 1-0:1.0: 6 ports detected527machine # [ 2.440932] uhci_hcd 0000:00:1d.0: UHCI Host Controller528machine # [ 2.442000] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2529machine # [ 2.452944] uhci_hcd 0000:00:1d.0: detected 2 ports530machine # [ 2.456194] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200531machine # [ 2.472771] SCSI subsystem initialized532machine # [ 2.480182] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18533machine # [ 2.481606] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1534machine # [ 2.500270] usb usb2: Product: UHCI Host Controller535machine # [ 2.512518] usb usb2: Manufacturer: Linux 6.18.47 uhci_hcd536machine # [ 2.313205] systemd[1]: Starting Virtual Console Setup...537machine # [ 2.520222] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0538machine # [ 2.524124] usb usb2: SerialNumber: 0000:00:1d.0539machine # [ 2.327488] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.540machine # [ 2.545677] hub 2-0:1.0: USB hub found541machine # [ 2.551274] hub 2-0:1.0: 2 ports detected542machine # [ 2.350781] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.543machine # [ 2.354533] systemd[1]: Finished Virtual Console Setup.544machine # [ 2.356303] (udev-worker)[92]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.545machine # [ 2.359430] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.546machine # [ 2.578495] uhci_hcd 0000:00:1d.1: UHCI Host Controller547machine # [ 2.579432] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3548machine # [ 2.392541] systemd[1]: Found device /dev/disk/by-label/nixos.549machine # [ 2.393723] systemd[1]: Reached target Initrd Root Device.550machine # [ 2.598602] uhci_hcd 0000:00:1d.1: detected 2 ports551machine # [ 2.599601] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220552machine # [ 2.399122] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...553machine # [ 2.607033] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18554machine # [ 2.613928] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1555machine # [ 2.627098] usb usb3: Product: UHCI Host Controller556machine # [ 2.632167] usb usb3: Manufacturer: Linux 6.18.47 uhci_hcd557machine # [ 2.430456] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks558machine # [ 2.636918] usb usb3: SerialNumber: 0000:00:1d.1559machine # [ 2.640007] hub 3-0:1.0: USB hub found560machine # [ 2.642305] hub 3-0:1.0: 2 ports detected561machine # [ 2.644656] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode562machine # [ 2.649657] uhci_hcd 0000:00:1d.2: UHCI Host Controller563machine # [ 2.651454] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)564machine # [ 2.653477] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4565machine # [ 2.655060] ahci 0000:00:1f.2: flags: 64bit ncq only566machine # [ 2.656287] uhci_hcd 0000:00:1d.2: detected 2 ports567machine # [ 2.660754] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240568machine # [ 2.662992] scsi host0: ahci569machine # [ 2.665038] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18570machine # [ 2.667047] scsi host1: ahci571machine # [ 2.669576] scsi host2: ahci572machine # [ 2.671977] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1573machine # [ 2.674865] scsi host3: ahci574machine # [ 2.675656] usb 1-1: new high-speed USB device number 2 using ehci-pci575machine # [ 2.678406] scsi host4: ahci576machine # [ 2.679672] usb usb4: Product: UHCI Host Controller577machine # [ 2.680926] usb usb4: Manufacturer: Linux 6.18.47 uhci_hcd578machine # [ 2.681871] usb usb4: SerialNumber: 0000:00:1d.2579machine # [ 2.682288] scsi host5: ahci580machine # [ 2.685326] hub 4-0:1.0: USB hub found581machine # [ 2.686317] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1582machine # [ 2.687766] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1583machine # [ 2.689918] hub 4-0:1.0: 2 ports detected584machine # [ 2.700827] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1585machine # [ 2.706976] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1586machine # [ 2.712926] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1587machine # [ 2.715558] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1588machine # [ 2.535316] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.589machine # [ 2.592471] systemd[1]: Mounting /sysroot...590machine # [ 2.804991] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00591machine # [ 2.806818] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10592machine # [ 2.811932] usb 1-1: Product: QEMU USB Tablet593machine # [ 2.812795] usb 1-1: Manufacturer: QEMU594machine # [ 2.815905] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1595machine # [ 2.834068] hid: raw HID events driver (C) Jiri Kosina596machine # [ 3.025720] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)597machine # [ 3.027299] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100598machine # [ 3.028914] ata3.00: applying bridge limits599machine # [ 3.030122] ata3.00: configured for UDMA/100600machine # [ 3.031359] ata1: SATA link down (SStatus 0 SControl 300)601machine # [ 3.032965] ata4: SATA link down (SStatus 0 SControl 300)602machine # [ 3.034435] ata5: SATA link down (SStatus 0 SControl 300)603machine # [ 3.035912] ata2: SATA link down (SStatus 0 SControl 300)604machine # [ 3.037083] ata6: SATA link down (SStatus 0 SControl 300)605machine # [ 3.038915] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5606machine # [ 3.097119] usbcore: registered new interface driver usbhid607machine # [ 3.099183] usbhid: USB HID core driver608machine # [ 3.116679] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2609machine # [ 3.123589] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0610machine # [ 3.130725] EXT4-fs (vda): mounted filesystem f1321948-1fe8-44d3-a9ac-8b6959077f71 r/w with ordered data mode. Quota mode: none.611machine # [ 3.132688] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray612machine # [ 2.933686] systemd[1]: Mounted /sysroot.613machine # [ 2.935902] systemd[1]: Reached target Initrd Root File System.614machine # [ 2.940529] systemd[1]: Starting Mountpoints Configured in the Real Root...615machine # [ 3.146933] cdrom: Uniform CD-ROM driver Revision: 3.20616machine # [ 2.959660] systemd-sysroot-fstab-check[140]: /sysroot should be mounted in the initrd, will request daemon-reload.617machine # [ 2.964644] systemd[1]: Reload requested from client PID 140 ('systemd-sysroot') (unit initrd-parse-etc.service)...618machine # [ 2.968185] systemd[1]: Reloading...619machine # [ 3.053808] systemd[1]: Reloading finished in 86 ms.620machine # [ 3.064386] systemd-sysroot-fstab-check[140]: Requesting initrd-fs.target/start/replace...621machine # [ 3.070201] systemd-sysroot-fstab-check[140]: Requesting swap.target/start/replace...622machine # [ 3.073650] systemd[1]: initrd-parse-etc.service: Deactivated successfully.623machine # [ 3.075661] systemd[1]: Finished Mountpoints Configured in the Real Root.624machine # [ 3.077190] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.625machine # [ 3.080182] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio626machine # [ 3.594134] systemd[1]: Mounting /sysroot/nix/.ro-store...627machine # [ 3.598240] systemd[1]: Mounting /sysroot/nix/.rw-store...628machine # [ 3.606941] systemd[1]: Mounting /sysroot/run...629machine # [ 3.611094] systemd[1]: Mounting /sysroot/tmp/shared...630machine # [ 3.617165] systemd[1]: Mounting /sysroot/tmp/xchg...631machine # [ 3.838467] 9p: Installing v9fs 9p2000 file system support632machine # [ 3.641083] systemd[1]: Mounted /sysroot/nix/.rw-store.633machine # [ 3.646952] systemd[1]: Mounted /sysroot/nix/.ro-store.634machine # [ 3.648483] systemd[1]: Mounted /sysroot/run.635machine # [ 3.654924] systemd[1]: Mounted /sysroot/tmp/shared.636machine # [ 3.657261] systemd[1]: Mounted /sysroot/tmp/xchg.637machine # [ 3.662974] systemd[1]: Starting rw-sysroot-nix-store.service...638machine # [ 3.673866] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.639machine # [ 3.677113] systemd[1]: Finished rw-sysroot-nix-store.service.640machine # [ 4.593162] systemd[1]: Mounting /sysroot/nix/store...641machine # [ 4.615982] systemd[1]: Mounted /sysroot/nix/store.642machine # [ 4.619100] systemd[1]: Reached target Initrd File Systems.643machine # [ 4.620675] systemd[1]: Starting Find NixOS closure...644machine # [ 4.625504] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...645machine # [ 4.643473] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.646machine # [ 4.647701] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.647machine # [ 4.654538] systemd[1]: Finished Find NixOS closure.648machine # [ 4.656786] systemd[1]: Reached target Initrd Default Target.649machine # [ 4.658255] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...650machine # [ 4.671530] systemd[1]: Stopped target Initrd Default Target.651machine # [ 4.672964] systemd[1]: Stopped target Basic System.652machine # [ 4.674313] systemd[1]: Stopped target Initrd Root Device.653machine # [ 4.676155] systemd[1]: Stopped target Path Units.654machine # [ 4.677186] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.655machine # [ 4.678693] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.656machine # [ 4.680630] systemd[1]: Stopped target Slice Units.657machine # [ 4.682232] systemd[1]: Stopped target Socket Units.658machine # [ 4.683578] systemd[1]: Stopped target System Initialization.659machine # [ 4.685247] systemd[1]: Stopped target Swaps.660machine # [ 4.687166] systemd[1]: Stopped target Timer Units.661machine # [ 4.688224] systemd[1]: dbus.socket: Deactivated successfully.662machine # [ 4.689441] systemd[1]: Closed D-Bus System Message Bus Socket.663machine # [ 4.690626] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.664machine # [ 4.692348] systemd[1]: Stopped Find NixOS closure.665machine # [ 4.694220] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio666machine # [ 4.696375] systemd[1]: Starting rw-sysroot-nix-store.service...667machine # [ 4.697630] systemd[1]: systemd-sysctl.service: Deactivated successfully.668machine # [ 4.699279] systemd[1]: Stopped Apply Kernel Variables.669machine # [ 4.701178] systemd[1]: systemd-modules-load.service: Deactivated successfully.670machine # [ 4.702678] systemd[1]: Stopped Load Kernel Modules.671machine # [ 4.703738] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.672machine # [ 4.706133] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.673machine # [ 4.707592] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.674machine # [ 4.708968] systemd[1]: Stopped Create System Files and Directories.675machine # [ 4.710386] systemd[1]: Stopped target Local File Systems.676machine # [ 4.711893] systemd[1]: Stopped target Preparation for Local File Systems.677machine # [ 4.713603] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.678machine # [ 4.716169] systemd[1]: Stopped Coldplug All udev Devices.679machine # [ 4.718874] systemd[1]: Stopping Rule-based Manager for Device Events and Files...680machine # [ 4.720297] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.681machine # [ 4.721677] systemd[1]: Stopped Virtual Console Setup.682machine # [ 4.732350] systemd[1]: initrd-cleanup.service: Deactivated successfully.683machine # [ 4.737117] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.684machine # [ 4.739953] systemd[1]: systemd-udevd.service: Deactivated successfully.685machine # [ 4.742156] systemd[1]: Stopped Rule-based Manager for Device Events and Files.686machine # [ 4.743847] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.687machine # [ 4.745891] systemd[1]: Finished rw-sysroot-nix-store.service.688machine # [ 4.748538] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.689machine # [ 4.750126] systemd[1]: Closed udev Control Socket.690machine # [ 4.752096] systemd[1]: Starting Cleanup udev Database...691machine # [ 4.754197] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.692machine # [ 4.756096] systemd[1]: Stopped Create Static Device Nodes in /dev.693machine # [ 4.757337] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.694machine # [ 4.759147] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.695machine # [ 4.760541] systemd[1]: kmod-static-nodes.service: Deactivated successfully.696machine # [ 4.762165] systemd[1]: Stopped Create List of Static Device Nodes.697machine # [ 4.775160] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.698machine # [ 4.777224] systemd[1]: Finished Cleanup udev Database.699machine # [ 4.778736] systemd[1]: Reached target Switch Root.700machine # [ 4.780632] systemd[1]: Starting NixOS Activation...701machine # [ 4.893924] initrd-nixos-activation-start[193]: booting system configuration /nix/store/r80g44sbsasf7isr3bff6fi04qmnd5f9-nixos-system-machine-test702machine # [ 4.943848] initrd-nixos-activation-start[193]: running activation script...703machine # [ 5.319240] initrd-nixos-activation-start[216]: setting up /etc...704machine # [ 5.552990] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.705machine # [ 5.556096] systemd[1]: Finished NixOS Activation.706machine # [ 5.557701] systemd[1]: Starting Switch Root...707machine # [ 5.574047] systemd[1]: Switching root.708machine # [ 5.906774] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).709machine # [ 6.086520] NET: Registered PF_VSOCK protocol family710machine # [ 6.461033] systemd[1]: systemd 261.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)711machine # [ 6.466036] systemd[1]: Detected virtualization kvm.712machine # [ 6.467029] systemd[1]: Detected architecture x86-64.713machine # [ 6.468025] systemd[1]: Detected first boot.714machine # [ 6.470808] systemd[1]: Initializing machine ID from random generator.715machine # [ 6.649875] systemd[1]: bpf-restrict-fs: LSM BPF program attached716machine # [ 6.746996] systemd[1]: Applying preset policy.717machine # [ 7.091034] systemd[1]: Populated /etc with preset unit settings.718machine # [ 7.482455] systemd[1]: initrd-switch-root.service: Deactivated successfully.719machine # [ 7.484621] systemd[1]: Stopped initrd-switch-root.service.720machine # [ 7.487688] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.721machine # [ 7.490239] systemd[1]: Created slice Slice /system/getty.722machine # [ 7.491897] systemd[1]: Created slice User and Session Slice.723machine # [ 7.493083] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.724machine # [ 7.494618] systemd[1]: Started Forward Password Requests to Wall Directory Watch.725machine # [ 7.496027] systemd[1]: Expecting device /dev/hvc0...726machine # [ 7.496994] systemd[1]: Expecting device /dev/ttyS0...727machine # [ 7.498012] systemd[1]: Reached target Local Encrypted Volumes.728machine # [ 7.499135] systemd[1]: Stopped target initrd-fs.target.729machine # [ 7.500155] systemd[1]: Stopped target initrd-root-fs.target.730machine # [ 7.511355] systemd[1]: Stopped target initrd-switch-root.target.731machine # [ 7.512498] systemd[1]: Reached target Virtual Machines and Containers.732machine # [ 7.513755] systemd[1]: Reached target Path Units.733machine # [ 7.514735] systemd[1]: Reached target Remote File Systems.734machine # [ 7.515872] systemd[1]: Reached target Slice Units.735machine # [ 7.516864] systemd[1]: Reached target Swaps.736machine # [ 7.519574] systemd[1]: Listening on Query the User Interactively for a Password.737machine # [ 7.523022] systemd[1]: Listening on Process Core Dump Socket.738machine # [ 7.525603] systemd[1]: Listening on Credential Encryption/Decryption.739machine # [ 7.531360] systemd[1]: Listening on Factory Reset Management.740machine # [ 7.532852] systemd[1]: Listening on Hostname Service Socket.741machine # [ 7.536593] systemd[1]: Starting Journal Log Access Socket...742machine # [ 7.538333] systemd[1]: Listening on Journal Audit Socket.743machine # [ 7.541580] systemd[1]: Listening on Console Output Muting Service Socket.744machine # [ 7.543027] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.745machine # [ 7.544631] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os746machine # [ 7.546400] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki747machine # [ 7.553241] systemd[1]: Listening on Disk Repartitioning Service Socket.748machine # [ 7.554563] systemd[1]: Listening on udev Control Socket.749machine # [ 7.555741] systemd[1]: Listening on udev Varlink Socket.750machine # [ 7.558885] systemd[1]: Mounting Huge Pages File System...751machine # [ 7.563598] systemd[1]: Mounting POSIX Message Queue File System...752machine # [ 7.569456] systemd[1]: Mounting Kernel Debug File System...753machine # [ 7.574602] systemd[1]: Mounting Kernel Trace File System...754machine # [ 7.583021] systemd[1]: Starting Create List of Static Device Nodes...755machine # [ 7.584869] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio756machine # [ 7.595031] systemd[1]: Starting Load Kernel Module configfs...757machine # [ 7.597297] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm758machine # [ 7.610193] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore759machine # [ 7.613997] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse760machine # [ 7.632113] systemd[1]: Mounting FUSE Control File System...761machine # [ 7.633439] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67762machine # [ 7.644739] systemd[1]: Starting Journal Service...763machine # [ 7.651010] systemd[1]: Starting Load Kernel Modules...764machine # [ 7.664945] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...765machine # [ 7.680422] systemd[1]: Starting Remount Root and Kernel File Systems...766machine # [ 7.688219] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os767machine # [ 7.704462] systemd-journald[286]: Collecting audit messages is enabled.768machine # [ 7.709356] systemd[1]: Starting Coldplug All udev Devices...769machine # [ 7.716255] loop: module loaded770machine # [ 7.737364] systemd[1]: Listening on Journal Log Access Socket.771machine # [ 7.742631] EXT4-fs (vda): re-mounted f1321948-1fe8-44d3-a9ac-8b6959077f71.772machine # [ 7.745013] systemd[1]: Mounted Huge Pages File System.773machine # [ 7.750456] systemd[1]: Mounted POSIX Message Queue File System.774machine # [ 7.550397] systemd[1]: Queued start job for default target Multi-User System.775machine # [ 7.554201] systemd[1]: systemd-journald.service: Deactivated successfully.776machine # [ 7.759287] systemd[1]: Started Journal Service.777machine # [ 7.558563] systemd-modules-load[287]: Inserted module 'loop'778machine # [ 7.561615] systemd[1]: Mounted Kernel Debug File System.779machine # [ 7.566234] systemd[1]: Mounted Kernel Trace File System.780machine # [ 7.571318] systemd[1]: Finished Create List of Static Device Nodes.781machine # [ 7.573715] systemd[1]: modprobe@configfs.service: Deactivated successfully.782machine # [ 7.582470] systemd[1]: Finished Load Kernel Module configfs.783machine # [ 7.585645] systemd[1]: Mounted FUSE Control File System.784machine # [ 7.590459] systemd[1]: Finished Load Kernel Modules.785machine # [ 7.596120] systemd[1]: Finished Remount Root and Kernel File Systems.786machine # [ 7.612123] systemd[1]: Listening on Disk Image Download Service Socket.787machine # [ 7.624096] systemd[1]: Mounting Kernel Configuration File System...788machine # [ 7.628399] systemd[1]: Starting Firewall...789machine # [ 7.641878] systemd[1]: Starting Flush Journal to Persistent Storage...790machine # [ 7.644455] systemd-oomd[289]: No swap; memory pressure usage will be degraded791machine # [ 7.647795] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore792machine # [ 7.662167] systemd[1]: Starting Load/Save OS Random Seed...793machine # [ 7.677865] systemd[1]: Starting Apply Kernel Variables...794machine # [ 7.895862] systemd-journald[286]: Received client request to flush runtime journal.795machine # [ 7.938452] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...796machine # [ 7.943934] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os797machine # [ 7.948098] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.798machine # [ 7.952310] systemd[1]: Mounted Kernel Configuration File System.799machine # [ 7.956117] systemd[1]: Finished Apply Kernel Variables.800machine # [ 7.958322] systemd[1]: Finished Load/Save OS Random Seed.801machine # [ 7.960289] systemd[1]: Reached target First Boot Complete.802machine # [ 7.963543] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.803machine # [ 7.965198] systemd[1]: Starting Create Static Device Nodes in /dev...804machine # [ 7.968624] systemd[1]: Finished Create Static Device Nodes in /dev.805machine # [ 7.969953] systemd[1]: Reached target Preparation for Local File Systems.806machine # [ 7.971718] systemd[1]: Starting Rule-based Manager for Device Events and Files...807machine # [ 7.974449] systemd[1]: Finished Flush Journal to Persistent Storage.808machine # [ 8.024320] systemd-udevd[322]: Using default interface naming scheme 'v261'.809machine # [ 8.031144] systemd[1]: Finished Coldplug All udev Devices.810machine # [ 8.120093] systemd[1]: Started Rule-based Manager for Device Events and Files.811machine # [ 8.284179] systemd[1]: Mounting /run/wrappers...812machine # [ 8.304486] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse813machine # [ 8.323099] systemd[1]: Mounted /run/wrappers.814machine # [ 8.325914] systemd[1]: Reached target Local File Systems.815machine # [ 8.330107] systemd[1]: Listening on Boot Loader Control Service Socket.816machine # [ 8.337084] systemd[1]: Starting register-nix-paths.service...817machine # [ 8.339984] systemd[1]: Starting Create SUID/SGID Wrappers...818machine # [ 8.343198] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.819machine # [ 8.357129] systemd[1]: Starting Save Transient machine-id to Disk...820machine # [ 8.377859] systemd[1]: Starting Create System Files and Directories...821machine # [ 8.537358] systemd[1]: Finished Create System Files and Directories.822machine # [ 8.554427] systemd[1]: Starting Rebuild Journal Catalog...823machine # [ 8.569486] systemd[1]: Starting Record System Boot/Shutdown in UTMP...824machine # [ 8.613436] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.825machine # [ 8.624358] systemd[1]: Finished Save Transient machine-id to Disk.826machine # [ 8.629067] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.827machine # [ 8.706364] systemd[1]: Finished Record System Boot/Shutdown in UTMP.828machine # [ 8.751421] systemd[1]: Finished Rebuild Journal Catalog.829machine # [ 8.754186] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.830machine # [ 8.764381] systemd[1]: Starting Update is Completed...831machine # [ 8.807865] (udev-worker)[375]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.832machine # [ 8.815436] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line.833machine # [ 8.820670] (udev-worker)[375]: Network interface NamePolicy= disabled on kernel command line.834machine # [ 8.854657] systemd[1]: Finished Update is Completed.835machine # [ 9.017627] systemd[1]: Finished Firewall.836machine # [ 9.064587] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.837machine # [ 9.068320] systemd[1]: Finished Create SUID/SGID Wrappers.838machine # [ 9.073440] systemd[1]: Condition check resulted in Virtio network device being skipped.839machine # [ 9.075062] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore840machine # [ 9.080838] systemd[1]: Starting Create SUID/SGID Wrappers...841machine # [ 9.082916] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.842machine # [ 9.085607] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67843machine # [ 9.089126] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore844machine # [ 9.092374] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os845machine # [ 9.095263] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os846machine # [ 9.485869] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3847machine # [ 9.499987] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console848machine # [ 9.512210] mousedev: PS/2 mouse device common for all mice849machine # [ 9.519895] ACPI: button: Power Button [PWRF]850machine # [ 9.633404] rtc_cmos 00:04: RTC can wake from S4851machine # [ 9.677471] parport_pc 00:02: reported by Plug and Play ACPI852machine # [ 9.677622] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]853machine # [ 9.679650] rtc_cmos 00:04: registered as rtc0854machine # [ 9.680090] systemd-journald[286]: Time jumped backwards, rotating.855machine # [ 9.680172] rtc_cmos 00:04: setting system clock to 2026-09-02T18:07:50 UTC (1788372470)856machine # [ 9.579634] systemd[1]: Finished register-nix-paths.service.857machine # [ 9.680329] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs858machine # [ 9.615785] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.859machine # [ 9.706693] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4860machine # [ 9.713537] Console: switching to colour dummy device 80x25861machine # [ 9.622248] systemd[1]: Finished Create SUID/SGID Wrappers.862machine # [ 9.623514] systemd[1]: Reached target System Initialization.863machine # [ 9.626085] systemd[1]: Started Discard unused filesystem blocks once a week.864machine # [ 9.627524] systemd[1]: Started Daily Cleanup of Temporary Directories.865machine # [ 9.629454] systemd[1]: Reached target Timer Units.866machine # [ 9.633210] systemd[1]: Listening on D-Bus System Message Bus Socket.867machine # [ 9.634492] systemd[1]: Listening on Nix Daemon Socket.868machine # [ 9.636145] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.869machine # [ 9.637969] systemd[1]: Reached target Socket Units.870machine # [ 9.639366] systemd[1]: Reached target Basic System.871machine # [ 9.641569] systemd[1]: Started backdoor.service.872machine # [ 9.650104] systemd[1]: Starting Import lastlog data into lastlog2 database...873machine # [ 9.664679] systemd[1]: Starting Name Service Cache Daemon (nsncd)...874machine # [ 9.762156] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized875machine # [ 9.876121] [drm] Found bochs VGA, ID 0xb0c5.876machine # [ 9.876124] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.877machine # [ 9.680377] systemd[1]: Starting Post-Boot Actions...878machine # [ 9.906038] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic879machine # [ 9.708118] systemd[1]: Started Reset console on configuration changes.880machine # [ 9.733450] systemd[1]: Starting resolvconf update...881machine # [ 9.939809] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt882machine # [ 9.940754] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD883machine # [ 9.964751] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0884machine # connecting to host...885machine # [ 9.794561] systemd[1]: Finished Post-Boot Actions.886machine: Guest shell says: b'Spawning backdoor root shell...\n'887machine: connected to guest root shell888machine: (connecting took 10.63 seconds)889machine: (finished: waiting for the VM to finish booting, in 10.63 seconds)890machine # [ 9.824817] systemd[1]: Started Name Service Cache Daemon (nsncd).891machine # [ 9.828986] nsncd[570]: Sep 02 18:07:50.843 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"892machine # [ 9.833482] systemd[1]: Reached target Host and Network Name Lookups.893machine # [ 9.836219] systemd[1]: Reached target User and Group Name Lookups.894machine # [ 9.846192] systemd[1]: Starting D-Bus System Message Bus...895machine # [ 9.883254] systemd[1]: Starting User Login Management...896machine # [ 9.894249] systemd[1]: Finished Import lastlog data into lastlog2 database.897machine # [ 10.104362] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6898machine # [ 10.110233] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5899machine # [ 10.071806] dbus-broker-launch[595]: Looking up NSS user entry for 'systemd-timesync'...900machine # [ 10.096366] systemd[1]: Stopped target Host and Network Name Lookups.901machine # [ 10.098372] dbus-broker-launch[595]: NSS returned no entry for 'systemd-timesync'902machine # [ 10.102356] dbus-broker-launch[595]: Invalid user-name in /nix/store/ps2dn9i3sh78skiwxcpx8s8f6x99vp5a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"903machine # [ 10.107702] systemd[1]: Stopping Host and Network Name Lookups...904machine # [ 10.110676] systemd[1]: Stopped target User and Group Name Lookups.905machine # [ 10.113673] systemd[1]: Stopping User and Group Name Lookups...906machine # [ 10.118670] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...907machine # [ 10.121331] systemd[1]: nscd.service: Deactivated successfully.908machine # [ 10.126850] systemd[1]: Stopped Name Service Cache Daemon (nsncd).909machine # [ 10.417644] Console: switching to colour frame buffer device 160x50910machine # [ 10.479308] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device911machine # [ 10.166321] systemd[1]: Starting Name Service Cache Daemon (nsncd)...912machine # [ 10.282545] systemd[1]: Started D-Bus System Message Bus.913machine # [ 10.287148] dbus-broker-launch[595]: Ready914machine # [ 10.288847] systemd[1]: Started Name Service Cache Daemon (nsncd).915machine # [ 10.293562] nsncd[653]: Sep 02 18:07:51.268 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"916machine # [ 10.298174] systemd[1]: Reached target Host and Network Name Lookups.917machine # [ 10.299532] systemd[1]: Reached target User and Group Name Lookups.918machine # [ 10.300787] systemd[1]: Finished resolvconf update.919machine # [ 10.301853] systemd[1]: Reached target Preparation for Network.920machine # [ 10.305209] systemd[1]: Starting DHCP Client...921machine # [ 10.306524] systemd[1]: Starting Address configuration of eth1...922machine # [ 10.307927] systemd[1]: Starting Extra networking commands....923machine # [ 10.311422] systemd-logind[598]: New seat seat0.924machine # [ 10.324335] systemd-logind[598]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)925machine # [ 10.330204] systemd[1]: Started User Login Management.926machine # [ 10.341192] systemd-logind[598]: Watching system buttons on /dev/input/event2 (Power Button)927machine # [ 10.355211] systemd[1]: Starting linger-users.service...928machine # [ 10.373473] systemd[1]: Starting Virtual Console Setup...929machine # [ 10.493170] systemd[1]: linger-users.service: Deactivated successfully.930machine # [ 10.503304] systemd[1]: Finished linger-users.service.931machine # [ 10.520400] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.932machine # [ 10.524048] network-addresses-eth1-start[678]: adding address 192.168.1.1/24... done933machine # [ 10.525975] systemd[1]: Stopped Virtual Console Setup.934machine # [ 10.551083] network-addresses-eth1-start[678]: adding address 2001:db8:1::1/64... done935machine # [ 10.585320] systemd[1]: Starting Virtual Console Setup...936machine # [ 10.593113] systemd[1]: Finished Address configuration of eth1.937machine # [ 10.814250] ppdev: user-space parallel port driver938machine # [ 10.709687] systemd[1]: Finished Extra networking commands..939machine # [ 10.713740] systemd[1]: Reached target Network.940machine # [ 10.722309] systemd[1]: Starting PostgreSQL Server...941machine # [ 10.735191] systemd[1]: Started Restate durable execution server.942machine # [ 10.744093] systemd-logind[598]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)943machine # [ 10.959630] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)944machine # [ 10.764116] systemd[1]: Starting Permit User Sessions...945machine # [ 10.781371] dhcpcd[702]: dhcpcd-10.3.2 starting946machine # [ 10.796906] dhcpcd[757]: dev: loaded udev947machine # [ 11.055201] 8021q: 802.1Q VLAN Support v1.8948machine # [ 11.061200] 8021q: adding VLAN 0 to HW filter on device eth1949machine # [ 11.062201] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)950machine # [ 10.890718] systemd[1]: Finished Permit User Sessions.951machine # [ 10.919545] systemd[1]: Started Getty on tty1.952machine # [ 10.928776] systemd[1]: Reached target Login Prompts.953machine # [ 11.065891] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.954machine # [ 11.071159] systemd[1]: Stopped Virtual Console Setup.955machine # [ 11.092393] systemd[1]: Starting Virtual Console Setup...956machine # [ 11.415109] cfg80211: Loading compiled-in X.509 certificates for regulatory database957machine # [ 11.454093] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'958machine # [ 11.460099] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'959machine # [ 11.470898] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2960machine # [ 11.472211] cfg80211: failed to load regulatory.db961machine # [ 11.338456] postgresql-pre-start[766]: The files belonging to this database system will be owned by user "postgres".962machine # [ 11.341740] postgresql-pre-start[766]: This user must also own the server process.963machine # [ 11.356746] postgresql-pre-start[766]: The database cluster will be initialized with locale "en_US.UTF-8".964machine # [ 11.358650] postgresql-pre-start[766]: The default database encoding has accordingly been set to "UTF8".965machine # [ 11.360518] postgresql-pre-start[766]: The default text search configuration will be set to "english".966machine # [ 11.362596] postgresql-pre-start[766]: Data page checksums are enabled.967machine # [ 11.364603] postgresql-pre-start[766]: fixing permissions on existing directory /var/lib/postgresql/18 ... ok968machine # [ 11.366506] postgresql-pre-start[766]: creating subdirectories ... ok969machine # [ 11.371697] postgresql-pre-start[766]: selecting dynamic shared memory implementation ... posix970machine # [ 11.659670] kvm_amd: TSC scaling supported971machine # [ 11.663120] kvm_amd: Nested Virtualization enabled972machine # [ 11.664943] kvm_amd: Nested Paging enabled973machine # [ 11.665557] kvm_amd: LBR virtualization supported974machine # [ 11.668582] kvm_amd: Virtual VMLOAD VMSAVE supported975machine # [ 11.673482] 8021q: adding VLAN 0 to HW filter on device eth0976machine # [ 11.471678] dhcpcd[757]: eth0: waiting for carrier977machine # [ 11.478258] dhcpcd[757]: libudev: received NULL device978machine # [ 11.480409] dhcpcd[757]: libudev: received NULL device979machine # [ 11.484091] dhcpcd[757]: eth0: carrier acquired980machine # [ 11.704475] kvm_amd: Virtual GIF supported981machine # [ 11.510704] dhcpcd[757]: DUID 00:01:00:01:32:2b:22:78:52:54:00:12:34:56982machine # [ 11.512134] dhcpcd[757]: eth0: IAID 00:12:34:56983machine # [ 11.514051] dhcpcd[757]: eth0: adding address fe80::5054:ff:fe12:3456984machine # [ 11.578597] postgresql-pre-start[766]: selecting default "max_connections" ... 100985machine # [ 11.593395] systemd-vconsole-setup[768]: Configuration of first virtual console was skipped, ignoring remaining ones.986machine # [ 11.601883] systemd[1]: Finished Virtual Console Setup.987machine # [ 11.661676] restate-server[753]: 2026-09-02T18:07:52.677266Z INFO restate_server988machine # [ 11.663656] restate-server[753]: Starting Restate Server 1.7.3 (v1.7.3 x86_64-unknown-linux-gnu 1980-01-01)989machine # [ 11.667172] restate-server[753]: node_name: "machine"990machine # [ 11.668538] restate-server[753]: config_source: /nix/store/q4576lssxm9j793sqglh43vb1yjpkjvh-restate-keep-failed-temp-test.toml991machine # [ 11.670982] restate-server[753]: base_dir: /var/lib/restate/machine/992machine # [ 11.673198] restate-server[753]: cpus: 1993machine # [ 11.675313] restate-server[753]: on main994machine # [ 11.691981] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.995machine # [ 11.926574] EDAC MC: Ver: 3.0.0996machine # [ 11.766403] postgresql-pre-start[766]: selecting default "shared_buffers" ... 128MB997machine # [ 11.770852] restate-server[753]: 2026-09-02T18:07:52.794667Z ERROR octocrab998machine # [ 11.773244] restate-server[753]: failed with error client error (Connect)999machine # [ 11.775454] restate-server[753]: on rs:worker-01000machine # [ 11.906709] dhcpcd[757]: eth0: soliciting a DHCP lease1001machine # [ 12.126378] NET: Registered PF_PACKET protocol family1002machine # [ 11.927536] dhcpcd[757]: eth0: offered 10.0.2.15 from 10.0.2.21003machine # [ 11.929268] dhcpcd[757]: eth0: probing address 10.0.2.15/241004machine # [ 12.679571] restate-server[753]: 2026-09-02T18:07:53.702833Z INFO restate_core::network::net_util1005machine # [ 12.681722] restate-server[753]: Server listening1006machine # [ 12.683942] restate-server[753]: on rs:worker-01007machine # [ 12.685729] restate-server[753]: in restate_core::network::net_util::server1008machine # [ 12.687969] restate-server[753]: server_name: message-fabric-server1009machine # [ 12.689701] restate-server[753]: uds.path: "machine/fabric.sock"1010machine # [ 12.691283] restate-server[753]: server.address: "127.0.0.1"1011machine # [ 12.692579] restate-server[753]: server.port: 51221012machine # [ 12.693822] restate-server[753]: 2026-09-02T18:07:53.709622Z INFO restate_node::init1013machine # [ 12.696123] restate-server[753]: Trying to join the cluster 'localcluster'1014machine # [ 12.698297] restate-server[753]: on rs:worker-01015machine # [ 12.806197] restate-server[753]: 2026-09-02T18:07:53.829944Z INFO restate_metadata_server::raft::server::member1016machine # [ 12.809223] restate-server[753]: Run as member of the metadata cluster1017machine # [ 12.810780] restate-server[753]: configuration: v1; [N1]1018machine # [ 12.812375] restate-server[753]: on rs:worker-11019machine # [ 12.813690] restate-server[753]: in restate_metadata_server::raft::server::member::run1020machine # [ 12.816202] restate-server[753]: member_id: N1:57b41021machine # [ 12.865214] restate-server[753]: 2026-09-02T18:07:53.888866Z INFO restate_metadata_server::raft::server::member1022machine # [ 12.872227] restate-server[753]: Won metadata cluster leadership1023machine # [ 12.873785] restate-server[753]: on rs:worker-11024machine # [ 12.874857] restate-server[753]: in restate_metadata_server::raft::server::member::run1025machine # [ 12.877840] restate-server[753]: member_id: N1:57b41026machine # [ 12.911801] dhcpcd[757]: eth0: soliciting an IPv6 router1027machine # [ 12.914159] dhcpcd[757]: eth0: Router Advertisement from fe80::21028machine # [ 12.915952] dhcpcd[757]: eth0: adding address fec0::5054:ff:fe12:3456/641029machine # [ 12.917711] dhcpcd[757]: eth0: adding route to fec0::/641030machine # [ 12.919145] dhcpcd[757]: eth0: adding default route via fe80::21031machine # [ 13.011331] restate-server[753]: 2026-09-02T18:07:54.034569Z INFO restate_node1032machine # [ 13.013648] restate-server[753]: Cluster 'localcluster' has been automatically provisioned1033machine # [ 13.016262] restate-server[753]: on rs:worker-01034machine # [ 13.020401] restate-server[753]: 2026-09-02T18:07:54.043496Z INFO restate_node1035machine # [ 13.023429] restate-server[753]: My Node ID is N1:21036machine # [ 13.025259] restate-server[753]: node_name: machine1037machine # [ 13.026461] restate-server[753]: roles: http-ingress | admin | worker | log-server | metadata-server1038machine # [ 13.028306] restate-server[753]: address: http://127.0.0.1:5122/1039machine # [ 13.029983] restate-server[753]: location:1040machine # [ 13.030968] restate-server[753]: nodes_config_version: v21041machine # [ 13.032311] restate-server[753]: cluster_name: localcluster1042machine # [ 13.033632] restate-server[753]: cluster_fingerprint: Some(ClusterFingerprint(12059260879744025911))1043machine # [ 13.035694] restate-server[753]: partition_table_version: v11044machine # [ 13.038876] restate-server[753]: logs_version: v11045machine # [ 13.041176] restate-server[753]: on rs:worker-01046machine # [ 13.096474] restate-server[753]: 2026-09-02T18:07:54.120249Z INFO restate_ingress_http::server1047machine # [ 13.099393] restate-server[753]: Ingress HTTP listening1048machine # [ 13.101080] restate-server[753]: on rs:worker-11049machine # [ 13.102522] restate-server[753]: in restate_ingress_http::server::server1050machine # [ 13.104279] restate-server[753]: server_name: http-ingress-server1051machine # [ 13.105665] restate-server[753]: uds.path: "machine/ingress.sock"1052machine # [ 13.107103] restate-server[753]: server.address: "127.0.0.1"1053machine # [ 13.108565] restate-server[753]: server.port: 80801054machine # [ 13.110229] restate-server[753]: 2026-09-02T18:07:54.132230Z INFO restate_node1055machine # [ 13.112324] restate-server[753]: Restate node roles [http-ingress | admin | worker | log-server | metadata-server] were started1056machine # [ 13.114875] restate-server[753]: on rs:worker-11057machine # [ 13.116380] restate-server[753]: 2026-09-02T18:07:54.132502Z INFO restate_node::failure_detector1058machine # [ 13.119198] restate-server[753]: Failure Detector Started1059machine # [ 13.121762] restate-server[753]: on rs:worker-11060machine # [ 13.157927] restate-server[753]: 2026-09-02T18:07:54.181779Z INFO restate_admin::service1061machine # [ 13.160918] restate-server[753]: Admin API starting on: http://127.0.0.1:9070/1062machine # [ 13.162978] restate-server[753]: on rs:worker-11063machine # [ 13.164541] restate-server[753]: 2026-09-02T18:07:54.184190Z INFO restate_core::network::net_util1064machine # [ 13.166849] restate-server[753]: Server listening1065machine # [ 13.169299] restate-server[753]: on rs:worker-11066machine # [ 13.171233] restate-server[753]: in restate_core::network::net_util::server1067machine # [ 13.173449] restate-server[753]: server_name: admin-api-server1068machine # [ 13.174882] restate-server[753]: uds.path: "machine/admin.sock"1069machine # [ 13.177190] restate-server[753]: server.address: "127.0.0.1"1070machine # [ 13.178315] restate-server[753]: server.port: 90701071machine # [ 13.179435] restate-server[753]: 2026-09-02T18:07:54.197314Z INFO restate_node::failure_detector::node_state1072machine # [ 13.181840] restate-server[753]: N1:2 transitioned from Dead to Alive (gossip-age=0)1073machine # [ 13.184066] restate-server[753]: on rs:worker-11074machine # [ 13.186846] restate-server[753]: 2026-09-02T18:07:54.209319Z INFO restate_admin::cluster_controller::service::cluster_controller_state1075machine # [ 13.189773] restate-server[753]: Cluster controller switching to leader mode1076machine # [ 13.192142] restate-server[753]: on rs:worker-11077machine # [ 13.892760] postgresql-pre-start[766]: selecting default time zone ... UTC1078machine # [ 13.895968] postgresql-pre-start[766]: creating configuration files ... ok1079machine # [ 13.916769] restate-server[753]: 2026-09-02T18:07:54.938551Z INFO restate_worker::partition_processor_manager1080machine # [ 13.920539] restate-server[753]: Reconciling partition processors: starts=[P0(v1 [N1]), P1(v1 [N1]), P2(v1 [N1]), P3(v1 [N1]), P4(v1 [N1]), P5(v1 [N1]), P6(v1 [N1]), P7(v1 [N1]), P8(v1 [N1]), P9(v1 [N1]), P10(v1 [N1]), P11(v1 [N1]), P12(v1 [N1]), P13(v1 [N1]), P14(v1 [N1]), P15(v1 [N1]), P16(v1 [N1]), P17(v1 [N1]), P18(v1 [N1]), P19(v1 [N1]), P20(v1 [N1]), P21(v1 [N1]), P22(v1 [N1]), P23(v1 [N1])] stops=[]1081machine # [ 13.930196] restate-server[753]: on rs:worker-11082machine # [ 14.185203] postgresql-pre-start[766]: running bootstrap script ... ok1083machine # [ 14.843140] postgresql-pre-start[766]: performing post-bootstrap initialization ... ok1084machine # [ 14.981833] restate-server[753]: 2026-09-02T18:07:56.005146Z INFO restate_worker::partition::processor::status1085machine # [ 14.985615] restate-server[753]: Partition 9 started1086machine # [ 14.987402] restate-server[753]: on rt:pp-91087machine # [ 14.988661] restate-server[753]: in restate_worker::partition::run1088machine # [ 14.990431] restate-server[753]: partition_id: 91089machine # [ 15.041131] restate-server[753]: 2026-09-02T18:07:56.064783Z INFO restate_worker::partition::leadership1090machine # [ 15.043884] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1091machine # [ 15.046566] restate-server[753]: partition_id: 91092machine # [ 15.047852] restate-server[753]: leader_epoch: e21093machine # [ 15.049268] restate-server[753]: campaign_duration: 55ms 890µs 64ns1094machine # [ 15.051142] restate-server[753]: on rt:pp-91095machine # [ 15.052309] restate-server[753]: in restate_worker::partition::run1096machine # [ 15.053921] restate-server[753]: partition_id: 91097machine # [ 15.119362] restate-server[753]: 2026-09-02T18:07:56.141117Z INFO restate_worker::partition::processor::status1098machine # [ 15.122856] restate-server[753]: Partition 2 started1099machine # [ 15.124516] restate-server[753]: on rt:pp-21100machine # [ 15.126122] restate-server[753]: in restate_worker::partition::run1101machine # [ 15.127699] restate-server[753]: partition_id: 21102machine # [ 15.128978] restate-server[753]: 2026-09-02T18:07:56.142157Z INFO restate_worker::partition::processor::status1103machine # [ 15.131754] restate-server[753]: Partition 5 started1104machine # [ 15.134130] restate-server[753]: on rt:pp-51105machine # [ 15.137248] restate-server[753]: in restate_worker::partition::run1106machine # [ 15.139380] restate-server[753]: partition_id: 51107machine # [ 15.140482] restate-server[753]: 2026-09-02T18:07:56.142658Z INFO restate_worker::partition::processor::status1108machine # [ 15.142815] restate-server[753]: Partition 1 started1109machine # [ 15.144483] restate-server[753]: on rt:pp-11110machine # [ 15.146201] restate-server[753]: in restate_worker::partition::run1111machine # [ 15.148114] restate-server[753]: partition_id: 11112machine # [ 15.149378] restate-server[753]: 2026-09-02T18:07:56.146191Z INFO restate_worker::partition::processor::status1113machine # [ 15.152124] restate-server[753]: Partition 6 started1114machine # [ 15.153596] restate-server[753]: on rt:pp-61115machine # [ 15.154605] restate-server[753]: in restate_worker::partition::run1116machine # [ 15.157122] restate-server[753]: partition_id: 61117machine # [ 15.204918] restate-server[753]: 2026-09-02T18:07:56.228487Z INFO restate_worker::partition::leadership1118machine # [ 15.208702] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1119machine # [ 15.211743] restate-server[753]: partition_id: 51120machine # [ 15.212724] restate-server[753]: leader_epoch: e21121machine # [ 15.214175] restate-server[753]: campaign_duration: 86ms 250µs 347ns1122machine # [ 15.216053] restate-server[753]: on rt:pp-51123machine # [ 15.217100] restate-server[753]: in restate_worker::partition::run1124machine # [ 15.218550] restate-server[753]: partition_id: 51125machine # [ 15.219752] restate-server[753]: 2026-09-02T18:07:56.228693Z INFO restate_worker::partition::leadership1126machine # [ 15.222199] restate-server[753]: Processor became Leader of epoch e2. Spent 163ms 843µs 729ns as BecomingLeader1127machine # [ 15.225275] restate-server[753]: campaign_duration: 219ms 799µs 444ns1128machine # [ 15.226729] restate-server[753]: partition_id: 91129machine # [ 15.227893] restate-server[753]: on rt:pp-91130machine # [ 15.228887] restate-server[753]: in restate_worker::partition::run1131machine # [ 15.230303] restate-server[753]: partition_id: 91132machine # [ 15.249329] restate-server[753]: 2026-09-02T18:07:56.271413Z INFO restate_worker::partition::leadership1133machine # [ 15.253182] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1134machine # [ 15.256869] restate-server[753]: partition_id: 11135machine # [ 15.259144] restate-server[753]: leader_epoch: e21136machine # [ 15.260369] restate-server[753]: campaign_duration: 128ms 693µs 451ns1137machine # [ 15.262179] restate-server[753]: on rt:pp-11138machine # [ 15.263395] restate-server[753]: in restate_worker::partition::run1139machine # [ 15.265768] restate-server[753]: partition_id: 11140machine # [ 15.266772] restate-server[753]: 2026-09-02T18:07:56.272964Z INFO restate_worker::partition::leadership1141machine # [ 15.269264] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1142machine # [ 15.271564] restate-server[753]: partition_id: 21143machine # [ 15.273099] restate-server[753]: leader_epoch: e21144machine # [ 15.274081] restate-server[753]: campaign_duration: 131ms 731µs 267ns1145machine # [ 15.275498] restate-server[753]: on rt:pp-21146machine # [ 15.277548] restate-server[753]: in restate_worker::partition::run1147machine # [ 15.278988] restate-server[753]: partition_id: 21148machine # [ 15.280883] restate-server[753]: 2026-09-02T18:07:56.304365Z INFO restate_worker::partition::leadership1149machine # [ 15.283166] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1150machine # [ 15.285419] restate-server[753]: partition_id: 61151machine # [ 15.286401] restate-server[753]: leader_epoch: e21152machine # [ 15.287383] restate-server[753]: campaign_duration: 158ms 98µs 585ns1153machine # [ 15.290379] restate-server[753]: on rt:pp-61154machine # [ 15.292773] restate-server[753]: in restate_worker::partition::run1155machine # [ 15.294843] restate-server[753]: partition_id: 61156machine # [ 15.451418] restate-server[753]: 2026-09-02T18:07:56.472873Z INFO restate_worker::partition::processor::status1157machine # [ 15.455747] restate-server[753]: Partition 23 started1158machine # [ 15.457535] restate-server[753]: on rt:pp-231159machine # [ 15.458676] restate-server[753]: in restate_worker::partition::run1160machine # [ 15.461415] restate-server[753]: partition_id: 231161machine # [ 15.467633] restate-server[753]: 2026-09-02T18:07:56.487381Z INFO restate_worker::partition::leadership1162machine # [ 15.471114] restate-server[753]: Processor became Leader of epoch e2. Spent 258ms 834µs 14ns as BecomingLeader1163machine # [ 15.473918] restate-server[753]: campaign_duration: 345ms 146µs 660ns1164machine # [ 15.476105] restate-server[753]: partition_id: 51165machine # [ 15.477778] restate-server[753]: on rt:pp-51166machine # [ 15.479516] restate-server[753]: in restate_worker::partition::run1167machine # [ 15.481953] restate-server[753]: partition_id: 51168machine # [ 15.484169] restate-server[753]: 2026-09-02T18:07:56.489054Z INFO restate_worker::partition::leadership1169machine # [ 15.487144] restate-server[753]: Processor became Leader of epoch e2. Spent 184ms 648µs 607ns as BecomingLeader1170machine # [ 15.489344] restate-server[753]: campaign_duration: 342ms 788µs 539ns1171machine # [ 15.492376] restate-server[753]: partition_id: 61172machine # [ 15.494149] restate-server[753]: on rt:pp-61173machine # [ 15.495314] restate-server[753]: in restate_worker::partition::run1174machine # [ 15.497085] restate-server[753]: partition_id: 61175machine # [ 15.498661] restate-server[753]: 2026-09-02T18:07:56.505709Z INFO restate_worker::partition::leadership1176machine # [ 15.501410] restate-server[753]: Processor became Leader of epoch e2. Spent 232ms 633µs 756ns as BecomingLeader1177machine # [ 15.503611] restate-server[753]: campaign_duration: 364ms 479µs 284ns1178machine # [ 15.504982] restate-server[753]: partition_id: 21179machine # [ 15.506333] restate-server[753]: on rt:pp-21180machine # [ 15.508101] restate-server[753]: in restate_worker::partition::run1181machine # [ 15.509616] restate-server[753]: partition_id: 21182machine # [ 15.529859] restate-server[753]: 2026-09-02T18:07:56.553527Z INFO restate_worker::partition::leadership1183machine # [ 15.533092] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1184machine # [ 15.535849] restate-server[753]: partition_id: 231185machine # [ 15.536952] restate-server[753]: leader_epoch: e21186machine # [ 15.538279] restate-server[753]: campaign_duration: 79ms 822µs 156ns1187machine # [ 15.539871] restate-server[753]: on rt:pp-231188machine # [ 15.540937] restate-server[753]: in restate_worker::partition::run1189machine # [ 15.542404] restate-server[753]: partition_id: 231190machine # [ 15.580663] restate-server[753]: 2026-09-02T18:07:56.604136Z INFO restate_worker::partition::leadership1191machine # [ 15.583738] restate-server[753]: Processor became Leader of epoch e2. Spent 332ms 667µs 700ns as BecomingLeader1192machine # [ 15.585759] restate-server[753]: campaign_duration: 461ms 413µs 951ns1193machine # [ 15.587116] restate-server[753]: partition_id: 11194machine # [ 15.588513] restate-server[753]: on rt:pp-11195machine # [ 15.589620] restate-server[753]: in restate_worker::partition::run1196machine # [ 15.591650] restate-server[753]: partition_id: 11197machine # [ 15.602128] restate-server[753]: 2026-09-02T18:07:56.625996Z INFO restate_worker::partition::processor::status1198machine # [ 15.604674] restate-server[753]: Partition 3 started1199machine # [ 15.606444] restate-server[753]: on rt:pp-31200machine # [ 15.608341] restate-server[753]: in restate_worker::partition::run1201machine # [ 15.609769] restate-server[753]: partition_id: 31202machine # [ 15.654701] restate-server[753]: 2026-09-02T18:07:56.676888Z INFO restate_worker::partition::leadership1203machine # [ 15.657520] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1204machine # [ 15.660532] restate-server[753]: partition_id: 31205machine # [ 15.661692] restate-server[753]: leader_epoch: e21206machine # [ 15.662755] restate-server[753]: campaign_duration: 45ms 157µs 974ns1207machine # [ 15.664388] restate-server[753]: on rt:pp-31208machine # [ 15.665543] restate-server[753]: in restate_worker::partition::run1209machine # [ 15.667499] restate-server[753]: partition_id: 31210machine # [ 15.721479] restate-server[753]: 2026-09-02T18:07:56.744724Z INFO restate_worker::partition::leadership1211machine # [ 15.723744] restate-server[753]: Processor became Leader of epoch e2. Spent 191ms 13µs 103ns as BecomingLeader1212machine # [ 15.725682] restate-server[753]: campaign_duration: 271ms 18µs 523ns1213machine # [ 15.726934] restate-server[753]: partition_id: 231214machine # [ 15.728600] restate-server[753]: on rt:pp-231215machine # [ 15.730129] restate-server[753]: in restate_worker::partition::run1216machine # [ 15.732613] restate-server[753]: partition_id: 231217machine # [ 15.744941] restate-server[753]: 2026-09-02T18:07:56.768129Z INFO restate_worker::partition::processor::status1218machine # [ 15.748047] restate-server[753]: Partition 0 started1219machine # [ 15.750291] restate-server[753]: on rt:pp-01220machine # [ 15.752763] restate-server[753]: in restate_worker::partition::run1221machine # [ 15.755192] restate-server[753]: partition_id: 01222machine # [ 15.756587] restate-server[753]: 2026-09-02T18:07:56.768595Z INFO restate_worker::partition::processor::status1223machine # [ 15.761211] restate-server[753]: Partition 8 started1224machine # [ 15.763206] restate-server[753]: on rt:pp-81225machine # [ 15.764436] restate-server[753]: in restate_worker::partition::run1226machine # [ 15.765922] restate-server[753]: partition_id: 81227machine # [ 15.837405] restate-server[753]: 2026-09-02T18:07:56.860195Z INFO restate_worker::partition::leadership1228machine # [ 15.840565] restate-server[753]: Processor became Leader of epoch e2. Spent 183ms 240µs 607ns as BecomingLeader1229machine # [ 15.842966] restate-server[753]: campaign_duration: 228ms 466µs 467ns1230machine # [ 15.844487] restate-server[753]: partition_id: 31231machine # [ 15.846746] restate-server[753]: on rt:pp-31232machine # [ 15.847893] restate-server[753]: in restate_worker::partition::run1233machine # [ 15.849561] restate-server[753]: partition_id: 31234machine # [ 15.867871] restate-server[753]: 2026-09-02T18:07:56.891259Z INFO restate_worker::partition::leadership1235machine # [ 15.870918] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1236machine # [ 15.873840] restate-server[753]: partition_id: 01237machine # [ 15.874872] restate-server[753]: leader_epoch: e21238machine # [ 15.876207] restate-server[753]: campaign_duration: 123ms 55µs 584ns1239machine # [ 15.877765] restate-server[753]: on rt:pp-01240machine # [ 15.878715] restate-server[753]: in restate_worker::partition::run1241machine # [ 15.880939] restate-server[753]: partition_id: 01242machine # [ 15.881898] restate-server[753]: 2026-09-02T18:07:56.891654Z INFO restate_worker::partition::leadership1243machine # [ 15.884602] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1244machine # [ 15.887413] restate-server[753]: partition_id: 81245machine # [ 15.888451] restate-server[753]: leader_epoch: e21246machine # [ 15.889475] restate-server[753]: campaign_duration: 122ms 991µs 889ns1247machine # [ 15.891219] restate-server[753]: on rt:pp-81248machine # [ 15.892209] restate-server[753]: in restate_worker::partition::run1249machine # [ 15.894456] restate-server[753]: partition_id: 81250machine # [ 15.895769] restate-server[753]: 2026-09-02T18:07:56.908148Z INFO restate_worker::partition::processor::status1251machine # [ 15.898748] restate-server[753]: Partition 7 started1252machine # [ 15.900270] restate-server[753]: on rt:pp-71253machine # [ 15.901289] restate-server[753]: in restate_worker::partition::run1254machine # [ 15.902770] restate-server[753]: partition_id: 71255machine # [ 15.944971] restate-server[753]: 2026-09-02T18:07:56.968466Z INFO restate_worker::partition::leadership1256machine # [ 15.947984] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1257machine # [ 15.952048] restate-server[753]: partition_id: 71258machine # [ 15.953334] restate-server[753]: leader_epoch: e21259machine # [ 15.954362] restate-server[753]: campaign_duration: 60ms 234µs 192ns1260machine # [ 15.955772] restate-server[753]: on rt:pp-71261machine # [ 15.957271] restate-server[753]: in restate_worker::partition::run1262machine # [ 15.958751] restate-server[753]: partition_id: 71263machine # [ 16.029875] restate-server[753]: 2026-09-02T18:07:57.052186Z INFO restate_worker::partition::leadership1264machine # [ 16.033367] restate-server[753]: Processor became Leader of epoch e2. Spent 160ms 482µs 966ns as BecomingLeader1265machine # [ 16.035390] restate-server[753]: campaign_duration: 283ms 522µs 347ns1266machine # [ 16.036749] restate-server[753]: partition_id: 81267machine # [ 16.037919] restate-server[753]: on rt:pp-81268machine # [ 16.038885] restate-server[753]: in restate_worker::partition::run1269machine # [ 16.040355] restate-server[753]: partition_id: 81270machine # [ 16.289359] restate-server[753]: 2026-09-02T18:07:57.312493Z INFO restate_worker::partition::leadership1271machine # [ 16.292417] restate-server[753]: Processor became Leader of epoch e2. Spent 343ms 949µs 22ns as BecomingLeader1272machine # [ 16.295782] restate-server[753]: campaign_duration: 404ms 261µs 715ns1273machine # [ 16.297571] restate-server[753]: partition_id: 71274machine # [ 16.299086] restate-server[753]: on rt:pp-71275machine # [ 16.300320] restate-server[753]: in restate_worker::partition::run1276machine # [ 16.302418] restate-server[753]: partition_id: 71277machine # [ 16.314248] restate-server[753]: 2026-09-02T18:07:57.338102Z INFO restate_worker::partition::processor::status1278machine # [ 16.317929] restate-server[753]: Partition 4 started1279machine # [ 16.319521] restate-server[753]: on rt:pp-41280machine # [ 16.320644] restate-server[753]: in restate_worker::partition::run1281machine # [ 16.322529] restate-server[753]: partition_id: 41282machine # [ 16.387162] restate-server[753]: 2026-09-02T18:07:57.410377Z INFO restate_worker::partition::leadership1283machine # [ 16.389918] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1284machine # [ 16.392374] restate-server[753]: partition_id: 41285machine # [ 16.393415] restate-server[753]: leader_epoch: e21286machine # [ 16.394521] restate-server[753]: campaign_duration: 68ms 917µs 977ns1287machine # [ 16.395932] restate-server[753]: on rt:pp-41288machine # [ 16.396919] restate-server[753]: in restate_worker::partition::run1289machine # [ 16.398356] restate-server[753]: partition_id: 41290machine # [ 16.494914] restate-server[753]: 2026-09-02T18:07:57.518403Z INFO restate_worker::partition::leadership1291machine # [ 16.498334] restate-server[753]: Processor became Leader of epoch e2. Spent 627ms 84µs 168ns as BecomingLeader1292machine # [ 16.500368] restate-server[753]: campaign_duration: 750ms 198µs 140ns1293machine # [ 16.501687] restate-server[753]: partition_id: 01294machine # [ 16.502856] restate-server[753]: on rt:pp-01295machine # [ 16.503869] restate-server[753]: in restate_worker::partition::run1296machine # [ 16.507659] restate-server[753]: partition_id: 01297machine # [ 16.530767] restate-server[753]: 2026-09-02T18:07:57.554260Z INFO restate_worker::partition::processor::status1298machine # [ 16.534134] restate-server[753]: Partition 10 started1299machine # [ 16.535550] restate-server[753]: on rt:pp-101300machine # [ 16.536602] restate-server[753]: in restate_worker::partition::run1301machine # [ 16.538225] restate-server[753]: partition_id: 101302machine # [ 16.648882] restate-server[753]: 2026-09-02T18:07:57.671368Z INFO restate_worker::partition::leadership1303machine # [ 16.652508] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1304machine # [ 16.655593] restate-server[753]: partition_id: 101305machine # [ 16.657473] restate-server[753]: leader_epoch: e21306machine # [ 16.658725] restate-server[753]: campaign_duration: 116ms 998µs 668ns1307machine # [ 16.660334] restate-server[753]: on rt:pp-101308machine # [ 16.661558] restate-server[753]: in restate_worker::partition::run1309machine # [ 16.664066] restate-server[753]: partition_id: 101310machine # [ 16.669358] restate-server[753]: 2026-09-02T18:07:57.690337Z INFO restate_worker::partition::processor::status1311machine # [ 16.672397] restate-server[753]: Partition 13 started1312machine # [ 16.674095] restate-server[753]: on rt:pp-131313machine # [ 16.675202] restate-server[753]: in restate_worker::partition::run1314machine # [ 16.676642] restate-server[753]: partition_id: 131315machine # [ 16.677631] restate-server[753]: 2026-09-02T18:07:57.690958Z INFO restate_worker::partition::leadership1316machine # [ 16.679946] restate-server[753]: Processor became Leader of epoch e2. Spent 280ms 519µs 451ns as BecomingLeader1317machine # [ 16.682431] restate-server[753]: campaign_duration: 349ms 500µs 565ns1318machine # [ 16.683711] restate-server[753]: partition_id: 41319machine # [ 16.684836] restate-server[753]: on rt:pp-41320machine # [ 16.685784] restate-server[753]: in restate_worker::partition::run1321machine # [ 16.687212] restate-server[753]: partition_id: 41322machine # [ 16.731416] restate-server[753]: 2026-09-02T18:07:57.755287Z INFO restate_worker::partition::leadership1323machine # [ 16.734432] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1324machine # [ 16.737232] restate-server[753]: partition_id: 131325machine # [ 16.738423] restate-server[753]: leader_epoch: e21326machine # [ 16.739729] restate-server[753]: campaign_duration: 64ms 880µs 593ns1327machine # [ 16.741190] restate-server[753]: on rt:pp-131328machine # [ 16.742220] restate-server[753]: in restate_worker::partition::run1329machine # [ 16.743725] restate-server[753]: partition_id: 131330machine # [ 16.814440] restate-server[753]: 2026-09-02T18:07:57.836448Z INFO restate_worker::partition::leadership1331machine # [ 16.817311] restate-server[753]: Processor became Leader of epoch e2. Spent 165ms 12µs 34ns as BecomingLeader1332machine # [ 16.819809] restate-server[753]: campaign_duration: 282ms 78µs 588ns1333machine # [ 16.821453] restate-server[753]: partition_id: 101334machine # [ 16.823262] restate-server[753]: on rt:pp-101335machine # [ 16.824654] restate-server[753]: in restate_worker::partition::run1336machine # [ 16.826634] restate-server[753]: partition_id: 101337machine # [ 16.842199] restate-server[753]: 2026-09-02T18:07:57.865586Z INFO restate_worker::partition::processor::status1338machine # [ 16.846172] restate-server[753]: Partition 12 started1339machine # [ 16.847576] restate-server[753]: on rt:pp-121340machine # [ 16.848558] restate-server[753]: in restate_worker::partition::run1341machine # [ 16.849978] restate-server[753]: partition_id: 121342machine # [ 16.863224] dhcpcd[757]: eth0: leased 10.0.2.15 for 86400 seconds1343machine # [ 16.865547] dhcpcd[757]: eth0: adding route to 10.0.2.0/241344machine # [ 16.867458] dhcpcd[757]: eth0: adding default route via 10.0.2.21345machine # [ 16.877381] restate-server[753]: 2026-09-02T18:07:57.900724Z INFO restate_worker::partition::leadership1346machine # [ 16.880055] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1347machine # [ 16.882598] restate-server[753]: partition_id: 121348machine # [ 16.884279] restate-server[753]: leader_epoch: e21349machine # [ 16.885439] restate-server[753]: campaign_duration: 35ms 7µs 522ns1350machine # [ 16.887333] restate-server[753]: on rt:pp-121351machine # [ 16.888567] restate-server[753]: in restate_worker::partition::run1352machine # [ 16.890314] restate-server[753]: partition_id: 121353machine # [ 16.954232] restate-server[753]: 2026-09-02T18:07:57.975900Z INFO restate_worker::partition::leadership1354machine # [ 16.956861] restate-server[753]: Processor became Leader of epoch e2. Spent 212ms 429µs 234ns as BecomingLeader1355machine # [ 16.959131] restate-server[753]: campaign_duration: 285ms 495µs 783ns1356machine # [ 16.960618] restate-server[753]: partition_id: 131357machine # [ 16.962219] restate-server[753]: on rt:pp-131358machine # [ 16.963519] restate-server[753]: in restate_worker::partition::run1359machine # [ 16.965574] restate-server[753]: partition_id: 131360machine # [ 16.990345] restate-server[753]: 2026-09-02T18:07:58.013294Z INFO restate_worker::partition::processor::status1361machine # [ 16.992660] restate-server[753]: Partition 15 started1362machine # [ 16.994130] restate-server[753]: on rt:pp-151363machine # [ 16.995130] restate-server[753]: in restate_worker::partition::run1364machine # [ 16.996758] restate-server[753]: partition_id: 151365machine # [ 17.008385] systemd[1]: Started DHCP Client.1366machine # [ 17.014892] systemd[1]: Reached target Network is Online.1367machine # [ 17.036098] restate-server[753]: 2026-09-02T18:07:58.059350Z INFO restate_worker::partition::leadership1368machine # [ 17.038780] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1369machine # [ 17.041463] restate-server[753]: partition_id: 151370machine # [ 17.043047] restate-server[753]: leader_epoch: e21371machine # [ 17.044281] restate-server[753]: campaign_duration: 37ms 372µs 68ns1372machine # [ 17.047952] restate-server[753]: on rt:pp-151373machine # [ 17.050539] restate-server[753]: in restate_worker::partition::run1374machine # [ 17.052592] restate-server[753]: partition_id: 151375machine # [ 17.117145] restate-server[753]: 2026-09-02T18:07:58.140601Z INFO restate_worker::partition::leadership1376machine # [ 17.120063] restate-server[753]: Processor became Leader of epoch e2. Spent 239ms 815µs 675ns as BecomingLeader1377machine # [ 17.123594] restate-server[753]: campaign_duration: 274ms 882µs 702ns1378machine # [ 17.125548] restate-server[753]: partition_id: 121379machine # [ 17.127352] restate-server[753]: on rt:pp-121380machine # [ 17.128986] restate-server[753]: in restate_worker::partition::run1381machine # [ 17.131097] restate-server[753]: partition_id: 121382machine # [ 17.162839] restate-server[753]: 2026-09-02T18:07:58.185329Z INFO restate_worker::partition::processor::status1383machine # [ 17.165467] restate-server[753]: Partition 11 started1384machine # [ 17.167149] restate-server[753]: on rt:pp-111385machine # [ 17.168299] restate-server[753]: in restate_worker::partition::run1386machine # [ 17.170064] restate-server[753]: partition_id: 111387machine # [ 17.215083] restate-server[753]: 2026-09-02T18:07:58.238165Z INFO restate_worker::partition::leadership1388machine # [ 17.217622] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1389machine # [ 17.219725] restate-server[753]: partition_id: 111390machine # [ 17.220741] restate-server[753]: leader_epoch: e21391machine # [ 17.221818] restate-server[753]: campaign_duration: 52ms 725µs 137ns1392machine # [ 17.223451] restate-server[753]: on rt:pp-111393machine # [ 17.224450] restate-server[753]: in restate_worker::partition::run1394machine # [ 17.225964] restate-server[753]: partition_id: 111395machine # [ 17.286260] restate-server[753]: 2026-09-02T18:07:58.309497Z INFO restate_worker::partition::leadership1396machine # [ 17.288863] restate-server[753]: Processor became Leader of epoch e2. Spent 250ms 78µs 431ns as BecomingLeader1397machine # [ 17.291185] restate-server[753]: campaign_duration: 287ms 520µs 900ns1398machine # [ 17.292601] restate-server[753]: partition_id: 151399machine # [ 17.293923] restate-server[753]: on rt:pp-151400machine # [ 17.295319] restate-server[753]: in restate_worker::partition::run1401machine # [ 17.296932] restate-server[753]: partition_id: 151402machine # [ 17.316162] restate-server[753]: 2026-09-02T18:07:58.338184Z INFO restate_worker::partition::processor::status1403machine # [ 17.319685] restate-server[753]: Partition 16 started1404machine # [ 17.321588] restate-server[753]: on rt:pp-161405machine # [ 17.322934] restate-server[753]: in restate_worker::partition::run1406machine # [ 17.325830] restate-server[753]: partition_id: 161407machine # [ 17.403499] restate-server[753]: 2026-09-02T18:07:58.427233Z INFO restate_worker::partition::leadership1408machine # [ 17.407243] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1409machine # [ 17.411084] restate-server[753]: partition_id: 161410machine # [ 17.412908] restate-server[753]: leader_epoch: e21411machine # [ 17.414533] restate-server[753]: campaign_duration: 88ms 908µs 507ns1412machine # [ 17.416722] restate-server[753]: on rt:pp-161413machine # [ 17.419138] restate-server[753]: in restate_worker::partition::run1414machine # [ 17.421193] restate-server[753]: partition_id: 161415machine # [ 17.426377] restate-server[753]: 2026-09-02T18:07:58.448814Z INFO restate_worker::partition::leadership1416machine # [ 17.428665] restate-server[753]: Processor became Leader of epoch e2. Spent 210ms 574µs 249ns as BecomingLeader1417machine # [ 17.431137] restate-server[753]: campaign_duration: 263ms 374µs 255ns1418machine # [ 17.432910] restate-server[753]: partition_id: 111419machine # [ 17.434456] restate-server[753]: on rt:pp-111420machine # [ 17.435764] restate-server[753]: in restate_worker::partition::run1421machine # [ 17.437775] restate-server[753]: partition_id: 111422machine # [ 17.468662] restate-server[753]: 2026-09-02T18:07:58.492472Z INFO restate_worker::partition::processor::status1423machine # [ 17.472126] restate-server[753]: Partition 17 started1424machine # [ 17.473603] restate-server[753]: on rt:pp-171425machine # [ 17.474639] restate-server[753]: in restate_worker::partition::run1426machine # [ 17.476230] restate-server[753]: partition_id: 171427machine # [ 17.479661] restate-server[753]: 2026-09-02T18:07:58.502860Z INFO restate_worker::partition::processor::status1428machine # [ 17.483118] restate-server[753]: Partition 14 started1429machine # [ 17.484815] restate-server[753]: on rt:pp-141430machine # [ 17.485902] restate-server[753]: in restate_worker::partition::run1431machine # [ 17.487369] restate-server[753]: partition_id: 141432machine # [ 17.510699] restate-server[753]: 2026-09-02T18:07:58.534311Z INFO restate_worker::partition::leadership1433machine # [ 17.513780] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1434machine # [ 17.516968] restate-server[753]: partition_id: 171435machine # [ 17.519575] restate-server[753]: leader_epoch: e21436machine # [ 17.521341] restate-server[753]: campaign_duration: 32ms 192µs 918ns1437machine # [ 17.523251] restate-server[753]: on rt:pp-171438machine # [ 17.524536] restate-server[753]: in restate_worker::partition::run1439machine # [ 17.526867] restate-server[753]: partition_id: 171440machine # [ 17.534729] restate-server[753]: 2026-09-02T18:07:58.557397Z INFO restate_worker::partition::leadership1441machine # [ 17.537643] restate-server[753]: Processor became Leader of epoch e2. Spent 130ms 103µs 966ns as BecomingLeader1442machine # [ 17.540896] restate-server[753]: campaign_duration: 219ms 73µs 374ns1443machine # [ 17.542451] restate-server[753]: partition_id: 161444machine # [ 17.544163] restate-server[753]: on rt:pp-161445machine # [ 17.545606] restate-server[753]: in restate_worker::partition::run1446machine # [ 17.547656] restate-server[753]: partition_id: 161447machine # [ 17.632654] restate-server[753]: 2026-09-02T18:07:58.656540Z INFO restate_worker::partition::leadership1448machine # [ 17.636167] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1449machine # [ 17.638574] restate-server[753]: partition_id: 141450machine # [ 17.640067] restate-server[753]: leader_epoch: e21451machine # [ 17.641665] restate-server[753]: campaign_duration: 153ms 604µs 159ns1452machine # [ 17.644582] restate-server[753]: on rt:pp-141453machine # [ 17.646053] restate-server[753]: in restate_worker::partition::run1454machine # [ 17.648599] restate-server[753]: partition_id: 141455machine # [ 17.654169] restate-server[753]: 2026-09-02T18:07:58.676443Z INFO restate_worker::partition::processor::status1456machine # [ 17.657242] restate-server[753]: Partition 18 started1457machine # [ 17.659353] restate-server[753]: on rt:pp-181458machine # [ 17.662201] restate-server[753]: in restate_worker::partition::run1459machine # [ 17.664959] restate-server[753]: partition_id: 181460machine # [ 17.667543] restate-server[753]: 2026-09-02T18:07:58.688489Z INFO restate_worker::partition::leadership1461machine # [ 17.670594] restate-server[753]: Processor became Leader of epoch e2. Spent 154ms 107µs 17ns as BecomingLeader1462machine # [ 17.672844] restate-server[753]: campaign_duration: 186ms 401µs 344ns1463machine # [ 17.674289] restate-server[753]: partition_id: 171464machine # [ 17.675827] restate-server[753]: on rt:pp-171465machine # [ 17.677256] restate-server[753]: in restate_worker::partition::run1466machine # [ 17.679779] restate-server[753]: partition_id: 171467machine # [ 17.747403] restate-server[753]: 2026-09-02T18:07:58.767976Z INFO restate_worker::partition::leadership1468machine # [ 17.749602] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1469machine # [ 17.752110] restate-server[753]: partition_id: 181470machine # [ 17.754122] restate-server[753]: leader_epoch: e21471machine # [ 17.755172] restate-server[753]: campaign_duration: 91ms 435µs 923ns1472machine # [ 17.756601] restate-server[753]: on rt:pp-181473machine # [ 17.757590] restate-server[753]: in restate_worker::partition::run1474machine # [ 17.759000] restate-server[753]: partition_id: 181475machine # [ 17.824851] restate-server[753]: 2026-09-02T18:07:58.847727Z INFO restate_worker::partition::leadership1476machine # [ 17.827456] restate-server[753]: Processor became Leader of epoch e2. Spent 188ms 417µs 802ns as BecomingLeader1477machine # [ 17.830069] restate-server[753]: campaign_duration: 344ms 789µs 631ns1478machine # [ 17.831645] restate-server[753]: partition_id: 141479machine # [ 17.832867] restate-server[753]: on rt:pp-141480machine # [ 17.833934] restate-server[753]: in restate_worker::partition::run1481machine # [ 17.835477] restate-server[753]: partition_id: 141482machine # [ 17.845504] restate-server[753]: 2026-09-02T18:07:58.868539Z INFO restate_worker::partition::leadership1483machine # [ 17.849189] restate-server[753]: Processor became Leader of epoch e2. Spent 97ms 473µs 3ns as BecomingLeader1484machine # [ 17.851218] restate-server[753]: campaign_duration: 191ms 999µs 821ns1485machine # [ 17.852484] restate-server[753]: partition_id: 181486machine # [ 17.853626] restate-server[753]: on rt:pp-181487machine # [ 17.854840] restate-server[753]: in restate_worker::partition::run1488machine # [ 17.856295] restate-server[753]: partition_id: 181489machine # [ 17.865755] restate-server[753]: 2026-09-02T18:07:58.888199Z INFO restate_worker::partition::processor::status1490machine # [ 17.869580] restate-server[753]: Partition 21 started1491machine # [ 17.871244] restate-server[753]: on rt:pp-211492machine # [ 17.873005] restate-server[753]: in restate_worker::partition::run1493machine # [ 17.875507] restate-server[753]: partition_id: 211494machine # [ 17.898534] restate-server[753]: 2026-09-02T18:07:58.921500Z INFO restate_worker::partition::leadership1495machine # [ 17.900890] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1496machine # [ 17.903200] restate-server[753]: partition_id: 211497machine # [ 17.904166] restate-server[753]: leader_epoch: e21498machine # [ 17.905140] restate-server[753]: campaign_duration: 33ms 229µs 922ns1499machine # [ 17.906928] restate-server[753]: on rt:pp-211500machine # [ 17.907994] restate-server[753]: in restate_worker::partition::run1501machine # [ 17.909554] restate-server[753]: partition_id: 211502machine # [ 17.929773] restate-server[753]: 2026-09-02T18:07:58.953324Z INFO restate_worker::partition::leadership1503machine # [ 17.932126] restate-server[753]: Processor became Leader of epoch e2. Spent 31ms 768µs 284ns as BecomingLeader1504machine # [ 17.934047] restate-server[753]: campaign_duration: 65ms 56µs 313ns1505machine # [ 17.935233] restate-server[753]: partition_id: 211506machine # [ 17.936334] restate-server[753]: on rt:pp-211507machine # [ 17.937296] restate-server[753]: in restate_worker::partition::run1508machine # [ 17.938839] restate-server[753]: partition_id: 211509machine # [ 18.008945] restate-server[753]: 2026-09-02T18:07:59.029304Z INFO restate_worker::partition::processor::status1510machine # [ 18.011329] restate-server[753]: Partition 19 started1511machine # [ 18.012803] restate-server[753]: on rt:pp-191512machine # [ 18.013844] restate-server[753]: in restate_worker::partition::run1513machine # [ 18.015997] restate-server[753]: partition_id: 191514machine # [ 18.017228] restate-server[753]: 2026-09-02T18:07:59.030561Z INFO restate_worker::partition::processor::status1515machine # [ 18.020283] restate-server[753]: Partition 22 started1516machine # [ 18.021649] restate-server[753]: on rt:pp-221517machine # [ 18.023410] restate-server[753]: in restate_worker::partition::run1518machine # [ 18.024928] restate-server[753]: partition_id: 221519machine # [ 18.025994] restate-server[753]: 2026-09-02T18:07:59.032331Z INFO restate_worker::partition::processor::status1520machine # [ 18.028317] restate-server[753]: Partition 20 started1521machine # [ 18.029743] restate-server[753]: on rt:pp-201522machine # [ 18.030758] restate-server[753]: in restate_worker::partition::run1523machine # [ 18.032235] restate-server[753]: partition_id: 201524machine # [ 18.060415] restate-server[753]: 2026-09-02T18:07:59.083438Z INFO restate_worker::partition::leadership1525machine # [ 18.063211] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1526machine # [ 18.066465] restate-server[753]: partition_id: 221527machine # [ 18.067686] restate-server[753]: leader_epoch: e21528machine # [ 18.068875] restate-server[753]: campaign_duration: 52ms 742µs 457ns1529machine # [ 18.070608] restate-server[753]: on rt:pp-221530machine # [ 18.071676] restate-server[753]: in restate_worker::partition::run1531machine # [ 18.073089] restate-server[753]: partition_id: 221532machine # [ 18.110855] restate-server[753]: 2026-09-02T18:07:59.134444Z INFO restate_worker::partition::leadership1533machine # [ 18.114312] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1534machine # [ 18.117407] restate-server[753]: partition_id: 191535machine # [ 18.118859] restate-server[753]: leader_epoch: e21536machine # [ 18.120314] restate-server[753]: campaign_duration: 105ms 62µs 795ns1537machine # [ 18.122101] restate-server[753]: on rt:pp-191538machine # [ 18.123139] restate-server[753]: in restate_worker::partition::run1539machine # [ 18.124535] restate-server[753]: partition_id: 191540machine # [ 18.153782] restate-server[753]: 2026-09-02T18:07:59.176934Z INFO restate_worker::partition::leadership1541machine # [ 18.156213] restate-server[753]: Processor transitioned into BecomingLeader. Will propose a `VersionBarrier` to enable [EnableJournalV2]1542machine # [ 18.158439] restate-server[753]: partition_id: 201543machine # [ 18.159388] restate-server[753]: leader_epoch: e21544machine # [ 18.160310] restate-server[753]: campaign_duration: 144ms 519µs 485ns1545machine # [ 18.161670] restate-server[753]: on rt:pp-201546machine # [ 18.162622] restate-server[753]: in restate_worker::partition::run1547machine # [ 18.164036] restate-server[753]: partition_id: 201548machine # [ 18.177533] restate-server[753]: 2026-09-02T18:07:59.201241Z INFO restate_worker::partition::leadership1549machine # [ 18.180480] restate-server[753]: Processor became Leader of epoch e2. Spent 117ms 529µs 183ns as BecomingLeader1550machine # [ 18.185128] restate-server[753]: campaign_duration: 170ms 548µs 491ns1551machine # [ 18.187474] restate-server[753]: partition_id: 221552machine # [ 18.189337] restate-server[753]: on rt:pp-221553machine # [ 18.190737] restate-server[753]: in restate_worker::partition::run1554machine # [ 18.192741] restate-server[753]: partition_id: 221555machine # [ 18.229978] restate-server[753]: 2026-09-02T18:07:59.253296Z INFO restate_worker::partition::leadership1556machine # [ 18.235081] restate-server[753]: Processor became Leader of epoch e2. Spent 118ms 804µs 485ns as BecomingLeader1557machine # [ 18.238803] restate-server[753]: campaign_duration: 223ms 915µs 889ns1558machine # [ 18.240765] restate-server[753]: partition_id: 191559machine # [ 18.243326] restate-server[753]: on rt:pp-191560machine # [ 18.245178] restate-server[753]: in restate_worker::partition::run1561machine # [ 18.247335] restate-server[753]: partition_id: 191562machine # [ 18.248639] restate-server[753]: 2026-09-02T18:07:59.253870Z INFO restate_worker::partition::leadership1563machine # [ 18.251821] restate-server[753]: Processor became Leader of epoch e2. Spent 76ms 804µs 734ns as BecomingLeader1564machine # [ 18.254062] restate-server[753]: campaign_duration: 221ms 461µs 666ns1565machine # [ 18.255316] restate-server[753]: partition_id: 201566machine # [ 18.256456] restate-server[753]: on rt:pp-201567machine # [ 18.257693] restate-server[753]: in restate_worker::partition::run1568machine # [ 18.259812] restate-server[753]: partition_id: 201569machine # [ 18.364201] postgresql-pre-start[766]: syncing data to disk ... ok1570machine # [ 18.366269] postgresql-pre-start[766]: initdb: warning: enabling "trust" authentication for local connections1571machine # [ 18.368778] postgresql-pre-start[766]: 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.1572machine # [ 18.372386] postgresql-pre-start[766]: Success. You can now start the database server using:1573machine # [ 18.374449] postgresql-pre-start[766]: pg_ctl -D /var/lib/postgresql/18 -l logfile start1574machine # [ 18.547700] postgres[1030]: [1030] LOG: starting PostgreSQL 18.6 on x86_64-pc-linux-gnu, compiled by clang version 21.1.8, 64-bit1575machine # [ 18.552466] postgres[1030]: [1030] LOG: listening on IPv6 address "::1", port 54321576machine # [ 18.554476] postgres[1030]: [1030] LOG: listening on IPv4 address "127.0.0.1", port 54321577machine # [ 18.576486] postgres[1030]: [1030] LOG: listening on Unix socket "/run/postgresql/.s.PGSQL.5432"1578machine # [ 18.631558] postgres[1041]: [1041] LOG: database system was shut down at 2026-09-02 18:07:55 GMT1579machine # [ 18.660361] postgres[1030]: [1030] LOG: database system is ready to accept connections1580machine # [ 18.687884] systemd[1]: Started PostgreSQL Server.1581machine # [ 18.696275] systemd[1]: Starting PostgreSQL Setup Scripts...1582machine # [ 18.977458] postgresql-setup-start[1054]: CREATE DATABASE1583machine # [ 19.019797] postgresql-setup-start[1059]: CREATE ROLE1584machine # [ 19.041290] postgresql-setup-start[1061]: ALTER DATABASE1585machine # [ 19.047798] systemd[1]: Finished PostgreSQL Setup Scripts.1586machine # [ 19.050246] systemd[1]: Reached target PostgreSQL.1587machine # [ 19.054851] systemd[1]: Starting Migrate URL media archive database...1588machine: (finished: waiting for unit postgresql.service, in 20.54 seconds)1589machine: waiting for unit restate.service1590machine: (finished: waiting for unit restate.service, in 0.06 seconds)1591machine: waiting for TCP port 8080 on localhost1592machine # Connection to localhost (127.0.0.1) 8080 port [tcp/http-alt] succeeded!1593machine: (finished: waiting for TCP port 8080 on localhost, in 0.07 seconds)1594machine: waiting for TCP port 9070 on localhost1595machine # Connection to localhost (127.0.0.1) 9070 port [tcp/*] succeeded!1596machine: (finished: waiting for TCP port 9070 on localhost, in 0.04 seconds)1597machine: waiting for success: systemctl show -p Result --value url-media-archive-worker-migrate.service | grep '^success$'1598machine: (finished: waiting for success: systemctl show -p Result --value url-media-archive-worker-migrate.service | grep '^success$', in 0.13 seconds)1599machine: waiting for unit url-media-archive-worker.service1600machine # [ 20.166427] systemd[1]: url-media-archive-worker-migrate.service: Deactivated successfully.1601machine # [ 20.170119] systemd[1]: Finished Migrate URL media archive database.1602machine # [ 20.173722] systemd[1]: url-media-archive-worker-migrate.service: Consumed 469ms CPU time over 1.114s wall clock time, 70.9M memory peak.1603machine # [ 20.185500] systemd[1]: Started URL media archive Restate worker.1604machine # [ 20.193748] systemd[1]: Starting Register URL media archive worker with Restate...1605machine # [ 20.303650] url-media-archive-worker-register-start[1111]: curl: (7) Failed to connect to 127.0.0.1:9080 after 2 ms: Could not connect to server1606machine # [ 20.623896] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:01.643Z] WARN: Accepting requests without validating request signatures; handler access must be restricted1607machine # [ 20.628540] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:01.652Z] WARN: Accepting requests without validating request signatures; handler access must be restricted1608machine: (finished: waiting for unit url-media-archive-worker.service, in 1.16 seconds)1609machine: waiting for TCP port 9080 on localhost1610machine # Connection to localhost (127.0.0.1) 9080 port [tcp/glrpc] succeeded!1611machine: (finished: waiting for TCP port 9080 on localhost, in 0.03 seconds)1612machine: waiting for success: systemctl show -p Result --value url-media-archive-worker-register.service | grep '^success$'1613machine: (finished: waiting for success: systemctl show -p Result --value url-media-archive-worker-register.service | grep '^success$', in 0.05 seconds)1614??? Warning (UserWarning): wait_until_succeeds(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1615 File "/nix/store/n2idrk0jvf991mc9cwbdlyb60018wbhz-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391616machine: waiting for success: curl --fail --silent --show-error --max-time 5 -H 'content-type: application/json' --data '{"source": "example-feed", "sourceKey": "456", "url": "https://example.com/media/456", "metadata": {"author": "user"}}' http://127.0.0.1:8080/UrlMediaArchive/submitDiscoveredUrl > /tmp/accepted-456.json1617??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1618 File "/nix/store/n2idrk0jvf991mc9cwbdlyb60018wbhz-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391619machine # curl: (22) The requested URL returned error: 4041620machine # [ 21.450255] url-media-archive-worker-register-start[1141]: {"id":"dp_13LmF8YOaWpYU6PcLdXpQWt","services":[{"name":"UrlMediaArchive","ty":"Service","handlers":[{"name":"getDiscoveryState","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"startDiscoveryScan","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"status","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"submitJob","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"statusBySource","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"submitUrl","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"drainPending","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"recordDiscoveryPage","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"submitDiscoveredUrl","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}}],"deployment_id":"dp_13LmF8YOaWpYU6PcLdXpQWt","revision":1,"public":true,"idempotency_retention":"1d","journal_retention":"1d","inactivity_timeout":"0s","abort_timeout":"10m","enable_lazy_state":false,"retry_policy":{"initial_interval":"500ms","exponentiation_factor":2.0,"max_attempts":70,"max_interval":"1m","on_max_attempts":"Pause"}},{"name":"UrlMediaArchiveRateLimit","ty":"VirtualObject","handlers":[{"name":"reserve","ty":"Exclusive","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}}],"deployment_id":"dp_13LmF8YOaWpYU6PcLdXpQWt","revision":1,"public":true,"idempotency_retention":"1d","journal_retention":"1d","inactivity_timeout":"0s","abort_timeout":"10m","enable_lazy_state":false,"retry_policy":{"initial_interval":"500ms","exponentiation_factor":2.0,"max_attempts":70,"max_interval":"1m","on_max_attempts":"Pause"}},{"name":"UrlMediaArchiveHostLeaseQueue","ty":"VirtualObject","handlers":[{"name":"acquire","ty":"Exclusive","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"release","ty":"Exclusive","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"drop","ty":"Exclusive","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"status","ty":"Shared","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}}],"deployment_id":"dp_13LmF8YOaWpYU6PcLdXpQWt","revision":1,"public":true,"idempotency_retention":"1d","journal_retention":"1d","inactivity_timeout":"0s","abort_timeout":"10m","enable_lazy_state":false,"retry_policy":{"initial_interval":"500ms","exponentiation_factor":2.0,"max_attempts":70,"max_interval":"1m","on_max_attempts":"Pause"}},{"name":"UrlMediaWorkflow","ty":"Workflow","handlers":[{"name":"run","ty":"Workflow","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}}],"deployment_id":"dp_13LmF8YOaWpYU6PcLdXpQWt","revision":1,"public":true,"idempotency_retention":"1d","workflow_completion_retention":"1d","journal_retention":"1d","inactivity_timeout":"0s","abort_timeout":"10m","enable_lazy_state":false,"retry_policy":{"initial_interval":"500ms","exponentiation_factor":2.0,"max_attempts":70,"max_interval":"1m","on_max_attempts":"Pause"}},{"name":"UrlMediaAttempt","ty":"VirtualObject","handlers":[{"name":"run","ty":"Exclusive","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}},{"name":"status","ty":"Shared","public":true,"input_description":"application/json","output_description":"application/json","retry_policy":{"max_attempts":null}}],"deployment_id":"dp_13LmF8YOaWpYU6PcLdXpQWt","revision":1,"public":true,"idempotency_retention":"1d","journal_retention":"1d","inactivity_timeout":"0s","abort_timeout":"10m","enable_lazy_state":false,"retry_policy":{"initial_interval":"500ms","exponentiation_factor":2.0,"max_attempts":70,"max_interval":"1m","on_max_attempts":"Pause"}}],"min_protocol_version":5,"max_protocol_version":6,"sdk_version":"restate-sdk-typescript/1.14.3"}1621machine # [ 21.533335] systemd[1]: url-media-archive-worker-register.service: Deactivated successfully.1622machine # [ 21.535410] systemd[1]: Finished Register URL media archive worker with Restate.1623machine # [ 21.537633] systemd[1]: url-media-archive-worker-register.service: Consumed 57ms CPU time over 1.333s wall clock time, 3.1M memory peak, 5.7K incoming IP traffic, 1.1K outgoing IP traffic.1624machine # [ 21.541139] systemd[1]: Reached target Multi-User System.1625machine # [ 21.542532] systemd[1]: Startup finished in 932ms (kernel) + 4.903s (initrd) + 15.706s (userspace) = 21.542s.1626machine # [ 22.445219] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.467Z][UrlMediaArchive/submitDiscoveredUrl][inv_14yIIt5bLZx32kt69llzJmsid8J35V9n2p] INFO: Starting invocation.1627machine # [ 22.492439] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.516Z][UrlMediaArchive/submitDiscoveredUrl][inv_14yIIt5bLZx32kt69llzJmsid8J35V9n2p] INFO: Invocation suspended1628machine # [ 22.527874] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.551Z][UrlMediaArchive/submitDiscoveredUrl][inv_14yIIt5bLZx32kt69llzJmsid8J35V9n2p] INFO: Replaying invocation.1629machine # [ 22.539045] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.562Z][UrlMediaArchive/submitDiscoveredUrl][inv_14yIIt5bLZx32kt69llzJmsid8J35V9n2p] INFO: Invocation completed successfully.1630machine: (finished: waiting for success: curl --fail --silent --show-error --max-time 5 -H 'content-type: application/json' --data '{"source": "example-feed", "sourceKey": "456", "url": "https://example.com/media/456", "metadata": {"author": "user"}}' http://127.0.0.1:8080/UrlMediaArchive/submitDiscoveredUrl > /tmp/accepted-456.json, in 1.34 seconds)1631machine: must succeed: cat /tmp/accepted-456.json1632machine # [ 22.630445] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.653Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Starting invocation.1633machine # [ 22.640082] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.662Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Invocation suspended1634machine: (finished: must succeed: cat /tmp/accepted-456.json, in 0.04 seconds)1635machine: waiting for success: curl --fail --silent --show-error --max-time 5 -H 'content-type: application/json' --data '{"source": "example-feed", "sourceKey": "456"}' http://127.0.0.1:8080/UrlMediaArchive/statusBySource | jq -e '.status == "terminal_failed" and .probeStatus == "has_media"'1636machine # [ 22.687849] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.711Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Replaying invocation.1637machine # [ 22.696853] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.719Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Invocation suspended1638machine # [ 22.749474] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.773Z][UrlMediaArchiveHostLeaseQueue/example.com/acquire][inv_1g3bLFi2O82v03fppr4QlTKVHzXimaAjF7] INFO: Starting invocation.1639machine # [ 22.758957] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.782Z][UrlMediaArchiveHostLeaseQueue/example.com/acquire][inv_1g3bLFi2O82v03fppr4QlTKVHzXimaAjF7] INFO: Invocation suspended1640machine # [ 22.767560] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.791Z][UrlMediaArchive/statusBySource][inv_1eTeSxhBmO6P6fLL6IaYeJYv8KgrozXudj] INFO: Starting invocation.1641machine # [ 22.775596] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.799Z][UrlMediaArchive/statusBySource][inv_1eTeSxhBmO6P6fLL6IaYeJYv8KgrozXudj] INFO: Invocation suspended1642machine # [ 22.828144] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.851Z][UrlMediaArchiveHostLeaseQueue/example.com/acquire][inv_1g3bLFi2O82v03fppr4QlTKVHzXimaAjF7] INFO: Replaying invocation.1643machine # [ 22.834994] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.858Z][UrlMediaArchiveHostLeaseQueue/example.com/acquire][inv_1g3bLFi2O82v03fppr4QlTKVHzXimaAjF7] INFO: Invocation completed successfully.1644machine # [ 22.841526] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.865Z][UrlMediaArchive/statusBySource][inv_1eTeSxhBmO6P6fLL6IaYeJYv8KgrozXudj] INFO: Replaying invocation.1645machine # [ 22.845062] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.868Z][UrlMediaArchive/statusBySource][inv_1eTeSxhBmO6P6fLL6IaYeJYv8KgrozXudj] INFO: Invocation completed successfully.1646machine # [ 22.906939] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.930Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Replaying invocation.1647machine # [ 22.917391] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:03.940Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Invocation suspended1648machine # [ 22.977143] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.000Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Starting invocation.1649machine # [ 22.985654] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.009Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1650machine # [ 23.023415] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.046Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1651machine # [ 23.048109] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.071Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1652machine # [ 23.083121] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.106Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1653machine # [ 23.109550] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.133Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1654machine # [ 23.145505] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.169Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1655machine # [ 23.155156] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.178Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1656machine # [ 23.187110] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.210Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1657machine # [ 23.194963] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.218Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1658machine # [ 23.226444] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.250Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1659machine # [ 23.253472] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.276Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1660machine # [ 23.289542] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.313Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1661machine # [ 23.308501] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.331Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1662machine # [ 23.341659] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.365Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1663machine # [ 23.347068] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.370Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation suspended1664machine # [ 23.379399] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.403Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Replaying invocation.1665machine # [ 23.384402] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.408Z][UrlMediaAttempt/pg:abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1eoqz1psnvVu1451Mqyd1eT86MQ6Er36jT] INFO: Invocation completed successfully.1666machine # [ 23.437838] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.461Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Replaying invocation.1667machine # [ 23.442359] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.465Z][UrlMediaWorkflow/abca407f-00d5-4613-a3cf-fe289c5d47d7/run][inv_1atqDNLRPc584Qd9ytZdQNFxHCnOAOoEZf] INFO: Invocation completed successfully.1668machine # [ 23.505522] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.528Z][UrlMediaArchiveHostLeaseQueue/example.com/release][inv_1g3bLFi2O82v07JnCJt6s3rQyVToElhlqp] INFO: Starting invocation.1669machine # [ 23.510811] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.534Z][UrlMediaArchiveHostLeaseQueue/example.com/release][inv_1g3bLFi2O82v07JnCJt6s3rQyVToElhlqp] INFO: Invocation suspended1670machine # [ 23.551563] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.575Z][UrlMediaArchiveHostLeaseQueue/example.com/release][inv_1g3bLFi2O82v07JnCJt6s3rQyVToElhlqp] INFO: Replaying invocation.1671machine # [ 23.557066] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.580Z][UrlMediaArchiveHostLeaseQueue/example.com/release][inv_1g3bLFi2O82v07JnCJt6s3rQyVToElhlqp] INFO: Invocation completed successfully.1672machine # [ 23.961917] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.985Z][UrlMediaArchive/statusBySource][inv_1ailmSYvm0LD0mwheR2l9cMMyAUtDgIThQ] INFO: Starting invocation.1673machine # [ 23.970312] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:04.994Z][UrlMediaArchive/statusBySource][inv_1ailmSYvm0LD0mwheR2l9cMMyAUtDgIThQ] INFO: Invocation suspended1674machine # [ 24.002691] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:05.025Z][UrlMediaArchive/statusBySource][inv_1ailmSYvm0LD0mwheR2l9cMMyAUtDgIThQ] INFO: Replaying invocation.1675machine # [ 24.006757] url-media-archive-worker[1106]: [restate][2026-09-02T18:08:05.030Z][UrlMediaArchive/statusBySource][inv_1ailmSYvm0LD0mwheR2l9cMMyAUtDgIThQ] INFO: Invocation completed successfully.1676machine: (finished: waiting for success: curl --fail --silent --show-error --max-time 5 -H 'content-type: application/json' --data '{"source": "example-feed", "sourceKey": "456"}' http://127.0.0.1:8080/UrlMediaArchive/statusBySource | jq -e '.status == "terminal_failed" and .probeStatus == "has_media"', in 1.39 seconds)1677machine: must succeed: test -f /var/lib/url-media-archive/archive/.tmp/pg_abca407f-00d5-4613-a3cf-fe289c5d47d7/failure-marker.part1678machine: (finished: must succeed: test -f /var/lib/url-media-archive/archive/.tmp/pg_abca407f-00d5-4613-a3cf-fe289c5d47d7/failure-marker.part, in 0.01 seconds)1679(finished: run the VM test script, in 25.18 seconds)1680test script finished in 25.25s1681cleanup1682kill QemuMachine (pid 45)1683machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)1684(finished: cleanup, in 0.16 seconds)