nixbot

1these 16 derivations will be built:2  /nix/store/saac2an3wgh2rb8dipwkkrh10800vz17-system-path.drv3  /nix/store/nh8l157qgjdyxnm8d21vqrc20wchgssp-dbus-1.drv4  /nix/store/n58sicyfzs6jj53vbq09sw3g8jdk16pl-X-Restart-Triggers-dbus-broker.drv5  /nix/store/3rzj138paprf9kjs417cnfdqvap02bnx-unit-dbus-broker.service.drv6  /nix/store/6fpk339vfnk40qabys5day7wzvk414hh-system-units.drv7  /nix/store/50i2z122h67s7w0an4f36fj7yi341aq7-unit-dbus-broker.service.drv8  /nix/store/wlcy70jwgyd1qz9hfpgks29pskxdyybk-user-units.drv9  /nix/store/nf7l2zac6f562zmv7a6v44hkablf80ab-etc.drv10  /nix/store/89sfwvlzxjqwfszrz332h7hlg58c60my-activate.drv11  /nix/store/lzizrd06y4kby7l956l975qp79h1jri8-nixos-system-server-test.drv12  /nix/store/1c0m9yik2g552i7mcqw8w5w6996gi31l-closure-info.drv13  /nix/store/gjcw4rlp4saj4cpaa990v8dy6q6xcv0v-run-nixos-vm.drv14  /nix/store/cypkz5k1saifby136xaf1y6r5jkxb1gw-nixos-vm.drv15  /nix/store/9sjzly1dgvb3sysbai8jykp9yhvkl8jm-driverConfiguration.json.drv16  /nix/store/kjz9rbp42fkg85rw2sms57sya8mb2fmg-nixos-test-driver-k3s.drv17  /nix/store/7v0jbprgss3zwb6fsgl0wysz0342z7d8-vm-test-run-k3s.drv18building '/nix/store/saac2an3wgh2rb8dipwkkrh10800vz17-system-path.drv'19building '/nix/store/saac2an3wgh2rb8dipwkkrh10800vz17-system-path.drv'20system-path> structuredAttrs is enabled21system-path> created 6814 symlinks in user environment22system-path> install-info: warning: no info dir entry in `/nix/store/c140idc6xw7swb1d17piqk9ygm127134-system-path/share/info/notes.info'23building '/nix/store/nh8l157qgjdyxnm8d21vqrc20wchgssp-dbus-1.drv'24building '/nix/store/nh8l157qgjdyxnm8d21vqrc20wchgssp-dbus-1.drv'25building '/nix/store/n58sicyfzs6jj53vbq09sw3g8jdk16pl-X-Restart-Triggers-dbus-broker.drv'26building '/nix/store/n58sicyfzs6jj53vbq09sw3g8jdk16pl-X-Restart-Triggers-dbus-broker.drv'27building '/nix/store/3rzj138paprf9kjs417cnfdqvap02bnx-unit-dbus-broker.service.drv'28building '/nix/store/50i2z122h67s7w0an4f36fj7yi341aq7-unit-dbus-broker.service.drv'29building '/nix/store/3rzj138paprf9kjs417cnfdqvap02bnx-unit-dbus-broker.service.drv'30building '/nix/store/50i2z122h67s7w0an4f36fj7yi341aq7-unit-dbus-broker.service.drv'31building '/nix/store/6fpk339vfnk40qabys5day7wzvk414hh-system-units.drv'32building '/nix/store/wlcy70jwgyd1qz9hfpgks29pskxdyybk-user-units.drv'33building '/nix/store/6fpk339vfnk40qabys5day7wzvk414hh-system-units.drv'34building '/nix/store/wlcy70jwgyd1qz9hfpgks29pskxdyybk-user-units.drv'35building '/nix/store/nf7l2zac6f562zmv7a6v44hkablf80ab-etc.drv'36building '/nix/store/nf7l2zac6f562zmv7a6v44hkablf80ab-etc.drv'37building '/nix/store/89sfwvlzxjqwfszrz332h7hlg58c60my-activate.drv'38building '/nix/store/89sfwvlzxjqwfszrz332h7hlg58c60my-activate.drv'39building '/nix/store/lzizrd06y4kby7l956l975qp79h1jri8-nixos-system-server-test.drv'40building '/nix/store/lzizrd06y4kby7l956l975qp79h1jri8-nixos-system-server-test.drv'41building '/nix/store/1c0m9yik2g552i7mcqw8w5w6996gi31l-closure-info.drv'42building '/nix/store/1c0m9yik2g552i7mcqw8w5w6996gi31l-closure-info.drv'43closure-info> structuredAttrs is enabled44building '/nix/store/gjcw4rlp4saj4cpaa990v8dy6q6xcv0v-run-nixos-vm.drv'45building '/nix/store/gjcw4rlp4saj4cpaa990v8dy6q6xcv0v-run-nixos-vm.drv'46building '/nix/store/cypkz5k1saifby136xaf1y6r5jkxb1gw-nixos-vm.drv'47building '/nix/store/cypkz5k1saifby136xaf1y6r5jkxb1gw-nixos-vm.drv'48building '/nix/store/9sjzly1dgvb3sysbai8jykp9yhvkl8jm-driverConfiguration.json.drv'49building '/nix/store/9sjzly1dgvb3sysbai8jykp9yhvkl8jm-driverConfiguration.json.drv'50building '/nix/store/kjz9rbp42fkg85rw2sms57sya8mb2fmg-nixos-test-driver-k3s.drv'51building '/nix/store/kjz9rbp42fkg85rw2sms57sya8mb2fmg-nixos-test-driver-k3s.drv'52nixos-test-driver-k3s> Running type check (enable/disable: config.skipTypeCheck)53nixos-test-driver-k3s> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck54nixos-test-driver-k3s> All checks passed!55nixos-test-driver-k3s> Linting test script (enable/disable: config.skipLint)56nixos-test-driver-k3s> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint57nixos-test-driver-k3s> All checks passed!58building '/nix/store/7v0jbprgss3zwb6fsgl0wysz0342z7d8-vm-test-run-k3s.drv' on 'ssh-ng://nix@jamie'59building '/nix/store/7v0jbprgss3zwb6fsgl0wysz0342z7d8-vm-test-run-k3s.drv'60vm-test-run-k3s> Machine state will be reset. To keep it, pass --keep-machine-state61vm-test-run-k3s> start all VLans62vm-test-run-k3s> (finished: start all VLans, in 0.00 seconds)63vm-test-run-k3s> Test will time out and terminate in 3600 seconds64vm-test-run-k3s> run the VM test script65vm-test-run-k3s> additionally exposed symbols:66vm-test-run-k3s>     server,67vm-test-run-k3s>     vlan1,68vm-test-run-k3s>     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_ssh69vm-test-run-k3s> start all VMs70vm-test-run-k3s> server: starting vm71vm-test-run-k3s> server # Disk image does not exist, creating the virtualisation disk image...72vm-test-run-k3s> server: QEMU running (pid 13)73vm-test-run-k3s> server # Formatting '/build/vm-state-server/tmp.GxmPOCxbJi', fmt=raw size=429496729674vm-test-run-k3s> server # mke2fs 1.47.3 (8-Jul-2025)75vm-test-run-k3s> server # Discarding device blocks:       0/1048576               done76vm-test-run-k3s> server # Creating filesystem with 1048576 4k blocks and 262144 inodes77vm-test-run-k3s> server # Filesystem UUID: 416e99fd-40e2-4aba-9740-047b07eda01d78vm-test-run-k3s> server # Superblock backups stored on blocks:79vm-test-run-k3s> server # 	32768, 98304, 163840, 229376, 294912, 819200, 88473680vm-test-run-k3s> server # 81vm-test-run-k3s> (finished: start all VMs, in 0.25 seconds)82vm-test-run-k3s> server: waiting for unit k3s83vm-test-run-k3s> server # Allocating group tables:  0/32     done84vm-test-run-k3s> server: waiting for the VM to finish booting85vm-test-run-k3s> server # Writing inode tables:  0/32     done86vm-test-run-k3s> server # Creating journal (16384 blocks): done87vm-test-run-k3s> server # Writing superblocks and filesystem accounting information:  0/32     done88vm-test-run-k3s> server # 89vm-test-run-k3s> server # Virtualisation disk image created.90vm-test-run-k3s> server # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)91vm-test-run-k3s> server # 92vm-test-run-k3s> server # 93vm-test-run-k3s> server # iPXE (http://ipxe.org) 00:03.0 CA00 PCI2.10 PnP PMM+5EFD1920+5EF31920 CA0094vm-test-run-k3s> server # Press Ctrl-B to configure iPXE (PCI 00:03.0)...95vm-test-run-k3s> server # 96vm-test-run-k3s> server # 97vm-test-run-k3s> server # 98vm-test-run-k3s> server # 99vm-test-run-k3s> server # iPXE (http://ipxe.org) 00:09.0 CB00 PCI2.10 PnP PMM 5EFD1920 5EF31920 CB00100vm-test-run-k3s> server # Press Ctrl-B to configure iPXE (PCI 00:09.0)...101vm-test-run-k3s> server # 102vm-test-run-k3s> server # 103vm-test-run-k3s> server # Booting from ROM...104vm-test-run-k3s> server # Probing EDD (edd=off to disable)... ok105vm-test-run-k3s> server # [    0.000000] Linux version 6.18.31 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri May 15 12:52:18 UTC 2026106vm-test-run-k3s> server # [    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/k11rnfsr52fbylyc86ri217f0qlhb0rs-nixos-system-server-test/init regInfo=/nix/store/y7nnikg76jn18hfq54a97ymfzjs2jb8v-closure-info/registration console=ttyS0,115200n8 console=tty0107vm-test-run-k3s> server # [    0.000000] BIOS-provided physical RAM map:108vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable109vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved110vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved111vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000005ffdafff] usable112vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x000000005ffdb000-0x000000005fffffff] reserved113vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved114vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved115vm-test-run-k3s> server # [    0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved116vm-test-run-k3s> server # [    0.000000] NX (Execute Disable) protection: active117vm-test-run-k3s> server # [    0.000000] APIC: Static calls initialized118vm-test-run-k3s> server # [    0.000000] SMBIOS 2.8 present.119vm-test-run-k3s> server # [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/2014120vm-test-run-k3s> server # [    0.000000] DMI: Memory slots populated: 1/1121vm-test-run-k3s> server # [    0.000000] Hypervisor detected: KVM122vm-test-run-k3s> server # [    0.000000] last_pfn = 0x5ffdb max_arch_pfn = 0x10000000000123vm-test-run-k3s> server # [    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00124vm-test-run-k3s> server # [    0.000000] kvm-clock: using sched offset of 506000531 cycles125vm-test-run-k3s> server # [    0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns126vm-test-run-k3s> server # [    0.000005] tsc: Detected 2400.010 MHz processor127vm-test-run-k3s> server # [    0.000814] last_pfn = 0x5ffdb max_arch_pfn = 0x10000000000128vm-test-run-k3s> server # [    0.000851] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs129vm-test-run-k3s> server # [    0.000854] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT130vm-test-run-k3s> server # [    0.002768] found SMP MP-table at [mem 0x000f5470-0x000f547f]131vm-test-run-k3s> server # [    0.002779] Using GB pages for direct mapping132vm-test-run-k3s> server # [    0.002918] RAMDISK: [mem 0x5e504000-0x5ffcffff]133vm-test-run-k3s> server # [    0.002926] ACPI: Early table checksum verification disabled134vm-test-run-k3s> server # [    0.002929] ACPI: RSDP 0x00000000000F5290 000014 (v00 BOCHS )135vm-test-run-k3s> server # [    0.002933] ACPI: RSDT 0x000000005FFE23D9 000034 (v01 BOCHS  BXPC     00000001 BXPC 00000001)136vm-test-run-k3s> server # [    0.002937] ACPI: FACP 0x000000005FFE228D 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)137vm-test-run-k3s> server # [    0.002944] ACPI: DSDT 0x000000005FFE0040 00224D (v01 BOCHS  BXPC     00000001 BXPC 00000001)138vm-test-run-k3s> server # [    0.002946] ACPI: FACS 0x000000005FFE0000 000040139vm-test-run-k3s> server # [    0.002947] ACPI: APIC 0x000000005FFE2301 000078 (v03 BOCHS  BXPC     00000001 BXPC 00000001)140vm-test-run-k3s> server # [    0.002949] ACPI: HPET 0x000000005FFE2379 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)141vm-test-run-k3s> server # [    0.002951] ACPI: WAET 0x000000005FFE23B1 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)142vm-test-run-k3s> server # [    0.002952] ACPI: Reserving FACP table memory at [mem 0x5ffe228d-0x5ffe2300]143vm-test-run-k3s> server # [    0.002953] ACPI: Reserving DSDT table memory at [mem 0x5ffe0040-0x5ffe228c]144vm-test-run-k3s> server # [    0.002954] ACPI: Reserving FACS table memory at [mem 0x5ffe0000-0x5ffe003f]145vm-test-run-k3s> server # [    0.002954] ACPI: Reserving APIC table memory at [mem 0x5ffe2301-0x5ffe2378]146vm-test-run-k3s> server # [    0.002955] ACPI: Reserving HPET table memory at [mem 0x5ffe2379-0x5ffe23b0]147vm-test-run-k3s> server # [    0.002955] ACPI: Reserving WAET table memory at [mem 0x5ffe23b1-0x5ffe23d8]148vm-test-run-k3s> server # [    0.003441] No NUMA configuration found149vm-test-run-k3s> server # [    0.003443] Faking a node at [mem 0x0000000000000000-0x000000005ffdafff]150vm-test-run-k3s> server # [    0.003446] NODE_DATA(0) allocated [mem 0x5ffd5780-0x5ffdacff]151vm-test-run-k3s> server # [    0.005848] Zone ranges:152vm-test-run-k3s> server # [    0.005848]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]153vm-test-run-k3s> server # [    0.005850]   DMA32    [mem 0x0000000001000000-0x000000005ffdafff]154vm-test-run-k3s> server # [    0.005851]   Normal   empty155vm-test-run-k3s> server # [    0.005852]   Device   empty156vm-test-run-k3s> server # [    0.005852] Movable zone start for each node157vm-test-run-k3s> server # [    0.005853] Early memory node ranges158vm-test-run-k3s> server # [    0.005853]   node   0: [mem 0x0000000000001000-0x000000000009efff]159vm-test-run-k3s> server # [    0.005854]   node   0: [mem 0x0000000000100000-0x000000005ffdafff]160vm-test-run-k3s> server # [    0.005855] Initmem setup node 0 [mem 0x0000000000001000-0x000000005ffdafff]161vm-test-run-k3s> server # [    0.005875] On node 0, zone DMA: 1 pages in unavailable ranges162vm-test-run-k3s> server # [    0.006710] On node 0, zone DMA: 97 pages in unavailable ranges163vm-test-run-k3s> server # [    0.037494] On node 0, zone DMA32: 37 pages in unavailable ranges164vm-test-run-k3s> server # [    0.038491] ACPI: PM-Timer IO Port: 0x608165vm-test-run-k3s> server # [    0.038505] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])166vm-test-run-k3s> server # [    0.038540] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23167vm-test-run-k3s> server # [    0.038542] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)168vm-test-run-k3s> server # [    0.038544] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)169vm-test-run-k3s> server # [    0.038545] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)170vm-test-run-k3s> server # [    0.038546] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)171vm-test-run-k3s> server # [    0.038547] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)172vm-test-run-k3s> server # [    0.038549] ACPI: Using ACPI (MADT) for SMP configuration information173vm-test-run-k3s> server # [    0.038550] ACPI: HPET id: 0x8086a201 base: 0xfed00000174vm-test-run-k3s> server # [    0.038554] TSC deadline timer available175vm-test-run-k3s> server # [    0.038559] CPU topo: Max. logical packages:   1176vm-test-run-k3s> server # [    0.038559] CPU topo: Max. logical dies:       1177vm-test-run-k3s> server # [    0.038560] CPU topo: Max. dies per package:   1178vm-test-run-k3s> server # [    0.038563] CPU topo: Max. threads per core:   1179vm-test-run-k3s> server # [    0.038564] CPU topo: Num. cores per package:     1180vm-test-run-k3s> server # [    0.038564] CPU topo: Num. threads per package:   1181vm-test-run-k3s> server # [    0.038564] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs182vm-test-run-k3s> server # [    0.038584] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()183vm-test-run-k3s> server # [    0.038620] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]184vm-test-run-k3s> server # [    0.038621] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]185vm-test-run-k3s> server # [    0.038622] [mem 0x60000000-0xfeffbfff] available for PCI devices186vm-test-run-k3s> server # [    0.038624] Booting paravirtualized kernel on KVM187vm-test-run-k3s> server # [    0.038626] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns188vm-test-run-k3s> server # [    0.043052] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1189vm-test-run-k3s> server # [    0.045552] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152190vm-test-run-k3s> server # [    0.045598] kvm-guest: PV spinlocks disabled, single CPU191vm-test-run-k3s> server # [    0.045600] 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/k11rnfsr52fbylyc86ri217f0qlhb0rs-nixos-system-server-test/init regInfo=/nix/store/y7nnikg76jn18hfq54a97ymfzjs2jb8v-closure-info/registration console=ttyS0,115200n8 console=tty0192vm-test-run-k3s> server # [    0.045690] Unknown kernel command line parameters "regInfo=/nix/store/y7nnikg76jn18hfq54a97ymfzjs2jb8v-closure-info/registration", will be passed to user space.193vm-test-run-k3s> server # [    0.045701] random: crng init done194vm-test-run-k3s> server # [    0.045702] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes195vm-test-run-k3s> server # [    0.048132] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)196vm-test-run-k3s> server # [    0.049351] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)197vm-test-run-k3s> server # [    0.049380] Fallback order for Node 0: 0198vm-test-run-k3s> server # [    0.049383] Built 1 zonelists, mobility grouping on.  Total pages: 393081199vm-test-run-k3s> server # [    0.049383] Policy zone: DMA32200vm-test-run-k3s> server # [    0.051809] mem auto-init: stack:all(zero), heap alloc:on, heap free:off201vm-test-run-k3s> server # [    0.054779] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1202vm-test-run-k3s> server # [    0.059057] allocated 3145728 bytes of page_ext203vm-test-run-k3s> server # [    0.068592] ftrace: allocating 48458 entries in 192 pages204vm-test-run-k3s> server # [    0.068594] ftrace: allocated 192 pages with 2 groups205vm-test-run-k3s> server # [    0.069814] Dynamic Preempt: voluntary206vm-test-run-k3s> server # [    0.070001] rcu: Preemptible hierarchical RCU implementation.207vm-test-run-k3s> server # [    0.070002] rcu: 	RCU event tracing is enabled.208vm-test-run-k3s> server # [    0.070003] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.209vm-test-run-k3s> server # [    0.070004] 	Trampoline variant of Tasks RCU enabled.210vm-test-run-k3s> server # [    0.070004] 	Rude variant of Tasks RCU enabled.211vm-test-run-k3s> server # [    0.070005] 	Tracing variant of Tasks RCU enabled.212vm-test-run-k3s> server # [    0.070005] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.213vm-test-run-k3s> server # [    0.070006] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1214vm-test-run-k3s> server # [    0.070029] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.215vm-test-run-k3s> server # [    0.070030] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.216vm-test-run-k3s> server # [    0.070031] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.217vm-test-run-k3s> server # [    0.074460] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16218vm-test-run-k3s> server # [    0.074752] rcu: srcu_init: Setting srcu_struct sizes based on contention.219vm-test-run-k3s> server # [    0.074854] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)220vm-test-run-k3s> server # [    0.082113] Console: colour VGA+ 80x25221vm-test-run-k3s> server # [    0.082117] printk: legacy console [tty0] enabled222vm-test-run-k3s> server # [    0.122831] printk: legacy console [ttyS0] enabled223vm-test-run-k3s> server # [    0.310059] ACPI: Core revision 20250807224vm-test-run-k3s> server # [    0.311648] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns225vm-test-run-k3s> server # [    0.314414] APIC: Switch to symmetric I/O mode setup226vm-test-run-k3s> server # [    0.316179] x2apic enabled227vm-test-run-k3s> server # [    0.317427] APIC: Switched APIC routing to: physical x2apic228vm-test-run-k3s> server # [    0.320226] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1229vm-test-run-k3s> server # [    0.322050] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns230vm-test-run-k3s> server # [    0.325126] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)231vm-test-run-k3s> server # [    0.327243] x86/cpu: User Mode Instruction Prevention (UMIP) activated232vm-test-run-k3s> server # [    0.328320] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127233vm-test-run-k3s> server # [    0.329125] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0234vm-test-run-k3s> server # [    0.330129] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto235vm-test-run-k3s> server # [    0.331125] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl236vm-test-run-k3s> server # [    0.332125] Transient Scheduler Attacks: Mitigation: Clear CPU buffers237vm-test-run-k3s> server # [    0.334124] Spectre V2 : Mitigation: Enhanced / Automatic IBRS238vm-test-run-k3s> server # [    0.336124] Speculative Return Stack Overflow: Mitigation: Safe RET239vm-test-run-k3s> server # [    0.338124] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization240vm-test-run-k3s> server # [    0.340130] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier241vm-test-run-k3s> server # [    0.341125] active return thunk: srso_alias_return_thunk242vm-test-run-k3s> server # [    0.343150] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'243vm-test-run-k3s> server # [    0.344124] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'244vm-test-run-k3s> server # [    0.346124] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'245vm-test-run-k3s> server # [    0.348124] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'246vm-test-run-k3s> server # [    0.350124] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'247vm-test-run-k3s> server # [    0.352124] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'248vm-test-run-k3s> server # [    0.353124] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'249vm-test-run-k3s> server # [    0.356124] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256250vm-test-run-k3s> server # [    0.357124] x86/fpu: xstate_offset[5]:  832, xstate_sizes[5]:   64251vm-test-run-k3s> server # [    0.359124] x86/fpu: xstate_offset[6]:  896, xstate_sizes[6]:  512252vm-test-run-k3s> server # [    0.361124] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024253vm-test-run-k3s> server # [    0.362124] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]:    8254vm-test-run-k3s> server # [    0.363124] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.255vm-test-run-k3s> server # [    0.396785] Freeing SMP alternatives memory: 44K256vm-test-run-k3s> server # [    0.398126] pid_max: default: 32768 minimum: 301257vm-test-run-k3s> server # [    0.399233] LSM: initializing lsm=capability,landlock,yama,bpf,ima258vm-test-run-k3s> server # [    0.401226] landlock: Up and running.259vm-test-run-k3s> server # [    0.403009] Yama: becoming mindful.260vm-test-run-k3s> server # [    0.404340] LSM support for eBPF active261vm-test-run-k3s> server # [    0.405250] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)262vm-test-run-k3s> server # [    0.407172] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)263vm-test-run-k3s> server # [    0.409651] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)264vm-test-run-k3s> server # [    0.410739] Performance Events: Fam17h+ core perfctr, AMD PMU driver.265vm-test-run-k3s> server # [    0.411129] ... version:                   2266vm-test-run-k3s> server # [    0.412126] ... bit width:                 48267vm-test-run-k3s> server # [    0.413127] ... generic counters:          6268vm-test-run-k3s> server # [    0.414126] ... generic bitmap:            000000000000003f269vm-test-run-k3s> server # [    0.415126] ... fixed-purpose counters:    0270vm-test-run-k3s> server # [    0.416126] ... fixed-purpose bitmap:      0000000000000000271vm-test-run-k3s> server # [    0.417126] ... value mask:                0000ffffffffffff272vm-test-run-k3s> server # [    0.418126] ... max period:                00007fffffffffff273vm-test-run-k3s> server # [    0.419126] ... global_ctrl mask:          000000000000003f274vm-test-run-k3s> server # [    0.420237] signal: max sigframe size: 3376275vm-test-run-k3s> server # [    0.421235] rcu: Hierarchical SRCU implementation.276vm-test-run-k3s> server # [    0.422126] rcu: 	Max phase no-delay instances is 400.277vm-test-run-k3s> server # [    0.427668] smp: Bringing up secondary CPUs ...278vm-test-run-k3s> server # [    0.428143] smp: Brought up 1 node, 1 CPU279vm-test-run-k3s> server # [    0.429129] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)280vm-test-run-k3s> server # [    0.430382] Memory: 1456224K/1572324K available (17070K kernel code, 2717K rwdata, 13492K rodata, 3636K init, 3028K bss, 107692K reserved, 0K cma-reserved)281vm-test-run-k3s> server # [    0.431465] devtmpfs: initialized282vm-test-run-k3s> server # [    0.432357] x86/mm: Memory block size: 128MB283vm-test-run-k3s> server # [    0.434224] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns284vm-test-run-k3s> server # [    0.435159] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)285vm-test-run-k3s> server # [    0.436156] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).286vm-test-run-k3s> server # [    0.437226] pinctrl core: initialized pinctrl subsystem287vm-test-run-k3s> server # [    0.438501] PM: RTC time: 06:44:17, date: 2026-06-22288vm-test-run-k3s> server # [    0.442520] NET: Registered PF_NETLINK/PF_ROUTE protocol family289vm-test-run-k3s> server # [    0.443709] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations290vm-test-run-k3s> server # [    0.444152] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations291vm-test-run-k3s> server # [    0.445445] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations292vm-test-run-k3s> server # [    0.446138] audit: initializing netlink subsys (disabled)293vm-test-run-k3s> server # [    0.447451] thermal_sys: Registered thermal governor 'fair_share'294vm-test-run-k3s> server # [    0.447453] thermal_sys: Registered thermal governor 'bang_bang'295vm-test-run-k3s> server # [    0.448130] audit: type=2000 audit(1782110657.483:1): state=initialized audit_enabled=0 res=1296vm-test-run-k3s> server # [    0.450130] thermal_sys: Registered thermal governor 'step_wise'297vm-test-run-k3s> server # [    0.450132] thermal_sys: Registered thermal governor 'user_space'298vm-test-run-k3s> server # [    0.451127] thermal_sys: Registered thermal governor 'power_allocator'299vm-test-run-k3s> server # [    0.452144] cpuidle: using governor menu300vm-test-run-k3s> server # [    0.455335] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5301vm-test-run-k3s> server # [    0.456420] PCI: Using configuration type 1 for base access302vm-test-run-k3s> server # [    0.457126] PCI: Using configuration type 1 for extended access303vm-test-run-k3s> server # [    0.458371] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.304vm-test-run-k3s> server # [    0.484156] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages305vm-test-run-k3s> server # [    0.485127] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page306vm-test-run-k3s> server # [    0.486127] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages307vm-test-run-k3s> server # [    0.487126] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page308vm-test-run-k3s> server # [    0.494735] ACPI: Added _OSI(Module Device)309vm-test-run-k3s> server # [    0.495140] ACPI: Added _OSI(Processor Device)310vm-test-run-k3s> server # [    0.496687] ACPI: Added _OSI(Processor Aggregator Device)311vm-test-run-k3s> server # [    0.499007] ACPI: 1 ACPI AML tables successfully acquired and loaded312vm-test-run-k3s> server # [    0.501390] ACPI: Interpreter enabled313vm-test-run-k3s> server # [    0.502143] ACPI: PM: (supports S0 S3 S4 S5)314vm-test-run-k3s> server # [    0.503127] ACPI: Using IOAPIC for interrupt routing315vm-test-run-k3s> server # [    0.504164] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug316vm-test-run-k3s> server # [    0.505126] PCI: Using E820 reservations for host bridge windows317vm-test-run-k3s> server # [    0.506284] ACPI: Enabled 2 GPEs in block 00 to 0F318vm-test-run-k3s> server # [    0.511676] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])319vm-test-run-k3s> server # [    0.512133] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]320vm-test-run-k3s> server # [    0.513779] acpiphp: Slot [3] registered321vm-test-run-k3s> server # [    0.514180] acpiphp: Slot [4] registered322vm-test-run-k3s> server # [    0.515191] acpiphp: Slot [5] registered323vm-test-run-k3s> server # [    0.516180] acpiphp: Slot [6] registered324vm-test-run-k3s> server # [    0.517172] acpiphp: Slot [7] registered325vm-test-run-k3s> server # [    0.518193] acpiphp: Slot [8] registered326vm-test-run-k3s> server # [    0.519219] acpiphp: Slot [9] registered327vm-test-run-k3s> server # [    0.520172] acpiphp: Slot [10] registered328vm-test-run-k3s> server # [    0.521183] acpiphp: Slot [11] registered329vm-test-run-k3s> server # [    0.522218] acpiphp: Slot [12] registered330vm-test-run-k3s> server # [    0.523175] acpiphp: Slot [13] registered331vm-test-run-k3s> server # [    0.524160] acpiphp: Slot [14] registered332vm-test-run-k3s> server # [    0.525160] acpiphp: Slot [15] registered333vm-test-run-k3s> server # [    0.526177] acpiphp: Slot [16] registered334vm-test-run-k3s> server # [    0.527160] acpiphp: Slot [17] registered335vm-test-run-k3s> server # [    0.528159] acpiphp: Slot [18] registered336vm-test-run-k3s> server # [    0.529160] acpiphp: Slot [19] registered337vm-test-run-k3s> server # [    0.530174] acpiphp: Slot [20] registered338vm-test-run-k3s> server # [    0.531161] acpiphp: Slot [21] registered339vm-test-run-k3s> server # [    0.532160] acpiphp: Slot [22] registered340vm-test-run-k3s> server # [    0.533176] acpiphp: Slot [23] registered341vm-test-run-k3s> server # [    0.534161] acpiphp: Slot [24] registered342vm-test-run-k3s> server # [    0.535160] acpiphp: Slot [25] registered343vm-test-run-k3s> server # [    0.536160] acpiphp: Slot [26] registered344vm-test-run-k3s> server # [    0.537173] acpiphp: Slot [27] registered345vm-test-run-k3s> server # [    0.538175] acpiphp: Slot [28] registered346vm-test-run-k3s> server # [    0.539181] acpiphp: Slot [29] registered347vm-test-run-k3s> server # [    0.540161] acpiphp: Slot [30] registered348vm-test-run-k3s> server # [    0.541175] acpiphp: Slot [31] registered349vm-test-run-k3s> server # [    0.542148] PCI host bridge to bus 0000:00350vm-test-run-k3s> server # [    0.543134] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]351vm-test-run-k3s> server # [    0.544127] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]352vm-test-run-k3s> server # [    0.545127] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]353vm-test-run-k3s> server # [    0.546128] pci_bus 0000:00: root bus resource [mem 0x60000000-0xfebfffff window]354vm-test-run-k3s> server # [    0.547127] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]355vm-test-run-k3s> server # [    0.548128] pci_bus 0000:00: root bus resource [bus 00-ff]356vm-test-run-k3s> server # [    0.549519] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint357vm-test-run-k3s> server # [    0.551081] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint358vm-test-run-k3s> server # [    0.553086] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint359vm-test-run-k3s> server # [    0.555724] pci 0000:00:01.1: BAR 4 [io  0xc1e0-0xc1ef]360vm-test-run-k3s> server # [    0.556193] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk361vm-test-run-k3s> server # [    0.557127] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk362vm-test-run-k3s> server # [    0.558127] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk363vm-test-run-k3s> server # [    0.559127] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk364vm-test-run-k3s> server # [    0.560477] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint365vm-test-run-k3s> server # [    0.562194] pci 0000:00:01.2: BAR 4 [io  0xc100-0xc11f]366vm-test-run-k3s> server # [    0.564436] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint367vm-test-run-k3s> server # [    0.565926] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI368vm-test-run-k3s> server # [    0.567142] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB369vm-test-run-k3s> server # [    0.568570] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint370vm-test-run-k3s> server # [    0.571211] pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]371vm-test-run-k3s> server # [    0.572154] pci 0000:00:02.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]372vm-test-run-k3s> server # [    0.573181] pci 0000:00:02.0: ROM [mem 0xfebc0000-0xfebcffff pref]373vm-test-run-k3s> server # [    0.574383] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]374vm-test-run-k3s> server # [    0.576321] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint375vm-test-run-k3s> server # [    0.579165] pci 0000:00:03.0: BAR 0 [io  0xc120-0xc13f]376vm-test-run-k3s> server # [    0.580142] pci 0000:00:03.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]377vm-test-run-k3s> server # [    0.581181] pci 0000:00:03.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]378vm-test-run-k3s> server # [    0.582141] pci 0000:00:03.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]379vm-test-run-k3s> server # [    0.585414] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint380vm-test-run-k3s> server # [    0.588167] pci 0000:00:04.0: BAR 0 [io  0xc140-0xc15f]381vm-test-run-k3s> server # [    0.589142] pci 0000:00:04.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]382vm-test-run-k3s> server # [    0.590181] pci 0000:00:04.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]383vm-test-run-k3s> server # [    0.593253] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint384vm-test-run-k3s> server # [    0.596407] pci 0000:00:05.0: BAR 0 [io  0xc080-0xc0bf]385vm-test-run-k3s> server # [    0.598142] pci 0000:00:05.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]386vm-test-run-k3s> server # [    0.599180] pci 0000:00:05.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]387vm-test-run-k3s> server # [    0.602167] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint388vm-test-run-k3s> server # [    0.605179] pci 0000:00:06.0: BAR 0 [io  0xc160-0xc17f]389vm-test-run-k3s> server # [    0.606142] pci 0000:00:06.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]390vm-test-run-k3s> server # [    0.607181] pci 0000:00:06.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]391vm-test-run-k3s> server # [    0.610167] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint392vm-test-run-k3s> server # [    0.613379] pci 0000:00:07.0: BAR 0 [io  0xc180-0xc19f]393vm-test-run-k3s> server # [    0.614142] pci 0000:00:07.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]394vm-test-run-k3s> server # [    0.616209] pci 0000:00:07.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]395vm-test-run-k3s> server # [    0.619219] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint396vm-test-run-k3s> server # [    0.622224] pci 0000:00:08.0: BAR 0 [io  0xc000-0xc07f]397vm-test-run-k3s> server # [    0.623141] pci 0000:00:08.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]398vm-test-run-k3s> server # [    0.624181] pci 0000:00:08.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]399vm-test-run-k3s> server # [    0.627802] pci 0000:00:09.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint400vm-test-run-k3s> server # [    0.631165] pci 0000:00:09.0: BAR 0 [io  0xc1a0-0xc1bf]401vm-test-run-k3s> server # [    0.632141] pci 0000:00:09.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]402vm-test-run-k3s> server # [    0.633181] pci 0000:00:09.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]403vm-test-run-k3s> server # [    0.634143] pci 0000:00:09.0: ROM [mem 0xfeb80000-0xfebbffff pref]404vm-test-run-k3s> server # [    0.637154] pci 0000:00:0a.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint405vm-test-run-k3s> server # [    0.639833] pci 0000:00:0a.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]406vm-test-run-k3s> server # [    0.641182] pci 0000:00:0a.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]407vm-test-run-k3s> server # [    0.644205] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint408vm-test-run-k3s> server # [    0.647142] pci 0000:00:0b.0: BAR 0 [io  0xc0c0-0xc0ff]409vm-test-run-k3s> server # [    0.649141] pci 0000:00:0b.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]410vm-test-run-k3s> server # [    0.650181] pci 0000:00:0b.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]411vm-test-run-k3s> server # [    0.653193] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint412vm-test-run-k3s> server # [    0.656460] pci 0000:00:0c.0: BAR 0 [io  0xc1c0-0xc1df]413vm-test-run-k3s> server # [    0.657142] pci 0000:00:0c.0: BAR 1 [mem 0xfebda000-0xfebdafff]414vm-test-run-k3s> server # [    0.658180] pci 0000:00:0c.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]415vm-test-run-k3s> server # [    0.666887] ACPI: PCI: Interrupt link LNKA configured for IRQ 10416vm-test-run-k3s> server # [    0.668373] ACPI: PCI: Interrupt link LNKB configured for IRQ 10417vm-test-run-k3s> server # [    0.669328] ACPI: PCI: Interrupt link LNKC configured for IRQ 11418vm-test-run-k3s> server # [    0.670324] ACPI: PCI: Interrupt link LNKD configured for IRQ 11419vm-test-run-k3s> server # [    0.671235] ACPI: PCI: Interrupt link LNKS configured for IRQ 9420vm-test-run-k3s> server # [    0.673306] iommu: Default domain type: Translated421vm-test-run-k3s> server # [    0.674126] iommu: DMA domain TLB invalidation policy: lazy mode422vm-test-run-k3s> server # [    0.675437] ACPI: bus type USB registered423vm-test-run-k3s> server # [    0.676209] usbcore: registered new interface driver usbfs424vm-test-run-k3s> server # [    0.677150] usbcore: registered new interface driver hub425vm-test-run-k3s> server # [    0.678150] usbcore: registered new device driver usb426vm-test-run-k3s> server # [    0.680174] NetLabel: Initializing427vm-test-run-k3s> server # [    0.681126] NetLabel:  domain hash size = 128428vm-test-run-k3s> server # [    0.682126] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO429vm-test-run-k3s> server # [    0.683195] NetLabel:  unlabeled traffic allowed by default430vm-test-run-k3s> server # [    0.684145] PCI: Using ACPI for IRQ routing431vm-test-run-k3s> server # [    0.685778] pci 0000:00:02.0: vgaarb: setting as boot VGA device432vm-test-run-k3s> server # [    0.686121] pci 0000:00:02.0: vgaarb: bridge control possible433vm-test-run-k3s> server # [    0.686121] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none434vm-test-run-k3s> server # [    0.686129] vgaarb: loaded435vm-test-run-k3s> server # [    0.687299] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0436vm-test-run-k3s> server # [    0.688126] hpet0: 3 comparators, 64-bit 100.000000 MHz counter437vm-test-run-k3s> server # [    0.692215] clocksource: Switched to clocksource kvm-clock438vm-test-run-k3s> server # [    0.696589] VFS: Disk quotas dquot_6.6.0439vm-test-run-k3s> server # [    0.697900] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)440vm-test-run-k3s> server # [    0.700269] pnp: PnP ACPI init441vm-test-run-k3s> server # [    0.701934] pnp: PnP ACPI: found 6 devices442vm-test-run-k3s> server # [    0.710311] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns443vm-test-run-k3s> server # [    0.713010] clocksource: Switched to clocksource acpi_pm444vm-test-run-k3s> server # [    0.714798] NET: Registered PF_INET protocol family445vm-test-run-k3s> server # [    0.716789] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)446vm-test-run-k3s> server # [    0.735934] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)447vm-test-run-k3s> server # [    0.738606] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)448vm-test-run-k3s> server # [    0.740977] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)449vm-test-run-k3s> server # [    0.743508] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)450vm-test-run-k3s> server # [    0.745775] TCP: Hash tables configured (established 16384 bind 16384)451vm-test-run-k3s> server # [    0.747840] MPTCP token hash table entries: 2048 (order: 4, 49152 bytes, linear)452vm-test-run-k3s> server # [    0.750148] UDP hash table entries: 1024 (order: 4, 65536 bytes, linear)453vm-test-run-k3s> server # [    0.752203] UDP-Lite hash table entries: 1024 (order: 4, 65536 bytes, linear)454vm-test-run-k3s> server # [    0.754502] NET: Registered PF_UNIX/PF_LOCAL protocol family455vm-test-run-k3s> server # [    0.756270] NET: Registered PF_XDP protocol family456vm-test-run-k3s> server # [    0.757854] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]457vm-test-run-k3s> server # [    0.759751] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]458vm-test-run-k3s> server # [    0.761644] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]459vm-test-run-k3s> server # [    0.763717] pci_bus 0000:00: resource 7 [mem 0x60000000-0xfebfffff window]460vm-test-run-k3s> server # [    0.765796] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]461vm-test-run-k3s> server # [    0.767997] pci 0000:00:01.0: PIIX3: Enabling Passive Release462vm-test-run-k3s> server # [    0.769820] pci 0000:00:00.0: Limiting direct PCI/PCI transfers463vm-test-run-k3s> server # [    0.773217] ACPI: \_SB_.LNKD: Enabled at IRQ 11464vm-test-run-k3s> server # [    0.776763] PCI: CLS 0 bytes, default 64465vm-test-run-k3s> server # [    0.778449] Trying to unpack rootfs image as initramfs...466vm-test-run-k3s> server # [    0.783738] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns467vm-test-run-k3s> server # [    0.827917] Initialise system trusted keyrings468vm-test-run-k3s> server # [    0.832645] workingset: timestamp_bits=40 max_order=19 bucket_order=0469vm-test-run-k3s> server # [    0.858457] Key type asymmetric registered470vm-test-run-k3s> server # [    0.861428] Asymmetric key parser 'x509' registered471vm-test-run-k3s> server # [    0.865504] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)472vm-test-run-k3s> server # [    0.872537] io scheduler mq-deadline registered473vm-test-run-k3s> server # [    0.873992] io scheduler kyber registered474vm-test-run-k3s> server # [    0.878976] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled475vm-test-run-k3s> server # [    0.884811] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A476vm-test-run-k3s> server # [    0.894481] Linux agpgart interface v0.103477vm-test-run-k3s> server # [    0.897464] ACPI: bus type drm_connector registered478vm-test-run-k3s> server # [    0.901911] usbcore: registered new interface driver usbserial_generic479vm-test-run-k3s> server # [    0.908430] usbserial: USB Serial support registered for generic480vm-test-run-k3s> server # [    0.910267] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.481vm-test-run-k3s> server # [    0.910267] Please enable it if your BIOS has the CPPC option.482vm-test-run-k3s> server # [    0.914408] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled483vm-test-run-k3s> server # [    0.923570] drop_monitor: Initializing network drop monitor service484vm-test-run-k3s> server # [    0.928612] NET: Registered PF_INET6 protocol family485vm-test-run-k3s> server # [    0.933900] Segment Routing with IPv6486vm-test-run-k3s> server # [    0.937436] In-situ OAM (IOAM) with IPv6487vm-test-run-k3s> server # [    0.940839] IPI shorthand broadcast: enabled488vm-test-run-k3s> server # [    0.951073] sched_clock: Marking stable (707030648, 243363013)->(1162409162, -212015501)489vm-test-run-k3s> server # [    0.958747] registered taskstats version 1490vm-test-run-k3s> server # [    0.961729] Loading compiled-in X.509 certificates491vm-test-run-k3s> server # [    0.983455] Demotion targets for Node 0: null492vm-test-run-k3s> server # [    0.986604] Key type .fscrypt registered493vm-test-run-k3s> server # [    0.989426] Key type fscrypt-provisioning registered494vm-test-run-k3s> server # [    0.992550] ima: No TPM chip found, activating TPM-bypass!495vm-test-run-k3s> server # [    0.996626] ima: Allocated hash algorithm: sha1496vm-test-run-k3s> server # [    0.999511] ima: No architecture policies found497vm-test-run-k3s> server # [    1.003730] PM:   Magic number: 14:970:721498vm-test-run-k3s> server # [    1.007240] RAS: Correctable Errors collector initialized.499vm-test-run-k3s> server # [    1.019449] clk: Disabling unused clocks500vm-test-run-k3s> server # [    1.021410] PM: genpd: Disabling unused power domains501vm-test-run-k3s> server # [    1.145733] Freeing initrd memory: 27440K502vm-test-run-k3s> server # [    1.149818] Freeing unused decrypted memory: 2028K503vm-test-run-k3s> server # [    1.153479] Freeing unused kernel image (initmem) memory: 3636K504vm-test-run-k3s> server # [    1.155460] Write protecting the kernel read-only data: 32768k505vm-test-run-k3s> server # [    1.158398] Freeing unused kernel image (text/rodata gap) memory: 1360K506vm-test-run-k3s> server # [    1.161030] Freeing unused kernel image (rodata/data gap) memory: 844K507vm-test-run-k3s> server # [    1.213309] x86/mm: Checked W+X mappings: passed, no W+X pages found.508vm-test-run-k3s> server # [    1.215431] Run /init as init process509vm-test-run-k3s> server # [    1.227502] systemd[1]: Inserted module 'autofs4'510vm-test-run-k3s> server # [    1.244107] fuse: init (API version 7.45)511vm-test-run-k3s> server # [    1.251319] ACPI: \_SB_.LNKC: Enabled at IRQ 10512vm-test-run-k3s> server # [    1.261456] ACPI: \_SB_.LNKA: Enabled at IRQ 10513vm-test-run-k3s> server # [    1.266304] ACPI: \_SB_.LNKB: Enabled at IRQ 11514vm-test-run-k3s> server # [    1.309462] systemd[1]: Successfully made /usr/ read-only.515vm-test-run-k3s> server # [    1.650051] systemd[1]: systemd 260.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)516vm-test-run-k3s> server # [    1.672160] systemd[1]: Detected virtualization kvm.517vm-test-run-k3s> server # [    1.676496] systemd[1]: Detected architecture x86-64.518vm-test-run-k3s> server # [    1.680488] systemd[1]: Running in initrd.519vm-test-run-k3s> server # [    1.684872] systemd[1]: Initializing machine ID from random generator.520vm-test-run-k3s> server # [    1.690315] systemd[1]: Hostname set to <server>.521vm-test-run-k3s> server # [    1.754267] systemd[1]: Queued start job for default target Initrd Default Target.522vm-test-run-k3s> server # [    1.815151] systemd[1]: Created slice Slice /system/modprobe.523vm-test-run-k3s> server # [    1.817448] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.524vm-test-run-k3s> server # [    1.819981] systemd[1]: Expecting device /dev/disk/by-label/nixos...525vm-test-run-k3s> server # [    1.822060] systemd[1]: Reached target Path Units.526vm-test-run-k3s> server # [    1.823722] systemd[1]: Reached target Slice Units.527vm-test-run-k3s> server # [    1.825452] systemd[1]: Reached target Swaps.528vm-test-run-k3s> server # [    1.826940] systemd[1]: Reached target Timer Units.529vm-test-run-k3s> server # [    1.828804] systemd[1]: Listening on D-Bus System Message Bus Socket.530vm-test-run-k3s> server # [    1.831046] systemd[1]: Listening on Journal Socket (/dev/log).531vm-test-run-k3s> server # [    1.833211] systemd[1]: Listening on Journal Sockets.532vm-test-run-k3s> server # [    1.835109] systemd[1]: Listening on udev Control Socket.533vm-test-run-k3s> server # [    1.837067] systemd[1]: Listening on udev Kernel Socket.534vm-test-run-k3s> server # [    1.838885] systemd[1]: Reached target Socket Units.535vm-test-run-k3s> server # [    1.843502] systemd[1]: Starting Create List of Static Device Nodes...536vm-test-run-k3s> server # [    1.851102] systemd[1]: Starting Load Kernel Module 9pnet_virtio...537vm-test-run-k3s> server # [    1.861502] systemd[1]: Starting Load Kernel Module configfs...538vm-test-run-k3s> server # [    1.879555] systemd[1]: Starting Journal Service...539vm-test-run-k3s> server # [    1.893553] systemd[1]: Starting Load Kernel Modules...540vm-test-run-k3s> server # [    1.901183] netfs: FS-Cache loaded541vm-test-run-k3s> server # [    1.902662] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki542vm-test-run-k3s> server # [    1.917785] 9pnet: Installing 9P2000 support543vm-test-run-k3s> server # [    1.922557] systemd[1]: Starting Coldplug All udev Devices...544vm-test-run-k3s> server # [    1.944315] systemd-journald[125]: Collecting audit messages is disabled.545vm-test-run-k3s> server # [    1.958104] systemd[1]: Finished Create List of Static Device Nodes.546vm-test-run-k3s> server # [    1.968177] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.547vm-test-run-k3s> server # [    1.988025] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.548vm-test-run-k3s> server # [    2.004448] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev549vm-test-run-k3s> server # [    2.032318] systemd[1]: Finished Load Kernel Module 9pnet_virtio.550vm-test-run-k3s> server # [    2.043035] systemd[1]: modprobe@configfs.service: Deactivated successfully.551vm-test-run-k3s> server # [    2.053126] systemd[1]: Finished Load Kernel Module configfs.552vm-test-run-k3s> server # [    2.067530] systemd[1]: Finished Load Kernel Modules.553vm-test-run-k3s> server # [    2.074240] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config554vm-test-run-k3s> server # [    2.092502] systemd[1]: Starting Apply Kernel Variables...555vm-test-run-k3s> server # [    2.110521] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...556vm-test-run-k3s> server # [    2.138561] systemd[1]: Finished Apply Kernel Variables.557vm-test-run-k3s> server # [    2.155600] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.558vm-test-run-k3s> server # [    2.169539] systemd[1]: Starting Create Static Device Nodes in /dev...559vm-test-run-k3s> server # [    1.931076] systemd-modules-load[127]: Inserted module 'dm_mod'560vm-test-run-k3s> server # [    1.933531] systemd-modules-load[127]: Inserted module 'virtio_balloon'561vm-test-run-k3s> server # [    1.937632] systemd-modules-load[127]: Inserted module 'virtio_gpu'562vm-test-run-k3s> server # [    2.186990] systemd[1]: Started Journal Service.563vm-test-run-k3s> server # [    1.969216] systemd[1]: Finished Create Static Device Nodes in /dev.564vm-test-run-k3s> server # [    1.974159] systemd[1]: Reached target Preparation for Local File Systems.565vm-test-run-k3s> server # [    1.977444] systemd[1]: Reached target Local File Systems.566vm-test-run-k3s> server # [    1.980222] systemd[1]: Starting Create System Files and Directories...567vm-test-run-k3s> server # [    1.990109] systemd[1]: Starting Rule-based Manager for Device Events and Files...568vm-test-run-k3s> server # [    2.022596] systemd[1]: Finished Create System Files and Directories.569vm-test-run-k3s> server # [    2.043324] systemd-udevd[161]: Using default interface naming scheme 'v260'.570vm-test-run-k3s> server # [    2.081109] systemd[1]: Started Rule-based Manager for Device Events and Files.571vm-test-run-k3s> server # [    2.143879] systemd[1]: Finished Coldplug All udev Devices.572vm-test-run-k3s> server # [    2.145590] systemd[1]: Reached target System Initialization.573vm-test-run-k3s> server # [    2.147419] systemd[1]: Reached target Basic System.574vm-test-run-k3s> server # [    2.686487] uhci_hcd 0000:00:01.2: UHCI Host Controller575vm-test-run-k3s> server # [    2.699994] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1576vm-test-run-k3s> server # [    2.710873] SCSI subsystem initialized577vm-test-run-k3s> server # [    2.721046] uhci_hcd 0000:00:01.2: detected 2 ports578vm-test-run-k3s> server # [    2.723657] uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c100579vm-test-run-k3s> server # [    2.735463] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12580vm-test-run-k3s> server # [    2.739199] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18581vm-test-run-k3s> server # [    2.741183] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1582vm-test-run-k3s> server # [    2.742965] usb usb1: Product: UHCI Host Controller583vm-test-run-k3s> server # [    2.744190] usb usb1: Manufacturer: Linux 6.18.31 uhci_hcd584vm-test-run-k3s> server # [    2.745586] usb usb1: SerialNumber: 0000:00:01.2585vm-test-run-k3s> server # [    2.757702] serio: i8042 KBD port at 0x60,0x64 irq 1586vm-test-run-k3s> server # [    2.769191] virtio_blk virtio5: 1/0/0 default/read/poll queues587vm-test-run-k3s> server # [    2.776772] serio: i8042 AUX port at 0x60,0x64 irq 12588vm-test-run-k3s> server # [    2.793190] virtio_blk virtio5: [vda] 8388608 512-byte logical blocks (4.29 GB/4.00 GiB)589vm-test-run-k3s> server # [    2.813353] hub 1-0:1.0: USB hub found590vm-test-run-k3s> server # [    2.814957] hub 1-0:1.0: 2 ports detected591vm-test-run-k3s> server # [    2.634064] systemd[1]: Starting Virtual Console Setup...592vm-test-run-k3s> server # [    2.651895] (udev-worker)[167]: Network interface NamePolicy= disabled on kernel command line.593vm-test-run-k3s> server # [    2.914152] scsi host0: ata_piix594vm-test-run-k3s> server # [    2.674568] systemd-vconsole-setup[182]: Configuration of first virtual console was skipped, ignoring remaining ones.595vm-test-run-k3s> server # [    2.683163] (udev-worker)[171]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.596vm-test-run-k3s> server # [    2.687445] (udev-worker)[171]: Network interface NamePolicy= disabled on kernel command line.597vm-test-run-k3s> server # [    2.693288] systemd[1]: Finished Virtual Console Setup.598vm-test-run-k3s> server # [    2.939636] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0599vm-test-run-k3s> server # [    2.943018] scsi host1: ata_piix600vm-test-run-k3s> server # [    2.952012] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0601vm-test-run-k3s> server # [    2.954003] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0602vm-test-run-k3s> server # [    2.742454] systemd[1]: Found device /dev/disk/by-label/nixos.603vm-test-run-k3s> server # [    2.744401] systemd[1]: Reached target Initrd Root Device.604vm-test-run-k3s> server # [    2.748197] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...605vm-test-run-k3s> server # [    2.771463] systemd-fsck[196]: nixos: clean, 12/262144 files, 37198/1048576 blocks606vm-test-run-k3s> server # [    2.779832] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.607vm-test-run-k3s> server # [    2.786629] systemd[1]: Mounting /sysroot...608vm-test-run-k3s> server # [    3.046426] usb 1-1: new full-speed USB device number 2 using uhci_hcd609vm-test-run-k3s> server # [    3.111102] ata2: found unknown device (class 0)610vm-test-run-k3s> server # [    3.113469] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100611vm-test-run-k3s> server # [    3.117409] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5612vm-test-run-k3s> server # [    3.173021] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray613vm-test-run-k3s> server # [    3.174672] cdrom: Uniform CD-ROM driver Revision: 3.20614vm-test-run-k3s> server # [    3.218347] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00615vm-test-run-k3s> server # [    3.220445] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10616vm-test-run-k3s> server # [    3.222221] usb 1-1: Product: QEMU USB Tablet617vm-test-run-k3s> server # [    3.223334] usb 1-1: Manufacturer: QEMU618vm-test-run-k3s> server # [    3.224396] usb 1-1: SerialNumber: 28754-0000:00:01.2-1619vm-test-run-k3s> server # [    3.257115] EXT4-fs (vda): mounted filesystem 416e99fd-40e2-4aba-9740-047b07eda01d r/w with ordered data mode. Quota mode: none.620vm-test-run-k3s> server # [    3.024157] systemd[1]: Mounted /sysroot.621vm-test-run-k3s> server # [    3.025979] systemd[1]: Reached target Initrd Root File System.622vm-test-run-k3s> server # [    3.031691] systemd[1]: Starting Mountpoints Configured in the Real Root...623vm-test-run-k3s> server # [    3.294141] hid: raw HID events driver (C) Jiri Kosina624vm-test-run-k3s> server # [    3.057533] systemd-sysroot-fstab-check[212]: /sysroot should be mounted in the initrd, will request daemon-reload.625vm-test-run-k3s> server # [    3.065488] systemd[1]: Reload requested from client PID 212 ('systemd-sysroot') (unit initrd-parse-etc.service)...626vm-test-run-k3s> server # [    3.068352] systemd[1]: Reloading...627vm-test-run-k3s> server # [    3.333447] usbcore: registered new interface driver usbhid628vm-test-run-k3s> server # [    3.334863] usbhid: USB HID core driver629vm-test-run-k3s> server # [    3.357850] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2630vm-test-run-k3s> server # [    3.365434] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0631vm-test-run-k3s> server # [    3.333088] systemd[1]: Reloading finished in 268 ms.632vm-test-run-k3s> server # [    3.349838] systemd-sysroot-fstab-check[212]: Requesting initrd-fs.target/start/replace...633vm-test-run-k3s> server # [    3.405955] systemd-sysroot-fstab-check[212]: Requesting swap.target/start/replace...634vm-test-run-k3s> server # [    3.412102] systemd[1]: initrd-parse-etc.service: Deactivated successfully.635vm-test-run-k3s> server # [    3.416131] systemd[1]: Finished Mountpoints Configured in the Real Root.636vm-test-run-k3s> server # [    3.418051] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.637vm-test-run-k3s> server # [    3.423407] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio638vm-test-run-k3s> server # [    3.716644] systemd[1]: Mounting /sysroot/nix/.ro-store...639vm-test-run-k3s> server # [    3.731313] systemd[1]: Mounting /sysroot/nix/.rw-store...640vm-test-run-k3s> server # [    3.750241] systemd[1]: Mounting /sysroot/run...641vm-test-run-k3s> server # [    3.763569] systemd[1]: Mounting /sysroot/tmp/shared...642vm-test-run-k3s> server # [    3.780243] systemd[1]: Mounting /sysroot/tmp/xchg...643vm-test-run-k3s> server # [    4.036734] 9p: Installing v9fs 9p2000 file system support644vm-test-run-k3s> server # [    3.813137] systemd[1]: Mounted /sysroot/nix/.ro-store.645vm-test-run-k3s> server # [    3.814673] systemd[1]: Mounted /sysroot/nix/.rw-store.646vm-test-run-k3s> server # [    3.817206] systemd[1]: Mounted /sysroot/run.647vm-test-run-k3s> server # [    3.822705] systemd[1]: Mounted /sysroot/tmp/shared.648vm-test-run-k3s> server # [    3.827118] systemd[1]: Mounted /sysroot/tmp/xchg.649vm-test-run-k3s> server # [    3.833534] systemd[1]: Starting rw-sysroot-nix-store.service...650vm-test-run-k3s> server # [    3.844950] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.651vm-test-run-k3s> server # [    3.848849] systemd[1]: Finished rw-sysroot-nix-store.service.652vm-test-run-k3s> server # [    3.856331] systemd[1]: Mounting /sysroot/nix/store...653vm-test-run-k3s> server # [    3.878099] systemd[1]: Mounted /sysroot/nix/store.654vm-test-run-k3s> server # [    3.880068] systemd[1]: Reached target Initrd File Systems.655vm-test-run-k3s> server # [    3.884123] systemd[1]: Starting Find NixOS closure...656vm-test-run-k3s> server # [    3.888378] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...657vm-test-run-k3s> server # [    3.913590] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.658vm-test-run-k3s> server # [    3.921967] systemd[1]: Finished Find NixOS closure.659vm-test-run-k3s> server # [    3.924473] systemd[1]: Reached target Initrd Default Target.660vm-test-run-k3s> server # [    3.929209] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...661vm-test-run-k3s> server # [    3.943316] systemd[1]: Stopped target Initrd Default Target.662vm-test-run-k3s> server # [    3.945716] systemd[1]: Stopped target Basic System.663vm-test-run-k3s> server # [    3.947725] systemd[1]: Stopped target Initrd Root Device.664vm-test-run-k3s> server # [    3.949411] systemd[1]: Stopped target Path Units.665vm-test-run-k3s> server # [    3.951324] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.666vm-test-run-k3s> server # [    3.954191] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.667vm-test-run-k3s> server # [    3.956533] systemd[1]: Stopped target Slice Units.668vm-test-run-k3s> server # [    3.958470] systemd[1]: Stopped target Socket Units.669vm-test-run-k3s> server # [    3.961202] systemd[1]: Stopped target System Initialization.670vm-test-run-k3s> server # [    3.962782] systemd[1]: Stopped target Swaps.671vm-test-run-k3s> server # [    3.964129] systemd[1]: Stopped target Timer Units.672vm-test-run-k3s> server # [    3.966200] systemd[1]: dbus.socket: Deactivated successfully.673vm-test-run-k3s> server # [    3.967830] systemd[1]: Closed D-Bus System Message Bus Socket.674vm-test-run-k3s> server # [    3.970287] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.675vm-test-run-k3s> server # [    3.973353] systemd[1]: Stopped Find NixOS closure.676vm-test-run-k3s> server # [    3.976938] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio677vm-test-run-k3s> server # [    3.982383] systemd[1]: Starting rw-sysroot-nix-store.service...678vm-test-run-k3s> server # [    3.984220] systemd[1]: systemd-sysctl.service: Deactivated successfully.679vm-test-run-k3s> server # [    3.987218] systemd[1]: Stopped Apply Kernel Variables.680vm-test-run-k3s> server # [    3.992132] systemd[1]: systemd-modules-load.service: Deactivated successfully.681vm-test-run-k3s> server # [    3.995653] systemd[1]: Stopped Load Kernel Modules.682vm-test-run-k3s> server # [    4.001166] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.683vm-test-run-k3s> server # [    4.003268] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.684vm-test-run-k3s> server # [    4.009130] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.685vm-test-run-k3s> server # [    4.012182] systemd[1]: Stopped Create System Files and Directories.686vm-test-run-k3s> server # [    4.016801] systemd[1]: Stopped target Local File Systems.687vm-test-run-k3s> server # [    4.018400] systemd[1]: Stopped target Preparation for Local File Systems.688vm-test-run-k3s> server # [    4.020292] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.689vm-test-run-k3s> server # [    4.023198] systemd[1]: Stopped Coldplug All udev Devices.690vm-test-run-k3s> server # [    4.026402] systemd[1]: Stopping Rule-based Manager for Device Events and Files...691vm-test-run-k3s> server # [    4.029582] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.692vm-test-run-k3s> server # [    4.032450] systemd[1]: Stopped Virtual Console Setup.693vm-test-run-k3s> server # [    4.041645] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.694vm-test-run-k3s> server # [    4.045447] systemd[1]: Finished rw-sysroot-nix-store.service.695vm-test-run-k3s> server # [    4.054297] systemd[1]: systemd-udevd.service: Deactivated successfully.696vm-test-run-k3s> server # [    4.058107] systemd[1]: Stopped Rule-based Manager for Device Events and Files.697vm-test-run-k3s> server # [    4.063113] systemd[1]: initrd-cleanup.service: Deactivated successfully.698vm-test-run-k3s> server # [    4.066599] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.699vm-test-run-k3s> server # [    4.074635] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.700vm-test-run-k3s> server # [    4.076788] systemd[1]: Closed udev Control Socket.701vm-test-run-k3s> server # [    4.083207] systemd[1]: Starting Cleanup udev Database...702vm-test-run-k3s> server # [    4.084963] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.703vm-test-run-k3s> server # [    4.088364] systemd[1]: Stopped Create Static Device Nodes in /dev.704vm-test-run-k3s> server # [    4.093229] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.705vm-test-run-k3s> server # [    4.095466] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.706vm-test-run-k3s> server # [    4.100382] systemd[1]: kmod-static-nodes.service: Deactivated successfully.707vm-test-run-k3s> server # [    4.103372] systemd[1]: Stopped Create List of Static Device Nodes.708vm-test-run-k3s> server # [    4.118682] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.709vm-test-run-k3s> server # [    4.122712] systemd[1]: Finished Cleanup udev Database.710vm-test-run-k3s> server # [    4.127128] systemd[1]: Reached target Switch Root.711vm-test-run-k3s> server # [    4.131202] systemd[1]: Starting NixOS Activation...712vm-test-run-k3s> server # [    4.298581] initrd-nixos-activation-start[499]: booting system configuration /nix/store/k11rnfsr52fbylyc86ri217f0qlhb0rs-nixos-system-server-test713vm-test-run-k3s> server # [    4.368733] initrd-nixos-activation-start[499]: running activation script...714vm-test-run-k3s> server # [    4.813307] initrd-nixos-activation-start[522]: setting up /etc...715vm-test-run-k3s> server # [    5.087921] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.716vm-test-run-k3s> server # [    5.092134] systemd[1]: Finished NixOS Activation.717vm-test-run-k3s> server # [    5.098386] systemd[1]: Starting Switch Root...718vm-test-run-k3s> server # [    5.112942] systemd[1]: Switching root.719vm-test-run-k3s> server # [    5.517176] systemd-journald[125]: Received SIGTERM from PID 1 (systemd).720vm-test-run-k3s> server # [    5.684468] NET: Registered PF_VSOCK protocol family721vm-test-run-k3s> server # [    6.077632] systemd[1]: systemd 260.1 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)722vm-test-run-k3s> server # [    6.094798] systemd[1]: Detected virtualization kvm.723vm-test-run-k3s> server # [    6.098039] systemd[1]: Detected architecture x86-64.724vm-test-run-k3s> server # [    6.101520] systemd[1]: Detected first boot.725vm-test-run-k3s> server # [    6.111529] systemd[1]: Initializing machine ID from random generator.726vm-test-run-k3s> server # [    6.251541] systemd[1]: bpf-restrict-fs: LSM BPF program attached727vm-test-run-k3s> server # [    6.378432] systemd[1]: Applying preset policy.728vm-test-run-k3s> server # [    6.917156] systemd[1]: Populated /etc with preset unit settings.729vm-test-run-k3s> server # [    7.446989] systemd[1]: initrd-switch-root.service: Deactivated successfully.730vm-test-run-k3s> server # [    7.449739] systemd[1]: Stopped initrd-switch-root.service.731vm-test-run-k3s> server # [    7.453497] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.732vm-test-run-k3s> server # [    7.456928] systemd[1]: Created slice Slice /system/getty.733vm-test-run-k3s> server # [    7.459132] systemd[1]: Created slice User and Session Slice.734vm-test-run-k3s> server # [    7.460778] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.735vm-test-run-k3s> server # [    7.462889] systemd[1]: Started Forward Password Requests to Wall Directory Watch.736vm-test-run-k3s> server # [    7.465032] systemd[1]: Expecting device /dev/hvc0...737vm-test-run-k3s> server # [    7.466341] systemd[1]: Expecting device /dev/ttyS0...738vm-test-run-k3s> server # [    7.467785] systemd[1]: Reached target Local Encrypted Volumes.739vm-test-run-k3s> server # [    7.469283] systemd[1]: Stopped target initrd-fs.target.740vm-test-run-k3s> server # [    7.470676] systemd[1]: Stopped target initrd-root-fs.target.741vm-test-run-k3s> server # [    7.472148] systemd[1]: Stopped target initrd-switch-root.target.742vm-test-run-k3s> server # [    7.473733] systemd[1]: Reached target Virtual Machines and Containers.743vm-test-run-k3s> server # [    7.475411] systemd[1]: Reached target Path Units.744vm-test-run-k3s> server # [    7.476734] systemd[1]: Reached target Remote File Systems.745vm-test-run-k3s> server # [    7.478151] systemd[1]: Reached target Slice Units.746vm-test-run-k3s> server # [    7.479526] systemd[1]: Reached target Swaps.747vm-test-run-k3s> server # [    7.485865] systemd[1]: Listening on Process Core Dump Socket.748vm-test-run-k3s> server # [    7.490417] systemd[1]: Listening on Credential Encryption/Decryption.749vm-test-run-k3s> server # [    7.495998] systemd[1]: Starting Journal Log Access Socket...750vm-test-run-k3s> server # [    7.498655] systemd[1]: Listening on Journal Audit Socket.751vm-test-run-k3s> server # [    7.500567] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.752vm-test-run-k3s> server # [    7.503054] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki753vm-test-run-k3s> server # [    7.505758] systemd[1]: Listening on udev Control Socket.754vm-test-run-k3s> server # [    7.511998] systemd[1]: Mounting Huge Pages File System...755vm-test-run-k3s> server # [    7.516781] systemd[1]: Mounting POSIX Message Queue File System...756vm-test-run-k3s> server # [    7.523438] systemd[1]: Mounting Kernel Debug File System...757vm-test-run-k3s> server # [    7.535925] systemd[1]: Mounting Kernel Trace File System...758vm-test-run-k3s> server # [    7.544324] systemd[1]: Starting Create List of Static Device Nodes...759vm-test-run-k3s> server # [    7.548626] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio760vm-test-run-k3s> server # [    7.560969] systemd[1]: Starting Load Kernel Module configfs...761vm-test-run-k3s> server # [    7.562948] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm762vm-test-run-k3s> server # [    7.566694] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore763vm-test-run-k3s> server # [    7.570777] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse764vm-test-run-k3s> server # [    7.593826] systemd[1]: Mounting FUSE Control File System...765vm-test-run-k3s> server # [    7.596775] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67766vm-test-run-k3s> server # [    7.622046] systemd[1]: Starting Journal Service...767vm-test-run-k3s> server # [    7.629876] systemd[1]: Starting Load Kernel Modules...768vm-test-run-k3s> server # [    7.640351] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...769vm-test-run-k3s> server # [    7.648966] systemd[1]: Starting Remount Root and Kernel File Systems...770vm-test-run-k3s> server # [    7.652846] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki771vm-test-run-k3s> server # [    7.663285] systemd[1]: Starting Coldplug All udev Devices...772vm-test-run-k3s> server # [    7.670509] systemd[1]: Listening on Journal Log Access Socket.773vm-test-run-k3s> server # [    7.675809] systemd[1]: Mounted Huge Pages File System.774vm-test-run-k3s> server # [    7.679062] systemd[1]: Mounted POSIX Message Queue File System.775vm-test-run-k3s> server # [    7.683083] systemd[1]: Mounted Kernel Debug File System.776vm-test-run-k3s> server # [    7.686567] systemd[1]: Mounted Kernel Trace File System.777vm-test-run-k3s> server # [    7.691622] systemd[1]: Finished Create List of Static Device Nodes.778vm-test-run-k3s> server # [    7.696260] systemd[1]: modprobe@configfs.service: Deactivated successfully.779vm-test-run-k3s> server # [    7.702540] systemd[1]: Finished Load Kernel Module configfs.780vm-test-run-k3s> server # [    7.705613] systemd[1]: Mounted FUSE Control File System.781vm-test-run-k3s> server # [    7.713507] systemd[1]: Mounting Kernel Configuration File System...782vm-test-run-k3s> server # [    7.725656] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...783vm-test-run-k3s> server # [    7.751138] systemd[1]: Mounted Kernel Configuration File System.784vm-test-run-k3s> server # [    7.775043] EXT4-fs (vda): re-mounted 416e99fd-40e2-4aba-9740-047b07eda01d.785vm-test-run-k3s> server # [    7.788628] systemd-journald[732]: Collecting audit messages is enabled.786vm-test-run-k3s> server # [    7.794182] systemd[1]: Finished Remount Root and Kernel File Systems.787vm-test-run-k3s> server # [    7.797005] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore788vm-test-run-k3s> server # [    7.802486] loop: module loaded789vm-test-run-k3s> server # [    7.807649] systemd[1]: Starting Load/Save OS Random Seed...790vm-test-run-k3s> server # [    7.811479] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki791vm-test-run-k3s> server # [    7.824850] systemd[1]: Finished Load Kernel Modules.792vm-test-run-k3s> server # [    7.834559] systemd[1]: Starting Firewall...793vm-test-run-k3s> server # [    7.842531] systemd[1]: Starting Apply Kernel Variables...794vm-test-run-k3s> server # [    7.895215] systemd[1]: Started Journal Service.795vm-test-run-k3s> server # [    7.654516] systemd[1]: Queued start job for default target Multi-User System.796vm-test-run-k3s> server # [    7.665659] systemd[1]: systemd-journald.service: Deactivated successfully.797vm-test-run-k3s> server # [    7.676348] systemd-modules-load[733]: Inserted module 'loop'798vm-test-run-k3s> server # [    7.684454] systemd[1]: Starting Flush Journal to Persistent Storage...799vm-test-run-k3s> server # [    7.692943] systemd[1]: Finished Load/Save OS Random Seed.800vm-test-run-k3s> server # [    7.701353] systemd[1]: Reached target First Boot Complete.801vm-test-run-k3s> server # [    7.708470] systemd-oomd[734]: No swap; memory pressure usage will be degraded802vm-test-run-k3s> server # [    7.729501] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.803vm-test-run-k3s> server # [    7.746981] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.804vm-test-run-k3s> server # [    7.753984] systemd[1]: Starting Create Static Device Nodes in /dev...805vm-test-run-k3s> server # [    8.022523] systemd-journald[732]: Received client request to flush runtime journal.806vm-test-run-k3s> server # [    7.997899] systemd[1]: Finished Apply Kernel Variables.807vm-test-run-k3s> server # [    8.005697] systemd[1]: Finished Create Static Device Nodes in /dev.808vm-test-run-k3s> server # [    8.008832] systemd[1]: Reached target Preparation for Local File Systems.809vm-test-run-k3s> server # [    8.014614] systemd[1]: Starting Rule-based Manager for Device Events and Files...810vm-test-run-k3s> server # [    8.018281] systemd[1]: Finished Flush Journal to Persistent Storage.811vm-test-run-k3s> server # [    8.044698] systemd-udevd[766]: Using default interface naming scheme 'v260'.812vm-test-run-k3s> server # [    8.202478] systemd[1]: Finished Coldplug All udev Devices.813vm-test-run-k3s> server # [    8.210301] systemd[1]: Mounting /run/wrappers...814vm-test-run-k3s> server # [    8.215900] systemd[1]: Started Rule-based Manager for Device Events and Files.815vm-test-run-k3s> server # [    8.261785] systemd[1]: Mounted /run/wrappers.816vm-test-run-k3s> server # [    8.264243] systemd[1]: Reached target Local File Systems.817vm-test-run-k3s> server # [    8.268738] systemd[1]: Listening on Boot Loader Control Service Socket.818vm-test-run-k3s> server # [    8.277364] systemd[1]: Starting register-nix-paths.service...819vm-test-run-k3s> server # [    8.282222] systemd[1]: Starting Create SUID/SGID Wrappers...820vm-test-run-k3s> server # [    8.285329] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.821vm-test-run-k3s> server # [    8.292241] systemd[1]: Starting Save Transient machine-id to Disk...822vm-test-run-k3s> server # [    8.296094] systemd[1]: Starting Create System Files and Directories...823vm-test-run-k3s> server # [    8.376695] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.824vm-test-run-k3s> server # [    8.383107] systemd[1]: Finished Save Transient machine-id to Disk.825vm-test-run-k3s> server # [    8.486691] systemd[1]: Finished Create System Files and Directories.826vm-test-run-k3s> server # [    8.497266] systemd[1]: Starting Rebuild Journal Catalog...827vm-test-run-k3s> server # [    8.501135] systemd[1]: Starting Record System Boot/Shutdown in UTMP...828vm-test-run-k3s> server # [    8.587879] systemd[1]: Finished Record System Boot/Shutdown in UTMP.829vm-test-run-k3s> server # [    8.657464] systemd[1]: Finished Rebuild Journal Catalog.830vm-test-run-k3s> server # [    8.664314] systemd[1]: Starting Update is Completed...831vm-test-run-k3s> server # [    8.732884] systemd[1]: Finished Update is Completed.832vm-test-run-k3s> server # [    8.855417] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse833vm-test-run-k3s> server # [    9.087922] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.834vm-test-run-k3s> server # [    9.139193] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.835vm-test-run-k3s> server # [    9.142769] systemd[1]: Finished Create SUID/SGID Wrappers.836vm-test-run-k3s> server # [    9.155140] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.837vm-test-run-k3s> server # [    9.196414] (udev-worker)[861]: Network interface NamePolicy= disabled on kernel command line.838vm-test-run-k3s> server # [    9.201455] (udev-worker)[853]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.839vm-test-run-k3s> server # [    9.207504] (udev-worker)[853]: Network interface NamePolicy= disabled on kernel command line.840vm-test-run-k3s> server # [    9.305537] systemd[1]: Finished Firewall.841vm-test-run-k3s> server # [    9.404662] systemd[1]: Condition check resulted in Virtio network device being skipped.842vm-test-run-k3s> server # [    9.409272] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore843vm-test-run-k3s> server # [    9.414242] systemd[1]: Starting Create SUID/SGID Wrappers...844vm-test-run-k3s> server # [    9.416581] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.845vm-test-run-k3s> server # [    9.419590] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67846vm-test-run-k3s> server # [    9.424270] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore847vm-test-run-k3s> server # [    9.430117] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki848vm-test-run-k3s> server # [    9.432594] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki849vm-test-run-k3s> server # [    9.436597] systemd[1]: Finished register-nix-paths.service.850vm-test-run-k3s> server # [    9.831208] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3851vm-test-run-k3s> server # [    9.856973] mousedev: PS/2 mouse device common for all mice852vm-test-run-k3s> server # [    9.866468] ACPI: button: Power Button [PWRF]853vm-test-run-k3s> server # [    9.903807] rtc_cmos 00:05: RTC can wake from S4854vm-test-run-k3s> server # [    9.915316] parport_pc 00:03: reported by Plug and Play ACPI855vm-test-run-k3s> server # [    9.927732] rtc_cmos 00:05: registered as rtc0856vm-test-run-k3s> server # [    9.930773] Floppy drive(s): fd0 is 2.88M AMI BIOS857vm-test-run-k3s> server # [    9.933794] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]858vm-test-run-k3s> server # [    9.941775] rtc_cmos 00:05: setting system clock to 2026-06-22T06:44:26 UTC (1782110666)859vm-test-run-k3s> server # [    9.956754] systemd-journald[732]: Time jumped backwards, rotating.860vm-test-run-k3s> server # [    9.974113] FDC 0 is a S82078B861vm-test-run-k3s> server # [   10.037561] bochs-drm 0000:00:02.0: vgaarb: deactivate vga console862vm-test-run-k3s> server # [   10.055161] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0863vm-test-run-k3s> server # [   10.087194] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD864vm-test-run-k3s> server # [   10.093159] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:0a.0/virtio7/input/input4865vm-test-run-k3s> server # [   10.105560] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs866vm-test-run-k3s> server # [   10.131966] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6867vm-test-run-k3s> server # [   10.132438] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5868vm-test-run-k3s> server # [   10.011248] systemd[1]: Starting Virtual Console Setup...869vm-test-run-k3s> server # [   10.170976] Console: switching to colour dummy device 80x25870vm-test-run-k3s> server # [   10.294230] [drm] Found bochs VGA, ID 0xb0c5.871vm-test-run-k3s> server # [   10.295152] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.872vm-test-run-k3s> server # [   10.333965] bochs-drm 0000:00:02.0: [drm] Registered 1 planes with drm panic873vm-test-run-k3s> server # [   10.335514] [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0874vm-test-run-k3s> server # [   10.379331] ppdev: user-space parallel port driver875vm-test-run-k3s> server # [   10.220628] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.876vm-test-run-k3s> server # [   10.224332] systemd[1]: Finished Create SUID/SGID Wrappers.877vm-test-run-k3s> server # [   10.419660] fbcon: bochs-drmdrmfb (fb0) is primary device878vm-test-run-k3s> server # [   10.492586] Console: switching to colour frame buffer device 160x50879vm-test-run-k3s> server # [   10.521862] bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device880vm-test-run-k3s> server # [   10.287138] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.881vm-test-run-k3s> server # [   10.289432] systemd[1]: Stopped Virtual Console Setup.882vm-test-run-k3s> server # [   10.297119] systemd[1]: Starting Virtual Console Setup...883vm-test-run-k3s> server # [   10.322580] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.884vm-test-run-k3s> server # [   10.324680] systemd[1]: Stopped Virtual Console Setup.885vm-test-run-k3s> server # [   10.328808] systemd[1]: Starting Virtual Console Setup...886vm-test-run-k3s> server # [   10.639853] kvm_amd: TSC scaling supported887vm-test-run-k3s> server # [   10.640751] kvm_amd: Nested Virtualization enabled888vm-test-run-k3s> server # [   10.641697] kvm_amd: Nested Paging enabled889vm-test-run-k3s> server # [   10.642496] kvm_amd: LBR virtualization supported890vm-test-run-k3s> server # [   10.644038] kvm_amd: Virtual VMLOAD VMSAVE supported891vm-test-run-k3s> server # [   10.645091] kvm_amd: Virtual GIF supported892vm-test-run-k3s> server # [   10.645936] kvm_amd: Virtual NMI enabled893vm-test-run-k3s> server # [   10.693228] EDAC MC: Ver: 3.0.0894vm-test-run-k3s> server # [   10.990703] systemd-vconsole-setup[1033]: Configuration of first virtual console was skipped, ignoring remaining ones.895vm-test-run-k3s> server # [   10.997109] systemd[1]: Finished Virtual Console Setup.896vm-test-run-k3s> server # [   10.999577] systemd[1]: Reached target System Initialization.897vm-test-run-k3s> server # [   11.004358] systemd[1]: Started Discard unused filesystem blocks once a week.898vm-test-run-k3s> server # [   11.007398] systemd[1]: Started Daily Cleanup of Temporary Directories.899vm-test-run-k3s> server # [   11.009344] systemd[1]: Reached target Timer Units.900vm-test-run-k3s> server # [   11.011697] systemd[1]: Listening on D-Bus System Message Bus Socket.901vm-test-run-k3s> server # [   11.014180] systemd[1]: Listening on Nix Daemon Socket.902vm-test-run-k3s> server # [   11.015855] systemd[1]: Listening on Hostname Service Socket.903vm-test-run-k3s> server # [   11.017686] systemd[1]: Reached target Socket Units.904vm-test-run-k3s> server # [   11.020190] systemd[1]: Reached target Basic System.905vm-test-run-k3s> server # [   11.022543] systemd[1]: Started backdoor.service.906vm-test-run-k3s> server # [   11.027117] systemd[1]: Starting Import lastlog data into lastlog2 database...907vm-test-run-k3s> server # [   11.034214] systemd[1]: Starting Name Service Cache Daemon (nsncd)...908vm-test-run-k3s> server # [   11.040142] systemd[1]: Starting Post-Boot Actions...909vm-test-run-k3s> server # [   11.048062] systemd[1]: Started Reset console on configuration changes.910vm-test-run-k3s> server # [   11.055171] systemd[1]: Starting resolvconf update...911vm-test-run-k3s> server # [   11.062121] systemd[1]: Starting D-Bus System Message Bus...912vm-test-run-k3s> server # [   11.143901] systemd[1]: Finished Post-Boot Actions.913vm-test-run-k3s> server # connecting to host...914vm-test-run-k3s> server # [   11.192275] systemd[1]: Finished Import lastlog data into lastlog2 database.915vm-test-run-k3s> server # [   11.199512] systemd[1]: Started Name Service Cache Daemon (nsncd).916vm-test-run-k3s> server # [   11.203568] systemd[1]: Reached target Host and Network Name Lookups.917vm-test-run-k3s> server # [   11.209556] nsncd[1039]: Jun 22 06:44:27.998 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"918vm-test-run-k3s> server # [   11.215925] systemd[1]: Reached target User and Group Name Lookups.919vm-test-run-k3s> server # [   11.219300] systemd[1]: Starting User Login Management...920vm-test-run-k3s> server: Guest shell says: b'Spawning backdoor root shell...\n'921vm-test-run-k3s> server: connected to guest root shell922vm-test-run-k3s> server: (connecting took 12.20 seconds)923vm-test-run-k3s> server: (finished: waiting for the VM to finish booting, in 12.20 seconds)924vm-test-run-k3s> server # [   11.279991] dbus-broker-launch[1043]: Looking up NSS user entry for 'systemd-timesync'...925vm-test-run-k3s> server # [   11.303200] dbus-broker-launch[1043]: NSS returned no entry for 'systemd-timesync'926vm-test-run-k3s> server # [   11.306398] dbus-broker-launch[1043]: Invalid user-name in /nix/store/c140idc6xw7swb1d17piqk9ygm127134-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"927vm-test-run-k3s> server # [   11.347355] systemd-logind[1066]: New seat seat0.928vm-test-run-k3s> server # [   11.354276] systemd-logind[1066]: Watching system buttons on /dev/input/event2 (Power Button)929vm-test-run-k3s> server # [   11.357761] systemd-logind[1066]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)930vm-test-run-k3s> server # [   11.361554] systemd-logind[1066]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)931vm-test-run-k3s> server # [   11.379234] systemd[1]: Started User Login Management.932vm-test-run-k3s> server # [   11.383392] systemd[1]: Started D-Bus System Message Bus.933vm-test-run-k3s> server # [   11.408893] dbus-broker-launch[1043]: Ready934vm-test-run-k3s> server # [   11.420934] systemd[1]: Stopped target Host and Network Name Lookups.935vm-test-run-k3s> server # [   11.425102] systemd[1]: Stopping Host and Network Name Lookups...936vm-test-run-k3s> server # [   11.426762] systemd[1]: Stopped target User and Group Name Lookups.937vm-test-run-k3s> server # [   11.429937] systemd[1]: Stopping User and Group Name Lookups...938vm-test-run-k3s> server # [   11.434518] systemd[1]: Starting linger-users.service...939vm-test-run-k3s> server # [   11.436730] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...940vm-test-run-k3s> server # [   11.445199] systemd[1]: nscd.service: Deactivated successfully.941vm-test-run-k3s> server # [   11.449741] systemd[1]: Stopped Name Service Cache Daemon (nsncd).942vm-test-run-k3s> server # [   11.461877] systemd[1]: Starting Name Service Cache Daemon (nsncd)...943vm-test-run-k3s> server # [   11.529299] systemd[1]: linger-users.service: Deactivated successfully.944vm-test-run-k3s> server # [   11.533206] systemd[1]: Finished linger-users.service.945vm-test-run-k3s> server # [   11.550350] nsncd[1114]: Jun 22 06:44:28.349 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"946vm-test-run-k3s> server # [   11.555285] systemd[1]: Started Name Service Cache Daemon (nsncd).947vm-test-run-k3s> server # [   11.557724] systemd[1]: Reached target Host and Network Name Lookups.948vm-test-run-k3s> server # [   11.559927] systemd[1]: Reached target User and Group Name Lookups.949vm-test-run-k3s> server # [   11.603462] systemd[1]: Finished resolvconf update.950vm-test-run-k3s> server # [   11.606704] systemd[1]: Reached target Preparation for Network.951vm-test-run-k3s> server # [   11.612818] systemd[1]: Starting DHCP Client...952vm-test-run-k3s> server # [   11.618131] systemd[1]: Starting Address configuration of eth1...953vm-test-run-k3s> server # [   11.624180] systemd[1]: Starting Extra networking commands....954vm-test-run-k3s> server # [   11.757589] network-addresses-eth1-start[1154]: adding address 192.168.1.1/24... done955vm-test-run-k3s> server # [   11.781673] network-addresses-eth1-start[1154]: adding address 2001:db8:1::1/64... done956vm-test-run-k3s> server # [   11.811449] systemd[1]: Finished Address configuration of eth1.957vm-test-run-k3s> server # [   11.822509] dhcpcd[1161]: dhcpcd-10.3.1 starting958vm-test-run-k3s> server # [   11.836155] dhcpcd[1209]: dev: loaded udev959vm-test-run-k3s> server # [   11.850834] systemd[1]: Finished Extra networking commands..960vm-test-run-k3s> server # [   11.853364] systemd[1]: Reached target Network.961vm-test-run-k3s> server # [   11.858599] systemd[1]: Starting Permit User Sessions...962vm-test-run-k3s> server # [   12.119601] 8021q: 802.1Q VLAN Support v1.8963vm-test-run-k3s> server # [   12.120600] 8021q: adding VLAN 0 to HW filter on device eth1964vm-test-run-k3s> server # [   11.906279] systemd[1]: Finished Permit User Sessions.965vm-test-run-k3s> server # [   11.916943] systemd[1]: Started Getty on tty1.966vm-test-run-k3s> server # [   11.919384] systemd[1]: Reached target Login Prompts.967vm-test-run-k3s> server # [   11.935960] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.968vm-test-run-k3s> server # [   12.235329] cfg80211: Loading compiled-in X.509 certificates for regulatory database969vm-test-run-k3s> server # [   12.283790] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'970vm-test-run-k3s> server # [   12.285109] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'971vm-test-run-k3s> server # [   12.289598] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2972vm-test-run-k3s> server # [   12.291230] cfg80211: failed to load regulatory.db973vm-test-run-k3s> server # [   12.375493] 8021q: adding VLAN 0 to HW filter on device eth0974vm-test-run-k3s> server # [   12.134134] dhcpcd[1209]: eth0: waiting for carrier975vm-test-run-k3s> server # [   12.780251] dhcpcd[1209]: eth0: carrier acquired976vm-test-run-k3s> server # [   12.806851] dhcpcd[1209]: DUID 00:01:00:01:31:cb:96:4d:52:54:00:12:34:56977vm-test-run-k3s> server # [   12.812523] dhcpcd[1209]: eth0: IAID 00:12:34:56978vm-test-run-k3s> server # [   12.816311] dhcpcd[1209]: eth0: adding address fe80::5054:ff:fe12:3456979vm-test-run-k3s> server # [   13.659955] dhcpcd[1209]: eth0: soliciting a DHCP lease980vm-test-run-k3s> server # [   13.946591] NET: Registered PF_PACKET protocol family981vm-test-run-k3s> server # [   13.715218] dhcpcd[1209]: eth0: offered 10.0.2.15 from 10.0.2.2982vm-test-run-k3s> server # [   13.720462] dhcpcd[1209]: eth0: probing address 10.0.2.15/24983vm-test-run-k3s> server # [   14.604865] dhcpcd[1209]: eth0: soliciting an IPv6 router984vm-test-run-k3s> server # [   14.610243] dhcpcd[1209]: eth0: Router Advertisement from fe80::2985vm-test-run-k3s> server # [   14.614700] dhcpcd[1209]: eth0: adding address fec0::5054:ff:fe12:3456/64986vm-test-run-k3s> server # [   14.619178] dhcpcd[1209]: eth0: adding route to fec0::/64987vm-test-run-k3s> server # [   14.623180] dhcpcd[1209]: eth0: adding default route via fe80::2988vm-test-run-k3s> server # [   18.869692] dhcpcd[1209]: eth0: leased 10.0.2.15 for 86400 seconds989vm-test-run-k3s> server # [   18.874899] dhcpcd[1209]: eth0: adding route to 10.0.2.0/24990vm-test-run-k3s> server # [   18.879405] dhcpcd[1209]: eth0: adding default route via 10.0.2.2991vm-test-run-k3s> server # [   19.038890] systemd[1]: Started DHCP Client.992vm-test-run-k3s> server # [   19.043122] systemd[1]: Reached target Network is Online.993vm-test-run-k3s> server # [   19.048397] systemd[1]: Starting k3s service...994vm-test-run-k3s> server # [   19.216952] k3s[1337]: time="2026-06-22T06:44:36Z" level=info msg="Acquiring lock file /var/lib/rancher/k3s/data/.lock"995vm-test-run-k3s> server # [   19.220114] k3s[1337]: time="2026-06-22T06:44:36Z" level=info msg="Preparing data dir /var/lib/rancher/k3s/data/51e88cc11f6233f16a43a797c25913291c6c7c60be42620fd166bade471610a8"996vm-test-run-k3s> server # [   24.185990] k3s[1337]: time="2026-06-22T06:44:40Z" level=info msg="Starting k3s 1.35.4+k3s1 (5dc8fe68)"997vm-test-run-k3s> server # [   24.212067] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Configuring sqlite3 database connection pooling: maxIdleConns=2, maxOpenConns=0, connMaxLifetime=0s"998vm-test-run-k3s> server # [   24.216098] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Configuring database table schema and indexes, this may take a moment..."999vm-test-run-k3s> server # [   24.229721] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Database tables and indexes are up to date"1000vm-test-run-k3s> server # [   24.232515] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Running startup VACUUM to reclaim disk space, this may take a moment..."1001vm-test-run-k3s> server # [   24.239829] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Startup VACUUM completed successfully"1002vm-test-run-k3s> server # [   24.243536] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Kine available at unix://kine.sock"1003vm-test-run-k3s> server # [   24.245942] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Opening etcd client connection with endpoints [unix://kine.sock]"1004vm-test-run-k3s> server # [   24.256917] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Datastore connection validated successfully, proceeding with bootstrap data generation"1005vm-test-run-k3s> server # [   24.261787] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="generated self-signed CA certificate CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:41.062810355 +0000 UTC notAfter=2036-06-19 05:44:41.062810355 +0000 UTC"1006vm-test-run-k3s> server # [   24.267651] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=system:admin,O=system:masters signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1007vm-test-run-k3s> server # [   24.274091] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=system:k3s-supervisor,O=system:masters signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1008vm-test-run-k3s> server # [   24.278749] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=system:kube-controller-manager signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1009vm-test-run-k3s> server # [   24.286083] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=system:kube-scheduler signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1010vm-test-run-k3s> server # [   24.290410] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=system:apiserver,O=system:masters signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1011vm-test-run-k3s> server # [   24.295681] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=k3s-cloud-controller-manager signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1012vm-test-run-k3s> server # [   24.301650] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="generated self-signed CA certificate CN=k3s-server-ca@1782110681: notBefore=2026-06-22 05:44:41.102724087 +0000 UTC notAfter=2036-06-19 05:44:41.102724087 +0000 UTC"1013vm-test-run-k3s> server # [   24.307777] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=kube-apiserver signed by CN=k3s-server-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1014vm-test-run-k3s> server # [   24.313431] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=kube-scheduler signed by CN=k3s-server-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1015vm-test-run-k3s> server # [   24.318527] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=kube-controller-manager signed by CN=k3s-server-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1016vm-test-run-k3s> server # [   24.324090] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="generated self-signed CA certificate CN=k3s-request-header-ca@1782110681: notBefore=2026-06-22 05:44:41.12477242 +0000 UTC notAfter=2036-06-19 05:44:41.12477242 +0000 UTC"1017vm-test-run-k3s> server # [   24.328754] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=system:auth-proxy signed by CN=k3s-request-header-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1018vm-test-run-k3s> server # [   24.334072] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="generated self-signed CA certificate CN=etcd-server-ca@1782110681: notBefore=2026-06-22 05:44:41.13511312 +0000 UTC notAfter=2036-06-19 05:44:41.13511312 +0000 UTC"1019vm-test-run-k3s> server # [   24.339786] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=etcd-client signed by CN=etcd-server-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1020vm-test-run-k3s> server # [   24.344548] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="generated self-signed CA certificate CN=etcd-peer-ca@1782110681: notBefore=2026-06-22 05:44:41.14590695 +0000 UTC notAfter=2036-06-19 05:44:41.14590695 +0000 UTC"1021vm-test-run-k3s> server # [   24.350089] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=etcd-peer signed by CN=etcd-peer-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1022vm-test-run-k3s> server # [   24.354752] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=etcd-server signed by CN=etcd-server-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1023vm-test-run-k3s> server # [   24.419938] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="certificate CN=k3s,O=k3s signed by CN=k3s-server-ca@1782110681: notBefore=2026-06-22 05:44:41 +0000 UTC notAfter=2027-06-22 05:44:41 +0000 UTC"1024vm-test-run-k3s> server # [   24.426098] k3s[1337]: time="2026-06-22T06:44:41Z" level=warning msg="dynamiclistener [::]:6443: no cached certificate available for preload - deferring certificate load until storage initialization or first client request"1025vm-test-run-k3s> server # [   24.431378] k3s[1337]: time="2026-06-22T06:44:41Z" level=info msg="Active TLS secret / (ver=) (count 11): map[listener.cattle.io/cn-10.0.2.15:10.0.2.15 listener.cattle.io/cn-10.43.0.1:10.43.0.1 listener.cattle.io/cn-127.0.0.1:127.0.0.1 listener.cattle.io/cn-__1-f16284:::1 listener.cattle.io/cn-fec0__40dd_dd50_c21b_8c9c-13a797:fec0::40dd:dd50:c21b:8c9c listener.cattle.io/cn-kubernetes:kubernetes listener.cattle.io/cn-kubernetes.default:kubernetes.default listener.cattle.io/cn-kubernetes.default.svc:kubernetes.default.svc listener.cattle.io/cn-kubernetes.default.svc.cluster.local:kubernetes.default.svc.cluster.local listener.cattle.io/cn-localhost:localhost listener.cattle.io/cn-server:server listener.cattle.io/fingerprint:SHA1=F7EED841DA6C10F3991A745B247A13101707FDB7]"1026vm-test-run-k3s> server # [   25.281469] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Password verified locally for node server"1027vm-test-run-k3s> server # [   25.285109] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="certificate CN=server signed by CN=k3s-server-ca@1782110681: notBefore=2026-06-22 05:44:42 +0000 UTC notAfter=2027-06-22 05:44:42 +0000 UTC"1028vm-test-run-k3s> server # [   25.516609] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="certificate CN=system:node:server,O=system:nodes signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:42 +0000 UTC notAfter=2027-06-22 05:44:42 +0000 UTC"1029vm-test-run-k3s> server # [   25.597208] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="certificate CN=system:kube-proxy signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:42 +0000 UTC notAfter=2027-06-22 05:44:42 +0000 UTC"1030vm-test-run-k3s> server # [   25.681638] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="certificate CN=system:k3s-controller signed by CN=k3s-client-ca@1782110681: notBefore=2026-06-22 05:44:42 +0000 UTC notAfter=2027-06-22 05:44:42 +0000 UTC"1031vm-test-run-k3s> server # [   25.692619] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Module overlay was already loaded"1032vm-test-run-k3s> server # [   25.695002] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Module nf_conntrack was already loaded"1033vm-test-run-k3s> server # [   25.999638] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.1034vm-test-run-k3s> server # [   26.007132] Bridge firewalling registered1035vm-test-run-k3s> server # [   25.796569] k3s[1337]: time="2026-06-22T06:44:42Z" level=warning msg="Failed to load kernel module iptable_nat with modprobe"1036vm-test-run-k3s> server # [   25.822113] k3s[1337]: time="2026-06-22T06:44:42Z" level=warning msg="Failed to load kernel module iptable_filter with modprobe"1037vm-test-run-k3s> server # [   25.847767] k3s[1337]: time="2026-06-22T06:44:42Z" level=warning msg="Failed to load kernel module nft-expr-counter with modprobe"1038vm-test-run-k3s> server # [   25.910826] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Set sysctl 'net/ipv4/conf/all/forwarding' to 1"1039vm-test-run-k3s> server # [   25.914129] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Set sysctl 'net/netfilter/nf_conntrack_max' to 131072"1040vm-test-run-k3s> server # [   25.917143] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Set sysctl 'net/netfilter/nf_conntrack_tcp_timeout_established' to 86400"1041vm-test-run-k3s> server # [   25.919980] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Set sysctl 'net/netfilter/nf_conntrack_tcp_timeout_close_wait' to 3600"1042vm-test-run-k3s> server # [   25.928954] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Creating k3s-cert-monitor event broadcaster"1043vm-test-run-k3s> server # [   25.934095] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Connecting to proxy" url="wss://127.0.0.1:6443/v1-k3s/connect"1044vm-test-run-k3s> server # [   25.936739] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Saving cluster bootstrap data to datastore"1045vm-test-run-k3s> server # [   25.941439] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Opening etcd client connection with endpoints [unix://kine.sock]"1046vm-test-run-k3s> server # [   25.945883] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Connection to etcd is ready"1047vm-test-run-k3s> server # [   25.948349] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="ETCD server is now running"1048vm-test-run-k3s> server # [   25.973445] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Handling backend connection request [server]"1049vm-test-run-k3s> server # [   25.983099] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Connected to proxy" url="wss://127.0.0.1:6443/v1-k3s/connect"1050vm-test-run-k3s> server # [   25.985751] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Polling for API server readiness: GET /readyz failed: the server is currently unable to handle the request"1051vm-test-run-k3s> server # [   25.989557] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Remotedialer connected to proxy" url="wss://127.0.0.1:6443/v1-k3s/connect"1052vm-test-run-k3s> server # [   26.000949] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Running kube-apiserver --advertise-port=6443 --allow-privileged=true --anonymous-auth=false --api-audiences=https://kubernetes.default.svc.cluster.local,k3s --authorization-mode=Node,RBAC --bind-address=127.0.0.1 --cert-dir=/var/lib/rancher/k3s/server/tls/temporary-certs --client-ca-file=/var/lib/rancher/k3s/server/tls/client-ca.crt --egress-selector-config-file=/var/lib/rancher/k3s/server/etc/egress-selector-config.yaml --enable-admission-plugins=NodeRestriction --enable-aggregator-routing=true --enable-bootstrap-token-auth=true --etcd-servers=unix://kine.sock --kubelet-certificate-authority=/var/lib/rancher/k3s/server/tls/server-ca.crt --kubelet-client-certificate=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.crt --kubelet-client-key=/var/lib/rancher/k3s/server/tls/client-kube-apiserver.key --kubelet-preferred-address-types=InternalIP,ExternalIP,Hostname --profiling=false --proxy-client-cert-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.crt --proxy-client-key-file=/var/lib/rancher/k3s/server/tls/client-auth-proxy.key --requestheader-allowed-names=system:auth-proxy --requestheader-client-ca-file=/var/lib/rancher/k3s/server/tls/request-header-ca.crt --requestheader-extra-headers-prefix=X-Remote-Extra- --requestheader-group-headers=X-Remote-Group --requestheader-username-headers=X-Remote-User --secure-port=6444 --service-account-issuer=https://kubernetes.default.svc.cluster.local --service-account-key-file=/var/lib/rancher/k3s/server/tls/service.key --service-account-signing-key-file=/var/lib/rancher/k3s/server/tls/service.current.key --service-cluster-ip-range=10.43.0.0/16 --service-node-port-range=30000-32767 --storage-backend=etcd3 --tls-cert-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt --tls-cipher-suites=TLS_ECDHE_ECDSA_WITH_AES_256_GCM_SHA384,TLS_ECDHE_RSA_WITH_AES_256_GCM_SHA384,TLS_ECDHE_ECDSA_WITH_AES_128_GCM_SHA256,TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256,TLS_ECDHE_ECDSA_WITH_CHACHA20_POLY1305,TLS_ECDHE_RSA_WITH_CHACHA20_POLY1305 --tls-private-key-file=/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key"1053vm-test-run-k3s> server # [   26.041790] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Running kube-scheduler --authentication-kubeconfig=/var/lib/rancher/k3s/server/cred/scheduler.kubeconfig --authorization-kubeconfig=/var/lib/rancher/k3s/server/cred/scheduler.kubeconfig --bind-address=127.0.0.1 --kubeconfig=/var/lib/rancher/k3s/server/cred/scheduler.kubeconfig --leader-elect=false --profiling=false --secure-port=10259 --tls-cert-file=/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.crt --tls-private-key-file=/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.key"1054vm-test-run-k3s> server # [   26.054447] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Running kube-controller-manager --allocate-node-cidrs=true --authentication-kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --authorization-kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --bind-address=127.0.0.1 --cluster-cidr=10.42.0.0/16 --cluster-signing-kube-apiserver-client-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt --cluster-signing-kube-apiserver-client-key-file=/var/lib/rancher/k3s/server/tls/client-ca.key --cluster-signing-kubelet-client-cert-file=/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt --cluster-signing-kubelet-client-key-file=/var/lib/rancher/k3s/server/tls/client-ca.key --cluster-signing-kubelet-serving-cert-file=/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt --cluster-signing-kubelet-serving-key-file=/var/lib/rancher/k3s/server/tls/server-ca.key --cluster-signing-legacy-unknown-cert-file=/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt --cluster-signing-legacy-unknown-key-file=/var/lib/rancher/k3s/server/tls/server-ca.key --configure-cloud-routes=false --controllers=*,tokencleaner,-service,-route,-cloud-node-lifecycle --kubeconfig=/var/lib/rancher/k3s/server/cred/controller.kubeconfig --leader-elect=false --profiling=false --root-ca-file=/var/lib/rancher/k3s/server/tls/server-ca.crt --secure-port=10257 --service-account-private-key-file=/var/lib/rancher/k3s/server/tls/service.current.key --service-cluster-ip-range=10.43.0.0/16 --tls-cert-file=/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.crt --tls-private-key-file=/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.key --use-service-account-credentials=true"1055vm-test-run-k3s> server # [   26.087774] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Running cloud-controller-manager --allocate-node-cidrs=true --authentication-kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --authorization-kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --bind-address=127.0.0.1 --cloud-config=/var/lib/rancher/k3s/server/etc/cloud-config.yaml --cloud-provider=k3s --cluster-cidr=10.42.0.0/16 --configure-cloud-routes=false --controllers=*,-route,-service --kubeconfig=/var/lib/rancher/k3s/server/cred/cloud-controller.kubeconfig --leader-elect=false --leader-elect-resource-name=k3s-cloud-controller-manager --node-status-update-frequency=1m0s --profiling=false"1056vm-test-run-k3s> server # [   26.102309] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Server node token is available at /var/lib/rancher/k3s/server/token"1057vm-test-run-k3s> server # [   26.105948] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="To join server node to cluster: k3s server -s https://10.0.2.15:6443 -t ${SERVER_NODE_TOKEN}"1058vm-test-run-k3s> server # [   26.110386] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Agent node token is available at /var/lib/rancher/k3s/server/agent-token"1059vm-test-run-k3s> server # [   26.113583] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="To join agent node to cluster: k3s agent -s https://10.0.2.15:6443 -t ${AGENT_NODE_TOKEN}"1060vm-test-run-k3s> server # [   26.118210] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Wrote kubeconfig /etc/rancher/k3s/k3s.yaml"1061vm-test-run-k3s> server # [   26.120751] k3s[1337]: time="2026-06-22T06:44:42Z" level=info msg="Run: k3s kubectl"1062vm-test-run-k3s> server # [   26.123223] k3s[1337]: I0622 06:44:42.924712    1337 options.go:263] external host was not specified, using 10.0.2.151063vm-test-run-k3s> server # [   26.158314] k3s[1337]: I0622 06:44:42.959813    1337 server.go:158] Version: v1.35.4+k3s11064vm-test-run-k3s> server # [   26.160593] k3s[1337]: I0622 06:44:42.961971    1337 server.go:160] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1065vm-test-run-k3s> server # [   26.283091] k3s[1337]: time="2026-06-22T06:44:43Z" level=info msg="Logging containerd to /var/lib/rancher/k3s/agent/containerd/containerd.log"1066vm-test-run-k3s> server # [   26.317491] k3s[1337]: time="2026-06-22T06:44:43Z" level=info msg="Running containerd -c /var/lib/rancher/k3s/agent/etc/containerd/config.toml"1067vm-test-run-k3s> server # [   26.355186] k3s[1337]: time="2026-06-22T06:44:43Z" level=error msg="Sending HTTP/1.1 503 response to 127.0.0.1:45458: runtime core not ready"1068vm-test-run-k3s> server # [   26.739851] k3s[1337]: I0622 06:44:43.541325    1337 shared_informer.go:349] "Waiting for caches to sync" controller="node_authorizer"1069vm-test-run-k3s> server # [   26.749108] k3s[1337]: time="2026-06-22T06:44:43Z" level=info msg="Running kube-proxy --cluster-cidr=10.42.0.0/16 --conntrack-max-per-core=0 --conntrack-tcp-timeout-close-wait=0s --conntrack-tcp-timeout-established=0s --healthz-bind-address=127.0.0.1 --hostname-override=server --kubeconfig=/var/lib/rancher/k3s/agent/kubeproxy.kubeconfig --proxy-mode=iptables"1070vm-test-run-k3s> server # [   26.766256] k3s[1337]: I0622 06:44:43.567767    1337 shared_informer.go:370] "Waiting for caches to sync"1071vm-test-run-k3s> server # [   26.770393] k3s[1337]: I0622 06:44:43.571820    1337 plugins.go:157] Loaded 14 mutating admission controller(s) successfully in the following order: NamespaceLifecycle,LimitRanger,ServiceAccount,NodeRestriction,TaintNodesByCondition,Priority,DefaultTolerationSeconds,DefaultStorageClass,StorageObjectInUseProtection,RuntimeClass,DefaultIngressClass,PodTopologyLabels,MutatingAdmissionPolicy,MutatingAdmissionWebhook.1072vm-test-run-k3s> server # [   26.778707] k3s[1337]: I0622 06:44:43.580005    1337 plugins.go:160] Loaded 14 validating admission controller(s) successfully in the following order: LimitRanger,ServiceAccount,PodSecurity,Priority,PersistentVolumeClaimResize,RuntimeClass,CertificateApproval,CertificateSigning,ClusterTrustBundleAttest,CertificateSubjectRestriction,NodeDeclaredFeatureValidator,ValidatingAdmissionPolicy,ValidatingAdmissionWebhook,ResourceQuota.1073vm-test-run-k3s> server # [   26.787308] k3s[1337]: I0622 06:44:43.588828    1337 instance.go:240] Using reconciler: lease1074vm-test-run-k3s> server # [   26.821773] k3s[1337]: I0622 06:44:43.623279    1337 handler.go:304] Adding GroupVersion apiextensions.k8s.io v1 to ResourceManager1075vm-test-run-k3s> server # [   26.825566] k3s[1337]: W0622 06:44:43.626718    1337 genericapiserver.go:787] Skipping API apiextensions.k8s.io/v1beta1 because it has no resources.1076vm-test-run-k3s> server # [   26.838967] k3s[1337]: I0622 06:44:43.640453    1337 cidrallocator.go:198] starting ServiceCIDR Allocator Controller1077vm-test-run-k3s> server # [   27.022758] k3s[1337]: I0622 06:44:43.824077    1337 handler.go:304] Adding GroupVersion  v1 to ResourceManager1078vm-test-run-k3s> server # [   27.029114] k3s[1337]: I0622 06:44:43.830402    1337 apis.go:112] API group "internal.apiserver.k8s.io" is not enabled, skipping.1079vm-test-run-k3s> server # [   27.278125] k3s[1337]: I0622 06:44:44.079485    1337 apis.go:112] API group "storagemigration.k8s.io" is not enabled, skipping.1080vm-test-run-k3s> server # [   27.350602] k3s[1337]: time="2026-06-22T06:44:44Z" level=info msg="containerd is now running"1081vm-test-run-k3s> server # [   27.418766] k3s[1337]: I0622 06:44:44.220259    1337 handler.go:304] Adding GroupVersion authentication.k8s.io v1 to ResourceManager1082vm-test-run-k3s> server # [   27.422100] k3s[1337]: W0622 06:44:44.223254    1337 genericapiserver.go:787] Skipping API authentication.k8s.io/v1beta1 because it has no resources.1083vm-test-run-k3s> server # [   27.425138] k3s[1337]: W0622 06:44:44.223292    1337 genericapiserver.go:787] Skipping API authentication.k8s.io/v1alpha1 because it has no resources.1084vm-test-run-k3s> server # [   27.429329] k3s[1337]: I0622 06:44:44.229586    1337 handler.go:304] Adding GroupVersion authorization.k8s.io v1 to ResourceManager1085vm-test-run-k3s> server # [   27.432102] k3s[1337]: W0622 06:44:44.229651    1337 genericapiserver.go:787] Skipping API authorization.k8s.io/v1beta1 because it has no resources.1086vm-test-run-k3s> server # [   27.435324] k3s[1337]: I0622 06:44:44.230137    1337 handler.go:304] Adding GroupVersion autoscaling v2 to ResourceManager1087vm-test-run-k3s> server # [   27.437884] k3s[1337]: I0622 06:44:44.230526    1337 handler.go:304] Adding GroupVersion autoscaling v1 to ResourceManager1088vm-test-run-k3s> server # [   27.441284] k3s[1337]: W0622 06:44:44.230552    1337 genericapiserver.go:787] Skipping API autoscaling/v2beta1 because it has no resources.1089vm-test-run-k3s> server # [   27.444159] k3s[1337]: W0622 06:44:44.230575    1337 genericapiserver.go:787] Skipping API autoscaling/v2beta2 because it has no resources.1090vm-test-run-k3s> server # [   27.447867] k3s[1337]: I0622 06:44:44.249382    1337 handler.go:304] Adding GroupVersion batch v1 to ResourceManager1091vm-test-run-k3s> server # [   27.452162] k3s[1337]: W0622 06:44:44.251967    1337 genericapiserver.go:787] Skipping API batch/v1beta1 because it has no resources.1092vm-test-run-k3s> server # [   27.454919] k3s[1337]: I0622 06:44:44.252456    1337 handler.go:304] Adding GroupVersion certificates.k8s.io v1 to ResourceManager1093vm-test-run-k3s> server # [   27.457676] k3s[1337]: W0622 06:44:44.252491    1337 genericapiserver.go:787] Skipping API certificates.k8s.io/v1beta1 because it has no resources.1094vm-test-run-k3s> server # [   27.460714] k3s[1337]: W0622 06:44:44.252515    1337 genericapiserver.go:787] Skipping API certificates.k8s.io/v1alpha1 because it has no resources.1095vm-test-run-k3s> server # [   27.463726] k3s[1337]: I0622 06:44:44.252872    1337 handler.go:304] Adding GroupVersion coordination.k8s.io v1 to ResourceManager1096vm-test-run-k3s> server # [   27.466484] k3s[1337]: W0622 06:44:44.252898    1337 genericapiserver.go:787] Skipping API coordination.k8s.io/v1beta1 because it has no resources.1097vm-test-run-k3s> server # [   27.469517] k3s[1337]: W0622 06:44:44.252921    1337 genericapiserver.go:787] Skipping API coordination.k8s.io/v1alpha2 because it has no resources.1098vm-test-run-k3s> server # [   27.472546] k3s[1337]: I0622 06:44:44.253223    1337 handler.go:304] Adding GroupVersion discovery.k8s.io v1 to ResourceManager1099vm-test-run-k3s> server # [   27.476267] k3s[1337]: W0622 06:44:44.253250    1337 genericapiserver.go:787] Skipping API discovery.k8s.io/v1beta1 because it has no resources.1100vm-test-run-k3s> server # [   27.482626] k3s[1337]: I0622 06:44:44.284137    1337 handler.go:304] Adding GroupVersion networking.k8s.io v1 to ResourceManager1101vm-test-run-k3s> server # [   27.485698] k3s[1337]: W0622 06:44:44.287205    1337 genericapiserver.go:787] Skipping API networking.k8s.io/v1beta1 because it has no resources.1102vm-test-run-k3s> server # [   27.489095] k3s[1337]: I0622 06:44:44.290578    1337 handler.go:304] Adding GroupVersion node.k8s.io v1 to ResourceManager1103vm-test-run-k3s> server # [   27.493094] k3s[1337]: W0622 06:44:44.293223    1337 genericapiserver.go:787] Skipping API node.k8s.io/v1beta1 because it has no resources.1104vm-test-run-k3s> server # [   27.495907] k3s[1337]: W0622 06:44:44.293252    1337 genericapiserver.go:787] Skipping API node.k8s.io/v1alpha1 because it has no resources.1105vm-test-run-k3s> server # [   27.498753] k3s[1337]: I0622 06:44:44.293776    1337 handler.go:304] Adding GroupVersion policy v1 to ResourceManager1106vm-test-run-k3s> server # [   27.501288] k3s[1337]: W0622 06:44:44.293802    1337 genericapiserver.go:787] Skipping API policy/v1beta1 because it has no resources.1107vm-test-run-k3s> server # [   27.506098] k3s[1337]: I0622 06:44:44.307577    1337 handler.go:304] Adding GroupVersion rbac.authorization.k8s.io v1 to ResourceManager1108vm-test-run-k3s> server # [   27.508979] k3s[1337]: W0622 06:44:44.310498    1337 genericapiserver.go:787] Skipping API rbac.authorization.k8s.io/v1beta1 because it has no resources.1109vm-test-run-k3s> server # [   27.513096] k3s[1337]: W0622 06:44:44.313716    1337 genericapiserver.go:787] Skipping API rbac.authorization.k8s.io/v1alpha1 because it has no resources.1110vm-test-run-k3s> server # [   27.516253] k3s[1337]: I0622 06:44:44.314072    1337 handler.go:304] Adding GroupVersion scheduling.k8s.io v1 to ResourceManager1111vm-test-run-k3s> server # [   27.518903] k3s[1337]: W0622 06:44:44.314100    1337 genericapiserver.go:787] Skipping API scheduling.k8s.io/v1beta1 because it has no resources.1112vm-test-run-k3s> server # [   27.521881] k3s[1337]: W0622 06:44:44.314123    1337 genericapiserver.go:787] Skipping API scheduling.k8s.io/v1alpha1 because it has no resources.1113vm-test-run-k3s> server # [   27.526325] k3s[1337]: I0622 06:44:44.327114    1337 handler.go:304] Adding GroupVersion storage.k8s.io v1 to ResourceManager1114vm-test-run-k3s> server # [   27.528992] k3s[1337]: W0622 06:44:44.327147    1337 genericapiserver.go:787] Skipping API storage.k8s.io/v1beta1 because it has no resources.1115vm-test-run-k3s> server # [   27.531933] k3s[1337]: W0622 06:44:44.327171    1337 genericapiserver.go:787] Skipping API storage.k8s.io/v1alpha1 because it has no resources.1116vm-test-run-k3s> server # [   27.537187] k3s[1337]: I0622 06:44:44.338643    1337 handler.go:304] Adding GroupVersion flowcontrol.apiserver.k8s.io v1 to ResourceManager1117vm-test-run-k3s> server # [   27.541363] k3s[1337]: W0622 06:44:44.341396    1337 genericapiserver.go:787] Skipping API flowcontrol.apiserver.k8s.io/v1beta3 because it has no resources.1118vm-test-run-k3s> server # [   27.544503] k3s[1337]: W0622 06:44:44.341456    1337 genericapiserver.go:787] Skipping API flowcontrol.apiserver.k8s.io/v1beta2 because it has no resources.1119vm-test-run-k3s> server # [   27.547635] k3s[1337]: W0622 06:44:44.341508    1337 genericapiserver.go:787] Skipping API flowcontrol.apiserver.k8s.io/v1beta1 because it has no resources.1120vm-test-run-k3s> server # [   27.551745] k3s[1337]: I0622 06:44:44.353262    1337 handler.go:304] Adding GroupVersion apps v1 to ResourceManager1121vm-test-run-k3s> server # [   27.554349] k3s[1337]: W0622 06:44:44.355860    1337 genericapiserver.go:787] Skipping API apps/v1beta2 because it has no resources.1122vm-test-run-k3s> server # [   27.558092] k3s[1337]: W0622 06:44:44.358703    1337 genericapiserver.go:787] Skipping API apps/v1beta1 because it has no resources.1123vm-test-run-k3s> server # [   27.561882] k3s[1337]: I0622 06:44:44.363394    1337 handler.go:304] Adding GroupVersion admissionregistration.k8s.io v1 to ResourceManager1124vm-test-run-k3s> server # [   27.564807] k3s[1337]: W0622 06:44:44.366329    1337 genericapiserver.go:787] Skipping API admissionregistration.k8s.io/v1beta1 because it has no resources.1125vm-test-run-k3s> server # [   27.569095] k3s[1337]: W0622 06:44:44.369560    1337 genericapiserver.go:787] Skipping API admissionregistration.k8s.io/v1alpha1 because it has no resources.1126vm-test-run-k3s> server # [   27.572249] k3s[1337]: I0622 06:44:44.369983    1337 handler.go:304] Adding GroupVersion events.k8s.io v1 to ResourceManager1127vm-test-run-k3s> server # [   27.574800] k3s[1337]: W0622 06:44:44.370009    1337 genericapiserver.go:787] Skipping API events.k8s.io/v1beta1 because it has no resources.1128vm-test-run-k3s> server # [   27.578339] k3s[1337]: I0622 06:44:44.379857    1337 handler.go:304] Adding GroupVersion resource.k8s.io v1 to ResourceManager1129vm-test-run-k3s> server # [   27.582091] k3s[1337]: W0622 06:44:44.382694    1337 genericapiserver.go:787] Skipping API resource.k8s.io/v1beta2 because it has no resources.1130vm-test-run-k3s> server # [   27.584984] k3s[1337]: W0622 06:44:44.382723    1337 genericapiserver.go:787] Skipping API resource.k8s.io/v1beta1 because it has no resources.1131vm-test-run-k3s> server # [   27.587952] k3s[1337]: W0622 06:44:44.382746    1337 genericapiserver.go:787] Skipping API resource.k8s.io/v1alpha3 because it has no resources.1132vm-test-run-k3s> server # [   27.597863] k3s[1337]: I0622 06:44:44.399379    1337 handler.go:304] Adding GroupVersion apiregistration.k8s.io v1 to ResourceManager1133vm-test-run-k3s> server # [   27.601093] k3s[1337]: W0622 06:44:44.402259    1337 genericapiserver.go:787] Skipping API apiregistration.k8s.io/v1beta1 because it has no resources.1134vm-test-run-k3s> server # [   27.919143] k3s[1337]: time="2026-06-22T06:44:44Z" level=info msg="Running kubelet --cloud-provider=external --config-dir=/var/lib/rancher/k3s/agent/etc/kubelet.conf.d --containerd=/run/k3s/containerd/containerd.sock --hostname-override=server --kubeconfig=/var/lib/rancher/k3s/agent/kubelet.kubeconfig --node-ip=10.0.2.15,fec0::40dd:dd50:c21b:8c9c --node-labels= --read-only-port=0"1135vm-test-run-k3s> server # [   28.074123] k3s[1337]: time="2026-06-22T06:44:44Z" level=info msg="Polling for API server readiness: GET /readyz failed: unknown"1136vm-test-run-k3s> server # [   28.419855] k3s[1337]: I0622 06:44:45.221014    1337 secure_serving.go:211] Serving securely on 127.0.0.1:64441137vm-test-run-k3s> server # [   28.423386] k3s[1337]: I0622 06:44:45.224149    1337 dynamic_cafile_content.go:161] "Starting controller" name="request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt"1138vm-test-run-k3s> server # [   28.427046] k3s[1337]: I0622 06:44:45.224303    1337 dynamic_serving_content.go:135] "Starting controller" name="serving-cert::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.crt::/var/lib/rancher/k3s/server/tls/serving-kube-apiserver.key"1139vm-test-run-k3s> server # [   28.433413] k3s[1337]: I0622 06:44:45.224431    1337 tlsconfig.go:243] "Starting DynamicServingCertificateController"1140vm-test-run-k3s> server # [   28.435945] k3s[1337]: I0622 06:44:45.234304    1337 controller.go:113] "Deleting old lease on startup" lease="kube-system/apiserver-qiczmurnxxy4duoopog2qwr2ru"1141vm-test-run-k3s> server # [   28.440053] k3s[1337]: I0622 06:44:45.241537    1337 system_namespaces_controller.go:66] Starting system namespaces controller1142vm-test-run-k3s> server # [   28.443054] k3s[1337]: I0622 06:44:45.244541    1337 apf_controller.go:377] Starting API Priority and Fairness config controller1143vm-test-run-k3s> server # [   28.445926] k3s[1337]: I0622 06:44:45.247434    1337 aggregator.go:185] waiting for initial CRD sync...1144vm-test-run-k3s> server # [   28.448297] k3s[1337]: I0622 06:44:45.249814    1337 controller.go:80] Starting OpenAPI V3 AggregationController1145vm-test-run-k3s> server # [   28.451311] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Starting apiserver lease garbage collector" logger=k3s1146vm-test-run-k3s> server # [   28.453861] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Waiting for caches to sync" logger=k3s1147vm-test-run-k3s> server # [   28.457338] k3s[1337]: I0622 06:44:45.258147    1337 local_available_controller.go:156] Starting LocalAvailability controller1148vm-test-run-k3s> server # [   28.459991] k3s[1337]: I0622 06:44:45.258172    1337 cache.go:32] Waiting for caches to sync for LocalAvailability controller1149vm-test-run-k3s> server # [   28.462613] k3s[1337]: I0622 06:44:45.258526    1337 remote_available_controller.go:425] Starting RemoteAvailability controller1150vm-test-run-k3s> server # [   28.465293] k3s[1337]: I0622 06:44:45.258550    1337 cache.go:32] Waiting for caches to sync for RemoteAvailability controller1151vm-test-run-k3s> server # [   28.469338] k3s[1337]: I0622 06:44:45.269357    1337 apiservice_controller.go:100] Starting APIServiceRegistrationController1152vm-test-run-k3s> server # [   28.471930] k3s[1337]: I0622 06:44:45.269386    1337 cache.go:32] Waiting for caches to sync for APIServiceRegistrationController controller1153vm-test-run-k3s> server # [   28.474791] k3s[1337]: I0622 06:44:45.269488    1337 controller.go:78] Starting OpenAPI AggregationController1154vm-test-run-k3s> server # [   28.477336] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Starting legacy_token_tracking_controller" logger=k3s1155vm-test-run-k3s> server # [   28.479819] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Waiting for caches to sync" logger=k3s1156vm-test-run-k3s> server # [   28.483112] k3s[1337]: I0622 06:44:45.270253    1337 customresource_discovery_controller.go:294] Starting DiscoveryController1157vm-test-run-k3s> server # [   28.485756] k3s[1337]: I0622 06:44:45.283699    1337 dynamic_serving_content.go:135] "Starting controller" name="aggregator-proxy-cert::/var/lib/rancher/k3s/server/tls/client-auth-proxy.crt::/var/lib/rancher/k3s/server/tls/client-auth-proxy.key"1158vm-test-run-k3s> server # [   28.490475] k3s[1337]: I0622 06:44:45.283940    1337 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt"1159vm-test-run-k3s> server # [   28.496451] k3s[1337]: I0622 06:44:45.297972    1337 crdregistration_controller.go:114] Starting crd-autoregister controller1160vm-test-run-k3s> server # [   28.500092] k3s[1337]: I0622 06:44:45.300665    1337 shared_informer.go:349] "Waiting for caches to sync" controller="crd-autoregister"1161vm-test-run-k3s> server # [   28.502852] k3s[1337]: I0622 06:44:45.301335    1337 default_servicecidr_controller.go:110] Starting kubernetes-service-cidr-controller1162vm-test-run-k3s> server # [   28.505657] k3s[1337]: I0622 06:44:45.301363    1337 shared_informer.go:349] "Waiting for caches to sync" controller="kubernetes-service-cidr-controller"1163vm-test-run-k3s> server # [   28.508754] k3s[1337]: I0622 06:44:45.301496    1337 repairip.go:210] Starting ipallocator-repair-controller1164vm-test-run-k3s> server # [   28.512154] k3s[1337]: I0622 06:44:45.301519    1337 shared_informer.go:349] "Waiting for caches to sync" controller="ipallocator-repair-controller"1165vm-test-run-k3s> server # [   28.527045] k3s[1337]: I0622 06:44:45.328515    1337 controller.go:142] Starting OpenAPI controller1166vm-test-run-k3s> server # [   28.529462] k3s[1337]: I0622 06:44:45.330988    1337 controller.go:90] Starting OpenAPI V3 controller1167vm-test-run-k3s> server # [   28.533093] k3s[1337]: I0622 06:44:45.333350    1337 naming_controller.go:305] Starting NamingConditionController1168vm-test-run-k3s> server # [   28.535489] k3s[1337]: I0622 06:44:45.333517    1337 nonstructuralschema_controller.go:202] Starting NonStructuralSchemaConditionController1169vm-test-run-k3s> server # [   28.538330] k3s[1337]: I0622 06:44:45.333580    1337 apiapproval_controller.go:196] Starting KubernetesAPIApprovalPolicyConformantConditionController1170vm-test-run-k3s> server # [   28.541343] k3s[1337]: I0622 06:44:45.333698    1337 crd_finalizer.go:273] Starting CRDFinalizer1171vm-test-run-k3s> server # [   28.543447] k3s[1337]: I0622 06:44:45.333829    1337 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/var/lib/rancher/k3s/server/tls/client-ca.crt"1172vm-test-run-k3s> server # [   28.546940] k3s[1337]: I0622 06:44:45.333968    1337 dynamic_cafile_content.go:161] "Starting controller" name="request-header::/var/lib/rancher/k3s/server/tls/request-header-ca.crt"1173vm-test-run-k3s> server # [   28.550566] k3s[1337]: I0622 06:44:45.334084    1337 cluster_authentication_trust_controller.go:459] Starting cluster_authentication_trust_controller controller1174vm-test-run-k3s> server # [   28.555288] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Waiting for caches to sync" logger=k3s1175vm-test-run-k3s> server # [   28.750986] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Caches are synced" logger=k3s1176vm-test-run-k3s> server # [   28.756827] k3s[1337]: I0622 06:44:45.558346    1337 cache.go:39] Caches are synced for LocalAvailability controller1177vm-test-run-k3s> server # [   28.762093] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Caches are synced" logger=k3s1178vm-test-run-k3s> server # [   28.768095] k3s[1337]: I0622 06:44:45.569496    1337 cache.go:39] Caches are synced for APIServiceRegistrationController controller1179vm-test-run-k3s> server # [   28.774103] k3s[1337]: I0622 06:44:45.575084    1337 handler_discovery.go:451] Starting ResourceDiscoveryManager1180vm-test-run-k3s> server # [   28.776478] k3s[1337]: time="2026-06-22T06:44:45Z" level=info msg="Caches are synced" logger=k3s1181vm-test-run-k3s> server # [   28.800072] k3s[1337]: I0622 06:44:45.601538    1337 shared_informer.go:356] "Caches are synced" controller="kubernetes-service-cidr-controller"1182vm-test-run-k3s> server # [   28.804092] k3s[1337]: I0622 06:44:45.604675    1337 default_servicecidr_controller.go:169] Creating default ServiceCIDR with CIDRs: [10.43.0.0/16]1183vm-test-run-k3s> server # [   28.843450] k3s[1337]: I0622 06:44:45.644946    1337 shared_informer.go:356] "Caches are synced" controller="node_authorizer"1184vm-test-run-k3s> server # [   28.857754] k3s[1337]: I0622 06:44:45.659271    1337 cache.go:39] Caches are synced for RemoteAvailability controller1185vm-test-run-k3s> server # [   28.869460] k3s[1337]: I0622 06:44:45.670673    1337 shared_informer.go:377] "Caches are synced"1186vm-test-run-k3s> server # [   28.871580] k3s[1337]: I0622 06:44:45.670722    1337 policy_source.go:248] refreshing policies1187vm-test-run-k3s> server # [   28.886222] k3s[1337]: E0622 06:44:45.687640    1337 controller.go:201] "Failed to ensure lease exists, will retry" err="namespaces \"kube-system\" not found" interval="200ms"1188vm-test-run-k3s> server # [   28.898104] k3s[1337]: I0622 06:44:45.698893    1337 apf_controller.go:382] Running API Priority and Fairness config worker1189vm-test-run-k3s> server # [   28.900708] k3s[1337]: I0622 06:44:45.698924    1337 apf_controller.go:385] Running API Priority and Fairness periodic rebalancing process1190vm-test-run-k3s> server # [   28.905574] k3s[1337]: I0622 06:44:45.707089    1337 controller.go:667] quota admission added evaluator for: namespaces1191vm-test-run-k3s> server # [   28.908753] k3s[1337]: I0622 06:44:45.710277    1337 shared_informer.go:356] "Caches are synced" controller="crd-autoregister"1192vm-test-run-k3s> server # [   28.911635] k3s[1337]: I0622 06:44:45.713163    1337 shared_informer.go:356] "Caches are synced" controller="ipallocator-repair-controller"1193vm-test-run-k3s> server # [   28.915122] k3s[1337]: I0622 06:44:45.716647    1337 aggregator.go:187] initial CRD sync complete...1194vm-test-run-k3s> server # [   28.918096] k3s[1337]: I0622 06:44:45.718891    1337 autoregister_controller.go:144] Starting autoregister controller1195vm-test-run-k3s> server # [   28.920519] k3s[1337]: I0622 06:44:45.718925    1337 cache.go:32] Waiting for caches to sync for autoregister controller1196vm-test-run-k3s> server # [   28.923069] k3s[1337]: I0622 06:44:45.718952    1337 cache.go:39] Caches are synced for autoregister controller1197vm-test-run-k3s> server # [   28.961981] k3s[1337]: I0622 06:44:45.763484    1337 cidrallocator.go:302] created ClusterIP allocator for Service CIDR 10.43.0.0/161198vm-test-run-k3s> server # [   28.970164] k3s[1337]: I0622 06:44:45.771682    1337 default_servicecidr_controller.go:231] Setting default ServiceCIDR condition Ready to True1199vm-test-run-k3s> server # [   28.975101] k3s[1337]: E0622 06:44:45.775707    1337 controller.go:95] Unable to perform initial Kubernetes service initialization: namespaces "default" not found1200vm-test-run-k3s> server # [   29.049180] k3s[1337]: I0622 06:44:45.850145    1337 cidrallocator.go:278] updated ClusterIP allocator for Service CIDR 10.43.0.0/161201vm-test-run-k3s> server # [   29.076343] k3s[1337]: I0622 06:44:45.877852    1337 default_servicecidr_controller.go:231] Setting default ServiceCIDR condition Ready to True1202vm-test-run-k3s> server # [   29.144127] k3s[1337]: I0622 06:44:45.945481    1337 controller.go:667] quota admission added evaluator for: leases.coordination.k8s.io1203vm-test-run-k3s> server # [   29.454468] k3s[1337]: I0622 06:44:46.255263    1337 storage_scheduling.go:123] created PriorityClass system-node-critical with value 20000010001204vm-test-run-k3s> server # [   29.469547] k3s[1337]: I0622 06:44:46.271064    1337 storage_scheduling.go:123] created PriorityClass system-cluster-critical with value 20000000001205vm-test-run-k3s> server # [   29.472672] k3s[1337]: I0622 06:44:46.274200    1337 storage_scheduling.go:139] all system priority classes are created successfully or already exist.1206vm-test-run-k3s> server # [   31.620477] k3s[1337]: I0622 06:44:48.421493    1337 controller.go:667] quota admission added evaluator for: roles.rbac.authorization.k8s.io1207vm-test-run-k3s> server # [   31.745126] k3s[1337]: I0622 06:44:48.546481    1337 controller.go:667] quota admission added evaluator for: rolebindings.rbac.authorization.k8s.io1208vm-test-run-k3s> server # [   31.934121] k3s[1337]: Flag --containerd has been deprecated, This is a cadvisor flag that was mistakenly registered with the Kubelet. Due to legacy concerns, it will follow the standard CLI deprecation timeline before being removed.1209vm-test-run-k3s> server # [   31.940510] k3s[1337]: time="2026-06-22T06:44:48Z" level=info msg="Waiting for cloud-controller-manager privileges to become available"1210vm-test-run-k3s> server # [   31.945093] k3s[1337]: time="2026-06-22T06:44:48Z" level=info msg="Creating k3s-supervisor event broadcaster"1211vm-test-run-k3s> server # [   31.948843] k3s[1337]: time="2026-06-22T06:44:48Z" level=info msg="Waiting for untainted node"1212vm-test-run-k3s> server # [   31.952121] k3s[1337]: time="2026-06-22T06:44:48Z" level=info msg="Kube API server is now running"1213vm-test-run-k3s> server # [   31.954353] k3s[1337]: time="2026-06-22T06:44:48Z" level=info msg="k3s is up and running"1214vm-test-run-k3s> server # [   31.957739] systemd[1]: Started k3s service.1215vm-test-run-k3s> server # [   31.960544] systemd[1]: Reached target Multi-User System.1216vm-test-run-k3s> server # [   31.963373] systemd[1]: Startup finished in 976ms (kernel) + 4.400s (initrd) + 26.581s (userspace) = 31.959s.1217vm-test-run-k3s> server # [   31.993137] k3s[1337]: time="2026-06-22T06:44:48Z" level=info msg="Event occurred" apiVersion= fieldPath= kind=Node logger=k3s message="Node and Certificate Authority certificates managed by k3s are OK" object=server reason=CertificateExpirationOK type=Normal1218vm-test-run-k3s> server # [   32.034115] k3s[1337]: I0622 06:44:48.834953    1337 server.go:521] "Kubelet version" kubeletVersion="v1.35.4+k3s1"1219vm-test-run-k3s> server # [   32.036589] k3s[1337]: I0622 06:44:48.834990    1337 server.go:523] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1220vm-test-run-k3s> server # [   32.039149] k3s[1337]: I0622 06:44:48.835024    1337 watchdog_linux.go:95] "Systemd watchdog is not enabled"1221vm-test-run-k3s> server # [   32.041444] k3s[1337]: I0622 06:44:48.835048    1337 watchdog_linux.go:138] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started."1222vm-test-run-k3s> server # [   32.052860] k3s[1337]: I0622 06:44:48.854362    1337 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/var/lib/rancher/k3s/agent/client-ca.crt"1223vm-test-run-k3s> server # [   32.142315] k3s[1337]: I0622 06:44:48.943741    1337 controllermanager.go:189] "Starting" version="v1.35.4+k3s1"1224vm-test-run-k3s> server # [   32.145060] k3s[1337]: I0622 06:44:48.943777    1337 controllermanager.go:191] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1225vm-test-run-k3s> server # [   32.199180] k3s[1337]: I0622 06:44:49.000402    1337 alloc.go:329] "allocated clusterIPs" service="default/kubernetes" clusterIPs={"IPv4":"10.43.0.1"}1226vm-test-run-k3s> server # [   32.231571] k3s[1337]: I0622 06:44:49.032964    1337 server.go:1414] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd"1227vm-test-run-k3s> server # [   32.239566] k3s[1337]: I0622 06:44:49.041038    1337 secure_serving.go:211] Serving securely on 127.0.0.1:102571228vm-test-run-k3s> server # [   32.244353] k3s[1337]: I0622 06:44:49.045765    1337 dynamic_serving_content.go:135] "Starting controller" name="serving-cert::/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.crt::/var/lib/rancher/k3s/server/tls/kube-controller-manager/kube-controller-manager.key"1229vm-test-run-k3s> server # [   32.251338] k3s[1337]: I0622 06:44:49.052690    1337 tlsconfig.go:243] "Starting DynamicServingCertificateController"1230vm-test-run-k3s> server # [   32.255093] k3s[1337]: I0622 06:44:49.056396    1337 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"1231vm-test-run-k3s> server # [   32.259175] k3s[1337]: I0622 06:44:49.056440    1337 shared_informer.go:370] "Waiting for caches to sync"1232vm-test-run-k3s> server # [   32.262497] k3s[1337]: I0622 06:44:49.063738    1337 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController1233vm-test-run-k3s> server # [   32.265377] k3s[1337]: I0622 06:44:49.063783    1337 shared_informer.go:370] "Waiting for caches to sync"1234vm-test-run-k3s> server # [   32.271391] k3s[1337]: I0622 06:44:49.072740    1337 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"1235vm-test-run-k3s> server # [   32.275830] k3s[1337]: I0622 06:44:49.072777    1337 shared_informer.go:370] "Waiting for caches to sync"1236vm-test-run-k3s> server # [   32.323491] k3s[1337]: I0622 06:44:49.124441    1337 server.go:771] "--cgroups-per-qos enabled, but --cgroup-root was not specified.  Defaulting to /"1237vm-test-run-k3s> server # [   32.326601] k3s[1337]: I0622 06:44:49.124505    1337 server.go:832] "NoSwap is set due to memorySwapBehavior not specified" memorySwapBehavior="" FailSwapOn=false1238vm-test-run-k3s> server # [   32.331118] k3s[1337]: I0622 06:44:49.132105    1337 container_manager_linux.go:272] "Container manager verified user specified cgroup-root exists" cgroupRoot=[]1239vm-test-run-k3s> server # [   32.337462] k3s[1337]: I0622 06:44:49.132149    1337 container_manager_linux.go:277] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"server","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2}1240vm-test-run-k3s> server # [   32.359766] k3s[1337]: I0622 06:44:49.132653    1337 topology_manager.go:143] "Creating topology manager with none policy"1241vm-test-run-k3s> server # [   32.362384] k3s[1337]: I0622 06:44:49.136373    1337 container_manager_linux.go:308] "Creating device plugin manager"1242vm-test-run-k3s> server # [   32.366291] k3s[1337]: I0622 06:44:49.136513    1337 container_manager_linux.go:317] "Creating Dynamic Resource Allocation (DRA) manager"1243vm-test-run-k3s> server: (finished: waiting for unit k3s, in 33.35 seconds)1244vm-test-run-k3s> server: must succeed: kubectl cluster-info1245vm-test-run-k3s> server # [   32.375252] k3s[1337]: I0622 06:44:49.176770    1337 shared_informer.go:370] "Waiting for caches to sync"1246vm-test-run-k3s> server # [   32.384681] k3s[1337]: I0622 06:44:49.186200    1337 state_mem.go:41] "Initialized" logger="CPUManager state memory"1247vm-test-run-k3s> server # [   32.387655] k3s[1337]: I0622 06:44:49.189182    1337 kubelet.go:482] "Attempting to sync node with API server"1248vm-test-run-k3s> server # [   32.392121] k3s[1337]: I0622 06:44:49.192047    1337 kubelet.go:383] "Adding static pod path" path="/var/lib/rancher/k3s/agent/pod-manifests"1249vm-test-run-k3s> server # [   32.395454] k3s[1337]: I0622 06:44:49.192098    1337 kubelet.go:394] "Adding apiserver pod source"1250vm-test-run-k3s> server # [   32.397679] k3s[1337]: I0622 06:44:49.192126    1337 apiserver.go:42] "Waiting for node sync before watching apiserver pods"1251vm-test-run-k3s> server # [   32.417313] k3s[1337]: time="2026-06-22T06:44:49Z" level=info msg="Creating embedded CRD addons.k3s.cattle.io"1252vm-test-run-k3s> server # [   32.428097] k3s[1337]: I0622 06:44:49.229539    1337 kuberuntime_manager.go:294] "Container runtime initialized" containerRuntime="containerd" version="2.2.3-k3s1" apiVersion="v1"1253vm-test-run-k3s> server # [   32.433468] k3s[1337]: I0622 06:44:49.234835    1337 kubelet.go:943] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled"1254vm-test-run-k3s> server # [   32.438098] k3s[1337]: I0622 06:44:49.234875    1337 kubelet.go:970] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled"1255vm-test-run-k3s> server # [   32.442164] k3s[1337]: W0622 06:44:49.234945    1337 probe.go:272] Flexvolume plugin directory at /usr/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating.1256vm-test-run-k3s> server # [   32.472329] k3s[1337]: I0622 06:44:49.273284    1337 server.go:1252] "Started kubelet"1257vm-test-run-k3s> server # [   32.484111] k3s[1337]: I0622 06:44:49.284742    1337 server.go:182] "Starting to listen" address="0.0.0.0" port=102501258vm-test-run-k3s> server # [   32.486566] k3s[1337]: I0622 06:44:49.285031    1337 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=101259vm-test-run-k3s> server # [   32.489641] k3s[1337]: I0622 06:44:49.285100    1337 server_v1.go:49] "podresources" method="list" useActivePods=true1260vm-test-run-k3s> server # [   32.492166] k3s[1337]: I0622 06:44:49.285316    1337 server.go:254] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock"1261vm-test-run-k3s> server # [   32.506753] k3s[1337]: I0622 06:44:49.308270    1337 server.go:317] "Adding debug handlers to kubelet server"1262vm-test-run-k3s> server # [   32.518566] k3s[1337]: I0622 06:44:49.320002    1337 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer"1263vm-test-run-k3s> server # [   32.531132] k3s[1337]: I0622 06:44:49.332497    1337 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/rancher/k3s/agent/serving-kubelet.crt::/var/lib/rancher/k3s/agent/serving-kubelet.key"1264vm-test-run-k3s> server # [   32.539106] k3s[1337]: I0622 06:44:49.339731    1337 volume_manager.go:311] "Starting Kubelet Volume Manager"1265vm-test-run-k3s> server # [   32.542198] k3s[1337]: E0622 06:44:49.340586    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1266vm-test-run-k3s> server # [   32.579120] k3s[1337]: I0622 06:44:49.355582    1337 desired_state_of_world_populator.go:146] "Desired state populator starts to run"1267vm-test-run-k3s> server # [   32.581886] k3s[1337]: I0622 06:44:49.355696    1337 reconciler.go:29] "Reconciler: start to sync state"1268vm-test-run-k3s> server # [   32.690683] k3s[1337]: I0622 06:44:49.491805    1337 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory1269vm-test-run-k3s> server # [   32.696571] k3s[1337]: E0622 06:44:49.497094    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1270vm-test-run-k3s> server # [   32.797567] k3s[1337]: I0622 06:44:49.598960    1337 factory.go:223] Registration of the containerd container factory successfully1271vm-test-run-k3s> server # [   32.801363] k3s[1337]: I0622 06:44:49.601681    1337 factory.go:223] Registration of the systemd container factory successfully1272vm-test-run-k3s> server # [   32.825102] k3s[1337]: E0622 06:44:49.626075    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1273vm-test-run-k3s> server # [   32.891832] k3s[1337]: time="2026-06-22T06:44:49Z" level=info msg="Creating embedded CRD etcdsnapshotfiles.k3s.cattle.io"1274vm-test-run-k3s> server # [   32.943067] k3s[1337]: E0622 06:44:49.743275    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1275vm-test-run-k3s> server # [   32.971626] k3s[1337]: I0622 06:44:49.773138    1337 shared_informer.go:377] "Caches are synced"1276vm-test-run-k3s> server # [   32.982591] k3s[1337]: I0622 06:44:49.784109    1337 shared_informer.go:377] "Caches are synced"1277vm-test-run-k3s> server # [   33.041882] k3s[1337]: E0622 06:44:49.843375    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1278vm-test-run-k3s> server # [   33.064171] k3s[1337]: I0622 06:44:49.864713    1337 shared_informer.go:377] "Caches are synced"1279vm-test-run-k3s> server # [   33.069827] k3s[1337]: I0622 06:44:49.871199    1337 cpu_manager.go:225] "Starting" policy="none"1280vm-test-run-k3s> server # [   33.072313] k3s[1337]: I0622 06:44:49.873508    1337 cpu_manager.go:226] "Reconciling" reconcilePeriod="10s"1281vm-test-run-k3s> server # [   33.076099] k3s[1337]: I0622 06:44:49.873544    1337 state_mem.go:41] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory"1282vm-test-run-k3s> server # [   33.096530] k3s[1337]: I0622 06:44:49.898047    1337 policy_none.go:50] "Start"1283vm-test-run-k3s> server # [   33.098654] k3s[1337]: I0622 06:44:49.900167    1337 memory_manager.go:187] "Starting memorymanager" policy="None"1284vm-test-run-k3s> server # [   33.101784] k3s[1337]: I0622 06:44:49.902593    1337 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint"1285vm-test-run-k3s> server # [   33.132688] k3s[1337]: I0622 06:44:49.933702    1337 policy_none.go:44] "Start"1286vm-test-run-k3s> server # [   33.136484] k3s[1337]: I0622 06:44:49.938000    1337 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4"1287vm-test-run-k3s> server # [   33.160442] k3s[1337]: time="2026-06-22T06:44:49Z" level=info msg="Creating embedded CRD helmchartconfigs.helm.cattle.io"1288vm-test-run-k3s> server # [   33.164076] k3s[1337]: E0622 06:44:49.965550    1337 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"server\" not found" node="server"1289vm-test-run-k3s> server # [   33.181525] k3s[1337]: E0622 06:44:49.982906    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1290vm-test-run-k3s> server # [   33.200119] k3s[1337]: I0622 06:44:50.001020    1337 shared_informer.go:377] "Caches are synced"1291vm-test-run-k3s> server # [   33.218722] k3s[1337]: I0622 06:44:50.020235    1337 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6"1292vm-test-run-k3s> server # [   33.221562] k3s[1337]: I0622 06:44:50.023088    1337 status_manager.go:249] "Starting to sync pod status with apiserver"1293vm-test-run-k3s> server # [   33.225322] k3s[1337]: I0622 06:44:50.025548    1337 kubelet.go:2501] "Starting kubelet main sync loop"1294vm-test-run-k3s> server # [   33.227610] k3s[1337]: E0622 06:44:50.025674    1337 kubelet.go:2525] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]"1295vm-test-run-k3s> server # [   33.283440] k3s[1337]: E0622 06:44:50.084760    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1296vm-test-run-k3s> server # [   33.303051] systemd[1]: Created slice libcontainer container kubepods.slice.1297vm-test-run-k3s> server # [   33.308099] k3s[1337]: W0622 06:44:50.109549    1337 lease.go:265] Resetting endpoints for master service "kubernetes" to [10.0.2.15]1298vm-test-run-k3s> server # [   33.331285] k3s[1337]: E0622 06:44:50.132579    1337 kubelet.go:2525] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]"1299vm-test-run-k3s> server # [   33.347696] k3s[1337]: I0622 06:44:50.149161    1337 controller.go:667] quota admission added evaluator for: endpoints1300vm-test-run-k3s> server # [   33.370050] k3s[1337]: I0622 06:44:50.170721    1337 controller.go:667] quota admission added evaluator for: serviceaccounts1301vm-test-run-k3s> server # [   33.376265] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Creating embedded CRD helmcharts.helm.cattle.io"1302vm-test-run-k3s> server # [   33.383956] k3s[1337]: E0622 06:44:50.185473    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1303vm-test-run-k3s> server # [   33.388343] k3s[1337]: I0622 06:44:50.189030    1337 handler.go:304] Adding GroupVersion k3s.cattle.io v1 to ResourceManager1304vm-test-run-k3s> server # [   33.400203] systemd[1]: Created slice libcontainer container kubepods-burstable.slice.1305vm-test-run-k3s> server # [   33.406906] k3s[1337]: I0622 06:44:50.208414    1337 apiserver.go:52] "Watching apiserver"1306vm-test-run-k3s> server # [   33.487104] systemd[1]: Created slice libcontainer container kubepods-besteffort.slice.1307vm-test-run-k3s> server # [   33.492724] k3s[1337]: E0622 06:44:50.294239    1337 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"server\" not found"1308vm-test-run-k3s> server: (finished: must succeed: kubectl cluster-info, in 1.12 seconds)1309vm-test-run-k3s> server: must succeed: /nix/store/idhigam18b13i2h84p8g2c02dm7yqghh-stream-pause | ctr --namespace k8s.io image import -1310vm-test-run-k3s> server # [   33.532110] k3s[1337]: E0622 06:44:50.332968    1337 kubelet.go:2525] "Skipping pod synchronization" err="container runtime status check may not have completed yet"1311vm-test-run-k3s> server # [   33.550910] k3s[1337]: I0622 06:44:50.352098    1337 handler.go:304] Adding GroupVersion k3s.cattle.io v1 to ResourceManager1312vm-test-run-k3s> server # [   33.582884] k3s[1337]: E0622 06:44:50.384314    1337 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint"1313vm-test-run-k3s> server # [   33.587115] k3s[1337]: I0622 06:44:50.388504    1337 eviction_manager.go:194] "Eviction manager: starting control loop"1314vm-test-run-k3s> server # [   33.591186] k3s[1337]: I0622 06:44:50.388538    1337 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s"1315vm-test-run-k3s> server # [   33.598103] k3s[1337]: I0622 06:44:50.397367    1337 plugin_manager.go:121] "Starting Kubelet Plugin Manager"1316vm-test-run-k3s> server # [   33.651190] k3s[1337]: E0622 06:44:50.452691    1337 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime"1317vm-test-run-k3s> server # [   33.655914] k3s[1337]: E0622 06:44:50.457105    1337 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"server\" not found"1318vm-test-run-k3s> server # [   33.703218] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Waiting for CRD helmcharts.helm.cattle.io to become available"1319vm-test-run-k3s> server # [   33.712544] k3s[1337]: I0622 06:44:50.513517    1337 controller.go:667] quota admission added evaluator for: endpointslices.discovery.k8s.io1320vm-test-run-k3s> server # [   33.725731] k3s[1337]: I0622 06:44:50.525920    1337 handler.go:304] Adding GroupVersion helm.cattle.io v1 to ResourceManager1321vm-test-run-k3s> server # [   33.735680] k3s[1337]: I0622 06:44:50.537196    1337 kubelet_node_status.go:74] "Attempting to register node" node="server"1322vm-test-run-k3s> server # [   33.839217] k3s[1337]: E0622 06:44:50.639806    1337 csi_plugin.go:403] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "server" not found1323vm-test-run-k3s> server # [   33.843427] k3s[1337]: I0622 06:44:50.640002    1337 kubelet_node_status.go:77] "Successfully registered node" node="server"1324vm-test-run-k3s> server # [   33.848395] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Done waiting for CRD helmcharts.helm.cattle.io to become available"1325vm-test-run-k3s> server # [   33.854098] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing static file: /var/lib/rancher/k3s/server/static/charts/traefik-39.0.701+up39.0.7.tgz"1326vm-test-run-k3s> server # [   33.857312] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing static file: /var/lib/rancher/k3s/server/static/charts/traefik-crd-39.0.701+up39.0.7.tgz"1327vm-test-run-k3s> server # [   33.860590] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/ccm.yaml"1328vm-test-run-k3s> server # [   33.863284] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/coredns.yaml"1329vm-test-run-k3s> server # [   33.866057] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/local-storage.yaml"1330vm-test-run-k3s> server # [   33.868923] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/rolebindings.yaml"1331vm-test-run-k3s> server # [   33.873139] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/runtimes.yaml"1332vm-test-run-k3s> server # [   33.875976] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Writing manifest: /var/lib/rancher/k3s/server/manifests/traefik.yaml"1333vm-test-run-k3s> server # [   33.890705] k3s[1337]: I0622 06:44:50.692211    1337 handler.go:304] Adding GroupVersion helm.cattle.io v1 to ResourceManager1334vm-test-run-k3s> server # [   33.979811] k3s[1337]: I0622 06:44:50.781278    1337 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world"1335vm-test-run-k3s> server # [   33.997437] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Annotations and labels have been set successfully on node: server"1336vm-test-run-k3s> server # [   34.010574] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Starting flannel with backend vxlan"1337vm-test-run-k3s> server # [   34.125201] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Starting dynamiclistener CN filter node controller with SANs: [127.0.0.1 ::1 localhost server 10.0.2.15 fec0::40dd:dd50:c21b:8c9c 10.43.0.1 kubernetes kubernetes.default kubernetes.default.svc kubernetes.default.svc.cluster.local]"1338vm-test-run-k3s> server # [   34.132367] k3s[1337]: time="2026-06-22T06:44:50Z" level=info msg="Tunnel server egress proxy mode: agent"1339vm-test-run-k3s> server # [   34.195129] k3s[1337]: I0622 06:44:50.996005    1337 kubelet_node_status.go:427] "Fast updating node status as it just became ready"1340vm-test-run-k3s> server # [   34.199791] k3s[1337]: I0622 06:44:51.001179    1337 server.go:218] "Successfully retrieved NodeIPs" NodeIPs=["10.0.2.15","fec0::40dd:dd50:c21b:8c9c"]1341vm-test-run-k3s> server # [   34.203364] k3s[1337]: E0622 06:44:51.001233    1337 server.go:255] "Kube-proxy configuration may be incomplete or incorrect" err="nodePortAddresses is unset; NodePort connections will be accepted on all local IPs. Consider using `--nodeport-addresses primary`"1342vm-test-run-k3s> server # [   34.328514] k3s[1337]: I0622 06:44:51.129946    1337 server.go:264] "kube-proxy running in dual-stack mode" primary ipFamily="IPv4"1343vm-test-run-k3s> server # [   34.331879] k3s[1337]: I0622 06:44:51.132709    1337 server_linux.go:136] "Using iptables Proxier"1344vm-test-run-k3s> server # No 'fromImage' provided1345vm-test-run-k3s> server # Creating layer 1 from paths: ['/nix/store/xx0z77494lfxr8qjwpck246fry05n3nm-xgcc-15.2.0-libgcc']1346vm-test-run-k3s> server # Creating layer 2 from paths: ['/nix/store/wrxyd3k2f4bmh52pr5rpdjxxsm5r2qxm-gcc-15.2.0-libgcc']1347vm-test-run-k3s> server # Creating layer 3 from paths: ['/nix/store/i4gg1f526vl5psg5nqniflj4v77vc1kd-libunistring-1.4.2']1348vm-test-run-k3s> server # [   34.595165] k3s[1337]: I0622 06:44:51.396544    1337 proxier.go:242] "Setting route_localnet=1 to allow node-ports on localhost; to change this either disable iptables.localhostNodePorts (--iptables-localhost-nodeports) or set nodePortAddresses (--nodeport-addresses) to filter loopback addresses" ipFamily="IPv4"1349vm-test-run-k3s> server # [   34.683959] k3s[1337]: I0622 06:44:51.485236    1337 server.go:529] "Version info" version="v1.35.4+k3s1"1350vm-test-run-k3s> server # [   34.687800] k3s[1337]: I0622 06:44:51.489319    1337 server.go:531] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1351vm-test-run-k3s> server # [   34.732936] k3s[1337]: I0622 06:44:51.534128    1337 config.go:200] "Starting service config controller"1352vm-test-run-k3s> server # [   34.805588] k3s[1337]: I0622 06:44:51.606514    1337 shared_informer.go:349] "Waiting for caches to sync" controller="service config"1353vm-test-run-k3s> server # Creating layer 4 from paths: ['/nix/store/sgswwrxkhdlfskklqp4gsbi2cskfg07c-libidn2-2.3.8']1354vm-test-run-k3s> server # [   34.849210] k3s[1337]: I0622 06:44:51.537722    1337 config.go:106] "Starting endpoint slice config controller"1355vm-test-run-k3s> server # [   34.851699] k3s[1337]: I0622 06:44:51.650228    1337 shared_informer.go:349] "Waiting for caches to sync" controller="endpoint slice config"1356vm-test-run-k3s> server # [   34.855584] k3s[1337]: I0622 06:44:51.537762    1337 config.go:403] "Starting serviceCIDR config controller"1357vm-test-run-k3s> server # [   34.858257] k3s[1337]: I0622 06:44:51.650304    1337 shared_informer.go:349] "Waiting for caches to sync" controller="serviceCIDR config"1358vm-test-run-k3s> server # [   34.864595] k3s[1337]: I0622 06:44:51.552076    1337 config.go:309] "Starting node config controller"1359vm-test-run-k3s> server # [   34.866792] k3s[1337]: I0622 06:44:51.665784    1337 shared_informer.go:349] "Waiting for caches to sync" controller="node config"1360vm-test-run-k3s> server # [   34.869858] k3s[1337]: I0622 06:44:51.665813    1337 shared_informer.go:356] "Caches are synced" controller="node config"1361vm-test-run-k3s> server # [   34.924826] k3s[1337]: time="2026-06-22T06:44:51Z" level=info msg="Creating new TLS secret for kube-system/k3s-serving (count: 11): map[listener.cattle.io/cn-10.0.2.15:10.0.2.15 listener.cattle.io/cn-10.43.0.1:10.43.0.1 listener.cattle.io/cn-127.0.0.1:127.0.0.1 listener.cattle.io/cn-__1-f16284:::1 listener.cattle.io/cn-fec0__40dd_dd50_c21b_8c9c-13a797:fec0::40dd:dd50:c21b:8c9c listener.cattle.io/cn-kubernetes:kubernetes listener.cattle.io/cn-kubernetes.default:kubernetes.default listener.cattle.io/cn-kubernetes.default.svc:kubernetes.default.svc listener.cattle.io/cn-kubernetes.default.svc.cluster.local:kubernetes.default.svc.cluster.local listener.cattle.io/cn-localhost:localhost listener.cattle.io/cn-server:server listener.cattle.io/fingerprint:SHA1=F7EED841DA6C10F3991A745B247A13101707FDB7]"1362vm-test-run-k3s> server # [   34.944389] k3s[1337]: I0622 06:44:51.745778    1337 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="podcertificaterequest-cleaner-controller" requiredFeatureGates=["PodCertificateRequest"]1363vm-test-run-k3s> server # [   34.949387] k3s[1337]: I0622 06:44:51.745829    1337 controllermanager.go:579] "Warning: skipping controller" controller="podcertificaterequest-cleaner-controller"1364vm-test-run-k3s> server # [   35.020878] k3s[1337]: time="2026-06-22T06:44:51Z" level=info msg="Active TLS secret kube-system/k3s-serving (ver=238) (count 11): map[listener.cattle.io/cn-10.0.2.15:10.0.2.15 listener.cattle.io/cn-10.43.0.1:10.43.0.1 listener.cattle.io/cn-127.0.0.1:127.0.0.1 listener.cattle.io/cn-__1-f16284:::1 listener.cattle.io/cn-fec0__40dd_dd50_c21b_8c9c-13a797:fec0::40dd:dd50:c21b:8c9c listener.cattle.io/cn-kubernetes:kubernetes listener.cattle.io/cn-kubernetes.default:kubernetes.default listener.cattle.io/cn-kubernetes.default.svc:kubernetes.default.svc listener.cattle.io/cn-kubernetes.default.svc.cluster.local:kubernetes.default.svc.cluster.local listener.cattle.io/cn-localhost:localhost listener.cattle.io/cn-server:server listener.cattle.io/fingerprint:SHA1=F7EED841DA6C10F3991A745B247A13101707FDB7]"1365vm-test-run-k3s> server # [   35.103708] k3s[1337]: I0622 06:44:51.905117    1337 controllermanager.go:627] "Warning: controller is disabled" controller="service-lb-controller"1366vm-test-run-k3s> server # [   35.108304] k3s[1337]: I0622 06:44:51.909770    1337 shared_informer.go:356] "Caches are synced" controller="service config"1367vm-test-run-k3s> server # [   35.150126] k3s[1337]: I0622 06:44:51.950667    1337 shared_informer.go:356] "Caches are synced" controller="serviceCIDR config"1368vm-test-run-k3s> server # [   35.153521] k3s[1337]: I0622 06:44:51.954881    1337 shared_informer.go:356] "Caches are synced" controller="endpoint slice config"1369vm-test-run-k3s> server # Creating layer 5 from paths: ['/nix/store/fjkx1l5cnskzrqacf08z7i8z17256w0j-glibc-2.42-61']1370vm-test-run-k3s> server # [   35.769817] k3s[1337]: time="2026-06-22T06:44:52Z" level=info msg="Starting k3s.cattle.io/v1, Kind=Addon controller"1371vm-test-run-k3s> server # [   35.773553] k3s[1337]: time="2026-06-22T06:44:52Z" level=info msg="Creating deploy event broadcaster"1372vm-test-run-k3s> server # [   35.801979] k3s[1337]: I0622 06:44:52.603186    1337 controller.go:667] quota admission added evaluator for: addons.k3s.cattle.io1373vm-test-run-k3s> server # [   36.044192] k3s[1337]: time="2026-06-22T06:44:52Z" level=info msg="Starting /v1, Kind=Node controller"1374vm-test-run-k3s> server # [   36.049292] k3s[1337]: time="2026-06-22T06:44:52Z" level=info msg="Creating helm-controller event broadcaster"1375vm-test-run-k3s> server # [   36.205848] k3s[1337]: time="2026-06-22T06:44:53Z" level=info msg="Event occurred" apiVersion= fieldPath= kind=Node logger=k3s message="Deferred node password secret validation complete" object=server reason=NodePasswordValidationComplete type=Normal1376vm-test-run-k3s> server # [   36.221670] k3s[1337]: time="2026-06-22T06:44:53Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applying manifest at \"/var/lib/rancher/k3s/server/manifests/ccm.yaml\"" object=kube-system/ccm reason=ApplyingManifest type=Normal1377vm-test-run-k3s> server # [   36.293138] k3s[1337]: time="2026-06-22T06:44:53Z" level=info msg="Adding node OwnerReference to node-password secret server.node-password.k3s"1378vm-test-run-k3s> server # [   36.467123] k3s[1337]: time="2026-06-22T06:44:53Z" level=info msg="Labels and annotations have been set successfully on node: server"1379vm-test-run-k3s> server # [   36.469939] k3s[1337]: time="2026-06-22T06:44:53Z" level=info msg="Cluster dns configmap has been set successfully"1380vm-test-run-k3s> server # [   36.693152] k3s[1337]: I0622 06:44:53.494245    1337 node_lifecycle_controller.go:419] "Controller will reconcile labels" logger="node-lifecycle-controller"1381vm-test-run-k3s> server # [   36.971901] k3s[1337]: I0622 06:44:53.772987    1337 controllermanager.go:627] "Warning: controller is disabled" controller="selinux-warning-controller"1382vm-test-run-k3s> server # [   36.988122] k3s[1337]: time="2026-06-22T06:44:53Z" level=info msg="Waiting for cloud-controller-manager privileges to become available: context deadline exceeded\nPost \"https://127.0.0.1:6444/apis/authorization.k8s.io/v1/subjectaccessreviews?timeout=15m0s\": context deadline exceeded"1383vm-test-run-k3s> server # [   37.381977] k3s[1337]: I0622 06:44:54.183353    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="leases.coordination.k8s.io"1384vm-test-run-k3s> server # [   37.387366] k3s[1337]: I0622 06:44:54.187755    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="endpointslices.discovery.k8s.io"1385vm-test-run-k3s> server # [   37.391379] k3s[1337]: I0622 06:44:54.187824    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="helmcharts.helm.cattle.io"1386vm-test-run-k3s> server # [   37.395618] k3s[1337]: I0622 06:44:54.187875    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="limitranges"1387vm-test-run-k3s> server # [   37.399331] k3s[1337]: I0622 06:44:54.187934    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="jobs.batch"1388vm-test-run-k3s> server # [   37.403390] k3s[1337]: I0622 06:44:54.187982    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="ingresses.networking.k8s.io"1389vm-test-run-k3s> server # [   37.408370] k3s[1337]: I0622 06:44:54.188055    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="daemonsets.apps"1390vm-test-run-k3s> server # [   37.412305] k3s[1337]: I0622 06:44:54.188102    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="statefulsets.apps"1391vm-test-run-k3s> server # [   37.416524] k3s[1337]: I0622 06:44:54.188148    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="endpoints"1392vm-test-run-k3s> server # [   37.420778] k3s[1337]: I0622 06:44:54.188195    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="replicasets.apps"1393vm-test-run-k3s> server # [   37.425290] k3s[1337]: I0622 06:44:54.188262    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="roles.rbac.authorization.k8s.io"1394vm-test-run-k3s> server # [   37.429463] k3s[1337]: I0622 06:44:54.188322    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="rolebindings.rbac.authorization.k8s.io"1395vm-test-run-k3s> server # [   37.434146] k3s[1337]: I0622 06:44:54.188401    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="addons.k3s.cattle.io"1396vm-test-run-k3s> server # [   37.438406] k3s[1337]: I0622 06:44:54.188450    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="podtemplates"1397vm-test-run-k3s> server # [   37.442895] k3s[1337]: I0622 06:44:54.188553    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="networkpolicies.networking.k8s.io"1398vm-test-run-k3s> server # [   37.450653] k3s[1337]: I0622 06:44:54.252120    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="resourceclaimtemplates.resource.k8s.io"1399vm-test-run-k3s> server # [   37.455346] k3s[1337]: I0622 06:44:54.256284    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="helmchartconfigs.helm.cattle.io"1400vm-test-run-k3s> server # [   37.459434] k3s[1337]: I0622 06:44:54.256346    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="serviceaccounts"1401vm-test-run-k3s> server # [   37.463251] k3s[1337]: I0622 06:44:54.256404    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="csistoragecapacities.storage.k8s.io"1402vm-test-run-k3s> server # [   37.468453] k3s[1337]: I0622 06:44:54.256534    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="deployments.apps"1403vm-test-run-k3s> server # [   37.472600] k3s[1337]: I0622 06:44:54.256586    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="poddisruptionbudgets.policy"1404vm-test-run-k3s> server # [   37.477605] k3s[1337]: I0622 06:44:54.268900    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="controllerrevisions.apps"1405vm-test-run-k3s> server # [   37.482444] k3s[1337]: I0622 06:44:54.268953    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="horizontalpodautoscalers.autoscaling"1406vm-test-run-k3s> server # [   37.486764] k3s[1337]: I0622 06:44:54.269096    1337 resource_quota_monitor.go:229] "QuotaMonitor created object count evaluator" logger="resourcequota-controller" resource="cronjobs.batch"1407vm-test-run-k3s> server # [   37.557376] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applied manifest at \"/var/lib/rancher/k3s/server/manifests/ccm.yaml\"" object=kube-system/ccm reason=AppliedManifest type=Normal1408vm-test-run-k3s> server # [   37.584757] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Starting rbac.authorization.k8s.io/v1, Kind=ClusterRoleBinding controller"1409vm-test-run-k3s> server # [   37.642619] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Starting batch/v1, Kind=Job controller"1410vm-test-run-k3s> server # [   37.646399] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Starting /v1, Kind=ServiceAccount controller"1411vm-test-run-k3s> server # [   37.650565] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Starting /v1, Kind=Secret controller"1412vm-test-run-k3s> server # [   37.657163] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Starting /v1, Kind=ConfigMap controller"1413vm-test-run-k3s> server # [   37.703236] k3s[1337]: I0622 06:44:54.504748    1337 controllermanager.go:627] "Warning: controller is disabled" controller="bootstrap-signer-controller"1414vm-test-run-k3s> server # [   37.726719] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Starting helm.cattle.io/v1, Kind=HelmChart controller"1415vm-test-run-k3s> server # [   37.731576] k3s[1337]: time="2026-06-22T06:44:54Z" level=info msg="Starting helm.cattle.io/v1, Kind=HelmChartConfig controller"1416vm-test-run-k3s> server # [   37.855267] k3s[1337]: I0622 06:44:54.656760    1337 controllermanager.go:579] "Warning: skipping controller" controller="storage-version-migrator-controller"1417vm-test-run-k3s> server # [   37.955785] k3s[1337]: I0622 06:44:54.757278    1337 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="kube-apiserver-serving-clustertrustbundle-publisher-controller" requiredFeatureGates=["ClusterTrustBundle"]1418vm-test-run-k3s> server # [   37.961184] k3s[1337]: I0622 06:44:54.762502    1337 controllermanager.go:579] "Warning: skipping controller" controller="kube-apiserver-serving-clustertrustbundle-publisher-controller"1419vm-test-run-k3s> server # [   37.965294] k3s[1337]: I0622 06:44:54.762535    1337 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="storageversion-garbage-collector-controller" requiredFeatureGates=["APIServerIdentity","StorageVersionAPI"]1420vm-test-run-k3s> server # [   37.970330] k3s[1337]: I0622 06:44:54.762560    1337 controllermanager.go:579] "Warning: skipping controller" controller="storageversion-garbage-collector-controller"1421vm-test-run-k3s> server # [   38.140858] k3s[1337]: I0622 06:44:54.941866    1337 serving.go:392] Generated self-signed cert in-memory1422vm-test-run-k3s> server # [   38.291123] k3s[1337]: I0622 06:44:55.092461    1337 serving.go:392] Generated self-signed cert in-memory1423vm-test-run-k3s> server # [   38.469888] k3s[1337]: I0622 06:44:55.271130    1337 controllermanager.go:160] Version: v1.35.4+k3s11424vm-test-run-k3s> server # [   38.487121] k3s[1337]: I0622 06:44:55.288351    1337 secure_serving.go:211] Serving securely on 127.0.0.1:102581425vm-test-run-k3s> server # [   38.490167] k3s[1337]: I0622 06:44:55.291421    1337 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController1426vm-test-run-k3s> server # [   38.492836] k3s[1337]: I0622 06:44:55.291458    1337 shared_informer.go:370] "Waiting for caches to sync"1427vm-test-run-k3s> server # [   38.495330] k3s[1337]: I0622 06:44:55.291551    1337 tlsconfig.go:243] "Starting DynamicServingCertificateController"1428vm-test-run-k3s> server # [   38.498931] k3s[1337]: I0622 06:44:55.300168    1337 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"1429vm-test-run-k3s> server # [   38.503320] k3s[1337]: I0622 06:44:55.300222    1337 shared_informer.go:370] "Waiting for caches to sync"1430vm-test-run-k3s> server # [   38.505653] k3s[1337]: I0622 06:44:55.300282    1337 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"1431vm-test-run-k3s> server # [   38.510492] k3s[1337]: I0622 06:44:55.300311    1337 shared_informer.go:370] "Waiting for caches to sync"1432vm-test-run-k3s> server # [   39.276093] k3s[1337]: I0622 06:44:56.076177    1337 range_allocator.go:113] "No Secondary Service CIDR provided. Skipping filtering out secondary service addresses" logger="node-ipam-controller"1433vm-test-run-k3s> server # [   39.280488] k3s[1337]: I0622 06:44:56.076247    1337 controllermanager.go:627] "Warning: controller is disabled" controller="cloud-node-lifecycle-controller"1434vm-test-run-k3s> server # [   39.284639] k3s[1337]: I0622 06:44:56.086143    1337 controllermanager.go:329] Started "cloud-node-lifecycle-controller"1435vm-test-run-k3s> server # [   39.287855] k3s[1337]: W0622 06:44:56.088813    1337 controllermanager.go:306] "service-lb-controller" is disabled1436vm-test-run-k3s> server # [   39.291388] k3s[1337]: W0622 06:44:56.088840    1337 controllermanager.go:306] "node-route-controller" is disabled1437vm-test-run-k3s> server # [   39.293847] k3s[1337]: I0622 06:44:56.091878    1337 controllermanager.go:329] Started "cloud-node-controller"1438vm-test-run-k3s> server # [   39.298450] k3s[1337]: I0622 06:44:56.099887    1337 node_lifecycle_controller.go:112] Sending events to api server1439vm-test-run-k3s> server # [   39.301400] k3s[1337]: I0622 06:44:56.102517    1337 node_controller.go:176] Sending events to api server.1440vm-test-run-k3s> server # [   39.304909] k3s[1337]: I0622 06:44:56.106356    1337 shared_informer.go:377] "Caches are synced"1441vm-test-run-k3s> server # [   39.308102] k3s[1337]: I0622 06:44:56.109191    1337 node_controller.go:185] Waiting for informer caches to sync1442vm-test-run-k3s> server # [   39.315102] k3s[1337]: I0622 06:44:56.116454    1337 shared_informer.go:377] "Caches are synced"1443vm-test-run-k3s> server # [   39.390634] k3s[1337]: I0622 06:44:56.192120    1337 shared_informer.go:377] "Caches are synced"1444vm-test-run-k3s> server # [   39.408243] k3s[1337]: I0622 06:44:56.209535    1337 node_controller.go:429] Initializing node server with cloud provider1445vm-test-run-k3s> server # [   39.482126] k3s[1337]: time="2026-06-22T06:44:56Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Deleting manifest at \"/var/lib/rancher/k3s/server/manifests/coredns.yaml\"" object=kube-system/coredns reason=DeletingManifest type=Normal1446vm-test-run-k3s> server # [   39.579211] k3s[1337]: I0622 06:44:56.380515    1337 node_controller.go:474] Successfully initialized node server with cloud provider1447vm-test-run-k3s> server # [   39.583381] k3s[1337]: I0622 06:44:56.384113    1337 event.go:389] "Event occurred" object="server" fieldPath="" kind="Node" apiVersion="v1" type="Normal" reason="Synced" message="Node synced successfully"1448vm-test-run-k3s> server # [   39.646063] k3s[1337]: I0622 06:44:56.447337    1337 server.go:173] "Starting Kubernetes Scheduler" version="v1.35.4+k3s1"1449vm-test-run-k3s> server # [   39.649310] k3s[1337]: I0622 06:44:56.447374    1337 server.go:175] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""1450vm-test-run-k3s> server # [   39.692435] k3s[1337]: I0622 06:44:56.493797    1337 secure_serving.go:211] Serving securely on 127.0.0.1:102591451vm-test-run-k3s> server # [   39.696216] k3s[1337]: I0622 06:44:56.496802    1337 requestheader_controller.go:180] Starting RequestHeaderAuthRequestController1452vm-test-run-k3s> server # [   39.698901] k3s[1337]: I0622 06:44:56.496834    1337 shared_informer.go:370] "Waiting for caches to sync"1453vm-test-run-k3s> server # [   39.701375] k3s[1337]: I0622 06:44:56.496920    1337 dynamic_serving_content.go:135] "Starting controller" name="serving-cert::/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.crt::/var/lib/rancher/k3s/server/tls/kube-scheduler/kube-scheduler.key"1454vm-test-run-k3s> server # [   39.707191] k3s[1337]: I0622 06:44:56.497078    1337 tlsconfig.go:243] "Starting DynamicServingCertificateController"1455vm-test-run-k3s> server # [   39.715547] k3s[1337]: I0622 06:44:56.516412    1337 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::client-ca-file"1456vm-test-run-k3s> server # [   39.719331] k3s[1337]: I0622 06:44:56.516450    1337 shared_informer.go:370] "Waiting for caches to sync"1457vm-test-run-k3s> server # [   39.722286] k3s[1337]: I0622 06:44:56.516530    1337 configmap_cafile_content.go:205] "Starting controller" name="client-ca::kube-system::extension-apiserver-authentication::requestheader-client-ca-file"1458vm-test-run-k3s> server # [   39.726268] k3s[1337]: I0622 06:44:56.516558    1337 shared_informer.go:370] "Waiting for caches to sync"1459vm-test-run-k3s> server # [   39.915576] k3s[1337]: I0622 06:44:56.716943    1337 shared_informer.go:377] "Caches are synced"1460vm-test-run-k3s> server # [   39.941106] k3s[1337]: I0622 06:44:56.742011    1337 shared_informer.go:377] "Caches are synced"1461vm-test-run-k3s> server # [   40.000644] k3s[1337]: I0622 06:44:56.801990    1337 shared_informer.go:377] "Caches are synced"1462vm-test-run-k3s> server # [   40.084940] k3s[1337]: I0622 06:44:56.886174    1337 controllermanager.go:627] "Warning: controller is disabled" controller="node-route-controller"1463vm-test-run-k3s> server # [   40.115627] k3s[1337]: I0622 06:44:56.916996    1337 controller_descriptor.go:99] "Controller is disabled by a feature gate" controller="device-taint-eviction-controller" requiredFeatureGates=["DynamicResourceAllocation","DRADeviceTaints"]1464vm-test-run-k3s> server # [   40.121319] k3s[1337]: I0622 06:44:56.917030    1337 controllermanager.go:579] "Warning: skipping controller" controller="device-taint-eviction-controller"1465vm-test-run-k3s> server # [   40.305878] k3s[1337]: I0622 06:44:57.106340    1337 servicecidrs_controller.go:136] "Starting" controller="service-cidr-controller"1466vm-test-run-k3s> server # [   40.309794] k3s[1337]: I0622 06:44:57.106380    1337 shared_informer.go:370] "Waiting for caches to sync"1467vm-test-run-k3s> server # [   40.399729] k3s[1337]: I0622 06:44:57.201148    1337 shared_informer.go:370] "Waiting for caches to sync"1468vm-test-run-k3s> server # [   40.402721] k3s[1337]: I0622 06:44:57.203531    1337 namespace_controller.go:202] "Starting namespace controller"1469vm-test-run-k3s> server # [   40.405717] k3s[1337]: I0622 06:44:57.203560    1337 shared_informer.go:370] "Waiting for caches to sync"1470vm-test-run-k3s> server # [   40.408410] k3s[1337]: I0622 06:44:57.206849    1337 disruption.go:458] "Sending events to api server."1471vm-test-run-k3s> server # [   40.411210] k3s[1337]: I0622 06:44:57.206932    1337 disruption.go:465] "Starting disruption controller"1472vm-test-run-k3s> server # [   40.413804] k3s[1337]: I0622 06:44:57.206955    1337 shared_informer.go:370] "Waiting for caches to sync"1473vm-test-run-k3s> server # [   40.416619] k3s[1337]: I0622 06:44:57.209843    1337 cronjob_controllerv2.go:143] "Starting cronjob controller v2"1474vm-test-run-k3s> server # [   40.419212] k3s[1337]: I0622 06:44:57.209876    1337 shared_informer.go:370] "Waiting for caches to sync"1475vm-test-run-k3s> server # [   40.421805] k3s[1337]: I0622 06:44:57.212188    1337 endpoints_controller.go:193] "Starting endpoint controller"1476vm-test-run-k3s> server # [   40.425308] k3s[1337]: I0622 06:44:57.212218    1337 shared_informer.go:370] "Waiting for caches to sync"1477vm-test-run-k3s> server # [   40.427680] k3s[1337]: I0622 06:44:57.212284    1337 taint_eviction.go:283] "Starting" controller="taint-eviction-controller"1478vm-test-run-k3s> server # [   40.430442] k3s[1337]: I0622 06:44:57.212393    1337 taint_eviction.go:288] "Sending events to API server"1479vm-test-run-k3s> server # [   40.433424] k3s[1337]: I0622 06:44:57.212416    1337 shared_informer.go:370] "Waiting for caches to sync"1480vm-test-run-k3s> server # [   40.435902] k3s[1337]: I0622 06:44:57.215257    1337 deployment_controller.go:172] "Starting controller" controller="deployment"1481vm-test-run-k3s> server # [   40.439257] k3s[1337]: I0622 06:44:57.215288    1337 shared_informer.go:370] "Waiting for caches to sync"1482vm-test-run-k3s> server # [   40.442121] k3s[1337]: I0622 06:44:57.217902    1337 endpointslice_controller.go:283] "Starting endpoint slice controller"1483vm-test-run-k3s> server # [   40.445165] k3s[1337]: I0622 06:44:57.217942    1337 shared_informer.go:370] "Waiting for caches to sync"1484vm-test-run-k3s> server # [   40.447595] k3s[1337]: I0622 06:44:57.218012    1337 gc_controller.go:98] "Starting GC controller"1485vm-test-run-k3s> server # [   40.450330] k3s[1337]: I0622 06:44:57.218039    1337 shared_informer.go:370] "Waiting for caches to sync"1486vm-test-run-k3s> server # [   40.453341] k3s[1337]: I0622 06:44:57.225774    1337 pvc_protection_controller.go:166] "Starting PVC protection controller"1487vm-test-run-k3s> server # [   40.455947] k3s[1337]: I0622 06:44:57.225803    1337 shared_informer.go:370] "Waiting for caches to sync"1488vm-test-run-k3s> server # [   40.458599] k3s[1337]: I0622 06:44:57.225878    1337 legacy_serviceaccount_token_cleaner.go:103] "Starting legacy service account token cleaner controller"1489vm-test-run-k3s> server # [   40.462284] k3s[1337]: I0622 06:44:57.225904    1337 shared_informer.go:370] "Waiting for caches to sync"1490vm-test-run-k3s> server # [   40.465076] k3s[1337]: I0622 06:44:57.226421    1337 stateful_set.go:180] "Starting stateful set controller"1491vm-test-run-k3s> server # [   40.467476] k3s[1337]: I0622 06:44:57.226449    1337 shared_informer.go:370] "Waiting for caches to sync"1492vm-test-run-k3s> server # [   40.470357] k3s[1337]: I0622 06:44:57.226538    1337 certificate_controller.go:120] "Starting certificate controller" name="csrapproving"1493vm-test-run-k3s> server # [   40.473542] k3s[1337]: I0622 06:44:57.226565    1337 shared_informer.go:370] "Waiting for caches to sync"1494vm-test-run-k3s> server # [   40.476341] k3s[1337]: I0622 06:44:57.234695    1337 attach_detach_controller.go:335] "Starting attach detach controller"1495vm-test-run-k3s> server # [   40.479265] k3s[1337]: I0622 06:44:57.234728    1337 shared_informer.go:370] "Waiting for caches to sync"1496vm-test-run-k3s> server # [   40.481891] k3s[1337]: I0622 06:44:57.234808    1337 pv_protection_controller.go:81] "Starting PV protection controller"1497vm-test-run-k3s> server # [   40.485283] k3s[1337]: I0622 06:44:57.234834    1337 shared_informer.go:370] "Waiting for caches to sync"1498vm-test-run-k3s> server # [   40.487565] k3s[1337]: I0622 06:44:57.234927    1337 publisher.go:107] "Starting root CA cert publisher controller"1499vm-test-run-k3s> server # [   40.490346] k3s[1337]: I0622 06:44:57.234955    1337 shared_informer.go:370] "Waiting for caches to sync"1500vm-test-run-k3s> server # [   40.494289] k3s[1337]: I0622 06:44:57.240190    1337 endpointslicemirroring_controller.go:226] "Starting EndpointSliceMirroring controller"1501vm-test-run-k3s> server # [   40.497514] k3s[1337]: I0622 06:44:57.240270    1337 shared_informer.go:370] "Waiting for caches to sync"1502vm-test-run-k3s> server # [   40.500290] k3s[1337]: I0622 06:44:57.240430    1337 replica_set.go:241] "Starting controller" name="replicationcontroller"1503vm-test-run-k3s> server # [   40.503120] k3s[1337]: I0622 06:44:57.240457    1337 shared_informer.go:370] "Waiting for caches to sync"1504vm-test-run-k3s> server # [   40.505756] k3s[1337]: I0622 06:44:57.243194    1337 daemon_controller.go:309] "Starting daemon sets controller"1505vm-test-run-k3s> server # [   40.508617] k3s[1337]: I0622 06:44:57.243221    1337 shared_informer.go:370] "Waiting for caches to sync"1506vm-test-run-k3s> server # [   40.510956] k3s[1337]: I0622 06:44:57.243338    1337 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-legacy-unknown"1507vm-test-run-k3s> server # [   40.514703] k3s[1337]: I0622 06:44:57.246397    1337 shared_informer.go:370] "Waiting for caches to sync"1508vm-test-run-k3s> server # [   40.517343] k3s[1337]: I0622 06:44:57.246556    1337 job_controller.go:254] "Starting job controller"1509vm-test-run-k3s> server # [   40.520118] k3s[1337]: I0622 06:44:57.246583    1337 shared_informer.go:370] "Waiting for caches to sync"1510vm-test-run-k3s> server # [   40.523130] k3s[1337]: I0622 06:44:57.251748    1337 replica_set.go:241] "Starting controller" name="replicaset"1511vm-test-run-k3s> server # [   40.525500] k3s[1337]: I0622 06:44:57.251779    1337 shared_informer.go:370] "Waiting for caches to sync"1512vm-test-run-k3s> server # [   40.527785] k3s[1337]: I0622 06:44:57.254183    1337 node_ipam_controller.go:142] "Starting ipam controller"1513vm-test-run-k3s> server # [   40.530227] k3s[1337]: I0622 06:44:57.254211    1337 shared_informer.go:370] "Waiting for caches to sync"1514vm-test-run-k3s> server # [   40.533087] k3s[1337]: I0622 06:44:57.254340    1337 pv_controller_base.go:307] "Starting persistent volume controller"1515vm-test-run-k3s> server # [   40.536337] k3s[1337]: I0622 06:44:57.254368    1337 shared_informer.go:370] "Waiting for caches to sync"1516vm-test-run-k3s> server # [   40.539490] k3s[1337]: I0622 06:44:57.254460    1337 clusterroleaggregation_controller.go:194] "Starting ClusterRoleAggregator controller"1517vm-test-run-k3s> server # [   40.542979] k3s[1337]: I0622 06:44:57.254496    1337 shared_informer.go:370] "Waiting for caches to sync"1518vm-test-run-k3s> server # [   40.545752] k3s[1337]: I0622 06:44:57.254564    1337 vac_protection_controller.go:206] "Starting VAC protection controller"1519vm-test-run-k3s> server # [   40.549069] k3s[1337]: I0622 06:44:57.254590    1337 shared_informer.go:370] "Waiting for caches to sync"1520vm-test-run-k3s> server # [   40.551709] k3s[1337]: I0622 06:44:57.259719    1337 ttlafterfinished_controller.go:112] "Starting TTL after finished controller"1521vm-test-run-k3s> server # [   40.554879] k3s[1337]: I0622 06:44:57.259746    1337 shared_informer.go:370] "Waiting for caches to sync"1522vm-test-run-k3s> server # [   40.557496] k3s[1337]: I0622 06:44:57.259813    1337 serviceaccounts_controller.go:117] "Starting service account controller"1523vm-test-run-k3s> server # [   40.560347] k3s[1337]: I0622 06:44:57.259841    1337 shared_informer.go:370] "Waiting for caches to sync"1524vm-test-run-k3s> server # [   40.563352] k3s[1337]: I0622 06:44:57.263230    1337 cleaner.go:83] "Starting CSR cleaner controller"1525vm-test-run-k3s> server # [   40.565878] k3s[1337]: I0622 06:44:57.263320    1337 ttl_controller.go:127] "Starting TTL controller"1526vm-test-run-k3s> server # [   40.568322] k3s[1337]: I0622 06:44:57.263346    1337 shared_informer.go:370] "Waiting for caches to sync"1527vm-test-run-k3s> server # [   40.571297] k3s[1337]: I0622 06:44:57.263718    1337 expand_controller.go:328] "Starting expand controller"1528vm-test-run-k3s> server # [   40.573579] k3s[1337]: I0622 06:44:57.263746    1337 shared_informer.go:370] "Waiting for caches to sync"1529vm-test-run-k3s> server # [   40.576309] k3s[1337]: I0622 06:44:57.266031    1337 shared_informer.go:370] "Waiting for caches to sync"1530vm-test-run-k3s> server # [   40.578695] k3s[1337]: I0622 06:44:57.271548    1337 horizontal.go:204] "Starting HPA controller"1531vm-test-run-k3s> server # [   40.581317] k3s[1337]: I0622 06:44:57.271580    1337 shared_informer.go:370] "Waiting for caches to sync"1532vm-test-run-k3s> server # [   40.584285] k3s[1337]: I0622 06:44:57.274781    1337 tokencleaner.go:117] "Starting token cleaner controller"1533vm-test-run-k3s> server # [   40.586576] k3s[1337]: I0622 06:44:57.274813    1337 shared_informer.go:370] "Waiting for caches to sync"1534vm-test-run-k3s> server # [   40.588789] k3s[1337]: I0622 06:44:57.280554    1337 controller.go:174] "Starting ephemeral volume controller"1535vm-test-run-k3s> server # [   40.591807] k3s[1337]: I0622 06:44:57.283167    1337 shared_informer.go:370] "Waiting for caches to sync"1536vm-test-run-k3s> server # [   40.594743] k3s[1337]: I0622 06:44:57.283332    1337 controller.go:423] "Starting resource claim controller"1537vm-test-run-k3s> server # [   40.598297] k3s[1337]: I0622 06:44:57.285923    1337 shared_informer.go:370] "Waiting for caches to sync"1538vm-test-run-k3s> server # [   40.600545] k3s[1337]: I0622 06:44:57.324126    1337 node_lifecycle_controller.go:453] "Sending events to api server"1539vm-test-run-k3s> server # [   40.602995] k3s[1337]: I0622 06:44:57.324206    1337 node_lifecycle_controller.go:460] "Starting node controller"1540vm-test-run-k3s> server # [   40.605424] k3s[1337]: I0622 06:44:57.324233    1337 shared_informer.go:370] "Waiting for caches to sync"1541vm-test-run-k3s> server # [   40.607668] k3s[1337]: I0622 06:44:57.324343    1337 resource_quota_controller.go:297] "Starting resource quota controller"1542vm-test-run-k3s> server # [   40.611678] k3s[1337]: I0622 06:44:57.324371    1337 shared_informer.go:370] "Waiting for caches to sync"1543vm-test-run-k3s> server # [   40.614061] k3s[1337]: I0622 06:44:57.337156    1337 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-kubelet-serving"1544vm-test-run-k3s> server # [   40.617120] k3s[1337]: I0622 06:44:57.337195    1337 shared_informer.go:370] "Waiting for caches to sync"1545vm-test-run-k3s> server # [   40.619819] k3s[1337]: I0622 06:44:57.337343    1337 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-kubelet-client"1546vm-test-run-k3s> server # [   40.623301] k3s[1337]: I0622 06:44:57.337370    1337 shared_informer.go:370] "Waiting for caches to sync"1547vm-test-run-k3s> server # [   40.626343] k3s[1337]: I0622 06:44:57.337449    1337 certificate_controller.go:120] "Starting certificate controller" name="csrsigning-kube-apiserver-client"1548vm-test-run-k3s> server # [   40.630281] k3s[1337]: I0622 06:44:57.337486    1337 shared_informer.go:370] "Waiting for caches to sync"1549vm-test-run-k3s> server # [   40.632521] k3s[1337]: I0622 06:44:57.344133    1337 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/server-ca.key"1550vm-test-run-k3s> server # [   40.638446] k3s[1337]: I0622 06:44:57.352893    1337 garbagecollector.go:141] "Starting controller" controller="garbagecollector"1551vm-test-run-k3s> server # [   40.641341] k3s[1337]: I0622 06:44:57.352962    1337 shared_informer.go:370] "Waiting for caches to sync"1552vm-test-run-k3s> server # [   40.643582] k3s[1337]: I0622 06:44:57.377518    1337 resource_quota_monitor.go:309] "QuotaMonitor running"1553vm-test-run-k3s> server # [   40.645814] k3s[1337]: I0622 06:44:57.383197    1337 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/server-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/server-ca.key"1554vm-test-run-k3s> server # [   40.650284] k3s[1337]: I0622 06:44:57.383342    1337 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/client-ca.key"1555vm-test-run-k3s> server # [   40.655367] k3s[1337]: I0622 06:44:57.383498    1337 dynamic_serving_content.go:135] "Starting controller" name="csr-controller::/var/lib/rancher/k3s/server/tls/client-ca.nochain.crt::/var/lib/rancher/k3s/server/tls/client-ca.key"1556vm-test-run-k3s> server # [   40.660269] k3s[1337]: I0622 06:44:57.398833    1337 graph_builder.go:386] "Running" component="GraphBuilder"1557vm-test-run-k3s> server # [   40.836604] k3s[1337]: I0622 06:44:57.637439    1337 shared_informer.go:370] "Waiting for caches to sync"1558vm-test-run-k3s> server # [   41.470666] k3s[1337]: time="2026-06-22T06:44:58Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Deleting manifest at \"/var/lib/rancher/k3s/server/manifests/local-storage.yaml\"" object=kube-system/local-storage reason=DeletingManifest type=Normal1559vm-test-run-k3s> server # [   41.476654] k3s[1337]: I0622 06:44:58.271242    1337 shared_informer.go:377] "Caches are synced"1560vm-test-run-k3s> server # [   41.554136] k3s[1337]: I0622 06:44:58.355281    1337 shared_informer.go:377] "Caches are synced"1561vm-test-run-k3s> server # [   41.587527] k3s[1337]: I0622 06:44:58.388080    1337 shared_informer.go:377] "Caches are synced"1562vm-test-run-k3s> server # [   41.596381] k3s[1337]: I0622 06:44:58.397201    1337 shared_informer.go:377] "Caches are synced"1563vm-test-run-k3s> server # [   41.605303] k3s[1337]: I0622 06:44:58.405705    1337 shared_informer.go:377] "Caches are synced"1564vm-test-run-k3s> server # [   41.607418] k3s[1337]: I0622 06:44:58.405779    1337 shared_informer.go:377] "Caches are synced"1565vm-test-run-k3s> server # [   41.609518] k3s[1337]: I0622 06:44:58.406153    1337 shared_informer.go:377] "Caches are synced"1566vm-test-run-k3s> server # [   41.611618] k3s[1337]: I0622 06:44:58.406228    1337 range_allocator.go:177] "Sending events to api server"1567vm-test-run-k3s> server # [   41.615362] k3s[1337]: I0622 06:44:58.406279    1337 range_allocator.go:181] "Starting range CIDR allocator"1568vm-test-run-k3s> server # [   41.617668] k3s[1337]: I0622 06:44:58.406304    1337 shared_informer.go:370] "Waiting for caches to sync"1569vm-test-run-k3s> server # [   41.619936] k3s[1337]: I0622 06:44:58.406328    1337 shared_informer.go:377] "Caches are synced"1570vm-test-run-k3s> server # [   41.622628] k3s[1337]: I0622 06:44:58.416105    1337 shared_informer.go:377] "Caches are synced"1571vm-test-run-k3s> server # [   41.639787] k3s[1337]: I0622 06:44:58.440342    1337 actual_state_of_world.go:541] "Failed to update statusUpdateNeeded field in actual state of world" logger="persistentvolume-attach-detach-controller" err="Failed to set statusUpdateNeeded to needed true, because nodeName=\"server\" does not exist"1572vm-test-run-k3s> server # [   41.647722] k3s[1337]: I0622 06:44:58.449242    1337 shared_informer.go:377] "Caches are synced"1573vm-test-run-k3s> server # [   41.651173] k3s[1337]: I0622 06:44:58.452369    1337 shared_informer.go:377] "Caches are synced"1574vm-test-run-k3s> server # [   41.655098] k3s[1337]: I0622 06:44:58.456576    1337 shared_informer.go:377] "Caches are synced"1575vm-test-run-k3s> server # [   41.657978] k3s[1337]: I0622 06:44:58.459273    1337 shared_informer.go:377] "Caches are synced"1576vm-test-run-k3s> server # [   41.661196] k3s[1337]: I0622 06:44:58.462029    1337 shared_informer.go:377] "Caches are synced"1577vm-test-run-k3s> server # [   41.663507] k3s[1337]: I0622 06:44:58.462113    1337 shared_informer.go:377] "Caches are synced"1578vm-test-run-k3s> server # [   41.665640] k3s[1337]: I0622 06:44:58.462364    1337 shared_informer.go:377] "Caches are synced"1579vm-test-run-k3s> server # [   41.670506] k3s[1337]: I0622 06:44:58.471486    1337 shared_informer.go:377] "Caches are synced"1580vm-test-run-k3s> server # [   41.673495] k3s[1337]: I0622 06:44:58.474993    1337 shared_informer.go:377] "Caches are synced"1581vm-test-run-k3s> server # [   41.677189] k3s[1337]: I0622 06:44:58.478547    1337 shared_informer.go:377] "Caches are synced"1582vm-test-run-k3s> server # [   41.681690] k3s[1337]: I0622 06:44:58.483180    1337 shared_informer.go:377] "Caches are synced"1583vm-test-run-k3s> server # [   41.685184] k3s[1337]: I0622 06:44:58.485789    1337 node_lifecycle_controller.go:1234] "Initializing eviction metric for zone" zone=""1584vm-test-run-k3s> server # [   41.687937] k3s[1337]: I0622 06:44:58.485975    1337 shared_informer.go:377] "Caches are synced"1585vm-test-run-k3s> server # [   41.690089] k3s[1337]: I0622 06:44:58.486121    1337 shared_informer.go:377] "Caches are synced"1586vm-test-run-k3s> server # [   41.692974] k3s[1337]: I0622 06:44:58.486186    1337 shared_informer.go:377] "Caches are synced"1587vm-test-run-k3s> server # [   41.698170] k3s[1337]: I0622 06:44:58.499522    1337 shared_informer.go:377] "Caches are synced"1588vm-test-run-k3s> server # [   41.702107] k3s[1337]: I0622 06:44:58.502871    1337 shared_informer.go:377] "Caches are synced"1589vm-test-run-k3s> server # [   41.706829] k3s[1337]: I0622 06:44:58.508321    1337 shared_informer.go:377] "Caches are synced"1590vm-test-run-k3s> server # [   41.709716] k3s[1337]: I0622 06:44:58.510885    1337 shared_informer.go:377] "Caches are synced"1591vm-test-run-k3s> server # [   41.714767] k3s[1337]: I0622 06:44:58.516224    1337 shared_informer.go:377] "Caches are synced"1592vm-test-run-k3s> server # [   41.717439] k3s[1337]: I0622 06:44:58.518571    1337 shared_informer.go:377] "Caches are synced"1593vm-test-run-k3s> server # [   41.721162] k3s[1337]: I0622 06:44:58.522038    1337 shared_informer.go:377] "Caches are synced"1594vm-test-run-k3s> server # [   41.723602] k3s[1337]: I0622 06:44:58.522507    1337 shared_informer.go:377] "Caches are synced"1595vm-test-run-k3s> server # [   41.729117] k3s[1337]: I0622 06:44:58.530436    1337 shared_informer.go:377] "Caches are synced"1596vm-test-run-k3s> server # [   41.731619] k3s[1337]: I0622 06:44:58.530520    1337 shared_informer.go:377] "Caches are synced"1597vm-test-run-k3s> server # [   41.735310] k3s[1337]: I0622 06:44:58.535873    1337 shared_informer.go:377] "Caches are synced"1598vm-test-run-k3s> server # [   41.737411] k3s[1337]: I0622 06:44:58.536387    1337 shared_informer.go:377] "Caches are synced"1599vm-test-run-k3s> server # [   41.747713] k3s[1337]: I0622 06:44:58.549231    1337 shared_informer.go:377] "Caches are synced"1600vm-test-run-k3s> server # [   41.754099] k3s[1337]: I0622 06:44:58.555412    1337 shared_informer.go:377] "Caches are synced"1601vm-test-run-k3s> server # [   41.773217] k3s[1337]: I0622 06:44:58.574523    1337 node_lifecycle_controller.go:886] "Missing timestamp for Node. Assuming now as a timestamp" node="server"1602vm-test-run-k3s> server # [   41.782403] k3s[1337]: I0622 06:44:58.583554    1337 shared_informer.go:377] "Caches are synced"1603vm-test-run-k3s> server # [   41.791103] k3s[1337]: I0622 06:44:58.592257    1337 shared_informer.go:377] "Caches are synced"1604vm-test-run-k3s> server # [   41.795455] k3s[1337]: I0622 06:44:58.595971    1337 shared_informer.go:377] "Caches are synced"1605vm-test-run-k3s> server # [   41.804808] k3s[1337]: I0622 06:44:58.606316    1337 node_lifecycle_controller.go:1080] "Controller detected that zone is now in new state" zone="" newState="Normal"1606vm-test-run-k3s> server # [   41.823916] k3s[1337]: I0622 06:44:58.625423    1337 shared_informer.go:377] "Caches are synced"1607vm-test-run-k3s> server # [   41.828124] k3s[1337]: I0622 06:44:58.629365    1337 shared_informer.go:377] "Caches are synced"1608vm-test-run-k3s> server # [   41.836209] k3s[1337]: I0622 06:44:58.637559    1337 shared_informer.go:377] "Caches are synced"1609vm-test-run-k3s> server # [   42.196128] k3s[1337]: I0622 06:44:58.996927    1337 range_allocator.go:433] "Set node PodCIDR" node="server" podCIDRs=["10.42.0.0/24"]1610vm-test-run-k3s> server # [   42.329157] k3s[1337]: I0622 06:44:59.130087    1337 shared_informer.go:370] "Waiting for caches to sync"1611vm-test-run-k3s> server # [   42.505540] k3s[1337]: time="2026-06-22T06:44:59Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applying manifest at \"/var/lib/rancher/k3s/server/manifests/rolebindings.yaml\"" object=kube-system/rolebindings reason=ApplyingManifest type=Normal1612vm-test-run-k3s> server # [   42.732144] k3s[1337]: I0622 06:44:59.533099    1337 cidrallocator.go:278] updated ClusterIP allocator for Service CIDR 10.43.0.0/161613vm-test-run-k3s> server # [   42.826662] k3s[1337]: I0622 06:44:59.627506    1337 cidrallocator.go:278] updated ClusterIP allocator for Service CIDR 10.43.0.0/161614vm-test-run-k3s> server # [   43.738536] k3s[1337]: I0622 06:45:00.539128    1337 shared_informer.go:377] "Caches are synced"1615vm-test-run-k3s> server # [   43.755194] k3s[1337]: I0622 06:45:00.556083    1337 shared_informer.go:377] "Caches are synced"1616vm-test-run-k3s> server # [   43.757289] k3s[1337]: I0622 06:45:00.556116    1337 garbagecollector.go:166] "Garbage collector: all resource monitors have synced"1617vm-test-run-k3s> server # [   43.760328] k3s[1337]: I0622 06:45:00.556138    1337 garbagecollector.go:169] "Proceeding to collect garbage"1618vm-test-run-k3s> server # [   43.769182] k3s[1337]: time="2026-06-22T06:45:00Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applied manifest at \"/var/lib/rancher/k3s/server/manifests/rolebindings.yaml\"" object=kube-system/rolebindings reason=AppliedManifest type=Normal1619vm-test-run-k3s> server # [   43.844695] k3s[1337]: time="2026-06-22T06:45:00Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applying manifest at \"/var/lib/rancher/k3s/server/manifests/runtimes.yaml\"" object=kube-system/runtimes reason=ApplyingManifest type=Normal1620vm-test-run-k3s> server # [   43.962827] k3s[1337]: time="2026-06-22T06:45:00Z" level=info msg="Tunnel authorizer set Kubelet Port 0.0.0.0:10250"1621vm-test-run-k3s> server # [   44.279046] k3s[1337]: time="2026-06-22T06:45:01Z" level=info msg="Flannel found PodCIDR assigned for node server"1622vm-test-run-k3s> server # [   44.302256] k3s[1337]: time="2026-06-22T06:45:01Z" level=info msg="The interface eth0 with ipv4 address 10.0.2.15 will be used by flannel"1623vm-test-run-k3s> server # [   44.312392] k3s[1337]: I0622 06:45:01.113436    1337 kuberuntime_manager.go:2062] "Updating runtime config through cri with podcidr" CIDR="10.42.0.0/24"1624vm-test-run-k3s> server # [   44.331976] k3s[1337]: I0622 06:45:01.132426    1337 kube.go:139] Waiting 10m0s for node controller to sync1625vm-test-run-k3s> server # [   44.337693] k3s[1337]: I0622 06:45:01.139134    1337 kube.go:537] Starting kube subnet manager1626vm-test-run-k3s> server # [   44.377476] k3s[1337]: I0622 06:45:01.178718    1337 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="10.42.0.0/24"1627vm-test-run-k3s> server # Creating layer 6 from paths: ['/nix/store/lzx3vwggc3y2diwpkql7a5k425x46pvz-tini-0.19.0']1628vm-test-run-k3s> server # Creating layer 7 from paths: ['/nix/store/sl325r3s6d4gl9lmr2hwg2wgp2m0qs7c-attr-2.5.2']1629vm-test-run-k3s> server # [   44.683336] k3s[1337]: time="2026-06-22T06:45:01Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Applied manifest at \"/var/lib/rancher/k3s/server/manifests/runtimes.yaml\"" object=kube-system/runtimes reason=AppliedManifest type=Normal1630vm-test-run-k3s> server # Creating layer 8 from paths: ['/nix/store/bifwnk7bvspj2mqc1w7dcydz8lkvfy1q-acl-2.3.2']1631vm-test-run-k3s> server # Creating layer 9 from paths: ['/nix/store/bjbr7401chfwy3mi1r27b70y3rzcfiz9-busybox-1.37.0']1632vm-test-run-k3s> server # [   45.264473] k3s[1337]: time="2026-06-22T06:45:02Z" level=info msg="Event occurred" apiVersion=k3s.cattle.io/v1 fieldPath= kind=Addon logger=k3s/deploy message="Deleting manifest at \"/var/lib/rancher/k3s/server/manifests/traefik.yaml\"" object=kube-system/traefik reason=DeletingManifest type=Normal1633vm-test-run-k3s> server # [   45.332177] k3s[1337]: I0622 06:45:02.133214    1337 kube.go:163] Node controller sync successful1634vm-test-run-k3s> server # [   45.334367] k3s[1337]: I0622 06:45:02.133285    1337 vxlan.go:128] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false1635vm-test-run-k3s> server # [   45.344749] k3s[1337]: I0622 06:45:02.146181    1337 kube.go:704] List of node(server) annotations: map[string]string{"alpha.kubernetes.io/provided-node-ip":"10.0.2.15,fec0::40dd:dd50:c21b:8c9c", "k3s.io/hostname":"server", "k3s.io/internal-ip":"10.0.2.15,fec0::40dd:dd50:c21b:8c9c", "k3s.io/node-args":"[\"server\",\"--disable\",\"coredns\",\"--disable\",\"local-storage\",\"--disable\",\"metrics-server\",\"--disable\",\"servicelb\",\"--disable\",\"traefik\",\"--pause-image\",\"test.local/pause:local\"]", "k3s.io/node-config-hash":"WHES665PV72PWPQAJCTI2KOL7KEXBT75CK3TXAUDVABYS4ZAOOPA====", "k3s.io/node-env":"{}", "node.alpha.kubernetes.io/ttl":"0", "volumes.kubernetes.io/controller-managed-attach-detach":"true"}1636vm-test-run-k3s> server # [   45.410609] (udev-worker)[1602]: Network interface NamePolicy= disabled on kernel command line.1637vm-test-run-k3s> server # [   45.446231] k3s[1337]: I0622 06:45:02.247250    1337 iptables.go:50] Starting flannel in iptables mode...1638vm-test-run-k3s> server # [   45.449232] k3s[1337]: time="2026-06-22T06:45:02Z" level=warning msg="no subnet found for key: FLANNEL_NETWORK in file: /run/flannel/subnet.env"1639vm-test-run-k3s> server # [   45.452773] k3s[1337]: time="2026-06-22T06:45:02Z" level=warning msg="no subnet found for key: FLANNEL_SUBNET in file: /run/flannel/subnet.env"1640vm-test-run-k3s> server # [   45.456219] k3s[1337]: time="2026-06-22T06:45:02Z" level=warning msg="no subnet found for key: FLANNEL_IPV6_NETWORK in file: /run/flannel/subnet.env"1641vm-test-run-k3s> server # [   45.459923] k3s[1337]: time="2026-06-22T06:45:02Z" level=warning msg="no subnet found for key: FLANNEL_IPV6_SUBNET in file: /run/flannel/subnet.env"1642vm-test-run-k3s> server # [   45.463445] k3s[1337]: I0622 06:45:02.250074    1337 iptables.go:101] Current network or subnet (10.42.0.0/16, 10.42.0.0/24) is not equal to previous one (0.0.0.0/0, 0.0.0.0/0), trying to recycle old iptables rules1643vm-test-run-k3s> server # [   45.470157] k3s[1337]: I0622 06:45:02.269588    1337 kube.go:558] Creating the node lease for IPv4. This is the n.Spec.PodCIDRs: [10.42.0.0/24]1644vm-test-run-k3s> server # [   45.512621] dhcpcd[1209]: flannel.1: IAID 15:1c:13:0f1645vm-test-run-k3s> server # [   45.514854] dhcpcd[1209]: flannel.1: adding address fe80::5c58:15ff:fe1c:130f1646vm-test-run-k3s> server # [   45.638201] k3s[1337]: I0622 06:45:02.439320    1337 iptables.go:111] Setting up masking rules1647vm-test-run-k3s> server # [   45.682775] k3s[1337]: I0622 06:45:02.484085    1337 iptables.go:212] Changing default FORWARD chain policy to ACCEPT1648vm-test-run-k3s> server # [   45.725787] k3s[1337]: time="2026-06-22T06:45:02Z" level=info msg="Wrote flannel subnet file to /run/flannel/subnet.env"1649vm-test-run-k3s> server # [   45.729296] k3s[1337]: time="2026-06-22T06:45:02Z" level=info msg="Running flannel backend."1650vm-test-run-k3s> server # [   45.732525] k3s[1337]: I0622 06:45:02.530806    1337 vxlan_network.go:68] watching for new subnet leases1651vm-test-run-k3s> server # [   45.735217] k3s[1337]: I0622 06:45:02.533982    1337 vxlan_network.go:115] starting vxlan device watcher1652vm-test-run-k3s> server # [   45.890590] k3s[1337]: I0622 06:45:02.692006    1337 iptables.go:358] bootstrap done1653vm-test-run-k3s> server # [   46.016985] k3s[1337]: I0622 06:45:02.818455    1337 iptables.go:358] bootstrap done1654vm-test-run-k3s> server # [   46.047287] dhcpcd[1209]: flannel.1: soliciting a DHCP lease1655vm-test-run-k3s> server # [   46.520112] k3s[1337]: time="2026-06-22T06:45:03Z" level=info msg="Starting network policy controller version v2.6.3-k3s1, built on 1970-01-01T01:01:01Z, go1.26.2"1656vm-test-run-k3s> server # [   46.524183] k3s[1337]: I0622 06:45:03.319563    1337 network_policy_controller.go:164] Starting network policy controller1657vm-test-run-k3s> server # Creating layer 10 from paths: ['/nix/store/si4q3zks5mn5jhzzyri9hhd3cv789vlm-gcc-15.2.0-lib']1658vm-test-run-k3s> server # [   47.211806] k3s[1337]: I0622 06:45:04.013049    1337 network_policy_controller.go:179] Starting network policy controller full sync goroutine1659vm-test-run-k3s> server # [   47.281180] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="Started tunnel to 10.0.2.15:6443"1660vm-test-run-k3s> server # [   47.283438] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="Stopped tunnel to 127.0.0.1:6443"1661vm-test-run-k3s> server # [   47.286258] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="Connecting to proxy" url="wss://10.0.2.15:6443/v1-k3s/connect"1662vm-test-run-k3s> server # [   47.290324] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="Proxy done" err="context canceled" url="wss://127.0.0.1:6443/v1-k3s/connect"1663vm-test-run-k3s> server # [   47.294358] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="error in remotedialer server [400]: websocket: close 1006 (abnormal closure): unexpected EOF"1664vm-test-run-k3s> server # [   47.312989] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="Handling backend connection request [server]"1665vm-test-run-k3s> server # [   47.318237] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="Connected to proxy" url="wss://10.0.2.15:6443/v1-k3s/connect"1666vm-test-run-k3s> server # [   47.320996] k3s[1337]: time="2026-06-22T06:45:04Z" level=info msg="Remotedialer connected to proxy" url="wss://10.0.2.15:6443/v1-k3s/connect"1667vm-test-run-k3s> server # Creating layer 11 from paths: ['/nix/store/5yypk94lvsbvskjz5xyav09j5ydxm8za-gmp-with-cxx-6.3.0']1668vm-test-run-k3s> server # [   47.672682] dhcpcd[1209]: flannel.1: soliciting an IPv6 router1669vm-test-run-k3s> server # Creating layer 12 from paths: ['/nix/store/jjxngswsb214vb58qx485jhmilf0kxxy-coreutils-9.10']1670vm-test-run-k3s> server # Creating layer 13 with customisation...1671vm-test-run-k3s> server # Adding manifests...1672vm-test-run-k3s> server # Done.1673vm-test-run-k3s> server # [   48.298228] systemd[1]: var-lib-rancher-k3s-agent-containerd-tmpmounts-containerd\x2dmount2931022332.mount: Deactivated successfully.1674vm-test-run-k3s> server: (finished: must succeed: /nix/store/idhigam18b13i2h84p8g2c02dm7yqghh-stream-pause | ctr --namespace k8s.io image import -, in 15.91 seconds)1675vm-test-run-k3s> server: must succeed: /nix/store/hnfbdxs1nnai2vs60x4ykd2mva0qq1gc-stream-cntr-test | ctr --namespace k8s.io image import -1676vm-test-run-k3s> server # No 'fromImage' provided1677vm-test-run-k3s> server # Creating layer 1 from paths: ['/nix/store/xx0z77494lfxr8qjwpck246fry05n3nm-xgcc-15.2.0-libgcc']1678vm-test-run-k3s> server # Creating layer 2 from paths: ['/nix/store/wrxyd3k2f4bmh52pr5rpdjxxsm5r2qxm-gcc-15.2.0-libgcc']1679vm-test-run-k3s> server # Creating layer 3 from paths: ['/nix/store/i4gg1f526vl5psg5nqniflj4v77vc1kd-libunistring-1.4.2']1680vm-test-run-k3s> server # Creating layer 4 from paths: ['/nix/store/sgswwrxkhdlfskklqp4gsbi2cskfg07c-libidn2-2.3.8']1681vm-test-run-k3s> server # Creating layer 5 from paths: ['/nix/store/fjkx1l5cnskzrqacf08z7i8z17256w0j-glibc-2.42-61']1682vm-test-run-k3s> server # [   51.048503] dhcpcd[1209]: flannel.1: probing for an IPv4LL address1683vm-test-run-k3s> server # [   56.061896] dhcpcd[1209]: flannel.1: using IPv4LL address 169.254.40.1531684vm-test-run-k3s> server # [   56.064465] dhcpcd[1209]: flannel.1: adding route to 169.254.0.0/161685vm-test-run-k3s> server # Creating layer 6 from paths: ['/nix/store/lzx3vwggc3y2diwpkql7a5k425x46pvz-tini-0.19.0']1686vm-test-run-k3s> server # Creating layer 7 from paths: ['/nix/store/sl325r3s6d4gl9lmr2hwg2wgp2m0qs7c-attr-2.5.2']1687vm-test-run-k3s> server # Creating layer 8 from paths: ['/nix/store/bifwnk7bvspj2mqc1w7dcydz8lkvfy1q-acl-2.3.2']1688vm-test-run-k3s> server # Creating layer 9 from paths: ['/nix/store/bjbr7401chfwy3mi1r27b70y3rzcfiz9-busybox-1.37.0']1689vm-test-run-k3s> server # Creating layer 10 from paths: ['/nix/store/si4q3zks5mn5jhzzyri9hhd3cv789vlm-gcc-15.2.0-lib']1690vm-test-run-k3s> server # Creating layer 11 from paths: ['/nix/store/5yypk94lvsbvskjz5xyav09j5ydxm8za-gmp-with-cxx-6.3.0']1691vm-test-run-k3s> server # Creating layer 12 from paths: ['/nix/store/jjxngswsb214vb58qx485jhmilf0kxxy-coreutils-9.10']1692vm-test-run-k3s> server # Creating layer 13 with customisation...1693vm-test-run-k3s> server # Adding manifests...1694vm-test-run-k3s> server # Done.1695vm-test-run-k3s> server: (finished: must succeed: /nix/store/hnfbdxs1nnai2vs60x4ykd2mva0qq1gc-stream-cntr-test | ctr --namespace k8s.io image import -, in 9.52 seconds)1696vm-test-run-k3s> server: waiting for success: kubectl get serviceaccount default1697vm-test-run-k3s> server: (finished: waiting for success: kubectl get serviceaccount default, in 0.27 seconds)1698vm-test-run-k3s> server: must succeed: kubectl apply -f /nix/store/qad18489x7w9xw1r5v6p78d1791frrkp-test-pod.yml1699vm-test-run-k3s> server: (finished: must succeed: kubectl apply -f /nix/store/qad18489x7w9xw1r5v6p78d1791frrkp-test-pod.yml, in 0.42 seconds)1700vm-test-run-k3s> server: must succeed: kubectl wait --timeout=60s --for=condition=Ready pod/cntr-test1701vm-test-run-k3s> server # [   59.677399] dhcpcd[1209]: flannel.1: no IPv6 Routers available1702vm-test-run-k3s> server # [   59.748087] k3s[1337]: I0622 06:45:16.547409    1337 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8r96n\" (UniqueName: \"kubernetes.io/projected/26520f8a-f893-41fc-bde6-f595d8087a8c-kube-api-access-8r96n\") pod \"cntr-test\" (UID: \"26520f8a-f893-41fc-bde6-f595d8087a8c\") " pod="default/cntr-test"1703vm-test-run-k3s> server # [   59.760496] systemd[1]: Created slice libcontainer container kubepods-besteffort-pod26520f8a_f893_41fc_bde6_f595d8087a8c.slice.1704vm-test-run-k3s> server # [   60.849548] cni0: port 1(veth88c21efc) entered blocking state1705vm-test-run-k3s> server # [   60.850711] cni0: port 1(veth88c21efc) entered disabled state1706vm-test-run-k3s> server # [   60.855796] veth88c21efc: entered allmulticast mode1707vm-test-run-k3s> server # [   60.858970] veth88c21efc: entered promiscuous mode1708vm-test-run-k3s> server # [   60.912231] cni0: port 1(veth88c21efc) entered blocking state1709vm-test-run-k3s> server # [   60.913466] cni0: port 1(veth88c21efc) entered forwarding state1710vm-test-run-k3s> server # [   60.724799] (udev-worker)[1857]: Network interface NamePolicy= disabled on kernel command line.1711vm-test-run-k3s> server # [   60.733778] (udev-worker)[1859]: Network interface NamePolicy= disabled on kernel command line.1712vm-test-run-k3s> server # [   60.814926] dhcpcd[1209]: veth88c21efc: IAID 35:90:aa:551713vm-test-run-k3s> server # [   60.818076] dhcpcd[1209]: veth88c21efc: adding address fe80::20cc:35ff:fe90:aa551714vm-test-run-k3s> server # [   60.917293] systemd[1]: Started libcontainer container ffc3d7b7c13148fa00701664c378cf06cb0e841679c46659cce9623ad33ba864.1715vm-test-run-k3s> server # [   61.114233] dhcpcd[1209]: veth88c21efc: soliciting a DHCP lease1716vm-test-run-k3s> server # [   61.130788] systemd[1]: Started libcontainer container 72964fe8a3b5a03f1dbed6a986e96b481d030fdbbf7f90f584041d30774f8af0.1717vm-test-run-k3s> server # [   61.297111] systemd[1]: var-lib-rancher-k3s-agent-containerd-tmpmounts-containerd\x2dmount1517855419.mount: Deactivated successfully.1718vm-test-run-k3s> server: (finished: must succeed: kubectl wait --timeout=60s --for=condition=Ready pod/cntr-test, in 2.11 seconds)1719vm-test-run-k3s> server: must succeed: crictl ps --name test-container -q1720vm-test-run-k3s> server # [   61.821059] dhcpcd[1209]: veth88c21efc: soliciting an IPv6 router1721vm-test-run-k3s> server: (finished: must succeed: crictl ps --name test-container -q, in 0.26 seconds)1722vm-test-run-k3s> server: must succeed: cntr attach 72964fe8a3b5a03f1dbed6a986e96b481d030fdbbf7f90f584041d30774f8af0 true1723vm-test-run-k3s> server # [   62.091450] systemd[1]: Created slice Virtual Machine and Container Slice.1724vm-test-run-k3s> server # [   62.095853] systemd[1]: Starting Virtual Machine and Container Registration Service...1725vm-test-run-k3s> server # [   62.158448] systemd[1]: Started Virtual Machine and Container Registration Service.1726vm-test-run-k3s> server: (finished: must succeed: cntr attach 72964fe8a3b5a03f1dbed6a986e96b481d030fdbbf7f90f584041d30774f8af0 true, in 0.41 seconds)1727vm-test-run-k3s> server: must succeed: cntr exec 72964fe8a3b5a03f1dbed6a986e96b481d030fdbbf7f90f584041d30774f8af0 -- /bin/sh -c 'echo exec test passed'1728vm-test-run-k3s> server: (finished: must succeed: cntr exec 72964fe8a3b5a03f1dbed6a986e96b481d030fdbbf7f90f584041d30774f8af0 -- /bin/sh -c 'echo exec test passed', in 0.23 seconds)1729vm-test-run-k3s> (finished: run the VM test script, in 63.85 seconds)1730vm-test-run-k3s> test script finished in 63.91s1731vm-test-run-k3s> cleanup1732vm-test-run-k3s> kill QemuMachine (pid 13)1733vm-test-run-k3s> server # qemu-system-x86_64: terminating on signal 15 from pid 6 (/nix/store/0r6k8xa2kgqyp3r4v2w7yrb80ma2iawm-python3-3.13.12/bin/python3.13)1734vm-test-run-k3s> (finished: cleanup, in 0.41 seconds)17351736post-build step Upload coverage to codecov: ok1737Skipping codecov: project=Mic92/cntr attr=x86_64-linux.k3s