Sep 13 00:22:52.707929 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 13 00:22:52.707948 kernel: Linux version 5.15.192-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 Fri Sep 12 23:05:37 -00 2025 Sep 13 00:22:52.707956 kernel: efi: EFI v2.70 by EDK II Sep 13 00:22:52.707962 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Sep 13 00:22:52.707966 kernel: random: crng init done Sep 13 00:22:52.707972 kernel: ACPI: Early table checksum verification disabled Sep 13 00:22:52.707978 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Sep 13 00:22:52.707985 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 13 00:22:52.707991 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.707996 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708002 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708007 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708012 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708018 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708026 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708031 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708037 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 13 00:22:52.708043 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 13 00:22:52.708049 kernel: NUMA: Failed to initialise from firmware Sep 13 00:22:52.708055 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 13 00:22:52.708061 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Sep 13 00:22:52.708067 kernel: Zone ranges: Sep 13 00:22:52.708072 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 13 00:22:52.708079 kernel: DMA32 empty Sep 13 00:22:52.708085 kernel: Normal empty Sep 13 00:22:52.708091 kernel: Movable zone start for each node Sep 13 00:22:52.708096 kernel: Early memory node ranges Sep 13 00:22:52.708102 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Sep 13 00:22:52.708108 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Sep 13 00:22:52.708114 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Sep 13 00:22:52.708120 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Sep 13 00:22:52.708125 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Sep 13 00:22:52.708131 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Sep 13 00:22:52.708137 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Sep 13 00:22:52.708143 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 13 00:22:52.708150 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 13 00:22:52.708156 kernel: psci: probing for conduit method from ACPI. Sep 13 00:22:52.708161 kernel: psci: PSCIv1.1 detected in firmware. Sep 13 00:22:52.708167 kernel: psci: Using standard PSCI v0.2 function IDs Sep 13 00:22:52.708173 kernel: psci: Trusted OS migration not required Sep 13 00:22:52.708181 kernel: psci: SMC Calling Convention v1.1 Sep 13 00:22:52.708199 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 13 00:22:52.708207 kernel: ACPI: SRAT not present Sep 13 00:22:52.708213 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Sep 13 00:22:52.708219 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Sep 13 00:22:52.708225 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 13 00:22:52.708232 kernel: Detected PIPT I-cache on CPU0 Sep 13 00:22:52.708238 kernel: CPU features: detected: GIC system register CPU interface Sep 13 00:22:52.708244 kernel: CPU features: detected: Hardware dirty bit management Sep 13 00:22:52.708250 kernel: CPU features: detected: Spectre-v4 Sep 13 00:22:52.708256 kernel: CPU features: detected: Spectre-BHB Sep 13 00:22:52.708263 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 13 00:22:52.708270 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 13 00:22:52.708276 kernel: CPU features: detected: ARM erratum 1418040 Sep 13 00:22:52.708282 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 13 00:22:52.708288 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Sep 13 00:22:52.708294 kernel: Policy zone: DMA Sep 13 00:22:52.708304 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=563df7b8a9b19b8c496587ae06f3c3ec1604a5105c3a3f313c9ccaa21d8055ca Sep 13 00:22:52.708311 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 13 00:22:52.708317 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 13 00:22:52.708324 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 13 00:22:52.708330 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 13 00:22:52.708338 kernel: Memory: 2457340K/2572288K available (9792K kernel code, 2094K rwdata, 7592K rodata, 36416K init, 777K bss, 114948K reserved, 0K cma-reserved) Sep 13 00:22:52.708344 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 13 00:22:52.708350 kernel: trace event string verifier disabled Sep 13 00:22:52.708356 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 13 00:22:52.708362 kernel: rcu: RCU event tracing is enabled. Sep 13 00:22:52.708369 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 13 00:22:52.708375 kernel: Trampoline variant of Tasks RCU enabled. Sep 13 00:22:52.708381 kernel: Tracing variant of Tasks RCU enabled. Sep 13 00:22:52.708387 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 13 00:22:52.708393 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 13 00:22:52.708399 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 13 00:22:52.708406 kernel: GICv3: 256 SPIs implemented Sep 13 00:22:52.708412 kernel: GICv3: 0 Extended SPIs implemented Sep 13 00:22:52.708418 kernel: GICv3: Distributor has no Range Selector support Sep 13 00:22:52.708424 kernel: Root IRQ handler: gic_handle_irq Sep 13 00:22:52.708434 kernel: GICv3: 16 PPIs implemented Sep 13 00:22:52.708440 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 13 00:22:52.708446 kernel: ACPI: SRAT not present Sep 13 00:22:52.708452 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 13 00:22:52.708458 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Sep 13 00:22:52.708465 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Sep 13 00:22:52.708471 kernel: GICv3: using LPI property table @0x00000000400d0000 Sep 13 00:22:52.708477 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Sep 13 00:22:52.708485 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 13 00:22:52.708491 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 13 00:22:52.708497 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 13 00:22:52.708503 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 13 00:22:52.708537 kernel: arm-pv: using stolen time PV Sep 13 00:22:52.708544 kernel: Console: colour dummy device 80x25 Sep 13 00:22:52.708550 kernel: ACPI: Core revision 20210730 Sep 13 00:22:52.708557 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 13 00:22:52.708563 kernel: pid_max: default: 32768 minimum: 301 Sep 13 00:22:52.708570 kernel: LSM: Security Framework initializing Sep 13 00:22:52.708578 kernel: SELinux: Initializing. Sep 13 00:22:52.708584 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 13 00:22:52.708590 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 13 00:22:52.708596 kernel: rcu: Hierarchical SRCU implementation. Sep 13 00:22:52.708611 kernel: Platform MSI: ITS@0x8080000 domain created Sep 13 00:22:52.708617 kernel: PCI/MSI: ITS@0x8080000 domain created Sep 13 00:22:52.708755 kernel: Remapping and enabling EFI services. Sep 13 00:22:52.708762 kernel: smp: Bringing up secondary CPUs ... Sep 13 00:22:52.708768 kernel: Detected PIPT I-cache on CPU1 Sep 13 00:22:52.708777 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 13 00:22:52.708784 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Sep 13 00:22:52.708791 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 13 00:22:52.708797 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 13 00:22:52.708803 kernel: Detected PIPT I-cache on CPU2 Sep 13 00:22:52.708809 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 13 00:22:52.708816 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Sep 13 00:22:52.708822 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 13 00:22:52.708828 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 13 00:22:52.708834 kernel: Detected PIPT I-cache on CPU3 Sep 13 00:22:52.708841 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 13 00:22:52.708848 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Sep 13 00:22:52.708854 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 13 00:22:52.708860 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 13 00:22:52.708870 kernel: smp: Brought up 1 node, 4 CPUs Sep 13 00:22:52.708878 kernel: SMP: Total of 4 processors activated. Sep 13 00:22:52.708885 kernel: CPU features: detected: 32-bit EL0 Support Sep 13 00:22:52.708891 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 13 00:22:52.708898 kernel: CPU features: detected: Common not Private translations Sep 13 00:22:52.708904 kernel: CPU features: detected: CRC32 instructions Sep 13 00:22:52.708911 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 13 00:22:52.708917 kernel: CPU features: detected: LSE atomic instructions Sep 13 00:22:52.708925 kernel: CPU features: detected: Privileged Access Never Sep 13 00:22:52.708932 kernel: CPU features: detected: RAS Extension Support Sep 13 00:22:52.708938 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 13 00:22:52.708945 kernel: CPU: All CPU(s) started at EL1 Sep 13 00:22:52.708951 kernel: alternatives: patching kernel code Sep 13 00:22:52.708959 kernel: devtmpfs: initialized Sep 13 00:22:52.708965 kernel: KASLR enabled Sep 13 00:22:52.708972 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 13 00:22:52.708979 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 13 00:22:52.708985 kernel: pinctrl core: initialized pinctrl subsystem Sep 13 00:22:52.708992 kernel: SMBIOS 3.0.0 present. Sep 13 00:22:52.708998 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Sep 13 00:22:52.709005 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 13 00:22:52.709011 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 13 00:22:52.709019 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 13 00:22:52.709026 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 13 00:22:52.709032 kernel: audit: initializing netlink subsys (disabled) Sep 13 00:22:52.709039 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Sep 13 00:22:52.709046 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 13 00:22:52.709052 kernel: cpuidle: using governor menu Sep 13 00:22:52.709059 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 13 00:22:52.709065 kernel: ASID allocator initialised with 32768 entries Sep 13 00:22:52.709072 kernel: ACPI: bus type PCI registered Sep 13 00:22:52.709080 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 13 00:22:52.709086 kernel: Serial: AMBA PL011 UART driver Sep 13 00:22:52.709093 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Sep 13 00:22:52.709099 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Sep 13 00:22:52.709106 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Sep 13 00:22:52.709113 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Sep 13 00:22:52.709119 kernel: cryptd: max_cpu_qlen set to 1000 Sep 13 00:22:52.709126 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 13 00:22:52.709133 kernel: ACPI: Added _OSI(Module Device) Sep 13 00:22:52.709140 kernel: ACPI: Added _OSI(Processor Device) Sep 13 00:22:52.709147 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 13 00:22:52.709153 kernel: ACPI: Added _OSI(Linux-Dell-Video) Sep 13 00:22:52.709160 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Sep 13 00:22:52.709166 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Sep 13 00:22:52.709173 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 13 00:22:52.709179 kernel: ACPI: Interpreter enabled Sep 13 00:22:52.709195 kernel: ACPI: Using GIC for interrupt routing Sep 13 00:22:52.709202 kernel: ACPI: MCFG table detected, 1 entries Sep 13 00:22:52.709210 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 13 00:22:52.709216 kernel: printk: console [ttyAMA0] enabled Sep 13 00:22:52.709223 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 13 00:22:52.709345 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 13 00:22:52.709418 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 13 00:22:52.709476 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 13 00:22:52.709564 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 13 00:22:52.709631 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 13 00:22:52.710013 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 13 00:22:52.710021 kernel: PCI host bridge to bus 0000:00 Sep 13 00:22:52.710110 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 13 00:22:52.710166 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 13 00:22:52.710413 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 13 00:22:52.710571 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 13 00:22:52.710758 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Sep 13 00:22:52.710883 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Sep 13 00:22:52.711028 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Sep 13 00:22:52.711138 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Sep 13 00:22:52.711308 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Sep 13 00:22:52.711592 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Sep 13 00:22:52.711741 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Sep 13 00:22:52.711919 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Sep 13 00:22:52.711984 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 13 00:22:52.712831 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 13 00:22:52.712948 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 13 00:22:52.712959 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 13 00:22:52.712967 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 13 00:22:52.712984 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 13 00:22:52.712998 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 13 00:22:52.713005 kernel: iommu: Default domain type: Translated Sep 13 00:22:52.713012 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 13 00:22:52.713018 kernel: vgaarb: loaded Sep 13 00:22:52.713025 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 13 00:22:52.713032 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 13 00:22:52.713039 kernel: PTP clock support registered Sep 13 00:22:52.713045 kernel: Registered efivars operations Sep 13 00:22:52.713052 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 13 00:22:52.713059 kernel: VFS: Disk quotas dquot_6.6.0 Sep 13 00:22:52.713067 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 13 00:22:52.713074 kernel: pnp: PnP ACPI init Sep 13 00:22:52.713143 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 13 00:22:52.713153 kernel: pnp: PnP ACPI: found 1 devices Sep 13 00:22:52.713160 kernel: NET: Registered PF_INET protocol family Sep 13 00:22:52.713166 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 13 00:22:52.713173 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 13 00:22:52.713180 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 13 00:22:52.713201 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 13 00:22:52.713208 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Sep 13 00:22:52.713215 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 13 00:22:52.713222 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 13 00:22:52.713229 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 13 00:22:52.713236 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 13 00:22:52.713242 kernel: PCI: CLS 0 bytes, default 64 Sep 13 00:22:52.713249 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Sep 13 00:22:52.713255 kernel: kvm [1]: HYP mode not available Sep 13 00:22:52.713264 kernel: Initialise system trusted keyrings Sep 13 00:22:52.713270 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 13 00:22:52.713277 kernel: Key type asymmetric registered Sep 13 00:22:52.713283 kernel: Asymmetric key parser 'x509' registered Sep 13 00:22:52.713290 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 13 00:22:52.713297 kernel: io scheduler mq-deadline registered Sep 13 00:22:52.713303 kernel: io scheduler kyber registered Sep 13 00:22:52.713310 kernel: io scheduler bfq registered Sep 13 00:22:52.713316 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 13 00:22:52.713324 kernel: ACPI: button: Power Button [PWRB] Sep 13 00:22:52.713331 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 13 00:22:52.713400 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 13 00:22:52.713409 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 13 00:22:52.713416 kernel: thunder_xcv, ver 1.0 Sep 13 00:22:52.713423 kernel: thunder_bgx, ver 1.0 Sep 13 00:22:52.713429 kernel: nicpf, ver 1.0 Sep 13 00:22:52.713435 kernel: nicvf, ver 1.0 Sep 13 00:22:52.713518 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 13 00:22:52.713583 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-13T00:22:52 UTC (1757722972) Sep 13 00:22:52.713592 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 13 00:22:52.713599 kernel: NET: Registered PF_INET6 protocol family Sep 13 00:22:52.713605 kernel: Segment Routing with IPv6 Sep 13 00:22:52.713612 kernel: In-situ OAM (IOAM) with IPv6 Sep 13 00:22:52.713619 kernel: NET: Registered PF_PACKET protocol family Sep 13 00:22:52.713625 kernel: Key type dns_resolver registered Sep 13 00:22:52.713632 kernel: registered taskstats version 1 Sep 13 00:22:52.713640 kernel: Loading compiled-in X.509 certificates Sep 13 00:22:52.713647 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.192-flatcar: 47ac98e9306f36eebe4291d409359a5a5d0c2b9c' Sep 13 00:22:52.713653 kernel: Key type .fscrypt registered Sep 13 00:22:52.713660 kernel: Key type fscrypt-provisioning registered Sep 13 00:22:52.713666 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 13 00:22:52.713673 kernel: ima: Allocated hash algorithm: sha1 Sep 13 00:22:52.713747 kernel: ima: No architecture policies found Sep 13 00:22:52.713754 kernel: clk: Disabling unused clocks Sep 13 00:22:52.713761 kernel: Freeing unused kernel memory: 36416K Sep 13 00:22:52.713770 kernel: Run /init as init process Sep 13 00:22:52.713777 kernel: with arguments: Sep 13 00:22:52.713783 kernel: /init Sep 13 00:22:52.713790 kernel: with environment: Sep 13 00:22:52.713796 kernel: HOME=/ Sep 13 00:22:52.713802 kernel: TERM=linux Sep 13 00:22:52.713809 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 13 00:22:52.713818 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) Sep 13 00:22:52.713828 systemd[1]: Detected virtualization kvm. Sep 13 00:22:52.713835 systemd[1]: Detected architecture arm64. Sep 13 00:22:52.713842 systemd[1]: Running in initrd. Sep 13 00:22:52.713849 systemd[1]: No hostname configured, using default hostname. Sep 13 00:22:52.713856 systemd[1]: Hostname set to . Sep 13 00:22:52.713863 systemd[1]: Initializing machine ID from VM UUID. Sep 13 00:22:52.713871 systemd[1]: Queued start job for default target initrd.target. Sep 13 00:22:52.713877 systemd[1]: Started systemd-ask-password-console.path. Sep 13 00:22:52.713886 systemd[1]: Reached target cryptsetup.target. Sep 13 00:22:52.713893 systemd[1]: Reached target paths.target. Sep 13 00:22:52.713900 systemd[1]: Reached target slices.target. Sep 13 00:22:52.713907 systemd[1]: Reached target swap.target. Sep 13 00:22:52.713913 systemd[1]: Reached target timers.target. Sep 13 00:22:52.713921 systemd[1]: Listening on iscsid.socket. Sep 13 00:22:52.713928 systemd[1]: Listening on iscsiuio.socket. Sep 13 00:22:52.713937 systemd[1]: Listening on systemd-journald-audit.socket. Sep 13 00:22:52.713944 systemd[1]: Listening on systemd-journald-dev-log.socket. Sep 13 00:22:52.713951 systemd[1]: Listening on systemd-journald.socket. Sep 13 00:22:52.713958 systemd[1]: Listening on systemd-networkd.socket. Sep 13 00:22:52.713965 systemd[1]: Listening on systemd-udevd-control.socket. Sep 13 00:22:52.713972 systemd[1]: Listening on systemd-udevd-kernel.socket. Sep 13 00:22:52.713979 systemd[1]: Reached target sockets.target. Sep 13 00:22:52.713986 systemd[1]: Starting kmod-static-nodes.service... Sep 13 00:22:52.713993 systemd[1]: Finished network-cleanup.service. Sep 13 00:22:52.714001 systemd[1]: Starting systemd-fsck-usr.service... Sep 13 00:22:52.715226 systemd[1]: Starting systemd-journald.service... Sep 13 00:22:52.715240 systemd[1]: Starting systemd-modules-load.service... Sep 13 00:22:52.715248 systemd[1]: Starting systemd-resolved.service... Sep 13 00:22:52.715255 systemd[1]: Starting systemd-vconsole-setup.service... Sep 13 00:22:52.715263 systemd[1]: Finished kmod-static-nodes.service. Sep 13 00:22:52.715270 systemd[1]: Finished systemd-fsck-usr.service. Sep 13 00:22:52.715277 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Sep 13 00:22:52.715284 systemd[1]: Finished systemd-vconsole-setup.service. Sep 13 00:22:52.715298 kernel: audit: type=1130 audit(1757722972.707:2): 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' Sep 13 00:22:52.715306 systemd[1]: Starting dracut-cmdline-ask.service... Sep 13 00:22:52.715313 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Sep 13 00:22:52.715323 systemd-journald[290]: Journal started Sep 13 00:22:52.715378 systemd-journald[290]: Runtime Journal (/run/log/journal/415fc89bdcb540b8a0d992aba0d4c34e) is 6.0M, max 48.7M, 42.6M free. Sep 13 00:22:52.707000 audit[1]: SERVICE_START 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' Sep 13 00:22:52.712960 systemd-modules-load[291]: Inserted module 'overlay' Sep 13 00:22:52.718828 kernel: audit: type=1130 audit(1757722972.715:3): 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' Sep 13 00:22:52.718844 systemd[1]: Started systemd-journald.service. Sep 13 00:22:52.715000 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' Sep 13 00:22:52.721212 kernel: audit: type=1130 audit(1757722972.720:4): 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' Sep 13 00:22:52.720000 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' Sep 13 00:22:52.729328 systemd[1]: Finished dracut-cmdline-ask.service. Sep 13 00:22:52.729000 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' Sep 13 00:22:52.730814 systemd[1]: Starting dracut-cmdline.service... Sep 13 00:22:52.733779 kernel: audit: type=1130 audit(1757722972.729:5): 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' Sep 13 00:22:52.735878 systemd-resolved[292]: Positive Trust Anchors: Sep 13 00:22:52.737003 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 13 00:22:52.735892 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 13 00:22:52.735920 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 Sep 13 00:22:52.740237 systemd-resolved[292]: Defaulting to hostname 'linux'. Sep 13 00:22:52.746224 kernel: Bridge firewalling registered Sep 13 00:22:52.746243 kernel: audit: type=1130 audit(1757722972.742:6): 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' Sep 13 00:22:52.742000 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' Sep 13 00:22:52.746287 dracut-cmdline[309]: dracut-dracut-053 Sep 13 00:22:52.741057 systemd[1]: Started systemd-resolved.service. Sep 13 00:22:52.743260 systemd[1]: Reached target nss-lookup.target. Sep 13 00:22:52.744272 systemd-modules-load[291]: Inserted module 'br_netfilter' Sep 13 00:22:52.749447 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=563df7b8a9b19b8c496587ae06f3c3ec1604a5105c3a3f313c9ccaa21d8055ca Sep 13 00:22:52.756471 kernel: SCSI subsystem initialized Sep 13 00:22:52.764232 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 13 00:22:52.764271 kernel: device-mapper: uevent: version 1.0.3 Sep 13 00:22:52.764287 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Sep 13 00:22:52.766478 systemd-modules-load[291]: Inserted module 'dm_multipath' Sep 13 00:22:52.768000 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' Sep 13 00:22:52.767828 systemd[1]: Finished systemd-modules-load.service. Sep 13 00:22:52.769370 systemd[1]: Starting systemd-sysctl.service... Sep 13 00:22:52.771679 kernel: audit: type=1130 audit(1757722972.768:7): 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' Sep 13 00:22:52.776901 systemd[1]: Finished systemd-sysctl.service. Sep 13 00:22:52.777000 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' Sep 13 00:22:52.780212 kernel: audit: type=1130 audit(1757722972.777:8): 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' Sep 13 00:22:52.808222 kernel: Loading iSCSI transport class v2.0-870. Sep 13 00:22:52.824219 kernel: iscsi: registered transport (tcp) Sep 13 00:22:52.838223 kernel: iscsi: registered transport (qla4xxx) Sep 13 00:22:52.838261 kernel: QLogic iSCSI HBA Driver Sep 13 00:22:52.871341 systemd[1]: Finished dracut-cmdline.service. Sep 13 00:22:52.871000 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' Sep 13 00:22:52.872780 systemd[1]: Starting dracut-pre-udev.service... Sep 13 00:22:52.875439 kernel: audit: type=1130 audit(1757722972.871:9): 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' Sep 13 00:22:52.915295 kernel: raid6: neonx8 gen() 13727 MB/s Sep 13 00:22:52.932227 kernel: raid6: neonx8 xor() 10790 MB/s Sep 13 00:22:52.949222 kernel: raid6: neonx4 gen() 13504 MB/s Sep 13 00:22:52.966222 kernel: raid6: neonx4 xor() 11075 MB/s Sep 13 00:22:52.983215 kernel: raid6: neonx2 gen() 12940 MB/s Sep 13 00:22:53.000222 kernel: raid6: neonx2 xor() 10283 MB/s Sep 13 00:22:53.017225 kernel: raid6: neonx1 gen() 10574 MB/s Sep 13 00:22:53.034231 kernel: raid6: neonx1 xor() 8773 MB/s Sep 13 00:22:53.051227 kernel: raid6: int64x8 gen() 6266 MB/s Sep 13 00:22:53.068235 kernel: raid6: int64x8 xor() 3539 MB/s Sep 13 00:22:53.085234 kernel: raid6: int64x4 gen() 7224 MB/s Sep 13 00:22:53.102221 kernel: raid6: int64x4 xor() 3847 MB/s Sep 13 00:22:53.119221 kernel: raid6: int64x2 gen() 6149 MB/s Sep 13 00:22:53.136222 kernel: raid6: int64x2 xor() 3318 MB/s Sep 13 00:22:53.153218 kernel: raid6: int64x1 gen() 5040 MB/s Sep 13 00:22:53.170471 kernel: raid6: int64x1 xor() 2645 MB/s Sep 13 00:22:53.170518 kernel: raid6: using algorithm neonx8 gen() 13727 MB/s Sep 13 00:22:53.170528 kernel: raid6: .... xor() 10790 MB/s, rmw enabled Sep 13 00:22:53.170537 kernel: raid6: using neon recovery algorithm Sep 13 00:22:53.181213 kernel: xor: measuring software checksum speed Sep 13 00:22:53.181242 kernel: 8regs : 17213 MB/sec Sep 13 00:22:53.182213 kernel: 32regs : 19557 MB/sec Sep 13 00:22:53.182235 kernel: arm64_neon : 27561 MB/sec Sep 13 00:22:53.182244 kernel: xor: using function: arm64_neon (27561 MB/sec) Sep 13 00:22:53.234258 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Sep 13 00:22:53.249000 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' Sep 13 00:22:53.249498 systemd[1]: Finished dracut-pre-udev.service. Sep 13 00:22:53.255699 kernel: audit: type=1130 audit(1757722973.249:10): 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' Sep 13 00:22:53.253000 audit: BPF prog-id=7 op=LOAD Sep 13 00:22:53.255000 audit: BPF prog-id=8 op=LOAD Sep 13 00:22:53.256057 systemd[1]: Starting systemd-udevd.service... Sep 13 00:22:53.270137 systemd-udevd[493]: Using default interface naming scheme 'v252'. Sep 13 00:22:53.273608 systemd[1]: Started systemd-udevd.service. Sep 13 00:22:53.280000 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' Sep 13 00:22:53.281183 systemd[1]: Starting dracut-pre-trigger.service... Sep 13 00:22:53.292250 dracut-pre-trigger[513]: rd.md=0: removing MD RAID activation Sep 13 00:22:53.324000 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' Sep 13 00:22:53.324228 systemd[1]: Finished dracut-pre-trigger.service. Sep 13 00:22:53.326979 systemd[1]: Starting systemd-udev-trigger.service... Sep 13 00:22:53.360632 systemd[1]: Finished systemd-udev-trigger.service. Sep 13 00:22:53.362000 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' Sep 13 00:22:53.395579 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 13 00:22:53.401549 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 13 00:22:53.401564 kernel: GPT:9289727 != 19775487 Sep 13 00:22:53.401573 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 13 00:22:53.401581 kernel: GPT:9289727 != 19775487 Sep 13 00:22:53.401589 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 13 00:22:53.401597 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 13 00:22:53.429207 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (555) Sep 13 00:22:53.430693 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Sep 13 00:22:53.431605 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Sep 13 00:22:53.439568 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Sep 13 00:22:53.442688 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Sep 13 00:22:53.445992 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Sep 13 00:22:53.447626 systemd[1]: Starting disk-uuid.service... Sep 13 00:22:53.453541 disk-uuid[568]: Primary Header is updated. Sep 13 00:22:53.453541 disk-uuid[568]: Secondary Entries is updated. Sep 13 00:22:53.453541 disk-uuid[568]: Secondary Header is updated. Sep 13 00:22:53.457581 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 13 00:22:53.460268 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 13 00:22:53.464223 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 13 00:22:54.465815 disk-uuid[569]: The operation has completed successfully. Sep 13 00:22:54.466752 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 13 00:22:54.489952 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 13 00:22:54.490039 systemd[1]: Finished disk-uuid.service. Sep 13 00:22:54.490000 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' Sep 13 00:22:54.490000 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' Sep 13 00:22:54.494475 systemd[1]: Starting verity-setup.service... Sep 13 00:22:54.509218 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 13 00:22:54.531313 systemd[1]: Found device dev-mapper-usr.device. Sep 13 00:22:54.533570 systemd[1]: Mounting sysusr-usr.mount... Sep 13 00:22:54.535182 systemd[1]: Finished verity-setup.service. Sep 13 00:22:54.535000 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' Sep 13 00:22:54.578209 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Sep 13 00:22:54.578514 systemd[1]: Mounted sysusr-usr.mount. Sep 13 00:22:54.579680 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Sep 13 00:22:54.580307 systemd[1]: Starting ignition-setup.service... Sep 13 00:22:54.581776 systemd[1]: Starting parse-ip-for-networkd.service... Sep 13 00:22:54.589640 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 13 00:22:54.589671 kernel: BTRFS info (device vda6): using free space tree Sep 13 00:22:54.589681 kernel: BTRFS info (device vda6): has skinny extents Sep 13 00:22:54.598391 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 13 00:22:54.605543 systemd[1]: Finished ignition-setup.service. Sep 13 00:22:54.605000 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' Sep 13 00:22:54.606843 systemd[1]: Starting ignition-fetch-offline.service... Sep 13 00:22:54.676323 systemd[1]: Finished parse-ip-for-networkd.service. Sep 13 00:22:54.677000 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' Sep 13 00:22:54.677000 audit: BPF prog-id=9 op=LOAD Sep 13 00:22:54.678519 systemd[1]: Starting systemd-networkd.service... Sep 13 00:22:54.689456 ignition[661]: Ignition 2.14.0 Sep 13 00:22:54.689466 ignition[661]: Stage: fetch-offline Sep 13 00:22:54.689548 ignition[661]: no configs at "/usr/lib/ignition/base.d" Sep 13 00:22:54.689570 ignition[661]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 13 00:22:54.689718 ignition[661]: parsed url from cmdline: "" Sep 13 00:22:54.689722 ignition[661]: no config URL provided Sep 13 00:22:54.689727 ignition[661]: reading system config file "/usr/lib/ignition/user.ign" Sep 13 00:22:54.689734 ignition[661]: no config at "/usr/lib/ignition/user.ign" Sep 13 00:22:54.689752 ignition[661]: op(1): [started] loading QEMU firmware config module Sep 13 00:22:54.700000 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' Sep 13 00:22:54.697804 systemd-networkd[747]: lo: Link UP Sep 13 00:22:54.689757 ignition[661]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 13 00:22:54.697807 systemd-networkd[747]: lo: Gained carrier Sep 13 00:22:54.698171 systemd-networkd[747]: Enumeration completed Sep 13 00:22:54.703316 ignition[661]: op(1): [finished] loading QEMU firmware config module Sep 13 00:22:54.698275 systemd[1]: Started systemd-networkd.service. Sep 13 00:22:54.698363 systemd-networkd[747]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 13 00:22:54.699317 systemd-networkd[747]: eth0: Link UP Sep 13 00:22:54.699320 systemd-networkd[747]: eth0: Gained carrier Sep 13 00:22:54.700742 systemd[1]: Reached target network.target. Sep 13 00:22:54.703769 systemd[1]: Starting iscsiuio.service... Sep 13 00:22:54.711000 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' Sep 13 00:22:54.710615 systemd[1]: Started iscsiuio.service. Sep 13 00:22:54.712915 systemd[1]: Starting iscsid.service... Sep 13 00:22:54.716663 iscsid[753]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Sep 13 00:22:54.716663 iscsid[753]: 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]. Sep 13 00:22:54.716663 iscsid[753]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Sep 13 00:22:54.716663 iscsid[753]: If using hardware iscsi like qla4xxx this message can be ignored. Sep 13 00:22:54.716663 iscsid[753]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Sep 13 00:22:54.716663 iscsid[753]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Sep 13 00:22:54.722000 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' Sep 13 00:22:54.723871 ignition[661]: parsing config with SHA512: 40cc7b721a15d07426aa4a8e5928d886c672e5731a690b0fa55f5766b16409d030bdfdb4872c8d13db9f87dccb73dadabfc4430255d7cb39c5f96a2ac858bba7 Sep 13 00:22:54.719205 systemd[1]: Started iscsid.service. Sep 13 00:22:54.724204 systemd[1]: Starting dracut-initqueue.service... Sep 13 00:22:54.726597 systemd-networkd[747]: eth0: DHCPv4 address 10.0.0.118/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 13 00:22:54.735173 unknown[661]: fetched base config from "system" Sep 13 00:22:54.735183 unknown[661]: fetched user config from "qemu" Sep 13 00:22:54.735520 ignition[661]: fetch-offline: fetch-offline passed Sep 13 00:22:54.737000 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' Sep 13 00:22:54.736631 systemd[1]: Finished ignition-fetch-offline.service. Sep 13 00:22:54.738000 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' Sep 13 00:22:54.735590 ignition[661]: Ignition finished successfully Sep 13 00:22:54.737573 systemd[1]: Finished dracut-initqueue.service. Sep 13 00:22:54.738559 systemd[1]: Reached target remote-fs-pre.target. Sep 13 00:22:54.739606 systemd[1]: Reached target remote-cryptsetup.target. Sep 13 00:22:54.740732 systemd[1]: Reached target remote-fs.target. Sep 13 00:22:54.742591 systemd[1]: Starting dracut-pre-mount.service... Sep 13 00:22:54.743539 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 13 00:22:54.744162 systemd[1]: Starting ignition-kargs.service... Sep 13 00:22:54.751000 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' Sep 13 00:22:54.751117 systemd[1]: Finished dracut-pre-mount.service. Sep 13 00:22:54.753916 ignition[764]: Ignition 2.14.0 Sep 13 00:22:54.753925 ignition[764]: Stage: kargs Sep 13 00:22:54.754012 ignition[764]: no configs at "/usr/lib/ignition/base.d" Sep 13 00:22:54.756000 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' Sep 13 00:22:54.756072 systemd[1]: Finished ignition-kargs.service. Sep 13 00:22:54.754021 ignition[764]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 13 00:22:54.757402 systemd[1]: Starting ignition-disks.service... Sep 13 00:22:54.754716 ignition[764]: kargs: kargs passed Sep 13 00:22:54.754758 ignition[764]: Ignition finished successfully Sep 13 00:22:54.763044 ignition[774]: Ignition 2.14.0 Sep 13 00:22:54.763060 ignition[774]: Stage: disks Sep 13 00:22:54.763141 ignition[774]: no configs at "/usr/lib/ignition/base.d" Sep 13 00:22:54.767000 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' Sep 13 00:22:54.765207 systemd[1]: Finished ignition-disks.service. Sep 13 00:22:54.763151 ignition[774]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 13 00:22:54.767472 systemd[1]: Reached target initrd-root-device.target. Sep 13 00:22:54.764099 ignition[774]: disks: disks passed Sep 13 00:22:54.768555 systemd[1]: Reached target local-fs-pre.target. Sep 13 00:22:54.764138 ignition[774]: Ignition finished successfully Sep 13 00:22:54.769585 systemd[1]: Reached target local-fs.target. Sep 13 00:22:54.770741 systemd[1]: Reached target sysinit.target. Sep 13 00:22:54.771782 systemd[1]: Reached target basic.target. Sep 13 00:22:54.773602 systemd[1]: Starting systemd-fsck-root.service... Sep 13 00:22:54.784696 systemd-fsck[782]: ROOT: clean, 629/553520 files, 56027/553472 blocks Sep 13 00:22:54.789264 systemd[1]: Finished systemd-fsck-root.service. Sep 13 00:22:54.790000 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' Sep 13 00:22:54.790988 systemd[1]: Mounting sysroot.mount... Sep 13 00:22:54.796010 systemd[1]: Mounted sysroot.mount. Sep 13 00:22:54.796988 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Sep 13 00:22:54.796641 systemd[1]: Reached target initrd-root-fs.target. Sep 13 00:22:54.799157 systemd[1]: Mounting sysroot-usr.mount... Sep 13 00:22:54.799881 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Sep 13 00:22:54.799916 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 13 00:22:54.799938 systemd[1]: Reached target ignition-diskful.target. Sep 13 00:22:54.801776 systemd[1]: Mounted sysroot-usr.mount. Sep 13 00:22:54.803011 systemd[1]: Starting initrd-setup-root.service... Sep 13 00:22:54.807689 initrd-setup-root[792]: cut: /sysroot/etc/passwd: No such file or directory Sep 13 00:22:54.811197 initrd-setup-root[800]: cut: /sysroot/etc/group: No such file or directory Sep 13 00:22:54.814501 initrd-setup-root[808]: cut: /sysroot/etc/shadow: No such file or directory Sep 13 00:22:54.818225 initrd-setup-root[816]: cut: /sysroot/etc/gshadow: No such file or directory Sep 13 00:22:54.852592 systemd[1]: Finished initrd-setup-root.service. Sep 13 00:22:54.852000 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' Sep 13 00:22:54.853958 systemd[1]: Starting ignition-mount.service... Sep 13 00:22:54.855120 systemd[1]: Starting sysroot-boot.service... Sep 13 00:22:54.859338 bash[833]: umount: /sysroot/usr/share/oem: not mounted. Sep 13 00:22:54.872008 ignition[834]: INFO : Ignition 2.14.0 Sep 13 00:22:54.872008 ignition[834]: INFO : Stage: mount Sep 13 00:22:54.874173 ignition[834]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 13 00:22:54.874173 ignition[834]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 13 00:22:54.874173 ignition[834]: INFO : mount: mount passed Sep 13 00:22:54.874173 ignition[834]: INFO : Ignition finished successfully Sep 13 00:22:54.875000 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' Sep 13 00:22:54.874242 systemd[1]: Finished ignition-mount.service. Sep 13 00:22:54.880277 systemd[1]: Finished sysroot-boot.service. Sep 13 00:22:54.880000 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' Sep 13 00:22:55.544581 systemd[1]: Mounting sysroot-usr-share-oem.mount... Sep 13 00:22:55.553266 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (844) Sep 13 00:22:55.553301 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 13 00:22:55.554454 kernel: BTRFS info (device vda6): using free space tree Sep 13 00:22:55.554483 kernel: BTRFS info (device vda6): has skinny extents Sep 13 00:22:55.562348 systemd[1]: Mounted sysroot-usr-share-oem.mount. Sep 13 00:22:55.565326 systemd[1]: Starting ignition-files.service... Sep 13 00:22:55.584459 ignition[864]: INFO : Ignition 2.14.0 Sep 13 00:22:55.584459 ignition[864]: INFO : Stage: files Sep 13 00:22:55.586595 ignition[864]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 13 00:22:55.586595 ignition[864]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 13 00:22:55.586595 ignition[864]: DEBUG : files: compiled without relabeling support, skipping Sep 13 00:22:55.590259 ignition[864]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 13 00:22:55.590259 ignition[864]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 13 00:22:55.593507 ignition[864]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 13 00:22:55.593507 ignition[864]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 13 00:22:55.593507 ignition[864]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 13 00:22:55.593507 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 13 00:22:55.593507 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 13 00:22:55.591114 unknown[864]: wrote ssh authorized keys file for user: core Sep 13 00:22:55.601599 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 13 00:22:55.601599 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 13 00:22:55.601599 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 13 00:22:55.601599 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 13 00:22:55.601599 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 13 00:22:55.601599 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-arm64.raw: attempt #1 Sep 13 00:22:56.009283 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 13 00:22:56.423719 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 13 00:22:56.423719 ignition[864]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 13 00:22:56.427952 ignition[864]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 13 00:22:56.427952 ignition[864]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 13 00:22:56.427952 ignition[864]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 13 00:22:56.427952 ignition[864]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 13 00:22:56.427952 ignition[864]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 13 00:22:56.453704 ignition[864]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 13 00:22:56.456144 ignition[864]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 13 00:22:56.456144 ignition[864]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 13 00:22:56.456144 ignition[864]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 13 00:22:56.456144 ignition[864]: INFO : files: files passed Sep 13 00:22:56.456144 ignition[864]: INFO : Ignition finished successfully Sep 13 00:22:56.458000 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' Sep 13 00:22:56.456439 systemd[1]: Finished ignition-files.service. Sep 13 00:22:56.460984 systemd[1]: Starting initrd-setup-root-after-ignition.service... Sep 13 00:22:56.466448 initrd-setup-root-after-ignition[888]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Sep 13 00:22:56.462584 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Sep 13 00:22:56.468000 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' Sep 13 00:22:56.468000 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' Sep 13 00:22:56.470179 initrd-setup-root-after-ignition[891]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 13 00:22:56.470000 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' Sep 13 00:22:56.463538 systemd[1]: Starting ignition-quench.service... Sep 13 00:22:56.467055 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 13 00:22:56.467138 systemd[1]: Finished ignition-quench.service. Sep 13 00:22:56.469049 systemd[1]: Finished initrd-setup-root-after-ignition.service. Sep 13 00:22:56.470931 systemd[1]: Reached target ignition-complete.target. Sep 13 00:22:56.473455 systemd[1]: Starting initrd-parse-etc.service... Sep 13 00:22:56.487725 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 13 00:22:56.487811 systemd[1]: Finished initrd-parse-etc.service. Sep 13 00:22:56.489000 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' Sep 13 00:22:56.489000 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' Sep 13 00:22:56.488778 systemd-networkd[747]: eth0: Gained IPv6LL Sep 13 00:22:56.489923 systemd[1]: Reached target initrd-fs.target. Sep 13 00:22:56.490961 systemd[1]: Reached target initrd.target. Sep 13 00:22:56.492228 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Sep 13 00:22:56.492968 systemd[1]: Starting dracut-pre-pivot.service... Sep 13 00:22:56.504809 systemd[1]: Finished dracut-pre-pivot.service. Sep 13 00:22:56.505000 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' Sep 13 00:22:56.506143 systemd[1]: Starting initrd-cleanup.service... Sep 13 00:22:56.513881 systemd[1]: Stopped target nss-lookup.target. Sep 13 00:22:56.514651 systemd[1]: Stopped target remote-cryptsetup.target. Sep 13 00:22:56.516012 systemd[1]: Stopped target timers.target. Sep 13 00:22:56.517200 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 13 00:22:56.518000 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' Sep 13 00:22:56.517303 systemd[1]: Stopped dracut-pre-pivot.service. Sep 13 00:22:56.518475 systemd[1]: Stopped target initrd.target. Sep 13 00:22:56.519723 systemd[1]: Stopped target basic.target. Sep 13 00:22:56.520836 systemd[1]: Stopped target ignition-complete.target. Sep 13 00:22:56.522003 systemd[1]: Stopped target ignition-diskful.target. Sep 13 00:22:56.523219 systemd[1]: Stopped target initrd-root-device.target. Sep 13 00:22:56.524581 systemd[1]: Stopped target remote-fs.target. Sep 13 00:22:56.525871 systemd[1]: Stopped target remote-fs-pre.target. Sep 13 00:22:56.527164 systemd[1]: Stopped target sysinit.target. Sep 13 00:22:56.528308 systemd[1]: Stopped target local-fs.target. Sep 13 00:22:56.529506 systemd[1]: Stopped target local-fs-pre.target. Sep 13 00:22:56.530770 systemd[1]: Stopped target swap.target. Sep 13 00:22:56.532000 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' Sep 13 00:22:56.531835 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 13 00:22:56.531937 systemd[1]: Stopped dracut-pre-mount.service. Sep 13 00:22:56.535000 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' Sep 13 00:22:56.533093 systemd[1]: Stopped target cryptsetup.target. Sep 13 00:22:56.536000 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' Sep 13 00:22:56.534212 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 13 00:22:56.534315 systemd[1]: Stopped dracut-initqueue.service. Sep 13 00:22:56.535714 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 13 00:22:56.535803 systemd[1]: Stopped ignition-fetch-offline.service. Sep 13 00:22:56.536962 systemd[1]: Stopped target paths.target. Sep 13 00:22:56.537994 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 13 00:22:56.541225 systemd[1]: Stopped systemd-ask-password-console.path. Sep 13 00:22:56.542051 systemd[1]: Stopped target slices.target. Sep 13 00:22:56.543201 systemd[1]: Stopped target sockets.target. Sep 13 00:22:56.544722 systemd[1]: iscsid.socket: Deactivated successfully. Sep 13 00:22:56.548000 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' Sep 13 00:22:56.544792 systemd[1]: Closed iscsid.socket. Sep 13 00:22:56.549000 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' Sep 13 00:22:56.545973 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 13 00:22:56.546038 systemd[1]: Closed iscsiuio.socket. Sep 13 00:22:56.547039 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 13 00:22:56.547135 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Sep 13 00:22:56.548318 systemd[1]: ignition-files.service: Deactivated successfully. Sep 13 00:22:56.554000 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' Sep 13 00:22:56.548403 systemd[1]: Stopped ignition-files.service. Sep 13 00:22:56.556000 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' Sep 13 00:22:56.550401 systemd[1]: Stopping ignition-mount.service... Sep 13 00:22:56.552132 systemd[1]: Stopping sysroot-boot.service... Sep 13 00:22:56.553634 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 13 00:22:56.553746 systemd[1]: Stopped systemd-udev-trigger.service. Sep 13 00:22:56.560811 ignition[904]: INFO : Ignition 2.14.0 Sep 13 00:22:56.560811 ignition[904]: INFO : Stage: umount Sep 13 00:22:56.560811 ignition[904]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 13 00:22:56.560811 ignition[904]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 13 00:22:56.562000 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' Sep 13 00:22:56.562000 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' Sep 13 00:22:56.555153 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 13 00:22:56.566000 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' Sep 13 00:22:56.567451 ignition[904]: INFO : umount: umount passed Sep 13 00:22:56.567451 ignition[904]: INFO : Ignition finished successfully Sep 13 00:22:56.569000 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' Sep 13 00:22:56.571000 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' Sep 13 00:22:56.555276 systemd[1]: Stopped dracut-pre-trigger.service. Sep 13 00:22:56.572000 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' Sep 13 00:22:56.562062 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 13 00:22:56.562151 systemd[1]: Finished initrd-cleanup.service. Sep 13 00:22:56.565091 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 13 00:22:56.565505 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 13 00:22:56.565585 systemd[1]: Stopped ignition-mount.service. Sep 13 00:22:56.566544 systemd[1]: Stopped target network.target. Sep 13 00:22:56.567900 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 13 00:22:56.567948 systemd[1]: Stopped ignition-disks.service. Sep 13 00:22:56.570036 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 13 00:22:56.585000 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' Sep 13 00:22:56.586000 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' Sep 13 00:22:56.570083 systemd[1]: Stopped ignition-kargs.service. Sep 13 00:22:56.571389 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 13 00:22:56.571431 systemd[1]: Stopped ignition-setup.service. Sep 13 00:22:56.573057 systemd[1]: Stopping systemd-networkd.service... Sep 13 00:22:56.592000 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' Sep 13 00:22:56.593000 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' Sep 13 00:22:56.574024 systemd[1]: Stopping systemd-resolved.service... Sep 13 00:22:56.594000 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' Sep 13 00:22:56.596000 audit: BPF prog-id=6 op=UNLOAD Sep 13 00:22:56.583343 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 13 00:22:56.583445 systemd[1]: Stopped systemd-resolved.service. Sep 13 00:22:56.584573 systemd-networkd[747]: eth0: DHCPv6 lease lost Sep 13 00:22:56.600000 audit: BPF prog-id=9 op=UNLOAD Sep 13 00:22:56.600000 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' Sep 13 00:22:56.585720 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 13 00:22:56.602000 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' Sep 13 00:22:56.585812 systemd[1]: Stopped systemd-networkd.service. Sep 13 00:22:56.587020 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 13 00:22:56.587051 systemd[1]: Closed systemd-networkd.socket. Sep 13 00:22:56.590004 systemd[1]: Stopping network-cleanup.service... Sep 13 00:22:56.590940 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 13 00:22:56.604000 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' Sep 13 00:22:56.605000 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' Sep 13 00:22:56.590994 systemd[1]: Stopped parse-ip-for-networkd.service. Sep 13 00:22:56.609000 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' Sep 13 00:22:56.592572 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 13 00:22:56.611000 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' Sep 13 00:22:56.592682 systemd[1]: Stopped systemd-sysctl.service. Sep 13 00:22:56.612000 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' Sep 13 00:22:56.594317 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 13 00:22:56.594359 systemd[1]: Stopped systemd-modules-load.service. Sep 13 00:22:56.595257 systemd[1]: Stopping systemd-udevd.service... Sep 13 00:22:56.599790 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 13 00:22:56.600275 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 13 00:22:56.616000 audit[1]: SERVICE_STOP 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' Sep 13 00:22:56.619000 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' Sep 13 00:22:56.600355 systemd[1]: Stopped sysroot-boot.service. Sep 13 00:22:56.620000 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' Sep 13 00:22:56.601641 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 13 00:22:56.601697 systemd[1]: Stopped initrd-setup-root.service. Sep 13 00:22:56.623000 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' Sep 13 00:22:56.623000 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' Sep 13 00:22:56.603687 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 13 00:22:56.603772 systemd[1]: Stopped network-cleanup.service. Sep 13 00:22:56.605031 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 13 00:22:56.605140 systemd[1]: Stopped systemd-udevd.service. Sep 13 00:22:56.606380 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 13 00:22:56.606417 systemd[1]: Closed systemd-udevd-control.socket. Sep 13 00:22:56.607497 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 13 00:22:56.607529 systemd[1]: Closed systemd-udevd-kernel.socket. Sep 13 00:22:56.608671 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 13 00:22:56.608708 systemd[1]: Stopped dracut-pre-udev.service. Sep 13 00:22:56.610049 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 13 00:22:56.610088 systemd[1]: Stopped dracut-cmdline.service. Sep 13 00:22:56.611481 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 13 00:22:56.611529 systemd[1]: Stopped dracut-cmdline-ask.service. Sep 13 00:22:56.613931 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Sep 13 00:22:56.615612 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 13 00:22:56.615771 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Sep 13 00:22:56.618005 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 13 00:22:56.618059 systemd[1]: Stopped kmod-static-nodes.service. Sep 13 00:22:56.619852 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 13 00:22:56.645874 systemd-journald[290]: Received SIGTERM from PID 1 (systemd). Sep 13 00:22:56.645928 iscsid[753]: iscsid shutting down. Sep 13 00:22:56.619891 systemd[1]: Stopped systemd-vconsole-setup.service. Sep 13 00:22:56.621900 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 13 00:22:56.622391 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 13 00:22:56.622474 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Sep 13 00:22:56.623664 systemd[1]: Reached target initrd-switch-root.target. Sep 13 00:22:56.625620 systemd[1]: Starting initrd-switch-root.service... Sep 13 00:22:56.631468 systemd[1]: Switching root. Sep 13 00:22:56.652756 systemd-journald[290]: Journal stopped Sep 13 00:22:58.760438 kernel: SELinux: Class mctp_socket not defined in policy. Sep 13 00:22:58.760665 kernel: SELinux: Class anon_inode not defined in policy. Sep 13 00:22:58.760688 kernel: SELinux: the above unknown classes and permissions will be allowed Sep 13 00:22:58.760698 kernel: SELinux: policy capability network_peer_controls=1 Sep 13 00:22:58.760709 kernel: SELinux: policy capability open_perms=1 Sep 13 00:22:58.760720 kernel: SELinux: policy capability extended_socket_class=1 Sep 13 00:22:58.760733 kernel: SELinux: policy capability always_check_network=0 Sep 13 00:22:58.760764 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 13 00:22:58.760784 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 13 00:22:58.760796 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 13 00:22:58.760806 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 13 00:22:58.760818 systemd[1]: Successfully loaded SELinux policy in 37.542ms. Sep 13 00:22:58.760849 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.501ms. Sep 13 00:22:58.760865 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) Sep 13 00:22:58.760878 systemd[1]: Detected virtualization kvm. Sep 13 00:22:58.760888 systemd[1]: Detected architecture arm64. Sep 13 00:22:58.760899 systemd[1]: Detected first boot. Sep 13 00:22:58.760909 systemd[1]: Initializing machine ID from VM UUID. Sep 13 00:22:58.760919 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Sep 13 00:22:58.760933 systemd[1]: Populated /etc with preset unit settings. Sep 13 00:22:58.760943 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Sep 13 00:22:58.760954 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Sep 13 00:22:58.760968 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. Sep 13 00:22:58.760979 kernel: kauditd_printk_skb: 79 callbacks suppressed Sep 13 00:22:58.760990 kernel: audit: type=1334 audit(1757722978.633:83): prog-id=12 op=LOAD Sep 13 00:22:58.760999 kernel: audit: type=1334 audit(1757722978.633:84): prog-id=3 op=UNLOAD Sep 13 00:22:58.761009 kernel: audit: type=1334 audit(1757722978.633:85): prog-id=13 op=LOAD Sep 13 00:22:58.761036 kernel: audit: type=1334 audit(1757722978.634:86): prog-id=14 op=LOAD Sep 13 00:22:58.761051 kernel: audit: type=1334 audit(1757722978.634:87): prog-id=4 op=UNLOAD Sep 13 00:22:58.761061 kernel: audit: type=1334 audit(1757722978.634:88): prog-id=5 op=UNLOAD Sep 13 00:22:58.761073 kernel: audit: type=1334 audit(1757722978.635:89): prog-id=15 op=LOAD Sep 13 00:22:58.761084 kernel: audit: type=1334 audit(1757722978.635:90): prog-id=12 op=UNLOAD Sep 13 00:22:58.761095 systemd[1]: iscsiuio.service: Deactivated successfully. Sep 13 00:22:58.761105 kernel: audit: type=1334 audit(1757722978.636:91): prog-id=16 op=LOAD Sep 13 00:22:58.761117 systemd[1]: Stopped iscsiuio.service. Sep 13 00:22:58.761128 kernel: audit: type=1334 audit(1757722978.637:92): prog-id=17 op=LOAD Sep 13 00:22:58.761138 systemd[1]: iscsid.service: Deactivated successfully. Sep 13 00:22:58.761149 systemd[1]: Stopped iscsid.service. Sep 13 00:22:58.761160 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 13 00:22:58.761170 systemd[1]: Stopped initrd-switch-root.service. Sep 13 00:22:58.761201 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 13 00:22:58.761214 systemd[1]: Created slice system-addon\x2dconfig.slice. Sep 13 00:22:58.761225 systemd[1]: Created slice system-addon\x2drun.slice. Sep 13 00:22:58.761235 systemd[1]: Created slice system-getty.slice. Sep 13 00:22:58.761246 systemd[1]: Created slice system-modprobe.slice. Sep 13 00:22:58.761256 systemd[1]: Created slice system-serial\x2dgetty.slice. Sep 13 00:22:58.761267 systemd[1]: Created slice system-system\x2dcloudinit.slice. Sep 13 00:22:58.761279 systemd[1]: Created slice system-systemd\x2dfsck.slice. Sep 13 00:22:58.761290 systemd[1]: Created slice user.slice. Sep 13 00:22:58.761302 systemd[1]: Started systemd-ask-password-console.path. Sep 13 00:22:58.761313 systemd[1]: Started systemd-ask-password-wall.path. Sep 13 00:22:58.761324 systemd[1]: Set up automount boot.automount. Sep 13 00:22:58.761334 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Sep 13 00:22:58.761345 systemd[1]: Stopped target initrd-switch-root.target. Sep 13 00:22:58.761356 systemd[1]: Stopped target initrd-fs.target. Sep 13 00:22:58.761366 systemd[1]: Stopped target initrd-root-fs.target. Sep 13 00:22:58.761378 systemd[1]: Reached target integritysetup.target. Sep 13 00:22:58.761388 systemd[1]: Reached target remote-cryptsetup.target. Sep 13 00:22:58.761399 systemd[1]: Reached target remote-fs.target. Sep 13 00:22:58.761409 systemd[1]: Reached target slices.target. Sep 13 00:22:58.761420 systemd[1]: Reached target swap.target. Sep 13 00:22:58.761431 systemd[1]: Reached target torcx.target. Sep 13 00:22:58.761442 systemd[1]: Reached target veritysetup.target. Sep 13 00:22:58.761452 systemd[1]: Listening on systemd-coredump.socket. Sep 13 00:22:58.761463 systemd[1]: Listening on systemd-initctl.socket. Sep 13 00:22:58.761483 systemd[1]: Listening on systemd-networkd.socket. Sep 13 00:22:58.761494 systemd[1]: Listening on systemd-udevd-control.socket. Sep 13 00:22:58.761504 systemd[1]: Listening on systemd-udevd-kernel.socket. Sep 13 00:22:58.761515 systemd[1]: Listening on systemd-userdbd.socket. Sep 13 00:22:58.761525 systemd[1]: Mounting dev-hugepages.mount... Sep 13 00:22:58.761539 systemd[1]: Mounting dev-mqueue.mount... Sep 13 00:22:58.761549 systemd[1]: Mounting media.mount... Sep 13 00:22:58.761561 systemd[1]: Mounting sys-kernel-debug.mount... Sep 13 00:22:58.761572 systemd[1]: Mounting sys-kernel-tracing.mount... Sep 13 00:22:58.761582 systemd[1]: Mounting tmp.mount... Sep 13 00:22:58.761592 systemd[1]: Starting flatcar-tmpfiles.service... Sep 13 00:22:58.761602 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Sep 13 00:22:58.761613 systemd[1]: Starting kmod-static-nodes.service... Sep 13 00:22:58.761624 systemd[1]: Starting modprobe@configfs.service... Sep 13 00:22:58.761634 systemd[1]: Starting modprobe@dm_mod.service... Sep 13 00:22:58.761645 systemd[1]: Starting modprobe@drm.service... Sep 13 00:22:58.761657 systemd[1]: Starting modprobe@efi_pstore.service... Sep 13 00:22:58.761667 systemd[1]: Starting modprobe@fuse.service... Sep 13 00:22:58.761677 systemd[1]: Starting modprobe@loop.service... Sep 13 00:22:58.761687 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 13 00:22:58.761698 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 13 00:22:58.761708 systemd[1]: Stopped systemd-fsck-root.service. Sep 13 00:22:58.761718 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 13 00:22:58.761728 systemd[1]: Stopped systemd-fsck-usr.service. Sep 13 00:22:58.761739 systemd[1]: Stopped systemd-journald.service. Sep 13 00:22:58.761750 kernel: loop: module loaded Sep 13 00:22:58.761762 systemd[1]: Starting systemd-journald.service... Sep 13 00:22:58.761772 systemd[1]: Starting systemd-modules-load.service... Sep 13 00:22:58.761783 systemd[1]: Starting systemd-network-generator.service... Sep 13 00:22:58.761794 systemd[1]: Starting systemd-remount-fs.service... Sep 13 00:22:58.761804 kernel: fuse: init (API version 7.34) Sep 13 00:22:58.761814 systemd[1]: Starting systemd-udev-trigger.service... Sep 13 00:22:58.761825 systemd[1]: verity-setup.service: Deactivated successfully. Sep 13 00:22:58.761835 systemd[1]: Stopped verity-setup.service. Sep 13 00:22:58.761847 systemd[1]: Mounted dev-hugepages.mount. Sep 13 00:22:58.761859 systemd[1]: Mounted dev-mqueue.mount. Sep 13 00:22:58.761869 systemd[1]: Mounted media.mount. Sep 13 00:22:58.761879 systemd[1]: Mounted sys-kernel-debug.mount. Sep 13 00:22:58.761890 systemd[1]: Mounted sys-kernel-tracing.mount. Sep 13 00:22:58.761900 systemd[1]: Mounted tmp.mount. Sep 13 00:22:58.761911 systemd[1]: Finished kmod-static-nodes.service. Sep 13 00:22:58.761925 systemd-journald[1008]: Journal started Sep 13 00:22:58.761975 systemd-journald[1008]: Runtime Journal (/run/log/journal/415fc89bdcb540b8a0d992aba0d4c34e) is 6.0M, max 48.7M, 42.6M free. Sep 13 00:22:56.716000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 13 00:22:56.792000 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 Sep 13 00:22:56.792000 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 Sep 13 00:22:56.792000 audit: BPF prog-id=10 op=LOAD Sep 13 00:22:56.792000 audit: BPF prog-id=10 op=UNLOAD Sep 13 00:22:56.793000 audit: BPF prog-id=11 op=LOAD Sep 13 00:22:56.793000 audit: BPF prog-id=11 op=UNLOAD Sep 13 00:22:56.844000 audit[938]: AVC avc: denied { associate } for pid=938 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" Sep 13 00:22:56.844000 audit[938]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001c58b4 a1=40000c8de0 a2=40000cf0c0 a3=32 items=0 ppid=921 pid=938 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) Sep 13 00:22:56.844000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Sep 13 00:22:56.845000 audit[938]: AVC avc: denied { associate } for pid=938 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Sep 13 00:22:56.845000 audit[938]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001c5989 a2=1ed a3=0 items=2 ppid=921 pid=938 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) Sep 13 00:22:56.845000 audit: CWD cwd="/" Sep 13 00:22:56.845000 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 Sep 13 00:22:56.845000 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 Sep 13 00:22:56.845000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Sep 13 00:22:58.633000 audit: BPF prog-id=12 op=LOAD Sep 13 00:22:58.633000 audit: BPF prog-id=3 op=UNLOAD Sep 13 00:22:58.633000 audit: BPF prog-id=13 op=LOAD Sep 13 00:22:58.634000 audit: BPF prog-id=14 op=LOAD Sep 13 00:22:58.634000 audit: BPF prog-id=4 op=UNLOAD Sep 13 00:22:58.634000 audit: BPF prog-id=5 op=UNLOAD Sep 13 00:22:58.635000 audit: BPF prog-id=15 op=LOAD Sep 13 00:22:58.635000 audit: BPF prog-id=12 op=UNLOAD Sep 13 00:22:58.636000 audit: BPF prog-id=16 op=LOAD Sep 13 00:22:58.637000 audit: BPF prog-id=17 op=LOAD Sep 13 00:22:58.637000 audit: BPF prog-id=13 op=UNLOAD Sep 13 00:22:58.637000 audit: BPF prog-id=14 op=UNLOAD Sep 13 00:22:58.638000 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' Sep 13 00:22:58.641000 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' Sep 13 00:22:58.643000 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' Sep 13 00:22:58.645000 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' Sep 13 00:22:58.645000 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' Sep 13 00:22:58.650000 audit: BPF prog-id=15 op=UNLOAD Sep 13 00:22:58.726000 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' Sep 13 00:22:58.762772 systemd[1]: Started systemd-journald.service. Sep 13 00:22:58.728000 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' Sep 13 00:22:58.729000 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' Sep 13 00:22:58.729000 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' Sep 13 00:22:58.730000 audit: BPF prog-id=18 op=LOAD Sep 13 00:22:58.732000 audit: BPF prog-id=19 op=LOAD Sep 13 00:22:58.732000 audit: BPF prog-id=20 op=LOAD Sep 13 00:22:58.732000 audit: BPF prog-id=16 op=UNLOAD Sep 13 00:22:58.732000 audit: BPF prog-id=17 op=UNLOAD Sep 13 00:22:58.751000 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' Sep 13 00:22:58.759000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Sep 13 00:22:58.759000 audit[1008]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=5 a1=ffffc32d2570 a2=4000 a3=1 items=0 ppid=1 pid=1008 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) Sep 13 00:22:58.759000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Sep 13 00:22:58.761000 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' Sep 13 00:22:56.838966 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" 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.8 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.8 /var/lib/torcx/store]" Sep 13 00:22:58.631826 systemd[1]: Queued start job for default target multi-user.target. Sep 13 00:22:56.839256 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Sep 13 00:22:58.631839 systemd[1]: Unnecessary job was removed for dev-vda6.device. Sep 13 00:22:56.839274 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Sep 13 00:22:58.638047 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 13 00:22:58.763000 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' Sep 13 00:22:56.839302 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Sep 13 00:22:56.839316 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=debug msg="skipped missing lower profile" missing profile=oem Sep 13 00:22:56.839345 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Sep 13 00:22:58.763707 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 13 00:22:56.839356 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Sep 13 00:22:56.839560 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Sep 13 00:22:56.839594 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Sep 13 00:22:56.839605 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Sep 13 00:22:56.844364 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" 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 Sep 13 00:22:56.844404 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" 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 Sep 13 00:22:56.844423 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.8: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.8 Sep 13 00:22:56.844437 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Sep 13 00:22:56.844455 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.8: no such file or directory" path=/var/lib/torcx/store/3510.3.8 Sep 13 00:22:56.844471 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:56Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Sep 13 00:22:58.348311 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:58Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Sep 13 00:22:58.348582 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:58Z" 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 Sep 13 00:22:58.348691 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:58Z" 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 Sep 13 00:22:58.348852 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:58Z" 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 Sep 13 00:22:58.348958 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:58Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Sep 13 00:22:58.349026 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-09-13T00:22:58Z" 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 Sep 13 00:22:58.765940 systemd[1]: Finished modprobe@configfs.service. Sep 13 00:22:58.766000 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' Sep 13 00:22:58.766000 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' Sep 13 00:22:58.767402 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 13 00:22:58.767564 systemd[1]: Finished modprobe@dm_mod.service. Sep 13 00:22:58.768000 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' Sep 13 00:22:58.768000 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' Sep 13 00:22:58.769000 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' Sep 13 00:22:58.769000 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' Sep 13 00:22:58.768575 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 13 00:22:58.768716 systemd[1]: Finished modprobe@drm.service. Sep 13 00:22:58.769571 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 13 00:22:58.769721 systemd[1]: Finished modprobe@efi_pstore.service. Sep 13 00:22:58.770000 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' Sep 13 00:22:58.770000 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' Sep 13 00:22:58.770628 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 13 00:22:58.770778 systemd[1]: Finished modprobe@fuse.service. Sep 13 00:22:58.771000 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' Sep 13 00:22:58.771000 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' Sep 13 00:22:58.771807 systemd[1]: Finished flatcar-tmpfiles.service. Sep 13 00:22:58.772000 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' Sep 13 00:22:58.772691 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 13 00:22:58.772832 systemd[1]: Finished modprobe@loop.service. Sep 13 00:22:58.773000 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' Sep 13 00:22:58.773000 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' Sep 13 00:22:58.773745 systemd[1]: Finished systemd-modules-load.service. Sep 13 00:22:58.774000 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' Sep 13 00:22:58.774716 systemd[1]: Finished systemd-network-generator.service. Sep 13 00:22:58.775000 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' Sep 13 00:22:58.775696 systemd[1]: Finished systemd-remount-fs.service. Sep 13 00:22:58.776000 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' Sep 13 00:22:58.776776 systemd[1]: Reached target network-pre.target. Sep 13 00:22:58.778710 systemd[1]: Mounting sys-fs-fuse-connections.mount... Sep 13 00:22:58.780348 systemd[1]: Mounting sys-kernel-config.mount... Sep 13 00:22:58.780943 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 13 00:22:58.782570 systemd[1]: Starting systemd-hwdb-update.service... Sep 13 00:22:58.784499 systemd[1]: Starting systemd-journal-flush.service... Sep 13 00:22:58.785242 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 13 00:22:58.786369 systemd[1]: Starting systemd-random-seed.service... Sep 13 00:22:58.787127 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Sep 13 00:22:58.792276 systemd-journald[1008]: Time spent on flushing to /var/log/journal/415fc89bdcb540b8a0d992aba0d4c34e is 13.698ms for 978 entries. Sep 13 00:22:58.792276 systemd-journald[1008]: System Journal (/var/log/journal/415fc89bdcb540b8a0d992aba0d4c34e) is 8.0M, max 195.6M, 187.6M free. Sep 13 00:22:58.824771 systemd-journald[1008]: Received client request to flush runtime journal. Sep 13 00:22:58.799000 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' Sep 13 00:22:58.803000 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' Sep 13 00:22:58.813000 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' Sep 13 00:22:58.788199 systemd[1]: Starting systemd-sysctl.service... Sep 13 00:22:58.789989 systemd[1]: Starting systemd-sysusers.service... Sep 13 00:22:58.792306 systemd[1]: Mounted sys-fs-fuse-connections.mount. Sep 13 00:22:58.794950 systemd[1]: Mounted sys-kernel-config.mount. Sep 13 00:22:58.825877 udevadm[1039]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 13 00:22:58.798611 systemd[1]: Finished systemd-udev-trigger.service. Sep 13 00:22:58.800628 systemd[1]: Starting systemd-udev-settle.service... Sep 13 00:22:58.802414 systemd[1]: Finished systemd-random-seed.service. Sep 13 00:22:58.803678 systemd[1]: Reached target first-boot-complete.target. Sep 13 00:22:58.812412 systemd[1]: Finished systemd-sysctl.service. Sep 13 00:22:58.826010 systemd[1]: Finished systemd-journal-flush.service. Sep 13 00:22:58.826000 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' Sep 13 00:22:58.833277 systemd[1]: Finished systemd-sysusers.service. Sep 13 00:22:58.833000 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' Sep 13 00:22:58.835115 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Sep 13 00:22:58.855551 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Sep 13 00:22:58.856000 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-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Sep 13 00:22:59.158266 systemd[1]: Finished systemd-hwdb-update.service. Sep 13 00:22:59.158000 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' Sep 13 00:22:59.159000 audit: BPF prog-id=21 op=LOAD Sep 13 00:22:59.160000 audit: BPF prog-id=22 op=LOAD Sep 13 00:22:59.160000 audit: BPF prog-id=7 op=UNLOAD Sep 13 00:22:59.160000 audit: BPF prog-id=8 op=UNLOAD Sep 13 00:22:59.160847 systemd[1]: Starting systemd-udevd.service... Sep 13 00:22:59.179590 systemd-udevd[1044]: Using default interface naming scheme 'v252'. Sep 13 00:22:59.217000 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' Sep 13 00:22:59.219000 audit: BPF prog-id=23 op=LOAD Sep 13 00:22:59.216693 systemd[1]: Started systemd-udevd.service. Sep 13 00:22:59.220197 systemd[1]: Starting systemd-networkd.service... Sep 13 00:22:59.230000 audit: BPF prog-id=24 op=LOAD Sep 13 00:22:59.233000 audit: BPF prog-id=25 op=LOAD Sep 13 00:22:59.235000 audit: BPF prog-id=26 op=LOAD Sep 13 00:22:59.236152 systemd[1]: Starting systemd-userdbd.service... Sep 13 00:22:59.245618 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Sep 13 00:22:59.276000 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' Sep 13 00:22:59.273422 systemd[1]: Started systemd-userdbd.service. Sep 13 00:22:59.330630 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Sep 13 00:22:59.342629 systemd-networkd[1056]: lo: Link UP Sep 13 00:22:59.342894 systemd-networkd[1056]: lo: Gained carrier Sep 13 00:22:59.343355 systemd-networkd[1056]: Enumeration completed Sep 13 00:22:59.344000 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' Sep 13 00:22:59.343528 systemd[1]: Started systemd-networkd.service. Sep 13 00:22:59.344790 systemd-networkd[1056]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 13 00:22:59.346104 systemd-networkd[1056]: eth0: Link UP Sep 13 00:22:59.346179 systemd-networkd[1056]: eth0: Gained carrier Sep 13 00:22:59.353568 systemd[1]: Finished systemd-udev-settle.service. Sep 13 00:22:59.354000 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' Sep 13 00:22:59.355710 systemd[1]: Starting lvm2-activation-early.service... Sep 13 00:22:59.364926 lvm[1077]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 13 00:22:59.367326 systemd-networkd[1056]: eth0: DHCPv4 address 10.0.0.118/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 13 00:22:59.393285 systemd[1]: Finished lvm2-activation-early.service. Sep 13 00:22:59.393000 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' Sep 13 00:22:59.394312 systemd[1]: Reached target cryptsetup.target. Sep 13 00:22:59.396233 systemd[1]: Starting lvm2-activation.service... Sep 13 00:22:59.399803 lvm[1078]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 13 00:22:59.428053 systemd[1]: Finished lvm2-activation.service. Sep 13 00:22:59.428000 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' Sep 13 00:22:59.428861 systemd[1]: Reached target local-fs-pre.target. Sep 13 00:22:59.429521 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 13 00:22:59.429557 systemd[1]: Reached target local-fs.target. Sep 13 00:22:59.430123 systemd[1]: Reached target machines.target. Sep 13 00:22:59.431879 systemd[1]: Starting ldconfig.service... Sep 13 00:22:59.432789 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.432841 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). Sep 13 00:22:59.433825 systemd[1]: Starting systemd-boot-update.service... Sep 13 00:22:59.435447 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Sep 13 00:22:59.437498 systemd[1]: Starting systemd-machine-id-commit.service... Sep 13 00:22:59.439866 systemd[1]: Starting systemd-sysext.service... Sep 13 00:22:59.442678 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1080 (bootctl) Sep 13 00:22:59.443663 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Sep 13 00:22:59.450449 systemd[1]: Unmounting usr-share-oem.mount... Sep 13 00:22:59.457740 systemd[1]: usr-share-oem.mount: Deactivated successfully. Sep 13 00:22:59.457928 systemd[1]: Unmounted usr-share-oem.mount. Sep 13 00:22:59.472301 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Sep 13 00:22:59.474063 kernel: loop0: detected capacity change from 0 to 203944 Sep 13 00:22:59.473000 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' Sep 13 00:22:59.527000 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' Sep 13 00:22:59.525959 systemd[1]: Finished systemd-machine-id-commit.service. Sep 13 00:22:59.532096 systemd-fsck[1090]: fsck.fat 4.2 (2021-01-31) Sep 13 00:22:59.532096 systemd-fsck[1090]: /dev/vda1: 236 files, 117310/258078 clusters Sep 13 00:22:59.534260 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 13 00:22:59.534947 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Sep 13 00:22:59.536000 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' Sep 13 00:22:59.564226 kernel: loop1: detected capacity change from 0 to 203944 Sep 13 00:22:59.573132 (sd-sysext)[1093]: Using extensions 'kubernetes'. Sep 13 00:22:59.573605 (sd-sysext)[1093]: Merged extensions into '/usr'. Sep 13 00:22:59.591018 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.592257 systemd[1]: Starting modprobe@dm_mod.service... Sep 13 00:22:59.594045 systemd[1]: Starting modprobe@efi_pstore.service... Sep 13 00:22:59.596037 systemd[1]: Starting modprobe@loop.service... Sep 13 00:22:59.596862 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.596988 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). Sep 13 00:22:59.597740 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 13 00:22:59.597865 systemd[1]: Finished modprobe@dm_mod.service. Sep 13 00:22:59.598000 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' Sep 13 00:22:59.598000 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' Sep 13 00:22:59.599217 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 13 00:22:59.599323 systemd[1]: Finished modprobe@efi_pstore.service. Sep 13 00:22:59.600000 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' Sep 13 00:22:59.600000 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' Sep 13 00:22:59.600789 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 13 00:22:59.600911 systemd[1]: Finished modprobe@loop.service. Sep 13 00:22:59.601000 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' Sep 13 00:22:59.601000 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' Sep 13 00:22:59.602067 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 13 00:22:59.602254 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.681400 ldconfig[1079]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 13 00:22:59.688268 systemd[1]: Finished ldconfig.service. Sep 13 00:22:59.688000 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' Sep 13 00:22:59.752948 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 13 00:22:59.754938 systemd[1]: Mounting boot.mount... Sep 13 00:22:59.756821 systemd[1]: Mounting usr-share-oem.mount... Sep 13 00:22:59.762571 systemd[1]: Mounted boot.mount. Sep 13 00:22:59.763567 systemd[1]: Mounted usr-share-oem.mount. Sep 13 00:22:59.765535 systemd[1]: Finished systemd-sysext.service. Sep 13 00:22:59.766000 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' Sep 13 00:22:59.767824 systemd[1]: Starting ensure-sysext.service... Sep 13 00:22:59.769928 systemd[1]: Starting systemd-tmpfiles-setup.service... Sep 13 00:22:59.773747 systemd[1]: Finished systemd-boot-update.service. Sep 13 00:22:59.774000 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' Sep 13 00:22:59.776075 systemd[1]: Reloading. Sep 13 00:22:59.780574 systemd-tmpfiles[1101]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Sep 13 00:22:59.781707 systemd-tmpfiles[1101]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 13 00:22:59.783617 systemd-tmpfiles[1101]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 13 00:22:59.816774 /usr/lib/systemd/system-generators/torcx-generator[1121]: time="2025-09-13T00:22: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.8 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.8 /var/lib/torcx/store]" Sep 13 00:22:59.820241 /usr/lib/systemd/system-generators/torcx-generator[1121]: time="2025-09-13T00:22:59Z" level=info msg="torcx already run" Sep 13 00:22:59.895077 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Sep 13 00:22:59.895095 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Sep 13 00:22:59.910669 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. Sep 13 00:22:59.950000 audit: BPF prog-id=27 op=LOAD Sep 13 00:22:59.951000 audit: BPF prog-id=18 op=UNLOAD Sep 13 00:22:59.951000 audit: BPF prog-id=28 op=LOAD Sep 13 00:22:59.951000 audit: BPF prog-id=29 op=LOAD Sep 13 00:22:59.951000 audit: BPF prog-id=19 op=UNLOAD Sep 13 00:22:59.951000 audit: BPF prog-id=20 op=UNLOAD Sep 13 00:22:59.952000 audit: BPF prog-id=30 op=LOAD Sep 13 00:22:59.952000 audit: BPF prog-id=31 op=LOAD Sep 13 00:22:59.952000 audit: BPF prog-id=21 op=UNLOAD Sep 13 00:22:59.952000 audit: BPF prog-id=22 op=UNLOAD Sep 13 00:22:59.953000 audit: BPF prog-id=32 op=LOAD Sep 13 00:22:59.953000 audit: BPF prog-id=24 op=UNLOAD Sep 13 00:22:59.953000 audit: BPF prog-id=33 op=LOAD Sep 13 00:22:59.953000 audit: BPF prog-id=34 op=LOAD Sep 13 00:22:59.953000 audit: BPF prog-id=25 op=UNLOAD Sep 13 00:22:59.953000 audit: BPF prog-id=26 op=UNLOAD Sep 13 00:22:59.954000 audit: BPF prog-id=35 op=LOAD Sep 13 00:22:59.954000 audit: BPF prog-id=23 op=UNLOAD Sep 13 00:22:59.956453 systemd[1]: Finished systemd-tmpfiles-setup.service. Sep 13 00:22:59.957000 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' Sep 13 00:22:59.960486 systemd[1]: Starting audit-rules.service... Sep 13 00:22:59.962252 systemd[1]: Starting clean-ca-certificates.service... Sep 13 00:22:59.965000 audit: BPF prog-id=36 op=LOAD Sep 13 00:22:59.964567 systemd[1]: Starting systemd-journal-catalog-update.service... Sep 13 00:22:59.966950 systemd[1]: Starting systemd-resolved.service... Sep 13 00:22:59.968000 audit: BPF prog-id=37 op=LOAD Sep 13 00:22:59.969145 systemd[1]: Starting systemd-timesyncd.service... Sep 13 00:22:59.971278 systemd[1]: Starting systemd-update-utmp.service... Sep 13 00:22:59.972754 systemd[1]: Finished clean-ca-certificates.service. Sep 13 00:22:59.973000 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' Sep 13 00:22:59.975754 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 13 00:22:59.977204 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.978529 systemd[1]: Starting modprobe@dm_mod.service... Sep 13 00:22:59.979000 audit[1171]: SYSTEM_BOOT pid=1171 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' Sep 13 00:22:59.981607 systemd[1]: Starting modprobe@efi_pstore.service... Sep 13 00:22:59.983450 systemd[1]: Starting modprobe@loop.service... Sep 13 00:22:59.984097 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.984247 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). Sep 13 00:22:59.984350 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 13 00:22:59.985135 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 13 00:22:59.985291 systemd[1]: Finished modprobe@dm_mod.service. Sep 13 00:22:59.985000 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' Sep 13 00:22:59.985000 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' Sep 13 00:22:59.986405 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 13 00:22:59.986532 systemd[1]: Finished modprobe@efi_pstore.service. Sep 13 00:22:59.987000 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' Sep 13 00:22:59.987000 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' Sep 13 00:22:59.987615 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 13 00:22:59.987718 systemd[1]: Finished modprobe@loop.service. Sep 13 00:22:59.988000 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' Sep 13 00:22:59.988000 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' Sep 13 00:22:59.990170 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 13 00:22:59.990343 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.992401 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.993629 systemd[1]: Starting modprobe@dm_mod.service... Sep 13 00:22:59.995478 systemd[1]: Starting modprobe@efi_pstore.service... Sep 13 00:22:59.997183 systemd[1]: Starting modprobe@loop.service... Sep 13 00:22:59.997867 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Sep 13 00:22:59.998003 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). Sep 13 00:22:59.998110 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 13 00:22:59.998920 systemd[1]: Finished systemd-journal-catalog-update.service. Sep 13 00:22:59.999000 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' Sep 13 00:23:00.000274 systemd[1]: Finished systemd-update-utmp.service. Sep 13 00:23:00.000000 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' Sep 13 00:23:00.001524 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 13 00:23:00.001633 systemd[1]: Finished modprobe@dm_mod.service. Sep 13 00:23:00.002000 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' Sep 13 00:23:00.002000 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' Sep 13 00:23:00.002765 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 13 00:23:00.002875 systemd[1]: Finished modprobe@efi_pstore.service. Sep 13 00:23:00.003000 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' Sep 13 00:23:00.003000 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' Sep 13 00:23:00.004112 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 13 00:23:00.004252 systemd[1]: Finished modprobe@loop.service. Sep 13 00:23:00.004000 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' Sep 13 00:23:00.004000 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' Sep 13 00:23:00.006057 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 13 00:23:00.006162 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Sep 13 00:23:00.008648 systemd[1]: Starting systemd-update-done.service... Sep 13 00:23:00.011927 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Sep 13 00:23:00.013111 systemd[1]: Starting modprobe@dm_mod.service... Sep 13 00:23:00.014900 systemd[1]: Starting modprobe@drm.service... Sep 13 00:23:00.016753 systemd[1]: Starting modprobe@efi_pstore.service... Sep 13 00:23:00.018586 systemd[1]: Starting modprobe@loop.service... Sep 13 00:23:00.019394 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Sep 13 00:23:00.019526 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). Sep 13 00:23:00.020666 systemd[1]: Starting systemd-networkd-wait-online.service... Sep 13 00:23:00.021533 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 13 00:23:00.022524 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 13 00:23:00.022657 systemd[1]: Finished modprobe@dm_mod.service. Sep 13 00:23:00.024000 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' Sep 13 00:23:00.024000 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' Sep 13 00:23:00.024571 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 13 00:23:00.024688 systemd[1]: Finished modprobe@drm.service. Sep 13 00:23:00.025000 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' Sep 13 00:23:00.025000 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' Sep 13 00:23:00.025728 systemd[1]: Finished systemd-update-done.service. Sep 13 00:23:00.026000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Sep 13 00:23:00.026000 audit[1192]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffdc978660 a2=420 a3=0 items=0 ppid=1160 pid=1192 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) Sep 13 00:23:00.026000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Sep 13 00:23:00.026579 augenrules[1192]: No rules Sep 13 00:23:00.026774 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 13 00:23:00.026893 systemd[1]: Finished modprobe@efi_pstore.service. Sep 13 00:23:00.027927 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 13 00:23:00.028036 systemd[1]: Finished modprobe@loop.service. Sep 13 00:23:00.029171 systemd[1]: Started systemd-timesyncd.service. Sep 13 00:23:00.029310 systemd-timesyncd[1168]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 13 00:23:00.029355 systemd-timesyncd[1168]: Initial clock synchronization to Sat 2025-09-13 00:22:59.981173 UTC. Sep 13 00:23:00.030497 systemd[1]: Finished audit-rules.service. Sep 13 00:23:00.032033 systemd-resolved[1166]: Positive Trust Anchors: Sep 13 00:23:00.032043 systemd-resolved[1166]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 13 00:23:00.032070 systemd-resolved[1166]: 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 Sep 13 00:23:00.032203 systemd[1]: Reached target time-set.target. Sep 13 00:23:00.032918 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 13 00:23:00.032954 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Sep 13 00:23:00.033240 systemd[1]: Finished ensure-sysext.service. Sep 13 00:23:00.042256 systemd-resolved[1166]: Defaulting to hostname 'linux'. Sep 13 00:23:00.043681 systemd[1]: Started systemd-resolved.service. Sep 13 00:23:00.044389 systemd[1]: Reached target network.target. Sep 13 00:23:00.044979 systemd[1]: Reached target nss-lookup.target. Sep 13 00:23:00.045644 systemd[1]: Reached target sysinit.target. Sep 13 00:23:00.046281 systemd[1]: Started motdgen.path. Sep 13 00:23:00.046822 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Sep 13 00:23:00.047800 systemd[1]: Started logrotate.timer. Sep 13 00:23:00.048452 systemd[1]: Started mdadm.timer. Sep 13 00:23:00.048974 systemd[1]: Started systemd-tmpfiles-clean.timer. Sep 13 00:23:00.049641 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 13 00:23:00.049669 systemd[1]: Reached target paths.target. Sep 13 00:23:00.050205 systemd[1]: Reached target timers.target. Sep 13 00:23:00.051069 systemd[1]: Listening on dbus.socket. Sep 13 00:23:00.052815 systemd[1]: Starting docker.socket... Sep 13 00:23:00.055904 systemd[1]: Listening on sshd.socket. Sep 13 00:23:00.056692 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). Sep 13 00:23:00.057108 systemd[1]: Listening on docker.socket. Sep 13 00:23:00.057858 systemd[1]: Reached target sockets.target. Sep 13 00:23:00.058476 systemd[1]: Reached target basic.target. Sep 13 00:23:00.059047 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Sep 13 00:23:00.059077 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Sep 13 00:23:00.060009 systemd[1]: Starting containerd.service... Sep 13 00:23:00.061598 systemd[1]: Starting dbus.service... Sep 13 00:23:00.063450 systemd[1]: Starting enable-oem-cloudinit.service... Sep 13 00:23:00.065379 systemd[1]: Starting extend-filesystems.service... Sep 13 00:23:00.066358 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Sep 13 00:23:00.067781 jq[1202]: false Sep 13 00:23:00.067517 systemd[1]: Starting motdgen.service... Sep 13 00:23:00.069371 systemd[1]: Starting ssh-key-proc-cmdline.service... Sep 13 00:23:00.071059 systemd[1]: Starting sshd-keygen.service... Sep 13 00:23:00.073982 systemd[1]: Starting systemd-logind.service... Sep 13 00:23:00.075295 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Sep 13 00:23:00.075369 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 13 00:23:00.075776 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 13 00:23:00.076442 systemd[1]: Starting update-engine.service... Sep 13 00:23:00.078319 systemd[1]: Starting update-ssh-keys-after-ignition.service... Sep 13 00:23:00.080702 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 13 00:23:00.080868 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Sep 13 00:23:00.081139 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 13 00:23:00.081309 systemd[1]: Finished ssh-key-proc-cmdline.service. Sep 13 00:23:00.081940 jq[1215]: true Sep 13 00:23:00.090013 jq[1217]: true Sep 13 00:23:00.093079 systemd[1]: motdgen.service: Deactivated successfully. Sep 13 00:23:00.093281 systemd[1]: Finished motdgen.service. Sep 13 00:23:00.097098 extend-filesystems[1203]: Found loop1 Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda1 Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda2 Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda3 Sep 13 00:23:00.098178 extend-filesystems[1203]: Found usr Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda4 Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda6 Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda7 Sep 13 00:23:00.098178 extend-filesystems[1203]: Found vda9 Sep 13 00:23:00.098178 extend-filesystems[1203]: Checking size of /dev/vda9 Sep 13 00:23:00.115378 extend-filesystems[1203]: Resized partition /dev/vda9 Sep 13 00:23:00.102566 systemd[1]: Started dbus.service. Sep 13 00:23:00.102408 dbus-daemon[1201]: [system] SELinux support is enabled Sep 13 00:23:00.116705 extend-filesystems[1241]: resize2fs 1.46.5 (30-Dec-2021) Sep 13 00:23:00.105331 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). Sep 13 00:23:00.105355 systemd[1]: Reached target system-config.target. Sep 13 00:23:00.106373 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 13 00:23:00.119209 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 13 00:23:00.106395 systemd[1]: Reached target user-config.target. Sep 13 00:23:00.136514 update_engine[1214]: I0913 00:23:00.136231 1214 main.cc:92] Flatcar Update Engine starting Sep 13 00:23:00.138912 systemd[1]: Started update-engine.service. Sep 13 00:23:00.139080 update_engine[1214]: I0913 00:23:00.138944 1214 update_check_scheduler.cc:74] Next update check in 6m9s Sep 13 00:23:00.142627 systemd[1]: Started locksmithd.service. Sep 13 00:23:00.144565 systemd-logind[1211]: Watching system buttons on /dev/input/event0 (Power Button) Sep 13 00:23:00.144945 systemd-logind[1211]: New seat seat0. Sep 13 00:23:00.148120 systemd[1]: Started systemd-logind.service. Sep 13 00:23:00.155210 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 13 00:23:00.172973 extend-filesystems[1241]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 13 00:23:00.172973 extend-filesystems[1241]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 13 00:23:00.172973 extend-filesystems[1241]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 13 00:23:00.176876 extend-filesystems[1203]: Resized filesystem in /dev/vda9 Sep 13 00:23:00.175688 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 13 00:23:00.178475 env[1218]: time="2025-09-13T00:23:00.173803680Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Sep 13 00:23:00.178651 bash[1249]: Updated "/home/core/.ssh/authorized_keys" Sep 13 00:23:00.175852 systemd[1]: Finished extend-filesystems.service. Sep 13 00:23:00.178249 systemd[1]: Finished update-ssh-keys-after-ignition.service. Sep 13 00:23:00.192099 env[1218]: time="2025-09-13T00:23:00.192060720Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 13 00:23:00.192267 env[1218]: time="2025-09-13T00:23:00.192245800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 13 00:23:00.193577 env[1218]: time="2025-09-13T00:23:00.193547720Z" 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.192-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 13 00:23:00.193623 env[1218]: time="2025-09-13T00:23:00.193583120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 13 00:23:00.193812 env[1218]: time="2025-09-13T00:23:00.193787360Z" 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 Sep 13 00:23:00.193812 env[1218]: time="2025-09-13T00:23:00.193810520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 13 00:23:00.193872 env[1218]: time="2025-09-13T00:23:00.193823920Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Sep 13 00:23:00.193872 env[1218]: time="2025-09-13T00:23:00.193832920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 13 00:23:00.194028 env[1218]: time="2025-09-13T00:23:00.193899040Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 13 00:23:00.194242 env[1218]: time="2025-09-13T00:23:00.194222120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 13 00:23:00.194368 env[1218]: time="2025-09-13T00:23:00.194348960Z" 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 Sep 13 00:23:00.194401 env[1218]: time="2025-09-13T00:23:00.194368840Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 13 00:23:00.194441 env[1218]: time="2025-09-13T00:23:00.194423400Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Sep 13 00:23:00.194441 env[1218]: time="2025-09-13T00:23:00.194439520Z" level=info msg="metadata content store policy set" policy=shared Sep 13 00:23:00.194828 locksmithd[1250]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 13 00:23:00.198493 env[1218]: time="2025-09-13T00:23:00.198466640Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 13 00:23:00.198542 env[1218]: time="2025-09-13T00:23:00.198501360Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 13 00:23:00.198542 env[1218]: time="2025-09-13T00:23:00.198515440Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 13 00:23:00.198593 env[1218]: time="2025-09-13T00:23:00.198544680Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.198593 env[1218]: time="2025-09-13T00:23:00.198558760Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.198593 env[1218]: time="2025-09-13T00:23:00.198573280Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.198593 env[1218]: time="2025-09-13T00:23:00.198591400Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.198954 env[1218]: time="2025-09-13T00:23:00.198928760Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.198983 env[1218]: time="2025-09-13T00:23:00.198957400Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.198983 env[1218]: time="2025-09-13T00:23:00.198975800Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.199024 env[1218]: time="2025-09-13T00:23:00.198988040Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.199024 env[1218]: time="2025-09-13T00:23:00.198999760Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 13 00:23:00.199144 env[1218]: time="2025-09-13T00:23:00.199121840Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 13 00:23:00.199256 env[1218]: time="2025-09-13T00:23:00.199239440Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 13 00:23:00.199491 env[1218]: time="2025-09-13T00:23:00.199472560Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 13 00:23:00.199519 env[1218]: time="2025-09-13T00:23:00.199501960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199519 env[1218]: time="2025-09-13T00:23:00.199516280Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 13 00:23:00.199635 env[1218]: time="2025-09-13T00:23:00.199620040Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199664 env[1218]: time="2025-09-13T00:23:00.199635960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199664 env[1218]: time="2025-09-13T00:23:00.199648240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199664 env[1218]: time="2025-09-13T00:23:00.199660000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199718 env[1218]: time="2025-09-13T00:23:00.199671960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199718 env[1218]: time="2025-09-13T00:23:00.199688320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199718 env[1218]: time="2025-09-13T00:23:00.199703040Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199718 env[1218]: time="2025-09-13T00:23:00.199714160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199796 env[1218]: time="2025-09-13T00:23:00.199726240Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 13 00:23:00.199863 env[1218]: time="2025-09-13T00:23:00.199842480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199897 env[1218]: time="2025-09-13T00:23:00.199864880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199897 env[1218]: time="2025-09-13T00:23:00.199880120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.199938 env[1218]: time="2025-09-13T00:23:00.199902800Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 13 00:23:00.199938 env[1218]: time="2025-09-13T00:23:00.199916960Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Sep 13 00:23:00.199938 env[1218]: time="2025-09-13T00:23:00.199927240Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 13 00:23:00.200000 env[1218]: time="2025-09-13T00:23:00.199945280Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Sep 13 00:23:00.200000 env[1218]: time="2025-09-13T00:23:00.199978200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 13 00:23:00.200248 env[1218]: time="2025-09-13T00:23:00.200179960Z" 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}" Sep 13 00:23:00.200851 env[1218]: time="2025-09-13T00:23:00.200254240Z" level=info msg="Connect containerd service" Sep 13 00:23:00.201099 env[1218]: time="2025-09-13T00:23:00.201050320Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 13 00:23:00.201825 env[1218]: time="2025-09-13T00:23:00.201770840Z" 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" Sep 13 00:23:00.202027 env[1218]: time="2025-09-13T00:23:00.201990960Z" level=info msg="Start subscribing containerd event" Sep 13 00:23:00.202062 env[1218]: time="2025-09-13T00:23:00.202049560Z" level=info msg="Start recovering state" Sep 13 00:23:00.202120 env[1218]: time="2025-09-13T00:23:00.202099520Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 13 00:23:00.202152 env[1218]: time="2025-09-13T00:23:00.202112200Z" level=info msg="Start event monitor" Sep 13 00:23:00.202152 env[1218]: time="2025-09-13T00:23:00.202138240Z" level=info msg="Start snapshots syncer" Sep 13 00:23:00.202152 env[1218]: time="2025-09-13T00:23:00.202149200Z" level=info msg="Start cni network conf syncer for default" Sep 13 00:23:00.202227 env[1218]: time="2025-09-13T00:23:00.202156720Z" level=info msg="Start streaming server" Sep 13 00:23:00.202227 env[1218]: time="2025-09-13T00:23:00.202141600Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 13 00:23:00.202268 env[1218]: time="2025-09-13T00:23:00.202250280Z" level=info msg="containerd successfully booted in 0.035955s" Sep 13 00:23:00.202323 systemd[1]: Started containerd.service. Sep 13 00:23:00.968367 systemd-networkd[1056]: eth0: Gained IPv6LL Sep 13 00:23:00.970949 systemd[1]: Finished systemd-networkd-wait-online.service. Sep 13 00:23:00.972518 systemd[1]: Reached target network-online.target. Sep 13 00:23:00.974980 systemd[1]: Starting kubelet.service... Sep 13 00:23:01.622142 systemd[1]: Started kubelet.service. Sep 13 00:23:01.917910 sshd_keygen[1223]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 13 00:23:01.937095 systemd[1]: Finished sshd-keygen.service. Sep 13 00:23:01.939687 systemd[1]: Starting issuegen.service... Sep 13 00:23:01.944786 systemd[1]: issuegen.service: Deactivated successfully. Sep 13 00:23:01.944958 systemd[1]: Finished issuegen.service. Sep 13 00:23:01.947417 systemd[1]: Starting systemd-user-sessions.service... Sep 13 00:23:01.954090 systemd[1]: Finished systemd-user-sessions.service. Sep 13 00:23:01.956968 systemd[1]: Started getty@tty1.service. Sep 13 00:23:01.959360 systemd[1]: Started serial-getty@ttyAMA0.service. Sep 13 00:23:01.960589 systemd[1]: Reached target getty.target. Sep 13 00:23:01.961575 systemd[1]: Reached target multi-user.target. Sep 13 00:23:01.963747 systemd[1]: Starting systemd-update-utmp-runlevel.service... Sep 13 00:23:01.970775 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Sep 13 00:23:01.970939 systemd[1]: Finished systemd-update-utmp-runlevel.service. Sep 13 00:23:01.972261 systemd[1]: Startup finished in 598ms (kernel) + 4.101s (initrd) + 5.295s (userspace) = 9.995s. Sep 13 00:23:02.093586 kubelet[1266]: E0913 00:23:02.093542 1266 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" Sep 13 00:23:02.095563 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 13 00:23:02.095686 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 13 00:23:04.789321 systemd[1]: Created slice system-sshd.slice. Sep 13 00:23:04.796315 systemd[1]: Started sshd@0-10.0.0.118:22-10.0.0.1:52974.service. Sep 13 00:23:04.841381 sshd[1288]: Accepted publickey for core from 10.0.0.1 port 52974 ssh2: RSA SHA256:IYYmYtZT7fhBES8dcJq//ghMZv88JUKT/A8TkXgi+lY Sep 13 00:23:04.843421 sshd[1288]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 13 00:23:04.856461 systemd[1]: Created slice user-500.slice. Sep 13 00:23:04.857705 systemd[1]: Starting user-runtime-dir@500.service... Sep 13 00:23:04.860061 systemd-logind[1211]: New session 1 of user core. Sep 13 00:23:04.866244 systemd[1]: Finished user-runtime-dir@500.service. Sep 13 00:23:04.867635 systemd[1]: Starting user@500.service... Sep 13 00:23:04.870629 (systemd)[1291]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 13 00:23:04.943428 systemd[1291]: Queued start job for default target default.target. Sep 13 00:23:04.943928 systemd[1291]: Reached target paths.target. Sep 13 00:23:04.943967 systemd[1291]: Reached target sockets.target. Sep 13 00:23:04.943979 systemd[1291]: Reached target timers.target. Sep 13 00:23:04.943989 systemd[1291]: Reached target basic.target. Sep 13 00:23:04.944026 systemd[1291]: Reached target default.target. Sep 13 00:23:04.944050 systemd[1291]: Startup finished in 67ms. Sep 13 00:23:04.944131 systemd[1]: Started user@500.service. Sep 13 00:23:04.945456 systemd[1]: Started session-1.scope. Sep 13 00:23:05.001842 systemd[1]: Started sshd@1-10.0.0.118:22-10.0.0.1:52986.service. Sep 13 00:23:05.039429 sshd[1300]: Accepted publickey for core from 10.0.0.1 port 52986 ssh2: RSA SHA256:IYYmYtZT7fhBES8dcJq//ghMZv88JUKT/A8TkXgi+lY Sep 13 00:23:05.039921 sshd[1300]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 13 00:23:05.046976 systemd-logind[1211]: New session 2 of user core. Sep 13 00:23:05.048728 systemd[1]: Started session-2.scope. Sep 13 00:23:05.114140 sshd[1300]: pam_unix(sshd:session): session closed for user core Sep 13 00:23:05.117631 systemd[1]: Started sshd@2-10.0.0.118:22-10.0.0.1:52996.service. Sep 13 00:23:05.118098 systemd[1]: sshd@1-10.0.0.118:22-10.0.0.1:52986.service: Deactivated successfully. Sep 13 00:23:05.118729 systemd[1]: session-2.scope: Deactivated successfully. Sep 13 00:23:05.119267 systemd-logind[1211]: Session 2 logged out. Waiting for processes to exit. Sep 13 00:23:05.120093 systemd-logind[1211]: Removed session 2. Sep 13 00:23:05.158712 sshd[1305]: Accepted publickey for core from 10.0.0.1 port 52996 ssh2: RSA SHA256:IYYmYtZT7fhBES8dcJq//ghMZv88JUKT/A8TkXgi+lY Sep 13 00:23:05.160062 sshd[1305]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 13 00:23:05.164242 systemd-logind[1211]: New session 3 of user core. Sep 13 00:23:05.165269 systemd[1]: Started session-3.scope. Sep 13 00:23:05.218586 sshd[1305]: pam_unix(sshd:session): session closed for user core Sep 13 00:23:05.221341 systemd[1]: Started sshd@3-10.0.0.118:22-10.0.0.1:53012.service. Sep 13 00:23:05.221822 systemd[1]: sshd@2-10.0.0.118:22-10.0.0.1:52996.service: Deactivated successfully. Sep 13 00:23:05.223106 systemd[1]: session-3.scope: Deactivated successfully. Sep 13 00:23:05.223674 systemd-logind[1211]: Session 3 logged out. Waiting for processes to exit. Sep 13 00:23:05.225018 systemd-logind[1211]: Removed session 3. Sep 13 00:23:05.258456 sshd[1311]: Accepted publickey for core from 10.0.0.1 port 53012 ssh2: RSA SHA256:IYYmYtZT7fhBES8dcJq//ghMZv88JUKT/A8TkXgi+lY Sep 13 00:23:05.259943 sshd[1311]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 13 00:23:05.264246 systemd[1]: Started session-4.scope. Sep 13 00:23:05.264559 systemd-logind[1211]: New session 4 of user core. Sep 13 00:23:05.320861 sshd[1311]: pam_unix(sshd:session): session closed for user core Sep 13 00:23:05.324343 systemd[1]: Started sshd@4-10.0.0.118:22-10.0.0.1:53022.service. Sep 13 00:23:05.325049 systemd[1]: sshd@3-10.0.0.118:22-10.0.0.1:53012.service: Deactivated successfully. Sep 13 00:23:05.325820 systemd[1]: session-4.scope: Deactivated successfully. Sep 13 00:23:05.326698 systemd-logind[1211]: Session 4 logged out. Waiting for processes to exit. Sep 13 00:23:05.327580 systemd-logind[1211]: Removed session 4. Sep 13 00:23:05.364969 sshd[1317]: Accepted publickey for core from 10.0.0.1 port 53022 ssh2: RSA SHA256:IYYmYtZT7fhBES8dcJq//ghMZv88JUKT/A8TkXgi+lY Sep 13 00:23:05.366582 sshd[1317]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 13 00:23:05.370243 systemd-logind[1211]: New session 5 of user core. Sep 13 00:23:05.370619 systemd[1]: Started session-5.scope. Sep 13 00:23:05.426946 sudo[1321]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 13 00:23:05.427167 sudo[1321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 13 00:23:05.439508 systemd[1]: Starting coreos-metadata.service... Sep 13 00:23:05.447897 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 13 00:23:05.448231 systemd[1]: Finished coreos-metadata.service. Sep 13 00:23:05.949690 systemd[1]: Stopped kubelet.service. Sep 13 00:23:05.951615 systemd[1]: Starting kubelet.service... Sep 13 00:23:05.978581 systemd[1]: Reloading. Sep 13 00:23:06.045438 /usr/lib/systemd/system-generators/torcx-generator[1380]: time="2025-09-13T00:23:06Z" 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.8 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.8 /var/lib/torcx/store]" Sep 13 00:23:06.045470 /usr/lib/systemd/system-generators/torcx-generator[1380]: time="2025-09-13T00:23:06Z" level=info msg="torcx already run" Sep 13 00:23:06.141487 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Sep 13 00:23:06.141507 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Sep 13 00:23:06.159251 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. Sep 13 00:23:06.238582 systemd[1]: Started kubelet.service. Sep 13 00:23:06.243591 systemd[1]: Stopping kubelet.service... Sep 13 00:23:06.244895 systemd[1]: kubelet.service: Deactivated successfully. Sep 13 00:23:06.245058 systemd[1]: Stopped kubelet.service. Sep 13 00:23:06.246694 systemd[1]: Starting kubelet.service... Sep 13 00:23:06.338564 systemd[1]: Started kubelet.service. Sep 13 00:23:06.374657 kubelet[1429]: 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. Sep 13 00:23:06.374657 kubelet[1429]: 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. Sep 13 00:23:06.374657 kubelet[1429]: 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. Sep 13 00:23:06.374984 kubelet[1429]: I0913 00:23:06.374698 1429 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 13 00:23:07.765068 kubelet[1429]: I0913 00:23:07.764707 1429 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Sep 13 00:23:07.765068 kubelet[1429]: I0913 00:23:07.764742 1429 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 13 00:23:07.765068 kubelet[1429]: I0913 00:23:07.764975 1429 server.go:934] "Client rotation is on, will bootstrap in background" Sep 13 00:23:07.791843 kubelet[1429]: I0913 00:23:07.791789 1429 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 13 00:23:07.799103 kubelet[1429]: E0913 00:23:07.799073 1429 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Sep 13 00:23:07.799103 kubelet[1429]: I0913 00:23:07.799102 1429 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Sep 13 00:23:07.803242 kubelet[1429]: I0913 00:23:07.803217 1429 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 13 00:23:07.804160 kubelet[1429]: I0913 00:23:07.804134 1429 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Sep 13 00:23:07.804329 kubelet[1429]: I0913 00:23:07.804292 1429 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 13 00:23:07.804504 kubelet[1429]: I0913 00:23:07.804331 1429 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.118","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":"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},{"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}],"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} Sep 13 00:23:07.804577 kubelet[1429]: I0913 00:23:07.804516 1429 topology_manager.go:138] "Creating topology manager with none policy" Sep 13 00:23:07.804577 kubelet[1429]: I0913 00:23:07.804526 1429 container_manager_linux.go:300] "Creating device plugin manager" Sep 13 00:23:07.804775 kubelet[1429]: I0913 00:23:07.804762 1429 state_mem.go:36] "Initialized new in-memory state store" Sep 13 00:23:07.813041 kubelet[1429]: I0913 00:23:07.813011 1429 kubelet.go:408] "Attempting to sync node with API server" Sep 13 00:23:07.813041 kubelet[1429]: I0913 00:23:07.813043 1429 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 13 00:23:07.813142 kubelet[1429]: I0913 00:23:07.813068 1429 kubelet.go:314] "Adding apiserver pod source" Sep 13 00:23:07.813254 kubelet[1429]: I0913 00:23:07.813242 1429 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 13 00:23:07.813302 kubelet[1429]: E0913 00:23:07.813271 1429 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:07.813347 kubelet[1429]: E0913 00:23:07.813313 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:07.817318 kubelet[1429]: I0913 00:23:07.817298 1429 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Sep 13 00:23:07.817992 kubelet[1429]: I0913 00:23:07.817965 1429 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 13 00:23:07.818136 kubelet[1429]: W0913 00:23:07.818126 1429 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 13 00:23:07.819132 kubelet[1429]: I0913 00:23:07.818964 1429 server.go:1274] "Started kubelet" Sep 13 00:23:07.819246 kubelet[1429]: I0913 00:23:07.819206 1429 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Sep 13 00:23:07.820297 kubelet[1429]: I0913 00:23:07.820227 1429 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 13 00:23:07.820497 kubelet[1429]: I0913 00:23:07.820478 1429 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 13 00:23:07.823197 kubelet[1429]: I0913 00:23:07.823169 1429 server.go:449] "Adding debug handlers to kubelet server" Sep 13 00:23:07.827002 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Sep 13 00:23:07.827580 kubelet[1429]: I0913 00:23:07.827544 1429 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 13 00:23:07.827970 kubelet[1429]: I0913 00:23:07.827891 1429 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 13 00:23:07.833928 kubelet[1429]: E0913 00:23:07.833901 1429 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 13 00:23:07.834679 kubelet[1429]: I0913 00:23:07.834647 1429 volume_manager.go:289] "Starting Kubelet Volume Manager" Sep 13 00:23:07.834909 kubelet[1429]: I0913 00:23:07.834892 1429 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Sep 13 00:23:07.835072 kubelet[1429]: I0913 00:23:07.835062 1429 reconciler.go:26] "Reconciler: start to sync state" Sep 13 00:23:07.835689 kubelet[1429]: E0913 00:23:07.835665 1429 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.118\" not found" Sep 13 00:23:07.836491 kubelet[1429]: I0913 00:23:07.836459 1429 factory.go:221] Registration of the systemd container factory successfully Sep 13 00:23:07.837031 kubelet[1429]: I0913 00:23:07.837006 1429 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 Sep 13 00:23:07.841127 kubelet[1429]: I0913 00:23:07.841104 1429 factory.go:221] Registration of the containerd container factory successfully Sep 13 00:23:07.851898 kubelet[1429]: I0913 00:23:07.851879 1429 cpu_manager.go:214] "Starting CPU manager" policy="none" Sep 13 00:23:07.852033 kubelet[1429]: I0913 00:23:07.852019 1429 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Sep 13 00:23:07.852107 kubelet[1429]: I0913 00:23:07.852098 1429 state_mem.go:36] "Initialized new in-memory state store" Sep 13 00:23:07.855604 kubelet[1429]: E0913 00:23:07.855578 1429 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.118\" not found" node="10.0.0.118" Sep 13 00:23:07.924139 kubelet[1429]: I0913 00:23:07.924111 1429 policy_none.go:49] "None policy: Start" Sep 13 00:23:07.924967 kubelet[1429]: I0913 00:23:07.924942 1429 memory_manager.go:170] "Starting memorymanager" policy="None" Sep 13 00:23:07.924967 kubelet[1429]: I0913 00:23:07.924968 1429 state_mem.go:35] "Initializing new in-memory state store" Sep 13 00:23:07.935832 kubelet[1429]: E0913 00:23:07.935810 1429 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.118\" not found" Sep 13 00:23:07.936815 systemd[1]: Created slice kubepods.slice. Sep 13 00:23:07.941289 systemd[1]: Created slice kubepods-besteffort.slice. Sep 13 00:23:07.949906 systemd[1]: Created slice kubepods-burstable.slice. Sep 13 00:23:07.953813 kubelet[1429]: I0913 00:23:07.953763 1429 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 13 00:23:07.955107 kubelet[1429]: I0913 00:23:07.955081 1429 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 13 00:23:07.955238 kubelet[1429]: I0913 00:23:07.955101 1429 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 13 00:23:07.955627 kubelet[1429]: I0913 00:23:07.955564 1429 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 13 00:23:07.958674 kubelet[1429]: E0913 00:23:07.958656 1429 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.118\" not found" Sep 13 00:23:07.979134 kubelet[1429]: I0913 00:23:07.979093 1429 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 13 00:23:07.979934 kubelet[1429]: I0913 00:23:07.979920 1429 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 13 00:23:07.979981 kubelet[1429]: I0913 00:23:07.979944 1429 status_manager.go:217] "Starting to sync pod status with apiserver" Sep 13 00:23:07.979981 kubelet[1429]: I0913 00:23:07.979965 1429 kubelet.go:2321] "Starting kubelet main sync loop" Sep 13 00:23:07.980031 kubelet[1429]: E0913 00:23:07.980007 1429 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 13 00:23:08.056260 kubelet[1429]: I0913 00:23:08.056124 1429 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.118" Sep 13 00:23:08.072637 kubelet[1429]: I0913 00:23:08.072586 1429 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.118" Sep 13 00:23:08.072637 kubelet[1429]: E0913 00:23:08.072633 1429 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.118\": node \"10.0.0.118\" not found" Sep 13 00:23:08.103515 kubelet[1429]: I0913 00:23:08.103476 1429 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 13 00:23:08.103871 env[1218]: time="2025-09-13T00:23:08.103815577Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 13 00:23:08.104302 kubelet[1429]: I0913 00:23:08.104272 1429 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 13 00:23:08.370425 sudo[1321]: pam_unix(sudo:session): session closed for user root Sep 13 00:23:08.375639 sshd[1317]: pam_unix(sshd:session): session closed for user core Sep 13 00:23:08.378258 systemd[1]: sshd@4-10.0.0.118:22-10.0.0.1:53022.service: Deactivated successfully. Sep 13 00:23:08.378907 systemd[1]: session-5.scope: Deactivated successfully. Sep 13 00:23:08.379507 systemd-logind[1211]: Session 5 logged out. Waiting for processes to exit. Sep 13 00:23:08.381617 systemd-logind[1211]: Removed session 5. Sep 13 00:23:08.768161 kubelet[1429]: I0913 00:23:08.768053 1429 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 13 00:23:08.768863 kubelet[1429]: W0913 00:23:08.768844 1429 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 Sep 13 00:23:08.768975 kubelet[1429]: W0913 00:23:08.768962 1429 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 Sep 13 00:23:08.769084 kubelet[1429]: W0913 00:23:08.769072 1429 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 Sep 13 00:23:08.814493 kubelet[1429]: E0913 00:23:08.814453 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:08.814724 kubelet[1429]: I0913 00:23:08.814701 1429 apiserver.go:52] "Watching apiserver" Sep 13 00:23:08.829430 systemd[1]: Created slice kubepods-besteffort-pod4cd51b0c_29b5_4d63_8287_d8c0e35fb91d.slice. Sep 13 00:23:08.835902 kubelet[1429]: I0913 00:23:08.835874 1429 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Sep 13 00:23:08.839564 kubelet[1429]: I0913 00:23:08.839535 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/4cd51b0c-29b5-4d63-8287-d8c0e35fb91d-lib-modules\") pod \"kube-proxy-bct46\" (UID: \"4cd51b0c-29b5-4d63-8287-d8c0e35fb91d\") " pod="kube-system/kube-proxy-bct46" Sep 13 00:23:08.839710 kubelet[1429]: I0913 00:23:08.839695 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1-run\") pod \"kube-flannel-ds-d8qzp\" (UID: \"bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1\") " pod="kube-flannel/kube-flannel-ds-d8qzp" Sep 13 00:23:08.839800 kubelet[1429]: I0913 00:23:08.839786 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1-cni\") pod \"kube-flannel-ds-d8qzp\" (UID: \"bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1\") " pod="kube-flannel/kube-flannel-ds-d8qzp" Sep 13 00:23:08.839869 kubelet[1429]: I0913 00:23:08.839857 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1-flannel-cfg\") pod \"kube-flannel-ds-d8qzp\" (UID: \"bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1\") " pod="kube-flannel/kube-flannel-ds-d8qzp" Sep 13 00:23:08.839947 kubelet[1429]: I0913 00:23:08.839930 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/4cd51b0c-29b5-4d63-8287-d8c0e35fb91d-kube-proxy\") pod \"kube-proxy-bct46\" (UID: \"4cd51b0c-29b5-4d63-8287-d8c0e35fb91d\") " pod="kube-system/kube-proxy-bct46" Sep 13 00:23:08.840087 kubelet[1429]: I0913 00:23:08.840072 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/4cd51b0c-29b5-4d63-8287-d8c0e35fb91d-xtables-lock\") pod \"kube-proxy-bct46\" (UID: \"4cd51b0c-29b5-4d63-8287-d8c0e35fb91d\") " pod="kube-system/kube-proxy-bct46" Sep 13 00:23:08.840160 kubelet[1429]: I0913 00:23:08.840147 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4dv8d\" (UniqueName: \"kubernetes.io/projected/4cd51b0c-29b5-4d63-8287-d8c0e35fb91d-kube-api-access-4dv8d\") pod \"kube-proxy-bct46\" (UID: \"4cd51b0c-29b5-4d63-8287-d8c0e35fb91d\") " pod="kube-system/kube-proxy-bct46" Sep 13 00:23:08.840243 kubelet[1429]: I0913 00:23:08.840231 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1-cni-plugin\") pod \"kube-flannel-ds-d8qzp\" (UID: \"bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1\") " pod="kube-flannel/kube-flannel-ds-d8qzp" Sep 13 00:23:08.840343 kubelet[1429]: I0913 00:23:08.840329 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1-xtables-lock\") pod \"kube-flannel-ds-d8qzp\" (UID: \"bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1\") " pod="kube-flannel/kube-flannel-ds-d8qzp" Sep 13 00:23:08.840413 kubelet[1429]: I0913 00:23:08.840401 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-576n6\" (UniqueName: \"kubernetes.io/projected/bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1-kube-api-access-576n6\") pod \"kube-flannel-ds-d8qzp\" (UID: \"bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1\") " pod="kube-flannel/kube-flannel-ds-d8qzp" Sep 13 00:23:08.853384 systemd[1]: Created slice kubepods-burstable-podbd7ad4bc_4892_485e_89d6_92cc4d6ba6b1.slice. Sep 13 00:23:08.950446 kubelet[1429]: I0913 00:23:08.950401 1429 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" Sep 13 00:23:09.152151 kubelet[1429]: E0913 00:23:09.151604 1429 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" Sep 13 00:23:09.152842 env[1218]: time="2025-09-13T00:23:09.152574820Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-bct46,Uid:4cd51b0c-29b5-4d63-8287-d8c0e35fb91d,Namespace:kube-system,Attempt:0,}" Sep 13 00:23:09.164905 kubelet[1429]: E0913 00:23:09.164873 1429 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" Sep 13 00:23:09.168852 env[1218]: time="2025-09-13T00:23:09.166859048Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-d8qzp,Uid:bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1,Namespace:kube-flannel,Attempt:0,}" Sep 13 00:23:09.739027 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2192380543.mount: Deactivated successfully. Sep 13 00:23:09.745214 env[1218]: time="2025-09-13T00:23:09.745162167Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.747821 env[1218]: time="2025-09-13T00:23:09.747101803Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.750818 env[1218]: time="2025-09-13T00:23:09.749908004Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.753966 env[1218]: time="2025-09-13T00:23:09.753075596Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.755206 env[1218]: time="2025-09-13T00:23:09.755153384Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.757758 env[1218]: time="2025-09-13T00:23:09.757726643Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.759098 env[1218]: time="2025-09-13T00:23:09.759072387Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.759945 env[1218]: time="2025-09-13T00:23:09.759880096Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:09.788884 env[1218]: time="2025-09-13T00:23:09.788742065Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 13 00:23:09.788884 env[1218]: time="2025-09-13T00:23:09.788815454Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 13 00:23:09.788884 env[1218]: time="2025-09-13T00:23:09.788826075Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 13 00:23:09.790306 env[1218]: time="2025-09-13T00:23:09.789134521Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 13 00:23:09.790306 env[1218]: time="2025-09-13T00:23:09.789159196Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 13 00:23:09.790306 env[1218]: time="2025-09-13T00:23:09.789168580Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 13 00:23:09.790306 env[1218]: time="2025-09-13T00:23:09.789279940Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/e1cdef6c2328530ff3a2c4eb60ff8a744136b4399628869d629417caaabd10da pid=1492 runtime=io.containerd.runc.v2 Sep 13 00:23:09.790306 env[1218]: time="2025-09-13T00:23:09.789046918Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f pid=1491 runtime=io.containerd.runc.v2 Sep 13 00:23:09.806866 systemd[1]: Started cri-containerd-e1cdef6c2328530ff3a2c4eb60ff8a744136b4399628869d629417caaabd10da.scope. Sep 13 00:23:09.811025 systemd[1]: Started cri-containerd-ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f.scope. Sep 13 00:23:09.815917 kubelet[1429]: E0913 00:23:09.815889 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:09.840797 env[1218]: time="2025-09-13T00:23:09.840755419Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-bct46,Uid:4cd51b0c-29b5-4d63-8287-d8c0e35fb91d,Namespace:kube-system,Attempt:0,} returns sandbox id \"e1cdef6c2328530ff3a2c4eb60ff8a744136b4399628869d629417caaabd10da\"" Sep 13 00:23:09.842559 kubelet[1429]: E0913 00:23:09.841842 1429 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" Sep 13 00:23:09.844167 env[1218]: time="2025-09-13T00:23:09.844126725Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.13\"" Sep 13 00:23:09.853888 env[1218]: time="2025-09-13T00:23:09.853851262Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-d8qzp,Uid:bd7ad4bc-4892-485e-89d6-92cc4d6ba6b1,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f\"" Sep 13 00:23:09.855035 kubelet[1429]: E0913 00:23:09.854593 1429 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" Sep 13 00:23:10.803386 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2121332934.mount: Deactivated successfully. Sep 13 00:23:10.816570 kubelet[1429]: E0913 00:23:10.816533 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:11.294068 env[1218]: time="2025-09-13T00:23:11.293949681Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.31.13,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:11.296112 env[1218]: time="2025-09-13T00:23:11.296084233Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:c15699f0b7002450249485b10f20211982dfd2bec4d61c86c35acebc659e794e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:11.297884 env[1218]: time="2025-09-13T00:23:11.297844934Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.31.13,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:11.299264 env[1218]: time="2025-09-13T00:23:11.299235101Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:a39637326e88d128d38da6ff2b2ceb4e856475887bfcb5f7a55734d4f63d9fae,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:11.299644 env[1218]: time="2025-09-13T00:23:11.299624766Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.13\" returns image reference \"sha256:c15699f0b7002450249485b10f20211982dfd2bec4d61c86c35acebc659e794e\"" Sep 13 00:23:11.301026 env[1218]: time="2025-09-13T00:23:11.300983542Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 13 00:23:11.302063 env[1218]: time="2025-09-13T00:23:11.301996623Z" level=info msg="CreateContainer within sandbox \"e1cdef6c2328530ff3a2c4eb60ff8a744136b4399628869d629417caaabd10da\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 13 00:23:11.320792 env[1218]: time="2025-09-13T00:23:11.320753345Z" level=info msg="CreateContainer within sandbox \"e1cdef6c2328530ff3a2c4eb60ff8a744136b4399628869d629417caaabd10da\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"9498de4515d720bf033579cca26c3e8b65d22790ec21af80ce6307b475bde28d\"" Sep 13 00:23:11.321537 env[1218]: time="2025-09-13T00:23:11.321464103Z" level=info msg="StartContainer for \"9498de4515d720bf033579cca26c3e8b65d22790ec21af80ce6307b475bde28d\"" Sep 13 00:23:11.340375 systemd[1]: Started cri-containerd-9498de4515d720bf033579cca26c3e8b65d22790ec21af80ce6307b475bde28d.scope. Sep 13 00:23:11.377266 env[1218]: time="2025-09-13T00:23:11.377163010Z" level=info msg="StartContainer for \"9498de4515d720bf033579cca26c3e8b65d22790ec21af80ce6307b475bde28d\" returns successfully" Sep 13 00:23:11.817274 kubelet[1429]: E0913 00:23:11.817237 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:11.989909 kubelet[1429]: E0913 00:23:11.989868 1429 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" Sep 13 00:23:12.817471 kubelet[1429]: E0913 00:23:12.817432 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:12.991696 kubelet[1429]: E0913 00:23:12.991658 1429 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" Sep 13 00:23:13.818585 kubelet[1429]: E0913 00:23:13.818532 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:14.819585 kubelet[1429]: E0913 00:23:14.819547 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:15.820164 kubelet[1429]: E0913 00:23:15.820114 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:16.282373 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2892640616.mount: Deactivated successfully. Sep 13 00:23:16.334157 env[1218]: time="2025-09-13T00:23:16.334091224Z" 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:[],}" Sep 13 00:23:16.335541 env[1218]: time="2025-09-13T00:23:16.335512880Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:16.337650 env[1218]: time="2025-09-13T00:23:16.337621112Z" 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:[],}" Sep 13 00:23:16.341295 env[1218]: time="2025-09-13T00:23:16.341256839Z" 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:[],}" Sep 13 00:23:16.342202 env[1218]: time="2025-09-13T00:23:16.342158049Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Sep 13 00:23:16.344672 env[1218]: time="2025-09-13T00:23:16.344635300Z" level=info msg="CreateContainer within sandbox \"ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 13 00:23:16.357410 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1862361749.mount: Deactivated successfully. Sep 13 00:23:16.404351 env[1218]: time="2025-09-13T00:23:16.404296309Z" level=info msg="CreateContainer within sandbox \"ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6\"" Sep 13 00:23:16.405159 env[1218]: time="2025-09-13T00:23:16.405129837Z" level=info msg="StartContainer for \"cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6\"" Sep 13 00:23:16.421395 systemd[1]: Started cri-containerd-cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6.scope. Sep 13 00:23:16.451888 systemd[1]: cri-containerd-cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6.scope: Deactivated successfully. Sep 13 00:23:16.452827 env[1218]: time="2025-09-13T00:23:16.452784602Z" level=info msg="StartContainer for \"cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6\" returns successfully" Sep 13 00:23:16.514911 env[1218]: time="2025-09-13T00:23:16.514855219Z" level=info msg="shim disconnected" id=cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6 Sep 13 00:23:16.514911 env[1218]: time="2025-09-13T00:23:16.514909437Z" level=warning msg="cleaning up after shim disconnected" id=cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6 namespace=k8s.io Sep 13 00:23:16.515121 env[1218]: time="2025-09-13T00:23:16.514921424Z" level=info msg="cleaning up dead shim" Sep 13 00:23:16.522252 env[1218]: time="2025-09-13T00:23:16.522175777Z" level=warning msg="cleanup warnings time=\"2025-09-13T00:23:16Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1770 runtime=io.containerd.runc.v2\n" Sep 13 00:23:16.821391 kubelet[1429]: E0913 00:23:16.820615 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:16.998289 kubelet[1429]: E0913 00:23:16.998260 1429 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" Sep 13 00:23:16.999254 env[1218]: time="2025-09-13T00:23:16.999219935Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 13 00:23:17.015862 kubelet[1429]: I0913 00:23:17.015790 1429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-bct46" podStartSLOduration=7.558885523 podStartE2EDuration="9.015775067s" podCreationTimestamp="2025-09-13 00:23:08 +0000 UTC" firstStartedPulling="2025-09-13 00:23:09.843663597 +0000 UTC m=+3.501452478" lastFinishedPulling="2025-09-13 00:23:11.300553101 +0000 UTC m=+4.958342022" observedRunningTime="2025-09-13 00:23:12.002365905 +0000 UTC m=+5.660154786" watchObservedRunningTime="2025-09-13 00:23:17.015775067 +0000 UTC m=+10.673563948" Sep 13 00:23:17.184132 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-cbe9efb4eb02daa4c77a5764480191d731cf1a60c9d7da34cf9cfd10118f95b6-rootfs.mount: Deactivated successfully. Sep 13 00:23:17.821751 kubelet[1429]: E0913 00:23:17.821705 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:18.822296 kubelet[1429]: E0913 00:23:18.822241 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:19.823298 kubelet[1429]: E0913 00:23:19.823261 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:20.824306 kubelet[1429]: E0913 00:23:20.824265 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:21.825268 kubelet[1429]: E0913 00:23:21.825215 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:21.954792 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount584449691.mount: Deactivated successfully. Sep 13 00:23:22.735597 env[1218]: time="2025-09-13T00:23:22.735553110Z" 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:[],}" Sep 13 00:23:22.737739 env[1218]: time="2025-09-13T00:23:22.737702284Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:22.739480 env[1218]: time="2025-09-13T00:23:22.739445292Z" 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:[],}" Sep 13 00:23:22.741290 env[1218]: time="2025-09-13T00:23:22.741264962Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:22.742092 env[1218]: time="2025-09-13T00:23:22.742057028Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Sep 13 00:23:22.744171 env[1218]: time="2025-09-13T00:23:22.744140652Z" level=info msg="CreateContainer within sandbox \"ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 13 00:23:22.752021 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2776177558.mount: Deactivated successfully. Sep 13 00:23:22.758496 env[1218]: time="2025-09-13T00:23:22.758448960Z" level=info msg="CreateContainer within sandbox \"ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"d20a639b0f3735b57060ec163d9d30b3b979e9e71466905dc8f33ec136ac2295\"" Sep 13 00:23:22.759056 env[1218]: time="2025-09-13T00:23:22.758979508Z" level=info msg="StartContainer for \"d20a639b0f3735b57060ec163d9d30b3b979e9e71466905dc8f33ec136ac2295\"" Sep 13 00:23:22.772991 systemd[1]: Started cri-containerd-d20a639b0f3735b57060ec163d9d30b3b979e9e71466905dc8f33ec136ac2295.scope. Sep 13 00:23:22.799767 systemd[1]: cri-containerd-d20a639b0f3735b57060ec163d9d30b3b979e9e71466905dc8f33ec136ac2295.scope: Deactivated successfully. Sep 13 00:23:22.800408 env[1218]: time="2025-09-13T00:23:22.800373578Z" level=info msg="StartContainer for \"d20a639b0f3735b57060ec163d9d30b3b979e9e71466905dc8f33ec136ac2295\" returns successfully" Sep 13 00:23:22.825380 kubelet[1429]: E0913 00:23:22.825332 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:22.846836 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1315596243.mount: Deactivated successfully. Sep 13 00:23:22.866760 kubelet[1429]: I0913 00:23:22.866730 1429 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Sep 13 00:23:22.919446 env[1218]: time="2025-09-13T00:23:22.919382755Z" level=info msg="shim disconnected" id=d20a639b0f3735b57060ec163d9d30b3b979e9e71466905dc8f33ec136ac2295 Sep 13 00:23:22.919446 env[1218]: time="2025-09-13T00:23:22.919430998Z" level=warning msg="cleaning up after shim disconnected" id=d20a639b0f3735b57060ec163d9d30b3b979e9e71466905dc8f33ec136ac2295 namespace=k8s.io Sep 13 00:23:22.919446 env[1218]: time="2025-09-13T00:23:22.919440031Z" level=info msg="cleaning up dead shim" Sep 13 00:23:22.927930 env[1218]: time="2025-09-13T00:23:22.927891279Z" level=warning msg="cleanup warnings time=\"2025-09-13T00:23:22Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1827 runtime=io.containerd.runc.v2\n" Sep 13 00:23:23.010595 kubelet[1429]: E0913 00:23:23.009852 1429 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" Sep 13 00:23:23.012989 env[1218]: time="2025-09-13T00:23:23.012946424Z" level=info msg="CreateContainer within sandbox \"ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 13 00:23:23.024414 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2272159227.mount: Deactivated successfully. Sep 13 00:23:23.028538 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount254371666.mount: Deactivated successfully. Sep 13 00:23:23.031332 env[1218]: time="2025-09-13T00:23:23.031285536Z" level=info msg="CreateContainer within sandbox \"ca2b41df8daf4b8fd7267936d6616ca5c2a95a22c3634a2e81f4cfc2bc3bb68f\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"de9ce638c240430af6e4270620ec20e88910610ab9c9713dfb9a7f49eab904d1\"" Sep 13 00:23:23.032052 env[1218]: time="2025-09-13T00:23:23.032018723Z" level=info msg="StartContainer for \"de9ce638c240430af6e4270620ec20e88910610ab9c9713dfb9a7f49eab904d1\"" Sep 13 00:23:23.046430 systemd[1]: Started cri-containerd-de9ce638c240430af6e4270620ec20e88910610ab9c9713dfb9a7f49eab904d1.scope. Sep 13 00:23:23.081010 env[1218]: time="2025-09-13T00:23:23.080957996Z" level=info msg="StartContainer for \"de9ce638c240430af6e4270620ec20e88910610ab9c9713dfb9a7f49eab904d1\" returns successfully" Sep 13 00:23:23.825487 kubelet[1429]: E0913 00:23:23.825442 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:24.012161 kubelet[1429]: E0913 00:23:24.012131 1429 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" Sep 13 00:23:24.023803 kubelet[1429]: I0913 00:23:24.023697 1429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-d8qzp" podStartSLOduration=3.135912441 podStartE2EDuration="16.023681519s" podCreationTimestamp="2025-09-13 00:23:08 +0000 UTC" firstStartedPulling="2025-09-13 00:23:09.855276223 +0000 UTC m=+3.513065104" lastFinishedPulling="2025-09-13 00:23:22.743045302 +0000 UTC m=+16.400834182" observedRunningTime="2025-09-13 00:23:24.023616683 +0000 UTC m=+17.681405564" watchObservedRunningTime="2025-09-13 00:23:24.023681519 +0000 UTC m=+17.681470400" Sep 13 00:23:24.143359 systemd-networkd[1056]: flannel.1: Link UP Sep 13 00:23:24.143365 systemd-networkd[1056]: flannel.1: Gained carrier Sep 13 00:23:24.270307 systemd[1]: Created slice kubepods-besteffort-podedc70260_7cde_4a95_8450_c45685a0971d.slice. Sep 13 00:23:24.332426 kubelet[1429]: I0913 00:23:24.332380 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bc5z6\" (UniqueName: \"kubernetes.io/projected/edc70260-7cde-4a95-8450-c45685a0971d-kube-api-access-bc5z6\") pod \"nginx-deployment-8587fbcb89-mtqkc\" (UID: \"edc70260-7cde-4a95-8450-c45685a0971d\") " pod="default/nginx-deployment-8587fbcb89-mtqkc" Sep 13 00:23:24.573241 env[1218]: time="2025-09-13T00:23:24.573122619Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-mtqkc,Uid:edc70260-7cde-4a95-8450-c45685a0971d,Namespace:default,Attempt:0,}" Sep 13 00:23:24.594085 systemd-networkd[1056]: cni0: Link UP Sep 13 00:23:24.594091 systemd-networkd[1056]: cni0: Gained carrier Sep 13 00:23:24.594341 systemd-networkd[1056]: cni0: Lost carrier Sep 13 00:23:24.603130 systemd-networkd[1056]: vethbe054b2b: Link UP Sep 13 00:23:24.604507 kernel: cni0: port 1(vethbe054b2b) entered blocking state Sep 13 00:23:24.604587 kernel: cni0: port 1(vethbe054b2b) entered disabled state Sep 13 00:23:24.604609 kernel: device vethbe054b2b entered promiscuous mode Sep 13 00:23:24.605576 kernel: cni0: port 1(vethbe054b2b) entered blocking state Sep 13 00:23:24.605618 kernel: cni0: port 1(vethbe054b2b) entered forwarding state Sep 13 00:23:24.606229 kernel: cni0: port 1(vethbe054b2b) entered disabled state Sep 13 00:23:24.614372 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethbe054b2b: link becomes ready Sep 13 00:23:24.614465 kernel: cni0: port 1(vethbe054b2b) entered blocking state Sep 13 00:23:24.614485 kernel: cni0: port 1(vethbe054b2b) entered forwarding state Sep 13 00:23:24.615283 systemd-networkd[1056]: vethbe054b2b: Gained carrier Sep 13 00:23:24.615503 systemd-networkd[1056]: cni0: Gained carrier Sep 13 00:23:24.617673 env[1218]: 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)(0x400001a928), "name":"cbr0", "type":"bridge"} Sep 13 00:23:24.617673 env[1218]: delegateAdd: netconf sent to delegate plugin: Sep 13 00:23:24.631808 env[1218]: {"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-09-13T00:23:24.631722694Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 13 00:23:24.631808 env[1218]: time="2025-09-13T00:23:24.631771460Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 13 00:23:24.631808 env[1218]: time="2025-09-13T00:23:24.631782053Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 13 00:23:24.632077 env[1218]: time="2025-09-13T00:23:24.632047392Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/2aed85c351b166ec27409970550ff79789cd640add4060594ffbd3a7e9012850 pid=2010 runtime=io.containerd.runc.v2 Sep 13 00:23:24.646037 systemd[1]: Started cri-containerd-2aed85c351b166ec27409970550ff79789cd640add4060594ffbd3a7e9012850.scope. Sep 13 00:23:24.666210 systemd-resolved[1166]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 13 00:23:24.681744 env[1218]: time="2025-09-13T00:23:24.681703201Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-mtqkc,Uid:edc70260-7cde-4a95-8450-c45685a0971d,Namespace:default,Attempt:0,} returns sandbox id \"2aed85c351b166ec27409970550ff79789cd640add4060594ffbd3a7e9012850\"" Sep 13 00:23:24.683027 env[1218]: time="2025-09-13T00:23:24.683002956Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 13 00:23:24.825928 kubelet[1429]: E0913 00:23:24.825806 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:25.013899 kubelet[1429]: E0913 00:23:25.013855 1429 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" Sep 13 00:23:25.544362 systemd-networkd[1056]: flannel.1: Gained IPv6LL Sep 13 00:23:25.826641 kubelet[1429]: E0913 00:23:25.826519 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:26.504322 systemd-networkd[1056]: cni0: Gained IPv6LL Sep 13 00:23:26.568332 systemd-networkd[1056]: vethbe054b2b: Gained IPv6LL Sep 13 00:23:26.827010 kubelet[1429]: E0913 00:23:26.826889 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:27.373724 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3231701831.mount: Deactivated successfully. Sep 13 00:23:27.813483 kubelet[1429]: E0913 00:23:27.813359 1429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:27.827865 kubelet[1429]: E0913 00:23:27.827833 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:28.628198 env[1218]: time="2025-09-13T00:23:28.628131900Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:28.629532 env[1218]: time="2025-09-13T00:23:28.629496981Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:28.631397 env[1218]: time="2025-09-13T00:23:28.631364758Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:28.632975 env[1218]: time="2025-09-13T00:23:28.632944167Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:28.633719 env[1218]: time="2025-09-13T00:23:28.633683858Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 13 00:23:28.635988 env[1218]: time="2025-09-13T00:23:28.635947307Z" level=info msg="CreateContainer within sandbox \"2aed85c351b166ec27409970550ff79789cd640add4060594ffbd3a7e9012850\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 13 00:23:28.644494 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2137007210.mount: Deactivated successfully. Sep 13 00:23:28.647872 env[1218]: time="2025-09-13T00:23:28.647840049Z" level=info msg="CreateContainer within sandbox \"2aed85c351b166ec27409970550ff79789cd640add4060594ffbd3a7e9012850\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"2265412b2673906ed9d0b47f6f54caa2b0ced5cb3e5e528d2b4c99fcca7ceda7\"" Sep 13 00:23:28.648499 env[1218]: time="2025-09-13T00:23:28.648438654Z" level=info msg="StartContainer for \"2265412b2673906ed9d0b47f6f54caa2b0ced5cb3e5e528d2b4c99fcca7ceda7\"" Sep 13 00:23:28.666657 systemd[1]: Started cri-containerd-2265412b2673906ed9d0b47f6f54caa2b0ced5cb3e5e528d2b4c99fcca7ceda7.scope. Sep 13 00:23:28.692037 env[1218]: time="2025-09-13T00:23:28.691984899Z" level=info msg="StartContainer for \"2265412b2673906ed9d0b47f6f54caa2b0ced5cb3e5e528d2b4c99fcca7ceda7\" returns successfully" Sep 13 00:23:28.828998 kubelet[1429]: E0913 00:23:28.828954 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:29.028796 kubelet[1429]: I0913 00:23:29.028666 1429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-mtqkc" podStartSLOduration=1.07658394 podStartE2EDuration="5.028651394s" podCreationTimestamp="2025-09-13 00:23:24 +0000 UTC" firstStartedPulling="2025-09-13 00:23:24.682751887 +0000 UTC m=+18.340540728" lastFinishedPulling="2025-09-13 00:23:28.634819301 +0000 UTC m=+22.292608182" observedRunningTime="2025-09-13 00:23:29.02815268 +0000 UTC m=+22.685941561" watchObservedRunningTime="2025-09-13 00:23:29.028651394 +0000 UTC m=+22.686440275" Sep 13 00:23:29.829825 kubelet[1429]: E0913 00:23:29.829762 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:30.830648 kubelet[1429]: E0913 00:23:30.830604 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:30.984772 systemd[1]: Created slice kubepods-besteffort-pod595b9cc4_df72_4eae_aed2_c3f247969fc6.slice. Sep 13 00:23:31.070144 kubelet[1429]: I0913 00:23:31.070091 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/595b9cc4-df72-4eae-aed2-c3f247969fc6-data\") pod \"nfs-server-provisioner-0\" (UID: \"595b9cc4-df72-4eae-aed2-c3f247969fc6\") " pod="default/nfs-server-provisioner-0" Sep 13 00:23:31.070144 kubelet[1429]: I0913 00:23:31.070139 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-txz56\" (UniqueName: \"kubernetes.io/projected/595b9cc4-df72-4eae-aed2-c3f247969fc6-kube-api-access-txz56\") pod \"nfs-server-provisioner-0\" (UID: \"595b9cc4-df72-4eae-aed2-c3f247969fc6\") " pod="default/nfs-server-provisioner-0" Sep 13 00:23:31.287708 env[1218]: time="2025-09-13T00:23:31.287605491Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:595b9cc4-df72-4eae-aed2-c3f247969fc6,Namespace:default,Attempt:0,}" Sep 13 00:23:31.304423 systemd-networkd[1056]: vethe03d8e16: Link UP Sep 13 00:23:31.306615 kernel: cni0: port 2(vethe03d8e16) entered blocking state Sep 13 00:23:31.306702 kernel: cni0: port 2(vethe03d8e16) entered disabled state Sep 13 00:23:31.306724 kernel: device vethe03d8e16 entered promiscuous mode Sep 13 00:23:31.314436 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sep 13 00:23:31.314518 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethe03d8e16: link becomes ready Sep 13 00:23:31.314545 kernel: cni0: port 2(vethe03d8e16) entered blocking state Sep 13 00:23:31.314560 kernel: cni0: port 2(vethe03d8e16) entered forwarding state Sep 13 00:23:31.314983 systemd-networkd[1056]: vethe03d8e16: Gained carrier Sep 13 00:23:31.316505 env[1218]: 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"} Sep 13 00:23:31.316505 env[1218]: delegateAdd: netconf sent to delegate plugin: Sep 13 00:23:31.327616 env[1218]: {"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-09-13T00:23:31.327556210Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 13 00:23:31.327616 env[1218]: time="2025-09-13T00:23:31.327597872Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 13 00:23:31.327616 env[1218]: time="2025-09-13T00:23:31.327608588Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 13 00:23:31.327780 env[1218]: time="2025-09-13T00:23:31.327722378Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/c60e73f0111ee7ea4eb234cf416eb5d18ed55cd6f17eb33af30407e49dc5403b pid=2180 runtime=io.containerd.runc.v2 Sep 13 00:23:31.340626 systemd[1]: Started cri-containerd-c60e73f0111ee7ea4eb234cf416eb5d18ed55cd6f17eb33af30407e49dc5403b.scope. Sep 13 00:23:31.356672 systemd-resolved[1166]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 13 00:23:31.373044 env[1218]: time="2025-09-13T00:23:31.372999868Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:595b9cc4-df72-4eae-aed2-c3f247969fc6,Namespace:default,Attempt:0,} returns sandbox id \"c60e73f0111ee7ea4eb234cf416eb5d18ed55cd6f17eb33af30407e49dc5403b\"" Sep 13 00:23:31.374593 env[1218]: time="2025-09-13T00:23:31.374448720Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 13 00:23:31.831221 kubelet[1429]: E0913 00:23:31.831149 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:32.584333 systemd-networkd[1056]: vethe03d8e16: Gained IPv6LL Sep 13 00:23:32.831469 kubelet[1429]: E0913 00:23:32.831423 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:33.508523 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount763384710.mount: Deactivated successfully. Sep 13 00:23:33.832577 kubelet[1429]: E0913 00:23:33.832353 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:34.832816 kubelet[1429]: E0913 00:23:34.832761 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:35.297610 env[1218]: time="2025-09-13T00:23:35.297396549Z" 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:[],}" Sep 13 00:23:35.299373 env[1218]: time="2025-09-13T00:23:35.299333780Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:35.301707 env[1218]: time="2025-09-13T00:23:35.301682554Z" 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:[],}" Sep 13 00:23:35.303728 env[1218]: time="2025-09-13T00:23:35.303693640Z" 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:[],}" Sep 13 00:23:35.304412 env[1218]: time="2025-09-13T00:23:35.304379970Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 13 00:23:35.306812 env[1218]: time="2025-09-13T00:23:35.306780087Z" level=info msg="CreateContainer within sandbox \"c60e73f0111ee7ea4eb234cf416eb5d18ed55cd6f17eb33af30407e49dc5403b\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 13 00:23:35.318461 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2403202710.mount: Deactivated successfully. Sep 13 00:23:35.322380 env[1218]: time="2025-09-13T00:23:35.322320683Z" level=info msg="CreateContainer within sandbox \"c60e73f0111ee7ea4eb234cf416eb5d18ed55cd6f17eb33af30407e49dc5403b\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"cd52ba3bd00fc38714674fd05caa3d8f7fa1d6d96d1deba51caf3c54b06796b3\"" Sep 13 00:23:35.323045 env[1218]: time="2025-09-13T00:23:35.323006853Z" level=info msg="StartContainer for \"cd52ba3bd00fc38714674fd05caa3d8f7fa1d6d96d1deba51caf3c54b06796b3\"" Sep 13 00:23:35.338758 systemd[1]: Started cri-containerd-cd52ba3bd00fc38714674fd05caa3d8f7fa1d6d96d1deba51caf3c54b06796b3.scope. Sep 13 00:23:35.369218 env[1218]: time="2025-09-13T00:23:35.369104656Z" level=info msg="StartContainer for \"cd52ba3bd00fc38714674fd05caa3d8f7fa1d6d96d1deba51caf3c54b06796b3\" returns successfully" Sep 13 00:23:35.833670 kubelet[1429]: E0913 00:23:35.833626 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:36.044607 kubelet[1429]: I0913 00:23:36.044545 1429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.113182491 podStartE2EDuration="6.04453064s" podCreationTimestamp="2025-09-13 00:23:30 +0000 UTC" firstStartedPulling="2025-09-13 00:23:31.374181956 +0000 UTC m=+25.031970797" lastFinishedPulling="2025-09-13 00:23:35.305530065 +0000 UTC m=+28.963318946" observedRunningTime="2025-09-13 00:23:36.044351616 +0000 UTC m=+29.702140537" watchObservedRunningTime="2025-09-13 00:23:36.04453064 +0000 UTC m=+29.702319521" Sep 13 00:23:36.835297 kubelet[1429]: E0913 00:23:36.835243 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:37.835366 kubelet[1429]: E0913 00:23:37.835328 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:38.835670 kubelet[1429]: E0913 00:23:38.835616 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:39.836203 kubelet[1429]: E0913 00:23:39.836135 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:40.836836 kubelet[1429]: E0913 00:23:40.836780 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:41.837773 kubelet[1429]: E0913 00:23:41.837735 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:42.838595 kubelet[1429]: E0913 00:23:42.838553 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:43.839299 kubelet[1429]: E0913 00:23:43.839258 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:44.840691 kubelet[1429]: E0913 00:23:44.840650 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:44.979760 update_engine[1214]: I0913 00:23:44.979342 1214 update_attempter.cc:509] Updating boot flags... Sep 13 00:23:45.177420 systemd[1]: Created slice kubepods-besteffort-podb59d9662_8de5_44d5_9e25_caa835fbe0b2.slice. Sep 13 00:23:45.356779 kubelet[1429]: I0913 00:23:45.356741 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-03bc32d7-b80d-4954-92c5-706f1bba0ff6\" (UniqueName: \"kubernetes.io/nfs/b59d9662-8de5-44d5-9e25-caa835fbe0b2-pvc-03bc32d7-b80d-4954-92c5-706f1bba0ff6\") pod \"test-pod-1\" (UID: \"b59d9662-8de5-44d5-9e25-caa835fbe0b2\") " pod="default/test-pod-1" Sep 13 00:23:45.357892 kubelet[1429]: I0913 00:23:45.356781 1429 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cf8f7\" (UniqueName: \"kubernetes.io/projected/b59d9662-8de5-44d5-9e25-caa835fbe0b2-kube-api-access-cf8f7\") pod \"test-pod-1\" (UID: \"b59d9662-8de5-44d5-9e25-caa835fbe0b2\") " pod="default/test-pod-1" Sep 13 00:23:45.484346 kernel: FS-Cache: Loaded Sep 13 00:23:45.516507 kernel: RPC: Registered named UNIX socket transport module. Sep 13 00:23:45.516629 kernel: RPC: Registered udp transport module. Sep 13 00:23:45.516650 kernel: RPC: Registered tcp transport module. Sep 13 00:23:45.517586 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 13 00:23:45.561215 kernel: FS-Cache: Netfs 'nfs' registered for caching Sep 13 00:23:45.692298 kernel: NFS: Registering the id_resolver key type Sep 13 00:23:45.692422 kernel: Key type id_resolver registered Sep 13 00:23:45.692456 kernel: Key type id_legacy registered Sep 13 00:23:45.716923 nfsidmap[2376]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 13 00:23:45.720074 nfsidmap[2379]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 13 00:23:45.780693 env[1218]: time="2025-09-13T00:23:45.780214476Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b59d9662-8de5-44d5-9e25-caa835fbe0b2,Namespace:default,Attempt:0,}" Sep 13 00:23:45.807211 systemd-networkd[1056]: veth84f1c836: Link UP Sep 13 00:23:45.809473 kernel: cni0: port 3(veth84f1c836) entered blocking state Sep 13 00:23:45.809535 kernel: cni0: port 3(veth84f1c836) entered disabled state Sep 13 00:23:45.809556 kernel: device veth84f1c836 entered promiscuous mode Sep 13 00:23:45.822528 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sep 13 00:23:45.822598 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth84f1c836: link becomes ready Sep 13 00:23:45.822617 kernel: cni0: port 3(veth84f1c836) entered blocking state Sep 13 00:23:45.824490 kernel: cni0: port 3(veth84f1c836) entered forwarding state Sep 13 00:23:45.825719 systemd-networkd[1056]: veth84f1c836: Gained carrier Sep 13 00:23:45.827571 env[1218]: 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"} Sep 13 00:23:45.827571 env[1218]: delegateAdd: netconf sent to delegate plugin: Sep 13 00:23:45.841393 env[1218]: {"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-09-13T00:23:45.841312827Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 13 00:23:45.841489 env[1218]: time="2025-09-13T00:23:45.841404331Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 13 00:23:45.841489 env[1218]: time="2025-09-13T00:23:45.841443844Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 13 00:23:45.841805 kubelet[1429]: E0913 00:23:45.841744 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:45.842049 env[1218]: time="2025-09-13T00:23:45.841709357Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/66fb20dd923db749b0380034ee10a64254724a6a66a3891aa75b82ce93293b4b pid=2428 runtime=io.containerd.runc.v2 Sep 13 00:23:45.853838 systemd[1]: Started cri-containerd-66fb20dd923db749b0380034ee10a64254724a6a66a3891aa75b82ce93293b4b.scope. Sep 13 00:23:45.874586 systemd-resolved[1166]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 13 00:23:45.895210 env[1218]: time="2025-09-13T00:23:45.894367511Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b59d9662-8de5-44d5-9e25-caa835fbe0b2,Namespace:default,Attempt:0,} returns sandbox id \"66fb20dd923db749b0380034ee10a64254724a6a66a3891aa75b82ce93293b4b\"" Sep 13 00:23:45.896147 env[1218]: time="2025-09-13T00:23:45.896089208Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 13 00:23:46.140758 env[1218]: time="2025-09-13T00:23:46.139777163Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:46.143161 env[1218]: time="2025-09-13T00:23:46.143126291Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:46.145749 env[1218]: time="2025-09-13T00:23:46.145722184Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:46.148159 env[1218]: time="2025-09-13T00:23:46.148121589Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Sep 13 00:23:46.149094 env[1218]: time="2025-09-13T00:23:46.148930416Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 13 00:23:46.151656 env[1218]: time="2025-09-13T00:23:46.151611574Z" level=info msg="CreateContainer within sandbox \"66fb20dd923db749b0380034ee10a64254724a6a66a3891aa75b82ce93293b4b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 13 00:23:46.171082 env[1218]: time="2025-09-13T00:23:46.171025179Z" level=info msg="CreateContainer within sandbox \"66fb20dd923db749b0380034ee10a64254724a6a66a3891aa75b82ce93293b4b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a4120a2137f2e2944615ef201739a557660e258f04d2366289ec27959f60b6da\"" Sep 13 00:23:46.172460 env[1218]: time="2025-09-13T00:23:46.172076805Z" level=info msg="StartContainer for \"a4120a2137f2e2944615ef201739a557660e258f04d2366289ec27959f60b6da\"" Sep 13 00:23:46.193462 systemd[1]: Started cri-containerd-a4120a2137f2e2944615ef201739a557660e258f04d2366289ec27959f60b6da.scope. Sep 13 00:23:46.227385 env[1218]: time="2025-09-13T00:23:46.226538200Z" level=info msg="StartContainer for \"a4120a2137f2e2944615ef201739a557660e258f04d2366289ec27959f60b6da\" returns successfully" Sep 13 00:23:46.842712 kubelet[1429]: E0913 00:23:46.842601 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:47.072804 kubelet[1429]: I0913 00:23:47.072597 1429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.818079761 podStartE2EDuration="16.072580212s" podCreationTimestamp="2025-09-13 00:23:31 +0000 UTC" firstStartedPulling="2025-09-13 00:23:45.895790381 +0000 UTC m=+39.553579262" lastFinishedPulling="2025-09-13 00:23:46.150290832 +0000 UTC m=+39.808079713" observedRunningTime="2025-09-13 00:23:47.071958108 +0000 UTC m=+40.729746989" watchObservedRunningTime="2025-09-13 00:23:47.072580212 +0000 UTC m=+40.730369093" Sep 13 00:23:47.814171 kubelet[1429]: E0913 00:23:47.814123 1429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:47.816354 systemd-networkd[1056]: veth84f1c836: Gained IPv6LL Sep 13 00:23:47.843844 kubelet[1429]: E0913 00:23:47.843760 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:48.845113 kubelet[1429]: E0913 00:23:48.844680 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:49.845334 kubelet[1429]: E0913 00:23:49.845277 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:50.846067 kubelet[1429]: E0913 00:23:50.846025 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:51.847073 kubelet[1429]: E0913 00:23:51.847000 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:52.848018 kubelet[1429]: E0913 00:23:52.847974 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 13 00:23:53.848702 kubelet[1429]: E0913 00:23:53.848662 1429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"