May 15 10:25:55.727816 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 15 10:25:55.727837 kernel: Linux version 5.15.182-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Thu May 15 09:09:56 -00 2025 May 15 10:25:55.727844 kernel: efi: EFI v2.70 by EDK II May 15 10:25:55.727850 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 May 15 10:25:55.727855 kernel: random: crng init done May 15 10:25:55.727868 kernel: ACPI: Early table checksum verification disabled May 15 10:25:55.727874 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) May 15 10:25:55.727881 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) May 15 10:25:55.727887 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727892 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727898 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727903 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727908 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727913 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727921 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727927 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727932 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 15 10:25:55.727938 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 15 10:25:55.727944 kernel: NUMA: Failed to initialise from firmware May 15 10:25:55.727950 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 15 10:25:55.727955 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] May 15 10:25:55.727961 kernel: Zone ranges: May 15 10:25:55.727967 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 15 10:25:55.727973 kernel: DMA32 empty May 15 10:25:55.727979 kernel: Normal empty May 15 10:25:55.727984 kernel: Movable zone start for each node May 15 10:25:55.727990 kernel: Early memory node ranges May 15 10:25:55.727996 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] May 15 10:25:55.728002 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] May 15 10:25:55.728008 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] May 15 10:25:55.728013 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] May 15 10:25:55.728019 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] May 15 10:25:55.728025 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] May 15 10:25:55.728030 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] May 15 10:25:55.728036 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 15 10:25:55.728043 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 15 10:25:55.728049 kernel: psci: probing for conduit method from ACPI. May 15 10:25:55.728054 kernel: psci: PSCIv1.1 detected in firmware. May 15 10:25:55.728060 kernel: psci: Using standard PSCI v0.2 function IDs May 15 10:25:55.728066 kernel: psci: Trusted OS migration not required May 15 10:25:55.728074 kernel: psci: SMC Calling Convention v1.1 May 15 10:25:55.728080 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 15 10:25:55.728087 kernel: ACPI: SRAT not present May 15 10:25:55.728093 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 May 15 10:25:55.728099 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 May 15 10:25:55.728106 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 15 10:25:55.728111 kernel: Detected PIPT I-cache on CPU0 May 15 10:25:55.728118 kernel: CPU features: detected: GIC system register CPU interface May 15 10:25:55.728124 kernel: CPU features: detected: Hardware dirty bit management May 15 10:25:55.728130 kernel: CPU features: detected: Spectre-v4 May 15 10:25:55.728136 kernel: CPU features: detected: Spectre-BHB May 15 10:25:55.728143 kernel: CPU features: kernel page table isolation forced ON by KASLR May 15 10:25:55.728149 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 15 10:25:55.728155 kernel: CPU features: detected: ARM erratum 1418040 May 15 10:25:55.728161 kernel: CPU features: detected: SSBS not fully self-synchronizing May 15 10:25:55.728167 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 15 10:25:55.728173 kernel: Policy zone: DMA May 15 10:25:55.728180 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=aa29d2e9841b6b978238db9eff73afa5af149616ae25608914babb265d82dda7 May 15 10:25:55.728186 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 15 10:25:55.728192 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 15 10:25:55.728198 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 15 10:25:55.728204 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 15 10:25:55.728212 kernel: Memory: 2457404K/2572288K available (9792K kernel code, 2094K rwdata, 7584K rodata, 36416K init, 777K bss, 114884K reserved, 0K cma-reserved) May 15 10:25:55.728218 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 15 10:25:55.728224 kernel: trace event string verifier disabled May 15 10:25:55.728230 kernel: rcu: Preemptible hierarchical RCU implementation. May 15 10:25:55.728237 kernel: rcu: RCU event tracing is enabled. May 15 10:25:55.728243 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 15 10:25:55.728249 kernel: Trampoline variant of Tasks RCU enabled. May 15 10:25:55.728255 kernel: Tracing variant of Tasks RCU enabled. May 15 10:25:55.728261 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 15 10:25:55.728267 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 15 10:25:55.728273 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 15 10:25:55.728280 kernel: GICv3: 256 SPIs implemented May 15 10:25:55.728286 kernel: GICv3: 0 Extended SPIs implemented May 15 10:25:55.728292 kernel: GICv3: Distributor has no Range Selector support May 15 10:25:55.728298 kernel: Root IRQ handler: gic_handle_irq May 15 10:25:55.728304 kernel: GICv3: 16 PPIs implemented May 15 10:25:55.728310 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 15 10:25:55.728316 kernel: ACPI: SRAT not present May 15 10:25:55.728322 kernel: ITS [mem 0x08080000-0x0809ffff] May 15 10:25:55.728328 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) May 15 10:25:55.728334 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) May 15 10:25:55.728340 kernel: GICv3: using LPI property table @0x00000000400d0000 May 15 10:25:55.728346 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 May 15 10:25:55.728354 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 15 10:25:55.728360 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 15 10:25:55.728366 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 15 10:25:55.728372 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 15 10:25:55.728379 kernel: arm-pv: using stolen time PV May 15 10:25:55.728385 kernel: Console: colour dummy device 80x25 May 15 10:25:55.728391 kernel: ACPI: Core revision 20210730 May 15 10:25:55.728398 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 15 10:25:55.728404 kernel: pid_max: default: 32768 minimum: 301 May 15 10:25:55.728410 kernel: LSM: Security Framework initializing May 15 10:25:55.728417 kernel: SELinux: Initializing. May 15 10:25:55.728424 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 10:25:55.728430 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 10:25:55.728437 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 15 10:25:55.728443 kernel: rcu: Hierarchical SRCU implementation. May 15 10:25:55.728449 kernel: Platform MSI: ITS@0x8080000 domain created May 15 10:25:55.728455 kernel: PCI/MSI: ITS@0x8080000 domain created May 15 10:25:55.728461 kernel: Remapping and enabling EFI services. May 15 10:25:55.728467 kernel: smp: Bringing up secondary CPUs ... May 15 10:25:55.728474 kernel: Detected PIPT I-cache on CPU1 May 15 10:25:55.728480 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 15 10:25:55.728487 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 May 15 10:25:55.728493 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 15 10:25:55.728499 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 15 10:25:55.728506 kernel: Detected PIPT I-cache on CPU2 May 15 10:25:55.728512 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 15 10:25:55.728518 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 May 15 10:25:55.728525 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 15 10:25:55.728531 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 15 10:25:55.728538 kernel: Detected PIPT I-cache on CPU3 May 15 10:25:55.728544 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 15 10:25:55.728551 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 May 15 10:25:55.728557 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 15 10:25:55.728568 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 15 10:25:55.728575 kernel: smp: Brought up 1 node, 4 CPUs May 15 10:25:55.728582 kernel: SMP: Total of 4 processors activated. May 15 10:25:55.728588 kernel: CPU features: detected: 32-bit EL0 Support May 15 10:25:55.728605 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 15 10:25:55.728611 kernel: CPU features: detected: Common not Private translations May 15 10:25:55.728618 kernel: CPU features: detected: CRC32 instructions May 15 10:25:55.728625 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 15 10:25:55.728633 kernel: CPU features: detected: LSE atomic instructions May 15 10:25:55.728640 kernel: CPU features: detected: Privileged Access Never May 15 10:25:55.728647 kernel: CPU features: detected: RAS Extension Support May 15 10:25:55.728654 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 15 10:25:55.728660 kernel: CPU: All CPU(s) started at EL1 May 15 10:25:55.728668 kernel: alternatives: patching kernel code May 15 10:25:55.728674 kernel: devtmpfs: initialized May 15 10:25:55.728681 kernel: KASLR enabled May 15 10:25:55.728687 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 15 10:25:55.728694 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 15 10:25:55.728701 kernel: pinctrl core: initialized pinctrl subsystem May 15 10:25:55.728707 kernel: SMBIOS 3.0.0 present. May 15 10:25:55.728714 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 May 15 10:25:55.728720 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 15 10:25:55.728728 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 15 10:25:55.728735 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 15 10:25:55.728741 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 15 10:25:55.728748 kernel: audit: initializing netlink subsys (disabled) May 15 10:25:55.728755 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 May 15 10:25:55.728761 kernel: thermal_sys: Registered thermal governor 'step_wise' May 15 10:25:55.728767 kernel: cpuidle: using governor menu May 15 10:25:55.728774 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 15 10:25:55.728780 kernel: ASID allocator initialised with 32768 entries May 15 10:25:55.728788 kernel: ACPI: bus type PCI registered May 15 10:25:55.728795 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 15 10:25:55.728801 kernel: Serial: AMBA PL011 UART driver May 15 10:25:55.728808 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages May 15 10:25:55.728814 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages May 15 10:25:55.728821 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages May 15 10:25:55.728827 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages May 15 10:25:55.728834 kernel: cryptd: max_cpu_qlen set to 1000 May 15 10:25:55.728840 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 15 10:25:55.728848 kernel: ACPI: Added _OSI(Module Device) May 15 10:25:55.728855 kernel: ACPI: Added _OSI(Processor Device) May 15 10:25:55.728865 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 15 10:25:55.728871 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 15 10:25:55.728878 kernel: ACPI: Added _OSI(Linux-Dell-Video) May 15 10:25:55.728884 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) May 15 10:25:55.728891 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) May 15 10:25:55.728898 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 15 10:25:55.728904 kernel: ACPI: Interpreter enabled May 15 10:25:55.728912 kernel: ACPI: Using GIC for interrupt routing May 15 10:25:55.728918 kernel: ACPI: MCFG table detected, 1 entries May 15 10:25:55.728925 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 15 10:25:55.728932 kernel: printk: console [ttyAMA0] enabled May 15 10:25:55.728938 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 15 10:25:55.729056 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 15 10:25:55.729120 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 15 10:25:55.729180 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 15 10:25:55.729239 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 15 10:25:55.729296 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 15 10:25:55.729305 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 15 10:25:55.729311 kernel: PCI host bridge to bus 0000:00 May 15 10:25:55.729377 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 15 10:25:55.729430 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 15 10:25:55.729482 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 15 10:25:55.729536 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 15 10:25:55.729629 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 15 10:25:55.729704 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 15 10:25:55.729769 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 15 10:25:55.729828 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 15 10:25:55.729896 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 15 10:25:55.729961 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 15 10:25:55.730022 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 15 10:25:55.730138 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 15 10:25:55.730197 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 15 10:25:55.730251 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 15 10:25:55.730303 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 15 10:25:55.730311 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 15 10:25:55.730318 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 15 10:25:55.730327 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 15 10:25:55.730334 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 15 10:25:55.730341 kernel: iommu: Default domain type: Translated May 15 10:25:55.730347 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 15 10:25:55.730354 kernel: vgaarb: loaded May 15 10:25:55.730361 kernel: pps_core: LinuxPPS API ver. 1 registered May 15 10:25:55.730367 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 15 10:25:55.730374 kernel: PTP clock support registered May 15 10:25:55.730380 kernel: Registered efivars operations May 15 10:25:55.730388 kernel: clocksource: Switched to clocksource arch_sys_counter May 15 10:25:55.730394 kernel: VFS: Disk quotas dquot_6.6.0 May 15 10:25:55.730401 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 15 10:25:55.730408 kernel: pnp: PnP ACPI init May 15 10:25:55.730473 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 15 10:25:55.730483 kernel: pnp: PnP ACPI: found 1 devices May 15 10:25:55.730489 kernel: NET: Registered PF_INET protocol family May 15 10:25:55.730496 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 15 10:25:55.730504 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 15 10:25:55.730510 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 15 10:25:55.730517 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 15 10:25:55.730524 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) May 15 10:25:55.730531 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 15 10:25:55.730537 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 10:25:55.730544 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 10:25:55.730550 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 15 10:25:55.730557 kernel: PCI: CLS 0 bytes, default 64 May 15 10:25:55.730564 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 15 10:25:55.730571 kernel: kvm [1]: HYP mode not available May 15 10:25:55.730577 kernel: Initialise system trusted keyrings May 15 10:25:55.730584 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 15 10:25:55.730590 kernel: Key type asymmetric registered May 15 10:25:55.730606 kernel: Asymmetric key parser 'x509' registered May 15 10:25:55.730613 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 15 10:25:55.730620 kernel: io scheduler mq-deadline registered May 15 10:25:55.730627 kernel: io scheduler kyber registered May 15 10:25:55.730635 kernel: io scheduler bfq registered May 15 10:25:55.730641 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 15 10:25:55.730648 kernel: ACPI: button: Power Button [PWRB] May 15 10:25:55.730655 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 15 10:25:55.730716 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 15 10:25:55.730725 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 15 10:25:55.730732 kernel: thunder_xcv, ver 1.0 May 15 10:25:55.730738 kernel: thunder_bgx, ver 1.0 May 15 10:25:55.730745 kernel: nicpf, ver 1.0 May 15 10:25:55.730753 kernel: nicvf, ver 1.0 May 15 10:25:55.730820 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 15 10:25:55.730885 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-15T10:25:55 UTC (1747304755) May 15 10:25:55.730895 kernel: hid: raw HID events driver (C) Jiri Kosina May 15 10:25:55.730901 kernel: NET: Registered PF_INET6 protocol family May 15 10:25:55.730908 kernel: Segment Routing with IPv6 May 15 10:25:55.730914 kernel: In-situ OAM (IOAM) with IPv6 May 15 10:25:55.730921 kernel: NET: Registered PF_PACKET protocol family May 15 10:25:55.730929 kernel: Key type dns_resolver registered May 15 10:25:55.730935 kernel: registered taskstats version 1 May 15 10:25:55.730942 kernel: Loading compiled-in X.509 certificates May 15 10:25:55.730948 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.182-flatcar: 3679cbfb4d4756a2ddc177f0eaedea33fb5fdf2e' May 15 10:25:55.730955 kernel: Key type .fscrypt registered May 15 10:25:55.730961 kernel: Key type fscrypt-provisioning registered May 15 10:25:55.730968 kernel: ima: No TPM chip found, activating TPM-bypass! May 15 10:25:55.730974 kernel: ima: Allocated hash algorithm: sha1 May 15 10:25:55.730981 kernel: ima: No architecture policies found May 15 10:25:55.730989 kernel: clk: Disabling unused clocks May 15 10:25:55.730995 kernel: Freeing unused kernel memory: 36416K May 15 10:25:55.731001 kernel: Run /init as init process May 15 10:25:55.731008 kernel: with arguments: May 15 10:25:55.731014 kernel: /init May 15 10:25:55.731020 kernel: with environment: May 15 10:25:55.731027 kernel: HOME=/ May 15 10:25:55.731033 kernel: TERM=linux May 15 10:25:55.731039 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 15 10:25:55.731049 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 15 10:25:55.731057 systemd[1]: Detected virtualization kvm. May 15 10:25:55.731065 systemd[1]: Detected architecture arm64. May 15 10:25:55.731071 systemd[1]: Running in initrd. May 15 10:25:55.731078 systemd[1]: No hostname configured, using default hostname. May 15 10:25:55.731085 systemd[1]: Hostname set to . May 15 10:25:55.731092 systemd[1]: Initializing machine ID from VM UUID. May 15 10:25:55.731100 systemd[1]: Queued start job for default target initrd.target. May 15 10:25:55.731107 systemd[1]: Started systemd-ask-password-console.path. May 15 10:25:55.731114 systemd[1]: Reached target cryptsetup.target. May 15 10:25:55.731121 systemd[1]: Reached target paths.target. May 15 10:25:55.731128 systemd[1]: Reached target slices.target. May 15 10:25:55.731134 systemd[1]: Reached target swap.target. May 15 10:25:55.731141 systemd[1]: Reached target timers.target. May 15 10:25:55.731149 systemd[1]: Listening on iscsid.socket. May 15 10:25:55.731157 systemd[1]: Listening on iscsiuio.socket. May 15 10:25:55.731164 systemd[1]: Listening on systemd-journald-audit.socket. May 15 10:25:55.731171 systemd[1]: Listening on systemd-journald-dev-log.socket. May 15 10:25:55.731178 systemd[1]: Listening on systemd-journald.socket. May 15 10:25:55.731185 systemd[1]: Listening on systemd-networkd.socket. May 15 10:25:55.731192 systemd[1]: Listening on systemd-udevd-control.socket. May 15 10:25:55.731199 systemd[1]: Listening on systemd-udevd-kernel.socket. May 15 10:25:55.731205 systemd[1]: Reached target sockets.target. May 15 10:25:55.731213 systemd[1]: Starting kmod-static-nodes.service... May 15 10:25:55.731220 systemd[1]: Finished network-cleanup.service. May 15 10:25:55.731227 systemd[1]: Starting systemd-fsck-usr.service... May 15 10:25:55.731234 systemd[1]: Starting systemd-journald.service... May 15 10:25:55.731241 systemd[1]: Starting systemd-modules-load.service... May 15 10:25:55.731248 systemd[1]: Starting systemd-resolved.service... May 15 10:25:55.731255 systemd[1]: Starting systemd-vconsole-setup.service... May 15 10:25:55.731261 systemd[1]: Finished kmod-static-nodes.service. May 15 10:25:55.731268 systemd[1]: Finished systemd-fsck-usr.service. May 15 10:25:55.731276 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... May 15 10:25:55.731283 systemd[1]: Finished systemd-vconsole-setup.service. May 15 10:25:55.731290 systemd[1]: Starting dracut-cmdline-ask.service... May 15 10:25:55.731297 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. May 15 10:25:55.731308 systemd-journald[290]: Journal started May 15 10:25:55.731346 systemd-journald[290]: Runtime Journal (/run/log/journal/7b51515e7eb242818f12ed64dd7cf9ea) is 6.0M, max 48.7M, 42.6M free. May 15 10:25:55.723167 systemd-modules-load[291]: Inserted module 'overlay' May 15 10:25:55.734463 kernel: audit: type=1130 audit(1747304755.730:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.734481 systemd[1]: Started systemd-journald.service. May 15 10:25:55.730000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.735000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.737610 kernel: audit: type=1130 audit(1747304755.735:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.742618 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 15 10:25:55.746060 kernel: Bridge firewalling registered May 15 10:25:55.745630 systemd-modules-load[291]: Inserted module 'br_netfilter' May 15 10:25:55.749427 systemd[1]: Finished dracut-cmdline-ask.service. May 15 10:25:55.752790 kernel: audit: type=1130 audit(1747304755.750:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.750000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.752368 systemd[1]: Starting dracut-cmdline.service... May 15 10:25:55.755997 systemd-resolved[292]: Positive Trust Anchors: May 15 10:25:55.756011 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 10:25:55.756038 systemd-resolved[292]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test May 15 10:25:55.760441 systemd-resolved[292]: Defaulting to hostname 'linux'. May 15 10:25:55.765308 kernel: SCSI subsystem initialized May 15 10:25:55.761176 systemd[1]: Started systemd-resolved.service. May 15 10:25:55.765000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.766651 systemd[1]: Reached target nss-lookup.target. May 15 10:25:55.769364 kernel: audit: type=1130 audit(1747304755.765:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.771590 dracut-cmdline[309]: dracut-dracut-053 May 15 10:25:55.773324 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 15 10:25:55.773339 kernel: device-mapper: uevent: version 1.0.3 May 15 10:25:55.773348 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com May 15 10:25:55.773767 dracut-cmdline[309]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=aa29d2e9841b6b978238db9eff73afa5af149616ae25608914babb265d82dda7 May 15 10:25:55.777131 systemd-modules-load[291]: Inserted module 'dm_multipath' May 15 10:25:55.777857 systemd[1]: Finished systemd-modules-load.service. May 15 10:25:55.777000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.779095 systemd[1]: Starting systemd-sysctl.service... May 15 10:25:55.782043 kernel: audit: type=1130 audit(1747304755.777:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.787247 systemd[1]: Finished systemd-sysctl.service. May 15 10:25:55.787000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.790620 kernel: audit: type=1130 audit(1747304755.787:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.829631 kernel: Loading iSCSI transport class v2.0-870. May 15 10:25:55.841624 kernel: iscsi: registered transport (tcp) May 15 10:25:55.856615 kernel: iscsi: registered transport (qla4xxx) May 15 10:25:55.856633 kernel: QLogic iSCSI HBA Driver May 15 10:25:55.890297 systemd[1]: Finished dracut-cmdline.service. May 15 10:25:55.890000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.891701 systemd[1]: Starting dracut-pre-udev.service... May 15 10:25:55.894167 kernel: audit: type=1130 audit(1747304755.890:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:55.935615 kernel: raid6: neonx8 gen() 13658 MB/s May 15 10:25:55.952609 kernel: raid6: neonx8 xor() 10706 MB/s May 15 10:25:55.969619 kernel: raid6: neonx4 gen() 13439 MB/s May 15 10:25:55.986612 kernel: raid6: neonx4 xor() 11087 MB/s May 15 10:25:56.003616 kernel: raid6: neonx2 gen() 12864 MB/s May 15 10:25:56.020608 kernel: raid6: neonx2 xor() 10345 MB/s May 15 10:25:56.037618 kernel: raid6: neonx1 gen() 10549 MB/s May 15 10:25:56.054607 kernel: raid6: neonx1 xor() 8728 MB/s May 15 10:25:56.071613 kernel: raid6: int64x8 gen() 6228 MB/s May 15 10:25:56.088608 kernel: raid6: int64x8 xor() 3517 MB/s May 15 10:25:56.105613 kernel: raid6: int64x4 gen() 7163 MB/s May 15 10:25:56.122610 kernel: raid6: int64x4 xor() 3829 MB/s May 15 10:25:56.139617 kernel: raid6: int64x2 gen() 6111 MB/s May 15 10:25:56.156620 kernel: raid6: int64x2 xor() 3300 MB/s May 15 10:25:56.173608 kernel: raid6: int64x1 gen() 5014 MB/s May 15 10:25:56.190839 kernel: raid6: int64x1 xor() 2633 MB/s May 15 10:25:56.190869 kernel: raid6: using algorithm neonx8 gen() 13658 MB/s May 15 10:25:56.190887 kernel: raid6: .... xor() 10706 MB/s, rmw enabled May 15 10:25:56.190909 kernel: raid6: using neon recovery algorithm May 15 10:25:56.201615 kernel: xor: measuring software checksum speed May 15 10:25:56.201646 kernel: 8regs : 17188 MB/sec May 15 10:25:56.203004 kernel: 32regs : 19349 MB/sec May 15 10:25:56.203015 kernel: arm64_neon : 27579 MB/sec May 15 10:25:56.203024 kernel: xor: using function: arm64_neon (27579 MB/sec) May 15 10:25:56.256623 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no May 15 10:25:56.267081 systemd[1]: Finished dracut-pre-udev.service. May 15 10:25:56.267000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:56.268591 systemd[1]: Starting systemd-udevd.service... May 15 10:25:56.271868 kernel: audit: type=1130 audit(1747304756.267:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:56.271887 kernel: audit: type=1334 audit(1747304756.267:10): prog-id=7 op=LOAD May 15 10:25:56.267000 audit: BPF prog-id=7 op=LOAD May 15 10:25:56.267000 audit: BPF prog-id=8 op=LOAD May 15 10:25:56.286201 systemd-udevd[492]: Using default interface naming scheme 'v252'. May 15 10:25:56.290436 systemd[1]: Started systemd-udevd.service. May 15 10:25:56.291000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:56.291997 systemd[1]: Starting dracut-pre-trigger.service... May 15 10:25:56.303738 dracut-pre-trigger[500]: rd.md=0: removing MD RAID activation May 15 10:25:56.329778 systemd[1]: Finished dracut-pre-trigger.service. May 15 10:25:56.329000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:56.331054 systemd[1]: Starting systemd-udev-trigger.service... May 15 10:25:56.363570 systemd[1]: Finished systemd-udev-trigger.service. May 15 10:25:56.363000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:56.389918 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 15 10:25:56.394335 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 15 10:25:56.394355 kernel: GPT:9289727 != 19775487 May 15 10:25:56.394365 kernel: GPT:Alternate GPT header not at the end of the disk. May 15 10:25:56.394373 kernel: GPT:9289727 != 19775487 May 15 10:25:56.394382 kernel: GPT: Use GNU Parted to correct GPT errors. May 15 10:25:56.394391 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 10:25:56.406614 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (553) May 15 10:25:56.407650 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. May 15 10:25:56.408662 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. May 15 10:25:56.417187 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. May 15 10:25:56.420669 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 15 10:25:56.423930 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. May 15 10:25:56.425549 systemd[1]: Starting disk-uuid.service... May 15 10:25:56.431305 disk-uuid[564]: Primary Header is updated. May 15 10:25:56.431305 disk-uuid[564]: Secondary Entries is updated. May 15 10:25:56.431305 disk-uuid[564]: Secondary Header is updated. May 15 10:25:56.433807 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 10:25:57.444281 disk-uuid[565]: The operation has completed successfully. May 15 10:25:57.445435 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 15 10:25:57.468278 systemd[1]: disk-uuid.service: Deactivated successfully. May 15 10:25:57.468000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.468000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.468372 systemd[1]: Finished disk-uuid.service. May 15 10:25:57.469800 systemd[1]: Starting verity-setup.service... May 15 10:25:57.490123 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 15 10:25:57.510041 systemd[1]: Found device dev-mapper-usr.device. May 15 10:25:57.511364 systemd[1]: Mounting sysusr-usr.mount... May 15 10:25:57.512022 systemd[1]: Finished verity-setup.service. May 15 10:25:57.512000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.557286 systemd[1]: Mounted sysusr-usr.mount. May 15 10:25:57.558309 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. May 15 10:25:57.557964 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. May 15 10:25:57.558660 systemd[1]: Starting ignition-setup.service... May 15 10:25:57.560378 systemd[1]: Starting parse-ip-for-networkd.service... May 15 10:25:57.567616 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 15 10:25:57.567646 kernel: BTRFS info (device vda6): using free space tree May 15 10:25:57.567657 kernel: BTRFS info (device vda6): has skinny extents May 15 10:25:57.576662 systemd[1]: mnt-oem.mount: Deactivated successfully. May 15 10:25:57.582559 systemd[1]: Finished ignition-setup.service. May 15 10:25:57.582000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.584080 systemd[1]: Starting ignition-fetch-offline.service... May 15 10:25:57.645920 systemd[1]: Finished parse-ip-for-networkd.service. May 15 10:25:57.646000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.646000 audit: BPF prog-id=9 op=LOAD May 15 10:25:57.647819 systemd[1]: Starting systemd-networkd.service... May 15 10:25:57.656305 ignition[656]: Ignition 2.14.0 May 15 10:25:57.656315 ignition[656]: Stage: fetch-offline May 15 10:25:57.656353 ignition[656]: no configs at "/usr/lib/ignition/base.d" May 15 10:25:57.656362 ignition[656]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 10:25:57.656491 ignition[656]: parsed url from cmdline: "" May 15 10:25:57.656494 ignition[656]: no config URL provided May 15 10:25:57.656499 ignition[656]: reading system config file "/usr/lib/ignition/user.ign" May 15 10:25:57.656506 ignition[656]: no config at "/usr/lib/ignition/user.ign" May 15 10:25:57.656523 ignition[656]: op(1): [started] loading QEMU firmware config module May 15 10:25:57.656528 ignition[656]: op(1): executing: "modprobe" "qemu_fw_cfg" May 15 10:25:57.662277 ignition[656]: op(1): [finished] loading QEMU firmware config module May 15 10:25:57.668400 ignition[656]: parsing config with SHA512: f9bec01e56c890934732c1e523547c0a13816090af2a090149d0184a8b3ff2c034a0637f8edcf64cfa98936d7537f9ef6ca61bb8b128d38ae7cd4423cd13f13e May 15 10:25:57.668953 systemd-networkd[741]: lo: Link UP May 15 10:25:57.668965 systemd-networkd[741]: lo: Gained carrier May 15 10:25:57.669314 systemd-networkd[741]: Enumeration completed May 15 10:25:57.669000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.669386 systemd[1]: Started systemd-networkd.service. May 15 10:25:57.669486 systemd-networkd[741]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 10:25:57.675716 ignition[656]: fetch-offline: fetch-offline passed May 15 10:25:57.670420 systemd[1]: Reached target network.target. May 15 10:25:57.675797 ignition[656]: Ignition finished successfully May 15 10:25:57.677000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.670535 systemd-networkd[741]: eth0: Link UP May 15 10:25:57.670538 systemd-networkd[741]: eth0: Gained carrier May 15 10:25:57.672066 systemd[1]: Starting iscsiuio.service... May 15 10:25:57.672522 unknown[656]: fetched base config from "system" May 15 10:25:57.681000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.672531 unknown[656]: fetched user config from "qemu" May 15 10:25:57.677139 systemd[1]: Finished ignition-fetch-offline.service. May 15 10:25:57.678548 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 15 10:25:57.679297 systemd[1]: Starting ignition-kargs.service... May 15 10:25:57.681108 systemd[1]: Started iscsiuio.service. May 15 10:25:57.682775 systemd[1]: Starting iscsid.service... May 15 10:25:57.686804 iscsid[749]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi May 15 10:25:57.686804 iscsid[749]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. May 15 10:25:57.686804 iscsid[749]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. May 15 10:25:57.686804 iscsid[749]: If using hardware iscsi like qla4xxx this message can be ignored. May 15 10:25:57.686804 iscsid[749]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi May 15 10:25:57.686804 iscsid[749]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf May 15 10:25:57.693000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.695000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.689200 ignition[747]: Ignition 2.14.0 May 15 10:25:57.688813 systemd[1]: Started iscsid.service. May 15 10:25:57.689207 ignition[747]: Stage: kargs May 15 10:25:57.693720 systemd-networkd[741]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 15 10:25:57.689301 ignition[747]: no configs at "/usr/lib/ignition/base.d" May 15 10:25:57.694700 systemd[1]: Finished ignition-kargs.service. May 15 10:25:57.689310 ignition[747]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 10:25:57.696884 systemd[1]: Starting dracut-initqueue.service... May 15 10:25:57.689998 ignition[747]: kargs: kargs passed May 15 10:25:57.707000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.698372 systemd[1]: Starting ignition-disks.service... May 15 10:25:57.708000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.690041 ignition[747]: Ignition finished successfully May 15 10:25:57.706795 systemd[1]: Finished ignition-disks.service. May 15 10:25:57.704527 ignition[756]: Ignition 2.14.0 May 15 10:25:57.708237 systemd[1]: Finished dracut-initqueue.service. May 15 10:25:57.704533 ignition[756]: Stage: disks May 15 10:25:57.709394 systemd[1]: Reached target initrd-root-device.target. May 15 10:25:57.704697 ignition[756]: no configs at "/usr/lib/ignition/base.d" May 15 10:25:57.710499 systemd[1]: Reached target local-fs-pre.target. May 15 10:25:57.704706 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 10:25:57.711645 systemd[1]: Reached target local-fs.target. May 15 10:25:57.705731 ignition[756]: disks: disks passed May 15 10:25:57.712818 systemd[1]: Reached target remote-fs-pre.target. May 15 10:25:57.705773 ignition[756]: Ignition finished successfully May 15 10:25:57.713899 systemd[1]: Reached target remote-cryptsetup.target. May 15 10:25:57.715144 systemd[1]: Reached target remote-fs.target. May 15 10:25:57.716102 systemd[1]: Reached target sysinit.target. May 15 10:25:57.717268 systemd[1]: Reached target basic.target. May 15 10:25:57.719351 systemd[1]: Starting dracut-pre-mount.service... May 15 10:25:57.726859 systemd[1]: Finished dracut-pre-mount.service. May 15 10:25:57.727000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.728704 systemd[1]: Starting systemd-fsck-root.service... May 15 10:25:57.739252 systemd-fsck[776]: ROOT: clean, 623/553520 files, 56022/553472 blocks May 15 10:25:57.743102 systemd[1]: Finished systemd-fsck-root.service. May 15 10:25:57.743000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.744529 systemd[1]: Mounting sysroot.mount... May 15 10:25:57.749615 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. May 15 10:25:57.749805 systemd[1]: Mounted sysroot.mount. May 15 10:25:57.750395 systemd[1]: Reached target initrd-root-fs.target. May 15 10:25:57.753161 systemd[1]: Mounting sysroot-usr.mount... May 15 10:25:57.754085 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. May 15 10:25:57.754122 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 15 10:25:57.754149 systemd[1]: Reached target ignition-diskful.target. May 15 10:25:57.756023 systemd[1]: Mounted sysroot-usr.mount. May 15 10:25:57.758057 systemd[1]: Starting initrd-setup-root.service... May 15 10:25:57.762615 initrd-setup-root[786]: cut: /sysroot/etc/passwd: No such file or directory May 15 10:25:57.767143 initrd-setup-root[794]: cut: /sysroot/etc/group: No such file or directory May 15 10:25:57.771151 initrd-setup-root[802]: cut: /sysroot/etc/shadow: No such file or directory May 15 10:25:57.774743 initrd-setup-root[810]: cut: /sysroot/etc/gshadow: No such file or directory May 15 10:25:57.801285 systemd[1]: Finished initrd-setup-root.service. May 15 10:25:57.801000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.802764 systemd[1]: Starting ignition-mount.service... May 15 10:25:57.803987 systemd[1]: Starting sysroot-boot.service... May 15 10:25:57.808525 bash[827]: umount: /sysroot/usr/share/oem: not mounted. May 15 10:25:57.819646 ignition[829]: INFO : Ignition 2.14.0 May 15 10:25:57.820422 ignition[829]: INFO : Stage: mount May 15 10:25:57.821064 ignition[829]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 10:25:57.821911 ignition[829]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 10:25:57.822782 ignition[829]: INFO : mount: mount passed May 15 10:25:57.822782 ignition[829]: INFO : Ignition finished successfully May 15 10:25:57.823000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.823270 systemd[1]: Finished sysroot-boot.service. May 15 10:25:57.824000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:57.824082 systemd[1]: Finished ignition-mount.service. May 15 10:25:58.519146 systemd[1]: Mounting sysroot-usr-share-oem.mount... May 15 10:25:58.524606 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (837) May 15 10:25:58.526626 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 15 10:25:58.526658 kernel: BTRFS info (device vda6): using free space tree May 15 10:25:58.526684 kernel: BTRFS info (device vda6): has skinny extents May 15 10:25:58.529331 systemd[1]: Mounted sysroot-usr-share-oem.mount. May 15 10:25:58.530656 systemd[1]: Starting ignition-files.service... May 15 10:25:58.543915 ignition[857]: INFO : Ignition 2.14.0 May 15 10:25:58.543915 ignition[857]: INFO : Stage: files May 15 10:25:58.545074 ignition[857]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 10:25:58.545074 ignition[857]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 10:25:58.545074 ignition[857]: DEBUG : files: compiled without relabeling support, skipping May 15 10:25:58.551674 ignition[857]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 15 10:25:58.551674 ignition[857]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 15 10:25:58.556596 ignition[857]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 15 10:25:58.557564 ignition[857]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 15 10:25:58.558728 unknown[857]: wrote ssh authorized keys file for user: core May 15 10:25:58.559575 ignition[857]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 15 10:25:58.559575 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 15 10:25:58.559575 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 15 10:25:58.559575 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 15 10:25:58.564418 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 15 10:25:58.564418 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 15 10:25:58.564418 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 15 10:25:58.564418 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 15 10:25:58.564418 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-arm64.raw: attempt #1 May 15 10:25:58.859506 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 15 10:25:59.006689 systemd-networkd[741]: eth0: Gained IPv6LL May 15 10:25:59.406413 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 15 10:25:59.408014 ignition[857]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 15 10:25:59.409002 ignition[857]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 15 10:25:59.409002 ignition[857]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 15 10:25:59.409002 ignition[857]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 15 10:25:59.409002 ignition[857]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 15 10:25:59.409002 ignition[857]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 15 10:25:59.448771 ignition[857]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 15 10:25:59.450000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.451586 ignition[857]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 15 10:25:59.451586 ignition[857]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 15 10:25:59.451586 ignition[857]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 15 10:25:59.451586 ignition[857]: INFO : files: files passed May 15 10:25:59.451586 ignition[857]: INFO : Ignition finished successfully May 15 10:25:59.450786 systemd[1]: Finished ignition-files.service. May 15 10:25:59.452307 systemd[1]: Starting initrd-setup-root-after-ignition.service... May 15 10:25:59.453420 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). May 15 10:25:59.458000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.458000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.460815 initrd-setup-root-after-ignition[882]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory May 15 10:25:59.460000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.454089 systemd[1]: Starting ignition-quench.service... May 15 10:25:59.463413 initrd-setup-root-after-ignition[885]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 10:25:59.458790 systemd[1]: ignition-quench.service: Deactivated successfully. May 15 10:25:59.458882 systemd[1]: Finished ignition-quench.service. May 15 10:25:59.459678 systemd[1]: Finished initrd-setup-root-after-ignition.service. May 15 10:25:59.461473 systemd[1]: Reached target ignition-complete.target. May 15 10:25:59.463526 systemd[1]: Starting initrd-parse-etc.service... May 15 10:25:59.475338 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 15 10:25:59.475428 systemd[1]: Finished initrd-parse-etc.service. May 15 10:25:59.475000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.476000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.476710 systemd[1]: Reached target initrd-fs.target. May 15 10:25:59.477748 systemd[1]: Reached target initrd.target. May 15 10:25:59.478764 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. May 15 10:25:59.479443 systemd[1]: Starting dracut-pre-pivot.service... May 15 10:25:59.489232 systemd[1]: Finished dracut-pre-pivot.service. May 15 10:25:59.489000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.490544 systemd[1]: Starting initrd-cleanup.service... May 15 10:25:59.497992 systemd[1]: Stopped target nss-lookup.target. May 15 10:25:59.498855 systemd[1]: Stopped target remote-cryptsetup.target. May 15 10:25:59.500094 systemd[1]: Stopped target timers.target. May 15 10:25:59.501262 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 15 10:25:59.502000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.501370 systemd[1]: Stopped dracut-pre-pivot.service. May 15 10:25:59.502553 systemd[1]: Stopped target initrd.target. May 15 10:25:59.503785 systemd[1]: Stopped target basic.target. May 15 10:25:59.504910 systemd[1]: Stopped target ignition-complete.target. May 15 10:25:59.506123 systemd[1]: Stopped target ignition-diskful.target. May 15 10:25:59.507300 systemd[1]: Stopped target initrd-root-device.target. May 15 10:25:59.508674 systemd[1]: Stopped target remote-fs.target. May 15 10:25:59.509872 systemd[1]: Stopped target remote-fs-pre.target. May 15 10:25:59.511099 systemd[1]: Stopped target sysinit.target. May 15 10:25:59.512190 systemd[1]: Stopped target local-fs.target. May 15 10:25:59.513338 systemd[1]: Stopped target local-fs-pre.target. May 15 10:25:59.514455 systemd[1]: Stopped target swap.target. May 15 10:25:59.516000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.515486 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 15 10:25:59.515618 systemd[1]: Stopped dracut-pre-mount.service. May 15 10:25:59.519000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.516759 systemd[1]: Stopped target cryptsetup.target. May 15 10:25:59.520000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.517776 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 15 10:25:59.517891 systemd[1]: Stopped dracut-initqueue.service. May 15 10:25:59.519249 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 15 10:25:59.519346 systemd[1]: Stopped ignition-fetch-offline.service. May 15 10:25:59.520535 systemd[1]: Stopped target paths.target. May 15 10:25:59.521620 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 15 10:25:59.525622 systemd[1]: Stopped systemd-ask-password-console.path. May 15 10:25:59.526550 systemd[1]: Stopped target slices.target. May 15 10:25:59.527772 systemd[1]: Stopped target sockets.target. May 15 10:25:59.528857 systemd[1]: iscsid.socket: Deactivated successfully. May 15 10:25:59.528934 systemd[1]: Closed iscsid.socket. May 15 10:25:59.529957 systemd[1]: iscsiuio.socket: Deactivated successfully. May 15 10:25:59.532000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.530022 systemd[1]: Closed iscsiuio.socket. May 15 10:25:59.532000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.531068 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 15 10:25:59.531167 systemd[1]: Stopped initrd-setup-root-after-ignition.service. May 15 10:25:59.532314 systemd[1]: ignition-files.service: Deactivated successfully. May 15 10:25:59.536000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.532405 systemd[1]: Stopped ignition-files.service. May 15 10:25:59.534370 systemd[1]: Stopping ignition-mount.service... May 15 10:25:59.535255 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 15 10:25:59.539000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.535387 systemd[1]: Stopped kmod-static-nodes.service. May 15 10:25:59.537416 systemd[1]: Stopping sysroot-boot.service... May 15 10:25:59.542285 ignition[898]: INFO : Ignition 2.14.0 May 15 10:25:59.542285 ignition[898]: INFO : Stage: umount May 15 10:25:59.542285 ignition[898]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 10:25:59.542285 ignition[898]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 15 10:25:59.542285 ignition[898]: INFO : umount: umount passed May 15 10:25:59.542285 ignition[898]: INFO : Ignition finished successfully May 15 10:25:59.542000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.538376 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 15 10:25:59.546000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.546000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.538496 systemd[1]: Stopped systemd-udev-trigger.service. May 15 10:25:59.547000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.539876 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 15 10:25:59.539963 systemd[1]: Stopped dracut-pre-trigger.service. May 15 10:25:59.545827 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 15 10:25:59.550000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.545916 systemd[1]: Finished initrd-cleanup.service. May 15 10:25:59.552000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.547171 systemd[1]: ignition-mount.service: Deactivated successfully. May 15 10:25:59.552000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.547246 systemd[1]: Stopped ignition-mount.service. May 15 10:25:59.549405 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 15 10:25:59.549683 systemd[1]: Stopped target network.target. May 15 10:25:59.550410 systemd[1]: ignition-disks.service: Deactivated successfully. May 15 10:25:59.550454 systemd[1]: Stopped ignition-disks.service. May 15 10:25:59.551522 systemd[1]: ignition-kargs.service: Deactivated successfully. May 15 10:25:59.551570 systemd[1]: Stopped ignition-kargs.service. May 15 10:25:59.552673 systemd[1]: ignition-setup.service: Deactivated successfully. May 15 10:25:59.552707 systemd[1]: Stopped ignition-setup.service. May 15 10:25:59.553736 systemd[1]: Stopping systemd-networkd.service... May 15 10:25:59.555038 systemd[1]: Stopping systemd-resolved.service... May 15 10:25:59.561844 systemd-networkd[741]: eth0: DHCPv6 lease lost May 15 10:25:59.562881 systemd[1]: systemd-networkd.service: Deactivated successfully. May 15 10:25:59.563000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.563016 systemd[1]: Stopped systemd-networkd.service. May 15 10:25:59.564446 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 15 10:25:59.564474 systemd[1]: Closed systemd-networkd.socket. May 15 10:25:59.566338 systemd[1]: Stopping network-cleanup.service... May 15 10:25:59.568000 audit: BPF prog-id=9 op=UNLOAD May 15 10:25:59.568000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.567612 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 15 10:25:59.569000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.567670 systemd[1]: Stopped parse-ip-for-networkd.service. May 15 10:25:59.569144 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 15 10:25:59.569188 systemd[1]: Stopped systemd-sysctl.service. May 15 10:25:59.571117 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 15 10:25:59.571000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.576000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.571158 systemd[1]: Stopped systemd-modules-load.service. May 15 10:25:59.572724 systemd[1]: Stopping systemd-udevd.service... May 15 10:25:59.575951 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 15 10:25:59.576421 systemd[1]: systemd-resolved.service: Deactivated successfully. May 15 10:25:59.581000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.576511 systemd[1]: Stopped systemd-resolved.service. May 15 10:25:59.582000 audit: BPF prog-id=6 op=UNLOAD May 15 10:25:59.580449 systemd[1]: network-cleanup.service: Deactivated successfully. May 15 10:25:59.582000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.583000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.580543 systemd[1]: Stopped network-cleanup.service. May 15 10:25:59.584000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.581887 systemd[1]: sysroot-boot.service: Deactivated successfully. May 15 10:25:59.581956 systemd[1]: Stopped sysroot-boot.service. May 15 10:25:59.583201 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 15 10:25:59.587000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.583240 systemd[1]: Stopped initrd-setup-root.service. May 15 10:25:59.588000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.584375 systemd[1]: systemd-udevd.service: Deactivated successfully. May 15 10:25:59.589000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.584478 systemd[1]: Stopped systemd-udevd.service. May 15 10:25:59.585527 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 15 10:25:59.585559 systemd[1]: Closed systemd-udevd-control.socket. May 15 10:25:59.592000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.586360 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 15 10:25:59.586390 systemd[1]: Closed systemd-udevd-kernel.socket. May 15 10:25:59.587438 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 15 10:25:59.587476 systemd[1]: Stopped dracut-pre-udev.service. May 15 10:25:59.588409 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 15 10:25:59.596000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.596000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.588443 systemd[1]: Stopped dracut-cmdline.service. May 15 10:25:59.589490 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 10:25:59.589523 systemd[1]: Stopped dracut-cmdline-ask.service. May 15 10:25:59.591133 systemd[1]: Starting initrd-udevadm-cleanup-db.service... May 15 10:25:59.592214 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 10:25:59.592262 systemd[1]: Stopped systemd-vconsole-setup.service. May 15 10:25:59.596053 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 15 10:25:59.596130 systemd[1]: Finished initrd-udevadm-cleanup-db.service. May 15 10:25:59.597189 systemd[1]: Reached target initrd-switch-root.target. May 15 10:25:59.598856 systemd[1]: Starting initrd-switch-root.service... May 15 10:25:59.604831 systemd[1]: Switching root. May 15 10:25:59.626881 iscsid[749]: iscsid shutting down. May 15 10:25:59.627397 systemd-journald[290]: Journal stopped May 15 10:26:01.557567 systemd-journald[290]: Received SIGTERM from PID 1 (n/a). May 15 10:26:01.557632 kernel: SELinux: Class mctp_socket not defined in policy. May 15 10:26:01.557645 kernel: SELinux: Class anon_inode not defined in policy. May 15 10:26:01.557655 kernel: SELinux: the above unknown classes and permissions will be allowed May 15 10:26:01.557665 kernel: SELinux: policy capability network_peer_controls=1 May 15 10:26:01.557675 kernel: SELinux: policy capability open_perms=1 May 15 10:26:01.557685 kernel: SELinux: policy capability extended_socket_class=1 May 15 10:26:01.557695 kernel: SELinux: policy capability always_check_network=0 May 15 10:26:01.557707 kernel: SELinux: policy capability cgroup_seclabel=1 May 15 10:26:01.557718 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 15 10:26:01.557728 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 15 10:26:01.557737 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 15 10:26:01.557747 systemd[1]: Successfully loaded SELinux policy in 33.814ms. May 15 10:26:01.557766 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.271ms. May 15 10:26:01.557777 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 15 10:26:01.557790 systemd[1]: Detected virtualization kvm. May 15 10:26:01.557800 systemd[1]: Detected architecture arm64. May 15 10:26:01.557811 systemd[1]: Detected first boot. May 15 10:26:01.557827 systemd[1]: Initializing machine ID from VM UUID. May 15 10:26:01.557843 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). May 15 10:26:01.557853 systemd[1]: Populated /etc with preset unit settings. May 15 10:26:01.557864 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 15 10:26:01.557880 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 15 10:26:01.557891 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 15 10:26:01.557904 kernel: kauditd_printk_skb: 77 callbacks suppressed May 15 10:26:01.557914 kernel: audit: type=1334 audit(1747304761.440:81): prog-id=12 op=LOAD May 15 10:26:01.557923 kernel: audit: type=1334 audit(1747304761.440:82): prog-id=3 op=UNLOAD May 15 10:26:01.557934 kernel: audit: type=1334 audit(1747304761.440:83): prog-id=13 op=LOAD May 15 10:26:01.557943 kernel: audit: type=1334 audit(1747304761.440:84): prog-id=14 op=LOAD May 15 10:26:01.557953 kernel: audit: type=1334 audit(1747304761.440:85): prog-id=4 op=UNLOAD May 15 10:26:01.557963 kernel: audit: type=1334 audit(1747304761.440:86): prog-id=5 op=UNLOAD May 15 10:26:01.557972 systemd[1]: iscsiuio.service: Deactivated successfully. May 15 10:26:01.557982 kernel: audit: type=1334 audit(1747304761.442:87): prog-id=15 op=LOAD May 15 10:26:01.557993 systemd[1]: Stopped iscsiuio.service. May 15 10:26:01.558004 kernel: audit: type=1334 audit(1747304761.442:88): prog-id=12 op=UNLOAD May 15 10:26:01.558013 kernel: audit: type=1334 audit(1747304761.442:89): prog-id=16 op=LOAD May 15 10:26:01.558024 kernel: audit: type=1334 audit(1747304761.443:90): prog-id=17 op=LOAD May 15 10:26:01.558036 systemd[1]: iscsid.service: Deactivated successfully. May 15 10:26:01.558050 systemd[1]: Stopped iscsid.service. May 15 10:26:01.558061 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 15 10:26:01.558073 systemd[1]: Stopped initrd-switch-root.service. May 15 10:26:01.558085 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 15 10:26:01.558095 systemd[1]: Created slice system-addon\x2dconfig.slice. May 15 10:26:01.558106 systemd[1]: Created slice system-addon\x2drun.slice. May 15 10:26:01.558117 systemd[1]: Created slice system-getty.slice. May 15 10:26:01.558127 systemd[1]: Created slice system-modprobe.slice. May 15 10:26:01.558138 systemd[1]: Created slice system-serial\x2dgetty.slice. May 15 10:26:01.558148 systemd[1]: Created slice system-system\x2dcloudinit.slice. May 15 10:26:01.558159 systemd[1]: Created slice system-systemd\x2dfsck.slice. May 15 10:26:01.558169 systemd[1]: Created slice user.slice. May 15 10:26:01.558181 systemd[1]: Started systemd-ask-password-console.path. May 15 10:26:01.558191 systemd[1]: Started systemd-ask-password-wall.path. May 15 10:26:01.558201 systemd[1]: Set up automount boot.automount. May 15 10:26:01.558212 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. May 15 10:26:01.558223 systemd[1]: Stopped target initrd-switch-root.target. May 15 10:26:01.558233 systemd[1]: Stopped target initrd-fs.target. May 15 10:26:01.558243 systemd[1]: Stopped target initrd-root-fs.target. May 15 10:26:01.558254 systemd[1]: Reached target integritysetup.target. May 15 10:26:01.558266 systemd[1]: Reached target remote-cryptsetup.target. May 15 10:26:01.558276 systemd[1]: Reached target remote-fs.target. May 15 10:26:01.558287 systemd[1]: Reached target slices.target. May 15 10:26:01.558298 systemd[1]: Reached target swap.target. May 15 10:26:01.558308 systemd[1]: Reached target torcx.target. May 15 10:26:01.558319 systemd[1]: Reached target veritysetup.target. May 15 10:26:01.558330 systemd[1]: Listening on systemd-coredump.socket. May 15 10:26:01.558342 systemd[1]: Listening on systemd-initctl.socket. May 15 10:26:01.558353 systemd[1]: Listening on systemd-networkd.socket. May 15 10:26:01.558363 systemd[1]: Listening on systemd-udevd-control.socket. May 15 10:26:01.558374 systemd[1]: Listening on systemd-udevd-kernel.socket. May 15 10:26:01.558385 systemd[1]: Listening on systemd-userdbd.socket. May 15 10:26:01.558395 systemd[1]: Mounting dev-hugepages.mount... May 15 10:26:01.558406 systemd[1]: Mounting dev-mqueue.mount... May 15 10:26:01.558416 systemd[1]: Mounting media.mount... May 15 10:26:01.558427 systemd[1]: Mounting sys-kernel-debug.mount... May 15 10:26:01.558438 systemd[1]: Mounting sys-kernel-tracing.mount... May 15 10:26:01.558452 systemd[1]: Mounting tmp.mount... May 15 10:26:01.558462 systemd[1]: Starting flatcar-tmpfiles.service... May 15 10:26:01.558473 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 15 10:26:01.558484 systemd[1]: Starting kmod-static-nodes.service... May 15 10:26:01.558494 systemd[1]: Starting modprobe@configfs.service... May 15 10:26:01.558505 systemd[1]: Starting modprobe@dm_mod.service... May 15 10:26:01.558515 systemd[1]: Starting modprobe@drm.service... May 15 10:26:01.558527 systemd[1]: Starting modprobe@efi_pstore.service... May 15 10:26:01.558539 systemd[1]: Starting modprobe@fuse.service... May 15 10:26:01.558550 systemd[1]: Starting modprobe@loop.service... May 15 10:26:01.558561 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 15 10:26:01.558572 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 15 10:26:01.558582 systemd[1]: Stopped systemd-fsck-root.service. May 15 10:26:01.558605 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 15 10:26:01.558616 systemd[1]: Stopped systemd-fsck-usr.service. May 15 10:26:01.558627 systemd[1]: Stopped systemd-journald.service. May 15 10:26:01.558638 kernel: loop: module loaded May 15 10:26:01.558650 kernel: fuse: init (API version 7.34) May 15 10:26:01.558660 systemd[1]: Starting systemd-journald.service... May 15 10:26:01.558670 systemd[1]: Starting systemd-modules-load.service... May 15 10:26:01.558681 systemd[1]: Starting systemd-network-generator.service... May 15 10:26:01.558692 systemd[1]: Starting systemd-remount-fs.service... May 15 10:26:01.558703 systemd[1]: Starting systemd-udev-trigger.service... May 15 10:26:01.558713 systemd[1]: verity-setup.service: Deactivated successfully. May 15 10:26:01.558723 systemd[1]: Stopped verity-setup.service. May 15 10:26:01.558734 systemd[1]: Mounted dev-hugepages.mount. May 15 10:26:01.558744 systemd[1]: Mounted dev-mqueue.mount. May 15 10:26:01.558756 systemd[1]: Mounted media.mount. May 15 10:26:01.558768 systemd[1]: Mounted sys-kernel-debug.mount. May 15 10:26:01.558778 systemd[1]: Mounted sys-kernel-tracing.mount. May 15 10:26:01.558789 systemd[1]: Mounted tmp.mount. May 15 10:26:01.558801 systemd-journald[993]: Journal started May 15 10:26:01.558845 systemd-journald[993]: Runtime Journal (/run/log/journal/7b51515e7eb242818f12ed64dd7cf9ea) is 6.0M, max 48.7M, 42.6M free. May 15 10:25:59.691000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 May 15 10:25:59.764000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 May 15 10:25:59.764000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 May 15 10:25:59.764000 audit: BPF prog-id=10 op=LOAD May 15 10:25:59.764000 audit: BPF prog-id=10 op=UNLOAD May 15 10:25:59.764000 audit: BPF prog-id=11 op=LOAD May 15 10:25:59.764000 audit: BPF prog-id=11 op=UNLOAD May 15 10:25:59.796000 audit[931]: AVC avc: denied { associate } for pid=931 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" May 15 10:25:59.796000 audit[931]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001858cc a1=4000028e40 a2=4000027080 a3=32 items=0 ppid=914 pid=931 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 10:25:59.796000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 May 15 10:25:59.797000 audit[931]: AVC avc: denied { associate } for pid=931 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 May 15 10:25:59.797000 audit[931]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001859a9 a2=1ed a3=0 items=2 ppid=914 pid=931 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 10:25:59.797000 audit: CWD cwd="/" May 15 10:25:59.797000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 10:25:59.797000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 May 15 10:25:59.797000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 May 15 10:26:01.440000 audit: BPF prog-id=12 op=LOAD May 15 10:26:01.440000 audit: BPF prog-id=3 op=UNLOAD May 15 10:26:01.440000 audit: BPF prog-id=13 op=LOAD May 15 10:26:01.440000 audit: BPF prog-id=14 op=LOAD May 15 10:26:01.440000 audit: BPF prog-id=4 op=UNLOAD May 15 10:26:01.440000 audit: BPF prog-id=5 op=UNLOAD May 15 10:26:01.442000 audit: BPF prog-id=15 op=LOAD May 15 10:26:01.442000 audit: BPF prog-id=12 op=UNLOAD May 15 10:26:01.442000 audit: BPF prog-id=16 op=LOAD May 15 10:26:01.443000 audit: BPF prog-id=17 op=LOAD May 15 10:26:01.443000 audit: BPF prog-id=13 op=UNLOAD May 15 10:26:01.443000 audit: BPF prog-id=14 op=UNLOAD May 15 10:26:01.444000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.448000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.450000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.452000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.452000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.459000 audit: BPF prog-id=15 op=UNLOAD May 15 10:26:01.531000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.533000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.534000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.534000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.534000 audit: BPF prog-id=18 op=LOAD May 15 10:26:01.535000 audit: BPF prog-id=19 op=LOAD May 15 10:26:01.535000 audit: BPF prog-id=20 op=LOAD May 15 10:26:01.535000 audit: BPF prog-id=16 op=UNLOAD May 15 10:26:01.535000 audit: BPF prog-id=17 op=UNLOAD May 15 10:26:01.549000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.555000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 May 15 10:26:01.555000 audit[993]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffc1266900 a2=4000 a3=1 items=0 ppid=1 pid=993 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 10:26:01.555000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" May 15 10:26:01.439900 systemd[1]: Queued start job for default target multi-user.target. May 15 10:25:59.795570 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.100 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.100 /var/lib/torcx/store]" May 15 10:26:01.439911 systemd[1]: Unnecessary job was removed for dev-vda6.device. May 15 10:25:59.795881 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json May 15 10:26:01.560185 systemd[1]: Started systemd-journald.service. May 15 10:26:01.444847 systemd[1]: systemd-journald.service: Deactivated successfully. May 15 10:25:59.795901 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json May 15 10:26:01.560000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:25:59.795930 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" May 15 10:25:59.795939 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="skipped missing lower profile" missing profile=oem May 15 10:25:59.795966 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" May 15 10:25:59.795977 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= May 15 10:25:59.796161 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack May 15 10:26:01.560688 systemd[1]: Finished kmod-static-nodes.service. May 15 10:25:59.796198 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json May 15 10:25:59.796209 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json May 15 10:25:59.796840 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 May 15 10:25:59.796877 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl May 15 10:25:59.796895 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.100: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.100 May 15 10:25:59.796908 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store May 15 10:25:59.796925 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.100: no such file or directory" path=/var/lib/torcx/store/3510.3.100 May 15 10:25:59.796937 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:25:59Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store May 15 10:26:01.202708 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:26:01Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 10:26:01.202974 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:26:01Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 10:26:01.560000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.203069 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:26:01Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 10:26:01.203232 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:26:01Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 15 10:26:01.203280 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:26:01Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= May 15 10:26:01.203334 /usr/lib/systemd/system-generators/torcx-generator[931]: time="2025-05-15T10:26:01Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx May 15 10:26:01.562030 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 15 10:26:01.562188 systemd[1]: Finished modprobe@configfs.service. May 15 10:26:01.562000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.562000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.563278 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 10:26:01.563442 systemd[1]: Finished modprobe@dm_mod.service. May 15 10:26:01.564000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.564000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.564512 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 10:26:01.564746 systemd[1]: Finished modprobe@drm.service. May 15 10:26:01.564000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.564000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.565765 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 10:26:01.565928 systemd[1]: Finished modprobe@efi_pstore.service. May 15 10:26:01.566000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.566000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.567179 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 15 10:26:01.567314 systemd[1]: Finished modprobe@fuse.service. May 15 10:26:01.568000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.568000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.568381 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 10:26:01.568528 systemd[1]: Finished modprobe@loop.service. May 15 10:26:01.569000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.569000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.569671 systemd[1]: Finished systemd-modules-load.service. May 15 10:26:01.569000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.570772 systemd[1]: Finished systemd-network-generator.service. May 15 10:26:01.571000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.571975 systemd[1]: Finished systemd-remount-fs.service. May 15 10:26:01.572000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.573208 systemd[1]: Finished flatcar-tmpfiles.service. May 15 10:26:01.573000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.574438 systemd[1]: Reached target network-pre.target. May 15 10:26:01.576419 systemd[1]: Mounting sys-fs-fuse-connections.mount... May 15 10:26:01.578390 systemd[1]: Mounting sys-kernel-config.mount... May 15 10:26:01.579164 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 15 10:26:01.580751 systemd[1]: Starting systemd-hwdb-update.service... May 15 10:26:01.582487 systemd[1]: Starting systemd-journal-flush.service... May 15 10:26:01.583429 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 10:26:01.584393 systemd[1]: Starting systemd-random-seed.service... May 15 10:26:01.585324 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 15 10:26:01.586332 systemd[1]: Starting systemd-sysctl.service... May 15 10:26:01.588277 systemd[1]: Starting systemd-sysusers.service... May 15 10:26:01.590792 systemd-journald[993]: Time spent on flushing to /var/log/journal/7b51515e7eb242818f12ed64dd7cf9ea is 11.654ms for 975 entries. May 15 10:26:01.590792 systemd-journald[993]: System Journal (/var/log/journal/7b51515e7eb242818f12ed64dd7cf9ea) is 8.0M, max 195.6M, 187.6M free. May 15 10:26:01.606015 systemd-journald[993]: Received client request to flush runtime journal. May 15 10:26:01.593000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.604000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.592751 systemd[1]: Finished systemd-udev-trigger.service. May 15 10:26:01.606830 udevadm[1032]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 15 10:26:01.594034 systemd[1]: Mounted sys-fs-fuse-connections.mount. May 15 10:26:01.595027 systemd[1]: Mounted sys-kernel-config.mount. May 15 10:26:01.596801 systemd[1]: Starting systemd-udev-settle.service... May 15 10:26:01.603986 systemd[1]: Finished systemd-random-seed.service. May 15 10:26:01.605023 systemd[1]: Reached target first-boot-complete.target. May 15 10:26:01.606803 systemd[1]: Finished systemd-journal-flush.service. May 15 10:26:01.607000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.608866 systemd[1]: Finished systemd-sysctl.service. May 15 10:26:01.609000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.618657 systemd[1]: Finished systemd-sysusers.service. May 15 10:26:01.618000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.950729 systemd[1]: Finished systemd-hwdb-update.service. May 15 10:26:01.950000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.951000 audit: BPF prog-id=21 op=LOAD May 15 10:26:01.951000 audit: BPF prog-id=22 op=LOAD May 15 10:26:01.951000 audit: BPF prog-id=7 op=UNLOAD May 15 10:26:01.951000 audit: BPF prog-id=8 op=UNLOAD May 15 10:26:01.952944 systemd[1]: Starting systemd-udevd.service... May 15 10:26:01.973176 systemd-udevd[1035]: Using default interface naming scheme 'v252'. May 15 10:26:01.986117 systemd[1]: Started systemd-udevd.service. May 15 10:26:01.986000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:01.987000 audit: BPF prog-id=23 op=LOAD May 15 10:26:01.988540 systemd[1]: Starting systemd-networkd.service... May 15 10:26:01.991000 audit: BPF prog-id=24 op=LOAD May 15 10:26:01.991000 audit: BPF prog-id=25 op=LOAD May 15 10:26:01.991000 audit: BPF prog-id=26 op=LOAD May 15 10:26:01.992515 systemd[1]: Starting systemd-userdbd.service... May 15 10:26:02.018827 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. May 15 10:26:02.037165 systemd[1]: Started systemd-userdbd.service. May 15 10:26:02.037000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.042947 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 15 10:26:02.087907 systemd-networkd[1036]: lo: Link UP May 15 10:26:02.088138 systemd-networkd[1036]: lo: Gained carrier May 15 10:26:02.088553 systemd-networkd[1036]: Enumeration completed May 15 10:26:02.088801 systemd[1]: Started systemd-networkd.service. May 15 10:26:02.088907 systemd-networkd[1036]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 10:26:02.089000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.093815 systemd-networkd[1036]: eth0: Link UP May 15 10:26:02.093905 systemd-networkd[1036]: eth0: Gained carrier May 15 10:26:02.102026 systemd[1]: Finished systemd-udev-settle.service. May 15 10:26:02.102000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.104075 systemd[1]: Starting lvm2-activation-early.service... May 15 10:26:02.111757 lvm[1068]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 10:26:02.113729 systemd-networkd[1036]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 15 10:26:02.136384 systemd[1]: Finished lvm2-activation-early.service. May 15 10:26:02.136000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.137409 systemd[1]: Reached target cryptsetup.target. May 15 10:26:02.139328 systemd[1]: Starting lvm2-activation.service... May 15 10:26:02.143104 lvm[1069]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 10:26:02.176482 systemd[1]: Finished lvm2-activation.service. May 15 10:26:02.176000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.177449 systemd[1]: Reached target local-fs-pre.target. May 15 10:26:02.178324 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 15 10:26:02.178358 systemd[1]: Reached target local-fs.target. May 15 10:26:02.179147 systemd[1]: Reached target machines.target. May 15 10:26:02.181051 systemd[1]: Starting ldconfig.service... May 15 10:26:02.182059 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 15 10:26:02.182112 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 10:26:02.183290 systemd[1]: Starting systemd-boot-update.service... May 15 10:26:02.185483 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... May 15 10:26:02.187679 systemd[1]: Starting systemd-machine-id-commit.service... May 15 10:26:02.190149 systemd[1]: Starting systemd-sysext.service... May 15 10:26:02.191879 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1071 (bootctl) May 15 10:26:02.193457 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... May 15 10:26:02.199991 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. May 15 10:26:02.200000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.208396 systemd[1]: Unmounting usr-share-oem.mount... May 15 10:26:02.214074 systemd[1]: usr-share-oem.mount: Deactivated successfully. May 15 10:26:02.214273 systemd[1]: Unmounted usr-share-oem.mount. May 15 10:26:02.253627 kernel: loop0: detected capacity change from 0 to 189592 May 15 10:26:02.253748 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 15 10:26:02.254588 systemd[1]: Finished systemd-machine-id-commit.service. May 15 10:26:02.255000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.264612 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 15 10:26:02.269401 systemd-fsck[1079]: fsck.fat 4.2 (2021-01-31) May 15 10:26:02.269401 systemd-fsck[1079]: /dev/vda1: 236 files, 117182/258078 clusters May 15 10:26:02.271683 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. May 15 10:26:02.272000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.275179 systemd[1]: Mounting boot.mount... May 15 10:26:02.285629 kernel: loop1: detected capacity change from 0 to 189592 May 15 10:26:02.288457 systemd[1]: Mounted boot.mount. May 15 10:26:02.290572 (sd-sysext)[1084]: Using extensions 'kubernetes'. May 15 10:26:02.291167 (sd-sysext)[1084]: Merged extensions into '/usr'. May 15 10:26:02.297521 systemd[1]: Finished systemd-boot-update.service. May 15 10:26:02.298000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.307571 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 15 10:26:02.308862 systemd[1]: Starting modprobe@dm_mod.service... May 15 10:26:02.310908 systemd[1]: Starting modprobe@efi_pstore.service... May 15 10:26:02.313003 systemd[1]: Starting modprobe@loop.service... May 15 10:26:02.313928 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 15 10:26:02.314060 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 10:26:02.314881 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 10:26:02.315009 systemd[1]: Finished modprobe@dm_mod.service. May 15 10:26:02.315000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.315000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.316308 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 10:26:02.316428 systemd[1]: Finished modprobe@efi_pstore.service. May 15 10:26:02.317000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.317000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.318112 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 10:26:02.318226 systemd[1]: Finished modprobe@loop.service. May 15 10:26:02.319000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.319000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.319910 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 10:26:02.320023 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 15 10:26:02.349022 ldconfig[1070]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 15 10:26:02.352750 systemd[1]: Finished ldconfig.service. May 15 10:26:02.352000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.552780 systemd[1]: Mounting usr-share-oem.mount... May 15 10:26:02.557533 systemd[1]: Mounted usr-share-oem.mount. May 15 10:26:02.559384 systemd[1]: Finished systemd-sysext.service. May 15 10:26:02.560000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.561346 systemd[1]: Starting ensure-sysext.service... May 15 10:26:02.563076 systemd[1]: Starting systemd-tmpfiles-setup.service... May 15 10:26:02.567053 systemd[1]: Reloading. May 15 10:26:02.577784 systemd-tmpfiles[1091]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. May 15 10:26:02.580037 systemd-tmpfiles[1091]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 15 10:26:02.582989 systemd-tmpfiles[1091]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 15 10:26:02.601528 /usr/lib/systemd/system-generators/torcx-generator[1111]: time="2025-05-15T10:26:02Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.100 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.100 /var/lib/torcx/store]" May 15 10:26:02.601561 /usr/lib/systemd/system-generators/torcx-generator[1111]: time="2025-05-15T10:26:02Z" level=info msg="torcx already run" May 15 10:26:02.657610 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 15 10:26:02.657631 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 15 10:26:02.672957 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 15 10:26:02.714000 audit: BPF prog-id=27 op=LOAD May 15 10:26:02.714000 audit: BPF prog-id=18 op=UNLOAD May 15 10:26:02.714000 audit: BPF prog-id=28 op=LOAD May 15 10:26:02.714000 audit: BPF prog-id=29 op=LOAD May 15 10:26:02.714000 audit: BPF prog-id=19 op=UNLOAD May 15 10:26:02.714000 audit: BPF prog-id=20 op=UNLOAD May 15 10:26:02.715000 audit: BPF prog-id=30 op=LOAD May 15 10:26:02.715000 audit: BPF prog-id=31 op=LOAD May 15 10:26:02.715000 audit: BPF prog-id=21 op=UNLOAD May 15 10:26:02.715000 audit: BPF prog-id=22 op=UNLOAD May 15 10:26:02.716000 audit: BPF prog-id=32 op=LOAD May 15 10:26:02.716000 audit: BPF prog-id=24 op=UNLOAD May 15 10:26:02.716000 audit: BPF prog-id=33 op=LOAD May 15 10:26:02.716000 audit: BPF prog-id=34 op=LOAD May 15 10:26:02.716000 audit: BPF prog-id=25 op=UNLOAD May 15 10:26:02.716000 audit: BPF prog-id=26 op=UNLOAD May 15 10:26:02.717000 audit: BPF prog-id=35 op=LOAD May 15 10:26:02.717000 audit: BPF prog-id=23 op=UNLOAD May 15 10:26:02.720564 systemd[1]: Finished systemd-tmpfiles-setup.service. May 15 10:26:02.720000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.724741 systemd[1]: Starting audit-rules.service... May 15 10:26:02.726403 systemd[1]: Starting clean-ca-certificates.service... May 15 10:26:02.728409 systemd[1]: Starting systemd-journal-catalog-update.service... May 15 10:26:02.732000 audit: BPF prog-id=36 op=LOAD May 15 10:26:02.733896 systemd[1]: Starting systemd-resolved.service... May 15 10:26:02.736000 audit: BPF prog-id=37 op=LOAD May 15 10:26:02.737703 systemd[1]: Starting systemd-timesyncd.service... May 15 10:26:02.739697 systemd[1]: Starting systemd-update-utmp.service... May 15 10:26:02.742494 systemd[1]: Finished clean-ca-certificates.service. May 15 10:26:02.743000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.743000 audit[1161]: SYSTEM_BOOT pid=1161 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' May 15 10:26:02.743581 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 15 10:26:02.748013 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 15 10:26:02.749163 systemd[1]: Starting modprobe@dm_mod.service... May 15 10:26:02.751861 systemd[1]: Starting modprobe@efi_pstore.service... May 15 10:26:02.753536 systemd[1]: Starting modprobe@loop.service... May 15 10:26:02.754172 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 15 10:26:02.754282 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 10:26:02.754377 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 15 10:26:02.755212 systemd[1]: Finished systemd-update-utmp.service. May 15 10:26:02.755000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.757000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.757000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.756331 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 10:26:02.756444 systemd[1]: Finished modprobe@dm_mod.service. May 15 10:26:02.757628 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 10:26:02.757739 systemd[1]: Finished modprobe@efi_pstore.service. May 15 10:26:02.757000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.757000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.758869 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 10:26:02.758982 systemd[1]: Finished modprobe@loop.service. May 15 10:26:02.759000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.759000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.760797 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 10:26:02.760922 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 15 10:26:02.762656 systemd[1]: Finished systemd-journal-catalog-update.service. May 15 10:26:02.762000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.764911 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 15 10:26:02.766018 systemd[1]: Starting modprobe@dm_mod.service... May 15 10:26:02.767641 systemd[1]: Starting modprobe@efi_pstore.service... May 15 10:26:02.769422 systemd[1]: Starting modprobe@loop.service... May 15 10:26:02.770189 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 15 10:26:02.770315 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 10:26:02.771529 systemd[1]: Starting systemd-update-done.service... May 15 10:26:02.772281 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 15 10:26:02.773185 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 10:26:02.773310 systemd[1]: Finished modprobe@dm_mod.service. May 15 10:26:02.773000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.773000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.774328 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 10:26:02.774435 systemd[1]: Finished modprobe@efi_pstore.service. May 15 10:26:02.775000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.775000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.775488 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 10:26:02.775658 systemd[1]: Finished modprobe@loop.service. May 15 10:26:02.775000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.775000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.778531 systemd[1]: Finished systemd-update-done.service. May 15 10:26:02.779000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.779781 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 15 10:26:02.780918 systemd[1]: Starting modprobe@dm_mod.service... May 15 10:26:02.782762 systemd[1]: Starting modprobe@drm.service... May 15 10:26:02.784538 systemd[1]: Starting modprobe@efi_pstore.service... May 15 10:26:02.786367 systemd[1]: Starting modprobe@loop.service... May 15 10:26:02.787080 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 15 10:26:02.787210 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 10:26:02.788500 systemd[1]: Starting systemd-networkd-wait-online.service... May 15 10:26:02.789430 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 15 10:26:02.790000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.790000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.791000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:02.790531 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 10:26:02.790709 systemd[1]: Finished modprobe@dm_mod.service. May 15 10:26:02.791664 systemd[1]: Started systemd-timesyncd.service. May 15 10:26:03.199401 systemd-timesyncd[1158]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 15 10:26:03.199512 systemd-timesyncd[1158]: Initial clock synchronization to Thu 2025-05-15 10:26:03.199301 UTC. May 15 10:26:03.199847 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 10:26:03.199964 systemd[1]: Finished modprobe@drm.service. May 15 10:26:03.200000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:03.200000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:03.201162 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 10:26:03.201317 systemd[1]: Finished modprobe@efi_pstore.service. May 15 10:26:03.202237 augenrules[1183]: No rules May 15 10:26:03.202442 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 10:26:03.202556 systemd[1]: Finished modprobe@loop.service. May 15 10:26:03.201000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 May 15 10:26:03.201000 audit[1183]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffc22ae7d0 a2=420 a3=0 items=0 ppid=1150 pid=1183 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) May 15 10:26:03.201000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 May 15 10:26:03.201000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 15 10:26:03.203607 systemd[1]: Finished audit-rules.service. May 15 10:26:03.204856 systemd[1]: Reached target time-set.target. May 15 10:26:03.205618 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 10:26:03.205670 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 15 10:26:03.205958 systemd[1]: Finished ensure-sysext.service. May 15 10:26:03.206285 systemd-resolved[1154]: Positive Trust Anchors: May 15 10:26:03.206296 systemd-resolved[1154]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 10:26:03.206323 systemd-resolved[1154]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test May 15 10:26:03.221483 systemd-resolved[1154]: Defaulting to hostname 'linux'. May 15 10:26:03.222882 systemd[1]: Started systemd-resolved.service. May 15 10:26:03.223568 systemd[1]: Reached target network.target. May 15 10:26:03.224131 systemd[1]: Reached target nss-lookup.target. May 15 10:26:03.224739 systemd[1]: Reached target sysinit.target. May 15 10:26:03.225363 systemd[1]: Started motdgen.path. May 15 10:26:03.225943 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. May 15 10:26:03.226896 systemd[1]: Started logrotate.timer. May 15 10:26:03.227534 systemd[1]: Started mdadm.timer. May 15 10:26:03.228043 systemd[1]: Started systemd-tmpfiles-clean.timer. May 15 10:26:03.228672 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 15 10:26:03.228700 systemd[1]: Reached target paths.target. May 15 10:26:03.229236 systemd[1]: Reached target timers.target. May 15 10:26:03.230153 systemd[1]: Listening on dbus.socket. May 15 10:26:03.231868 systemd[1]: Starting docker.socket... May 15 10:26:03.235321 systemd[1]: Listening on sshd.socket. May 15 10:26:03.236031 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 10:26:03.236469 systemd[1]: Listening on docker.socket. May 15 10:26:03.237103 systemd[1]: Reached target sockets.target. May 15 10:26:03.237716 systemd[1]: Reached target basic.target. May 15 10:26:03.238288 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. May 15 10:26:03.238321 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. May 15 10:26:03.239291 systemd[1]: Starting containerd.service... May 15 10:26:03.240894 systemd[1]: Starting dbus.service... May 15 10:26:03.242445 systemd[1]: Starting enable-oem-cloudinit.service... May 15 10:26:03.244192 systemd[1]: Starting extend-filesystems.service... May 15 10:26:03.244914 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). May 15 10:26:03.246346 systemd[1]: Starting motdgen.service... May 15 10:26:03.248025 systemd[1]: Starting ssh-key-proc-cmdline.service... May 15 10:26:03.249829 jq[1193]: false May 15 10:26:03.251837 systemd[1]: Starting sshd-keygen.service... May 15 10:26:03.254532 systemd[1]: Starting systemd-logind.service... May 15 10:26:03.255299 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 15 10:26:03.255372 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 15 10:26:03.257782 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 15 10:26:03.259482 systemd[1]: Starting update-engine.service... May 15 10:26:03.261164 systemd[1]: Starting update-ssh-keys-after-ignition.service... May 15 10:26:03.263378 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 15 10:26:03.264454 jq[1210]: true May 15 10:26:03.263579 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. May 15 10:26:03.263876 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 15 10:26:03.264009 systemd[1]: Finished ssh-key-proc-cmdline.service. May 15 10:26:03.267794 extend-filesystems[1194]: Found loop1 May 15 10:26:03.267794 extend-filesystems[1194]: Found vda May 15 10:26:03.267794 extend-filesystems[1194]: Found vda1 May 15 10:26:03.267794 extend-filesystems[1194]: Found vda2 May 15 10:26:03.267794 extend-filesystems[1194]: Found vda3 May 15 10:26:03.267794 extend-filesystems[1194]: Found usr May 15 10:26:03.267794 extend-filesystems[1194]: Found vda4 May 15 10:26:03.267794 extend-filesystems[1194]: Found vda6 May 15 10:26:03.267794 extend-filesystems[1194]: Found vda7 May 15 10:26:03.267794 extend-filesystems[1194]: Found vda9 May 15 10:26:03.267794 extend-filesystems[1194]: Checking size of /dev/vda9 May 15 10:26:03.281801 dbus-daemon[1192]: [system] SELinux support is enabled May 15 10:26:03.274569 systemd[1]: motdgen.service: Deactivated successfully. May 15 10:26:03.274744 systemd[1]: Finished motdgen.service. May 15 10:26:03.281963 systemd[1]: Started dbus.service. May 15 10:26:03.284963 jq[1214]: true May 15 10:26:03.287700 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 15 10:26:03.287741 systemd[1]: Reached target system-config.target. May 15 10:26:03.288450 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 15 10:26:03.288470 systemd[1]: Reached target user-config.target. May 15 10:26:03.317146 extend-filesystems[1194]: Resized partition /dev/vda9 May 15 10:26:03.318678 systemd-logind[1204]: Watching system buttons on /dev/input/event0 (Power Button) May 15 10:26:03.322431 systemd-logind[1204]: New seat seat0. May 15 10:26:03.325497 extend-filesystems[1240]: resize2fs 1.46.5 (30-Dec-2021) May 15 10:26:03.326460 systemd[1]: Started systemd-logind.service. May 15 10:26:03.353068 update_engine[1209]: I0515 10:26:03.351307 1209 main.cc:92] Flatcar Update Engine starting May 15 10:26:03.354995 systemd[1]: Started update-engine.service. May 15 10:26:03.355128 update_engine[1209]: I0515 10:26:03.354998 1209 update_check_scheduler.cc:74] Next update check in 10m35s May 15 10:26:03.359087 systemd[1]: Started locksmithd.service. May 15 10:26:03.359460 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 15 10:26:03.366175 env[1213]: time="2025-05-15T10:26:03.366132669Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 May 15 10:26:03.386107 env[1213]: time="2025-05-15T10:26:03.386042469Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 15 10:26:03.389939 env[1213]: time="2025-05-15T10:26:03.389914389Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 15 10:26:03.391186 env[1213]: time="2025-05-15T10:26:03.391155349Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.182-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 15 10:26:03.391336 env[1213]: time="2025-05-15T10:26:03.391314709Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 15 10:26:03.391675 env[1213]: time="2025-05-15T10:26:03.391648189Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 15 10:26:03.391753 env[1213]: time="2025-05-15T10:26:03.391738949Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 15 10:26:03.391826 env[1213]: time="2025-05-15T10:26:03.391810869Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" May 15 10:26:03.391876 env[1213]: time="2025-05-15T10:26:03.391863469Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 15 10:26:03.393309 env[1213]: time="2025-05-15T10:26:03.393290029Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 15 10:26:03.393713 env[1213]: time="2025-05-15T10:26:03.393687869Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 15 10:26:03.393942 env[1213]: time="2025-05-15T10:26:03.393920709Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 15 10:26:03.394010 env[1213]: time="2025-05-15T10:26:03.393996109Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 15 10:26:03.394178 env[1213]: time="2025-05-15T10:26:03.394157349Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" May 15 10:26:03.394268 env[1213]: time="2025-05-15T10:26:03.394254469Z" level=info msg="metadata content store policy set" policy=shared May 15 10:26:03.394949 bash[1239]: Updated "/home/core/.ssh/authorized_keys" May 15 10:26:03.395788 systemd[1]: Finished update-ssh-keys-after-ignition.service. May 15 10:26:03.398434 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 15 10:26:03.420567 extend-filesystems[1240]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 15 10:26:03.420567 extend-filesystems[1240]: old_desc_blocks = 1, new_desc_blocks = 1 May 15 10:26:03.420567 extend-filesystems[1240]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 15 10:26:03.423842 extend-filesystems[1194]: Resized filesystem in /dev/vda9 May 15 10:26:03.421319 systemd[1]: extend-filesystems.service: Deactivated successfully. May 15 10:26:03.421508 systemd[1]: Finished extend-filesystems.service. May 15 10:26:03.425441 env[1213]: time="2025-05-15T10:26:03.425363429Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 15 10:26:03.425441 env[1213]: time="2025-05-15T10:26:03.425429669Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 15 10:26:03.425545 env[1213]: time="2025-05-15T10:26:03.425445629Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 15 10:26:03.425545 env[1213]: time="2025-05-15T10:26:03.425485589Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 15 10:26:03.425545 env[1213]: time="2025-05-15T10:26:03.425502909Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 15 10:26:03.425545 env[1213]: time="2025-05-15T10:26:03.425516709Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 15 10:26:03.425681 env[1213]: time="2025-05-15T10:26:03.425596349Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 15 10:26:03.426037 env[1213]: time="2025-05-15T10:26:03.425999629Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 15 10:26:03.426037 env[1213]: time="2025-05-15T10:26:03.426033589Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 May 15 10:26:03.426102 env[1213]: time="2025-05-15T10:26:03.426046949Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 15 10:26:03.426102 env[1213]: time="2025-05-15T10:26:03.426060189Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 15 10:26:03.426102 env[1213]: time="2025-05-15T10:26:03.426074629Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 15 10:26:03.426167 locksmithd[1242]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 15 10:26:03.426354 env[1213]: time="2025-05-15T10:26:03.426199149Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 15 10:26:03.426354 env[1213]: time="2025-05-15T10:26:03.426274909Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 15 10:26:03.426523 env[1213]: time="2025-05-15T10:26:03.426504509Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 15 10:26:03.426557 env[1213]: time="2025-05-15T10:26:03.426536029Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426557 env[1213]: time="2025-05-15T10:26:03.426550789Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 15 10:26:03.426678 env[1213]: time="2025-05-15T10:26:03.426665029Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426710 env[1213]: time="2025-05-15T10:26:03.426682509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426710 env[1213]: time="2025-05-15T10:26:03.426694669Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426710 env[1213]: time="2025-05-15T10:26:03.426707589Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426775 env[1213]: time="2025-05-15T10:26:03.426723309Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426775 env[1213]: time="2025-05-15T10:26:03.426736429Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426775 env[1213]: time="2025-05-15T10:26:03.426747389Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426775 env[1213]: time="2025-05-15T10:26:03.426759509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426775 env[1213]: time="2025-05-15T10:26:03.426771909Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 15 10:26:03.426956 env[1213]: time="2025-05-15T10:26:03.426884869Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426956 env[1213]: time="2025-05-15T10:26:03.426928429Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426956 env[1213]: time="2025-05-15T10:26:03.426941629Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 15 10:26:03.426956 env[1213]: time="2025-05-15T10:26:03.426954349Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 15 10:26:03.427042 env[1213]: time="2025-05-15T10:26:03.426968429Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 May 15 10:26:03.427042 env[1213]: time="2025-05-15T10:26:03.426978989Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 15 10:26:03.427042 env[1213]: time="2025-05-15T10:26:03.426996389Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" May 15 10:26:03.427042 env[1213]: time="2025-05-15T10:26:03.427028069Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 15 10:26:03.427261 env[1213]: time="2025-05-15T10:26:03.427213789Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" May 15 10:26:03.427870 env[1213]: time="2025-05-15T10:26:03.427269149Z" level=info msg="Connect containerd service" May 15 10:26:03.427870 env[1213]: time="2025-05-15T10:26:03.427297709Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 15 10:26:03.428208 env[1213]: time="2025-05-15T10:26:03.428178989Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.428651989Z" level=info msg="Start subscribing containerd event" May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.428979589Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.429002789Z" level=info msg="Start recovering state" May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.429102629Z" level=info msg="Start event monitor" May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.429023669Z" level=info msg=serving... address=/run/containerd/containerd.sock May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.429131629Z" level=info msg="Start snapshots syncer" May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.429170749Z" level=info msg="Start cni network conf syncer for default" May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.429182709Z" level=info msg="Start streaming server" May 15 10:26:03.430481 env[1213]: time="2025-05-15T10:26:03.430065509Z" level=info msg="containerd successfully booted in 0.064531s" May 15 10:26:03.429249 systemd[1]: Started containerd.service. May 15 10:26:03.573588 systemd-networkd[1036]: eth0: Gained IPv6LL May 15 10:26:03.575261 systemd[1]: Finished systemd-networkd-wait-online.service. May 15 10:26:03.576276 systemd[1]: Reached target network-online.target. May 15 10:26:03.578498 systemd[1]: Starting kubelet.service... May 15 10:26:04.079920 systemd[1]: Started kubelet.service. May 15 10:26:04.546599 kubelet[1256]: E0515 10:26:04.546494 1256 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" May 15 10:26:04.548611 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 15 10:26:04.548747 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 15 10:26:04.799762 sshd_keygen[1211]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 15 10:26:04.817565 systemd[1]: Finished sshd-keygen.service. May 15 10:26:04.819625 systemd[1]: Starting issuegen.service... May 15 10:26:04.824168 systemd[1]: issuegen.service: Deactivated successfully. May 15 10:26:04.824316 systemd[1]: Finished issuegen.service. May 15 10:26:04.826541 systemd[1]: Starting systemd-user-sessions.service... May 15 10:26:04.832294 systemd[1]: Finished systemd-user-sessions.service. May 15 10:26:04.834523 systemd[1]: Started getty@tty1.service. May 15 10:26:04.836555 systemd[1]: Started serial-getty@ttyAMA0.service. May 15 10:26:04.837609 systemd[1]: Reached target getty.target. May 15 10:26:04.838545 systemd[1]: Reached target multi-user.target. May 15 10:26:04.840673 systemd[1]: Starting systemd-update-utmp-runlevel.service... May 15 10:26:04.847005 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 15 10:26:04.847157 systemd[1]: Finished systemd-update-utmp-runlevel.service. May 15 10:26:04.848264 systemd[1]: Startup finished in 559ms (kernel) + 4.089s (initrd) + 4.785s (userspace) = 9.433s. May 15 10:26:08.404953 systemd[1]: Created slice system-sshd.slice. May 15 10:26:08.406022 systemd[1]: Started sshd@0-10.0.0.129:22-10.0.0.1:60964.service. May 15 10:26:08.452418 sshd[1279]: Accepted publickey for core from 10.0.0.1 port 60964 ssh2: RSA SHA256:I/C30/eWBhvgAgcCboY0f9pk+vr1TzGX+qBjeoJjilE May 15 10:26:08.454551 sshd[1279]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 10:26:08.465905 systemd-logind[1204]: New session 1 of user core. May 15 10:26:08.466875 systemd[1]: Created slice user-500.slice. May 15 10:26:08.468062 systemd[1]: Starting user-runtime-dir@500.service... May 15 10:26:08.476545 systemd[1]: Finished user-runtime-dir@500.service. May 15 10:26:08.477987 systemd[1]: Starting user@500.service... May 15 10:26:08.481018 (systemd)[1282]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 15 10:26:08.540644 systemd[1282]: Queued start job for default target default.target. May 15 10:26:08.541132 systemd[1282]: Reached target paths.target. May 15 10:26:08.541164 systemd[1282]: Reached target sockets.target. May 15 10:26:08.541176 systemd[1282]: Reached target timers.target. May 15 10:26:08.541186 systemd[1282]: Reached target basic.target. May 15 10:26:08.541227 systemd[1282]: Reached target default.target. May 15 10:26:08.541254 systemd[1282]: Startup finished in 54ms. May 15 10:26:08.541313 systemd[1]: Started user@500.service. May 15 10:26:08.542277 systemd[1]: Started session-1.scope. May 15 10:26:08.593556 systemd[1]: Started sshd@1-10.0.0.129:22-10.0.0.1:60972.service. May 15 10:26:08.640638 sshd[1291]: Accepted publickey for core from 10.0.0.1 port 60972 ssh2: RSA SHA256:I/C30/eWBhvgAgcCboY0f9pk+vr1TzGX+qBjeoJjilE May 15 10:26:08.642464 sshd[1291]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 10:26:08.646379 systemd-logind[1204]: New session 2 of user core. May 15 10:26:08.647181 systemd[1]: Started session-2.scope. May 15 10:26:08.702288 sshd[1291]: pam_unix(sshd:session): session closed for user core May 15 10:26:08.704849 systemd[1]: sshd@1-10.0.0.129:22-10.0.0.1:60972.service: Deactivated successfully. May 15 10:26:08.705486 systemd[1]: session-2.scope: Deactivated successfully. May 15 10:26:08.706024 systemd-logind[1204]: Session 2 logged out. Waiting for processes to exit. May 15 10:26:08.707116 systemd[1]: Started sshd@2-10.0.0.129:22-10.0.0.1:60978.service. May 15 10:26:08.707758 systemd-logind[1204]: Removed session 2. May 15 10:26:08.748291 sshd[1297]: Accepted publickey for core from 10.0.0.1 port 60978 ssh2: RSA SHA256:I/C30/eWBhvgAgcCboY0f9pk+vr1TzGX+qBjeoJjilE May 15 10:26:08.749535 sshd[1297]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 10:26:08.752772 systemd-logind[1204]: New session 3 of user core. May 15 10:26:08.753602 systemd[1]: Started session-3.scope. May 15 10:26:08.803420 sshd[1297]: pam_unix(sshd:session): session closed for user core May 15 10:26:08.807147 systemd[1]: sshd@2-10.0.0.129:22-10.0.0.1:60978.service: Deactivated successfully. May 15 10:26:08.807741 systemd[1]: session-3.scope: Deactivated successfully. May 15 10:26:08.808231 systemd-logind[1204]: Session 3 logged out. Waiting for processes to exit. May 15 10:26:08.809269 systemd[1]: Started sshd@3-10.0.0.129:22-10.0.0.1:60982.service. May 15 10:26:08.809945 systemd-logind[1204]: Removed session 3. May 15 10:26:08.848964 sshd[1303]: Accepted publickey for core from 10.0.0.1 port 60982 ssh2: RSA SHA256:I/C30/eWBhvgAgcCboY0f9pk+vr1TzGX+qBjeoJjilE May 15 10:26:08.850053 sshd[1303]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 10:26:08.853312 systemd-logind[1204]: New session 4 of user core. May 15 10:26:08.854096 systemd[1]: Started session-4.scope. May 15 10:26:08.906371 sshd[1303]: pam_unix(sshd:session): session closed for user core May 15 10:26:08.910340 systemd[1]: sshd@3-10.0.0.129:22-10.0.0.1:60982.service: Deactivated successfully. May 15 10:26:08.911129 systemd[1]: session-4.scope: Deactivated successfully. May 15 10:26:08.911867 systemd-logind[1204]: Session 4 logged out. Waiting for processes to exit. May 15 10:26:08.913295 systemd[1]: Started sshd@4-10.0.0.129:22-10.0.0.1:60988.service. May 15 10:26:08.914384 systemd-logind[1204]: Removed session 4. May 15 10:26:08.953488 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 60988 ssh2: RSA SHA256:I/C30/eWBhvgAgcCboY0f9pk+vr1TzGX+qBjeoJjilE May 15 10:26:08.954887 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 15 10:26:08.958240 systemd-logind[1204]: New session 5 of user core. May 15 10:26:08.959087 systemd[1]: Started session-5.scope. May 15 10:26:09.017674 sudo[1312]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 15 10:26:09.017899 sudo[1312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) May 15 10:26:09.029081 systemd[1]: Starting coreos-metadata.service... May 15 10:26:09.035296 systemd[1]: coreos-metadata.service: Deactivated successfully. May 15 10:26:09.035476 systemd[1]: Finished coreos-metadata.service. May 15 10:26:09.476035 systemd[1]: Stopped kubelet.service. May 15 10:26:09.478619 systemd[1]: Starting kubelet.service... May 15 10:26:09.499582 systemd[1]: Reloading. May 15 10:26:09.544395 /usr/lib/systemd/system-generators/torcx-generator[1372]: time="2025-05-15T10:26:09Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.100 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.100 /var/lib/torcx/store]" May 15 10:26:09.544473 /usr/lib/systemd/system-generators/torcx-generator[1372]: time="2025-05-15T10:26:09Z" level=info msg="torcx already run" May 15 10:26:09.699190 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 15 10:26:09.699212 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 15 10:26:09.714422 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 15 10:26:09.778905 systemd[1]: Started kubelet.service. May 15 10:26:09.780400 systemd[1]: Stopping kubelet.service... May 15 10:26:09.780797 systemd[1]: kubelet.service: Deactivated successfully. May 15 10:26:09.781055 systemd[1]: Stopped kubelet.service. May 15 10:26:09.782542 systemd[1]: Starting kubelet.service... May 15 10:26:09.871966 systemd[1]: Started kubelet.service. May 15 10:26:09.904132 kubelet[1415]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 15 10:26:09.904132 kubelet[1415]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 15 10:26:09.904132 kubelet[1415]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 15 10:26:09.904482 kubelet[1415]: I0515 10:26:09.904245 1415 server.go:206] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 15 10:26:10.679653 kubelet[1415]: I0515 10:26:10.679613 1415 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" May 15 10:26:10.679653 kubelet[1415]: I0515 10:26:10.679646 1415 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 15 10:26:10.679908 kubelet[1415]: I0515 10:26:10.679891 1415 server.go:929] "Client rotation is on, will bootstrap in background" May 15 10:26:10.739249 kubelet[1415]: I0515 10:26:10.739219 1415 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 15 10:26:10.751856 kubelet[1415]: E0515 10:26:10.751813 1415 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 15 10:26:10.751856 kubelet[1415]: I0515 10:26:10.751848 1415 server.go:1403] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 15 10:26:10.756964 kubelet[1415]: I0515 10:26:10.756937 1415 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 15 10:26:10.761760 kubelet[1415]: I0515 10:26:10.761739 1415 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 15 10:26:10.761909 kubelet[1415]: I0515 10:26:10.761879 1415 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 15 10:26:10.762157 kubelet[1415]: I0515 10:26:10.761912 1415 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.129","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":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} May 15 10:26:10.762299 kubelet[1415]: I0515 10:26:10.762288 1415 topology_manager.go:138] "Creating topology manager with none policy" May 15 10:26:10.762335 kubelet[1415]: I0515 10:26:10.762304 1415 container_manager_linux.go:300] "Creating device plugin manager" May 15 10:26:10.762513 kubelet[1415]: I0515 10:26:10.762500 1415 state_mem.go:36] "Initialized new in-memory state store" May 15 10:26:10.766466 kubelet[1415]: I0515 10:26:10.766438 1415 kubelet.go:408] "Attempting to sync node with API server" May 15 10:26:10.766569 kubelet[1415]: I0515 10:26:10.766556 1415 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 15 10:26:10.766729 kubelet[1415]: I0515 10:26:10.766720 1415 kubelet.go:314] "Adding apiserver pod source" May 15 10:26:10.766789 kubelet[1415]: I0515 10:26:10.766778 1415 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 15 10:26:10.766858 kubelet[1415]: E0515 10:26:10.766826 1415 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:10.766858 kubelet[1415]: E0515 10:26:10.766837 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:10.770494 kubelet[1415]: I0515 10:26:10.770472 1415 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" May 15 10:26:10.772185 kubelet[1415]: I0515 10:26:10.772152 1415 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 15 10:26:10.772915 kubelet[1415]: W0515 10:26:10.772889 1415 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 15 10:26:10.773601 kubelet[1415]: I0515 10:26:10.773536 1415 server.go:1269] "Started kubelet" May 15 10:26:10.773835 kubelet[1415]: I0515 10:26:10.773792 1415 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 15 10:26:10.774102 kubelet[1415]: I0515 10:26:10.773986 1415 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 15 10:26:10.774259 kubelet[1415]: I0515 10:26:10.774237 1415 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 15 10:26:10.778613 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). May 15 10:26:10.778659 kubelet[1415]: I0515 10:26:10.775468 1415 server.go:460] "Adding debug handlers to kubelet server" May 15 10:26:10.778659 kubelet[1415]: I0515 10:26:10.778106 1415 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 15 10:26:10.786802 kubelet[1415]: I0515 10:26:10.786772 1415 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 15 10:26:10.787713 kubelet[1415]: E0515 10:26:10.787696 1415 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 15 10:26:10.788427 kubelet[1415]: E0515 10:26:10.788398 1415 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" May 15 10:26:10.788628 kubelet[1415]: I0515 10:26:10.788613 1415 volume_manager.go:289] "Starting Kubelet Volume Manager" May 15 10:26:10.788811 kubelet[1415]: I0515 10:26:10.788799 1415 desired_state_of_world_populator.go:146] "Desired state populator starts to run" May 15 10:26:10.788958 kubelet[1415]: I0515 10:26:10.788934 1415 reconciler.go:26] "Reconciler: start to sync state" May 15 10:26:10.791809 kubelet[1415]: I0515 10:26:10.791784 1415 factory.go:221] Registration of the containerd container factory successfully May 15 10:26:10.791897 kubelet[1415]: I0515 10:26:10.791818 1415 factory.go:221] Registration of the systemd container factory successfully May 15 10:26:10.792501 kubelet[1415]: I0515 10:26:10.792457 1415 factory.go:219] 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 directory May 15 10:26:10.792818 kubelet[1415]: E0515 10:26:10.792776 1415 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.129\" not found" node="10.0.0.129" May 15 10:26:10.801461 kubelet[1415]: I0515 10:26:10.801436 1415 cpu_manager.go:214] "Starting CPU manager" policy="none" May 15 10:26:10.801461 kubelet[1415]: I0515 10:26:10.801451 1415 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 15 10:26:10.801461 kubelet[1415]: I0515 10:26:10.801469 1415 state_mem.go:36] "Initialized new in-memory state store" May 15 10:26:10.889047 kubelet[1415]: E0515 10:26:10.889011 1415 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" May 15 10:26:10.889181 kubelet[1415]: I0515 10:26:10.889107 1415 policy_none.go:49] "None policy: Start" May 15 10:26:10.889943 kubelet[1415]: I0515 10:26:10.889927 1415 memory_manager.go:170] "Starting memorymanager" policy="None" May 15 10:26:10.890022 kubelet[1415]: I0515 10:26:10.890013 1415 state_mem.go:35] "Initializing new in-memory state store" May 15 10:26:10.909856 systemd[1]: Created slice kubepods.slice. May 15 10:26:10.914091 systemd[1]: Created slice kubepods-burstable.slice. May 15 10:26:10.919811 systemd[1]: Created slice kubepods-besteffort.slice. May 15 10:26:10.928041 kubelet[1415]: I0515 10:26:10.928001 1415 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 15 10:26:10.928283 kubelet[1415]: I0515 10:26:10.928253 1415 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 15 10:26:10.928430 kubelet[1415]: I0515 10:26:10.928387 1415 eviction_manager.go:189] "Eviction manager: starting control loop" May 15 10:26:10.928855 kubelet[1415]: I0515 10:26:10.928421 1415 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 15 10:26:10.929085 kubelet[1415]: I0515 10:26:10.929067 1415 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 15 10:26:10.930049 kubelet[1415]: I0515 10:26:10.929927 1415 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 15 10:26:10.930049 kubelet[1415]: I0515 10:26:10.929954 1415 status_manager.go:217] "Starting to sync pod status with apiserver" May 15 10:26:10.930049 kubelet[1415]: I0515 10:26:10.929968 1415 kubelet.go:2321] "Starting kubelet main sync loop" May 15 10:26:10.930049 kubelet[1415]: E0515 10:26:10.930012 1415 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" May 15 10:26:10.931480 kubelet[1415]: E0515 10:26:10.931459 1415 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.129\" not found" May 15 10:26:11.029528 kubelet[1415]: I0515 10:26:11.029499 1415 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.129" May 15 10:26:11.033522 kubelet[1415]: I0515 10:26:11.033478 1415 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.129" May 15 10:26:11.040537 kubelet[1415]: I0515 10:26:11.040502 1415 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 15 10:26:11.040846 env[1213]: time="2025-05-15T10:26:11.040792829Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 15 10:26:11.041088 kubelet[1415]: I0515 10:26:11.040979 1415 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 15 10:26:11.392555 sudo[1312]: pam_unix(sudo:session): session closed for user root May 15 10:26:11.394421 sshd[1309]: pam_unix(sshd:session): session closed for user core May 15 10:26:11.397055 systemd[1]: sshd@4-10.0.0.129:22-10.0.0.1:60988.service: Deactivated successfully. May 15 10:26:11.397734 systemd[1]: session-5.scope: Deactivated successfully. May 15 10:26:11.398257 systemd-logind[1204]: Session 5 logged out. Waiting for processes to exit. May 15 10:26:11.399053 systemd-logind[1204]: Removed session 5. May 15 10:26:11.682559 kubelet[1415]: I0515 10:26:11.682474 1415 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 15 10:26:11.683002 kubelet[1415]: W0515 10:26:11.682978 1415 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 15 10:26:11.683104 kubelet[1415]: W0515 10:26:11.682978 1415 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 15 10:26:11.683242 kubelet[1415]: W0515 10:26:11.683199 1415 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 15 10:26:11.766939 kubelet[1415]: E0515 10:26:11.766912 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:11.767089 kubelet[1415]: I0515 10:26:11.767004 1415 apiserver.go:52] "Watching apiserver" May 15 10:26:11.774883 systemd[1]: Created slice kubepods-besteffort-podd9b812c0_6ab5_45b7_85b3_44a8b86aef81.slice. May 15 10:26:11.787030 systemd[1]: Created slice kubepods-burstable-poda1ee15af_ff66_4de8_bb56_cf4d47fdfbc8.slice. May 15 10:26:11.790145 kubelet[1415]: I0515 10:26:11.790125 1415 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" May 15 10:26:11.792701 kubelet[1415]: I0515 10:26:11.792674 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8-xtables-lock\") pod \"kube-flannel-ds-d927p\" (UID: \"a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8\") " pod="kube-flannel/kube-flannel-ds-d927p" May 15 10:26:11.792745 kubelet[1415]: I0515 10:26:11.792705 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/d9b812c0-6ab5-45b7-85b3-44a8b86aef81-xtables-lock\") pod \"kube-proxy-d7c64\" (UID: \"d9b812c0-6ab5-45b7-85b3-44a8b86aef81\") " pod="kube-system/kube-proxy-d7c64" May 15 10:26:11.792745 kubelet[1415]: I0515 10:26:11.792722 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8-run\") pod \"kube-flannel-ds-d927p\" (UID: \"a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8\") " pod="kube-flannel/kube-flannel-ds-d927p" May 15 10:26:11.792745 kubelet[1415]: I0515 10:26:11.792737 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8-cni-plugin\") pod \"kube-flannel-ds-d927p\" (UID: \"a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8\") " pod="kube-flannel/kube-flannel-ds-d927p" May 15 10:26:11.792817 kubelet[1415]: I0515 10:26:11.792752 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8-cni\") pod \"kube-flannel-ds-d927p\" (UID: \"a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8\") " pod="kube-flannel/kube-flannel-ds-d927p" May 15 10:26:11.792817 kubelet[1415]: I0515 10:26:11.792768 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8-flannel-cfg\") pod \"kube-flannel-ds-d927p\" (UID: \"a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8\") " pod="kube-flannel/kube-flannel-ds-d927p" May 15 10:26:11.792817 kubelet[1415]: I0515 10:26:11.792783 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7vn2d\" (UniqueName: \"kubernetes.io/projected/a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8-kube-api-access-7vn2d\") pod \"kube-flannel-ds-d927p\" (UID: \"a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8\") " pod="kube-flannel/kube-flannel-ds-d927p" May 15 10:26:11.792817 kubelet[1415]: I0515 10:26:11.792799 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/d9b812c0-6ab5-45b7-85b3-44a8b86aef81-kube-proxy\") pod \"kube-proxy-d7c64\" (UID: \"d9b812c0-6ab5-45b7-85b3-44a8b86aef81\") " pod="kube-system/kube-proxy-d7c64" May 15 10:26:11.792817 kubelet[1415]: I0515 10:26:11.792815 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/d9b812c0-6ab5-45b7-85b3-44a8b86aef81-lib-modules\") pod \"kube-proxy-d7c64\" (UID: \"d9b812c0-6ab5-45b7-85b3-44a8b86aef81\") " pod="kube-system/kube-proxy-d7c64" May 15 10:26:11.792921 kubelet[1415]: I0515 10:26:11.792829 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jsv9s\" (UniqueName: \"kubernetes.io/projected/d9b812c0-6ab5-45b7-85b3-44a8b86aef81-kube-api-access-jsv9s\") pod \"kube-proxy-d7c64\" (UID: \"d9b812c0-6ab5-45b7-85b3-44a8b86aef81\") " pod="kube-system/kube-proxy-d7c64" May 15 10:26:11.901111 kubelet[1415]: I0515 10:26:11.901081 1415 swap_util.go:74] "error creating dir to test if tmpfs noswap is enabled. Assuming not supported" mount path="" error="stat /var/lib/kubelet/plugins/kubernetes.io/empty-dir: no such file or directory" May 15 10:26:12.085811 kubelet[1415]: E0515 10:26:12.085703 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:12.086989 env[1213]: time="2025-05-15T10:26:12.086920429Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-d7c64,Uid:d9b812c0-6ab5-45b7-85b3-44a8b86aef81,Namespace:kube-system,Attempt:0,}" May 15 10:26:12.096200 kubelet[1415]: E0515 10:26:12.096177 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:12.096752 env[1213]: time="2025-05-15T10:26:12.096701309Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-d927p,Uid:a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8,Namespace:kube-flannel,Attempt:0,}" May 15 10:26:12.663028 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2548343725.mount: Deactivated successfully. May 15 10:26:12.668844 env[1213]: time="2025-05-15T10:26:12.668806549Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.669664 env[1213]: time="2025-05-15T10:26:12.669629269Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.671396 env[1213]: time="2025-05-15T10:26:12.671362949Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.675177 env[1213]: time="2025-05-15T10:26:12.675129429Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.677316 env[1213]: time="2025-05-15T10:26:12.677292389Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.678680 env[1213]: time="2025-05-15T10:26:12.678656629Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.680074 env[1213]: time="2025-05-15T10:26:12.680046069Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.680909 env[1213]: time="2025-05-15T10:26:12.680886949Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:12.703621 env[1213]: time="2025-05-15T10:26:12.703537549Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 10:26:12.703621 env[1213]: time="2025-05-15T10:26:12.703585909Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 10:26:12.703621 env[1213]: time="2025-05-15T10:26:12.703596229Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 10:26:12.704026 env[1213]: time="2025-05-15T10:26:12.703987029Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/76b10c83cee2c3cca251070503cb9db665220cb13ed53f775a19cc65145cf2e1 pid=1477 runtime=io.containerd.runc.v2 May 15 10:26:12.704149 env[1213]: time="2025-05-15T10:26:12.704104869Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 10:26:12.704186 env[1213]: time="2025-05-15T10:26:12.704153269Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 10:26:12.704186 env[1213]: time="2025-05-15T10:26:12.704167709Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 10:26:12.704348 env[1213]: time="2025-05-15T10:26:12.704317229Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717 pid=1478 runtime=io.containerd.runc.v2 May 15 10:26:12.719144 systemd[1]: Started cri-containerd-76b10c83cee2c3cca251070503cb9db665220cb13ed53f775a19cc65145cf2e1.scope. May 15 10:26:12.725904 systemd[1]: Started cri-containerd-4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717.scope. May 15 10:26:12.766780 env[1213]: time="2025-05-15T10:26:12.766742189Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-d7c64,Uid:d9b812c0-6ab5-45b7-85b3-44a8b86aef81,Namespace:kube-system,Attempt:0,} returns sandbox id \"76b10c83cee2c3cca251070503cb9db665220cb13ed53f775a19cc65145cf2e1\"" May 15 10:26:12.767180 kubelet[1415]: E0515 10:26:12.767145 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:12.767785 kubelet[1415]: E0515 10:26:12.767755 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:12.768530 env[1213]: time="2025-05-15T10:26:12.768497749Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-d927p,Uid:a1ee15af-ff66-4de8-bb56-cf4d47fdfbc8,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717\"" May 15 10:26:12.769110 env[1213]: time="2025-05-15T10:26:12.769085709Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\"" May 15 10:26:12.769719 kubelet[1415]: E0515 10:26:12.769702 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:13.726297 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount79078336.mount: Deactivated successfully. May 15 10:26:13.767763 kubelet[1415]: E0515 10:26:13.767696 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:14.173432 env[1213]: time="2025-05-15T10:26:14.173174149Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.31.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:14.175357 env[1213]: time="2025-05-15T10:26:14.175313109Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:14.176521 env[1213]: time="2025-05-15T10:26:14.176496309Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.31.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:14.178360 env[1213]: time="2025-05-15T10:26:14.178330149Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:14.178791 env[1213]: time="2025-05-15T10:26:14.178758109Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\" returns image reference \"sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581\"" May 15 10:26:14.180283 env[1213]: time="2025-05-15T10:26:14.180249709Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 15 10:26:14.180967 env[1213]: time="2025-05-15T10:26:14.180931829Z" level=info msg="CreateContainer within sandbox \"76b10c83cee2c3cca251070503cb9db665220cb13ed53f775a19cc65145cf2e1\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 15 10:26:14.190113 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount905873656.mount: Deactivated successfully. May 15 10:26:14.193242 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount437458465.mount: Deactivated successfully. May 15 10:26:14.197024 env[1213]: time="2025-05-15T10:26:14.196990389Z" level=info msg="CreateContainer within sandbox \"76b10c83cee2c3cca251070503cb9db665220cb13ed53f775a19cc65145cf2e1\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"f747c29f4d5e390861b105587b8bfd657e581980719ab04048e8035a2c137ad7\"" May 15 10:26:14.197766 env[1213]: time="2025-05-15T10:26:14.197724389Z" level=info msg="StartContainer for \"f747c29f4d5e390861b105587b8bfd657e581980719ab04048e8035a2c137ad7\"" May 15 10:26:14.214104 systemd[1]: Started cri-containerd-f747c29f4d5e390861b105587b8bfd657e581980719ab04048e8035a2c137ad7.scope. May 15 10:26:14.249164 env[1213]: time="2025-05-15T10:26:14.249123309Z" level=info msg="StartContainer for \"f747c29f4d5e390861b105587b8bfd657e581980719ab04048e8035a2c137ad7\" returns successfully" May 15 10:26:14.768641 kubelet[1415]: E0515 10:26:14.768600 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:14.940707 kubelet[1415]: E0515 10:26:14.940680 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:15.334393 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount337267626.mount: Deactivated successfully. May 15 10:26:15.372094 env[1213]: time="2025-05-15T10:26:15.372044389Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:15.373891 env[1213]: time="2025-05-15T10:26:15.373864429Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:15.375290 env[1213]: time="2025-05-15T10:26:15.375253469Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:15.376568 env[1213]: time="2025-05-15T10:26:15.376533229Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:15.377143 env[1213]: time="2025-05-15T10:26:15.377110109Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 15 10:26:15.379321 env[1213]: time="2025-05-15T10:26:15.379290749Z" level=info msg="CreateContainer within sandbox \"4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 15 10:26:15.387774 env[1213]: time="2025-05-15T10:26:15.387732629Z" level=info msg="CreateContainer within sandbox \"4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"dd0f0d4628e24b4a728f90da390e2cb1c11ed761d109c3cb108b7fc2a000ba2d\"" May 15 10:26:15.388144 env[1213]: time="2025-05-15T10:26:15.388103269Z" level=info msg="StartContainer for \"dd0f0d4628e24b4a728f90da390e2cb1c11ed761d109c3cb108b7fc2a000ba2d\"" May 15 10:26:15.401218 systemd[1]: Started cri-containerd-dd0f0d4628e24b4a728f90da390e2cb1c11ed761d109c3cb108b7fc2a000ba2d.scope. May 15 10:26:15.438402 env[1213]: time="2025-05-15T10:26:15.436796509Z" level=info msg="StartContainer for \"dd0f0d4628e24b4a728f90da390e2cb1c11ed761d109c3cb108b7fc2a000ba2d\" returns successfully" May 15 10:26:15.444438 systemd[1]: cri-containerd-dd0f0d4628e24b4a728f90da390e2cb1c11ed761d109c3cb108b7fc2a000ba2d.scope: Deactivated successfully. May 15 10:26:15.596371 env[1213]: time="2025-05-15T10:26:15.596240589Z" level=info msg="shim disconnected" id=dd0f0d4628e24b4a728f90da390e2cb1c11ed761d109c3cb108b7fc2a000ba2d May 15 10:26:15.596371 env[1213]: time="2025-05-15T10:26:15.596289869Z" level=warning msg="cleaning up after shim disconnected" id=dd0f0d4628e24b4a728f90da390e2cb1c11ed761d109c3cb108b7fc2a000ba2d namespace=k8s.io May 15 10:26:15.596371 env[1213]: time="2025-05-15T10:26:15.596299669Z" level=info msg="cleaning up dead shim" May 15 10:26:15.602855 env[1213]: time="2025-05-15T10:26:15.602810589Z" level=warning msg="cleanup warnings time=\"2025-05-15T10:26:15Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1755 runtime=io.containerd.runc.v2\n" May 15 10:26:15.768735 kubelet[1415]: E0515 10:26:15.768685 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:15.943623 kubelet[1415]: E0515 10:26:15.943517 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:15.944330 kubelet[1415]: E0515 10:26:15.944204 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:15.945172 env[1213]: time="2025-05-15T10:26:15.945127829Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 15 10:26:15.954321 kubelet[1415]: I0515 10:26:15.954266 1415 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-d7c64" podStartSLOduration=3.543493229 podStartE2EDuration="4.954253469s" podCreationTimestamp="2025-05-15 10:26:11 +0000 UTC" firstStartedPulling="2025-05-15 10:26:12.768731829 +0000 UTC m=+2.892783321" lastFinishedPulling="2025-05-15 10:26:14.179492069 +0000 UTC m=+4.303543561" observedRunningTime="2025-05-15 10:26:14.948962509 +0000 UTC m=+5.073014001" watchObservedRunningTime="2025-05-15 10:26:15.954253469 +0000 UTC m=+6.078304921" May 15 10:26:16.769367 kubelet[1415]: E0515 10:26:16.769329 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:17.087127 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2323941491.mount: Deactivated successfully. May 15 10:26:17.756368 env[1213]: time="2025-05-15T10:26:17.756321709Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:17.759000 env[1213]: time="2025-05-15T10:26:17.758958509Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:17.762211 env[1213]: time="2025-05-15T10:26:17.762179909Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:17.763098 env[1213]: time="2025-05-15T10:26:17.763057429Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 15 10:26:17.763891 env[1213]: time="2025-05-15T10:26:17.763861549Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:17.765288 env[1213]: time="2025-05-15T10:26:17.765256349Z" level=info msg="CreateContainer within sandbox \"4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 15 10:26:17.771509 kubelet[1415]: E0515 10:26:17.771453 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:17.773714 env[1213]: time="2025-05-15T10:26:17.773669949Z" level=info msg="CreateContainer within sandbox \"4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044\"" May 15 10:26:17.774245 env[1213]: time="2025-05-15T10:26:17.774196149Z" level=info msg="StartContainer for \"d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044\"" May 15 10:26:17.789309 systemd[1]: Started cri-containerd-d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044.scope. May 15 10:26:17.820397 env[1213]: time="2025-05-15T10:26:17.819420509Z" level=info msg="StartContainer for \"d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044\" returns successfully" May 15 10:26:17.822290 systemd[1]: cri-containerd-d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044.scope: Deactivated successfully. May 15 10:26:17.842216 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044-rootfs.mount: Deactivated successfully. May 15 10:26:17.845319 kubelet[1415]: I0515 10:26:17.844880 1415 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 15 10:26:17.949349 env[1213]: time="2025-05-15T10:26:17.949284509Z" level=info msg="shim disconnected" id=d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044 May 15 10:26:17.949349 env[1213]: time="2025-05-15T10:26:17.949324829Z" level=warning msg="cleaning up after shim disconnected" id=d0c4eedbed92e9f541379bd2bcd061b01d7c8a1dfbd0d3a843536c8e524b9044 namespace=k8s.io May 15 10:26:17.949349 env[1213]: time="2025-05-15T10:26:17.949334189Z" level=info msg="cleaning up dead shim" May 15 10:26:17.950826 kubelet[1415]: E0515 10:26:17.950789 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:17.956756 env[1213]: time="2025-05-15T10:26:17.956718469Z" level=warning msg="cleanup warnings time=\"2025-05-15T10:26:17Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1810 runtime=io.containerd.runc.v2\n" May 15 10:26:18.772392 kubelet[1415]: E0515 10:26:18.772346 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:18.953348 kubelet[1415]: E0515 10:26:18.953319 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:18.955235 env[1213]: time="2025-05-15T10:26:18.955196069Z" level=info msg="CreateContainer within sandbox \"4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 15 10:26:18.969162 env[1213]: time="2025-05-15T10:26:18.969113629Z" level=info msg="CreateContainer within sandbox \"4ed756f89971f117e49d13e89b724f824b63e31208417d30d8a89a7b2696a717\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"7ea6c02bc7efeddf8228430f86a5daf70abeabc25168def556060ec1f3d32ffb\"" May 15 10:26:18.969718 env[1213]: time="2025-05-15T10:26:18.969570749Z" level=info msg="StartContainer for \"7ea6c02bc7efeddf8228430f86a5daf70abeabc25168def556060ec1f3d32ffb\"" May 15 10:26:18.983600 systemd[1]: Started cri-containerd-7ea6c02bc7efeddf8228430f86a5daf70abeabc25168def556060ec1f3d32ffb.scope. May 15 10:26:19.019533 env[1213]: time="2025-05-15T10:26:19.019484589Z" level=info msg="StartContainer for \"7ea6c02bc7efeddf8228430f86a5daf70abeabc25168def556060ec1f3d32ffb\" returns successfully" May 15 10:26:19.773166 kubelet[1415]: E0515 10:26:19.773105 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:19.956671 kubelet[1415]: E0515 10:26:19.956644 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:20.099923 systemd-networkd[1036]: flannel.1: Link UP May 15 10:26:20.099928 systemd-networkd[1036]: flannel.1: Gained carrier May 15 10:26:20.773871 kubelet[1415]: E0515 10:26:20.773825 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:20.959486 kubelet[1415]: E0515 10:26:20.957983 1415 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 15 10:26:21.774289 kubelet[1415]: E0515 10:26:21.774241 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:22.054343 kubelet[1415]: I0515 10:26:22.054207 1415 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-d927p" podStartSLOduration=6.060291469 podStartE2EDuration="11.054188309s" podCreationTimestamp="2025-05-15 10:26:11 +0000 UTC" firstStartedPulling="2025-05-15 10:26:12.770159669 +0000 UTC m=+2.894211121" lastFinishedPulling="2025-05-15 10:26:17.764056469 +0000 UTC m=+7.888107961" observedRunningTime="2025-05-15 10:26:19.966609229 +0000 UTC m=+10.090660721" watchObservedRunningTime="2025-05-15 10:26:22.054188309 +0000 UTC m=+12.178239801" May 15 10:26:22.059249 systemd[1]: Created slice kubepods-besteffort-pod394cb981_8065_4fc2_b110_ee2b3507b61d.slice. May 15 10:26:22.133654 systemd-networkd[1036]: flannel.1: Gained IPv6LL May 15 10:26:22.152878 kubelet[1415]: I0515 10:26:22.152844 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ktzzw\" (UniqueName: \"kubernetes.io/projected/394cb981-8065-4fc2-b110-ee2b3507b61d-kube-api-access-ktzzw\") pod \"nginx-deployment-8587fbcb89-c6rqc\" (UID: \"394cb981-8065-4fc2-b110-ee2b3507b61d\") " pod="default/nginx-deployment-8587fbcb89-c6rqc" May 15 10:26:22.362331 env[1213]: time="2025-05-15T10:26:22.361951709Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-c6rqc,Uid:394cb981-8065-4fc2-b110-ee2b3507b61d,Namespace:default,Attempt:0,}" May 15 10:26:22.394467 systemd-networkd[1036]: cni0: Link UP May 15 10:26:22.394476 systemd-networkd[1036]: cni0: Gained carrier May 15 10:26:22.396005 systemd-networkd[1036]: cni0: Lost carrier May 15 10:26:22.404474 kernel: cni0: port 1(vethd395b627) entered blocking state May 15 10:26:22.404550 kernel: cni0: port 1(vethd395b627) entered disabled state May 15 10:26:22.404573 kernel: device vethd395b627 entered promiscuous mode May 15 10:26:22.405549 kernel: cni0: port 1(vethd395b627) entered blocking state May 15 10:26:22.405607 kernel: cni0: port 1(vethd395b627) entered forwarding state May 15 10:26:22.408193 kernel: cni0: port 1(vethd395b627) entered disabled state May 15 10:26:22.408212 systemd-networkd[1036]: vethd395b627: Link UP May 15 10:26:22.416513 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethd395b627: link becomes ready May 15 10:26:22.416576 kernel: cni0: port 1(vethd395b627) entered blocking state May 15 10:26:22.416596 kernel: cni0: port 1(vethd395b627) entered forwarding state May 15 10:26:22.417221 systemd-networkd[1036]: vethd395b627: Gained carrier May 15 10:26:22.417403 systemd-networkd[1036]: cni0: Gained carrier May 15 10:26:22.419617 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400009e8e8), "name":"cbr0", "type":"bridge"} May 15 10:26:22.419617 env[1213]: delegateAdd: netconf sent to delegate plugin: May 15 10:26:22.435246 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-15T10:26:22.435161669Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 10:26:22.435246 env[1213]: time="2025-05-15T10:26:22.435210549Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 10:26:22.435246 env[1213]: time="2025-05-15T10:26:22.435233029Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 10:26:22.435483 env[1213]: time="2025-05-15T10:26:22.435436149Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/0ce979ab75d4fe0d743291b81b30e1ce3eb1347d0994bfd62facefc03f3bf760 pid=1989 runtime=io.containerd.runc.v2 May 15 10:26:22.447643 systemd[1]: Started cri-containerd-0ce979ab75d4fe0d743291b81b30e1ce3eb1347d0994bfd62facefc03f3bf760.scope. May 15 10:26:22.464736 systemd-resolved[1154]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 15 10:26:22.480563 env[1213]: time="2025-05-15T10:26:22.480525269Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-c6rqc,Uid:394cb981-8065-4fc2-b110-ee2b3507b61d,Namespace:default,Attempt:0,} returns sandbox id \"0ce979ab75d4fe0d743291b81b30e1ce3eb1347d0994bfd62facefc03f3bf760\"" May 15 10:26:22.482013 env[1213]: time="2025-05-15T10:26:22.481988309Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 15 10:26:22.775247 kubelet[1415]: E0515 10:26:22.775198 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:23.775356 kubelet[1415]: E0515 10:26:23.775321 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:23.797560 systemd-networkd[1036]: vethd395b627: Gained IPv6LL May 15 10:26:23.797811 systemd-networkd[1036]: cni0: Gained IPv6LL May 15 10:26:24.776150 kubelet[1415]: E0515 10:26:24.776087 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:25.315922 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4031760711.mount: Deactivated successfully. May 15 10:26:25.777243 kubelet[1415]: E0515 10:26:25.777187 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:26.550835 env[1213]: time="2025-05-15T10:26:26.550782509Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:26.552437 env[1213]: time="2025-05-15T10:26:26.552397949Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:26.554207 env[1213]: time="2025-05-15T10:26:26.554175229Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:26.556480 env[1213]: time="2025-05-15T10:26:26.556447909Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:26.557196 env[1213]: time="2025-05-15T10:26:26.557158429Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 15 10:26:26.559863 env[1213]: time="2025-05-15T10:26:26.559834589Z" level=info msg="CreateContainer within sandbox \"0ce979ab75d4fe0d743291b81b30e1ce3eb1347d0994bfd62facefc03f3bf760\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 15 10:26:26.568799 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2773360129.mount: Deactivated successfully. May 15 10:26:26.570308 env[1213]: time="2025-05-15T10:26:26.570278269Z" level=info msg="CreateContainer within sandbox \"0ce979ab75d4fe0d743291b81b30e1ce3eb1347d0994bfd62facefc03f3bf760\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"971ee12ac3472767ee7eab4fc0e9f88455a47fd87dde9d3171c718f3911e6ebf\"" May 15 10:26:26.570907 env[1213]: time="2025-05-15T10:26:26.570873029Z" level=info msg="StartContainer for \"971ee12ac3472767ee7eab4fc0e9f88455a47fd87dde9d3171c718f3911e6ebf\"" May 15 10:26:26.587979 systemd[1]: Started cri-containerd-971ee12ac3472767ee7eab4fc0e9f88455a47fd87dde9d3171c718f3911e6ebf.scope. May 15 10:26:26.619548 env[1213]: time="2025-05-15T10:26:26.619510309Z" level=info msg="StartContainer for \"971ee12ac3472767ee7eab4fc0e9f88455a47fd87dde9d3171c718f3911e6ebf\" returns successfully" May 15 10:26:26.778740 kubelet[1415]: E0515 10:26:26.778692 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:26.977633 kubelet[1415]: I0515 10:26:26.977579 1415 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-c6rqc" podStartSLOduration=0.900752269 podStartE2EDuration="4.977563629s" podCreationTimestamp="2025-05-15 10:26:22 +0000 UTC" firstStartedPulling="2025-05-15 10:26:22.481787509 +0000 UTC m=+12.605839001" lastFinishedPulling="2025-05-15 10:26:26.558598869 +0000 UTC m=+16.682650361" observedRunningTime="2025-05-15 10:26:26.976564309 +0000 UTC m=+17.100615801" watchObservedRunningTime="2025-05-15 10:26:26.977563629 +0000 UTC m=+17.101615121" May 15 10:26:27.780174 kubelet[1415]: E0515 10:26:27.780127 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:28.780928 kubelet[1415]: E0515 10:26:28.780871 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:28.957606 systemd[1]: Created slice kubepods-besteffort-pod28a4af44_a227_4327_9d83_d1298ace253f.slice. May 15 10:26:28.986669 kubelet[1415]: I0515 10:26:28.986586 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/28a4af44-a227-4327-9d83-d1298ace253f-data\") pod \"nfs-server-provisioner-0\" (UID: \"28a4af44-a227-4327-9d83-d1298ace253f\") " pod="default/nfs-server-provisioner-0" May 15 10:26:28.986669 kubelet[1415]: I0515 10:26:28.986630 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rlk6d\" (UniqueName: \"kubernetes.io/projected/28a4af44-a227-4327-9d83-d1298ace253f-kube-api-access-rlk6d\") pod \"nfs-server-provisioner-0\" (UID: \"28a4af44-a227-4327-9d83-d1298ace253f\") " pod="default/nfs-server-provisioner-0" May 15 10:26:29.260979 env[1213]: time="2025-05-15T10:26:29.260942029Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:28a4af44-a227-4327-9d83-d1298ace253f,Namespace:default,Attempt:0,}" May 15 10:26:29.284865 systemd-networkd[1036]: vethea09a4ca: Link UP May 15 10:26:29.287592 kernel: cni0: port 2(vethea09a4ca) entered blocking state May 15 10:26:29.287663 kernel: cni0: port 2(vethea09a4ca) entered disabled state May 15 10:26:29.287683 kernel: device vethea09a4ca entered promiscuous mode May 15 10:26:29.290623 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 15 10:26:29.290682 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethea09a4ca: link becomes ready May 15 10:26:29.290707 kernel: cni0: port 2(vethea09a4ca) entered blocking state May 15 10:26:29.292028 kernel: cni0: port 2(vethea09a4ca) entered forwarding state May 15 10:26:29.291772 systemd-networkd[1036]: vethea09a4ca: Gained carrier May 15 10:26:29.296434 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000018928), "name":"cbr0", "type":"bridge"} May 15 10:26:29.296434 env[1213]: delegateAdd: netconf sent to delegate plugin: May 15 10:26:29.307415 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-15T10:26:29.307346509Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 10:26:29.307415 env[1213]: time="2025-05-15T10:26:29.307385269Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 10:26:29.307415 env[1213]: time="2025-05-15T10:26:29.307397189Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 10:26:29.307591 env[1213]: time="2025-05-15T10:26:29.307526309Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/6bd8f5d9585b31453b182f35421215c31e1361567a582ec9b0d0fdf4b02786ae pid=2151 runtime=io.containerd.runc.v2 May 15 10:26:29.324417 systemd[1]: Started cri-containerd-6bd8f5d9585b31453b182f35421215c31e1361567a582ec9b0d0fdf4b02786ae.scope. May 15 10:26:29.345169 systemd-resolved[1154]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 15 10:26:29.360417 env[1213]: time="2025-05-15T10:26:29.360368469Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:28a4af44-a227-4327-9d83-d1298ace253f,Namespace:default,Attempt:0,} returns sandbox id \"6bd8f5d9585b31453b182f35421215c31e1361567a582ec9b0d0fdf4b02786ae\"" May 15 10:26:29.361804 env[1213]: time="2025-05-15T10:26:29.361768629Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 15 10:26:29.781589 kubelet[1415]: E0515 10:26:29.781540 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:30.767252 kubelet[1415]: E0515 10:26:30.767202 1415 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:30.781649 kubelet[1415]: E0515 10:26:30.781610 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:31.093539 systemd-networkd[1036]: vethea09a4ca: Gained IPv6LL May 15 10:26:31.436746 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3767414439.mount: Deactivated successfully. May 15 10:26:31.782669 kubelet[1415]: E0515 10:26:31.782620 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:32.783311 kubelet[1415]: E0515 10:26:32.783264 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:33.170941 env[1213]: time="2025-05-15T10:26:33.170715269Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:33.174702 env[1213]: time="2025-05-15T10:26:33.174648509Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:33.176941 env[1213]: time="2025-05-15T10:26:33.176914589Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:33.178837 env[1213]: time="2025-05-15T10:26:33.178808029Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:33.179742 env[1213]: time="2025-05-15T10:26:33.179707349Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 15 10:26:33.182315 env[1213]: time="2025-05-15T10:26:33.182281989Z" level=info msg="CreateContainer within sandbox \"6bd8f5d9585b31453b182f35421215c31e1361567a582ec9b0d0fdf4b02786ae\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 15 10:26:33.193149 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1006339212.mount: Deactivated successfully. May 15 10:26:33.200247 env[1213]: time="2025-05-15T10:26:33.200178629Z" level=info msg="CreateContainer within sandbox \"6bd8f5d9585b31453b182f35421215c31e1361567a582ec9b0d0fdf4b02786ae\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"e9ebe663e1227b2fc17205d77d441b9a3050552815a9f05fd37ed07198a40195\"" May 15 10:26:33.200766 env[1213]: time="2025-05-15T10:26:33.200734469Z" level=info msg="StartContainer for \"e9ebe663e1227b2fc17205d77d441b9a3050552815a9f05fd37ed07198a40195\"" May 15 10:26:33.216328 systemd[1]: Started cri-containerd-e9ebe663e1227b2fc17205d77d441b9a3050552815a9f05fd37ed07198a40195.scope. May 15 10:26:33.265225 env[1213]: time="2025-05-15T10:26:33.265125989Z" level=info msg="StartContainer for \"e9ebe663e1227b2fc17205d77d441b9a3050552815a9f05fd37ed07198a40195\" returns successfully" May 15 10:26:33.784122 kubelet[1415]: E0515 10:26:33.784076 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:34.784507 kubelet[1415]: E0515 10:26:34.784468 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:35.785032 kubelet[1415]: E0515 10:26:35.784981 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:36.785239 kubelet[1415]: E0515 10:26:36.785192 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:37.786365 kubelet[1415]: E0515 10:26:37.786317 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:38.786737 kubelet[1415]: E0515 10:26:38.786692 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:39.787574 kubelet[1415]: E0515 10:26:39.787534 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:40.788514 kubelet[1415]: E0515 10:26:40.788467 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:41.789716 kubelet[1415]: E0515 10:26:41.789681 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:42.790836 kubelet[1415]: E0515 10:26:42.790799 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:43.443342 kubelet[1415]: I0515 10:26:43.443231 1415 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.623635519 podStartE2EDuration="15.443211999s" podCreationTimestamp="2025-05-15 10:26:28 +0000 UTC" firstStartedPulling="2025-05-15 10:26:29.361375029 +0000 UTC m=+19.485426521" lastFinishedPulling="2025-05-15 10:26:33.180951509 +0000 UTC m=+23.305003001" observedRunningTime="2025-05-15 10:26:33.989207309 +0000 UTC m=+24.113258801" watchObservedRunningTime="2025-05-15 10:26:43.443211999 +0000 UTC m=+33.567263491" May 15 10:26:43.447839 systemd[1]: Created slice kubepods-besteffort-pod69bace90_1ff5_42e8_8a26_2b123d1a632e.slice. May 15 10:26:43.467148 kubelet[1415]: I0515 10:26:43.467113 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-449030ce-e37d-4def-8483-bbf579f86250\" (UniqueName: \"kubernetes.io/nfs/69bace90-1ff5-42e8-8a26-2b123d1a632e-pvc-449030ce-e37d-4def-8483-bbf579f86250\") pod \"test-pod-1\" (UID: \"69bace90-1ff5-42e8-8a26-2b123d1a632e\") " pod="default/test-pod-1" May 15 10:26:43.467536 kubelet[1415]: I0515 10:26:43.467486 1415 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9k5v2\" (UniqueName: \"kubernetes.io/projected/69bace90-1ff5-42e8-8a26-2b123d1a632e-kube-api-access-9k5v2\") pod \"test-pod-1\" (UID: \"69bace90-1ff5-42e8-8a26-2b123d1a632e\") " pod="default/test-pod-1" May 15 10:26:43.602432 kernel: FS-Cache: Loaded May 15 10:26:43.631810 kernel: RPC: Registered named UNIX socket transport module. May 15 10:26:43.631900 kernel: RPC: Registered udp transport module. May 15 10:26:43.631946 kernel: RPC: Registered tcp transport module. May 15 10:26:43.631992 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 15 10:26:43.673435 kernel: FS-Cache: Netfs 'nfs' registered for caching May 15 10:26:43.791792 kubelet[1415]: E0515 10:26:43.791755 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:43.805433 kernel: NFS: Registering the id_resolver key type May 15 10:26:43.805526 kernel: Key type id_resolver registered May 15 10:26:43.805547 kernel: Key type id_legacy registered May 15 10:26:43.838205 nfsidmap[2335]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 15 10:26:43.841293 nfsidmap[2338]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 15 10:26:44.050871 env[1213]: time="2025-05-15T10:26:44.050430681Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:69bace90-1ff5-42e8-8a26-2b123d1a632e,Namespace:default,Attempt:0,}" May 15 10:26:44.065344 systemd-networkd[1036]: veth84b79823: Link UP May 15 10:26:44.067739 kernel: cni0: port 3(veth84b79823) entered blocking state May 15 10:26:44.067826 kernel: cni0: port 3(veth84b79823) entered disabled state May 15 10:26:44.067862 kernel: device veth84b79823 entered promiscuous mode May 15 10:26:44.073458 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 15 10:26:44.073530 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth84b79823: link becomes ready May 15 10:26:44.073554 kernel: cni0: port 3(veth84b79823) entered blocking state May 15 10:26:44.074619 kernel: cni0: port 3(veth84b79823) entered forwarding state May 15 10:26:44.074898 systemd-networkd[1036]: veth84b79823: Gained carrier May 15 10:26:44.123758 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} May 15 10:26:44.123758 env[1213]: delegateAdd: netconf sent to delegate plugin: May 15 10:26:44.133092 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-15T10:26:44.133002308Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 10:26:44.133092 env[1213]: time="2025-05-15T10:26:44.133054468Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 10:26:44.133092 env[1213]: time="2025-05-15T10:26:44.133065508Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 10:26:44.133386 env[1213]: time="2025-05-15T10:26:44.133343068Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/0af93491e74c5191848996f7df062631daa8fa8a2cdeb19bcafb5aa85701240c pid=2384 runtime=io.containerd.runc.v2 May 15 10:26:44.146769 systemd[1]: Started cri-containerd-0af93491e74c5191848996f7df062631daa8fa8a2cdeb19bcafb5aa85701240c.scope. May 15 10:26:44.189813 systemd-resolved[1154]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 15 10:26:44.206866 env[1213]: time="2025-05-15T10:26:44.206812847Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:69bace90-1ff5-42e8-8a26-2b123d1a632e,Namespace:default,Attempt:0,} returns sandbox id \"0af93491e74c5191848996f7df062631daa8fa8a2cdeb19bcafb5aa85701240c\"" May 15 10:26:44.208473 env[1213]: time="2025-05-15T10:26:44.208404209Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 15 10:26:44.479534 env[1213]: time="2025-05-15T10:26:44.479485308Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:44.481226 env[1213]: time="2025-05-15T10:26:44.481195549Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:44.484031 env[1213]: time="2025-05-15T10:26:44.483992152Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:44.493231 env[1213]: time="2025-05-15T10:26:44.493188759Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 15 10:26:44.494114 env[1213]: time="2025-05-15T10:26:44.494075000Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 15 10:26:44.496682 env[1213]: time="2025-05-15T10:26:44.496649642Z" level=info msg="CreateContainer within sandbox \"0af93491e74c5191848996f7df062631daa8fa8a2cdeb19bcafb5aa85701240c\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 15 10:26:44.509079 env[1213]: time="2025-05-15T10:26:44.509024732Z" level=info msg="CreateContainer within sandbox \"0af93491e74c5191848996f7df062631daa8fa8a2cdeb19bcafb5aa85701240c\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"f154e601a2593c7f4e6da040a59d96fd443e18afef8b234b01211de4b4882b99\"" May 15 10:26:44.509663 env[1213]: time="2025-05-15T10:26:44.509634012Z" level=info msg="StartContainer for \"f154e601a2593c7f4e6da040a59d96fd443e18afef8b234b01211de4b4882b99\"" May 15 10:26:44.524697 systemd[1]: Started cri-containerd-f154e601a2593c7f4e6da040a59d96fd443e18afef8b234b01211de4b4882b99.scope. May 15 10:26:44.565673 env[1213]: time="2025-05-15T10:26:44.565627698Z" level=info msg="StartContainer for \"f154e601a2593c7f4e6da040a59d96fd443e18afef8b234b01211de4b4882b99\" returns successfully" May 15 10:26:44.792003 kubelet[1415]: E0515 10:26:44.791876 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:45.109586 systemd-networkd[1036]: veth84b79823: Gained IPv6LL May 15 10:26:45.792236 kubelet[1415]: E0515 10:26:45.792177 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:46.792888 kubelet[1415]: E0515 10:26:46.792843 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:47.793508 kubelet[1415]: E0515 10:26:47.793456 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:48.418917 update_engine[1209]: I0515 10:26:48.418833 1209 update_attempter.cc:509] Updating boot flags... May 15 10:26:48.794522 kubelet[1415]: E0515 10:26:48.794473 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:49.795484 kubelet[1415]: E0515 10:26:49.795430 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:50.767196 kubelet[1415]: E0515 10:26:50.767152 1415 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 10:26:50.795983 kubelet[1415]: E0515 10:26:50.795943 1415 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"