May 13 00:18:20.782515 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 13 00:18:20.782536 kernel: Linux version 5.15.181-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 Mon May 12 23:22:00 -00 2025 May 13 00:18:20.782544 kernel: efi: EFI v2.70 by EDK II May 13 00:18:20.782550 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 May 13 00:18:20.782555 kernel: random: crng init done May 13 00:18:20.782560 kernel: ACPI: Early table checksum verification disabled May 13 00:18:20.782566 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) May 13 00:18:20.782573 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) May 13 00:18:20.782579 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782584 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782590 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782595 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782600 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782606 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782613 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782619 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782625 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 13 00:18:20.782631 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 13 00:18:20.782637 kernel: NUMA: Failed to initialise from firmware May 13 00:18:20.782642 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 13 00:18:20.782648 kernel: NUMA: NODE_DATA [mem 0xdcb0c900-0xdcb11fff] May 13 00:18:20.782654 kernel: Zone ranges: May 13 00:18:20.782660 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 13 00:18:20.782666 kernel: DMA32 empty May 13 00:18:20.782672 kernel: Normal empty May 13 00:18:20.782678 kernel: Movable zone start for each node May 13 00:18:20.782684 kernel: Early memory node ranges May 13 00:18:20.782689 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] May 13 00:18:20.782695 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] May 13 00:18:20.782701 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] May 13 00:18:20.782707 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] May 13 00:18:20.782713 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] May 13 00:18:20.782718 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] May 13 00:18:20.782724 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] May 13 00:18:20.782730 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 13 00:18:20.782737 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 13 00:18:20.782743 kernel: psci: probing for conduit method from ACPI. May 13 00:18:20.782749 kernel: psci: PSCIv1.1 detected in firmware. May 13 00:18:20.782754 kernel: psci: Using standard PSCI v0.2 function IDs May 13 00:18:20.782760 kernel: psci: Trusted OS migration not required May 13 00:18:20.782769 kernel: psci: SMC Calling Convention v1.1 May 13 00:18:20.782775 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 13 00:18:20.782782 kernel: ACPI: SRAT not present May 13 00:18:20.782789 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 May 13 00:18:20.782795 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 May 13 00:18:20.782801 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 13 00:18:20.782807 kernel: Detected PIPT I-cache on CPU0 May 13 00:18:20.782813 kernel: CPU features: detected: GIC system register CPU interface May 13 00:18:20.782819 kernel: CPU features: detected: Hardware dirty bit management May 13 00:18:20.782825 kernel: CPU features: detected: Spectre-v4 May 13 00:18:20.782831 kernel: CPU features: detected: Spectre-BHB May 13 00:18:20.782838 kernel: CPU features: kernel page table isolation forced ON by KASLR May 13 00:18:20.782844 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 13 00:18:20.782850 kernel: CPU features: detected: ARM erratum 1418040 May 13 00:18:20.782856 kernel: CPU features: detected: SSBS not fully self-synchronizing May 13 00:18:20.782862 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 13 00:18:20.782868 kernel: Policy zone: DMA May 13 00:18:20.782875 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=ae60136413c5686d5b1e9c38408a367f831e354d706496e9f743f02289aad53d May 13 00:18:20.782882 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 13 00:18:20.782888 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 13 00:18:20.782900 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 13 00:18:20.782906 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 13 00:18:20.782914 kernel: Memory: 2457344K/2572288K available (9792K kernel code, 2094K rwdata, 7584K rodata, 36480K init, 777K bss, 114944K reserved, 0K cma-reserved) May 13 00:18:20.782921 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 13 00:18:20.782927 kernel: trace event string verifier disabled May 13 00:18:20.782933 kernel: rcu: Preemptible hierarchical RCU implementation. May 13 00:18:20.782939 kernel: rcu: RCU event tracing is enabled. May 13 00:18:20.782946 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 13 00:18:20.782952 kernel: Trampoline variant of Tasks RCU enabled. May 13 00:18:20.782958 kernel: Tracing variant of Tasks RCU enabled. May 13 00:18:20.782964 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 13 00:18:20.782971 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 13 00:18:20.782977 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 13 00:18:20.782984 kernel: GICv3: 256 SPIs implemented May 13 00:18:20.782990 kernel: GICv3: 0 Extended SPIs implemented May 13 00:18:20.782996 kernel: GICv3: Distributor has no Range Selector support May 13 00:18:20.783002 kernel: Root IRQ handler: gic_handle_irq May 13 00:18:20.783008 kernel: GICv3: 16 PPIs implemented May 13 00:18:20.783014 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 13 00:18:20.783020 kernel: ACPI: SRAT not present May 13 00:18:20.783026 kernel: ITS [mem 0x08080000-0x0809ffff] May 13 00:18:20.783032 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) May 13 00:18:20.783038 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) May 13 00:18:20.783044 kernel: GICv3: using LPI property table @0x00000000400d0000 May 13 00:18:20.783053 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 May 13 00:18:20.783060 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 00:18:20.783070 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 13 00:18:20.783077 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 13 00:18:20.783083 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 13 00:18:20.783089 kernel: arm-pv: using stolen time PV May 13 00:18:20.783095 kernel: Console: colour dummy device 80x25 May 13 00:18:20.783101 kernel: ACPI: Core revision 20210730 May 13 00:18:20.783108 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 13 00:18:20.783115 kernel: pid_max: default: 32768 minimum: 301 May 13 00:18:20.783121 kernel: LSM: Security Framework initializing May 13 00:18:20.783128 kernel: SELinux: Initializing. May 13 00:18:20.783134 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 13 00:18:20.783141 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 13 00:18:20.783147 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 13 00:18:20.783153 kernel: rcu: Hierarchical SRCU implementation. May 13 00:18:20.783159 kernel: Platform MSI: ITS@0x8080000 domain created May 13 00:18:20.783166 kernel: PCI/MSI: ITS@0x8080000 domain created May 13 00:18:20.783172 kernel: Remapping and enabling EFI services. May 13 00:18:20.783178 kernel: smp: Bringing up secondary CPUs ... May 13 00:18:20.783185 kernel: Detected PIPT I-cache on CPU1 May 13 00:18:20.783191 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 13 00:18:20.783198 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 May 13 00:18:20.783204 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 00:18:20.783210 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 13 00:18:20.783217 kernel: Detected PIPT I-cache on CPU2 May 13 00:18:20.783223 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 13 00:18:20.783229 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 May 13 00:18:20.783242 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 00:18:20.783248 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 13 00:18:20.783256 kernel: Detected PIPT I-cache on CPU3 May 13 00:18:20.783262 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 13 00:18:20.783268 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 May 13 00:18:20.783275 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 00:18:20.783285 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 13 00:18:20.783299 kernel: smp: Brought up 1 node, 4 CPUs May 13 00:18:20.783306 kernel: SMP: Total of 4 processors activated. May 13 00:18:20.783313 kernel: CPU features: detected: 32-bit EL0 Support May 13 00:18:20.783319 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 13 00:18:20.783326 kernel: CPU features: detected: Common not Private translations May 13 00:18:20.783332 kernel: CPU features: detected: CRC32 instructions May 13 00:18:20.783339 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 13 00:18:20.783346 kernel: CPU features: detected: LSE atomic instructions May 13 00:18:20.783353 kernel: CPU features: detected: Privileged Access Never May 13 00:18:20.783360 kernel: CPU features: detected: RAS Extension Support May 13 00:18:20.783366 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 13 00:18:20.783373 kernel: CPU: All CPU(s) started at EL1 May 13 00:18:20.783380 kernel: alternatives: patching kernel code May 13 00:18:20.783387 kernel: devtmpfs: initialized May 13 00:18:20.783393 kernel: KASLR enabled May 13 00:18:20.783400 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 13 00:18:20.783406 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 13 00:18:20.783412 kernel: pinctrl core: initialized pinctrl subsystem May 13 00:18:20.783419 kernel: SMBIOS 3.0.0 present. May 13 00:18:20.783425 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 May 13 00:18:20.783432 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 13 00:18:20.783440 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 13 00:18:20.783447 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 13 00:18:20.783453 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 13 00:18:20.783460 kernel: audit: initializing netlink subsys (disabled) May 13 00:18:20.783466 kernel: audit: type=2000 audit(0.052:1): state=initialized audit_enabled=0 res=1 May 13 00:18:20.783473 kernel: thermal_sys: Registered thermal governor 'step_wise' May 13 00:18:20.783479 kernel: cpuidle: using governor menu May 13 00:18:20.783486 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 13 00:18:20.783492 kernel: ASID allocator initialised with 32768 entries May 13 00:18:20.783500 kernel: ACPI: bus type PCI registered May 13 00:18:20.783507 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 13 00:18:20.783513 kernel: Serial: AMBA PL011 UART driver May 13 00:18:20.783520 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages May 13 00:18:20.783526 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages May 13 00:18:20.783533 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages May 13 00:18:20.783539 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages May 13 00:18:20.783546 kernel: cryptd: max_cpu_qlen set to 1000 May 13 00:18:20.783552 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 13 00:18:20.783560 kernel: ACPI: Added _OSI(Module Device) May 13 00:18:20.783566 kernel: ACPI: Added _OSI(Processor Device) May 13 00:18:20.783573 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 13 00:18:20.783579 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 13 00:18:20.783586 kernel: ACPI: Added _OSI(Linux-Dell-Video) May 13 00:18:20.783593 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) May 13 00:18:20.783599 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) May 13 00:18:20.783606 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 13 00:18:20.783612 kernel: ACPI: Interpreter enabled May 13 00:18:20.783620 kernel: ACPI: Using GIC for interrupt routing May 13 00:18:20.783627 kernel: ACPI: MCFG table detected, 1 entries May 13 00:18:20.783634 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 13 00:18:20.783641 kernel: printk: console [ttyAMA0] enabled May 13 00:18:20.783647 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 13 00:18:20.783770 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 13 00:18:20.783836 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 13 00:18:20.783896 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 13 00:18:20.783954 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 13 00:18:20.784010 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 13 00:18:20.784018 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 13 00:18:20.784025 kernel: PCI host bridge to bus 0000:00 May 13 00:18:20.784093 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 13 00:18:20.784166 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 13 00:18:20.784219 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 13 00:18:20.784286 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 13 00:18:20.784387 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 13 00:18:20.784463 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 13 00:18:20.784527 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 13 00:18:20.784588 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 13 00:18:20.784648 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 13 00:18:20.784711 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 13 00:18:20.784772 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 13 00:18:20.784830 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 13 00:18:20.784885 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 13 00:18:20.784943 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 13 00:18:20.784998 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 13 00:18:20.785009 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 13 00:18:20.785019 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 13 00:18:20.785028 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 13 00:18:20.785034 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 13 00:18:20.785041 kernel: iommu: Default domain type: Translated May 13 00:18:20.785048 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 13 00:18:20.785055 kernel: vgaarb: loaded May 13 00:18:20.785061 kernel: pps_core: LinuxPPS API ver. 1 registered May 13 00:18:20.785068 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 13 00:18:20.785074 kernel: PTP clock support registered May 13 00:18:20.785081 kernel: Registered efivars operations May 13 00:18:20.785089 kernel: clocksource: Switched to clocksource arch_sys_counter May 13 00:18:20.785096 kernel: VFS: Disk quotas dquot_6.6.0 May 13 00:18:20.785102 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 13 00:18:20.785109 kernel: pnp: PnP ACPI init May 13 00:18:20.785173 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 13 00:18:20.785183 kernel: pnp: PnP ACPI: found 1 devices May 13 00:18:20.785189 kernel: NET: Registered PF_INET protocol family May 13 00:18:20.785196 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 13 00:18:20.785204 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 13 00:18:20.785211 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 13 00:18:20.785217 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 13 00:18:20.785224 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) May 13 00:18:20.785231 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 13 00:18:20.785245 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 13 00:18:20.785252 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 13 00:18:20.785258 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 13 00:18:20.785265 kernel: PCI: CLS 0 bytes, default 64 May 13 00:18:20.785273 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 13 00:18:20.785280 kernel: kvm [1]: HYP mode not available May 13 00:18:20.785286 kernel: Initialise system trusted keyrings May 13 00:18:20.785302 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 13 00:18:20.785309 kernel: Key type asymmetric registered May 13 00:18:20.785315 kernel: Asymmetric key parser 'x509' registered May 13 00:18:20.785322 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 13 00:18:20.785328 kernel: io scheduler mq-deadline registered May 13 00:18:20.785335 kernel: io scheduler kyber registered May 13 00:18:20.785343 kernel: io scheduler bfq registered May 13 00:18:20.785349 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 13 00:18:20.785356 kernel: ACPI: button: Power Button [PWRB] May 13 00:18:20.785363 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 13 00:18:20.785427 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 13 00:18:20.785436 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 13 00:18:20.785443 kernel: thunder_xcv, ver 1.0 May 13 00:18:20.785449 kernel: thunder_bgx, ver 1.0 May 13 00:18:20.785456 kernel: nicpf, ver 1.0 May 13 00:18:20.785464 kernel: nicvf, ver 1.0 May 13 00:18:20.785532 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 13 00:18:20.785589 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-13T00:18:20 UTC (1747095500) May 13 00:18:20.785598 kernel: hid: raw HID events driver (C) Jiri Kosina May 13 00:18:20.785605 kernel: NET: Registered PF_INET6 protocol family May 13 00:18:20.785611 kernel: Segment Routing with IPv6 May 13 00:18:20.785618 kernel: In-situ OAM (IOAM) with IPv6 May 13 00:18:20.785625 kernel: NET: Registered PF_PACKET protocol family May 13 00:18:20.785633 kernel: Key type dns_resolver registered May 13 00:18:20.785639 kernel: registered taskstats version 1 May 13 00:18:20.785646 kernel: Loading compiled-in X.509 certificates May 13 00:18:20.785653 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.181-flatcar: d291b704d59536a3c0ba96fd6f5a99459de8de99' May 13 00:18:20.785659 kernel: Key type .fscrypt registered May 13 00:18:20.785666 kernel: Key type fscrypt-provisioning registered May 13 00:18:20.785673 kernel: ima: No TPM chip found, activating TPM-bypass! May 13 00:18:20.785679 kernel: ima: Allocated hash algorithm: sha1 May 13 00:18:20.785686 kernel: ima: No architecture policies found May 13 00:18:20.785694 kernel: clk: Disabling unused clocks May 13 00:18:20.785700 kernel: Freeing unused kernel memory: 36480K May 13 00:18:20.785706 kernel: Run /init as init process May 13 00:18:20.785713 kernel: with arguments: May 13 00:18:20.785719 kernel: /init May 13 00:18:20.785725 kernel: with environment: May 13 00:18:20.785732 kernel: HOME=/ May 13 00:18:20.785738 kernel: TERM=linux May 13 00:18:20.785744 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 13 00:18:20.785754 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 13 00:18:20.785762 systemd[1]: Detected virtualization kvm. May 13 00:18:20.785770 systemd[1]: Detected architecture arm64. May 13 00:18:20.785777 systemd[1]: Running in initrd. May 13 00:18:20.785784 systemd[1]: No hostname configured, using default hostname. May 13 00:18:20.785791 systemd[1]: Hostname set to . May 13 00:18:20.785798 systemd[1]: Initializing machine ID from VM UUID. May 13 00:18:20.785806 systemd[1]: Queued start job for default target initrd.target. May 13 00:18:20.785813 systemd[1]: Started systemd-ask-password-console.path. May 13 00:18:20.785820 systemd[1]: Reached target cryptsetup.target. May 13 00:18:20.785827 systemd[1]: Reached target paths.target. May 13 00:18:20.785834 systemd[1]: Reached target slices.target. May 13 00:18:20.785841 systemd[1]: Reached target swap.target. May 13 00:18:20.785848 systemd[1]: Reached target timers.target. May 13 00:18:20.785856 systemd[1]: Listening on iscsid.socket. May 13 00:18:20.785864 systemd[1]: Listening on iscsiuio.socket. May 13 00:18:20.785871 systemd[1]: Listening on systemd-journald-audit.socket. May 13 00:18:20.785878 systemd[1]: Listening on systemd-journald-dev-log.socket. May 13 00:18:20.785885 systemd[1]: Listening on systemd-journald.socket. May 13 00:18:20.785892 systemd[1]: Listening on systemd-networkd.socket. May 13 00:18:20.785899 systemd[1]: Listening on systemd-udevd-control.socket. May 13 00:18:20.785906 systemd[1]: Listening on systemd-udevd-kernel.socket. May 13 00:18:20.785913 systemd[1]: Reached target sockets.target. May 13 00:18:20.785921 systemd[1]: Starting kmod-static-nodes.service... May 13 00:18:20.785928 systemd[1]: Finished network-cleanup.service. May 13 00:18:20.785935 systemd[1]: Starting systemd-fsck-usr.service... May 13 00:18:20.785942 systemd[1]: Starting systemd-journald.service... May 13 00:18:20.785949 systemd[1]: Starting systemd-modules-load.service... May 13 00:18:20.785956 systemd[1]: Starting systemd-resolved.service... May 13 00:18:20.785963 systemd[1]: Starting systemd-vconsole-setup.service... May 13 00:18:20.785970 systemd[1]: Finished kmod-static-nodes.service. May 13 00:18:20.785977 systemd[1]: Finished systemd-fsck-usr.service. May 13 00:18:20.785986 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... May 13 00:18:20.785993 systemd[1]: Finished systemd-vconsole-setup.service. May 13 00:18:20.786000 systemd[1]: Starting dracut-cmdline-ask.service... May 13 00:18:20.786007 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. May 13 00:18:20.786014 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 13 00:18:20.786021 kernel: Bridge firewalling registered May 13 00:18:20.786028 systemd[1]: Finished dracut-cmdline-ask.service. May 13 00:18:20.786035 kernel: audit: type=1130 audit(1747095500.783:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.786046 systemd-journald[289]: Journal started May 13 00:18:20.786086 systemd-journald[289]: Runtime Journal (/run/log/journal/10f446edbc314026aebd13a858d6e607) is 6.0M, max 48.7M, 42.6M free. May 13 00:18:20.783000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.758470 systemd-modules-load[290]: Inserted module 'overlay' May 13 00:18:20.787920 systemd[1]: Started systemd-resolved.service. May 13 00:18:20.780876 systemd-resolved[291]: Positive Trust Anchors: May 13 00:18:20.791845 kernel: audit: type=1130 audit(1747095500.788:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.788000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.780883 systemd-resolved[291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 00:18:20.794484 systemd[1]: Started systemd-journald.service. May 13 00:18:20.794505 kernel: SCSI subsystem initialized May 13 00:18:20.780909 systemd-resolved[291]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test May 13 00:18:20.802414 kernel: audit: type=1130 audit(1747095500.794: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' May 13 00:18:20.794000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.781971 systemd-modules-load[290]: Inserted module 'br_netfilter' May 13 00:18:20.805484 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 13 00:18:20.805506 kernel: device-mapper: uevent: version 1.0.3 May 13 00:18:20.785146 systemd-resolved[291]: Defaulting to hostname 'linux'. May 13 00:18:20.808493 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com May 13 00:18:20.799546 systemd[1]: Reached target nss-lookup.target. May 13 00:18:20.804007 systemd[1]: Starting dracut-cmdline.service... May 13 00:18:20.809210 systemd-modules-load[290]: Inserted module 'dm_multipath' May 13 00:18:20.811011 systemd[1]: Finished systemd-modules-load.service. May 13 00:18:20.811000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.813472 systemd[1]: Starting systemd-sysctl.service... May 13 00:18:20.817040 kernel: audit: type=1130 audit(1747095500.811:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.817143 dracut-cmdline[308]: dracut-dracut-053 May 13 00:18:20.817924 dracut-cmdline[308]: 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=ae60136413c5686d5b1e9c38408a367f831e354d706496e9f743f02289aad53d May 13 00:18:20.821224 systemd[1]: Finished systemd-sysctl.service. May 13 00:18:20.822000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.826328 kernel: audit: type=1130 audit(1747095500.822:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.883318 kernel: Loading iSCSI transport class v2.0-870. May 13 00:18:20.895318 kernel: iscsi: registered transport (tcp) May 13 00:18:20.910768 kernel: iscsi: registered transport (qla4xxx) May 13 00:18:20.910822 kernel: QLogic iSCSI HBA Driver May 13 00:18:20.944967 systemd[1]: Finished dracut-cmdline.service. May 13 00:18:20.945000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.946677 systemd[1]: Starting dracut-pre-udev.service... May 13 00:18:20.950129 kernel: audit: type=1130 audit(1747095500.945:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:20.992323 kernel: raid6: neonx8 gen() 13743 MB/s May 13 00:18:21.009328 kernel: raid6: neonx8 xor() 10819 MB/s May 13 00:18:21.026317 kernel: raid6: neonx4 gen() 13516 MB/s May 13 00:18:21.043318 kernel: raid6: neonx4 xor() 11017 MB/s May 13 00:18:21.060313 kernel: raid6: neonx2 gen() 12937 MB/s May 13 00:18:21.077308 kernel: raid6: neonx2 xor() 10383 MB/s May 13 00:18:21.094306 kernel: raid6: neonx1 gen() 10514 MB/s May 13 00:18:21.111322 kernel: raid6: neonx1 xor() 8765 MB/s May 13 00:18:21.128321 kernel: raid6: int64x8 gen() 6260 MB/s May 13 00:18:21.145316 kernel: raid6: int64x8 xor() 3536 MB/s May 13 00:18:21.162318 kernel: raid6: int64x4 gen() 7214 MB/s May 13 00:18:21.179321 kernel: raid6: int64x4 xor() 3853 MB/s May 13 00:18:21.196321 kernel: raid6: int64x2 gen() 6143 MB/s May 13 00:18:21.213339 kernel: raid6: int64x2 xor() 3318 MB/s May 13 00:18:21.230319 kernel: raid6: int64x1 gen() 5030 MB/s May 13 00:18:21.247505 kernel: raid6: int64x1 xor() 2640 MB/s May 13 00:18:21.247559 kernel: raid6: using algorithm neonx8 gen() 13743 MB/s May 13 00:18:21.247577 kernel: raid6: .... xor() 10819 MB/s, rmw enabled May 13 00:18:21.248627 kernel: raid6: using neon recovery algorithm May 13 00:18:21.260324 kernel: xor: measuring software checksum speed May 13 00:18:21.260359 kernel: 8regs : 17227 MB/sec May 13 00:18:21.261654 kernel: 32regs : 17940 MB/sec May 13 00:18:21.261665 kernel: arm64_neon : 27524 MB/sec May 13 00:18:21.261673 kernel: xor: using function: arm64_neon (27524 MB/sec) May 13 00:18:21.318343 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no May 13 00:18:21.329180 systemd[1]: Finished dracut-pre-udev.service. May 13 00:18:21.329000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:21.332000 audit: BPF prog-id=7 op=LOAD May 13 00:18:21.334066 kernel: audit: type=1130 audit(1747095501.329:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:21.334090 kernel: audit: type=1334 audit(1747095501.332:9): prog-id=7 op=LOAD May 13 00:18:21.334100 kernel: audit: type=1334 audit(1747095501.333:10): prog-id=8 op=LOAD May 13 00:18:21.333000 audit: BPF prog-id=8 op=LOAD May 13 00:18:21.334539 systemd[1]: Starting systemd-udevd.service... May 13 00:18:21.347205 systemd-udevd[493]: Using default interface naming scheme 'v252'. May 13 00:18:21.350652 systemd[1]: Started systemd-udevd.service. May 13 00:18:21.351000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:21.352734 systemd[1]: Starting dracut-pre-trigger.service... May 13 00:18:21.363611 dracut-pre-trigger[502]: rd.md=0: removing MD RAID activation May 13 00:18:21.392481 systemd[1]: Finished dracut-pre-trigger.service. May 13 00:18:21.392000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:21.394108 systemd[1]: Starting systemd-udev-trigger.service... May 13 00:18:21.428801 systemd[1]: Finished systemd-udev-trigger.service. May 13 00:18:21.429000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:21.451313 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 13 00:18:21.459854 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 13 00:18:21.459874 kernel: GPT:9289727 != 19775487 May 13 00:18:21.459893 kernel: GPT:Alternate GPT header not at the end of the disk. May 13 00:18:21.459905 kernel: GPT:9289727 != 19775487 May 13 00:18:21.459916 kernel: GPT: Use GNU Parted to correct GPT errors. May 13 00:18:21.459924 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 00:18:21.471315 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (543) May 13 00:18:21.472678 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. May 13 00:18:21.473744 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. May 13 00:18:21.478009 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. May 13 00:18:21.486874 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. May 13 00:18:21.490439 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 13 00:18:21.492906 systemd[1]: Starting disk-uuid.service... May 13 00:18:21.500770 disk-uuid[564]: Primary Header is updated. May 13 00:18:21.500770 disk-uuid[564]: Secondary Entries is updated. May 13 00:18:21.500770 disk-uuid[564]: Secondary Header is updated. May 13 00:18:21.505319 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 00:18:22.513980 disk-uuid[565]: The operation has completed successfully. May 13 00:18:22.515062 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 00:18:22.537839 systemd[1]: disk-uuid.service: Deactivated successfully. May 13 00:18:22.538000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.538000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.537939 systemd[1]: Finished disk-uuid.service. May 13 00:18:22.539538 systemd[1]: Starting verity-setup.service... May 13 00:18:22.559325 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 13 00:18:22.579753 systemd[1]: Found device dev-mapper-usr.device. May 13 00:18:22.581987 systemd[1]: Mounting sysusr-usr.mount... May 13 00:18:22.583819 systemd[1]: Finished verity-setup.service. May 13 00:18:22.584000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.649314 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. May 13 00:18:22.649553 systemd[1]: Mounted sysusr-usr.mount. May 13 00:18:22.650317 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. May 13 00:18:22.651117 systemd[1]: Starting ignition-setup.service... May 13 00:18:22.653357 systemd[1]: Starting parse-ip-for-networkd.service... May 13 00:18:22.660354 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 00:18:22.660396 kernel: BTRFS info (device vda6): using free space tree May 13 00:18:22.660406 kernel: BTRFS info (device vda6): has skinny extents May 13 00:18:22.669282 systemd[1]: mnt-oem.mount: Deactivated successfully. May 13 00:18:22.675537 systemd[1]: Finished ignition-setup.service. May 13 00:18:22.675000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.677139 systemd[1]: Starting ignition-fetch-offline.service... May 13 00:18:22.736212 systemd[1]: Finished parse-ip-for-networkd.service. May 13 00:18:22.736000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.737000 audit: BPF prog-id=9 op=LOAD May 13 00:18:22.738361 systemd[1]: Starting systemd-networkd.service... May 13 00:18:22.760160 ignition[649]: Ignition 2.14.0 May 13 00:18:22.760170 ignition[649]: Stage: fetch-offline May 13 00:18:22.760208 ignition[649]: no configs at "/usr/lib/ignition/base.d" May 13 00:18:22.760216 ignition[649]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 00:18:22.760373 ignition[649]: parsed url from cmdline: "" May 13 00:18:22.760376 ignition[649]: no config URL provided May 13 00:18:22.760380 ignition[649]: reading system config file "/usr/lib/ignition/user.ign" May 13 00:18:22.760388 ignition[649]: no config at "/usr/lib/ignition/user.ign" May 13 00:18:22.760407 ignition[649]: op(1): [started] loading QEMU firmware config module May 13 00:18:22.760411 ignition[649]: op(1): executing: "modprobe" "qemu_fw_cfg" May 13 00:18:22.764596 ignition[649]: op(1): [finished] loading QEMU firmware config module May 13 00:18:22.771968 systemd-networkd[739]: lo: Link UP May 13 00:18:22.771983 systemd-networkd[739]: lo: Gained carrier May 13 00:18:22.772358 systemd-networkd[739]: Enumeration completed May 13 00:18:22.772450 systemd[1]: Started systemd-networkd.service. May 13 00:18:22.772000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.772523 systemd-networkd[739]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 00:18:22.773855 systemd[1]: Reached target network.target. May 13 00:18:22.775786 systemd[1]: Starting iscsiuio.service... May 13 00:18:22.777053 systemd-networkd[739]: eth0: Link UP May 13 00:18:22.777056 systemd-networkd[739]: eth0: Gained carrier May 13 00:18:22.780330 ignition[649]: parsing config with SHA512: d33f1813f2b5828560845e8438f8a360fdd13ea3472d2b969b88ec94b2a78566575310fdca087b52b917813ffda46257c1964376cf8a74b030965daab8585c73 May 13 00:18:22.784383 unknown[649]: fetched base config from "system" May 13 00:18:22.784392 unknown[649]: fetched user config from "qemu" May 13 00:18:22.787341 systemd[1]: Started iscsiuio.service. May 13 00:18:22.787000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.788922 systemd[1]: Starting iscsid.service... May 13 00:18:22.789899 ignition[649]: fetch-offline: fetch-offline passed May 13 00:18:22.789984 ignition[649]: Ignition finished successfully May 13 00:18:22.791000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.790904 systemd[1]: Finished ignition-fetch-offline.service. May 13 00:18:22.793532 iscsid[747]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi May 13 00:18:22.793532 iscsid[747]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. May 13 00:18:22.793532 iscsid[747]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. May 13 00:18:22.793532 iscsid[747]: If using hardware iscsi like qla4xxx this message can be ignored. May 13 00:18:22.793532 iscsid[747]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi May 13 00:18:22.793532 iscsid[747]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf May 13 00:18:22.801000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.791409 systemd-networkd[739]: eth0: DHCPv4 address 10.0.0.32/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 13 00:18:22.808003 ignition[748]: Ignition 2.14.0 May 13 00:18:22.792066 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 13 00:18:22.808009 ignition[748]: Stage: kargs May 13 00:18:22.792758 systemd[1]: Starting ignition-kargs.service... May 13 00:18:22.811000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.808095 ignition[748]: no configs at "/usr/lib/ignition/base.d" May 13 00:18:22.795568 systemd[1]: Started iscsid.service. May 13 00:18:22.815000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.808104 ignition[748]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 00:18:22.802686 systemd[1]: Starting dracut-initqueue.service... May 13 00:18:22.809105 ignition[748]: kargs: kargs passed May 13 00:18:22.810926 systemd[1]: Finished ignition-kargs.service. May 13 00:18:22.809148 ignition[748]: Ignition finished successfully May 13 00:18:22.813061 systemd[1]: Starting ignition-disks.service... May 13 00:18:22.819856 ignition[762]: Ignition 2.14.0 May 13 00:18:22.823000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.814022 systemd[1]: Finished dracut-initqueue.service. May 13 00:18:22.819862 ignition[762]: Stage: disks May 13 00:18:22.815523 systemd[1]: Reached target remote-fs-pre.target. May 13 00:18:22.819952 ignition[762]: no configs at "/usr/lib/ignition/base.d" May 13 00:18:22.816838 systemd[1]: Reached target remote-cryptsetup.target. May 13 00:18:22.832000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.819961 ignition[762]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 00:18:22.818739 systemd[1]: Reached target remote-fs.target. May 13 00:18:22.820963 ignition[762]: disks: disks passed May 13 00:18:22.821484 systemd[1]: Starting dracut-pre-mount.service... May 13 00:18:22.821011 ignition[762]: Ignition finished successfully May 13 00:18:22.822937 systemd[1]: Finished ignition-disks.service. May 13 00:18:22.824314 systemd[1]: Reached target initrd-root-device.target. May 13 00:18:22.825726 systemd[1]: Reached target local-fs-pre.target. May 13 00:18:22.827023 systemd[1]: Reached target local-fs.target. May 13 00:18:22.828489 systemd[1]: Reached target sysinit.target. May 13 00:18:22.829618 systemd[1]: Reached target basic.target. May 13 00:18:22.831369 systemd[1]: Finished dracut-pre-mount.service. May 13 00:18:22.833377 systemd[1]: Starting systemd-fsck-root.service... May 13 00:18:22.845728 systemd-fsck[775]: ROOT: clean, 619/553520 files, 56022/553472 blocks May 13 00:18:22.849842 systemd[1]: Finished systemd-fsck-root.service. May 13 00:18:22.852441 systemd[1]: Mounting sysroot.mount... May 13 00:18:22.851000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.859445 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. May 13 00:18:22.859717 systemd[1]: Mounted sysroot.mount. May 13 00:18:22.860624 systemd[1]: Reached target initrd-root-fs.target. May 13 00:18:22.863093 systemd[1]: Mounting sysroot-usr.mount... May 13 00:18:22.864061 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. May 13 00:18:22.864101 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 13 00:18:22.864126 systemd[1]: Reached target ignition-diskful.target. May 13 00:18:22.866089 systemd[1]: Mounted sysroot-usr.mount. May 13 00:18:22.868083 systemd[1]: Starting initrd-setup-root.service... May 13 00:18:22.872654 initrd-setup-root[785]: cut: /sysroot/etc/passwd: No such file or directory May 13 00:18:22.876544 initrd-setup-root[793]: cut: /sysroot/etc/group: No such file or directory May 13 00:18:22.880741 initrd-setup-root[801]: cut: /sysroot/etc/shadow: No such file or directory May 13 00:18:22.885152 initrd-setup-root[809]: cut: /sysroot/etc/gshadow: No such file or directory May 13 00:18:22.916559 systemd[1]: Finished initrd-setup-root.service. May 13 00:18:22.917000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.918196 systemd[1]: Starting ignition-mount.service... May 13 00:18:22.919654 systemd[1]: Starting sysroot-boot.service... May 13 00:18:22.924092 bash[826]: umount: /sysroot/usr/share/oem: not mounted. May 13 00:18:22.933304 ignition[827]: INFO : Ignition 2.14.0 May 13 00:18:22.933304 ignition[827]: INFO : Stage: mount May 13 00:18:22.936104 ignition[827]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 00:18:22.936104 ignition[827]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 00:18:22.936104 ignition[827]: INFO : mount: mount passed May 13 00:18:22.936104 ignition[827]: INFO : Ignition finished successfully May 13 00:18:22.935000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:22.935173 systemd[1]: Finished ignition-mount.service. May 13 00:18:22.950836 systemd[1]: Finished sysroot-boot.service. May 13 00:18:22.951000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:23.597356 systemd[1]: Mounting sysroot-usr-share-oem.mount... May 13 00:18:23.606115 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (837) May 13 00:18:23.606151 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 00:18:23.606162 kernel: BTRFS info (device vda6): using free space tree May 13 00:18:23.606808 kernel: BTRFS info (device vda6): has skinny extents May 13 00:18:23.640102 systemd[1]: Mounted sysroot-usr-share-oem.mount. May 13 00:18:23.641801 systemd[1]: Starting ignition-files.service... May 13 00:18:23.661170 ignition[857]: INFO : Ignition 2.14.0 May 13 00:18:23.661170 ignition[857]: INFO : Stage: files May 13 00:18:23.661170 ignition[857]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 00:18:23.661170 ignition[857]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 00:18:23.661170 ignition[857]: DEBUG : files: compiled without relabeling support, skipping May 13 00:18:23.667356 ignition[857]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 13 00:18:23.667356 ignition[857]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 13 00:18:23.670594 ignition[857]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 13 00:18:23.670594 ignition[857]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 13 00:18:23.676078 ignition[857]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" May 13 00:18:23.676078 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1 May 13 00:18:23.671107 unknown[857]: wrote ssh authorized keys file for user: core May 13 00:18:23.937588 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK May 13 00:18:24.215002 systemd-networkd[739]: eth0: Gained IPv6LL May 13 00:18:24.216413 ignition[857]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" May 13 00:18:24.216413 ignition[857]: INFO : files: op(8): [started] processing unit "containerd.service" May 13 00:18:24.216413 ignition[857]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" May 13 00:18:24.222518 ignition[857]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" May 13 00:18:24.222518 ignition[857]: INFO : files: op(8): [finished] processing unit "containerd.service" May 13 00:18:24.222518 ignition[857]: INFO : files: op(a): [started] processing unit "coreos-metadata.service" May 13 00:18:24.222518 ignition[857]: INFO : files: op(a): op(b): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 13 00:18:24.222518 ignition[857]: INFO : files: op(a): op(b): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 13 00:18:24.222518 ignition[857]: INFO : files: op(a): [finished] processing unit "coreos-metadata.service" May 13 00:18:24.222518 ignition[857]: INFO : files: op(c): [started] setting preset to disabled for "coreos-metadata.service" May 13 00:18:24.222518 ignition[857]: INFO : files: op(c): op(d): [started] removing enablement symlink(s) for "coreos-metadata.service" May 13 00:18:24.265305 ignition[857]: INFO : files: op(c): op(d): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 13 00:18:24.267903 ignition[857]: INFO : files: op(c): [finished] setting preset to disabled for "coreos-metadata.service" May 13 00:18:24.267903 ignition[857]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" May 13 00:18:24.267903 ignition[857]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" May 13 00:18:24.267903 ignition[857]: INFO : files: files passed May 13 00:18:24.267903 ignition[857]: INFO : Ignition finished successfully May 13 00:18:24.268000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.267908 systemd[1]: Finished ignition-files.service. May 13 00:18:24.272454 systemd[1]: Starting initrd-setup-root-after-ignition.service... May 13 00:18:24.275889 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). May 13 00:18:24.276564 systemd[1]: Starting ignition-quench.service... May 13 00:18:24.281149 initrd-setup-root-after-ignition[881]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory May 13 00:18:24.282599 initrd-setup-root-after-ignition[884]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 00:18:24.283000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.283000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.281846 systemd[1]: ignition-quench.service: Deactivated successfully. May 13 00:18:24.285000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.281937 systemd[1]: Finished ignition-quench.service. May 13 00:18:24.283752 systemd[1]: Finished initrd-setup-root-after-ignition.service. May 13 00:18:24.285793 systemd[1]: Reached target ignition-complete.target. May 13 00:18:24.288143 systemd[1]: Starting initrd-parse-etc.service... May 13 00:18:24.301666 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 13 00:18:24.301764 systemd[1]: Finished initrd-parse-etc.service. May 13 00:18:24.303605 systemd[1]: Reached target initrd-fs.target. May 13 00:18:24.303000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.303000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.304834 systemd[1]: Reached target initrd.target. May 13 00:18:24.306189 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. May 13 00:18:24.306982 systemd[1]: Starting dracut-pre-pivot.service... May 13 00:18:24.320533 systemd[1]: Finished dracut-pre-pivot.service. May 13 00:18:24.321000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.322160 systemd[1]: Starting initrd-cleanup.service... May 13 00:18:24.330715 systemd[1]: Stopped target nss-lookup.target. May 13 00:18:24.331617 systemd[1]: Stopped target remote-cryptsetup.target. May 13 00:18:24.333063 systemd[1]: Stopped target timers.target. May 13 00:18:24.334472 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 13 00:18:24.335000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.334591 systemd[1]: Stopped dracut-pre-pivot.service. May 13 00:18:24.335903 systemd[1]: Stopped target initrd.target. May 13 00:18:24.337314 systemd[1]: Stopped target basic.target. May 13 00:18:24.338590 systemd[1]: Stopped target ignition-complete.target. May 13 00:18:24.339942 systemd[1]: Stopped target ignition-diskful.target. May 13 00:18:24.341244 systemd[1]: Stopped target initrd-root-device.target. May 13 00:18:24.342743 systemd[1]: Stopped target remote-fs.target. May 13 00:18:24.344088 systemd[1]: Stopped target remote-fs-pre.target. May 13 00:18:24.345528 systemd[1]: Stopped target sysinit.target. May 13 00:18:24.346778 systemd[1]: Stopped target local-fs.target. May 13 00:18:24.348105 systemd[1]: Stopped target local-fs-pre.target. May 13 00:18:24.349482 systemd[1]: Stopped target swap.target. May 13 00:18:24.352000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.350715 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 13 00:18:24.350842 systemd[1]: Stopped dracut-pre-mount.service. May 13 00:18:24.354000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.352317 systemd[1]: Stopped target cryptsetup.target. May 13 00:18:24.356000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.353524 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 13 00:18:24.353626 systemd[1]: Stopped dracut-initqueue.service. May 13 00:18:24.355125 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 13 00:18:24.355224 systemd[1]: Stopped ignition-fetch-offline.service. May 13 00:18:24.356550 systemd[1]: Stopped target paths.target. May 13 00:18:24.357822 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 13 00:18:24.361319 systemd[1]: Stopped systemd-ask-password-console.path. May 13 00:18:24.362538 systemd[1]: Stopped target slices.target. May 13 00:18:24.364041 systemd[1]: Stopped target sockets.target. May 13 00:18:24.366000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.365435 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 13 00:18:24.367000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.365537 systemd[1]: Stopped initrd-setup-root-after-ignition.service. May 13 00:18:24.370671 iscsid[747]: iscsid shutting down. May 13 00:18:24.366911 systemd[1]: ignition-files.service: Deactivated successfully. May 13 00:18:24.366999 systemd[1]: Stopped ignition-files.service. May 13 00:18:24.369036 systemd[1]: Stopping ignition-mount.service... May 13 00:18:24.373000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.372043 systemd[1]: Stopping iscsid.service... May 13 00:18:24.372808 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 13 00:18:24.372921 systemd[1]: Stopped kmod-static-nodes.service. May 13 00:18:24.378000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.379402 ignition[897]: INFO : Ignition 2.14.0 May 13 00:18:24.379402 ignition[897]: INFO : Stage: umount May 13 00:18:24.379402 ignition[897]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 00:18:24.379402 ignition[897]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 00:18:24.379402 ignition[897]: INFO : umount: umount passed May 13 00:18:24.379402 ignition[897]: INFO : Ignition finished successfully May 13 00:18:24.379000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.382000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.384000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.374956 systemd[1]: Stopping sysroot-boot.service... May 13 00:18:24.387000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.377108 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 13 00:18:24.388000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.377257 systemd[1]: Stopped systemd-udev-trigger.service. May 13 00:18:24.390000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.378820 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 13 00:18:24.378910 systemd[1]: Stopped dracut-pre-trigger.service. May 13 00:18:24.381630 systemd[1]: iscsid.service: Deactivated successfully. May 13 00:18:24.395000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.381722 systemd[1]: Stopped iscsid.service. May 13 00:18:24.397000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.397000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.383700 systemd[1]: ignition-mount.service: Deactivated successfully. May 13 00:18:24.383858 systemd[1]: Stopped ignition-mount.service. May 13 00:18:24.385464 systemd[1]: iscsid.socket: Deactivated successfully. May 13 00:18:24.385536 systemd[1]: Closed iscsid.socket. May 13 00:18:24.386419 systemd[1]: ignition-disks.service: Deactivated successfully. May 13 00:18:24.386521 systemd[1]: Stopped ignition-disks.service. May 13 00:18:24.387993 systemd[1]: ignition-kargs.service: Deactivated successfully. May 13 00:18:24.388083 systemd[1]: Stopped ignition-kargs.service. May 13 00:18:24.389285 systemd[1]: ignition-setup.service: Deactivated successfully. May 13 00:18:24.389387 systemd[1]: Stopped ignition-setup.service. May 13 00:18:24.390951 systemd[1]: Stopping iscsiuio.service... May 13 00:18:24.394736 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 13 00:18:24.395190 systemd[1]: iscsiuio.service: Deactivated successfully. May 13 00:18:24.395313 systemd[1]: Stopped iscsiuio.service. May 13 00:18:24.396452 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 13 00:18:24.396538 systemd[1]: Finished initrd-cleanup.service. May 13 00:18:24.415000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.399481 systemd[1]: Stopped target network.target. May 13 00:18:24.416000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.400883 systemd[1]: iscsiuio.socket: Deactivated successfully. May 13 00:18:24.400917 systemd[1]: Closed iscsiuio.socket. May 13 00:18:24.402436 systemd[1]: Stopping systemd-networkd.service... May 13 00:18:24.420000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.421000 audit: BPF prog-id=6 op=UNLOAD May 13 00:18:24.405237 systemd[1]: Stopping systemd-resolved.service... May 13 00:18:24.421000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.411351 systemd-networkd[739]: eth0: DHCPv6 lease lost May 13 00:18:24.422000 audit: BPF prog-id=9 op=UNLOAD May 13 00:18:24.423000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.412913 systemd[1]: systemd-networkd.service: Deactivated successfully. May 13 00:18:24.413027 systemd[1]: Stopped systemd-networkd.service. May 13 00:18:24.415791 systemd[1]: systemd-resolved.service: Deactivated successfully. May 13 00:18:24.429000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.415880 systemd[1]: Stopped systemd-resolved.service. May 13 00:18:24.430000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.417152 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 13 00:18:24.417180 systemd[1]: Closed systemd-networkd.socket. May 13 00:18:24.418755 systemd[1]: Stopping network-cleanup.service... May 13 00:18:24.434000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.419417 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 13 00:18:24.435000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.419471 systemd[1]: Stopped parse-ip-for-networkd.service. May 13 00:18:24.420849 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 13 00:18:24.420908 systemd[1]: Stopped systemd-sysctl.service. May 13 00:18:24.440000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.423033 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 13 00:18:24.441000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.423076 systemd[1]: Stopped systemd-modules-load.service. May 13 00:18:24.442000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.424052 systemd[1]: Stopping systemd-udevd.service... May 13 00:18:24.428134 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 13 00:18:24.428638 systemd[1]: sysroot-boot.service: Deactivated successfully. May 13 00:18:24.446000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.428720 systemd[1]: Stopped sysroot-boot.service. May 13 00:18:24.430397 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 13 00:18:24.430453 systemd[1]: Stopped initrd-setup-root.service. May 13 00:18:24.450000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.450000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:24.433254 systemd[1]: systemd-udevd.service: Deactivated successfully. May 13 00:18:24.433459 systemd[1]: Stopped systemd-udevd.service. May 13 00:18:24.434813 systemd[1]: network-cleanup.service: Deactivated successfully. May 13 00:18:24.434898 systemd[1]: Stopped network-cleanup.service. May 13 00:18:24.436026 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 13 00:18:24.436063 systemd[1]: Closed systemd-udevd-control.socket. May 13 00:18:24.437563 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 13 00:18:24.437600 systemd[1]: Closed systemd-udevd-kernel.socket. May 13 00:18:24.438969 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 13 00:18:24.459000 audit: BPF prog-id=8 op=UNLOAD May 13 00:18:24.459000 audit: BPF prog-id=7 op=UNLOAD May 13 00:18:24.439018 systemd[1]: Stopped dracut-pre-udev.service. May 13 00:18:24.440493 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 13 00:18:24.440536 systemd[1]: Stopped dracut-cmdline.service. May 13 00:18:24.462000 audit: BPF prog-id=5 op=UNLOAD May 13 00:18:24.462000 audit: BPF prog-id=4 op=UNLOAD May 13 00:18:24.462000 audit: BPF prog-id=3 op=UNLOAD May 13 00:18:24.441791 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 00:18:24.441833 systemd[1]: Stopped dracut-cmdline-ask.service. May 13 00:18:24.444082 systemd[1]: Starting initrd-udevadm-cleanup-db.service... May 13 00:18:24.445486 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 00:18:24.445545 systemd[1]: Stopped systemd-vconsole-setup.service. May 13 00:18:24.449627 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 13 00:18:24.449716 systemd[1]: Finished initrd-udevadm-cleanup-db.service. May 13 00:18:24.450779 systemd[1]: Reached target initrd-switch-root.target. May 13 00:18:24.452804 systemd[1]: Starting initrd-switch-root.service... May 13 00:18:24.458218 systemd[1]: Switching root. May 13 00:18:24.476779 systemd-journald[289]: Journal stopped May 13 00:18:26.609400 systemd-journald[289]: Received SIGTERM from PID 1 (systemd). May 13 00:18:26.609453 kernel: SELinux: Class mctp_socket not defined in policy. May 13 00:18:26.609466 kernel: SELinux: Class anon_inode not defined in policy. May 13 00:18:26.609476 kernel: SELinux: the above unknown classes and permissions will be allowed May 13 00:18:26.609489 kernel: SELinux: policy capability network_peer_controls=1 May 13 00:18:26.609503 kernel: SELinux: policy capability open_perms=1 May 13 00:18:26.609513 kernel: SELinux: policy capability extended_socket_class=1 May 13 00:18:26.609523 kernel: SELinux: policy capability always_check_network=0 May 13 00:18:26.609533 kernel: SELinux: policy capability cgroup_seclabel=1 May 13 00:18:26.609543 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 13 00:18:26.609552 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 13 00:18:26.609562 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 13 00:18:26.609572 systemd[1]: Successfully loaded SELinux policy in 36.458ms. May 13 00:18:26.609592 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.009ms. May 13 00:18:26.609605 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 13 00:18:26.609616 systemd[1]: Detected virtualization kvm. May 13 00:18:26.609627 systemd[1]: Detected architecture arm64. May 13 00:18:26.609637 systemd[1]: Detected first boot. May 13 00:18:26.609649 systemd[1]: Initializing machine ID from VM UUID. May 13 00:18:26.609659 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). May 13 00:18:26.609670 systemd[1]: Populated /etc with preset unit settings. May 13 00:18:26.609682 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 13 00:18:26.609694 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 13 00:18:26.609705 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 13 00:18:26.609717 systemd[1]: Queued start job for default target multi-user.target. May 13 00:18:26.609727 systemd[1]: Unnecessary job was removed for dev-vda6.device. May 13 00:18:26.609738 systemd[1]: Created slice system-addon\x2dconfig.slice. May 13 00:18:26.609749 systemd[1]: Created slice system-addon\x2drun.slice. May 13 00:18:26.609759 systemd[1]: Created slice system-getty.slice. May 13 00:18:26.609771 systemd[1]: Created slice system-modprobe.slice. May 13 00:18:26.609782 systemd[1]: Created slice system-serial\x2dgetty.slice. May 13 00:18:26.609793 systemd[1]: Created slice system-system\x2dcloudinit.slice. May 13 00:18:26.609804 systemd[1]: Created slice system-systemd\x2dfsck.slice. May 13 00:18:26.609815 systemd[1]: Created slice user.slice. May 13 00:18:26.609826 systemd[1]: Started systemd-ask-password-console.path. May 13 00:18:26.609838 systemd[1]: Started systemd-ask-password-wall.path. May 13 00:18:26.609849 systemd[1]: Set up automount boot.automount. May 13 00:18:26.609860 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. May 13 00:18:26.609872 systemd[1]: Reached target integritysetup.target. May 13 00:18:26.609882 systemd[1]: Reached target remote-cryptsetup.target. May 13 00:18:26.609893 systemd[1]: Reached target remote-fs.target. May 13 00:18:26.609907 systemd[1]: Reached target slices.target. May 13 00:18:26.609918 systemd[1]: Reached target swap.target. May 13 00:18:26.609930 systemd[1]: Reached target torcx.target. May 13 00:18:26.609941 systemd[1]: Reached target veritysetup.target. May 13 00:18:26.609953 systemd[1]: Listening on systemd-coredump.socket. May 13 00:18:26.609963 systemd[1]: Listening on systemd-initctl.socket. May 13 00:18:26.609973 kernel: kauditd_printk_skb: 78 callbacks suppressed May 13 00:18:26.609984 kernel: audit: type=1400 audit(1747095506.497:82): avc: denied { audit_read } for pid=1 comm="systemd" capability=37 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 May 13 00:18:26.609994 kernel: audit: type=1335 audit(1747095506.500:83): pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 May 13 00:18:26.610007 systemd[1]: Listening on systemd-journald-audit.socket. May 13 00:18:26.610019 systemd[1]: Listening on systemd-journald-dev-log.socket. May 13 00:18:26.610030 systemd[1]: Listening on systemd-journald.socket. May 13 00:18:26.610041 systemd[1]: Listening on systemd-networkd.socket. May 13 00:18:26.610051 systemd[1]: Listening on systemd-udevd-control.socket. May 13 00:18:26.610062 systemd[1]: Listening on systemd-udevd-kernel.socket. May 13 00:18:26.610072 systemd[1]: Listening on systemd-userdbd.socket. May 13 00:18:26.610082 systemd[1]: Mounting dev-hugepages.mount... May 13 00:18:26.610093 systemd[1]: Mounting dev-mqueue.mount... May 13 00:18:26.610104 systemd[1]: Mounting media.mount... May 13 00:18:26.610115 systemd[1]: Mounting sys-kernel-debug.mount... May 13 00:18:26.610127 systemd[1]: Mounting sys-kernel-tracing.mount... May 13 00:18:26.610138 systemd[1]: Mounting tmp.mount... May 13 00:18:26.610148 systemd[1]: Starting flatcar-tmpfiles.service... May 13 00:18:26.610159 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 13 00:18:26.610170 systemd[1]: Starting kmod-static-nodes.service... May 13 00:18:26.610181 systemd[1]: Starting modprobe@configfs.service... May 13 00:18:26.610192 systemd[1]: Starting modprobe@dm_mod.service... May 13 00:18:26.610208 systemd[1]: Starting modprobe@drm.service... May 13 00:18:26.610220 systemd[1]: Starting modprobe@efi_pstore.service... May 13 00:18:26.610232 systemd[1]: Starting modprobe@fuse.service... May 13 00:18:26.610244 systemd[1]: Starting modprobe@loop.service... May 13 00:18:26.610255 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 13 00:18:26.610266 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. May 13 00:18:26.610277 systemd[1]: (This warning is only shown for the first unit using IP firewalling.) May 13 00:18:26.610287 systemd[1]: Starting systemd-journald.service... May 13 00:18:26.610307 systemd[1]: Starting systemd-modules-load.service... May 13 00:18:26.610319 systemd[1]: Starting systemd-network-generator.service... May 13 00:18:26.610331 kernel: fuse: init (API version 7.34) May 13 00:18:26.610342 systemd[1]: Starting systemd-remount-fs.service... May 13 00:18:26.610353 systemd[1]: Starting systemd-udev-trigger.service... May 13 00:18:26.610364 systemd[1]: Mounted dev-hugepages.mount. May 13 00:18:26.610374 systemd[1]: Mounted dev-mqueue.mount. May 13 00:18:26.610389 systemd[1]: Mounted media.mount. May 13 00:18:26.610400 systemd[1]: Mounted sys-kernel-debug.mount. May 13 00:18:26.610410 kernel: loop: module loaded May 13 00:18:26.610420 systemd[1]: Mounted sys-kernel-tracing.mount. May 13 00:18:26.610433 systemd[1]: Mounted tmp.mount. May 13 00:18:26.610443 systemd[1]: Finished kmod-static-nodes.service. May 13 00:18:26.610455 kernel: audit: type=1130 audit(1747095506.599:84): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.610466 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 13 00:18:26.610476 systemd[1]: Finished modprobe@configfs.service. May 13 00:18:26.610489 kernel: audit: type=1305 audit(1747095506.604:85): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 May 13 00:18:26.610500 kernel: audit: type=1300 audit(1747095506.604:85): arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffc1047c90 a2=4000 a3=1 items=0 ppid=1 pid=1028 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) May 13 00:18:26.610514 systemd-journald[1028]: Journal started May 13 00:18:26.610561 systemd-journald[1028]: Runtime Journal (/run/log/journal/10f446edbc314026aebd13a858d6e607) is 6.0M, max 48.7M, 42.6M free. May 13 00:18:26.599000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.604000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 May 13 00:18:26.604000 audit[1028]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffc1047c90 a2=4000 a3=1 items=0 ppid=1 pid=1028 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) May 13 00:18:26.604000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" May 13 00:18:26.612925 kernel: audit: type=1327 audit(1747095506.604:85): proctitle="/usr/lib/systemd/systemd-journald" May 13 00:18:26.612000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.613870 systemd[1]: Started systemd-journald.service. May 13 00:18:26.613891 kernel: audit: type=1130 audit(1747095506.612:86): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.612000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.618628 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 00:18:26.618883 systemd[1]: Finished modprobe@dm_mod.service. May 13 00:18:26.620973 kernel: audit: type=1131 audit(1747095506.612:87): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.621018 kernel: audit: type=1130 audit(1747095506.617:88): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.617000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.625750 kernel: audit: type=1130 audit(1747095506.624:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.624000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.624720 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 00:18:26.624934 systemd[1]: Finished modprobe@drm.service. May 13 00:18:26.624000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.627000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.627000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.628251 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 00:18:26.628552 systemd[1]: Finished modprobe@efi_pstore.service. May 13 00:18:26.629000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.629000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.629653 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 13 00:18:26.629805 systemd[1]: Finished modprobe@fuse.service. May 13 00:18:26.630000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.630000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.630873 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 00:18:26.631027 systemd[1]: Finished modprobe@loop.service. May 13 00:18:26.631000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.631000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.632169 systemd[1]: Finished systemd-modules-load.service. May 13 00:18:26.632000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.633471 systemd[1]: Finished systemd-network-generator.service. May 13 00:18:26.634000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.634862 systemd[1]: Finished systemd-remount-fs.service. May 13 00:18:26.635000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.636174 systemd[1]: Reached target network-pre.target. May 13 00:18:26.638260 systemd[1]: Mounting sys-fs-fuse-connections.mount... May 13 00:18:26.640471 systemd[1]: Mounting sys-kernel-config.mount... May 13 00:18:26.641336 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 13 00:18:26.645148 systemd[1]: Starting systemd-hwdb-update.service... May 13 00:18:26.647392 systemd[1]: Starting systemd-journal-flush.service... May 13 00:18:26.648524 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 00:18:26.649525 systemd[1]: Starting systemd-random-seed.service... May 13 00:18:26.650633 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 13 00:18:26.652006 systemd[1]: Starting systemd-sysctl.service... May 13 00:18:26.655322 systemd[1]: Finished flatcar-tmpfiles.service. May 13 00:18:26.657572 systemd-journald[1028]: Time spent on flushing to /var/log/journal/10f446edbc314026aebd13a858d6e607 is 18.187ms for 912 entries. May 13 00:18:26.657572 systemd-journald[1028]: System Journal (/var/log/journal/10f446edbc314026aebd13a858d6e607) is 8.0M, max 195.6M, 187.6M free. May 13 00:18:26.686773 systemd-journald[1028]: Received client request to flush runtime journal. May 13 00:18:26.651000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.658000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.665000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.672000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.656611 systemd[1]: Finished systemd-udev-trigger.service. May 13 00:18:26.660111 systemd[1]: Mounted sys-fs-fuse-connections.mount. May 13 00:18:26.661216 systemd[1]: Mounted sys-kernel-config.mount. May 13 00:18:26.687665 udevadm[1079]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 13 00:18:26.662845 systemd[1]: Finished systemd-random-seed.service. May 13 00:18:26.665642 systemd[1]: Reached target first-boot-complete.target. May 13 00:18:26.667926 systemd[1]: Starting systemd-sysusers.service... May 13 00:18:26.669991 systemd[1]: Starting systemd-udev-settle.service... May 13 00:18:26.672466 systemd[1]: Finished systemd-sysctl.service. May 13 00:18:26.687954 systemd[1]: Finished systemd-journal-flush.service. May 13 00:18:26.688000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.706586 systemd[1]: Finished systemd-sysusers.service. May 13 00:18:26.708719 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... May 13 00:18:26.707000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:26.728000 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' May 13 00:18:26.727336 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. May 13 00:18:27.008439 systemd[1]: Finished systemd-hwdb-update.service. May 13 00:18:27.008000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.010484 systemd[1]: Starting systemd-udevd.service... May 13 00:18:27.028959 systemd-udevd[1088]: Using default interface naming scheme 'v252'. May 13 00:18:27.041226 systemd[1]: Started systemd-udevd.service. May 13 00:18:27.041000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.043687 systemd[1]: Starting systemd-networkd.service... May 13 00:18:27.051130 systemd[1]: Starting systemd-userdbd.service... May 13 00:18:27.063951 systemd[1]: Found device dev-ttyAMA0.device. May 13 00:18:27.082523 systemd[1]: Started systemd-userdbd.service. May 13 00:18:27.083000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.104215 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 13 00:18:27.145460 systemd-networkd[1097]: lo: Link UP May 13 00:18:27.145472 systemd-networkd[1097]: lo: Gained carrier May 13 00:18:27.145803 systemd-networkd[1097]: Enumeration completed May 13 00:18:27.145903 systemd-networkd[1097]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 00:18:27.145910 systemd[1]: Started systemd-networkd.service. May 13 00:18:27.146000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.150759 systemd-networkd[1097]: eth0: Link UP May 13 00:18:27.150769 systemd-networkd[1097]: eth0: Gained carrier May 13 00:18:27.152766 systemd[1]: Finished systemd-udev-settle.service. May 13 00:18:27.153000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.154827 systemd[1]: Starting lvm2-activation-early.service... May 13 00:18:27.165505 lvm[1122]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 00:18:27.172487 systemd-networkd[1097]: eth0: DHCPv4 address 10.0.0.32/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 13 00:18:27.191131 systemd[1]: Finished lvm2-activation-early.service. May 13 00:18:27.191000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.192179 systemd[1]: Reached target cryptsetup.target. May 13 00:18:27.194145 systemd[1]: Starting lvm2-activation.service... May 13 00:18:27.197711 lvm[1125]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 00:18:27.225155 systemd[1]: Finished lvm2-activation.service. May 13 00:18:27.225000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.226140 systemd[1]: Reached target local-fs-pre.target. May 13 00:18:27.227006 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 13 00:18:27.227033 systemd[1]: Reached target local-fs.target. May 13 00:18:27.227796 systemd[1]: Reached target machines.target. May 13 00:18:27.229786 systemd[1]: Starting ldconfig.service... May 13 00:18:27.230813 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 13 00:18:27.230871 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 13 00:18:27.231938 systemd[1]: Starting systemd-boot-update.service... May 13 00:18:27.233794 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... May 13 00:18:27.236149 systemd[1]: Starting systemd-machine-id-commit.service... May 13 00:18:27.238268 systemd[1]: Starting systemd-sysext.service... May 13 00:18:27.242223 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1128 (bootctl) May 13 00:18:27.243536 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... May 13 00:18:27.249800 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. May 13 00:18:27.250000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.255526 systemd[1]: Unmounting usr-share-oem.mount... May 13 00:18:27.261264 systemd[1]: usr-share-oem.mount: Deactivated successfully. May 13 00:18:27.261596 systemd[1]: Unmounted usr-share-oem.mount. May 13 00:18:27.305343 kernel: loop0: detected capacity change from 0 to 194096 May 13 00:18:27.305568 systemd[1]: Finished systemd-machine-id-commit.service. May 13 00:18:27.308000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.317330 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 13 00:18:27.329572 systemd-fsck[1140]: fsck.fat 4.2 (2021-01-31) May 13 00:18:27.329572 systemd-fsck[1140]: /dev/vda1: 236 files, 117310/258078 clusters May 13 00:18:27.332000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.331100 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. May 13 00:18:27.334308 kernel: loop1: detected capacity change from 0 to 194096 May 13 00:18:27.342027 (sd-sysext)[1145]: Using extensions 'kubernetes'. May 13 00:18:27.342410 (sd-sysext)[1145]: Merged extensions into '/usr'. May 13 00:18:27.357937 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 13 00:18:27.359444 systemd[1]: Starting modprobe@dm_mod.service... May 13 00:18:27.361813 systemd[1]: Starting modprobe@efi_pstore.service... May 13 00:18:27.363967 systemd[1]: Starting modprobe@loop.service... May 13 00:18:27.367000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.367000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.365429 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 13 00:18:27.365565 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 13 00:18:27.366460 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 00:18:27.366619 systemd[1]: Finished modprobe@dm_mod.service. May 13 00:18:27.368105 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 00:18:27.368257 systemd[1]: Finished modprobe@efi_pstore.service. May 13 00:18:27.369000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.369000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.369758 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 00:18:27.369889 systemd[1]: Finished modprobe@loop.service. May 13 00:18:27.370000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.370000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.371279 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 00:18:27.371391 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 13 00:18:27.419989 ldconfig[1127]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 13 00:18:27.423445 systemd[1]: Finished ldconfig.service. May 13 00:18:27.423000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.586899 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 13 00:18:27.588879 systemd[1]: Mounting boot.mount... May 13 00:18:27.590961 systemd[1]: Mounting usr-share-oem.mount... May 13 00:18:27.597960 systemd[1]: Mounted boot.mount. May 13 00:18:27.598980 systemd[1]: Mounted usr-share-oem.mount. May 13 00:18:27.601005 systemd[1]: Finished systemd-sysext.service. May 13 00:18:27.601000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.603232 systemd[1]: Starting ensure-sysext.service... May 13 00:18:27.605113 systemd[1]: Starting systemd-tmpfiles-setup.service... May 13 00:18:27.611482 systemd[1]: Reloading. May 13 00:18:27.615979 systemd-tmpfiles[1163]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. May 13 00:18:27.617014 systemd-tmpfiles[1163]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 13 00:18:27.618291 systemd-tmpfiles[1163]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 13 00:18:27.646722 /usr/lib/systemd/system-generators/torcx-generator[1185]: time="2025-05-13T00:18:27Z" 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.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" May 13 00:18:27.646754 /usr/lib/systemd/system-generators/torcx-generator[1185]: time="2025-05-13T00:18:27Z" level=info msg="torcx already run" May 13 00:18:27.715499 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 13 00:18:27.715768 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 13 00:18:27.731174 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 13 00:18:27.776705 systemd[1]: Finished systemd-boot-update.service. May 13 00:18:27.777000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.779225 systemd[1]: Finished systemd-tmpfiles-setup.service. May 13 00:18:27.782334 systemd[1]: Starting audit-rules.service... May 13 00:18:27.779000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.784466 systemd[1]: Starting clean-ca-certificates.service... May 13 00:18:27.787089 systemd[1]: Starting systemd-journal-catalog-update.service... May 13 00:18:27.789603 systemd[1]: Starting systemd-resolved.service... May 13 00:18:27.791855 systemd[1]: Starting systemd-timesyncd.service... May 13 00:18:27.794139 systemd[1]: Starting systemd-update-utmp.service... May 13 00:18:27.795769 systemd[1]: Finished clean-ca-certificates.service. May 13 00:18:27.796000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.800058 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 13 00:18:27.802000 audit[1241]: SYSTEM_BOOT pid=1241 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' May 13 00:18:27.805254 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 13 00:18:27.806682 systemd[1]: Starting modprobe@dm_mod.service... May 13 00:18:27.809745 systemd[1]: Starting modprobe@efi_pstore.service... May 13 00:18:27.813166 systemd[1]: Starting modprobe@loop.service... May 13 00:18:27.814245 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 13 00:18:27.814438 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 13 00:18:27.814581 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 13 00:18:27.815905 systemd[1]: Finished systemd-journal-catalog-update.service. May 13 00:18:27.816000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.817497 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 00:18:27.817635 systemd[1]: Finished modprobe@dm_mod.service. May 13 00:18:27.818000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.818000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.819175 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 00:18:27.819335 systemd[1]: Finished modprobe@efi_pstore.service. May 13 00:18:27.820812 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 00:18:27.820961 systemd[1]: Finished modprobe@loop.service. May 13 00:18:27.823632 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 13 00:18:27.820000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.820000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.821000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.821000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.824954 systemd[1]: Starting modprobe@dm_mod.service... May 13 00:18:27.827139 systemd[1]: Starting modprobe@efi_pstore.service... May 13 00:18:27.829242 systemd[1]: Starting modprobe@loop.service... May 13 00:18:27.830207 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 13 00:18:27.830336 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 13 00:18:27.831635 systemd[1]: Starting systemd-update-done.service... May 13 00:18:27.832604 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 13 00:18:27.833742 systemd[1]: Finished systemd-update-utmp.service. May 13 00:18:27.834000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.836000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.836000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.835120 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 00:18:27.837000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.837000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.835267 systemd[1]: Finished modprobe@dm_mod.service. May 13 00:18:27.836530 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 00:18:27.836666 systemd[1]: Finished modprobe@efi_pstore.service. May 13 00:18:27.837961 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 00:18:27.838115 systemd[1]: Finished modprobe@loop.service. May 13 00:18:27.838000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.838000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.840000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 13 00:18:27.839505 systemd[1]: Finished systemd-update-done.service. May 13 00:18:27.841533 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 00:18:27.841635 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 13 00:18:27.846553 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 13 00:18:27.847778 systemd[1]: Starting modprobe@dm_mod.service... May 13 00:18:27.849620 systemd[1]: Starting modprobe@drm.service... May 13 00:18:27.851737 systemd[1]: Starting modprobe@efi_pstore.service... May 13 00:18:27.853971 systemd[1]: Starting modprobe@loop.service... May 13 00:18:27.854816 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 13 00:18:27.854944 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 13 00:18:27.856375 systemd[1]: Starting systemd-networkd-wait-online.service... May 13 00:18:27.856000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 May 13 00:18:27.856000 audit[1270]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffffb9b4eb0 a2=420 a3=0 items=0 ppid=1231 pid=1270 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) May 13 00:18:27.856000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 May 13 00:18:27.857412 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 13 00:18:27.857839 augenrules[1270]: No rules May 13 00:18:27.858568 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 00:18:27.858722 systemd[1]: Finished modprobe@dm_mod.service. May 13 00:18:27.860074 systemd[1]: Finished audit-rules.service. May 13 00:18:27.861445 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 00:18:27.861604 systemd[1]: Finished modprobe@drm.service. May 13 00:18:27.863353 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 00:18:27.863487 systemd[1]: Finished modprobe@loop.service. May 13 00:18:27.864660 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 00:18:27.865487 systemd[1]: Finished modprobe@efi_pstore.service. May 13 00:18:27.868516 systemd[1]: Finished ensure-sysext.service. May 13 00:18:27.870555 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 00:18:27.870600 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 13 00:18:27.884237 systemd[1]: Started systemd-timesyncd.service. May 13 00:18:27.885635 systemd[1]: Reached target time-set.target. May 13 00:18:27.886504 systemd-timesyncd[1237]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 13 00:18:27.886801 systemd-timesyncd[1237]: Initial clock synchronization to Tue 2025-05-13 00:18:28.045490 UTC. May 13 00:18:27.887152 systemd-resolved[1236]: Positive Trust Anchors: May 13 00:18:27.887163 systemd-resolved[1236]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 00:18:27.887193 systemd-resolved[1236]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test May 13 00:18:27.894942 systemd-resolved[1236]: Defaulting to hostname 'linux'. May 13 00:18:27.897980 systemd[1]: Started systemd-resolved.service. May 13 00:18:27.898965 systemd[1]: Reached target network.target. May 13 00:18:27.899771 systemd[1]: Reached target nss-lookup.target. May 13 00:18:27.900597 systemd[1]: Reached target sysinit.target. May 13 00:18:27.901443 systemd[1]: Started motdgen.path. May 13 00:18:27.902142 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. May 13 00:18:27.903443 systemd[1]: Started logrotate.timer. May 13 00:18:27.904276 systemd[1]: Started mdadm.timer. May 13 00:18:27.904951 systemd[1]: Started systemd-tmpfiles-clean.timer. May 13 00:18:27.905805 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 13 00:18:27.905835 systemd[1]: Reached target paths.target. May 13 00:18:27.906592 systemd[1]: Reached target timers.target. May 13 00:18:27.907669 systemd[1]: Listening on dbus.socket. May 13 00:18:27.909604 systemd[1]: Starting docker.socket... May 13 00:18:27.911466 systemd[1]: Listening on sshd.socket. May 13 00:18:27.912291 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 13 00:18:27.912645 systemd[1]: Listening on docker.socket. May 13 00:18:27.913439 systemd[1]: Reached target sockets.target. May 13 00:18:27.914210 systemd[1]: Reached target basic.target. May 13 00:18:27.915135 systemd[1]: System is tainted: cgroupsv1 May 13 00:18:27.915184 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. May 13 00:18:27.915217 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. May 13 00:18:27.916273 systemd[1]: Starting containerd.service... May 13 00:18:27.918127 systemd[1]: Starting dbus.service... May 13 00:18:27.920077 systemd[1]: Starting enable-oem-cloudinit.service... May 13 00:18:27.922163 systemd[1]: Starting extend-filesystems.service... May 13 00:18:27.923098 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). May 13 00:18:27.924526 systemd[1]: Starting motdgen.service... May 13 00:18:27.928709 jq[1293]: false May 13 00:18:27.927250 systemd[1]: Starting ssh-key-proc-cmdline.service... May 13 00:18:27.929587 systemd[1]: Starting sshd-keygen.service... May 13 00:18:27.933323 systemd[1]: Starting systemd-logind.service... May 13 00:18:27.934203 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 13 00:18:27.934320 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 13 00:18:27.935698 systemd[1]: Starting update-engine.service... May 13 00:18:27.937865 systemd[1]: Starting update-ssh-keys-after-ignition.service... May 13 00:18:27.940548 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 13 00:18:27.944031 jq[1307]: true May 13 00:18:27.940863 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. May 13 00:18:27.945995 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 13 00:18:27.946265 systemd[1]: Finished ssh-key-proc-cmdline.service. May 13 00:18:27.956447 jq[1311]: true May 13 00:18:27.960874 dbus-daemon[1292]: [system] SELinux support is enabled May 13 00:18:27.961068 systemd[1]: Started dbus.service. May 13 00:18:27.963704 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 13 00:18:27.963743 systemd[1]: Reached target system-config.target. May 13 00:18:27.964787 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 13 00:18:27.964829 systemd[1]: Reached target user-config.target. May 13 00:18:27.967580 extend-filesystems[1294]: Found loop1 May 13 00:18:27.968653 extend-filesystems[1294]: Found vda May 13 00:18:27.969756 extend-filesystems[1294]: Found vda1 May 13 00:18:27.970815 extend-filesystems[1294]: Found vda2 May 13 00:18:27.971626 extend-filesystems[1294]: Found vda3 May 13 00:18:27.972449 extend-filesystems[1294]: Found usr May 13 00:18:27.973207 extend-filesystems[1294]: Found vda4 May 13 00:18:27.974008 extend-filesystems[1294]: Found vda6 May 13 00:18:27.974798 extend-filesystems[1294]: Found vda7 May 13 00:18:27.975611 extend-filesystems[1294]: Found vda9 May 13 00:18:27.976542 extend-filesystems[1294]: Checking size of /dev/vda9 May 13 00:18:27.978571 systemd[1]: motdgen.service: Deactivated successfully. May 13 00:18:27.978817 systemd[1]: Finished motdgen.service. May 13 00:18:27.987948 extend-filesystems[1294]: Resized partition /dev/vda9 May 13 00:18:28.000235 systemd-logind[1302]: Watching system buttons on /dev/input/event0 (Power Button) May 13 00:18:28.000524 systemd-logind[1302]: New seat seat0. May 13 00:18:28.003281 systemd[1]: Started systemd-logind.service. May 13 00:18:28.007828 extend-filesystems[1341]: resize2fs 1.46.5 (30-Dec-2021) May 13 00:18:28.025714 bash[1338]: Updated "/home/core/.ssh/authorized_keys" May 13 00:18:28.028378 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 13 00:18:28.026890 systemd[1]: Finished update-ssh-keys-after-ignition.service. May 13 00:18:28.042366 update_engine[1306]: I0513 00:18:28.042131 1306 main.cc:92] Flatcar Update Engine starting May 13 00:18:28.044506 systemd[1]: Started update-engine.service. May 13 00:18:28.045909 update_engine[1306]: I0513 00:18:28.045768 1306 update_check_scheduler.cc:74] Next update check in 2m45s May 13 00:18:28.047669 systemd[1]: Started locksmithd.service. May 13 00:18:28.050331 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 13 00:18:28.060151 extend-filesystems[1341]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 13 00:18:28.060151 extend-filesystems[1341]: old_desc_blocks = 1, new_desc_blocks = 1 May 13 00:18:28.060151 extend-filesystems[1341]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 13 00:18:28.064518 extend-filesystems[1294]: Resized filesystem in /dev/vda9 May 13 00:18:28.062720 systemd[1]: extend-filesystems.service: Deactivated successfully. May 13 00:18:28.062961 systemd[1]: Finished extend-filesystems.service. May 13 00:18:28.084337 env[1314]: time="2025-05-13T00:18:28.083127915Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 May 13 00:18:28.101038 locksmithd[1349]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 13 00:18:28.101408 env[1314]: time="2025-05-13T00:18:28.101087784Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 13 00:18:28.101408 env[1314]: time="2025-05-13T00:18:28.101236646Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 13 00:18:28.103366 env[1314]: time="2025-05-13T00:18:28.103327122Z" 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.181-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 13 00:18:28.103366 env[1314]: time="2025-05-13T00:18:28.103361717Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 13 00:18:28.103625 env[1314]: time="2025-05-13T00:18:28.103596004Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 13 00:18:28.103625 env[1314]: time="2025-05-13T00:18:28.103619910Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 13 00:18:28.103688 env[1314]: time="2025-05-13T00:18:28.103634515Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" May 13 00:18:28.103688 env[1314]: time="2025-05-13T00:18:28.103644510Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 13 00:18:28.103726 env[1314]: time="2025-05-13T00:18:28.103713740Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 13 00:18:28.104009 env[1314]: time="2025-05-13T00:18:28.103979195Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 13 00:18:28.104165 env[1314]: time="2025-05-13T00:18:28.104140908Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 13 00:18:28.104165 env[1314]: time="2025-05-13T00:18:28.104161795Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 13 00:18:28.104241 env[1314]: time="2025-05-13T00:18:28.104223845Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" May 13 00:18:28.104281 env[1314]: time="2025-05-13T00:18:28.104242162Z" level=info msg="metadata content store policy set" policy=shared May 13 00:18:28.107773 env[1314]: time="2025-05-13T00:18:28.107739670Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 13 00:18:28.107773 env[1314]: time="2025-05-13T00:18:28.107774060Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 13 00:18:28.107877 env[1314]: time="2025-05-13T00:18:28.107788420Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 13 00:18:28.107877 env[1314]: time="2025-05-13T00:18:28.107820771Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 13 00:18:28.107877 env[1314]: time="2025-05-13T00:18:28.107835253Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 13 00:18:28.107877 env[1314]: time="2025-05-13T00:18:28.107852959Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 13 00:18:28.107877 env[1314]: time="2025-05-13T00:18:28.107866340Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 13 00:18:28.108220 env[1314]: time="2025-05-13T00:18:28.108201392Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 13 00:18:28.108244 env[1314]: time="2025-05-13T00:18:28.108234150Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 May 13 00:18:28.108264 env[1314]: time="2025-05-13T00:18:28.108252427Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 13 00:18:28.108285 env[1314]: time="2025-05-13T00:18:28.108265155Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 13 00:18:28.108285 env[1314]: time="2025-05-13T00:18:28.108277720Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 13 00:18:28.108435 env[1314]: time="2025-05-13T00:18:28.108415812Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 13 00:18:28.108512 env[1314]: time="2025-05-13T00:18:28.108497892Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 13 00:18:28.108804 env[1314]: time="2025-05-13T00:18:28.108787784Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 13 00:18:28.108828 env[1314]: time="2025-05-13T00:18:28.108817728Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 13 00:18:28.108850 env[1314]: time="2025-05-13T00:18:28.108831721Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 13 00:18:28.108957 env[1314]: time="2025-05-13T00:18:28.108946233Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 13 00:18:28.108979 env[1314]: time="2025-05-13T00:18:28.108962470Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109008 env[1314]: time="2025-05-13T00:18:28.108976748Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109008 env[1314]: time="2025-05-13T00:18:28.108989395Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109008 env[1314]: time="2025-05-13T00:18:28.109001960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109068 env[1314]: time="2025-05-13T00:18:28.109014117Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109068 env[1314]: time="2025-05-13T00:18:28.109027824Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109068 env[1314]: time="2025-05-13T00:18:28.109039124Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109068 env[1314]: time="2025-05-13T00:18:28.109053403Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 13 00:18:28.109185 env[1314]: time="2025-05-13T00:18:28.109167589Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109215 env[1314]: time="2025-05-13T00:18:28.109190883Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109215 env[1314]: time="2025-05-13T00:18:28.109203489Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 13 00:18:28.109256 env[1314]: time="2025-05-13T00:18:28.109218991Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 13 00:18:28.109256 env[1314]: time="2025-05-13T00:18:28.109233677Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 May 13 00:18:28.109256 env[1314]: time="2025-05-13T00:18:28.109244733Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 13 00:18:28.109339 env[1314]: time="2025-05-13T00:18:28.109261459Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" May 13 00:18:28.109339 env[1314]: time="2025-05-13T00:18:28.109296992Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 13 00:18:28.110370 env[1314]: time="2025-05-13T00:18:28.110254336Z" 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:false] 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:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" May 13 00:18:28.111014 env[1314]: time="2025-05-13T00:18:28.110393693Z" level=info msg="Connect containerd service" May 13 00:18:28.111014 env[1314]: time="2025-05-13T00:18:28.110445626Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 13 00:18:28.111309 env[1314]: time="2025-05-13T00:18:28.111274587Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 13 00:18:28.111595 env[1314]: time="2025-05-13T00:18:28.111576595Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 13 00:18:28.111631 env[1314]: time="2025-05-13T00:18:28.111620654Z" level=info msg=serving... address=/run/containerd/containerd.sock May 13 00:18:28.111675 env[1314]: time="2025-05-13T00:18:28.111664999Z" level=info msg="containerd successfully booted in 0.029335s" May 13 00:18:28.111771 systemd[1]: Started containerd.service. May 13 00:18:28.121418 env[1314]: time="2025-05-13T00:18:28.121304165Z" level=info msg="Start subscribing containerd event" May 13 00:18:28.121418 env[1314]: time="2025-05-13T00:18:28.121383390Z" level=info msg="Start recovering state" May 13 00:18:28.121506 env[1314]: time="2025-05-13T00:18:28.121456699Z" level=info msg="Start event monitor" May 13 00:18:28.121631 env[1314]: time="2025-05-13T00:18:28.121598912Z" level=info msg="Start snapshots syncer" May 13 00:18:28.121631 env[1314]: time="2025-05-13T00:18:28.121619473Z" level=info msg="Start cni network conf syncer for default" May 13 00:18:28.121631 env[1314]: time="2025-05-13T00:18:28.121632323Z" level=info msg="Start streaming server" May 13 00:18:28.757438 systemd-networkd[1097]: eth0: Gained IPv6LL May 13 00:18:28.759457 systemd[1]: Finished systemd-networkd-wait-online.service. May 13 00:18:28.760872 systemd[1]: Reached target network-online.target. May 13 00:18:28.763491 systemd[1]: Starting kubelet.service... May 13 00:18:29.273534 sshd_keygen[1308]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 13 00:18:29.284800 systemd[1]: Started kubelet.service. May 13 00:18:29.297481 systemd[1]: Finished sshd-keygen.service. May 13 00:18:29.299965 systemd[1]: Starting issuegen.service... May 13 00:18:29.305275 systemd[1]: issuegen.service: Deactivated successfully. May 13 00:18:29.305565 systemd[1]: Finished issuegen.service. May 13 00:18:29.308045 systemd[1]: Starting systemd-user-sessions.service... May 13 00:18:29.314340 systemd[1]: Finished systemd-user-sessions.service. May 13 00:18:29.316743 systemd[1]: Started getty@tty1.service. May 13 00:18:29.318888 systemd[1]: Started serial-getty@ttyAMA0.service. May 13 00:18:29.320061 systemd[1]: Reached target getty.target. May 13 00:18:29.321058 systemd[1]: Reached target multi-user.target. May 13 00:18:29.323446 systemd[1]: Starting systemd-update-utmp-runlevel.service... May 13 00:18:29.330277 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 13 00:18:29.330539 systemd[1]: Finished systemd-update-utmp-runlevel.service. May 13 00:18:29.331750 systemd[1]: Startup finished in 4.568s (kernel) + 4.804s (userspace) = 9.373s. May 13 00:18:29.868759 kubelet[1376]: E0513 00:18:29.868711 1376 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" May 13 00:18:29.871740 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 13 00:18:29.871904 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 13 00:18:32.898823 systemd[1]: Created slice system-sshd.slice. May 13 00:18:32.899994 systemd[1]: Started sshd@0-10.0.0.32:22-10.0.0.1:46690.service. May 13 00:18:32.942722 sshd[1398]: Accepted publickey for core from 10.0.0.1 port 46690 ssh2: RSA SHA256:JqaCSrDIbVVQNbxsbpFjz60HxEXsX2X9A6oTs4HqYQk May 13 00:18:32.944728 sshd[1398]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 13 00:18:32.953413 systemd-logind[1302]: New session 1 of user core. May 13 00:18:32.954239 systemd[1]: Created slice user-500.slice. May 13 00:18:32.955184 systemd[1]: Starting user-runtime-dir@500.service... May 13 00:18:32.963571 systemd[1]: Finished user-runtime-dir@500.service. May 13 00:18:32.964761 systemd[1]: Starting user@500.service... May 13 00:18:32.967670 (systemd)[1403]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 13 00:18:33.025190 systemd[1403]: Queued start job for default target default.target. May 13 00:18:33.025423 systemd[1403]: Reached target paths.target. May 13 00:18:33.025438 systemd[1403]: Reached target sockets.target. May 13 00:18:33.025448 systemd[1403]: Reached target timers.target. May 13 00:18:33.025459 systemd[1403]: Reached target basic.target. May 13 00:18:33.025502 systemd[1403]: Reached target default.target. May 13 00:18:33.025525 systemd[1403]: Startup finished in 52ms. May 13 00:18:33.025600 systemd[1]: Started user@500.service. May 13 00:18:33.026533 systemd[1]: Started session-1.scope. May 13 00:18:33.075673 systemd[1]: Started sshd@1-10.0.0.32:22-10.0.0.1:46698.service. May 13 00:18:33.110911 sshd[1412]: Accepted publickey for core from 10.0.0.1 port 46698 ssh2: RSA SHA256:JqaCSrDIbVVQNbxsbpFjz60HxEXsX2X9A6oTs4HqYQk May 13 00:18:33.112300 sshd[1412]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 13 00:18:33.116298 systemd-logind[1302]: New session 2 of user core. May 13 00:18:33.117062 systemd[1]: Started session-2.scope. May 13 00:18:33.170338 sshd[1412]: pam_unix(sshd:session): session closed for user core May 13 00:18:33.172847 systemd[1]: Started sshd@2-10.0.0.32:22-10.0.0.1:46714.service. May 13 00:18:33.173370 systemd[1]: sshd@1-10.0.0.32:22-10.0.0.1:46698.service: Deactivated successfully. May 13 00:18:33.174250 systemd[1]: session-2.scope: Deactivated successfully. May 13 00:18:33.174274 systemd-logind[1302]: Session 2 logged out. Waiting for processes to exit. May 13 00:18:33.175225 systemd-logind[1302]: Removed session 2. May 13 00:18:33.207949 sshd[1417]: Accepted publickey for core from 10.0.0.1 port 46714 ssh2: RSA SHA256:JqaCSrDIbVVQNbxsbpFjz60HxEXsX2X9A6oTs4HqYQk May 13 00:18:33.208993 sshd[1417]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 13 00:18:33.212255 systemd-logind[1302]: New session 3 of user core. May 13 00:18:33.212575 systemd[1]: Started session-3.scope. May 13 00:18:33.262394 sshd[1417]: pam_unix(sshd:session): session closed for user core May 13 00:18:33.264452 systemd[1]: Started sshd@3-10.0.0.32:22-10.0.0.1:46720.service. May 13 00:18:33.265082 systemd[1]: sshd@2-10.0.0.32:22-10.0.0.1:46714.service: Deactivated successfully. May 13 00:18:33.265953 systemd-logind[1302]: Session 3 logged out. Waiting for processes to exit. May 13 00:18:33.266001 systemd[1]: session-3.scope: Deactivated successfully. May 13 00:18:33.266648 systemd-logind[1302]: Removed session 3. May 13 00:18:33.301791 sshd[1424]: Accepted publickey for core from 10.0.0.1 port 46720 ssh2: RSA SHA256:JqaCSrDIbVVQNbxsbpFjz60HxEXsX2X9A6oTs4HqYQk May 13 00:18:33.302865 sshd[1424]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 13 00:18:33.305743 systemd-logind[1302]: New session 4 of user core. May 13 00:18:33.306518 systemd[1]: Started session-4.scope. May 13 00:18:33.359452 sshd[1424]: pam_unix(sshd:session): session closed for user core May 13 00:18:33.361778 systemd[1]: Started sshd@4-10.0.0.32:22-10.0.0.1:46734.service. May 13 00:18:33.362205 systemd[1]: sshd@3-10.0.0.32:22-10.0.0.1:46720.service: Deactivated successfully. May 13 00:18:33.363168 systemd-logind[1302]: Session 4 logged out. Waiting for processes to exit. May 13 00:18:33.363214 systemd[1]: session-4.scope: Deactivated successfully. May 13 00:18:33.364050 systemd-logind[1302]: Removed session 4. May 13 00:18:33.397858 sshd[1432]: Accepted publickey for core from 10.0.0.1 port 46734 ssh2: RSA SHA256:JqaCSrDIbVVQNbxsbpFjz60HxEXsX2X9A6oTs4HqYQk May 13 00:18:33.399061 sshd[1432]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 13 00:18:33.402652 systemd-logind[1302]: New session 5 of user core. May 13 00:18:33.404584 systemd[1]: Started session-5.scope. May 13 00:18:33.466231 sudo[1437]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 13 00:18:33.466493 sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) May 13 00:18:33.479456 systemd[1]: Starting coreos-metadata.service... May 13 00:18:33.485880 systemd[1]: coreos-metadata.service: Deactivated successfully. May 13 00:18:33.486085 systemd[1]: Finished coreos-metadata.service. May 13 00:18:33.957463 systemd[1]: Stopped kubelet.service. May 13 00:18:33.959817 systemd[1]: Starting kubelet.service... May 13 00:18:33.975666 systemd[1]: Reloading. May 13 00:18:34.019662 /usr/lib/systemd/system-generators/torcx-generator[1509]: time="2025-05-13T00:18:34Z" 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.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" May 13 00:18:34.019695 /usr/lib/systemd/system-generators/torcx-generator[1509]: time="2025-05-13T00:18:34Z" level=info msg="torcx already run" May 13 00:18:34.101570 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 13 00:18:34.101589 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 13 00:18:34.117201 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 13 00:18:34.179609 systemd[1]: Started kubelet.service. May 13 00:18:34.181504 systemd[1]: Stopping kubelet.service... May 13 00:18:34.181879 systemd[1]: kubelet.service: Deactivated successfully. May 13 00:18:34.182223 systemd[1]: Stopped kubelet.service. May 13 00:18:34.183878 systemd[1]: Starting kubelet.service... May 13 00:18:34.261908 systemd[1]: Started kubelet.service. May 13 00:18:34.297081 kubelet[1565]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 13 00:18:34.297081 kubelet[1565]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 13 00:18:34.297081 kubelet[1565]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 13 00:18:34.297467 kubelet[1565]: I0513 00:18:34.297240 1565 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 13 00:18:35.000618 kubelet[1565]: I0513 00:18:35.000582 1565 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" May 13 00:18:35.000755 kubelet[1565]: I0513 00:18:35.000744 1565 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 13 00:18:35.001022 kubelet[1565]: I0513 00:18:35.001004 1565 server.go:927] "Client rotation is on, will bootstrap in background" May 13 00:18:35.045950 kubelet[1565]: I0513 00:18:35.045421 1565 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 13 00:18:35.055694 kubelet[1565]: I0513 00:18:35.055653 1565 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 13 00:18:35.057160 kubelet[1565]: I0513 00:18:35.057112 1565 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 13 00:18:35.057414 kubelet[1565]: I0513 00:18:35.057148 1565 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.32","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"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},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"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} May 13 00:18:35.057518 kubelet[1565]: I0513 00:18:35.057476 1565 topology_manager.go:138] "Creating topology manager with none policy" May 13 00:18:35.057518 kubelet[1565]: I0513 00:18:35.057490 1565 container_manager_linux.go:301] "Creating device plugin manager" May 13 00:18:35.057761 kubelet[1565]: I0513 00:18:35.057738 1565 state_mem.go:36] "Initialized new in-memory state store" May 13 00:18:35.059654 kubelet[1565]: I0513 00:18:35.059553 1565 kubelet.go:400] "Attempting to sync node with API server" May 13 00:18:35.059654 kubelet[1565]: I0513 00:18:35.059586 1565 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" May 13 00:18:35.059972 kubelet[1565]: I0513 00:18:35.059955 1565 kubelet.go:312] "Adding apiserver pod source" May 13 00:18:35.060051 kubelet[1565]: I0513 00:18:35.060033 1565 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 13 00:18:35.060323 kubelet[1565]: E0513 00:18:35.060259 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:35.060323 kubelet[1565]: E0513 00:18:35.060286 1565 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:35.061784 kubelet[1565]: I0513 00:18:35.061759 1565 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" May 13 00:18:35.062276 kubelet[1565]: I0513 00:18:35.062260 1565 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 13 00:18:35.062417 kubelet[1565]: W0513 00:18:35.062402 1565 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 13 00:18:35.063465 kubelet[1565]: I0513 00:18:35.063417 1565 server.go:1264] "Started kubelet" May 13 00:18:35.066214 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). May 13 00:18:35.066919 kubelet[1565]: I0513 00:18:35.066887 1565 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 13 00:18:35.071893 kubelet[1565]: I0513 00:18:35.071843 1565 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 13 00:18:35.073074 kubelet[1565]: I0513 00:18:35.073048 1565 server.go:455] "Adding debug handlers to kubelet server" May 13 00:18:35.075889 kubelet[1565]: I0513 00:18:35.074779 1565 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 13 00:18:35.075889 kubelet[1565]: I0513 00:18:35.075112 1565 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 13 00:18:35.077485 kubelet[1565]: E0513 00:18:35.077463 1565 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.32\" not found" May 13 00:18:35.077568 kubelet[1565]: W0513 00:18:35.077468 1565 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope May 13 00:18:35.077639 kubelet[1565]: E0513 00:18:35.077628 1565 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope May 13 00:18:35.077775 kubelet[1565]: I0513 00:18:35.077763 1565 volume_manager.go:291] "Starting Kubelet Volume Manager" May 13 00:18:35.077910 kubelet[1565]: I0513 00:18:35.077898 1565 desired_state_of_world_populator.go:149] "Desired state populator starts to run" May 13 00:18:35.078924 kubelet[1565]: I0513 00:18:35.078905 1565 reconciler.go:26] "Reconciler: start to sync state" May 13 00:18:35.083335 kubelet[1565]: E0513 00:18:35.083268 1565 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 13 00:18:35.087614 kubelet[1565]: I0513 00:18:35.087591 1565 factory.go:221] Registration of the containerd container factory successfully May 13 00:18:35.087614 kubelet[1565]: I0513 00:18:35.087610 1565 factory.go:221] Registration of the systemd container factory successfully May 13 00:18:35.087722 kubelet[1565]: I0513 00:18:35.087699 1565 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory May 13 00:18:35.093594 kubelet[1565]: E0513 00:18:35.093565 1565 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.32\" not found" node="10.0.0.32" May 13 00:18:35.107466 kubelet[1565]: I0513 00:18:35.107435 1565 cpu_manager.go:214] "Starting CPU manager" policy="none" May 13 00:18:35.107466 kubelet[1565]: I0513 00:18:35.107454 1565 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 13 00:18:35.107584 kubelet[1565]: I0513 00:18:35.107477 1565 state_mem.go:36] "Initialized new in-memory state store" May 13 00:18:35.178950 kubelet[1565]: I0513 00:18:35.178924 1565 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.32" May 13 00:18:35.180707 kubelet[1565]: I0513 00:18:35.180674 1565 policy_none.go:49] "None policy: Start" May 13 00:18:35.181366 kubelet[1565]: I0513 00:18:35.181325 1565 memory_manager.go:170] "Starting memorymanager" policy="None" May 13 00:18:35.181366 kubelet[1565]: I0513 00:18:35.181351 1565 state_mem.go:35] "Initializing new in-memory state store" May 13 00:18:35.187701 kubelet[1565]: I0513 00:18:35.187675 1565 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.32" May 13 00:18:35.188413 kubelet[1565]: I0513 00:18:35.188386 1565 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 13 00:18:35.188569 kubelet[1565]: I0513 00:18:35.188533 1565 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 13 00:18:35.188645 kubelet[1565]: I0513 00:18:35.188635 1565 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 13 00:18:35.195808 kubelet[1565]: I0513 00:18:35.195781 1565 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 13 00:18:35.196538 env[1314]: time="2025-05-13T00:18:35.196287922Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 13 00:18:35.196937 kubelet[1565]: I0513 00:18:35.196533 1565 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 13 00:18:35.219893 kubelet[1565]: I0513 00:18:35.219838 1565 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 13 00:18:35.220841 kubelet[1565]: I0513 00:18:35.220808 1565 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 13 00:18:35.220926 kubelet[1565]: I0513 00:18:35.220905 1565 status_manager.go:217] "Starting to sync pod status with apiserver" May 13 00:18:35.220926 kubelet[1565]: I0513 00:18:35.220926 1565 kubelet.go:2337] "Starting kubelet main sync loop" May 13 00:18:35.221008 kubelet[1565]: E0513 00:18:35.220971 1565 kubelet.go:2361] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" May 13 00:18:35.282111 sudo[1437]: pam_unix(sudo:session): session closed for user root May 13 00:18:35.284462 sshd[1432]: pam_unix(sshd:session): session closed for user core May 13 00:18:35.287022 systemd[1]: sshd@4-10.0.0.32:22-10.0.0.1:46734.service: Deactivated successfully. May 13 00:18:35.287971 systemd-logind[1302]: Session 5 logged out. Waiting for processes to exit. May 13 00:18:35.288017 systemd[1]: session-5.scope: Deactivated successfully. May 13 00:18:35.288994 systemd-logind[1302]: Removed session 5. May 13 00:18:36.004140 kubelet[1565]: I0513 00:18:36.004085 1565 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 13 00:18:36.004504 kubelet[1565]: W0513 00:18:36.004312 1565 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 13 00:18:36.004504 kubelet[1565]: W0513 00:18:36.004351 1565 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 13 00:18:36.060983 kubelet[1565]: I0513 00:18:36.060949 1565 apiserver.go:52] "Watching apiserver" May 13 00:18:36.061096 kubelet[1565]: E0513 00:18:36.060965 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:36.067037 kubelet[1565]: I0513 00:18:36.067003 1565 topology_manager.go:215] "Topology Admit Handler" podUID="f093c70d-e1f5-4d68-b32d-910673d5dcb1" podNamespace="kube-flannel" podName="kube-flannel-ds-5ndnb" May 13 00:18:36.067231 kubelet[1565]: I0513 00:18:36.067215 1565 topology_manager.go:215] "Topology Admit Handler" podUID="c09469e6-6bde-4e0b-892d-c23f6341b8cb" podNamespace="kube-system" podName="kube-proxy-ffln7" May 13 00:18:36.078730 kubelet[1565]: I0513 00:18:36.078698 1565 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" May 13 00:18:36.084530 kubelet[1565]: I0513 00:18:36.084504 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c09469e6-6bde-4e0b-892d-c23f6341b8cb-xtables-lock\") pod \"kube-proxy-ffln7\" (UID: \"c09469e6-6bde-4e0b-892d-c23f6341b8cb\") " pod="kube-system/kube-proxy-ffln7" May 13 00:18:36.084607 kubelet[1565]: I0513 00:18:36.084536 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f093c70d-e1f5-4d68-b32d-910673d5dcb1-cni-plugin\") pod \"kube-flannel-ds-5ndnb\" (UID: \"f093c70d-e1f5-4d68-b32d-910673d5dcb1\") " pod="kube-flannel/kube-flannel-ds-5ndnb" May 13 00:18:36.084607 kubelet[1565]: I0513 00:18:36.084553 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/c09469e6-6bde-4e0b-892d-c23f6341b8cb-kube-proxy\") pod \"kube-proxy-ffln7\" (UID: \"c09469e6-6bde-4e0b-892d-c23f6341b8cb\") " pod="kube-system/kube-proxy-ffln7" May 13 00:18:36.084607 kubelet[1565]: I0513 00:18:36.084572 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f093c70d-e1f5-4d68-b32d-910673d5dcb1-flannel-cfg\") pod \"kube-flannel-ds-5ndnb\" (UID: \"f093c70d-e1f5-4d68-b32d-910673d5dcb1\") " pod="kube-flannel/kube-flannel-ds-5ndnb" May 13 00:18:36.084701 kubelet[1565]: I0513 00:18:36.084613 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f093c70d-e1f5-4d68-b32d-910673d5dcb1-xtables-lock\") pod \"kube-flannel-ds-5ndnb\" (UID: \"f093c70d-e1f5-4d68-b32d-910673d5dcb1\") " pod="kube-flannel/kube-flannel-ds-5ndnb" May 13 00:18:36.084701 kubelet[1565]: I0513 00:18:36.084631 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qhkbk\" (UniqueName: \"kubernetes.io/projected/f093c70d-e1f5-4d68-b32d-910673d5dcb1-kube-api-access-qhkbk\") pod \"kube-flannel-ds-5ndnb\" (UID: \"f093c70d-e1f5-4d68-b32d-910673d5dcb1\") " pod="kube-flannel/kube-flannel-ds-5ndnb" May 13 00:18:36.084701 kubelet[1565]: I0513 00:18:36.084647 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/c09469e6-6bde-4e0b-892d-c23f6341b8cb-lib-modules\") pod \"kube-proxy-ffln7\" (UID: \"c09469e6-6bde-4e0b-892d-c23f6341b8cb\") " pod="kube-system/kube-proxy-ffln7" May 13 00:18:36.084701 kubelet[1565]: I0513 00:18:36.084691 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fqn7k\" (UniqueName: \"kubernetes.io/projected/c09469e6-6bde-4e0b-892d-c23f6341b8cb-kube-api-access-fqn7k\") pod \"kube-proxy-ffln7\" (UID: \"c09469e6-6bde-4e0b-892d-c23f6341b8cb\") " pod="kube-system/kube-proxy-ffln7" May 13 00:18:36.084785 kubelet[1565]: I0513 00:18:36.084711 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f093c70d-e1f5-4d68-b32d-910673d5dcb1-run\") pod \"kube-flannel-ds-5ndnb\" (UID: \"f093c70d-e1f5-4d68-b32d-910673d5dcb1\") " pod="kube-flannel/kube-flannel-ds-5ndnb" May 13 00:18:36.084785 kubelet[1565]: I0513 00:18:36.084729 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f093c70d-e1f5-4d68-b32d-910673d5dcb1-cni\") pod \"kube-flannel-ds-5ndnb\" (UID: \"f093c70d-e1f5-4d68-b32d-910673d5dcb1\") " pod="kube-flannel/kube-flannel-ds-5ndnb" May 13 00:18:36.370606 kubelet[1565]: E0513 00:18:36.370501 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:36.371797 env[1314]: time="2025-05-13T00:18:36.371682840Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ffln7,Uid:c09469e6-6bde-4e0b-892d-c23f6341b8cb,Namespace:kube-system,Attempt:0,}" May 13 00:18:36.375179 kubelet[1565]: E0513 00:18:36.375147 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:36.375918 env[1314]: time="2025-05-13T00:18:36.375876659Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5ndnb,Uid:f093c70d-e1f5-4d68-b32d-910673d5dcb1,Namespace:kube-flannel,Attempt:0,}" May 13 00:18:37.027276 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1639632894.mount: Deactivated successfully. May 13 00:18:37.031242 env[1314]: time="2025-05-13T00:18:37.031202131Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.034437 env[1314]: time="2025-05-13T00:18:37.034381747Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.036426 env[1314]: time="2025-05-13T00:18:37.036394146Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.038702 env[1314]: time="2025-05-13T00:18:37.038677113Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.040254 env[1314]: time="2025-05-13T00:18:37.040226239Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.042590 env[1314]: time="2025-05-13T00:18:37.042559143Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.043510 env[1314]: time="2025-05-13T00:18:37.043485447Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.044337 env[1314]: time="2025-05-13T00:18:37.044310913Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:37.062136 kubelet[1565]: E0513 00:18:37.062098 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:37.073251 env[1314]: time="2025-05-13T00:18:37.073183212Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 13 00:18:37.073251 env[1314]: time="2025-05-13T00:18:37.073228521Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 13 00:18:37.073251 env[1314]: time="2025-05-13T00:18:37.073239064Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 13 00:18:37.073553 env[1314]: time="2025-05-13T00:18:37.073505608Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54 pid=1628 runtime=io.containerd.runc.v2 May 13 00:18:37.073740 env[1314]: time="2025-05-13T00:18:37.073586569Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 13 00:18:37.073740 env[1314]: time="2025-05-13T00:18:37.073728412Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 13 00:18:37.073819 env[1314]: time="2025-05-13T00:18:37.073740162Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 13 00:18:37.073984 env[1314]: time="2025-05-13T00:18:37.073890495Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/d4e6c2419739cd9c9e63908225c722c5e2b9de5cafe824f0b2b2e6c37c7ebfe3 pid=1627 runtime=io.containerd.runc.v2 May 13 00:18:37.140230 env[1314]: time="2025-05-13T00:18:37.140187760Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ffln7,Uid:c09469e6-6bde-4e0b-892d-c23f6341b8cb,Namespace:kube-system,Attempt:0,} returns sandbox id \"d4e6c2419739cd9c9e63908225c722c5e2b9de5cafe824f0b2b2e6c37c7ebfe3\"" May 13 00:18:37.141495 kubelet[1565]: E0513 00:18:37.141141 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:37.143561 env[1314]: time="2025-05-13T00:18:37.143524952Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\"" May 13 00:18:37.152519 env[1314]: time="2025-05-13T00:18:37.152472524Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5ndnb,Uid:f093c70d-e1f5-4d68-b32d-910673d5dcb1,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54\"" May 13 00:18:37.153260 kubelet[1565]: E0513 00:18:37.153235 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:38.063005 kubelet[1565]: E0513 00:18:38.062965 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:38.152728 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3954579024.mount: Deactivated successfully. May 13 00:18:38.603796 env[1314]: time="2025-05-13T00:18:38.603728963Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.30.12,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:38.605468 env[1314]: time="2025-05-13T00:18:38.605432588Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b4250a9efcae16f8d20358e204a159844e2b7e854edad08aee8791774acbdaed,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:38.606729 env[1314]: time="2025-05-13T00:18:38.606700064Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.30.12,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:38.609377 env[1314]: time="2025-05-13T00:18:38.609351179Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:ea8c7d5392acf6b0c11ebba78301e1a6c2dc6abcd7544102ed578e49d1c82f15,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:38.609840 env[1314]: time="2025-05-13T00:18:38.609811817Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\" returns image reference \"sha256:b4250a9efcae16f8d20358e204a159844e2b7e854edad08aee8791774acbdaed\"" May 13 00:18:38.611799 env[1314]: time="2025-05-13T00:18:38.611765101Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 13 00:18:38.612601 env[1314]: time="2025-05-13T00:18:38.612571779Z" level=info msg="CreateContainer within sandbox \"d4e6c2419739cd9c9e63908225c722c5e2b9de5cafe824f0b2b2e6c37c7ebfe3\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 13 00:18:38.625534 env[1314]: time="2025-05-13T00:18:38.625469816Z" level=info msg="CreateContainer within sandbox \"d4e6c2419739cd9c9e63908225c722c5e2b9de5cafe824f0b2b2e6c37c7ebfe3\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"70bd84b20219943a642788e87ba0f04ee716ee8fefbde15692814d9453257f57\"" May 13 00:18:38.626418 env[1314]: time="2025-05-13T00:18:38.626364432Z" level=info msg="StartContainer for \"70bd84b20219943a642788e87ba0f04ee716ee8fefbde15692814d9453257f57\"" May 13 00:18:38.681663 env[1314]: time="2025-05-13T00:18:38.681616750Z" level=info msg="StartContainer for \"70bd84b20219943a642788e87ba0f04ee716ee8fefbde15692814d9453257f57\" returns successfully" May 13 00:18:39.063831 kubelet[1565]: E0513 00:18:39.063776 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:39.234828 kubelet[1565]: E0513 00:18:39.234780 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:39.244985 kubelet[1565]: I0513 00:18:39.244898 1565 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-ffln7" podStartSLOduration=2.775690559 podStartE2EDuration="4.244883194s" podCreationTimestamp="2025-05-13 00:18:35 +0000 UTC" firstStartedPulling="2025-05-13 00:18:37.141835391 +0000 UTC m=+2.875894513" lastFinishedPulling="2025-05-13 00:18:38.611028025 +0000 UTC m=+4.345087148" observedRunningTime="2025-05-13 00:18:39.244739821 +0000 UTC m=+4.978798903" watchObservedRunningTime="2025-05-13 00:18:39.244883194 +0000 UTC m=+4.978942276" May 13 00:18:39.756340 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount76208338.mount: Deactivated successfully. May 13 00:18:39.800046 env[1314]: time="2025-05-13T00:18:39.800007063Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:39.802352 env[1314]: time="2025-05-13T00:18:39.802326993Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:39.804486 env[1314]: time="2025-05-13T00:18:39.804462040Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:39.806435 env[1314]: time="2025-05-13T00:18:39.806398222Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:39.807054 env[1314]: time="2025-05-13T00:18:39.807012340Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 13 00:18:39.809254 env[1314]: time="2025-05-13T00:18:39.809220039Z" level=info msg="CreateContainer within sandbox \"b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 13 00:18:39.821888 env[1314]: time="2025-05-13T00:18:39.821841425Z" level=info msg="CreateContainer within sandbox \"b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"a898cfafb789578c582905de32523b433f4a9379029bc06fd0f0ccd2e307960a\"" May 13 00:18:39.822675 env[1314]: time="2025-05-13T00:18:39.822606712Z" level=info msg="StartContainer for \"a898cfafb789578c582905de32523b433f4a9379029bc06fd0f0ccd2e307960a\"" May 13 00:18:39.876327 env[1314]: time="2025-05-13T00:18:39.875226419Z" level=info msg="StartContainer for \"a898cfafb789578c582905de32523b433f4a9379029bc06fd0f0ccd2e307960a\" returns successfully" May 13 00:18:40.036545 env[1314]: time="2025-05-13T00:18:40.036434886Z" level=info msg="shim disconnected" id=a898cfafb789578c582905de32523b433f4a9379029bc06fd0f0ccd2e307960a May 13 00:18:40.036545 env[1314]: time="2025-05-13T00:18:40.036481231Z" level=warning msg="cleaning up after shim disconnected" id=a898cfafb789578c582905de32523b433f4a9379029bc06fd0f0ccd2e307960a namespace=k8s.io May 13 00:18:40.036545 env[1314]: time="2025-05-13T00:18:40.036491110Z" level=info msg="cleaning up dead shim" May 13 00:18:40.043034 env[1314]: time="2025-05-13T00:18:40.042980998Z" level=warning msg="cleanup warnings time=\"2025-05-13T00:18:40Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1900 runtime=io.containerd.runc.v2\n" May 13 00:18:40.064328 kubelet[1565]: E0513 00:18:40.064243 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:40.237590 kubelet[1565]: E0513 00:18:40.237518 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:40.237922 kubelet[1565]: E0513 00:18:40.237865 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:40.238602 env[1314]: time="2025-05-13T00:18:40.238555940Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 13 00:18:40.674168 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a898cfafb789578c582905de32523b433f4a9379029bc06fd0f0ccd2e307960a-rootfs.mount: Deactivated successfully. May 13 00:18:41.065483 kubelet[1565]: E0513 00:18:41.065345 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:41.425150 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount307734288.mount: Deactivated successfully. May 13 00:18:42.065912 kubelet[1565]: E0513 00:18:42.065858 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:42.135520 env[1314]: time="2025-05-13T00:18:42.135465642Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:42.138178 env[1314]: time="2025-05-13T00:18:42.138137851Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:42.140496 env[1314]: time="2025-05-13T00:18:42.140465286Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:42.142618 env[1314]: time="2025-05-13T00:18:42.142595197Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:42.143460 env[1314]: time="2025-05-13T00:18:42.143432316Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 13 00:18:42.145850 env[1314]: time="2025-05-13T00:18:42.145818771Z" level=info msg="CreateContainer within sandbox \"b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 13 00:18:42.154721 env[1314]: time="2025-05-13T00:18:42.154684232Z" level=info msg="CreateContainer within sandbox \"b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"5359817f975ee557ce4dcb2dd9721c02fcc26c492caf8c4edbb316bbbae1d780\"" May 13 00:18:42.155485 env[1314]: time="2025-05-13T00:18:42.155417594Z" level=info msg="StartContainer for \"5359817f975ee557ce4dcb2dd9721c02fcc26c492caf8c4edbb316bbbae1d780\"" May 13 00:18:42.218822 env[1314]: time="2025-05-13T00:18:42.218776035Z" level=info msg="StartContainer for \"5359817f975ee557ce4dcb2dd9721c02fcc26c492caf8c4edbb316bbbae1d780\" returns successfully" May 13 00:18:42.244486 kubelet[1565]: I0513 00:18:42.243547 1565 kubelet_node_status.go:497] "Fast updating node status as it just became ready" May 13 00:18:42.246340 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-5359817f975ee557ce4dcb2dd9721c02fcc26c492caf8c4edbb316bbbae1d780-rootfs.mount: Deactivated successfully. May 13 00:18:42.249122 kubelet[1565]: E0513 00:18:42.248737 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:42.357559 env[1314]: time="2025-05-13T00:18:42.357453921Z" level=info msg="shim disconnected" id=5359817f975ee557ce4dcb2dd9721c02fcc26c492caf8c4edbb316bbbae1d780 May 13 00:18:42.357759 env[1314]: time="2025-05-13T00:18:42.357740397Z" level=warning msg="cleaning up after shim disconnected" id=5359817f975ee557ce4dcb2dd9721c02fcc26c492caf8c4edbb316bbbae1d780 namespace=k8s.io May 13 00:18:42.357828 env[1314]: time="2025-05-13T00:18:42.357815546Z" level=info msg="cleaning up dead shim" May 13 00:18:42.365057 env[1314]: time="2025-05-13T00:18:42.365020973Z" level=warning msg="cleanup warnings time=\"2025-05-13T00:18:42Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1955 runtime=io.containerd.runc.v2\n" May 13 00:18:43.066547 kubelet[1565]: E0513 00:18:43.066501 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:43.252234 kubelet[1565]: E0513 00:18:43.252011 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:43.253759 env[1314]: time="2025-05-13T00:18:43.253717279Z" level=info msg="CreateContainer within sandbox \"b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 13 00:18:43.264005 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3293389916.mount: Deactivated successfully. May 13 00:18:43.268749 env[1314]: time="2025-05-13T00:18:43.268703379Z" level=info msg="CreateContainer within sandbox \"b71a9cba475c954affaedd13a52e53868198f3f2bebc3c4d8cc1702ca0543e54\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"042b576b62466c387701d1ccf412d7db6d23b7176ced560cf3bf38d51e859e48\"" May 13 00:18:43.269569 env[1314]: time="2025-05-13T00:18:43.269539978Z" level=info msg="StartContainer for \"042b576b62466c387701d1ccf412d7db6d23b7176ced560cf3bf38d51e859e48\"" May 13 00:18:43.331910 env[1314]: time="2025-05-13T00:18:43.331814894Z" level=info msg="StartContainer for \"042b576b62466c387701d1ccf412d7db6d23b7176ced560cf3bf38d51e859e48\" returns successfully" May 13 00:18:44.067642 kubelet[1565]: E0513 00:18:44.067595 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:44.255416 kubelet[1565]: E0513 00:18:44.255388 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:44.261176 systemd[1]: run-containerd-runc-k8s.io-042b576b62466c387701d1ccf412d7db6d23b7176ced560cf3bf38d51e859e48-runc.FIfHZC.mount: Deactivated successfully. May 13 00:18:44.404707 systemd-networkd[1097]: flannel.1: Link UP May 13 00:18:44.404715 systemd-networkd[1097]: flannel.1: Gained carrier May 13 00:18:45.067958 kubelet[1565]: E0513 00:18:45.067891 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:45.257032 kubelet[1565]: E0513 00:18:45.256988 1565 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 13 00:18:45.845713 systemd-networkd[1097]: flannel.1: Gained IPv6LL May 13 00:18:46.068987 kubelet[1565]: E0513 00:18:46.068950 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:46.074760 kubelet[1565]: I0513 00:18:46.074437 1565 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-5ndnb" podStartSLOduration=6.084408798 podStartE2EDuration="11.074421375s" podCreationTimestamp="2025-05-13 00:18:35 +0000 UTC" firstStartedPulling="2025-05-13 00:18:37.154662378 +0000 UTC m=+2.888721500" lastFinishedPulling="2025-05-13 00:18:42.144674954 +0000 UTC m=+7.878734077" observedRunningTime="2025-05-13 00:18:44.26566276 +0000 UTC m=+9.999721962" watchObservedRunningTime="2025-05-13 00:18:46.074421375 +0000 UTC m=+11.808480497" May 13 00:18:46.074760 kubelet[1565]: I0513 00:18:46.074644 1565 topology_manager.go:215] "Topology Admit Handler" podUID="67204280-7d89-44dd-9e1e-99d206053146" podNamespace="default" podName="nginx-deployment-85f456d6dd-nx6nj" May 13 00:18:46.245712 kubelet[1565]: I0513 00:18:46.245109 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fnnp5\" (UniqueName: \"kubernetes.io/projected/67204280-7d89-44dd-9e1e-99d206053146-kube-api-access-fnnp5\") pod \"nginx-deployment-85f456d6dd-nx6nj\" (UID: \"67204280-7d89-44dd-9e1e-99d206053146\") " pod="default/nginx-deployment-85f456d6dd-nx6nj" May 13 00:18:46.381948 env[1314]: time="2025-05-13T00:18:46.378503031Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-nx6nj,Uid:67204280-7d89-44dd-9e1e-99d206053146,Namespace:default,Attempt:0,}" May 13 00:18:46.414704 systemd-networkd[1097]: cni0: Link UP May 13 00:18:46.414710 systemd-networkd[1097]: cni0: Gained carrier May 13 00:18:46.416757 systemd-networkd[1097]: cni0: Lost carrier May 13 00:18:46.420853 systemd-networkd[1097]: vethecef26fd: Link UP May 13 00:18:46.422875 kernel: cni0: port 1(vethecef26fd) entered blocking state May 13 00:18:46.422979 kernel: cni0: port 1(vethecef26fd) entered disabled state May 13 00:18:46.423867 kernel: device vethecef26fd entered promiscuous mode May 13 00:18:46.423925 kernel: cni0: port 1(vethecef26fd) entered blocking state May 13 00:18:46.424638 kernel: cni0: port 1(vethecef26fd) entered forwarding state May 13 00:18:46.428213 kernel: cni0: port 1(vethecef26fd) entered disabled state May 13 00:18:46.439792 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethecef26fd: link becomes ready May 13 00:18:46.439883 kernel: cni0: port 1(vethecef26fd) entered blocking state May 13 00:18:46.439904 kernel: cni0: port 1(vethecef26fd) entered forwarding state May 13 00:18:46.440578 systemd-networkd[1097]: vethecef26fd: Gained carrier May 13 00:18:46.440800 systemd-networkd[1097]: cni0: Gained carrier May 13 00:18:46.442960 env[1314]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000018928), "name":"cbr0", "type":"bridge"} May 13 00:18:46.442960 env[1314]: delegateAdd: netconf sent to delegate plugin: May 13 00:18:46.454681 env[1314]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-13T00:18:46.454623965Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 13 00:18:46.454681 env[1314]: time="2025-05-13T00:18:46.454661632Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 13 00:18:46.454681 env[1314]: time="2025-05-13T00:18:46.454672492Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 13 00:18:46.454893 env[1314]: time="2025-05-13T00:18:46.454861150Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/313e6c009806945ae8d37728542d833d2c0dcb30395269e64f5b609f12c04ac6 pid=2139 runtime=io.containerd.runc.v2 May 13 00:18:46.488753 systemd-resolved[1236]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 00:18:46.504932 env[1314]: time="2025-05-13T00:18:46.504837336Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-nx6nj,Uid:67204280-7d89-44dd-9e1e-99d206053146,Namespace:default,Attempt:0,} returns sandbox id \"313e6c009806945ae8d37728542d833d2c0dcb30395269e64f5b609f12c04ac6\"" May 13 00:18:46.506589 env[1314]: time="2025-05-13T00:18:46.506551171Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 13 00:18:47.069385 kubelet[1565]: E0513 00:18:47.069337 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:48.070173 kubelet[1565]: E0513 00:18:48.070122 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:48.405413 systemd-networkd[1097]: cni0: Gained IPv6LL May 13 00:18:48.469424 systemd-networkd[1097]: vethecef26fd: Gained IPv6LL May 13 00:18:48.515409 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2802891836.mount: Deactivated successfully. May 13 00:18:49.070527 kubelet[1565]: E0513 00:18:49.070468 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:49.785792 env[1314]: time="2025-05-13T00:18:49.785746514Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:49.787884 env[1314]: time="2025-05-13T00:18:49.787844077Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:49.789642 env[1314]: time="2025-05-13T00:18:49.789618731Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:49.791618 env[1314]: time="2025-05-13T00:18:49.791583334Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:49.791974 env[1314]: time="2025-05-13T00:18:49.791937120Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 13 00:18:49.794619 env[1314]: time="2025-05-13T00:18:49.794582061Z" level=info msg="CreateContainer within sandbox \"313e6c009806945ae8d37728542d833d2c0dcb30395269e64f5b609f12c04ac6\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 13 00:18:49.816653 env[1314]: time="2025-05-13T00:18:49.816604987Z" level=info msg="CreateContainer within sandbox \"313e6c009806945ae8d37728542d833d2c0dcb30395269e64f5b609f12c04ac6\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"aeae1675f93913b55eaf0afcba14b5e3f18580519f55e298710a9df68661eef7\"" May 13 00:18:49.817070 env[1314]: time="2025-05-13T00:18:49.817040471Z" level=info msg="StartContainer for \"aeae1675f93913b55eaf0afcba14b5e3f18580519f55e298710a9df68661eef7\"" May 13 00:18:49.881639 env[1314]: time="2025-05-13T00:18:49.881588380Z" level=info msg="StartContainer for \"aeae1675f93913b55eaf0afcba14b5e3f18580519f55e298710a9df68661eef7\" returns successfully" May 13 00:18:50.071095 kubelet[1565]: E0513 00:18:50.070735 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:50.272984 kubelet[1565]: I0513 00:18:50.272919 1565 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-nx6nj" podStartSLOduration=0.985927606 podStartE2EDuration="4.272904983s" podCreationTimestamp="2025-05-13 00:18:46 +0000 UTC" firstStartedPulling="2025-05-13 00:18:46.506288941 +0000 UTC m=+12.240348063" lastFinishedPulling="2025-05-13 00:18:49.793266318 +0000 UTC m=+15.527325440" observedRunningTime="2025-05-13 00:18:50.272511328 +0000 UTC m=+16.006570450" watchObservedRunningTime="2025-05-13 00:18:50.272904983 +0000 UTC m=+16.006964065" May 13 00:18:51.071351 kubelet[1565]: E0513 00:18:51.071290 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:52.072049 kubelet[1565]: E0513 00:18:52.072005 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:52.968212 kubelet[1565]: I0513 00:18:52.968171 1565 topology_manager.go:215] "Topology Admit Handler" podUID="469c5498-15e5-4fd9-88b9-90f3e50171b0" podNamespace="default" podName="nfs-server-provisioner-0" May 13 00:18:53.072883 kubelet[1565]: E0513 00:18:53.072840 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:53.082075 kubelet[1565]: I0513 00:18:53.082035 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/469c5498-15e5-4fd9-88b9-90f3e50171b0-data\") pod \"nfs-server-provisioner-0\" (UID: \"469c5498-15e5-4fd9-88b9-90f3e50171b0\") " pod="default/nfs-server-provisioner-0" May 13 00:18:53.082163 kubelet[1565]: I0513 00:18:53.082082 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-d562p\" (UniqueName: \"kubernetes.io/projected/469c5498-15e5-4fd9-88b9-90f3e50171b0-kube-api-access-d562p\") pod \"nfs-server-provisioner-0\" (UID: \"469c5498-15e5-4fd9-88b9-90f3e50171b0\") " pod="default/nfs-server-provisioner-0" May 13 00:18:53.271936 env[1314]: time="2025-05-13T00:18:53.271666428Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:469c5498-15e5-4fd9-88b9-90f3e50171b0,Namespace:default,Attempt:0,}" May 13 00:18:53.292240 systemd-networkd[1097]: vethd5009271: Link UP May 13 00:18:53.295766 kernel: cni0: port 2(vethd5009271) entered blocking state May 13 00:18:53.295868 kernel: cni0: port 2(vethd5009271) entered disabled state May 13 00:18:53.295897 kernel: device vethd5009271 entered promiscuous mode May 13 00:18:53.301999 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 13 00:18:53.302088 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethd5009271: link becomes ready May 13 00:18:53.302113 kernel: cni0: port 2(vethd5009271) entered blocking state May 13 00:18:53.303089 kernel: cni0: port 2(vethd5009271) entered forwarding state May 13 00:18:53.303064 systemd-networkd[1097]: vethd5009271: Gained carrier May 13 00:18:53.304652 env[1314]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} May 13 00:18:53.304652 env[1314]: delegateAdd: netconf sent to delegate plugin: May 13 00:18:53.314931 env[1314]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-13T00:18:53.314864097Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 13 00:18:53.314931 env[1314]: time="2025-05-13T00:18:53.314902484Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 13 00:18:53.314931 env[1314]: time="2025-05-13T00:18:53.314912371Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 13 00:18:53.315100 env[1314]: time="2025-05-13T00:18:53.315065879Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/ea2cab3207566585183d5ccd219e45d57829fd4349841eb5182142e9d5ba7a6a pid=2300 runtime=io.containerd.runc.v2 May 13 00:18:53.347219 systemd-resolved[1236]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 00:18:53.365801 env[1314]: time="2025-05-13T00:18:53.365744384Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:469c5498-15e5-4fd9-88b9-90f3e50171b0,Namespace:default,Attempt:0,} returns sandbox id \"ea2cab3207566585183d5ccd219e45d57829fd4349841eb5182142e9d5ba7a6a\"" May 13 00:18:53.367395 env[1314]: time="2025-05-13T00:18:53.367358643Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 13 00:18:54.073885 kubelet[1565]: E0513 00:18:54.073841 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:55.060103 kubelet[1565]: E0513 00:18:55.060065 1565 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:55.074363 kubelet[1565]: E0513 00:18:55.074317 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:55.125433 systemd-networkd[1097]: vethd5009271: Gained IPv6LL May 13 00:18:55.467436 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3055886169.mount: Deactivated successfully. May 13 00:18:56.075383 kubelet[1565]: E0513 00:18:56.075327 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:57.075481 kubelet[1565]: E0513 00:18:57.075442 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:57.233605 env[1314]: time="2025-05-13T00:18:57.233558422Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:57.235398 env[1314]: time="2025-05-13T00:18:57.235352324Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:57.237567 env[1314]: time="2025-05-13T00:18:57.237536027Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:57.239418 env[1314]: time="2025-05-13T00:18:57.239386632Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:18:57.240274 env[1314]: time="2025-05-13T00:18:57.240227460Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 13 00:18:57.243152 env[1314]: time="2025-05-13T00:18:57.243113374Z" level=info msg="CreateContainer within sandbox \"ea2cab3207566585183d5ccd219e45d57829fd4349841eb5182142e9d5ba7a6a\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 13 00:18:57.252780 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3276370770.mount: Deactivated successfully. May 13 00:18:57.256783 env[1314]: time="2025-05-13T00:18:57.256735127Z" level=info msg="CreateContainer within sandbox \"ea2cab3207566585183d5ccd219e45d57829fd4349841eb5182142e9d5ba7a6a\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"1a39a389b58f86aec7156e1ea88b2df7859d5f3976ce1a37fdc2b544df671d6d\"" May 13 00:18:57.257336 env[1314]: time="2025-05-13T00:18:57.257265507Z" level=info msg="StartContainer for \"1a39a389b58f86aec7156e1ea88b2df7859d5f3976ce1a37fdc2b544df671d6d\"" May 13 00:18:57.333485 env[1314]: time="2025-05-13T00:18:57.333376984Z" level=info msg="StartContainer for \"1a39a389b58f86aec7156e1ea88b2df7859d5f3976ce1a37fdc2b544df671d6d\" returns successfully" May 13 00:18:58.076410 kubelet[1565]: E0513 00:18:58.076361 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:18:59.076660 kubelet[1565]: E0513 00:18:59.076612 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:00.077398 kubelet[1565]: E0513 00:19:00.077344 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:01.077827 kubelet[1565]: E0513 00:19:01.077770 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:02.078172 kubelet[1565]: E0513 00:19:02.078119 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:03.078626 kubelet[1565]: E0513 00:19:03.078576 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:04.079194 kubelet[1565]: E0513 00:19:04.079111 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:05.079974 kubelet[1565]: E0513 00:19:05.079936 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:06.080259 kubelet[1565]: E0513 00:19:06.080200 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:07.080829 kubelet[1565]: E0513 00:19:07.080795 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:07.526272 kubelet[1565]: I0513 00:19:07.526133 1565 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.651630225 podStartE2EDuration="15.526115394s" podCreationTimestamp="2025-05-13 00:18:52 +0000 UTC" firstStartedPulling="2025-05-13 00:18:53.36707016 +0000 UTC m=+19.101129242" lastFinishedPulling="2025-05-13 00:18:57.241555329 +0000 UTC m=+22.975614411" observedRunningTime="2025-05-13 00:18:58.290121351 +0000 UTC m=+24.024180473" watchObservedRunningTime="2025-05-13 00:19:07.526115394 +0000 UTC m=+33.260174516" May 13 00:19:07.526272 kubelet[1565]: I0513 00:19:07.526273 1565 topology_manager.go:215] "Topology Admit Handler" podUID="4c4f9fb3-1d4a-40fe-99c9-7fc49e184171" podNamespace="default" podName="test-pod-1" May 13 00:19:07.661157 kubelet[1565]: I0513 00:19:07.661122 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7mrbd\" (UniqueName: \"kubernetes.io/projected/4c4f9fb3-1d4a-40fe-99c9-7fc49e184171-kube-api-access-7mrbd\") pod \"test-pod-1\" (UID: \"4c4f9fb3-1d4a-40fe-99c9-7fc49e184171\") " pod="default/test-pod-1" May 13 00:19:07.661157 kubelet[1565]: I0513 00:19:07.661161 1565 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-b8426667-2b58-4923-a29e-806fc1b2e548\" (UniqueName: \"kubernetes.io/nfs/4c4f9fb3-1d4a-40fe-99c9-7fc49e184171-pvc-b8426667-2b58-4923-a29e-806fc1b2e548\") pod \"test-pod-1\" (UID: \"4c4f9fb3-1d4a-40fe-99c9-7fc49e184171\") " pod="default/test-pod-1" May 13 00:19:07.797724 kernel: FS-Cache: Loaded May 13 00:19:07.830617 kernel: RPC: Registered named UNIX socket transport module. May 13 00:19:07.830744 kernel: RPC: Registered udp transport module. May 13 00:19:07.830765 kernel: RPC: Registered tcp transport module. May 13 00:19:07.831872 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 13 00:19:07.882328 kernel: FS-Cache: Netfs 'nfs' registered for caching May 13 00:19:08.011507 kernel: NFS: Registering the id_resolver key type May 13 00:19:08.011636 kernel: Key type id_resolver registered May 13 00:19:08.011658 kernel: Key type id_legacy registered May 13 00:19:08.040931 nfsidmap[2477]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 13 00:19:08.044452 nfsidmap[2480]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 13 00:19:08.082393 kubelet[1565]: E0513 00:19:08.082235 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:08.130191 env[1314]: time="2025-05-13T00:19:08.130074260Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4c4f9fb3-1d4a-40fe-99c9-7fc49e184171,Namespace:default,Attempt:0,}" May 13 00:19:08.151201 systemd-networkd[1097]: veth328faae1: Link UP May 13 00:19:08.152983 kernel: cni0: port 3(veth328faae1) entered blocking state May 13 00:19:08.153059 kernel: cni0: port 3(veth328faae1) entered disabled state May 13 00:19:08.157546 kernel: device veth328faae1 entered promiscuous mode May 13 00:19:08.163618 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 13 00:19:08.163730 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth328faae1: link becomes ready May 13 00:19:08.163762 kernel: cni0: port 3(veth328faae1) entered blocking state May 13 00:19:08.165001 kernel: cni0: port 3(veth328faae1) entered forwarding state May 13 00:19:08.165084 systemd-networkd[1097]: veth328faae1: Gained carrier May 13 00:19:08.166594 env[1314]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} May 13 00:19:08.166594 env[1314]: delegateAdd: netconf sent to delegate plugin: May 13 00:19:08.177513 env[1314]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-13T00:19:08.177426166Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 13 00:19:08.177664 env[1314]: time="2025-05-13T00:19:08.177533583Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 13 00:19:08.177664 env[1314]: time="2025-05-13T00:19:08.177560707Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 13 00:19:08.177805 env[1314]: time="2025-05-13T00:19:08.177773699Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/04f8cc7df56bbbace6d872c63f44f1c84f100e7324b572696d6b8fed6391e1a3 pid=2526 runtime=io.containerd.runc.v2 May 13 00:19:08.210444 systemd-resolved[1236]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 00:19:08.227190 env[1314]: time="2025-05-13T00:19:08.227142115Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4c4f9fb3-1d4a-40fe-99c9-7fc49e184171,Namespace:default,Attempt:0,} returns sandbox id \"04f8cc7df56bbbace6d872c63f44f1c84f100e7324b572696d6b8fed6391e1a3\"" May 13 00:19:08.228948 env[1314]: time="2025-05-13T00:19:08.228883423Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 13 00:19:08.508608 env[1314]: time="2025-05-13T00:19:08.508545619Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:19:08.510787 env[1314]: time="2025-05-13T00:19:08.510734915Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:19:08.513435 env[1314]: time="2025-05-13T00:19:08.513391243Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:19:08.516823 env[1314]: time="2025-05-13T00:19:08.516782243Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 13 00:19:08.517717 env[1314]: time="2025-05-13T00:19:08.517679741Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 13 00:19:08.520411 env[1314]: time="2025-05-13T00:19:08.520375835Z" level=info msg="CreateContainer within sandbox \"04f8cc7df56bbbace6d872c63f44f1c84f100e7324b572696d6b8fed6391e1a3\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 13 00:19:08.536447 env[1314]: time="2025-05-13T00:19:08.536386012Z" level=info msg="CreateContainer within sandbox \"04f8cc7df56bbbace6d872c63f44f1c84f100e7324b572696d6b8fed6391e1a3\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"4d90260fb51f8b04fde7f48684ef8f9dcf79bcdc6105e541fb1d1667547cd0f8\"" May 13 00:19:08.537024 env[1314]: time="2025-05-13T00:19:08.536996626Z" level=info msg="StartContainer for \"4d90260fb51f8b04fde7f48684ef8f9dcf79bcdc6105e541fb1d1667547cd0f8\"" May 13 00:19:08.594688 env[1314]: time="2025-05-13T00:19:08.594634111Z" level=info msg="StartContainer for \"4d90260fb51f8b04fde7f48684ef8f9dcf79bcdc6105e541fb1d1667547cd0f8\" returns successfully" May 13 00:19:09.083426 kubelet[1565]: E0513 00:19:09.083370 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:09.461467 systemd-networkd[1097]: veth328faae1: Gained IPv6LL May 13 00:19:10.084579 kubelet[1565]: E0513 00:19:10.084528 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:11.084901 kubelet[1565]: E0513 00:19:11.084859 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:12.085374 kubelet[1565]: E0513 00:19:12.085282 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:12.996257 update_engine[1306]: I0513 00:19:12.996186 1306 update_attempter.cc:509] Updating boot flags... May 13 00:19:13.086103 kubelet[1565]: E0513 00:19:13.086049 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:14.087094 kubelet[1565]: E0513 00:19:14.087043 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:15.060593 kubelet[1565]: E0513 00:19:15.060538 1565 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 00:19:15.087876 kubelet[1565]: E0513 00:19:15.087834 1565 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"