Jul 11 00:18:12.730486 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 11 00:18:12.730505 kernel: Linux version 5.15.186-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Thu Jul 10 23:22:35 -00 2025 Jul 11 00:18:12.730513 kernel: efi: EFI v2.70 by EDK II Jul 11 00:18:12.730519 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Jul 11 00:18:12.730524 kernel: random: crng init done Jul 11 00:18:12.730530 kernel: ACPI: Early table checksum verification disabled Jul 11 00:18:12.730536 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Jul 11 00:18:12.730543 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 11 00:18:12.730549 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730554 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730560 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730565 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730571 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730576 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730584 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730590 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730596 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 11 00:18:12.730602 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 11 00:18:12.730608 kernel: NUMA: Failed to initialise from firmware Jul 11 00:18:12.730614 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 11 00:18:12.730620 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Jul 11 00:18:12.730626 kernel: Zone ranges: Jul 11 00:18:12.730632 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 11 00:18:12.730639 kernel: DMA32 empty Jul 11 00:18:12.730644 kernel: Normal empty Jul 11 00:18:12.730650 kernel: Movable zone start for each node Jul 11 00:18:12.730656 kernel: Early memory node ranges Jul 11 00:18:12.730662 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Jul 11 00:18:12.730667 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Jul 11 00:18:12.730673 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Jul 11 00:18:12.730679 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Jul 11 00:18:12.730685 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Jul 11 00:18:12.730691 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Jul 11 00:18:12.730697 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Jul 11 00:18:12.730718 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 11 00:18:12.730727 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 11 00:18:12.730733 kernel: psci: probing for conduit method from ACPI. Jul 11 00:18:12.730739 kernel: psci: PSCIv1.1 detected in firmware. Jul 11 00:18:12.730745 kernel: psci: Using standard PSCI v0.2 function IDs Jul 11 00:18:12.730751 kernel: psci: Trusted OS migration not required Jul 11 00:18:12.730759 kernel: psci: SMC Calling Convention v1.1 Jul 11 00:18:12.730765 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 11 00:18:12.730773 kernel: ACPI: SRAT not present Jul 11 00:18:12.730779 kernel: percpu: Embedded 30 pages/cpu s82968 r8192 d31720 u122880 Jul 11 00:18:12.730785 kernel: pcpu-alloc: s82968 r8192 d31720 u122880 alloc=30*4096 Jul 11 00:18:12.730792 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 11 00:18:12.730798 kernel: Detected PIPT I-cache on CPU0 Jul 11 00:18:12.730805 kernel: CPU features: detected: GIC system register CPU interface Jul 11 00:18:12.730811 kernel: CPU features: detected: Hardware dirty bit management Jul 11 00:18:12.730817 kernel: CPU features: detected: Spectre-v4 Jul 11 00:18:12.730823 kernel: CPU features: detected: Spectre-BHB Jul 11 00:18:12.730830 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 11 00:18:12.730836 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 11 00:18:12.730843 kernel: CPU features: detected: ARM erratum 1418040 Jul 11 00:18:12.730849 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 11 00:18:12.730855 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Jul 11 00:18:12.730861 kernel: Policy zone: DMA Jul 11 00:18:12.730869 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=8fd3ef416118421b63f30b3d02e5d4feea39e34704e91050cdad11fae31df42c Jul 11 00:18:12.730875 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 11 00:18:12.730882 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 11 00:18:12.730888 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 11 00:18:12.730894 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 11 00:18:12.730902 kernel: Memory: 2457340K/2572288K available (9792K kernel code, 2094K rwdata, 7588K rodata, 36416K init, 777K bss, 114948K reserved, 0K cma-reserved) Jul 11 00:18:12.730909 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 11 00:18:12.730915 kernel: trace event string verifier disabled Jul 11 00:18:12.730921 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 11 00:18:12.730928 kernel: rcu: RCU event tracing is enabled. Jul 11 00:18:12.730934 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 11 00:18:12.730940 kernel: Trampoline variant of Tasks RCU enabled. Jul 11 00:18:12.730947 kernel: Tracing variant of Tasks RCU enabled. Jul 11 00:18:12.730953 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 11 00:18:12.730959 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 11 00:18:12.730965 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 11 00:18:12.730973 kernel: GICv3: 256 SPIs implemented Jul 11 00:18:12.730979 kernel: GICv3: 0 Extended SPIs implemented Jul 11 00:18:12.730986 kernel: GICv3: Distributor has no Range Selector support Jul 11 00:18:12.730992 kernel: Root IRQ handler: gic_handle_irq Jul 11 00:18:12.730998 kernel: GICv3: 16 PPIs implemented Jul 11 00:18:12.731008 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 11 00:18:12.731015 kernel: ACPI: SRAT not present Jul 11 00:18:12.731021 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 11 00:18:12.731027 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Jul 11 00:18:12.731034 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Jul 11 00:18:12.731040 kernel: GICv3: using LPI property table @0x00000000400d0000 Jul 11 00:18:12.731046 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Jul 11 00:18:12.731054 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 11 00:18:12.731060 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 11 00:18:12.731066 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 11 00:18:12.731073 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 11 00:18:12.731079 kernel: arm-pv: using stolen time PV Jul 11 00:18:12.731085 kernel: Console: colour dummy device 80x25 Jul 11 00:18:12.731092 kernel: ACPI: Core revision 20210730 Jul 11 00:18:12.731098 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 11 00:18:12.731105 kernel: pid_max: default: 32768 minimum: 301 Jul 11 00:18:12.731111 kernel: LSM: Security Framework initializing Jul 11 00:18:12.731119 kernel: SELinux: Initializing. Jul 11 00:18:12.731126 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 11 00:18:12.731132 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 11 00:18:12.731138 kernel: rcu: Hierarchical SRCU implementation. Jul 11 00:18:12.731145 kernel: Platform MSI: ITS@0x8080000 domain created Jul 11 00:18:12.731151 kernel: PCI/MSI: ITS@0x8080000 domain created Jul 11 00:18:12.731157 kernel: Remapping and enabling EFI services. Jul 11 00:18:12.731164 kernel: smp: Bringing up secondary CPUs ... Jul 11 00:18:12.731170 kernel: Detected PIPT I-cache on CPU1 Jul 11 00:18:12.731178 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 11 00:18:12.731184 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Jul 11 00:18:12.731190 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 11 00:18:12.731197 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 11 00:18:12.731203 kernel: Detected PIPT I-cache on CPU2 Jul 11 00:18:12.731210 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 11 00:18:12.731217 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Jul 11 00:18:12.731224 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 11 00:18:12.731230 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 11 00:18:12.731236 kernel: Detected PIPT I-cache on CPU3 Jul 11 00:18:12.731243 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 11 00:18:12.731250 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Jul 11 00:18:12.731256 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 11 00:18:12.731263 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 11 00:18:12.731274 kernel: smp: Brought up 1 node, 4 CPUs Jul 11 00:18:12.731281 kernel: SMP: Total of 4 processors activated. Jul 11 00:18:12.731288 kernel: CPU features: detected: 32-bit EL0 Support Jul 11 00:18:12.731294 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 11 00:18:12.731301 kernel: CPU features: detected: Common not Private translations Jul 11 00:18:12.731308 kernel: CPU features: detected: CRC32 instructions Jul 11 00:18:12.731314 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 11 00:18:12.731321 kernel: CPU features: detected: LSE atomic instructions Jul 11 00:18:12.731329 kernel: CPU features: detected: Privileged Access Never Jul 11 00:18:12.731336 kernel: CPU features: detected: RAS Extension Support Jul 11 00:18:12.731342 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 11 00:18:12.731349 kernel: CPU: All CPU(s) started at EL1 Jul 11 00:18:12.731356 kernel: alternatives: patching kernel code Jul 11 00:18:12.731363 kernel: devtmpfs: initialized Jul 11 00:18:12.731370 kernel: KASLR enabled Jul 11 00:18:12.731384 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 11 00:18:12.731391 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 11 00:18:12.731398 kernel: pinctrl core: initialized pinctrl subsystem Jul 11 00:18:12.731404 kernel: SMBIOS 3.0.0 present. Jul 11 00:18:12.731411 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Jul 11 00:18:12.731418 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 11 00:18:12.731425 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 11 00:18:12.731434 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 11 00:18:12.731441 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 11 00:18:12.731447 kernel: audit: initializing netlink subsys (disabled) Jul 11 00:18:12.731454 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Jul 11 00:18:12.731461 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 11 00:18:12.731467 kernel: cpuidle: using governor menu Jul 11 00:18:12.731474 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 11 00:18:12.731481 kernel: ASID allocator initialised with 32768 entries Jul 11 00:18:12.731487 kernel: ACPI: bus type PCI registered Jul 11 00:18:12.731497 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 11 00:18:12.731508 kernel: Serial: AMBA PL011 UART driver Jul 11 00:18:12.731517 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Jul 11 00:18:12.731524 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Jul 11 00:18:12.731530 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Jul 11 00:18:12.731537 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Jul 11 00:18:12.731544 kernel: cryptd: max_cpu_qlen set to 1000 Jul 11 00:18:12.731551 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 11 00:18:12.731558 kernel: ACPI: Added _OSI(Module Device) Jul 11 00:18:12.731566 kernel: ACPI: Added _OSI(Processor Device) Jul 11 00:18:12.731572 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 11 00:18:12.731579 kernel: ACPI: Added _OSI(Linux-Dell-Video) Jul 11 00:18:12.731585 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Jul 11 00:18:12.731592 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Jul 11 00:18:12.731599 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 11 00:18:12.731605 kernel: ACPI: Interpreter enabled Jul 11 00:18:12.731612 kernel: ACPI: Using GIC for interrupt routing Jul 11 00:18:12.731619 kernel: ACPI: MCFG table detected, 1 entries Jul 11 00:18:12.731627 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 11 00:18:12.731634 kernel: printk: console [ttyAMA0] enabled Jul 11 00:18:12.731640 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 11 00:18:12.731774 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 11 00:18:12.731842 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 11 00:18:12.731902 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 11 00:18:12.731962 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 11 00:18:12.732023 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 11 00:18:12.732032 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 11 00:18:12.732039 kernel: PCI host bridge to bus 0000:00 Jul 11 00:18:12.732114 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 11 00:18:12.732170 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 11 00:18:12.732224 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 11 00:18:12.732277 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 11 00:18:12.732355 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Jul 11 00:18:12.732437 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Jul 11 00:18:12.732508 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Jul 11 00:18:12.732573 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Jul 11 00:18:12.732635 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Jul 11 00:18:12.732696 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Jul 11 00:18:12.732789 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Jul 11 00:18:12.732855 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Jul 11 00:18:12.732912 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 11 00:18:12.732966 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 11 00:18:12.733021 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 11 00:18:12.733029 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 11 00:18:12.733036 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 11 00:18:12.733044 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 11 00:18:12.733052 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 11 00:18:12.733059 kernel: iommu: Default domain type: Translated Jul 11 00:18:12.733066 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 11 00:18:12.733072 kernel: vgaarb: loaded Jul 11 00:18:12.733081 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 11 00:18:12.733088 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 11 00:18:12.733095 kernel: PTP clock support registered Jul 11 00:18:12.733101 kernel: Registered efivars operations Jul 11 00:18:12.733108 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 11 00:18:12.733115 kernel: VFS: Disk quotas dquot_6.6.0 Jul 11 00:18:12.733123 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 11 00:18:12.733129 kernel: pnp: PnP ACPI init Jul 11 00:18:12.733197 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 11 00:18:12.733206 kernel: pnp: PnP ACPI: found 1 devices Jul 11 00:18:12.733213 kernel: NET: Registered PF_INET protocol family Jul 11 00:18:12.733220 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 11 00:18:12.733227 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 11 00:18:12.733234 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 11 00:18:12.733243 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 11 00:18:12.733249 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Jul 11 00:18:12.733256 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 11 00:18:12.733263 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 11 00:18:12.733270 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 11 00:18:12.733276 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 11 00:18:12.733283 kernel: PCI: CLS 0 bytes, default 64 Jul 11 00:18:12.733290 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Jul 11 00:18:12.733297 kernel: kvm [1]: HYP mode not available Jul 11 00:18:12.733305 kernel: Initialise system trusted keyrings Jul 11 00:18:12.733311 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 11 00:18:12.733318 kernel: Key type asymmetric registered Jul 11 00:18:12.733324 kernel: Asymmetric key parser 'x509' registered Jul 11 00:18:12.733331 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 11 00:18:12.733338 kernel: io scheduler mq-deadline registered Jul 11 00:18:12.733345 kernel: io scheduler kyber registered Jul 11 00:18:12.733351 kernel: io scheduler bfq registered Jul 11 00:18:12.733358 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 11 00:18:12.733366 kernel: ACPI: button: Power Button [PWRB] Jul 11 00:18:12.733382 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 11 00:18:12.733450 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 11 00:18:12.733460 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 11 00:18:12.733466 kernel: thunder_xcv, ver 1.0 Jul 11 00:18:12.733473 kernel: thunder_bgx, ver 1.0 Jul 11 00:18:12.733480 kernel: nicpf, ver 1.0 Jul 11 00:18:12.733486 kernel: nicvf, ver 1.0 Jul 11 00:18:12.733556 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 11 00:18:12.733617 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-11T00:18:12 UTC (1752193092) Jul 11 00:18:12.733626 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 11 00:18:12.733633 kernel: NET: Registered PF_INET6 protocol family Jul 11 00:18:12.733640 kernel: Segment Routing with IPv6 Jul 11 00:18:12.733647 kernel: In-situ OAM (IOAM) with IPv6 Jul 11 00:18:12.733653 kernel: NET: Registered PF_PACKET protocol family Jul 11 00:18:12.733660 kernel: Key type dns_resolver registered Jul 11 00:18:12.733667 kernel: registered taskstats version 1 Jul 11 00:18:12.733675 kernel: Loading compiled-in X.509 certificates Jul 11 00:18:12.733682 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.186-flatcar: e29f2f0310c2b60e0457f826e7476605fb3b6ab2' Jul 11 00:18:12.733688 kernel: Key type .fscrypt registered Jul 11 00:18:12.733695 kernel: Key type fscrypt-provisioning registered Jul 11 00:18:12.733733 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 11 00:18:12.733742 kernel: ima: Allocated hash algorithm: sha1 Jul 11 00:18:12.733748 kernel: ima: No architecture policies found Jul 11 00:18:12.733755 kernel: clk: Disabling unused clocks Jul 11 00:18:12.733762 kernel: Freeing unused kernel memory: 36416K Jul 11 00:18:12.733770 kernel: Run /init as init process Jul 11 00:18:12.733776 kernel: with arguments: Jul 11 00:18:12.733783 kernel: /init Jul 11 00:18:12.733789 kernel: with environment: Jul 11 00:18:12.733796 kernel: HOME=/ Jul 11 00:18:12.733802 kernel: TERM=linux Jul 11 00:18:12.733809 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 11 00:18:12.733817 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) Jul 11 00:18:12.733827 systemd[1]: Detected virtualization kvm. Jul 11 00:18:12.733834 systemd[1]: Detected architecture arm64. Jul 11 00:18:12.733841 systemd[1]: Running in initrd. Jul 11 00:18:12.733848 systemd[1]: No hostname configured, using default hostname. Jul 11 00:18:12.733855 systemd[1]: Hostname set to . Jul 11 00:18:12.733863 systemd[1]: Initializing machine ID from VM UUID. Jul 11 00:18:12.733870 systemd[1]: Queued start job for default target initrd.target. Jul 11 00:18:12.733877 systemd[1]: Started systemd-ask-password-console.path. Jul 11 00:18:12.733885 systemd[1]: Reached target cryptsetup.target. Jul 11 00:18:12.733892 systemd[1]: Reached target paths.target. Jul 11 00:18:12.733899 systemd[1]: Reached target slices.target. Jul 11 00:18:12.733906 systemd[1]: Reached target swap.target. Jul 11 00:18:12.733913 systemd[1]: Reached target timers.target. Jul 11 00:18:12.733920 systemd[1]: Listening on iscsid.socket. Jul 11 00:18:12.733927 systemd[1]: Listening on iscsiuio.socket. Jul 11 00:18:12.733936 systemd[1]: Listening on systemd-journald-audit.socket. Jul 11 00:18:12.733943 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 11 00:18:12.733951 systemd[1]: Listening on systemd-journald.socket. Jul 11 00:18:12.733958 systemd[1]: Listening on systemd-networkd.socket. Jul 11 00:18:12.733965 systemd[1]: Listening on systemd-udevd-control.socket. Jul 11 00:18:12.733972 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 11 00:18:12.733979 systemd[1]: Reached target sockets.target. Jul 11 00:18:12.733986 systemd[1]: Starting kmod-static-nodes.service... Jul 11 00:18:12.733993 systemd[1]: Finished network-cleanup.service. Jul 11 00:18:12.734001 systemd[1]: Starting systemd-fsck-usr.service... Jul 11 00:18:12.734008 systemd[1]: Starting systemd-journald.service... Jul 11 00:18:12.734015 systemd[1]: Starting systemd-modules-load.service... Jul 11 00:18:12.734023 systemd[1]: Starting systemd-resolved.service... Jul 11 00:18:12.734030 systemd[1]: Starting systemd-vconsole-setup.service... Jul 11 00:18:12.734037 systemd[1]: Finished kmod-static-nodes.service. Jul 11 00:18:12.734044 systemd[1]: Finished systemd-fsck-usr.service. Jul 11 00:18:12.734051 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 11 00:18:12.734058 systemd[1]: Finished systemd-vconsole-setup.service. Jul 11 00:18:12.734066 systemd[1]: Starting dracut-cmdline-ask.service... Jul 11 00:18:12.734077 systemd-journald[289]: Journal started Jul 11 00:18:12.734118 systemd-journald[289]: Runtime Journal (/run/log/journal/2a3392270a6a422f89aa62fe35f794f6) is 6.0M, max 48.7M, 42.6M free. Jul 11 00:18:12.720360 systemd-modules-load[290]: Inserted module 'overlay' Jul 11 00:18:12.736196 systemd[1]: Started systemd-journald.service. Jul 11 00:18:12.736000 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' Jul 11 00:18:12.740580 kernel: audit: type=1130 audit(1752193092.736:2): 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' Jul 11 00:18:12.736925 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 11 00:18:12.745758 kernel: audit: type=1130 audit(1752193092.741:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:12.741000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:12.741259 systemd-resolved[291]: Positive Trust Anchors: Jul 11 00:18:12.741267 systemd-resolved[291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 11 00:18:12.747000 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' Jul 11 00:18:12.741295 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 Jul 11 00:18:12.766567 kernel: audit: type=1130 audit(1752193092.747:4): 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' Jul 11 00:18:12.766591 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 11 00:18:12.766601 kernel: audit: type=1130 audit(1752193092.755:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:12.766611 kernel: Bridge firewalling registered Jul 11 00:18:12.755000 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' Jul 11 00:18:12.748283 systemd-resolved[291]: Defaulting to hostname 'linux'. Jul 11 00:18:12.749296 systemd[1]: Started systemd-resolved.service. Jul 11 00:18:12.769134 dracut-cmdline[308]: dracut-dracut-053 Jul 11 00:18:12.750602 systemd[1]: Reached target nss-lookup.target. Jul 11 00:18:12.756063 systemd[1]: Finished dracut-cmdline-ask.service. Jul 11 00:18:12.757572 systemd[1]: Starting dracut-cmdline.service... Jul 11 00:18:12.772637 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=8fd3ef416118421b63f30b3d02e5d4feea39e34704e91050cdad11fae31df42c Jul 11 00:18:12.759642 systemd-modules-load[290]: Inserted module 'br_netfilter' Jul 11 00:18:12.778730 kernel: SCSI subsystem initialized Jul 11 00:18:12.786730 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 11 00:18:12.786776 kernel: device-mapper: uevent: version 1.0.3 Jul 11 00:18:12.786786 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Jul 11 00:18:12.790052 systemd-modules-load[290]: Inserted module 'dm_multipath' Jul 11 00:18:12.790864 systemd[1]: Finished systemd-modules-load.service. Jul 11 00:18:12.792000 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' Jul 11 00:18:12.796322 systemd[1]: Starting systemd-sysctl.service... Jul 11 00:18:12.797736 kernel: audit: type=1130 audit(1752193092.792:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:12.804832 systemd[1]: Finished systemd-sysctl.service. Jul 11 00:18:12.804000 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' Jul 11 00:18:12.808729 kernel: audit: type=1130 audit(1752193092.804:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:12.838727 kernel: Loading iSCSI transport class v2.0-870. Jul 11 00:18:12.851740 kernel: iscsi: registered transport (tcp) Jul 11 00:18:12.871746 kernel: iscsi: registered transport (qla4xxx) Jul 11 00:18:12.871797 kernel: QLogic iSCSI HBA Driver Jul 11 00:18:12.907731 systemd[1]: Finished dracut-cmdline.service. Jul 11 00:18:12.912545 kernel: audit: type=1130 audit(1752193092.907:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:12.907000 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' Jul 11 00:18:12.909180 systemd[1]: Starting dracut-pre-udev.service... Jul 11 00:18:12.958400 kernel: raid6: neonx8 gen() 13524 MB/s Jul 11 00:18:12.976387 kernel: raid6: neonx8 xor() 10696 MB/s Jul 11 00:18:12.996388 kernel: raid6: neonx4 gen() 12984 MB/s Jul 11 00:18:13.015388 kernel: raid6: neonx4 xor() 11097 MB/s Jul 11 00:18:13.030250 kernel: raid6: neonx2 gen() 12413 MB/s Jul 11 00:18:13.046729 kernel: raid6: neonx2 xor() 5374 MB/s Jul 11 00:18:13.063728 kernel: raid6: neonx1 gen() 10583 MB/s Jul 11 00:18:13.080725 kernel: raid6: neonx1 xor() 8778 MB/s Jul 11 00:18:13.097727 kernel: raid6: int64x8 gen() 5988 MB/s Jul 11 00:18:13.114725 kernel: raid6: int64x8 xor() 3525 MB/s Jul 11 00:18:13.131723 kernel: raid6: int64x4 gen() 7198 MB/s Jul 11 00:18:13.148722 kernel: raid6: int64x4 xor() 3843 MB/s Jul 11 00:18:13.165732 kernel: raid6: int64x2 gen() 5875 MB/s Jul 11 00:18:13.182726 kernel: raid6: int64x2 xor() 3316 MB/s Jul 11 00:18:13.199732 kernel: raid6: int64x1 gen() 5031 MB/s Jul 11 00:18:13.216808 kernel: raid6: int64x1 xor() 2641 MB/s Jul 11 00:18:13.216821 kernel: raid6: using algorithm neonx8 gen() 13524 MB/s Jul 11 00:18:13.216831 kernel: raid6: .... xor() 10696 MB/s, rmw enabled Jul 11 00:18:13.217885 kernel: raid6: using neon recovery algorithm Jul 11 00:18:13.229185 kernel: xor: measuring software checksum speed Jul 11 00:18:13.229200 kernel: 8regs : 17170 MB/sec Jul 11 00:18:13.229209 kernel: 32regs : 20697 MB/sec Jul 11 00:18:13.229805 kernel: arm64_neon : 27663 MB/sec Jul 11 00:18:13.229815 kernel: xor: using function: arm64_neon (27663 MB/sec) Jul 11 00:18:13.284731 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Jul 11 00:18:13.295243 systemd[1]: Finished dracut-pre-udev.service. Jul 11 00:18:13.299516 kernel: audit: type=1130 audit(1752193093.295:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:13.299543 kernel: audit: type=1334 audit(1752193093.297:10): prog-id=7 op=LOAD Jul 11 00:18:13.295000 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' Jul 11 00:18:13.297000 audit: BPF prog-id=7 op=LOAD Jul 11 00:18:13.298000 audit: BPF prog-id=8 op=LOAD Jul 11 00:18:13.299880 systemd[1]: Starting systemd-udevd.service... Jul 11 00:18:13.313803 systemd-udevd[492]: Using default interface naming scheme 'v252'. Jul 11 00:18:13.317103 systemd[1]: Started systemd-udevd.service. Jul 11 00:18:13.317000 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' Jul 11 00:18:13.318486 systemd[1]: Starting dracut-pre-trigger.service... Jul 11 00:18:13.330265 dracut-pre-trigger[499]: rd.md=0: removing MD RAID activation Jul 11 00:18:13.358047 systemd[1]: Finished dracut-pre-trigger.service. Jul 11 00:18:13.358000 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' Jul 11 00:18:13.359622 systemd[1]: Starting systemd-udev-trigger.service... Jul 11 00:18:13.397477 systemd[1]: Finished systemd-udev-trigger.service. Jul 11 00:18:13.398000 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' Jul 11 00:18:13.429455 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 11 00:18:13.433499 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 11 00:18:13.433512 kernel: GPT:9289727 != 19775487 Jul 11 00:18:13.433528 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 11 00:18:13.433537 kernel: GPT:9289727 != 19775487 Jul 11 00:18:13.433545 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 11 00:18:13.433553 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 11 00:18:13.451877 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Jul 11 00:18:13.454742 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by (udev-worker) (538) Jul 11 00:18:13.459293 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Jul 11 00:18:13.461953 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Jul 11 00:18:13.462773 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Jul 11 00:18:13.469425 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 11 00:18:13.471068 systemd[1]: Starting disk-uuid.service... Jul 11 00:18:13.477101 disk-uuid[562]: Primary Header is updated. Jul 11 00:18:13.477101 disk-uuid[562]: Secondary Entries is updated. Jul 11 00:18:13.477101 disk-uuid[562]: Secondary Header is updated. Jul 11 00:18:13.480722 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 11 00:18:14.492735 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 11 00:18:14.493008 disk-uuid[563]: The operation has completed successfully. Jul 11 00:18:14.516099 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 11 00:18:14.516000 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' Jul 11 00:18:14.516000 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' Jul 11 00:18:14.516196 systemd[1]: Finished disk-uuid.service. Jul 11 00:18:14.517855 systemd[1]: Starting verity-setup.service... Jul 11 00:18:14.539739 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jul 11 00:18:14.565487 systemd[1]: Found device dev-mapper-usr.device. Jul 11 00:18:14.567579 systemd[1]: Mounting sysusr-usr.mount... Jul 11 00:18:14.569615 systemd[1]: Finished verity-setup.service. Jul 11 00:18:14.569000 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' Jul 11 00:18:14.615730 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Jul 11 00:18:14.615950 systemd[1]: Mounted sysusr-usr.mount. Jul 11 00:18:14.616605 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Jul 11 00:18:14.617332 systemd[1]: Starting ignition-setup.service... Jul 11 00:18:14.619125 systemd[1]: Starting parse-ip-for-networkd.service... Jul 11 00:18:14.626108 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 11 00:18:14.626147 kernel: BTRFS info (device vda6): using free space tree Jul 11 00:18:14.626157 kernel: BTRFS info (device vda6): has skinny extents Jul 11 00:18:14.635597 systemd[1]: mnt-oem.mount: Deactivated successfully. Jul 11 00:18:14.641911 systemd[1]: Finished ignition-setup.service. Jul 11 00:18:14.641000 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' Jul 11 00:18:14.643365 systemd[1]: Starting ignition-fetch-offline.service... Jul 11 00:18:14.699092 systemd[1]: Finished parse-ip-for-networkd.service. Jul 11 00:18:14.699000 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' Jul 11 00:18:14.700000 audit: BPF prog-id=9 op=LOAD Jul 11 00:18:14.701455 systemd[1]: Starting systemd-networkd.service... Jul 11 00:18:14.729488 ignition[648]: Ignition 2.14.0 Jul 11 00:18:14.729498 ignition[648]: Stage: fetch-offline Jul 11 00:18:14.729540 ignition[648]: no configs at "/usr/lib/ignition/base.d" Jul 11 00:18:14.729549 ignition[648]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 11 00:18:14.729687 ignition[648]: parsed url from cmdline: "" Jul 11 00:18:14.729691 ignition[648]: no config URL provided Jul 11 00:18:14.729696 ignition[648]: reading system config file "/usr/lib/ignition/user.ign" Jul 11 00:18:14.732968 systemd-networkd[739]: lo: Link UP Jul 11 00:18:14.729716 ignition[648]: no config at "/usr/lib/ignition/user.ign" Jul 11 00:18:14.734000 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' Jul 11 00:18:14.732972 systemd-networkd[739]: lo: Gained carrier Jul 11 00:18:14.729748 ignition[648]: op(1): [started] loading QEMU firmware config module Jul 11 00:18:14.733354 systemd-networkd[739]: Enumeration completed Jul 11 00:18:14.729754 ignition[648]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 11 00:18:14.733548 systemd-networkd[739]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 11 00:18:14.736760 ignition[648]: op(1): [finished] loading QEMU firmware config module Jul 11 00:18:14.734198 systemd[1]: Started systemd-networkd.service. Jul 11 00:18:14.734710 systemd-networkd[739]: eth0: Link UP Jul 11 00:18:14.734714 systemd-networkd[739]: eth0: Gained carrier Jul 11 00:18:14.735602 systemd[1]: Reached target network.target. Jul 11 00:18:14.737417 systemd[1]: Starting iscsiuio.service... Jul 11 00:18:14.747384 ignition[648]: parsing config with SHA512: f29d68c28405758d907c27f407f6ebbb5084eaff58cbb96ce3c4e48fd9c8522c09dbd74a7e43ab1984907dc888dc02f8f7b90a6aef3463b32660f0895f0675de Jul 11 00:18:14.747797 systemd[1]: Started iscsiuio.service. Jul 11 00:18:14.747000 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' Jul 11 00:18:14.749247 systemd[1]: Starting iscsid.service... Jul 11 00:18:14.752789 iscsid[745]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Jul 11 00:18:14.752789 iscsid[745]: 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]. Jul 11 00:18:14.752789 iscsid[745]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Jul 11 00:18:14.752789 iscsid[745]: If using hardware iscsi like qla4xxx this message can be ignored. Jul 11 00:18:14.752789 iscsid[745]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Jul 11 00:18:14.752789 iscsid[745]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Jul 11 00:18:14.758000 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' Jul 11 00:18:14.753849 ignition[648]: fetch-offline: fetch-offline passed Jul 11 00:18:14.762000 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' Jul 11 00:18:14.753248 unknown[648]: fetched base config from "system" Jul 11 00:18:14.753952 ignition[648]: Ignition finished successfully Jul 11 00:18:14.753259 unknown[648]: fetched user config from "qemu" Jul 11 00:18:14.756439 systemd[1]: Started iscsid.service. Jul 11 00:18:14.757466 systemd-networkd[739]: eth0: DHCPv4 address 10.0.0.16/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 11 00:18:14.760113 systemd[1]: Starting dracut-initqueue.service... Jul 11 00:18:14.761422 systemd[1]: Finished ignition-fetch-offline.service. Jul 11 00:18:14.762887 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 11 00:18:14.763546 systemd[1]: Starting ignition-kargs.service... Jul 11 00:18:14.770651 systemd[1]: Finished dracut-initqueue.service. Jul 11 00:18:14.770000 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' Jul 11 00:18:14.771575 systemd[1]: Reached target remote-fs-pre.target. Jul 11 00:18:14.772482 systemd[1]: Reached target remote-cryptsetup.target. Jul 11 00:18:14.772087 ignition[748]: Ignition 2.14.0 Jul 11 00:18:14.774336 systemd[1]: Reached target remote-fs.target. Jul 11 00:18:14.772092 ignition[748]: Stage: kargs Jul 11 00:18:14.776113 systemd[1]: Starting dracut-pre-mount.service... Jul 11 00:18:14.772182 ignition[748]: no configs at "/usr/lib/ignition/base.d" Jul 11 00:18:14.776935 systemd[1]: Finished ignition-kargs.service. Jul 11 00:18:14.772190 ignition[748]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 11 00:18:14.776000 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' Jul 11 00:18:14.778362 systemd[1]: Starting ignition-disks.service... Jul 11 00:18:14.773056 ignition[748]: kargs: kargs passed Jul 11 00:18:14.773096 ignition[748]: Ignition finished successfully Jul 11 00:18:14.784161 systemd[1]: Finished dracut-pre-mount.service. Jul 11 00:18:14.784000 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' Jul 11 00:18:14.784957 ignition[762]: Ignition 2.14.0 Jul 11 00:18:14.784963 ignition[762]: Stage: disks Jul 11 00:18:14.785049 ignition[762]: no configs at "/usr/lib/ignition/base.d" Jul 11 00:18:14.786486 systemd[1]: Finished ignition-disks.service. Jul 11 00:18:14.785059 ignition[762]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 11 00:18:14.787750 systemd[1]: Reached target initrd-root-device.target. Jul 11 00:18:14.786000 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' Jul 11 00:18:14.785742 ignition[762]: disks: disks passed Jul 11 00:18:14.788532 systemd[1]: Reached target local-fs-pre.target. Jul 11 00:18:14.785781 ignition[762]: Ignition finished successfully Jul 11 00:18:14.789839 systemd[1]: Reached target local-fs.target. Jul 11 00:18:14.790857 systemd[1]: Reached target sysinit.target. Jul 11 00:18:14.791768 systemd[1]: Reached target basic.target. Jul 11 00:18:14.793589 systemd[1]: Starting systemd-fsck-root.service... Jul 11 00:18:14.804620 systemd-fsck[774]: ROOT: clean, 619/553520 files, 56022/553472 blocks Jul 11 00:18:14.850852 systemd[1]: Finished systemd-fsck-root.service. Jul 11 00:18:14.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' Jul 11 00:18:14.852858 systemd[1]: Mounting sysroot.mount... Jul 11 00:18:14.864727 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Jul 11 00:18:14.865338 systemd[1]: Mounted sysroot.mount. Jul 11 00:18:14.865986 systemd[1]: Reached target initrd-root-fs.target. Jul 11 00:18:14.868004 systemd[1]: Mounting sysroot-usr.mount... Jul 11 00:18:14.868799 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Jul 11 00:18:14.868848 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 11 00:18:14.868872 systemd[1]: Reached target ignition-diskful.target. Jul 11 00:18:14.870975 systemd[1]: Mounted sysroot-usr.mount. Jul 11 00:18:14.873016 systemd[1]: Starting initrd-setup-root.service... Jul 11 00:18:14.877496 initrd-setup-root[784]: cut: /sysroot/etc/passwd: No such file or directory Jul 11 00:18:14.882090 initrd-setup-root[792]: cut: /sysroot/etc/group: No such file or directory Jul 11 00:18:14.885828 initrd-setup-root[800]: cut: /sysroot/etc/shadow: No such file or directory Jul 11 00:18:14.890163 initrd-setup-root[808]: cut: /sysroot/etc/gshadow: No such file or directory Jul 11 00:18:14.928422 systemd[1]: Finished initrd-setup-root.service. Jul 11 00:18:14.928000 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' Jul 11 00:18:14.930362 systemd[1]: Starting ignition-mount.service... Jul 11 00:18:14.932550 systemd[1]: Starting sysroot-boot.service... Jul 11 00:18:14.937632 bash[825]: umount: /sysroot/usr/share/oem: not mounted. Jul 11 00:18:14.947136 ignition[827]: INFO : Ignition 2.14.0 Jul 11 00:18:14.947136 ignition[827]: INFO : Stage: mount Jul 11 00:18:14.948401 ignition[827]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 11 00:18:14.948401 ignition[827]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 11 00:18:14.948401 ignition[827]: INFO : mount: mount passed Jul 11 00:18:14.948401 ignition[827]: INFO : Ignition finished successfully Jul 11 00:18:14.950492 systemd[1]: Finished ignition-mount.service. Jul 11 00:18:14.951000 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' Jul 11 00:18:14.955661 systemd[1]: Finished sysroot-boot.service. Jul 11 00:18:14.956000 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' Jul 11 00:18:15.580848 systemd[1]: Mounting sysroot-usr-share-oem.mount... Jul 11 00:18:15.588742 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (835) Jul 11 00:18:15.588784 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 11 00:18:15.590350 kernel: BTRFS info (device vda6): using free space tree Jul 11 00:18:15.590368 kernel: BTRFS info (device vda6): has skinny extents Jul 11 00:18:15.596255 systemd[1]: Mounted sysroot-usr-share-oem.mount. Jul 11 00:18:15.597751 systemd[1]: Starting ignition-files.service... Jul 11 00:18:15.617629 ignition[855]: INFO : Ignition 2.14.0 Jul 11 00:18:15.617629 ignition[855]: INFO : Stage: files Jul 11 00:18:15.618968 ignition[855]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 11 00:18:15.618968 ignition[855]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 11 00:18:15.618968 ignition[855]: DEBUG : files: compiled without relabeling support, skipping Jul 11 00:18:15.633288 ignition[855]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 11 00:18:15.633288 ignition[855]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 11 00:18:15.637195 ignition[855]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 11 00:18:15.638291 ignition[855]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 11 00:18:15.639527 unknown[855]: wrote ssh authorized keys file for user: core Jul 11 00:18:15.640357 ignition[855]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 11 00:18:15.641423 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 11 00:18:15.641423 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 11 00:18:15.641423 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 11 00:18:15.645281 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 11 00:18:15.645281 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 11 00:18:15.645281 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 11 00:18:15.645281 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 11 00:18:15.645281 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-arm64.raw: attempt #1 Jul 11 00:18:15.948849 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 11 00:18:16.535565 ignition[855]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Jul 11 00:18:16.535565 ignition[855]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Jul 11 00:18:16.538130 ignition[855]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 11 00:18:16.539657 ignition[855]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 11 00:18:16.539657 ignition[855]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Jul 11 00:18:16.539657 ignition[855]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Jul 11 00:18:16.539657 ignition[855]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 11 00:18:16.581520 ignition[855]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 11 00:18:16.582792 ignition[855]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Jul 11 00:18:16.582792 ignition[855]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 11 00:18:16.582792 ignition[855]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 11 00:18:16.582792 ignition[855]: INFO : files: files passed Jul 11 00:18:16.582792 ignition[855]: INFO : Ignition finished successfully Jul 11 00:18:16.583000 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' Jul 11 00:18:16.583203 systemd[1]: Finished ignition-files.service. Jul 11 00:18:16.585268 systemd[1]: Starting initrd-setup-root-after-ignition.service... Jul 11 00:18:16.586539 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Jul 11 00:18:16.587178 systemd[1]: Starting ignition-quench.service... Jul 11 00:18:16.593385 initrd-setup-root-after-ignition[880]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Jul 11 00:18:16.593811 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 11 00:18:16.594000 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' Jul 11 00:18:16.594000 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' Jul 11 00:18:16.593899 systemd[1]: Finished ignition-quench.service. Jul 11 00:18:16.595000 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' Jul 11 00:18:16.597006 initrd-setup-root-after-ignition[883]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 11 00:18:16.595647 systemd[1]: Finished initrd-setup-root-after-ignition.service. Jul 11 00:18:16.596561 systemd[1]: Reached target ignition-complete.target. Jul 11 00:18:16.598889 systemd[1]: Starting initrd-parse-etc.service... Jul 11 00:18:16.615044 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 11 00:18:16.615156 systemd[1]: Finished initrd-parse-etc.service. Jul 11 00:18:16.615000 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' Jul 11 00:18:16.616000 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' Jul 11 00:18:16.616846 systemd[1]: Reached target initrd-fs.target. Jul 11 00:18:16.617682 systemd[1]: Reached target initrd.target. Jul 11 00:18:16.619191 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Jul 11 00:18:16.620068 systemd[1]: Starting dracut-pre-pivot.service... Jul 11 00:18:16.633114 systemd[1]: Finished dracut-pre-pivot.service. Jul 11 00:18:16.633000 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' Jul 11 00:18:16.634679 systemd[1]: Starting initrd-cleanup.service... Jul 11 00:18:16.645265 systemd[1]: Stopped target nss-lookup.target. Jul 11 00:18:16.646429 systemd[1]: Stopped target remote-cryptsetup.target. Jul 11 00:18:16.650256 systemd[1]: Stopped target timers.target. Jul 11 00:18:16.651556 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 11 00:18:16.651000 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' Jul 11 00:18:16.651681 systemd[1]: Stopped dracut-pre-pivot.service. Jul 11 00:18:16.652674 systemd[1]: Stopped target initrd.target. Jul 11 00:18:16.653745 systemd[1]: Stopped target basic.target. Jul 11 00:18:16.654734 systemd[1]: Stopped target ignition-complete.target. Jul 11 00:18:16.657202 systemd[1]: Stopped target ignition-diskful.target. Jul 11 00:18:16.658200 systemd[1]: Stopped target initrd-root-device.target. Jul 11 00:18:16.659340 systemd[1]: Stopped target remote-fs.target. Jul 11 00:18:16.660338 systemd[1]: Stopped target remote-fs-pre.target. Jul 11 00:18:16.663116 systemd[1]: Stopped target sysinit.target. Jul 11 00:18:16.664159 systemd[1]: Stopped target local-fs.target. Jul 11 00:18:16.665295 systemd[1]: Stopped target local-fs-pre.target. Jul 11 00:18:16.668959 systemd[1]: Stopped target swap.target. Jul 11 00:18:16.669657 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 11 00:18:16.670000 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' Jul 11 00:18:16.669795 systemd[1]: Stopped dracut-pre-mount.service. Jul 11 00:18:16.670921 systemd[1]: Stopped target cryptsetup.target. Jul 11 00:18:16.673000 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' Jul 11 00:18:16.671985 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 11 00:18:16.674000 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' Jul 11 00:18:16.672082 systemd[1]: Stopped dracut-initqueue.service. Jul 11 00:18:16.673824 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 11 00:18:16.673927 systemd[1]: Stopped ignition-fetch-offline.service. Jul 11 00:18:16.675641 systemd[1]: Stopped target paths.target. Jul 11 00:18:16.677560 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 11 00:18:16.681768 systemd[1]: Stopped systemd-ask-password-console.path. Jul 11 00:18:16.682526 systemd[1]: Stopped target slices.target. Jul 11 00:18:16.683613 systemd[1]: Stopped target sockets.target. Jul 11 00:18:16.684622 systemd[1]: iscsid.socket: Deactivated successfully. Jul 11 00:18:16.684694 systemd[1]: Closed iscsid.socket. Jul 11 00:18:16.685682 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 11 00:18:16.687000 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' Jul 11 00:18:16.685765 systemd[1]: Closed iscsiuio.socket. Jul 11 00:18:16.689000 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' Jul 11 00:18:16.686943 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 11 00:18:16.687037 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Jul 11 00:18:16.688063 systemd[1]: ignition-files.service: Deactivated successfully. Jul 11 00:18:16.693000 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' Jul 11 00:18:16.688147 systemd[1]: Stopped ignition-files.service. Jul 11 00:18:16.690066 systemd[1]: Stopping ignition-mount.service... Jul 11 00:18:16.692358 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 11 00:18:16.692493 systemd[1]: Stopped kmod-static-nodes.service. Jul 11 00:18:16.699684 ignition[896]: INFO : Ignition 2.14.0 Jul 11 00:18:16.699684 ignition[896]: INFO : Stage: umount Jul 11 00:18:16.699684 ignition[896]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 11 00:18:16.699684 ignition[896]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 11 00:18:16.699684 ignition[896]: INFO : umount: umount passed Jul 11 00:18:16.699684 ignition[896]: INFO : Ignition finished successfully Jul 11 00:18:16.700000 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' Jul 11 00:18:16.701000 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' Jul 11 00:18:16.704000 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' Jul 11 00:18:16.694891 systemd[1]: Stopping sysroot-boot.service... Jul 11 00:18:16.708000 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' Jul 11 00:18:16.699027 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 11 00:18:16.709000 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' Jul 11 00:18:16.699178 systemd[1]: Stopped systemd-udev-trigger.service. Jul 11 00:18:16.711000 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' Jul 11 00:18:16.700828 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 11 00:18:16.700927 systemd[1]: Stopped dracut-pre-trigger.service. Jul 11 00:18:16.715000 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' Jul 11 00:18:16.715000 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' Jul 11 00:18:16.704017 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 11 00:18:16.704106 systemd[1]: Stopped ignition-mount.service. Jul 11 00:18:16.706395 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 11 00:18:16.718000 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' Jul 11 00:18:16.707246 systemd[1]: Stopped target network.target. Jul 11 00:18:16.708217 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 11 00:18:16.708293 systemd[1]: Stopped ignition-disks.service. Jul 11 00:18:16.724000 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' Jul 11 00:18:16.709486 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 11 00:18:16.725000 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' Jul 11 00:18:16.709524 systemd[1]: Stopped ignition-kargs.service. Jul 11 00:18:16.727000 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' Jul 11 00:18:16.710522 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 11 00:18:16.710687 systemd[1]: Stopped ignition-setup.service. Jul 11 00:18:16.712131 systemd[1]: Stopping systemd-networkd.service... Jul 11 00:18:16.713494 systemd[1]: Stopping systemd-resolved.service... Jul 11 00:18:16.714285 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 11 00:18:16.714377 systemd[1]: Finished initrd-cleanup.service. Jul 11 00:18:16.717757 systemd-networkd[739]: eth0: DHCPv6 lease lost Jul 11 00:18:16.718661 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 11 00:18:16.718766 systemd[1]: Stopped systemd-networkd.service. Jul 11 00:18:16.734000 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' Jul 11 00:18:16.736000 audit: BPF prog-id=9 op=UNLOAD Jul 11 00:18:16.719915 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 11 00:18:16.719944 systemd[1]: Closed systemd-networkd.socket. Jul 11 00:18:16.722047 systemd[1]: Stopping network-cleanup.service... Jul 11 00:18:16.739000 audit: BPF prog-id=6 op=UNLOAD Jul 11 00:18:16.739000 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' Jul 11 00:18:16.722900 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 11 00:18:16.739000 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' Jul 11 00:18:16.722957 systemd[1]: Stopped parse-ip-for-networkd.service. Jul 11 00:18:16.724569 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 11 00:18:16.724610 systemd[1]: Stopped systemd-sysctl.service. Jul 11 00:18:16.743000 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' Jul 11 00:18:16.726583 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 11 00:18:16.745000 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' Jul 11 00:18:16.726624 systemd[1]: Stopped systemd-modules-load.service. Jul 11 00:18:16.747000 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' Jul 11 00:18:16.727508 systemd[1]: Stopping systemd-udevd.service... Jul 11 00:18:16.734178 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 11 00:18:16.734685 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 11 00:18:16.749000 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' Jul 11 00:18:16.734802 systemd[1]: Stopped systemd-resolved.service. Jul 11 00:18:16.751000 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' Jul 11 00:18:16.738256 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 11 00:18:16.753000 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' Jul 11 00:18:16.738374 systemd[1]: Stopped network-cleanup.service. Jul 11 00:18:16.739640 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 11 00:18:16.755000 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' Jul 11 00:18:16.755000 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' Jul 11 00:18:16.739776 systemd[1]: Stopped systemd-udevd.service. Jul 11 00:18:16.740810 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 11 00:18:16.740845 systemd[1]: Closed systemd-udevd-control.socket. Jul 11 00:18:16.742041 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 11 00:18:16.742071 systemd[1]: Closed systemd-udevd-kernel.socket. Jul 11 00:18:16.743268 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 11 00:18:16.743314 systemd[1]: Stopped dracut-pre-udev.service. Jul 11 00:18:16.744765 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 11 00:18:16.744804 systemd[1]: Stopped dracut-cmdline.service. Jul 11 00:18:16.745922 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 11 00:18:16.745962 systemd[1]: Stopped dracut-cmdline-ask.service. Jul 11 00:18:16.748111 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Jul 11 00:18:16.749074 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 11 00:18:16.749131 systemd[1]: Stopped systemd-vconsole-setup.service. Jul 11 00:18:16.750910 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 11 00:18:16.751003 systemd[1]: Stopped sysroot-boot.service. Jul 11 00:18:16.752092 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 11 00:18:16.752132 systemd[1]: Stopped initrd-setup-root.service. Jul 11 00:18:16.753908 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 11 00:18:16.753990 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Jul 11 00:18:16.755343 systemd[1]: Reached target initrd-switch-root.target. Jul 11 00:18:16.757347 systemd[1]: Starting initrd-switch-root.service... Jul 11 00:18:16.764057 systemd[1]: Switching root. Jul 11 00:18:16.782616 iscsid[745]: iscsid shutting down. Jul 11 00:18:16.783231 systemd-journald[289]: Received SIGTERM from PID 1 (n/a). Jul 11 00:18:16.783276 systemd-journald[289]: Journal stopped Jul 11 00:18:18.864509 kernel: SELinux: Class mctp_socket not defined in policy. Jul 11 00:18:18.864563 kernel: SELinux: Class anon_inode not defined in policy. Jul 11 00:18:18.864578 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 11 00:18:18.864589 kernel: SELinux: policy capability network_peer_controls=1 Jul 11 00:18:18.864599 kernel: SELinux: policy capability open_perms=1 Jul 11 00:18:18.864612 kernel: SELinux: policy capability extended_socket_class=1 Jul 11 00:18:18.864622 kernel: SELinux: policy capability always_check_network=0 Jul 11 00:18:18.864631 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 11 00:18:18.864640 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 11 00:18:18.864650 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 11 00:18:18.864660 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 11 00:18:18.864673 systemd[1]: Successfully loaded SELinux policy in 35.801ms. Jul 11 00:18:18.864691 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.129ms. Jul 11 00:18:18.864713 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) Jul 11 00:18:18.864727 systemd[1]: Detected virtualization kvm. Jul 11 00:18:18.864738 systemd[1]: Detected architecture arm64. Jul 11 00:18:18.864748 systemd[1]: Detected first boot. Jul 11 00:18:18.864760 systemd[1]: Initializing machine ID from VM UUID. Jul 11 00:18:18.864775 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 11 00:18:18.864786 systemd[1]: Populated /etc with preset unit settings. Jul 11 00:18:18.864797 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 11 00:18:18.864809 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 11 00:18:18.864821 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. Jul 11 00:18:18.864833 kernel: kauditd_printk_skb: 77 callbacks suppressed Jul 11 00:18:18.864842 kernel: audit: type=1334 audit(1752193098.685:81): prog-id=12 op=LOAD Jul 11 00:18:18.864852 kernel: audit: type=1334 audit(1752193098.685:82): prog-id=3 op=UNLOAD Jul 11 00:18:18.864864 kernel: audit: type=1334 audit(1752193098.685:83): prog-id=13 op=LOAD Jul 11 00:18:18.864874 kernel: audit: type=1334 audit(1752193098.685:84): prog-id=14 op=LOAD Jul 11 00:18:18.864885 systemd[1]: iscsiuio.service: Deactivated successfully. Jul 11 00:18:18.864896 kernel: audit: type=1334 audit(1752193098.685:85): prog-id=4 op=UNLOAD Jul 11 00:18:18.864906 systemd[1]: Stopped iscsiuio.service. Jul 11 00:18:18.864916 kernel: audit: type=1334 audit(1752193098.685:86): prog-id=5 op=UNLOAD Jul 11 00:18:18.864928 kernel: audit: type=1131 audit(1752193098.690:87): 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' Jul 11 00:18:18.864938 kernel: audit: type=1131 audit(1752193098.696:88): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.864950 systemd[1]: iscsid.service: Deactivated successfully. Jul 11 00:18:18.864962 systemd[1]: Stopped iscsid.service. Jul 11 00:18:18.864973 kernel: audit: type=1131 audit(1752193098.701:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.864983 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 11 00:18:18.864994 systemd[1]: Stopped initrd-switch-root.service. Jul 11 00:18:18.865005 kernel: audit: type=1130 audit(1752193098.706:90): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.865016 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 11 00:18:18.865029 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 11 00:18:18.865040 systemd[1]: Created slice system-addon\x2drun.slice. Jul 11 00:18:18.865050 systemd[1]: Created slice system-getty.slice. Jul 11 00:18:18.865061 systemd[1]: Created slice system-modprobe.slice. Jul 11 00:18:18.865072 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 11 00:18:18.865082 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 11 00:18:18.865093 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 11 00:18:18.865104 systemd[1]: Created slice user.slice. Jul 11 00:18:18.865116 systemd[1]: Started systemd-ask-password-console.path. Jul 11 00:18:18.865127 systemd[1]: Started systemd-ask-password-wall.path. Jul 11 00:18:18.865138 systemd[1]: Set up automount boot.automount. Jul 11 00:18:18.865148 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 11 00:18:18.865160 systemd[1]: Stopped target initrd-switch-root.target. Jul 11 00:18:18.865170 systemd[1]: Stopped target initrd-fs.target. Jul 11 00:18:18.865181 systemd[1]: Stopped target initrd-root-fs.target. Jul 11 00:18:18.865191 systemd[1]: Reached target integritysetup.target. Jul 11 00:18:18.865203 systemd[1]: Reached target remote-cryptsetup.target. Jul 11 00:18:18.865215 systemd[1]: Reached target remote-fs.target. Jul 11 00:18:18.865226 systemd[1]: Reached target slices.target. Jul 11 00:18:18.865237 systemd[1]: Reached target swap.target. Jul 11 00:18:18.865248 systemd[1]: Reached target torcx.target. Jul 11 00:18:18.865261 systemd[1]: Reached target veritysetup.target. Jul 11 00:18:18.865272 systemd[1]: Listening on systemd-coredump.socket. Jul 11 00:18:18.865285 systemd[1]: Listening on systemd-initctl.socket. Jul 11 00:18:18.865295 systemd[1]: Listening on systemd-networkd.socket. Jul 11 00:18:18.865306 systemd[1]: Listening on systemd-udevd-control.socket. Jul 11 00:18:18.865316 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 11 00:18:18.865326 systemd[1]: Listening on systemd-userdbd.socket. Jul 11 00:18:18.865336 systemd[1]: Mounting dev-hugepages.mount... Jul 11 00:18:18.865347 systemd[1]: Mounting dev-mqueue.mount... Jul 11 00:18:18.865362 systemd[1]: Mounting media.mount... Jul 11 00:18:18.865375 systemd[1]: Mounting sys-kernel-debug.mount... Jul 11 00:18:18.865387 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 11 00:18:18.865397 systemd[1]: Mounting tmp.mount... Jul 11 00:18:18.865408 systemd[1]: Starting flatcar-tmpfiles.service... Jul 11 00:18:18.865418 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 11 00:18:18.865429 systemd[1]: Starting kmod-static-nodes.service... Jul 11 00:18:18.865440 systemd[1]: Starting modprobe@configfs.service... Jul 11 00:18:18.865451 systemd[1]: Starting modprobe@dm_mod.service... Jul 11 00:18:18.865463 systemd[1]: Starting modprobe@drm.service... Jul 11 00:18:18.865473 systemd[1]: Starting modprobe@efi_pstore.service... Jul 11 00:18:18.865485 systemd[1]: Starting modprobe@fuse.service... Jul 11 00:18:18.865495 systemd[1]: Starting modprobe@loop.service... Jul 11 00:18:18.865506 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 11 00:18:18.865517 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 11 00:18:18.865527 systemd[1]: Stopped systemd-fsck-root.service. Jul 11 00:18:18.865538 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 11 00:18:18.865550 systemd[1]: Stopped systemd-fsck-usr.service. Jul 11 00:18:18.865560 systemd[1]: Stopped systemd-journald.service. Jul 11 00:18:18.865570 kernel: fuse: init (API version 7.34) Jul 11 00:18:18.865582 systemd[1]: Starting systemd-journald.service... Jul 11 00:18:18.865600 systemd[1]: Starting systemd-modules-load.service... Jul 11 00:18:18.865611 systemd[1]: Starting systemd-network-generator.service... Jul 11 00:18:18.865623 systemd[1]: Starting systemd-remount-fs.service... Jul 11 00:18:18.865639 systemd[1]: Starting systemd-udev-trigger.service... Jul 11 00:18:18.865649 systemd[1]: verity-setup.service: Deactivated successfully. Jul 11 00:18:18.865660 systemd[1]: Stopped verity-setup.service. Jul 11 00:18:18.865670 kernel: loop: module loaded Jul 11 00:18:18.865680 systemd[1]: Mounted dev-hugepages.mount. Jul 11 00:18:18.865692 systemd[1]: Mounted dev-mqueue.mount. Jul 11 00:18:18.865727 systemd[1]: Mounted media.mount. Jul 11 00:18:18.865739 systemd[1]: Mounted sys-kernel-debug.mount. Jul 11 00:18:18.865752 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 11 00:18:18.865764 systemd[1]: Mounted tmp.mount. Jul 11 00:18:18.865775 systemd[1]: Finished kmod-static-nodes.service. Jul 11 00:18:18.865787 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 11 00:18:18.865798 systemd[1]: Finished modprobe@configfs.service. Jul 11 00:18:18.865811 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 11 00:18:18.865822 systemd[1]: Finished modprobe@dm_mod.service. Jul 11 00:18:18.865834 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 11 00:18:18.865845 systemd[1]: Finished modprobe@drm.service. Jul 11 00:18:18.865856 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 11 00:18:18.865868 systemd[1]: Finished modprobe@efi_pstore.service. Jul 11 00:18:18.865879 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 11 00:18:18.865893 systemd-journald[992]: Journal started Jul 11 00:18:18.865938 systemd-journald[992]: Runtime Journal (/run/log/journal/2a3392270a6a422f89aa62fe35f794f6) is 6.0M, max 48.7M, 42.6M free. Jul 11 00:18:16.845000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 11 00:18:16.916000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Jul 11 00:18:16.916000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Jul 11 00:18:16.916000 audit: BPF prog-id=10 op=LOAD Jul 11 00:18:16.916000 audit: BPF prog-id=10 op=UNLOAD Jul 11 00:18:16.916000 audit: BPF prog-id=11 op=LOAD Jul 11 00:18:16.916000 audit: BPF prog-id=11 op=UNLOAD Jul 11 00:18:16.966000 audit[930]: AVC avc: denied { associate } for pid=930 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Jul 11 00:18:16.966000 audit[930]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001c589c a1=40000c8de0 a2=40000cf0c0 a3=32 items=0 ppid=913 pid=930 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 11 00:18:16.966000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 11 00:18:16.968000 audit[930]: AVC avc: denied { associate } for pid=930 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 11 00:18:16.968000 audit[930]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001c5975 a2=1ed a3=0 items=2 ppid=913 pid=930 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 11 00:18:16.968000 audit: CWD cwd="/" Jul 11 00:18:16.968000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Jul 11 00:18:16.968000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Jul 11 00:18:16.968000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 11 00:18:18.685000 audit: BPF prog-id=12 op=LOAD Jul 11 00:18:18.685000 audit: BPF prog-id=3 op=UNLOAD Jul 11 00:18:18.685000 audit: BPF prog-id=13 op=LOAD Jul 11 00:18:18.685000 audit: BPF prog-id=14 op=LOAD Jul 11 00:18:18.685000 audit: BPF prog-id=4 op=UNLOAD Jul 11 00:18:18.685000 audit: BPF prog-id=5 op=UNLOAD Jul 11 00:18:18.690000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.696000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.701000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.706000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.706000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.710000 audit: BPF prog-id=12 op=UNLOAD Jul 11 00:18:18.808000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.811000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.813000 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' Jul 11 00:18:18.813000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.813000 audit: BPF prog-id=15 op=LOAD Jul 11 00:18:18.814000 audit: BPF prog-id=16 op=LOAD Jul 11 00:18:18.814000 audit: BPF prog-id=17 op=LOAD Jul 11 00:18:18.814000 audit: BPF prog-id=13 op=UNLOAD Jul 11 00:18:18.814000 audit: BPF prog-id=14 op=UNLOAD Jul 11 00:18:18.838000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 11 00:18:18.851000 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' Jul 11 00:18:18.853000 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' Jul 11 00:18:18.853000 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' Jul 11 00:18:18.857000 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' Jul 11 00:18:18.857000 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' Jul 11 00:18:18.860000 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' Jul 11 00:18:18.860000 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' Jul 11 00:18:18.862000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 11 00:18:18.862000 audit[992]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=fffffb30aca0 a2=4000 a3=1 items=0 ppid=1 pid=992 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) Jul 11 00:18:18.862000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 11 00:18:18.862000 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' Jul 11 00:18:18.863000 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' Jul 11 00:18:16.965456 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" 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]" Jul 11 00:18:18.683646 systemd[1]: Queued start job for default target multi-user.target. Jul 11 00:18:16.965764 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 11 00:18:18.683658 systemd[1]: Unnecessary job was removed for dev-vda6.device. Jul 11 00:18:16.965782 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 11 00:18:18.687252 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 11 00:18:16.965811 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Jul 11 00:18:16.965821 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="skipped missing lower profile" missing profile=oem Jul 11 00:18:16.965847 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Jul 11 00:18:16.965858 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Jul 11 00:18:16.966175 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Jul 11 00:18:16.966211 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 11 00:18:16.966296 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 11 00:18:16.967126 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Jul 11 00:18:16.967163 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Jul 11 00:18:16.967182 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.7: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.7 Jul 11 00:18:16.967196 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Jul 11 00:18:16.967213 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.7: no such file or directory" path=/var/lib/torcx/store/3510.3.7 Jul 11 00:18:16.967225 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:16Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Jul 11 00:18:18.422340 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:18Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 11 00:18:18.422621 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:18Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 11 00:18:18.868000 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' Jul 11 00:18:18.868810 systemd[1]: Finished modprobe@fuse.service. Jul 11 00:18:18.868837 systemd[1]: Started systemd-journald.service. Jul 11 00:18:18.868000 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' Jul 11 00:18:18.422745 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:18Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 11 00:18:18.422911 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:18Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 11 00:18:18.422960 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:18Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Jul 11 00:18:18.423014 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2025-07-11T00:18:18Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Jul 11 00:18:18.870000 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' Jul 11 00:18:18.870867 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 11 00:18:18.871141 systemd[1]: Finished modprobe@loop.service. Jul 11 00:18:18.871000 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' Jul 11 00:18:18.871000 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' Jul 11 00:18:18.872127 systemd[1]: Finished systemd-modules-load.service. Jul 11 00:18:18.872000 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' Jul 11 00:18:18.873266 systemd[1]: Finished systemd-network-generator.service. Jul 11 00:18:18.873000 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' Jul 11 00:18:18.874501 systemd[1]: Finished systemd-remount-fs.service. Jul 11 00:18:18.875000 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' Jul 11 00:18:18.875630 systemd[1]: Finished flatcar-tmpfiles.service. Jul 11 00:18:18.876000 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' Jul 11 00:18:18.876981 systemd[1]: Reached target network-pre.target. Jul 11 00:18:18.879175 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 11 00:18:18.881270 systemd[1]: Mounting sys-kernel-config.mount... Jul 11 00:18:18.882095 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 11 00:18:18.883947 systemd[1]: Starting systemd-hwdb-update.service... Jul 11 00:18:18.886218 systemd[1]: Starting systemd-journal-flush.service... Jul 11 00:18:18.887168 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 11 00:18:18.888447 systemd[1]: Starting systemd-random-seed.service... Jul 11 00:18:18.889439 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 11 00:18:18.891050 systemd[1]: Starting systemd-sysctl.service... Jul 11 00:18:18.894229 systemd[1]: Starting systemd-sysusers.service... Jul 11 00:18:18.898920 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 11 00:18:18.899853 systemd[1]: Mounted sys-kernel-config.mount. Jul 11 00:18:18.905000 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' Jul 11 00:18:18.907989 systemd-journald[992]: Time spent on flushing to /var/log/journal/2a3392270a6a422f89aa62fe35f794f6 is 12.502ms for 969 entries. Jul 11 00:18:18.907989 systemd-journald[992]: System Journal (/var/log/journal/2a3392270a6a422f89aa62fe35f794f6) is 8.0M, max 195.6M, 187.6M free. Jul 11 00:18:18.934282 systemd-journald[992]: Received client request to flush runtime journal. Jul 11 00:18:18.914000 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' Jul 11 00:18:18.918000 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' Jul 11 00:18:18.904559 systemd[1]: Finished systemd-udev-trigger.service. Jul 11 00:18:18.935011 udevadm[1030]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 11 00:18:18.906824 systemd[1]: Starting systemd-udev-settle.service... Jul 11 00:18:18.913839 systemd[1]: Finished systemd-random-seed.service. Jul 11 00:18:18.916784 systemd[1]: Reached target first-boot-complete.target. Jul 11 00:18:18.918877 systemd[1]: Finished systemd-sysctl.service. Jul 11 00:18:18.935332 systemd[1]: Finished systemd-journal-flush.service. Jul 11 00:18:18.936000 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' Jul 11 00:18:18.937450 systemd[1]: Finished systemd-sysusers.service. Jul 11 00:18:18.938000 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' Jul 11 00:18:19.306104 systemd[1]: Finished systemd-hwdb-update.service. Jul 11 00:18:19.306000 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' Jul 11 00:18:19.306000 audit: BPF prog-id=18 op=LOAD Jul 11 00:18:19.306000 audit: BPF prog-id=19 op=LOAD Jul 11 00:18:19.306000 audit: BPF prog-id=7 op=UNLOAD Jul 11 00:18:19.306000 audit: BPF prog-id=8 op=UNLOAD Jul 11 00:18:19.308199 systemd[1]: Starting systemd-udevd.service... Jul 11 00:18:19.327010 systemd-udevd[1033]: Using default interface naming scheme 'v252'. Jul 11 00:18:19.345112 systemd[1]: Started systemd-udevd.service. Jul 11 00:18:19.345000 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' Jul 11 00:18:19.348000 audit: BPF prog-id=20 op=LOAD Jul 11 00:18:19.350828 systemd[1]: Starting systemd-networkd.service... Jul 11 00:18:19.354000 audit: BPF prog-id=21 op=LOAD Jul 11 00:18:19.354000 audit: BPF prog-id=22 op=LOAD Jul 11 00:18:19.354000 audit: BPF prog-id=23 op=LOAD Jul 11 00:18:19.356372 systemd[1]: Starting systemd-userdbd.service... Jul 11 00:18:19.371190 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Jul 11 00:18:19.387035 systemd[1]: Started systemd-userdbd.service. Jul 11 00:18:19.387000 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' Jul 11 00:18:19.441323 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 11 00:18:19.466165 systemd[1]: Finished systemd-udev-settle.service. Jul 11 00:18:19.466000 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' Jul 11 00:18:19.468288 systemd[1]: Starting lvm2-activation-early.service... Jul 11 00:18:19.492123 systemd-networkd[1048]: lo: Link UP Jul 11 00:18:19.492440 systemd-networkd[1048]: lo: Gained carrier Jul 11 00:18:19.492971 systemd-networkd[1048]: Enumeration completed Jul 11 00:18:19.493185 systemd-networkd[1048]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 11 00:18:19.493199 systemd[1]: Started systemd-networkd.service. Jul 11 00:18:19.493000 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' Jul 11 00:18:19.496672 lvm[1067]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 11 00:18:19.496682 systemd-networkd[1048]: eth0: Link UP Jul 11 00:18:19.496686 systemd-networkd[1048]: eth0: Gained carrier Jul 11 00:18:19.520853 systemd-networkd[1048]: eth0: DHCPv4 address 10.0.0.16/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 11 00:18:19.531853 systemd[1]: Finished lvm2-activation-early.service. Jul 11 00:18:19.531000 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' Jul 11 00:18:19.532800 systemd[1]: Reached target cryptsetup.target. Jul 11 00:18:19.535390 systemd[1]: Starting lvm2-activation.service... Jul 11 00:18:19.539879 lvm[1068]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 11 00:18:19.574688 systemd[1]: Finished lvm2-activation.service. Jul 11 00:18:19.574000 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' Jul 11 00:18:19.575582 systemd[1]: Reached target local-fs-pre.target. Jul 11 00:18:19.576277 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 11 00:18:19.576310 systemd[1]: Reached target local-fs.target. Jul 11 00:18:19.576935 systemd[1]: Reached target machines.target. Jul 11 00:18:19.578870 systemd[1]: Starting ldconfig.service... Jul 11 00:18:19.579906 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 11 00:18:19.579965 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). Jul 11 00:18:19.581260 systemd[1]: Starting systemd-boot-update.service... Jul 11 00:18:19.583255 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 11 00:18:19.585306 systemd[1]: Starting systemd-machine-id-commit.service... Jul 11 00:18:19.587288 systemd[1]: Starting systemd-sysext.service... Jul 11 00:18:19.588262 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1070 (bootctl) Jul 11 00:18:19.589413 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 11 00:18:19.597264 systemd[1]: Unmounting usr-share-oem.mount... Jul 11 00:18:19.598621 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 11 00:18:19.599000 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' Jul 11 00:18:19.603050 systemd[1]: usr-share-oem.mount: Deactivated successfully. Jul 11 00:18:19.603252 systemd[1]: Unmounted usr-share-oem.mount. Jul 11 00:18:19.618738 kernel: loop0: detected capacity change from 0 to 203944 Jul 11 00:18:19.662928 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 11 00:18:19.663609 systemd[1]: Finished systemd-machine-id-commit.service. Jul 11 00:18:19.664000 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' Jul 11 00:18:19.671727 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 11 00:18:19.685803 systemd-fsck[1080]: fsck.fat 4.2 (2021-01-31) Jul 11 00:18:19.685803 systemd-fsck[1080]: /dev/vda1: 236 files, 117310/258078 clusters Jul 11 00:18:19.688419 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 11 00:18:19.689000 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' Jul 11 00:18:19.691393 systemd[1]: Mounting boot.mount... Jul 11 00:18:19.696738 kernel: loop1: detected capacity change from 0 to 203944 Jul 11 00:18:19.701616 systemd[1]: Mounted boot.mount. Jul 11 00:18:19.709073 (sd-sysext)[1084]: Using extensions 'kubernetes'. Jul 11 00:18:19.709497 (sd-sysext)[1084]: Merged extensions into '/usr'. Jul 11 00:18:19.718864 systemd[1]: Finished systemd-boot-update.service. Jul 11 00:18:19.719000 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' Jul 11 00:18:19.735121 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 11 00:18:19.736945 systemd[1]: Starting modprobe@dm_mod.service... Jul 11 00:18:19.739284 systemd[1]: Starting modprobe@efi_pstore.service... Jul 11 00:18:19.741417 systemd[1]: Starting modprobe@loop.service... Jul 11 00:18:19.742331 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 11 00:18:19.742500 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). Jul 11 00:18:19.743518 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 11 00:18:19.743755 systemd[1]: Finished modprobe@dm_mod.service. Jul 11 00:18:19.743000 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' Jul 11 00:18:19.743000 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' Jul 11 00:18:19.745067 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 11 00:18:19.745205 systemd[1]: Finished modprobe@efi_pstore.service. Jul 11 00:18:19.745000 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' Jul 11 00:18:19.745000 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' Jul 11 00:18:19.746549 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 11 00:18:19.746668 systemd[1]: Finished modprobe@loop.service. Jul 11 00:18:19.747000 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' Jul 11 00:18:19.747000 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' Jul 11 00:18:19.747901 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 11 00:18:19.748013 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 11 00:18:19.811548 ldconfig[1069]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 11 00:18:19.815555 systemd[1]: Finished ldconfig.service. Jul 11 00:18:19.815000 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' Jul 11 00:18:19.842999 systemd[1]: Mounting usr-share-oem.mount... Jul 11 00:18:19.848495 systemd[1]: Mounted usr-share-oem.mount. Jul 11 00:18:19.850433 systemd[1]: Finished systemd-sysext.service. Jul 11 00:18:19.850000 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' Jul 11 00:18:19.852669 systemd[1]: Starting ensure-sysext.service... Jul 11 00:18:19.854511 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 11 00:18:19.858929 systemd[1]: Reloading. Jul 11 00:18:19.872200 systemd-tmpfiles[1091]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 11 00:18:19.873929 systemd-tmpfiles[1091]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 11 00:18:19.876700 systemd-tmpfiles[1091]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 11 00:18:19.895585 /usr/lib/systemd/system-generators/torcx-generator[1111]: time="2025-07-11T00:18:19Z" 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]" Jul 11 00:18:19.896026 /usr/lib/systemd/system-generators/torcx-generator[1111]: time="2025-07-11T00:18:19Z" level=info msg="torcx already run" Jul 11 00:18:19.972762 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 11 00:18:19.972787 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 11 00:18:19.989457 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. Jul 11 00:18:20.033000 audit: BPF prog-id=24 op=LOAD Jul 11 00:18:20.033000 audit: BPF prog-id=21 op=UNLOAD Jul 11 00:18:20.033000 audit: BPF prog-id=25 op=LOAD Jul 11 00:18:20.033000 audit: BPF prog-id=26 op=LOAD Jul 11 00:18:20.034000 audit: BPF prog-id=22 op=UNLOAD Jul 11 00:18:20.034000 audit: BPF prog-id=23 op=UNLOAD Jul 11 00:18:20.034000 audit: BPF prog-id=27 op=LOAD Jul 11 00:18:20.034000 audit: BPF prog-id=15 op=UNLOAD Jul 11 00:18:20.034000 audit: BPF prog-id=28 op=LOAD Jul 11 00:18:20.034000 audit: BPF prog-id=29 op=LOAD Jul 11 00:18:20.035000 audit: BPF prog-id=16 op=UNLOAD Jul 11 00:18:20.035000 audit: BPF prog-id=17 op=UNLOAD Jul 11 00:18:20.035000 audit: BPF prog-id=30 op=LOAD Jul 11 00:18:20.035000 audit: BPF prog-id=20 op=UNLOAD Jul 11 00:18:20.037000 audit: BPF prog-id=31 op=LOAD Jul 11 00:18:20.037000 audit: BPF prog-id=32 op=LOAD Jul 11 00:18:20.037000 audit: BPF prog-id=18 op=UNLOAD Jul 11 00:18:20.037000 audit: BPF prog-id=19 op=UNLOAD Jul 11 00:18:20.039416 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 11 00:18:20.040000 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' Jul 11 00:18:20.043672 systemd[1]: Starting audit-rules.service... Jul 11 00:18:20.045559 systemd[1]: Starting clean-ca-certificates.service... Jul 11 00:18:20.047696 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 11 00:18:20.052000 audit: BPF prog-id=33 op=LOAD Jul 11 00:18:20.056515 systemd[1]: Starting systemd-resolved.service... Jul 11 00:18:20.057000 audit: BPF prog-id=34 op=LOAD Jul 11 00:18:20.059050 systemd[1]: Starting systemd-timesyncd.service... Jul 11 00:18:20.061382 systemd[1]: Starting systemd-update-utmp.service... Jul 11 00:18:20.062833 systemd[1]: Finished clean-ca-certificates.service. Jul 11 00:18:20.062000 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' Jul 11 00:18:20.066000 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 11 00:18:20.065000 audit[1160]: SYSTEM_BOOT pid=1160 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' Jul 11 00:18:20.068824 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 11 00:18:20.068000 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' Jul 11 00:18:20.071398 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.072773 systemd[1]: Starting modprobe@dm_mod.service... Jul 11 00:18:20.074488 systemd[1]: Starting modprobe@efi_pstore.service... Jul 11 00:18:20.076461 systemd[1]: Starting modprobe@loop.service... Jul 11 00:18:20.077136 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.077288 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). Jul 11 00:18:20.078642 systemd[1]: Starting systemd-update-done.service... Jul 11 00:18:20.079435 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 11 00:18:20.080610 systemd[1]: Finished systemd-update-utmp.service. Jul 11 00:18:20.081000 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' Jul 11 00:18:20.081877 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 11 00:18:20.082001 systemd[1]: Finished modprobe@dm_mod.service. Jul 11 00:18:20.081000 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' Jul 11 00:18:20.081000 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' Jul 11 00:18:20.083055 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 11 00:18:20.083176 systemd[1]: Finished modprobe@efi_pstore.service. Jul 11 00:18:20.083000 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' Jul 11 00:18:20.083000 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' Jul 11 00:18:20.084231 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 11 00:18:20.084363 systemd[1]: Finished modprobe@loop.service. Jul 11 00:18:20.084000 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' Jul 11 00:18:20.084000 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' Jul 11 00:18:20.087000 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' Jul 11 00:18:20.086520 systemd[1]: Finished systemd-update-done.service. Jul 11 00:18:20.088571 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.090323 systemd[1]: Starting modprobe@dm_mod.service... Jul 11 00:18:20.092263 systemd[1]: Starting modprobe@efi_pstore.service... Jul 11 00:18:20.094253 systemd[1]: Starting modprobe@loop.service... Jul 11 00:18:20.095089 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.095228 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). Jul 11 00:18:20.095320 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 11 00:18:20.096155 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 11 00:18:20.096286 systemd[1]: Finished modprobe@dm_mod.service. Jul 11 00:18:20.096000 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' Jul 11 00:18:20.096000 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' Jul 11 00:18:20.097661 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 11 00:18:20.097802 systemd[1]: Finished modprobe@efi_pstore.service. Jul 11 00:18:20.097000 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' Jul 11 00:18:20.097000 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' Jul 11 00:18:20.098990 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 11 00:18:20.099131 systemd[1]: Finished modprobe@loop.service. Jul 11 00:18:20.099000 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' Jul 11 00:18:20.099000 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' Jul 11 00:18:20.100394 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 11 00:18:20.100496 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.102845 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.104500 systemd[1]: Starting modprobe@dm_mod.service... Jul 11 00:18:20.106387 systemd[1]: Starting modprobe@drm.service... Jul 11 00:18:20.108510 systemd[1]: Starting modprobe@efi_pstore.service... Jul 11 00:18:20.109750 augenrules[1177]: No rules Jul 11 00:18:20.109000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Jul 11 00:18:20.109000 audit[1177]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffd58dfd20 a2=420 a3=0 items=0 ppid=1149 pid=1177 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) Jul 11 00:18:20.109000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 11 00:18:20.110541 systemd[1]: Starting modprobe@loop.service... Jul 11 00:18:20.111292 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.111445 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). Jul 11 00:18:20.112775 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 11 00:18:20.113586 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 11 00:18:20.114938 systemd[1]: Finished audit-rules.service. Jul 11 00:18:20.116042 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 11 00:18:20.116172 systemd[1]: Finished modprobe@dm_mod.service. Jul 11 00:18:20.117183 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 11 00:18:20.117309 systemd[1]: Finished modprobe@drm.service. Jul 11 00:18:20.118364 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 11 00:18:20.118494 systemd[1]: Finished modprobe@efi_pstore.service. Jul 11 00:18:20.119651 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 11 00:18:20.119781 systemd[1]: Finished modprobe@loop.service. Jul 11 00:18:20.121066 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 11 00:18:20.121162 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.122324 systemd[1]: Finished ensure-sysext.service. Jul 11 00:18:20.127388 systemd[1]: Started systemd-timesyncd.service. Jul 11 00:18:20.128486 systemd-timesyncd[1155]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 11 00:18:20.128548 systemd-timesyncd[1155]: Initial clock synchronization to Fri 2025-07-11 00:18:20.399719 UTC. Jul 11 00:18:20.128640 systemd[1]: Reached target time-set.target. Jul 11 00:18:20.130485 systemd-resolved[1153]: Positive Trust Anchors: Jul 11 00:18:20.130497 systemd-resolved[1153]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 11 00:18:20.130526 systemd-resolved[1153]: 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 Jul 11 00:18:20.139119 systemd-resolved[1153]: Defaulting to hostname 'linux'. Jul 11 00:18:20.140611 systemd[1]: Started systemd-resolved.service. Jul 11 00:18:20.141458 systemd[1]: Reached target network.target. Jul 11 00:18:20.142070 systemd[1]: Reached target nss-lookup.target. Jul 11 00:18:20.142655 systemd[1]: Reached target sysinit.target. Jul 11 00:18:20.143340 systemd[1]: Started motdgen.path. Jul 11 00:18:20.143919 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 11 00:18:20.144869 systemd[1]: Started logrotate.timer. Jul 11 00:18:20.145524 systemd[1]: Started mdadm.timer. Jul 11 00:18:20.146072 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 11 00:18:20.146685 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 11 00:18:20.146727 systemd[1]: Reached target paths.target. Jul 11 00:18:20.147271 systemd[1]: Reached target timers.target. Jul 11 00:18:20.148212 systemd[1]: Listening on dbus.socket. Jul 11 00:18:20.149872 systemd[1]: Starting docker.socket... Jul 11 00:18:20.153023 systemd[1]: Listening on sshd.socket. Jul 11 00:18:20.153688 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). Jul 11 00:18:20.154150 systemd[1]: Listening on docker.socket. Jul 11 00:18:20.154913 systemd[1]: Reached target sockets.target. Jul 11 00:18:20.155499 systemd[1]: Reached target basic.target. Jul 11 00:18:20.156155 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.156189 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 11 00:18:20.157189 systemd[1]: Starting containerd.service... Jul 11 00:18:20.158838 systemd[1]: Starting dbus.service... Jul 11 00:18:20.160767 systemd[1]: Starting enable-oem-cloudinit.service... Jul 11 00:18:20.162763 systemd[1]: Starting extend-filesystems.service... Jul 11 00:18:20.163533 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 11 00:18:20.164884 systemd[1]: Starting motdgen.service... Jul 11 00:18:20.166910 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 11 00:18:20.169422 systemd[1]: Starting sshd-keygen.service... Jul 11 00:18:20.174114 jq[1192]: false Jul 11 00:18:20.176786 systemd[1]: Starting systemd-logind.service... Jul 11 00:18:20.178115 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 11 00:18:20.178279 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 11 00:18:20.179217 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 11 00:18:20.187022 jq[1210]: true Jul 11 00:18:20.180799 systemd[1]: Starting update-engine.service... Jul 11 00:18:20.183038 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 11 00:18:20.186740 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 11 00:18:20.186997 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 11 00:18:20.187833 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 11 00:18:20.188249 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 11 00:18:20.193120 systemd[1]: motdgen.service: Deactivated successfully. Jul 11 00:18:20.193493 systemd[1]: Finished motdgen.service. Jul 11 00:18:20.195585 jq[1212]: true Jul 11 00:18:20.195788 extend-filesystems[1193]: Found loop1 Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda1 Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda2 Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda3 Jul 11 00:18:20.195788 extend-filesystems[1193]: Found usr Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda4 Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda6 Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda7 Jul 11 00:18:20.195788 extend-filesystems[1193]: Found vda9 Jul 11 00:18:20.195788 extend-filesystems[1193]: Checking size of /dev/vda9 Jul 11 00:18:20.223821 extend-filesystems[1193]: Resized partition /dev/vda9 Jul 11 00:18:20.231523 extend-filesystems[1229]: resize2fs 1.46.5 (30-Dec-2021) Jul 11 00:18:20.238232 dbus-daemon[1191]: [system] SELinux support is enabled Jul 11 00:18:20.239156 systemd[1]: Started dbus.service. Jul 11 00:18:20.239737 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 11 00:18:20.245647 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). Jul 11 00:18:20.245679 systemd[1]: Reached target system-config.target. Jul 11 00:18:20.248178 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 11 00:18:20.248202 systemd[1]: Reached target user-config.target. Jul 11 00:18:20.264845 systemd-logind[1204]: Watching system buttons on /dev/input/event0 (Power Button) Jul 11 00:18:20.268174 systemd-logind[1204]: New seat seat0. Jul 11 00:18:20.284227 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 11 00:18:20.284487 systemd[1]: Started systemd-logind.service. Jul 11 00:18:20.299362 systemd[1]: Started update-engine.service. Jul 11 00:18:20.310984 update_engine[1206]: I0711 00:18:20.289094 1206 main.cc:92] Flatcar Update Engine starting Jul 11 00:18:20.310984 update_engine[1206]: I0711 00:18:20.299383 1206 update_check_scheduler.cc:74] Next update check in 9m25s Jul 11 00:18:20.311195 extend-filesystems[1229]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 11 00:18:20.311195 extend-filesystems[1229]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 11 00:18:20.311195 extend-filesystems[1229]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 11 00:18:20.302475 systemd[1]: Started locksmithd.service. Jul 11 00:18:20.316918 env[1213]: time="2025-07-11T00:18:20.311858760Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 11 00:18:20.318541 extend-filesystems[1193]: Resized filesystem in /dev/vda9 Jul 11 00:18:20.315211 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 11 00:18:20.319469 bash[1240]: Updated "/home/core/.ssh/authorized_keys" Jul 11 00:18:20.315452 systemd[1]: Finished extend-filesystems.service. Jul 11 00:18:20.317273 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 11 00:18:20.332036 env[1213]: time="2025-07-11T00:18:20.331977960Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 11 00:18:20.332367 env[1213]: time="2025-07-11T00:18:20.332335360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 11 00:18:20.333949 env[1213]: time="2025-07-11T00:18:20.333914960Z" 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.186-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 11 00:18:20.334043 env[1213]: time="2025-07-11T00:18:20.334024960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 11 00:18:20.334329 env[1213]: time="2025-07-11T00:18:20.334304200Z" 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 Jul 11 00:18:20.334432 env[1213]: time="2025-07-11T00:18:20.334415840Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 11 00:18:20.334494 env[1213]: time="2025-07-11T00:18:20.334479320Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 11 00:18:20.334546 env[1213]: time="2025-07-11T00:18:20.334533160Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 11 00:18:20.334680 env[1213]: time="2025-07-11T00:18:20.334662360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 11 00:18:20.335014 env[1213]: time="2025-07-11T00:18:20.334989920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 11 00:18:20.335223 env[1213]: time="2025-07-11T00:18:20.335200920Z" 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 Jul 11 00:18:20.335289 env[1213]: time="2025-07-11T00:18:20.335275240Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 11 00:18:20.335415 env[1213]: time="2025-07-11T00:18:20.335393880Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 11 00:18:20.335526 env[1213]: time="2025-07-11T00:18:20.335509440Z" level=info msg="metadata content store policy set" policy=shared Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.354809160Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.354853400Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.354866960Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.354902360Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.354917920Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.354931840Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.354944600Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.355280080Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.355299240Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.355312320Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.355332560Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.355346440Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.355497640Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 11 00:18:20.355781 env[1213]: time="2025-07-11T00:18:20.355567880Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.355851320Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.355893280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.355908000Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356010160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356023160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356036560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356047680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356059600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356071520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356082520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356093960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356106 env[1213]: time="2025-07-11T00:18:20.356107720Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 11 00:18:20.356329 env[1213]: time="2025-07-11T00:18:20.356244240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356329 env[1213]: time="2025-07-11T00:18:20.356260720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356329 env[1213]: time="2025-07-11T00:18:20.356273600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356329 env[1213]: time="2025-07-11T00:18:20.356286400Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 11 00:18:20.356329 env[1213]: time="2025-07-11T00:18:20.356300840Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Jul 11 00:18:20.356329 env[1213]: time="2025-07-11T00:18:20.356313120Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 11 00:18:20.356501 env[1213]: time="2025-07-11T00:18:20.356330520Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 11 00:18:20.356501 env[1213]: time="2025-07-11T00:18:20.356378080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 11 00:18:20.356656 env[1213]: time="2025-07-11T00:18:20.356592360Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Jul 11 00:18:20.356656 env[1213]: time="2025-07-11T00:18:20.356655520Z" level=info msg="Connect containerd service" Jul 11 00:18:20.357404 env[1213]: time="2025-07-11T00:18:20.356690960Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 11 00:18:20.357512 env[1213]: time="2025-07-11T00:18:20.357443640Z" 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" Jul 11 00:18:20.357799 env[1213]: time="2025-07-11T00:18:20.357757240Z" level=info msg="Start subscribing containerd event" Jul 11 00:18:20.357877 env[1213]: time="2025-07-11T00:18:20.357823520Z" level=info msg="Start recovering state" Jul 11 00:18:20.357877 env[1213]: time="2025-07-11T00:18:20.357838760Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 11 00:18:20.358915 env[1213]: time="2025-07-11T00:18:20.357876560Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 11 00:18:20.358915 env[1213]: time="2025-07-11T00:18:20.357926080Z" level=info msg="containerd successfully booted in 0.061051s" Jul 11 00:18:20.358915 env[1213]: time="2025-07-11T00:18:20.357997720Z" level=info msg="Start event monitor" Jul 11 00:18:20.358915 env[1213]: time="2025-07-11T00:18:20.358049840Z" level=info msg="Start snapshots syncer" Jul 11 00:18:20.358915 env[1213]: time="2025-07-11T00:18:20.358061000Z" level=info msg="Start cni network conf syncer for default" Jul 11 00:18:20.358915 env[1213]: time="2025-07-11T00:18:20.358069680Z" level=info msg="Start streaming server" Jul 11 00:18:20.358006 systemd[1]: Started containerd.service. Jul 11 00:18:20.360947 locksmithd[1241]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 11 00:18:20.840824 systemd-networkd[1048]: eth0: Gained IPv6LL Jul 11 00:18:20.842511 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 11 00:18:20.843801 systemd[1]: Reached target network-online.target. Jul 11 00:18:20.846019 systemd[1]: Starting kubelet.service... Jul 11 00:18:21.515003 systemd[1]: Started kubelet.service. Jul 11 00:18:22.086428 kubelet[1256]: E0711 00:18:22.086367 1256 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 11 00:18:22.088436 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 11 00:18:22.088563 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 11 00:18:23.091629 sshd_keygen[1209]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 11 00:18:23.110935 systemd[1]: Finished sshd-keygen.service. Jul 11 00:18:23.113214 systemd[1]: Starting issuegen.service... Jul 11 00:18:23.118055 systemd[1]: issuegen.service: Deactivated successfully. Jul 11 00:18:23.118257 systemd[1]: Finished issuegen.service. Jul 11 00:18:23.120668 systemd[1]: Starting systemd-user-sessions.service... Jul 11 00:18:23.127529 systemd[1]: Finished systemd-user-sessions.service. Jul 11 00:18:23.129988 systemd[1]: Started getty@tty1.service. Jul 11 00:18:23.132128 systemd[1]: Started serial-getty@ttyAMA0.service. Jul 11 00:18:23.133170 systemd[1]: Reached target getty.target. Jul 11 00:18:23.133985 systemd[1]: Reached target multi-user.target. Jul 11 00:18:23.136160 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 11 00:18:23.143554 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 11 00:18:23.143773 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 11 00:18:23.144766 systemd[1]: Startup finished in 593ms (kernel) + 4.236s (initrd) + 6.337s (userspace) = 11.167s. Jul 11 00:18:25.598035 systemd[1]: Created slice system-sshd.slice. Jul 11 00:18:25.599145 systemd[1]: Started sshd@0-10.0.0.16:22-10.0.0.1:33040.service. Jul 11 00:18:25.645242 sshd[1278]: Accepted publickey for core from 10.0.0.1 port 33040 ssh2: RSA SHA256:kAw98lsrYCxXKwzslBlKMy3//X0GU8J77htUo5WbMYE Jul 11 00:18:25.647405 sshd[1278]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 11 00:18:25.655743 systemd[1]: Created slice user-500.slice. Jul 11 00:18:25.656929 systemd[1]: Starting user-runtime-dir@500.service... Jul 11 00:18:25.658860 systemd-logind[1204]: New session 1 of user core. Jul 11 00:18:25.665383 systemd[1]: Finished user-runtime-dir@500.service. Jul 11 00:18:25.666825 systemd[1]: Starting user@500.service... Jul 11 00:18:25.669760 (systemd)[1281]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 11 00:18:25.736572 systemd[1281]: Queued start job for default target default.target. Jul 11 00:18:25.737135 systemd[1281]: Reached target paths.target. Jul 11 00:18:25.737169 systemd[1281]: Reached target sockets.target. Jul 11 00:18:25.737180 systemd[1281]: Reached target timers.target. Jul 11 00:18:25.737190 systemd[1281]: Reached target basic.target. Jul 11 00:18:25.737235 systemd[1281]: Reached target default.target. Jul 11 00:18:25.737265 systemd[1281]: Startup finished in 61ms. Jul 11 00:18:25.737331 systemd[1]: Started user@500.service. Jul 11 00:18:25.738329 systemd[1]: Started session-1.scope. Jul 11 00:18:25.791274 systemd[1]: Started sshd@1-10.0.0.16:22-10.0.0.1:33052.service. Jul 11 00:18:25.843118 sshd[1290]: Accepted publickey for core from 10.0.0.1 port 33052 ssh2: RSA SHA256:kAw98lsrYCxXKwzslBlKMy3//X0GU8J77htUo5WbMYE Jul 11 00:18:25.844433 sshd[1290]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 11 00:18:25.850464 systemd[1]: Started session-2.scope. Jul 11 00:18:25.850829 systemd-logind[1204]: New session 2 of user core. Jul 11 00:18:25.909480 sshd[1290]: pam_unix(sshd:session): session closed for user core Jul 11 00:18:25.914087 systemd[1]: Started sshd@2-10.0.0.16:22-10.0.0.1:33058.service. Jul 11 00:18:25.914824 systemd[1]: sshd@1-10.0.0.16:22-10.0.0.1:33052.service: Deactivated successfully. Jul 11 00:18:25.915497 systemd[1]: session-2.scope: Deactivated successfully. Jul 11 00:18:25.916028 systemd-logind[1204]: Session 2 logged out. Waiting for processes to exit. Jul 11 00:18:25.917312 systemd-logind[1204]: Removed session 2. Jul 11 00:18:25.959507 sshd[1295]: Accepted publickey for core from 10.0.0.1 port 33058 ssh2: RSA SHA256:kAw98lsrYCxXKwzslBlKMy3//X0GU8J77htUo5WbMYE Jul 11 00:18:25.960947 sshd[1295]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 11 00:18:25.964565 systemd-logind[1204]: New session 3 of user core. Jul 11 00:18:25.965496 systemd[1]: Started session-3.scope. Jul 11 00:18:26.017137 sshd[1295]: pam_unix(sshd:session): session closed for user core Jul 11 00:18:26.020853 systemd[1]: Started sshd@3-10.0.0.16:22-10.0.0.1:33068.service. Jul 11 00:18:26.021349 systemd[1]: sshd@2-10.0.0.16:22-10.0.0.1:33058.service: Deactivated successfully. Jul 11 00:18:26.021979 systemd[1]: session-3.scope: Deactivated successfully. Jul 11 00:18:26.022520 systemd-logind[1204]: Session 3 logged out. Waiting for processes to exit. Jul 11 00:18:26.023446 systemd-logind[1204]: Removed session 3. Jul 11 00:18:26.062153 sshd[1301]: Accepted publickey for core from 10.0.0.1 port 33068 ssh2: RSA SHA256:kAw98lsrYCxXKwzslBlKMy3//X0GU8J77htUo5WbMYE Jul 11 00:18:26.063448 sshd[1301]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 11 00:18:26.067058 systemd-logind[1204]: New session 4 of user core. Jul 11 00:18:26.067759 systemd[1]: Started session-4.scope. Jul 11 00:18:26.124689 sshd[1301]: pam_unix(sshd:session): session closed for user core Jul 11 00:18:26.127986 systemd[1]: Started sshd@4-10.0.0.16:22-10.0.0.1:33082.service. Jul 11 00:18:26.128497 systemd[1]: sshd@3-10.0.0.16:22-10.0.0.1:33068.service: Deactivated successfully. Jul 11 00:18:26.129095 systemd[1]: session-4.scope: Deactivated successfully. Jul 11 00:18:26.129679 systemd-logind[1204]: Session 4 logged out. Waiting for processes to exit. Jul 11 00:18:26.131005 systemd-logind[1204]: Removed session 4. Jul 11 00:18:26.172872 sshd[1307]: Accepted publickey for core from 10.0.0.1 port 33082 ssh2: RSA SHA256:kAw98lsrYCxXKwzslBlKMy3//X0GU8J77htUo5WbMYE Jul 11 00:18:26.174216 sshd[1307]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 11 00:18:26.178714 systemd-logind[1204]: New session 5 of user core. Jul 11 00:18:26.179572 systemd[1]: Started session-5.scope. Jul 11 00:18:26.238115 sudo[1311]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 11 00:18:26.238337 sudo[1311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 11 00:18:26.249979 systemd[1]: Starting coreos-metadata.service... Jul 11 00:18:26.256552 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 11 00:18:26.256714 systemd[1]: Finished coreos-metadata.service. Jul 11 00:18:26.801420 systemd[1]: Stopped kubelet.service. Jul 11 00:18:26.803618 systemd[1]: Starting kubelet.service... Jul 11 00:18:26.830772 systemd[1]: Reloading. Jul 11 00:18:26.891596 /usr/lib/systemd/system-generators/torcx-generator[1371]: time="2025-07-11T00:18:26Z" 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]" Jul 11 00:18:26.891914 /usr/lib/systemd/system-generators/torcx-generator[1371]: time="2025-07-11T00:18:26Z" level=info msg="torcx already run" Jul 11 00:18:27.044144 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 11 00:18:27.044165 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 11 00:18:27.061794 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. Jul 11 00:18:27.132323 systemd[1]: Started kubelet.service. Jul 11 00:18:27.133551 systemd[1]: Stopping kubelet.service... Jul 11 00:18:27.133929 systemd[1]: kubelet.service: Deactivated successfully. Jul 11 00:18:27.134101 systemd[1]: Stopped kubelet.service. Jul 11 00:18:27.135624 systemd[1]: Starting kubelet.service... Jul 11 00:18:27.231123 systemd[1]: Started kubelet.service. Jul 11 00:18:27.265530 kubelet[1413]: 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. Jul 11 00:18:27.265530 kubelet[1413]: 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. Jul 11 00:18:27.265530 kubelet[1413]: 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. Jul 11 00:18:27.265913 kubelet[1413]: I0711 00:18:27.265578 1413 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jul 11 00:18:27.633165 kubelet[1413]: I0711 00:18:27.633122 1413 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Jul 11 00:18:27.633334 kubelet[1413]: I0711 00:18:27.633321 1413 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 11 00:18:27.633799 kubelet[1413]: I0711 00:18:27.633780 1413 server.go:934] "Client rotation is on, will bootstrap in background" Jul 11 00:18:27.689337 kubelet[1413]: I0711 00:18:27.689189 1413 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 11 00:18:27.698176 kubelet[1413]: E0711 00:18:27.698128 1413 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Jul 11 00:18:27.698176 kubelet[1413]: I0711 00:18:27.698161 1413 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Jul 11 00:18:27.701664 kubelet[1413]: I0711 00:18:27.701634 1413 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 11 00:18:27.705485 kubelet[1413]: I0711 00:18:27.705444 1413 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Jul 11 00:18:27.705644 kubelet[1413]: I0711 00:18:27.705620 1413 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 11 00:18:27.705840 kubelet[1413]: I0711 00:18:27.705647 1413 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.16","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Jul 11 00:18:27.705936 kubelet[1413]: I0711 00:18:27.705908 1413 topology_manager.go:138] "Creating topology manager with none policy" Jul 11 00:18:27.705936 kubelet[1413]: I0711 00:18:27.705918 1413 container_manager_linux.go:300] "Creating device plugin manager" Jul 11 00:18:27.706180 kubelet[1413]: I0711 00:18:27.706156 1413 state_mem.go:36] "Initialized new in-memory state store" Jul 11 00:18:27.708542 kubelet[1413]: I0711 00:18:27.708515 1413 kubelet.go:408] "Attempting to sync node with API server" Jul 11 00:18:27.708542 kubelet[1413]: I0711 00:18:27.708546 1413 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 11 00:18:27.708653 kubelet[1413]: I0711 00:18:27.708567 1413 kubelet.go:314] "Adding apiserver pod source" Jul 11 00:18:27.708653 kubelet[1413]: I0711 00:18:27.708643 1413 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 11 00:18:27.708808 kubelet[1413]: E0711 00:18:27.708782 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:27.708902 kubelet[1413]: E0711 00:18:27.708889 1413 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:27.715350 kubelet[1413]: W0711 00:18:27.715315 1413 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.16" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 11 00:18:27.715658 kubelet[1413]: E0711 00:18:27.715618 1413 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.16\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Jul 11 00:18:27.718690 kubelet[1413]: I0711 00:18:27.718668 1413 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Jul 11 00:18:27.719473 kubelet[1413]: I0711 00:18:27.719451 1413 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 11 00:18:27.719707 kubelet[1413]: W0711 00:18:27.719695 1413 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 11 00:18:27.720854 kubelet[1413]: I0711 00:18:27.720836 1413 server.go:1274] "Started kubelet" Jul 11 00:18:27.722897 kubelet[1413]: I0711 00:18:27.722851 1413 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Jul 11 00:18:27.724451 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Jul 11 00:18:27.724979 kubelet[1413]: I0711 00:18:27.724953 1413 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 11 00:18:27.730913 kubelet[1413]: I0711 00:18:27.730844 1413 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 11 00:18:27.731310 kubelet[1413]: I0711 00:18:27.731287 1413 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 11 00:18:27.731450 kubelet[1413]: I0711 00:18:27.731420 1413 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jul 11 00:18:27.732498 kubelet[1413]: I0711 00:18:27.732451 1413 server.go:449] "Adding debug handlers to kubelet server" Jul 11 00:18:27.732959 kubelet[1413]: I0711 00:18:27.732939 1413 volume_manager.go:289] "Starting Kubelet Volume Manager" Jul 11 00:18:27.733271 kubelet[1413]: E0711 00:18:27.733244 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:27.733804 kubelet[1413]: I0711 00:18:27.733781 1413 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Jul 11 00:18:27.734409 kubelet[1413]: I0711 00:18:27.734373 1413 factory.go:221] Registration of the systemd container factory successfully Jul 11 00:18:27.734475 kubelet[1413]: I0711 00:18:27.734092 1413 reconciler.go:26] "Reconciler: start to sync state" Jul 11 00:18:27.734552 kubelet[1413]: E0711 00:18:27.734532 1413 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 11 00:18:27.734644 kubelet[1413]: I0711 00:18:27.734616 1413 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 Jul 11 00:18:27.738606 kubelet[1413]: E0711 00:18:27.738577 1413 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.16\" not found" node="10.0.0.16" Jul 11 00:18:27.739455 kubelet[1413]: I0711 00:18:27.739222 1413 factory.go:221] Registration of the containerd container factory successfully Jul 11 00:18:27.748667 kubelet[1413]: I0711 00:18:27.748648 1413 cpu_manager.go:214] "Starting CPU manager" policy="none" Jul 11 00:18:27.748797 kubelet[1413]: I0711 00:18:27.748781 1413 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jul 11 00:18:27.748856 kubelet[1413]: I0711 00:18:27.748847 1413 state_mem.go:36] "Initialized new in-memory state store" Jul 11 00:18:27.833500 kubelet[1413]: I0711 00:18:27.833467 1413 policy_none.go:49] "None policy: Start" Jul 11 00:18:27.834029 kubelet[1413]: E0711 00:18:27.833764 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:27.834391 kubelet[1413]: I0711 00:18:27.834363 1413 memory_manager.go:170] "Starting memorymanager" policy="None" Jul 11 00:18:27.834391 kubelet[1413]: I0711 00:18:27.834391 1413 state_mem.go:35] "Initializing new in-memory state store" Jul 11 00:18:27.840028 systemd[1]: Created slice kubepods.slice. Jul 11 00:18:27.845036 systemd[1]: Created slice kubepods-burstable.slice. Jul 11 00:18:27.847579 systemd[1]: Created slice kubepods-besteffort.slice. Jul 11 00:18:27.862673 kubelet[1413]: I0711 00:18:27.862539 1413 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 11 00:18:27.862779 kubelet[1413]: I0711 00:18:27.862698 1413 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 11 00:18:27.862779 kubelet[1413]: I0711 00:18:27.862709 1413 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 11 00:18:27.863302 kubelet[1413]: I0711 00:18:27.862978 1413 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 11 00:18:27.865210 kubelet[1413]: E0711 00:18:27.865188 1413 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.16\" not found" Jul 11 00:18:27.874667 kubelet[1413]: I0711 00:18:27.874610 1413 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 11 00:18:27.875968 kubelet[1413]: I0711 00:18:27.875934 1413 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 11 00:18:27.875968 kubelet[1413]: I0711 00:18:27.875964 1413 status_manager.go:217] "Starting to sync pod status with apiserver" Jul 11 00:18:27.876055 kubelet[1413]: I0711 00:18:27.875986 1413 kubelet.go:2321] "Starting kubelet main sync loop" Jul 11 00:18:27.876055 kubelet[1413]: E0711 00:18:27.876046 1413 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Jul 11 00:18:27.963801 kubelet[1413]: I0711 00:18:27.963677 1413 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.16" Jul 11 00:18:27.969978 kubelet[1413]: I0711 00:18:27.969935 1413 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.16" Jul 11 00:18:27.969978 kubelet[1413]: E0711 00:18:27.969982 1413 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.16\": node \"10.0.0.16\" not found" Jul 11 00:18:27.981323 kubelet[1413]: E0711 00:18:27.981286 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.082019 kubelet[1413]: E0711 00:18:28.081990 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.182409 kubelet[1413]: E0711 00:18:28.182370 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.254291 sudo[1311]: pam_unix(sudo:session): session closed for user root Jul 11 00:18:28.256131 sshd[1307]: pam_unix(sshd:session): session closed for user core Jul 11 00:18:28.258636 systemd[1]: sshd@4-10.0.0.16:22-10.0.0.1:33082.service: Deactivated successfully. Jul 11 00:18:28.259410 systemd[1]: session-5.scope: Deactivated successfully. Jul 11 00:18:28.259931 systemd-logind[1204]: Session 5 logged out. Waiting for processes to exit. Jul 11 00:18:28.260633 systemd-logind[1204]: Removed session 5. Jul 11 00:18:28.283531 kubelet[1413]: E0711 00:18:28.283498 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.384649 kubelet[1413]: E0711 00:18:28.384611 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.485717 kubelet[1413]: E0711 00:18:28.485675 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.586951 kubelet[1413]: E0711 00:18:28.586863 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.637277 kubelet[1413]: I0711 00:18:28.637238 1413 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 11 00:18:28.637595 kubelet[1413]: W0711 00:18:28.637563 1413 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 11 00:18:28.637595 kubelet[1413]: W0711 00:18:28.637573 1413 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 11 00:18:28.637700 kubelet[1413]: W0711 00:18:28.637684 1413 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 11 00:18:28.687138 kubelet[1413]: E0711 00:18:28.687088 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.709808 kubelet[1413]: E0711 00:18:28.709765 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:28.787244 kubelet[1413]: E0711 00:18:28.787190 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.888233 kubelet[1413]: E0711 00:18:28.888121 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:28.988454 kubelet[1413]: E0711 00:18:28.988400 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:29.088986 kubelet[1413]: E0711 00:18:29.088939 1413 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.16\" not found" Jul 11 00:18:29.190484 kubelet[1413]: I0711 00:18:29.190380 1413 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 11 00:18:29.191068 env[1213]: time="2025-07-11T00:18:29.190965030Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 11 00:18:29.191349 kubelet[1413]: I0711 00:18:29.191134 1413 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 11 00:18:29.709950 kubelet[1413]: E0711 00:18:29.709907 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:29.710297 kubelet[1413]: I0711 00:18:29.710280 1413 apiserver.go:52] "Watching apiserver" Jul 11 00:18:29.721143 systemd[1]: Created slice kubepods-besteffort-pod0e5b637e_d9d0_4424_bff0_00a5b61f6b7a.slice. Jul 11 00:18:29.734743 kubelet[1413]: I0711 00:18:29.734698 1413 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Jul 11 00:18:29.736084 systemd[1]: Created slice kubepods-burstable-pod812fb4c3_8080_497a_bd49_09fd5d9e8d5d.slice. Jul 11 00:18:29.741954 kubelet[1413]: I0711 00:18:29.741900 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/0e5b637e-d9d0-4424-bff0-00a5b61f6b7a-kube-proxy\") pod \"kube-proxy-cdr98\" (UID: \"0e5b637e-d9d0-4424-bff0-00a5b61f6b7a\") " pod="kube-system/kube-proxy-cdr98" Jul 11 00:18:29.741954 kubelet[1413]: I0711 00:18:29.741944 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0e5b637e-d9d0-4424-bff0-00a5b61f6b7a-lib-modules\") pod \"kube-proxy-cdr98\" (UID: \"0e5b637e-d9d0-4424-bff0-00a5b61f6b7a\") " pod="kube-system/kube-proxy-cdr98" Jul 11 00:18:29.741954 kubelet[1413]: I0711 00:18:29.741966 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zfpvr\" (UniqueName: \"kubernetes.io/projected/0e5b637e-d9d0-4424-bff0-00a5b61f6b7a-kube-api-access-zfpvr\") pod \"kube-proxy-cdr98\" (UID: \"0e5b637e-d9d0-4424-bff0-00a5b61f6b7a\") " pod="kube-system/kube-proxy-cdr98" Jul 11 00:18:29.742114 kubelet[1413]: I0711 00:18:29.741984 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/812fb4c3-8080-497a-bd49-09fd5d9e8d5d-run\") pod \"kube-flannel-ds-nvxxx\" (UID: \"812fb4c3-8080-497a-bd49-09fd5d9e8d5d\") " pod="kube-flannel/kube-flannel-ds-nvxxx" Jul 11 00:18:29.742114 kubelet[1413]: I0711 00:18:29.742000 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zbv7x\" (UniqueName: \"kubernetes.io/projected/812fb4c3-8080-497a-bd49-09fd5d9e8d5d-kube-api-access-zbv7x\") pod \"kube-flannel-ds-nvxxx\" (UID: \"812fb4c3-8080-497a-bd49-09fd5d9e8d5d\") " pod="kube-flannel/kube-flannel-ds-nvxxx" Jul 11 00:18:29.742114 kubelet[1413]: I0711 00:18:29.742015 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0e5b637e-d9d0-4424-bff0-00a5b61f6b7a-xtables-lock\") pod \"kube-proxy-cdr98\" (UID: \"0e5b637e-d9d0-4424-bff0-00a5b61f6b7a\") " pod="kube-system/kube-proxy-cdr98" Jul 11 00:18:29.742114 kubelet[1413]: I0711 00:18:29.742030 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/812fb4c3-8080-497a-bd49-09fd5d9e8d5d-cni-plugin\") pod \"kube-flannel-ds-nvxxx\" (UID: \"812fb4c3-8080-497a-bd49-09fd5d9e8d5d\") " pod="kube-flannel/kube-flannel-ds-nvxxx" Jul 11 00:18:29.742114 kubelet[1413]: I0711 00:18:29.742043 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/812fb4c3-8080-497a-bd49-09fd5d9e8d5d-cni\") pod \"kube-flannel-ds-nvxxx\" (UID: \"812fb4c3-8080-497a-bd49-09fd5d9e8d5d\") " pod="kube-flannel/kube-flannel-ds-nvxxx" Jul 11 00:18:29.742244 kubelet[1413]: I0711 00:18:29.742059 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/812fb4c3-8080-497a-bd49-09fd5d9e8d5d-flannel-cfg\") pod \"kube-flannel-ds-nvxxx\" (UID: \"812fb4c3-8080-497a-bd49-09fd5d9e8d5d\") " pod="kube-flannel/kube-flannel-ds-nvxxx" Jul 11 00:18:29.742244 kubelet[1413]: I0711 00:18:29.742074 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/812fb4c3-8080-497a-bd49-09fd5d9e8d5d-xtables-lock\") pod \"kube-flannel-ds-nvxxx\" (UID: \"812fb4c3-8080-497a-bd49-09fd5d9e8d5d\") " pod="kube-flannel/kube-flannel-ds-nvxxx" Jul 11 00:18:29.852082 kubelet[1413]: I0711 00:18:29.852043 1413 swap_util.go:74] "error creating dir to test if tmpfs noswap is enabled. Assuming not supported" mount path="" error="stat /var/lib/kubelet/plugins/kubernetes.io/empty-dir: no such file or directory" Jul 11 00:18:30.035360 kubelet[1413]: E0711 00:18:30.035242 1413 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" Jul 11 00:18:30.036603 env[1213]: time="2025-07-11T00:18:30.036543700Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-cdr98,Uid:0e5b637e-d9d0-4424-bff0-00a5b61f6b7a,Namespace:kube-system,Attempt:0,}" Jul 11 00:18:30.052065 kubelet[1413]: E0711 00:18:30.052035 1413 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" Jul 11 00:18:30.052591 env[1213]: time="2025-07-11T00:18:30.052549138Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-nvxxx,Uid:812fb4c3-8080-497a-bd49-09fd5d9e8d5d,Namespace:kube-flannel,Attempt:0,}" Jul 11 00:18:30.659309 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3374966738.mount: Deactivated successfully. Jul 11 00:18:30.663424 env[1213]: time="2025-07-11T00:18:30.663374482Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.664755 env[1213]: time="2025-07-11T00:18:30.664725155Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.667659 env[1213]: time="2025-07-11T00:18:30.667609227Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.668810 env[1213]: time="2025-07-11T00:18:30.668780893Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.670442 env[1213]: time="2025-07-11T00:18:30.670413937Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.672118 env[1213]: time="2025-07-11T00:18:30.672088480Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.674528 env[1213]: time="2025-07-11T00:18:30.674499335Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.675983 env[1213]: time="2025-07-11T00:18:30.675954503Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:30.709833 env[1213]: time="2025-07-11T00:18:30.709754195Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 11 00:18:30.710037 env[1213]: time="2025-07-11T00:18:30.709810038Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 11 00:18:30.710037 env[1213]: time="2025-07-11T00:18:30.709821070Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 11 00:18:30.710155 env[1213]: time="2025-07-11T00:18:30.710127644Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a pid=1476 runtime=io.containerd.runc.v2 Jul 11 00:18:30.710621 env[1213]: time="2025-07-11T00:18:30.710571525Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 11 00:18:30.710719 env[1213]: time="2025-07-11T00:18:30.710609427Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 11 00:18:30.710719 env[1213]: time="2025-07-11T00:18:30.710620984Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 11 00:18:30.710810 env[1213]: time="2025-07-11T00:18:30.710755906Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/f11a0c9a3690a049ac8ccd538ae51cda3ba7920be72b964cc7cf79b16f044964 pid=1477 runtime=io.containerd.runc.v2 Jul 11 00:18:30.710893 kubelet[1413]: E0711 00:18:30.710837 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:30.735204 systemd[1]: Started cri-containerd-e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a.scope. Jul 11 00:18:30.736367 systemd[1]: Started cri-containerd-f11a0c9a3690a049ac8ccd538ae51cda3ba7920be72b964cc7cf79b16f044964.scope. Jul 11 00:18:30.783379 env[1213]: time="2025-07-11T00:18:30.783330387Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-cdr98,Uid:0e5b637e-d9d0-4424-bff0-00a5b61f6b7a,Namespace:kube-system,Attempt:0,} returns sandbox id \"f11a0c9a3690a049ac8ccd538ae51cda3ba7920be72b964cc7cf79b16f044964\"" Jul 11 00:18:30.785601 kubelet[1413]: E0711 00:18:30.785341 1413 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" Jul 11 00:18:30.786687 env[1213]: time="2025-07-11T00:18:30.786648156Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\"" Jul 11 00:18:30.787101 env[1213]: time="2025-07-11T00:18:30.787069853Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-nvxxx,Uid:812fb4c3-8080-497a-bd49-09fd5d9e8d5d,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a\"" Jul 11 00:18:30.787877 kubelet[1413]: E0711 00:18:30.787854 1413 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" Jul 11 00:18:31.711213 kubelet[1413]: E0711 00:18:31.711141 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:31.834777 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1184158975.mount: Deactivated successfully. Jul 11 00:18:32.304344 env[1213]: time="2025-07-11T00:18:32.304291430Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.31.10,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:32.305643 env[1213]: time="2025-07-11T00:18:32.305611334Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:dde5ff0da443b455e81aefc7bf6a216fdd659d1cbe13b8e8ac8129c3ecd27f89,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:32.307127 env[1213]: time="2025-07-11T00:18:32.307088013Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.31.10,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:32.308400 env[1213]: time="2025-07-11T00:18:32.308375668Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:32.308828 env[1213]: time="2025-07-11T00:18:32.308798665Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\" returns image reference \"sha256:dde5ff0da443b455e81aefc7bf6a216fdd659d1cbe13b8e8ac8129c3ecd27f89\"" Jul 11 00:18:32.313013 env[1213]: time="2025-07-11T00:18:32.312970825Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 11 00:18:32.313985 env[1213]: time="2025-07-11T00:18:32.313948438Z" level=info msg="CreateContainer within sandbox \"f11a0c9a3690a049ac8ccd538ae51cda3ba7920be72b964cc7cf79b16f044964\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 11 00:18:32.324392 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2428939539.mount: Deactivated successfully. Jul 11 00:18:32.332487 env[1213]: time="2025-07-11T00:18:32.332441171Z" level=info msg="CreateContainer within sandbox \"f11a0c9a3690a049ac8ccd538ae51cda3ba7920be72b964cc7cf79b16f044964\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"7aec39bafedcd29667302b3813a23ffdb9dd0418a77db76c540f14703a44afc3\"" Jul 11 00:18:32.333473 env[1213]: time="2025-07-11T00:18:32.333404756Z" level=info msg="StartContainer for \"7aec39bafedcd29667302b3813a23ffdb9dd0418a77db76c540f14703a44afc3\"" Jul 11 00:18:32.356481 systemd[1]: Started cri-containerd-7aec39bafedcd29667302b3813a23ffdb9dd0418a77db76c540f14703a44afc3.scope. Jul 11 00:18:32.406072 env[1213]: time="2025-07-11T00:18:32.406019545Z" level=info msg="StartContainer for \"7aec39bafedcd29667302b3813a23ffdb9dd0418a77db76c540f14703a44afc3\" returns successfully" Jul 11 00:18:32.711956 kubelet[1413]: E0711 00:18:32.711843 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:32.886773 kubelet[1413]: E0711 00:18:32.886739 1413 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" Jul 11 00:18:32.896034 kubelet[1413]: I0711 00:18:32.895957 1413 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-cdr98" podStartSLOduration=3.369797444 podStartE2EDuration="4.895931401s" podCreationTimestamp="2025-07-11 00:18:28 +0000 UTC" firstStartedPulling="2025-07-11 00:18:30.786174576 +0000 UTC m=+3.551653114" lastFinishedPulling="2025-07-11 00:18:32.312308534 +0000 UTC m=+5.077787071" observedRunningTime="2025-07-11 00:18:32.895523239 +0000 UTC m=+5.661001776" watchObservedRunningTime="2025-07-11 00:18:32.895931401 +0000 UTC m=+5.661409938" Jul 11 00:18:33.551320 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3779391469.mount: Deactivated successfully. Jul 11 00:18:33.603525 env[1213]: time="2025-07-11T00:18:33.603459602Z" 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:[],}" Jul 11 00:18:33.606206 env[1213]: time="2025-07-11T00:18:33.606162058Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:33.607870 env[1213]: time="2025-07-11T00:18:33.607837062Z" 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:[],}" Jul 11 00:18:33.609369 env[1213]: time="2025-07-11T00:18:33.609336717Z" 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:[],}" Jul 11 00:18:33.609944 env[1213]: time="2025-07-11T00:18:33.609911295Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 11 00:18:33.612546 env[1213]: time="2025-07-11T00:18:33.612500301Z" level=info msg="CreateContainer within sandbox \"e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 11 00:18:33.626935 env[1213]: time="2025-07-11T00:18:33.626878171Z" level=info msg="CreateContainer within sandbox \"e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"2644735bc79289b8e3b9ab29e6925f04de0c2a50b91b83703d77b0848df7917d\"" Jul 11 00:18:33.627534 env[1213]: time="2025-07-11T00:18:33.627409134Z" level=info msg="StartContainer for \"2644735bc79289b8e3b9ab29e6925f04de0c2a50b91b83703d77b0848df7917d\"" Jul 11 00:18:33.641421 systemd[1]: Started cri-containerd-2644735bc79289b8e3b9ab29e6925f04de0c2a50b91b83703d77b0848df7917d.scope. Jul 11 00:18:33.675606 env[1213]: time="2025-07-11T00:18:33.675239329Z" level=info msg="StartContainer for \"2644735bc79289b8e3b9ab29e6925f04de0c2a50b91b83703d77b0848df7917d\" returns successfully" Jul 11 00:18:33.680675 systemd[1]: cri-containerd-2644735bc79289b8e3b9ab29e6925f04de0c2a50b91b83703d77b0848df7917d.scope: Deactivated successfully. Jul 11 00:18:33.712068 kubelet[1413]: E0711 00:18:33.711984 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:33.838308 env[1213]: time="2025-07-11T00:18:33.837884314Z" level=info msg="shim disconnected" id=2644735bc79289b8e3b9ab29e6925f04de0c2a50b91b83703d77b0848df7917d Jul 11 00:18:33.838471 env[1213]: time="2025-07-11T00:18:33.838448019Z" level=warning msg="cleaning up after shim disconnected" id=2644735bc79289b8e3b9ab29e6925f04de0c2a50b91b83703d77b0848df7917d namespace=k8s.io Jul 11 00:18:33.838538 env[1213]: time="2025-07-11T00:18:33.838525786Z" level=info msg="cleaning up dead shim" Jul 11 00:18:33.845392 env[1213]: time="2025-07-11T00:18:33.845355122Z" level=warning msg="cleanup warnings time=\"2025-07-11T00:18:33Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1756 runtime=io.containerd.runc.v2\n" Jul 11 00:18:33.889062 kubelet[1413]: E0711 00:18:33.889023 1413 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" Jul 11 00:18:33.889300 kubelet[1413]: E0711 00:18:33.889284 1413 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" Jul 11 00:18:33.889909 env[1213]: time="2025-07-11T00:18:33.889865715Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 11 00:18:34.714649 kubelet[1413]: E0711 00:18:34.712992 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:35.123831 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount114864067.mount: Deactivated successfully. Jul 11 00:18:35.714102 kubelet[1413]: E0711 00:18:35.714042 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:35.803531 env[1213]: time="2025-07-11T00:18:35.803451669Z" 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:[],}" Jul 11 00:18:35.806858 env[1213]: time="2025-07-11T00:18:35.806806805Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:35.808924 env[1213]: time="2025-07-11T00:18:35.808887551Z" 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:[],}" Jul 11 00:18:35.811296 env[1213]: time="2025-07-11T00:18:35.811263674Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:35.812144 env[1213]: time="2025-07-11T00:18:35.812055232Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 11 00:18:35.814917 env[1213]: time="2025-07-11T00:18:35.814862397Z" level=info msg="CreateContainer within sandbox \"e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 11 00:18:35.823647 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3217825653.mount: Deactivated successfully. Jul 11 00:18:35.827944 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1808684776.mount: Deactivated successfully. Jul 11 00:18:35.832554 env[1213]: time="2025-07-11T00:18:35.832499199Z" level=info msg="CreateContainer within sandbox \"e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90\"" Jul 11 00:18:35.833390 env[1213]: time="2025-07-11T00:18:35.833358349Z" level=info msg="StartContainer for \"e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90\"" Jul 11 00:18:35.852069 systemd[1]: Started cri-containerd-e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90.scope. Jul 11 00:18:35.889103 env[1213]: time="2025-07-11T00:18:35.889054769Z" level=info msg="StartContainer for \"e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90\" returns successfully" Jul 11 00:18:35.894888 systemd[1]: cri-containerd-e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90.scope: Deactivated successfully. Jul 11 00:18:35.896061 kubelet[1413]: E0711 00:18:35.896035 1413 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" Jul 11 00:18:35.923860 kubelet[1413]: I0711 00:18:35.923806 1413 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Jul 11 00:18:36.042829 env[1213]: time="2025-07-11T00:18:36.042323733Z" level=info msg="shim disconnected" id=e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90 Jul 11 00:18:36.043063 env[1213]: time="2025-07-11T00:18:36.043018216Z" level=warning msg="cleaning up after shim disconnected" id=e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90 namespace=k8s.io Jul 11 00:18:36.043063 env[1213]: time="2025-07-11T00:18:36.043047108Z" level=info msg="cleaning up dead shim" Jul 11 00:18:36.050028 env[1213]: time="2025-07-11T00:18:36.049964058Z" level=warning msg="cleanup warnings time=\"2025-07-11T00:18:36Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1812 runtime=io.containerd.runc.v2\n" Jul 11 00:18:36.714385 kubelet[1413]: E0711 00:18:36.714312 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:36.821608 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e37a757f0296d4e66e133bc76611150bb9c1b2d73fb1c59ea5824ed5d4794a90-rootfs.mount: Deactivated successfully. Jul 11 00:18:36.899508 kubelet[1413]: E0711 00:18:36.899452 1413 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" Jul 11 00:18:36.902286 env[1213]: time="2025-07-11T00:18:36.902207411Z" level=info msg="CreateContainer within sandbox \"e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 11 00:18:36.916449 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3314890947.mount: Deactivated successfully. Jul 11 00:18:36.922990 env[1213]: time="2025-07-11T00:18:36.922949018Z" level=info msg="CreateContainer within sandbox \"e8b12901374bc04728ff58a512db16a994f50e5731ece457963098476d51921a\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"ee9a5f4e25b2b97cc81fb666d909a7536ba9bfde54a14bbf0eb091b46c6c9fdb\"" Jul 11 00:18:36.923868 env[1213]: time="2025-07-11T00:18:36.923833688Z" level=info msg="StartContainer for \"ee9a5f4e25b2b97cc81fb666d909a7536ba9bfde54a14bbf0eb091b46c6c9fdb\"" Jul 11 00:18:36.947952 systemd[1]: Started cri-containerd-ee9a5f4e25b2b97cc81fb666d909a7536ba9bfde54a14bbf0eb091b46c6c9fdb.scope. Jul 11 00:18:36.987229 env[1213]: time="2025-07-11T00:18:36.987111065Z" level=info msg="StartContainer for \"ee9a5f4e25b2b97cc81fb666d909a7536ba9bfde54a14bbf0eb091b46c6c9fdb\" returns successfully" Jul 11 00:18:37.715323 kubelet[1413]: E0711 00:18:37.715268 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:37.902729 kubelet[1413]: E0711 00:18:37.902623 1413 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" Jul 11 00:18:38.065181 systemd-networkd[1048]: flannel.1: Link UP Jul 11 00:18:38.065204 systemd-networkd[1048]: flannel.1: Gained carrier Jul 11 00:18:38.716100 kubelet[1413]: E0711 00:18:38.716046 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:38.904592 kubelet[1413]: E0711 00:18:38.904551 1413 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" Jul 11 00:18:39.010481 kubelet[1413]: I0711 00:18:39.010346 1413 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-nvxxx" podStartSLOduration=5.984846263 podStartE2EDuration="11.010329483s" podCreationTimestamp="2025-07-11 00:18:28 +0000 UTC" firstStartedPulling="2025-07-11 00:18:30.788261643 +0000 UTC m=+3.553740180" lastFinishedPulling="2025-07-11 00:18:35.813744863 +0000 UTC m=+8.579223400" observedRunningTime="2025-07-11 00:18:37.915115284 +0000 UTC m=+10.680593861" watchObservedRunningTime="2025-07-11 00:18:39.010329483 +0000 UTC m=+11.775808020" Jul 11 00:18:39.017966 systemd[1]: Created slice kubepods-besteffort-podf0ec8470_1c17_4c2e_aedd_2e573c2054b5.slice. Jul 11 00:18:39.204393 kubelet[1413]: I0711 00:18:39.204343 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8p6nv\" (UniqueName: \"kubernetes.io/projected/f0ec8470-1c17-4c2e-aedd-2e573c2054b5-kube-api-access-8p6nv\") pod \"nginx-deployment-8587fbcb89-rrz6l\" (UID: \"f0ec8470-1c17-4c2e-aedd-2e573c2054b5\") " pod="default/nginx-deployment-8587fbcb89-rrz6l" Jul 11 00:18:39.272838 systemd-networkd[1048]: flannel.1: Gained IPv6LL Jul 11 00:18:39.320472 env[1213]: time="2025-07-11T00:18:39.320426461Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-rrz6l,Uid:f0ec8470-1c17-4c2e-aedd-2e573c2054b5,Namespace:default,Attempt:0,}" Jul 11 00:18:39.352241 systemd-networkd[1048]: cni0: Link UP Jul 11 00:18:39.352247 systemd-networkd[1048]: cni0: Gained carrier Jul 11 00:18:39.352989 systemd-networkd[1048]: cni0: Lost carrier Jul 11 00:18:39.361984 systemd-networkd[1048]: veth83dbe8b9: Link UP Jul 11 00:18:39.362829 kernel: cni0: port 1(veth83dbe8b9) entered blocking state Jul 11 00:18:39.362892 kernel: cni0: port 1(veth83dbe8b9) entered disabled state Jul 11 00:18:39.364280 kernel: device veth83dbe8b9 entered promiscuous mode Jul 11 00:18:39.364338 kernel: cni0: port 1(veth83dbe8b9) entered blocking state Jul 11 00:18:39.364361 kernel: cni0: port 1(veth83dbe8b9) entered forwarding state Jul 11 00:18:39.366204 kernel: cni0: port 1(veth83dbe8b9) entered disabled state Jul 11 00:18:39.376297 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth83dbe8b9: link becomes ready Jul 11 00:18:39.376367 kernel: cni0: port 1(veth83dbe8b9) entered blocking state Jul 11 00:18:39.376386 kernel: cni0: port 1(veth83dbe8b9) entered forwarding state Jul 11 00:18:39.377086 systemd-networkd[1048]: veth83dbe8b9: Gained carrier Jul 11 00:18:39.377284 systemd-networkd[1048]: cni0: Gained carrier Jul 11 00:18:39.385998 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001c928), "name":"cbr0", "type":"bridge"} Jul 11 00:18:39.385998 env[1213]: delegateAdd: netconf sent to delegate plugin: Jul 11 00:18:39.407398 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-11T00:18:39.407318998Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 11 00:18:39.407529 env[1213]: time="2025-07-11T00:18:39.407406065Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 11 00:18:39.407529 env[1213]: time="2025-07-11T00:18:39.407441373Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 11 00:18:39.407644 env[1213]: time="2025-07-11T00:18:39.407606036Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/6b4f1fa54368110fa767260c00e261a1f30305d4d1ec96768a93c0e7d1d76c07 pid=1998 runtime=io.containerd.runc.v2 Jul 11 00:18:39.420236 systemd[1]: Started cri-containerd-6b4f1fa54368110fa767260c00e261a1f30305d4d1ec96768a93c0e7d1d76c07.scope. Jul 11 00:18:39.440026 systemd-resolved[1153]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 11 00:18:39.456227 env[1213]: time="2025-07-11T00:18:39.456187440Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-rrz6l,Uid:f0ec8470-1c17-4c2e-aedd-2e573c2054b5,Namespace:default,Attempt:0,} returns sandbox id \"6b4f1fa54368110fa767260c00e261a1f30305d4d1ec96768a93c0e7d1d76c07\"" Jul 11 00:18:39.457993 env[1213]: time="2025-07-11T00:18:39.457961943Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 11 00:18:39.717178 kubelet[1413]: E0711 00:18:39.717063 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:40.489880 systemd-networkd[1048]: cni0: Gained IPv6LL Jul 11 00:18:40.552882 systemd-networkd[1048]: veth83dbe8b9: Gained IPv6LL Jul 11 00:18:40.717394 kubelet[1413]: E0711 00:18:40.717345 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:41.274154 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2213369651.mount: Deactivated successfully. Jul 11 00:18:41.717777 kubelet[1413]: E0711 00:18:41.717653 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:42.694834 env[1213]: time="2025-07-11T00:18:42.694785232Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:42.696283 env[1213]: time="2025-07-11T00:18:42.696255004Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:42.698232 env[1213]: time="2025-07-11T00:18:42.698185922Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:42.699758 env[1213]: time="2025-07-11T00:18:42.699732652Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:42.701282 env[1213]: time="2025-07-11T00:18:42.701251405Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 11 00:18:42.706172 env[1213]: time="2025-07-11T00:18:42.706140185Z" level=info msg="CreateContainer within sandbox \"6b4f1fa54368110fa767260c00e261a1f30305d4d1ec96768a93c0e7d1d76c07\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 11 00:18:42.717038 env[1213]: time="2025-07-11T00:18:42.716987097Z" level=info msg="CreateContainer within sandbox \"6b4f1fa54368110fa767260c00e261a1f30305d4d1ec96768a93c0e7d1d76c07\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"c101caac697c914b04f3469fdbf5780a2f711a158856a433bc3ec5602d5b0db3\"" Jul 11 00:18:42.717778 env[1213]: time="2025-07-11T00:18:42.717750181Z" level=info msg="StartContainer for \"c101caac697c914b04f3469fdbf5780a2f711a158856a433bc3ec5602d5b0db3\"" Jul 11 00:18:42.718804 kubelet[1413]: E0711 00:18:42.718775 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:42.738995 systemd[1]: Started cri-containerd-c101caac697c914b04f3469fdbf5780a2f711a158856a433bc3ec5602d5b0db3.scope. Jul 11 00:18:42.779041 env[1213]: time="2025-07-11T00:18:42.778980560Z" level=info msg="StartContainer for \"c101caac697c914b04f3469fdbf5780a2f711a158856a433bc3ec5602d5b0db3\" returns successfully" Jul 11 00:18:43.719862 kubelet[1413]: E0711 00:18:43.719817 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:44.721290 kubelet[1413]: E0711 00:18:44.721249 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:45.721639 kubelet[1413]: E0711 00:18:45.721593 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:45.853596 kubelet[1413]: I0711 00:18:45.853537 1413 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-rrz6l" podStartSLOduration=3.606355289 podStartE2EDuration="6.853518316s" podCreationTimestamp="2025-07-11 00:18:39 +0000 UTC" firstStartedPulling="2025-07-11 00:18:39.45749949 +0000 UTC m=+12.222978027" lastFinishedPulling="2025-07-11 00:18:42.704662557 +0000 UTC m=+15.470141054" observedRunningTime="2025-07-11 00:18:42.925514137 +0000 UTC m=+15.690992674" watchObservedRunningTime="2025-07-11 00:18:45.853518316 +0000 UTC m=+18.618996853" Jul 11 00:18:45.858095 systemd[1]: Created slice kubepods-besteffort-pod15900fbc_5a3e_4afe_97c2_0bb07160c21b.slice. Jul 11 00:18:46.043426 kubelet[1413]: I0711 00:18:46.043042 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-szsdc\" (UniqueName: \"kubernetes.io/projected/15900fbc-5a3e-4afe-97c2-0bb07160c21b-kube-api-access-szsdc\") pod \"nfs-server-provisioner-0\" (UID: \"15900fbc-5a3e-4afe-97c2-0bb07160c21b\") " pod="default/nfs-server-provisioner-0" Jul 11 00:18:46.043426 kubelet[1413]: I0711 00:18:46.043087 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/15900fbc-5a3e-4afe-97c2-0bb07160c21b-data\") pod \"nfs-server-provisioner-0\" (UID: \"15900fbc-5a3e-4afe-97c2-0bb07160c21b\") " pod="default/nfs-server-provisioner-0" Jul 11 00:18:46.160881 env[1213]: time="2025-07-11T00:18:46.160824910Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:15900fbc-5a3e-4afe-97c2-0bb07160c21b,Namespace:default,Attempt:0,}" Jul 11 00:18:46.179956 systemd-networkd[1048]: vethb79cfeda: Link UP Jul 11 00:18:46.182318 kernel: cni0: port 2(vethb79cfeda) entered blocking state Jul 11 00:18:46.182410 kernel: cni0: port 2(vethb79cfeda) entered disabled state Jul 11 00:18:46.182434 kernel: device vethb79cfeda entered promiscuous mode Jul 11 00:18:46.190049 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 11 00:18:46.190148 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethb79cfeda: link becomes ready Jul 11 00:18:46.190169 kernel: cni0: port 2(vethb79cfeda) entered blocking state Jul 11 00:18:46.190191 kernel: cni0: port 2(vethb79cfeda) entered forwarding state Jul 11 00:18:46.190587 systemd-networkd[1048]: vethb79cfeda: Gained carrier Jul 11 00:18:46.239823 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40001148e8), "name":"cbr0", "type":"bridge"} Jul 11 00:18:46.239823 env[1213]: delegateAdd: netconf sent to delegate plugin: Jul 11 00:18:46.253502 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-11T00:18:46.253431266Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 11 00:18:46.253502 env[1213]: time="2025-07-11T00:18:46.253469231Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 11 00:18:46.253502 env[1213]: time="2025-07-11T00:18:46.253479964Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 11 00:18:46.253931 env[1213]: time="2025-07-11T00:18:46.253899148Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/bbb6065cfd255cced0b474e24a0a00796d170346c5fa783f398e5a5fbde85ee2 pid=2169 runtime=io.containerd.runc.v2 Jul 11 00:18:46.266606 systemd[1]: Started cri-containerd-bbb6065cfd255cced0b474e24a0a00796d170346c5fa783f398e5a5fbde85ee2.scope. Jul 11 00:18:46.301230 systemd-resolved[1153]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 11 00:18:46.318782 env[1213]: time="2025-07-11T00:18:46.318692620Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:15900fbc-5a3e-4afe-97c2-0bb07160c21b,Namespace:default,Attempt:0,} returns sandbox id \"bbb6065cfd255cced0b474e24a0a00796d170346c5fa783f398e5a5fbde85ee2\"" Jul 11 00:18:46.322154 env[1213]: time="2025-07-11T00:18:46.322098435Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 11 00:18:46.722742 kubelet[1413]: E0711 00:18:46.722609 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:47.709503 kubelet[1413]: E0711 00:18:47.709450 1413 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:47.723221 kubelet[1413]: E0711 00:18:47.723184 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:48.040923 systemd-networkd[1048]: vethb79cfeda: Gained IPv6LL Jul 11 00:18:48.384280 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3437264577.mount: Deactivated successfully. Jul 11 00:18:48.723628 kubelet[1413]: E0711 00:18:48.723515 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:49.723850 kubelet[1413]: E0711 00:18:49.723801 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:50.162847 env[1213]: time="2025-07-11T00:18:50.162799997Z" 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:[],}" Jul 11 00:18:50.165976 env[1213]: time="2025-07-11T00:18:50.165918836Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:18:50.169022 env[1213]: time="2025-07-11T00:18:50.168981916Z" 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:[],}" Jul 11 00:18:50.169822 env[1213]: time="2025-07-11T00:18:50.169791768Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 11 00:18:50.171282 env[1213]: time="2025-07-11T00:18:50.171213050Z" 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:[],}" Jul 11 00:18:50.173590 env[1213]: time="2025-07-11T00:18:50.173541132Z" level=info msg="CreateContainer within sandbox \"bbb6065cfd255cced0b474e24a0a00796d170346c5fa783f398e5a5fbde85ee2\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 11 00:18:50.185915 env[1213]: time="2025-07-11T00:18:50.185813867Z" level=info msg="CreateContainer within sandbox \"bbb6065cfd255cced0b474e24a0a00796d170346c5fa783f398e5a5fbde85ee2\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"8264d822ec4ff68addec98a9c3f7b1c66cc0814acd98f04d3acff4f470cbd9ae\"" Jul 11 00:18:50.186498 env[1213]: time="2025-07-11T00:18:50.186451676Z" level=info msg="StartContainer for \"8264d822ec4ff68addec98a9c3f7b1c66cc0814acd98f04d3acff4f470cbd9ae\"" Jul 11 00:18:50.204875 systemd[1]: Started cri-containerd-8264d822ec4ff68addec98a9c3f7b1c66cc0814acd98f04d3acff4f470cbd9ae.scope. Jul 11 00:18:50.245766 env[1213]: time="2025-07-11T00:18:50.241084364Z" level=info msg="StartContainer for \"8264d822ec4ff68addec98a9c3f7b1c66cc0814acd98f04d3acff4f470cbd9ae\" returns successfully" Jul 11 00:18:50.724124 kubelet[1413]: E0711 00:18:50.724083 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:50.937962 kubelet[1413]: I0711 00:18:50.937830 1413 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.088191966 podStartE2EDuration="5.937814187s" podCreationTimestamp="2025-07-11 00:18:45 +0000 UTC" firstStartedPulling="2025-07-11 00:18:46.321841486 +0000 UTC m=+19.087320023" lastFinishedPulling="2025-07-11 00:18:50.171463707 +0000 UTC m=+22.936942244" observedRunningTime="2025-07-11 00:18:50.937399335 +0000 UTC m=+23.702877872" watchObservedRunningTime="2025-07-11 00:18:50.937814187 +0000 UTC m=+23.703292684" Jul 11 00:18:51.724785 kubelet[1413]: E0711 00:18:51.724731 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:52.725148 kubelet[1413]: E0711 00:18:52.725098 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:53.726246 kubelet[1413]: E0711 00:18:53.726193 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:54.727035 kubelet[1413]: E0711 00:18:54.726994 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:55.727916 kubelet[1413]: E0711 00:18:55.727876 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:56.729411 kubelet[1413]: E0711 00:18:56.729369 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:57.730680 kubelet[1413]: E0711 00:18:57.730639 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:58.731627 kubelet[1413]: E0711 00:18:58.731594 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:18:59.733051 kubelet[1413]: E0711 00:18:59.732998 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:00.084572 systemd[1]: Created slice kubepods-besteffort-pod1e4803c6_3b3c_4489_ab8a_bc88314a36bd.slice. Jul 11 00:19:00.219137 kubelet[1413]: I0711 00:19:00.219095 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4f0ef5a9-1ae4-49db-a9b1-893bcbc64093\" (UniqueName: \"kubernetes.io/nfs/1e4803c6-3b3c-4489-ab8a-bc88314a36bd-pvc-4f0ef5a9-1ae4-49db-a9b1-893bcbc64093\") pod \"test-pod-1\" (UID: \"1e4803c6-3b3c-4489-ab8a-bc88314a36bd\") " pod="default/test-pod-1" Jul 11 00:19:00.219334 kubelet[1413]: I0711 00:19:00.219315 1413 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-92jc2\" (UniqueName: \"kubernetes.io/projected/1e4803c6-3b3c-4489-ab8a-bc88314a36bd-kube-api-access-92jc2\") pod \"test-pod-1\" (UID: \"1e4803c6-3b3c-4489-ab8a-bc88314a36bd\") " pod="default/test-pod-1" Jul 11 00:19:00.345811 kernel: FS-Cache: Loaded Jul 11 00:19:00.374142 kernel: RPC: Registered named UNIX socket transport module. Jul 11 00:19:00.374254 kernel: RPC: Registered udp transport module. Jul 11 00:19:00.374275 kernel: RPC: Registered tcp transport module. Jul 11 00:19:00.375796 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 11 00:19:00.424734 kernel: FS-Cache: Netfs 'nfs' registered for caching Jul 11 00:19:00.555845 kernel: NFS: Registering the id_resolver key type Jul 11 00:19:00.555965 kernel: Key type id_resolver registered Jul 11 00:19:00.555985 kernel: Key type id_legacy registered Jul 11 00:19:00.585114 nfsidmap[2352]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 11 00:19:00.591599 nfsidmap[2355]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 11 00:19:00.688247 env[1213]: time="2025-07-11T00:19:00.688133515Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1e4803c6-3b3c-4489-ab8a-bc88314a36bd,Namespace:default,Attempt:0,}" Jul 11 00:19:00.703239 systemd-networkd[1048]: veth3907f93e: Link UP Jul 11 00:19:00.706255 kernel: cni0: port 3(veth3907f93e) entered blocking state Jul 11 00:19:00.706335 kernel: cni0: port 3(veth3907f93e) entered disabled state Jul 11 00:19:00.707019 kernel: device veth3907f93e entered promiscuous mode Jul 11 00:19:00.715619 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 11 00:19:00.715747 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth3907f93e: link becomes ready Jul 11 00:19:00.715775 kernel: cni0: port 3(veth3907f93e) entered blocking state Jul 11 00:19:00.715798 kernel: cni0: port 3(veth3907f93e) entered forwarding state Jul 11 00:19:00.715899 systemd-networkd[1048]: veth3907f93e: Gained carrier Jul 11 00:19:00.720091 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016928), "name":"cbr0", "type":"bridge"} Jul 11 00:19:00.720091 env[1213]: delegateAdd: netconf sent to delegate plugin: Jul 11 00:19:00.732850 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-11T00:19:00.732767043Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 11 00:19:00.732850 env[1213]: time="2025-07-11T00:19:00.732817704Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 11 00:19:00.732850 env[1213]: time="2025-07-11T00:19:00.732828468Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 11 00:19:00.733172 kubelet[1413]: E0711 00:19:00.733118 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:00.733574 env[1213]: time="2025-07-11T00:19:00.733529996Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/4c5418163aa54a7d5018a1cf9d028e5884723d62b1ae739b2c29198acc14557c pid=2405 runtime=io.containerd.runc.v2 Jul 11 00:19:00.744176 systemd[1]: Started cri-containerd-4c5418163aa54a7d5018a1cf9d028e5884723d62b1ae739b2c29198acc14557c.scope. Jul 11 00:19:00.778636 systemd-resolved[1153]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 11 00:19:00.795060 env[1213]: time="2025-07-11T00:19:00.795006345Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1e4803c6-3b3c-4489-ab8a-bc88314a36bd,Namespace:default,Attempt:0,} returns sandbox id \"4c5418163aa54a7d5018a1cf9d028e5884723d62b1ae739b2c29198acc14557c\"" Jul 11 00:19:00.796518 env[1213]: time="2025-07-11T00:19:00.796468344Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 11 00:19:01.024885 env[1213]: time="2025-07-11T00:19:01.024763863Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:19:01.026370 env[1213]: time="2025-07-11T00:19:01.026322468Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:19:01.028776 env[1213]: time="2025-07-11T00:19:01.028744569Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:19:01.030434 env[1213]: time="2025-07-11T00:19:01.030394650Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 11 00:19:01.032058 env[1213]: time="2025-07-11T00:19:01.032021922Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 11 00:19:01.034248 env[1213]: time="2025-07-11T00:19:01.034215734Z" level=info msg="CreateContainer within sandbox \"4c5418163aa54a7d5018a1cf9d028e5884723d62b1ae739b2c29198acc14557c\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 11 00:19:01.047268 env[1213]: time="2025-07-11T00:19:01.047208779Z" level=info msg="CreateContainer within sandbox \"4c5418163aa54a7d5018a1cf9d028e5884723d62b1ae739b2c29198acc14557c\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"2f40d34b5e42f36b1292e97a84f08157e13c383131db7868047a78ef5004d571\"" Jul 11 00:19:01.048000 env[1213]: time="2025-07-11T00:19:01.047942584Z" level=info msg="StartContainer for \"2f40d34b5e42f36b1292e97a84f08157e13c383131db7868047a78ef5004d571\"" Jul 11 00:19:01.063566 systemd[1]: Started cri-containerd-2f40d34b5e42f36b1292e97a84f08157e13c383131db7868047a78ef5004d571.scope. Jul 11 00:19:01.118549 env[1213]: time="2025-07-11T00:19:01.118490701Z" level=info msg="StartContainer for \"2f40d34b5e42f36b1292e97a84f08157e13c383131db7868047a78ef5004d571\" returns successfully" Jul 11 00:19:01.733903 kubelet[1413]: E0711 00:19:01.733848 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:01.928840 systemd-networkd[1048]: veth3907f93e: Gained IPv6LL Jul 11 00:19:02.734934 kubelet[1413]: E0711 00:19:02.734830 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:03.735740 kubelet[1413]: E0711 00:19:03.735638 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:04.736672 kubelet[1413]: E0711 00:19:04.736626 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:05.737205 kubelet[1413]: E0711 00:19:05.737162 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:05.982825 update_engine[1206]: I0711 00:19:05.982769 1206 update_attempter.cc:509] Updating boot flags... Jul 11 00:19:06.737937 kubelet[1413]: E0711 00:19:06.737849 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:07.708872 kubelet[1413]: E0711 00:19:07.708836 1413 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:07.738395 kubelet[1413]: E0711 00:19:07.738344 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 11 00:19:08.738810 kubelet[1413]: E0711 00:19:08.738771 1413 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"