Jul 10 00:31:23.729721 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 10 00:31:23.729741 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 Wed Jul 9 23:19:15 -00 2025 Jul 10 00:31:23.729749 kernel: efi: EFI v2.70 by EDK II Jul 10 00:31:23.729755 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Jul 10 00:31:23.729760 kernel: random: crng init done Jul 10 00:31:23.729765 kernel: ACPI: Early table checksum verification disabled Jul 10 00:31:23.729772 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Jul 10 00:31:23.729778 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 10 00:31:23.729784 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729789 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729794 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729800 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729805 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729810 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729818 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729824 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729830 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 10 00:31:23.729835 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 10 00:31:23.729841 kernel: NUMA: Failed to initialise from firmware Jul 10 00:31:23.729847 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 10 00:31:23.729852 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Jul 10 00:31:23.729858 kernel: Zone ranges: Jul 10 00:31:23.729863 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 10 00:31:23.729874 kernel: DMA32 empty Jul 10 00:31:23.729880 kernel: Normal empty Jul 10 00:31:23.729885 kernel: Movable zone start for each node Jul 10 00:31:23.729891 kernel: Early memory node ranges Jul 10 00:31:23.729896 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Jul 10 00:31:23.729902 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Jul 10 00:31:23.729908 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Jul 10 00:31:23.729913 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Jul 10 00:31:23.729919 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Jul 10 00:31:23.729924 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Jul 10 00:31:23.729930 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Jul 10 00:31:23.729936 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 10 00:31:23.729943 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 10 00:31:23.729949 kernel: psci: probing for conduit method from ACPI. Jul 10 00:31:23.729954 kernel: psci: PSCIv1.1 detected in firmware. Jul 10 00:31:23.729960 kernel: psci: Using standard PSCI v0.2 function IDs Jul 10 00:31:23.729965 kernel: psci: Trusted OS migration not required Jul 10 00:31:23.729974 kernel: psci: SMC Calling Convention v1.1 Jul 10 00:31:23.729980 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 10 00:31:23.729987 kernel: ACPI: SRAT not present Jul 10 00:31:23.729994 kernel: percpu: Embedded 30 pages/cpu s82968 r8192 d31720 u122880 Jul 10 00:31:23.729999 kernel: pcpu-alloc: s82968 r8192 d31720 u122880 alloc=30*4096 Jul 10 00:31:23.730006 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 10 00:31:23.730012 kernel: Detected PIPT I-cache on CPU0 Jul 10 00:31:23.730018 kernel: CPU features: detected: GIC system register CPU interface Jul 10 00:31:23.730024 kernel: CPU features: detected: Hardware dirty bit management Jul 10 00:31:23.730030 kernel: CPU features: detected: Spectre-v4 Jul 10 00:31:23.730036 kernel: CPU features: detected: Spectre-BHB Jul 10 00:31:23.730043 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 10 00:31:23.730049 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 10 00:31:23.730055 kernel: CPU features: detected: ARM erratum 1418040 Jul 10 00:31:23.730061 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 10 00:31:23.730067 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Jul 10 00:31:23.730073 kernel: Policy zone: DMA Jul 10 00:31:23.730080 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=97626bbec4e8c603c151f40dbbae5fabba3cda417023e06335ea30183b36a27f Jul 10 00:31:23.730086 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 10 00:31:23.730093 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 10 00:31:23.730099 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 10 00:31:23.730105 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 10 00:31:23.730113 kernel: Memory: 2457340K/2572288K available (9792K kernel code, 2094K rwdata, 7588K rodata, 36416K init, 777K bss, 114948K reserved, 0K cma-reserved) Jul 10 00:31:23.730119 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 10 00:31:23.730125 kernel: trace event string verifier disabled Jul 10 00:31:23.730131 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 10 00:31:23.730138 kernel: rcu: RCU event tracing is enabled. Jul 10 00:31:23.730144 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 10 00:31:23.730150 kernel: Trampoline variant of Tasks RCU enabled. Jul 10 00:31:23.730156 kernel: Tracing variant of Tasks RCU enabled. Jul 10 00:31:23.730162 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 10 00:31:23.730168 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 10 00:31:23.730174 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 10 00:31:23.730181 kernel: GICv3: 256 SPIs implemented Jul 10 00:31:23.730187 kernel: GICv3: 0 Extended SPIs implemented Jul 10 00:31:23.730193 kernel: GICv3: Distributor has no Range Selector support Jul 10 00:31:23.730199 kernel: Root IRQ handler: gic_handle_irq Jul 10 00:31:23.730205 kernel: GICv3: 16 PPIs implemented Jul 10 00:31:23.730211 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 10 00:31:23.730217 kernel: ACPI: SRAT not present Jul 10 00:31:23.730223 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 10 00:31:23.730229 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Jul 10 00:31:23.730236 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Jul 10 00:31:23.730242 kernel: GICv3: using LPI property table @0x00000000400d0000 Jul 10 00:31:23.730248 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Jul 10 00:31:23.730255 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 10 00:31:23.730261 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 10 00:31:23.730268 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 10 00:31:23.730274 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 10 00:31:23.730280 kernel: arm-pv: using stolen time PV Jul 10 00:31:23.730286 kernel: Console: colour dummy device 80x25 Jul 10 00:31:23.730292 kernel: ACPI: Core revision 20210730 Jul 10 00:31:23.730299 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 10 00:31:23.730305 kernel: pid_max: default: 32768 minimum: 301 Jul 10 00:31:23.730311 kernel: LSM: Security Framework initializing Jul 10 00:31:23.730319 kernel: SELinux: Initializing. Jul 10 00:31:23.730325 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 10 00:31:23.730331 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 10 00:31:23.730337 kernel: rcu: Hierarchical SRCU implementation. Jul 10 00:31:23.730344 kernel: Platform MSI: ITS@0x8080000 domain created Jul 10 00:31:23.730350 kernel: PCI/MSI: ITS@0x8080000 domain created Jul 10 00:31:23.730356 kernel: Remapping and enabling EFI services. Jul 10 00:31:23.730417 kernel: smp: Bringing up secondary CPUs ... Jul 10 00:31:23.730424 kernel: Detected PIPT I-cache on CPU1 Jul 10 00:31:23.730432 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 10 00:31:23.730439 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Jul 10 00:31:23.730445 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 10 00:31:23.730452 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 10 00:31:23.730458 kernel: Detected PIPT I-cache on CPU2 Jul 10 00:31:23.730464 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 10 00:31:23.730471 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Jul 10 00:31:23.730477 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 10 00:31:23.730483 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 10 00:31:23.730489 kernel: Detected PIPT I-cache on CPU3 Jul 10 00:31:23.730497 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 10 00:31:23.730503 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Jul 10 00:31:23.730509 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 10 00:31:23.730515 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 10 00:31:23.730526 kernel: smp: Brought up 1 node, 4 CPUs Jul 10 00:31:23.730534 kernel: SMP: Total of 4 processors activated. Jul 10 00:31:23.730547 kernel: CPU features: detected: 32-bit EL0 Support Jul 10 00:31:23.730553 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 10 00:31:23.730560 kernel: CPU features: detected: Common not Private translations Jul 10 00:31:23.730567 kernel: CPU features: detected: CRC32 instructions Jul 10 00:31:23.730573 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 10 00:31:23.730580 kernel: CPU features: detected: LSE atomic instructions Jul 10 00:31:23.730588 kernel: CPU features: detected: Privileged Access Never Jul 10 00:31:23.730594 kernel: CPU features: detected: RAS Extension Support Jul 10 00:31:23.730601 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 10 00:31:23.730607 kernel: CPU: All CPU(s) started at EL1 Jul 10 00:31:23.730614 kernel: alternatives: patching kernel code Jul 10 00:31:23.730621 kernel: devtmpfs: initialized Jul 10 00:31:23.730628 kernel: KASLR enabled Jul 10 00:31:23.730635 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 10 00:31:23.730641 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 10 00:31:23.730648 kernel: pinctrl core: initialized pinctrl subsystem Jul 10 00:31:23.730654 kernel: SMBIOS 3.0.0 present. Jul 10 00:31:23.730661 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Jul 10 00:31:23.730667 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 10 00:31:23.730674 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 10 00:31:23.730682 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 10 00:31:23.730689 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 10 00:31:23.730695 kernel: audit: initializing netlink subsys (disabled) Jul 10 00:31:23.730702 kernel: audit: type=2000 audit(0.034:1): state=initialized audit_enabled=0 res=1 Jul 10 00:31:23.730708 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 10 00:31:23.730715 kernel: cpuidle: using governor menu Jul 10 00:31:23.730721 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 10 00:31:23.730728 kernel: ASID allocator initialised with 32768 entries Jul 10 00:31:23.730734 kernel: ACPI: bus type PCI registered Jul 10 00:31:23.730742 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 10 00:31:23.730748 kernel: Serial: AMBA PL011 UART driver Jul 10 00:31:23.730755 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Jul 10 00:31:23.730762 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Jul 10 00:31:23.730768 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Jul 10 00:31:23.730775 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Jul 10 00:31:23.730782 kernel: cryptd: max_cpu_qlen set to 1000 Jul 10 00:31:23.730788 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 10 00:31:23.730795 kernel: ACPI: Added _OSI(Module Device) Jul 10 00:31:23.730803 kernel: ACPI: Added _OSI(Processor Device) Jul 10 00:31:23.730809 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 10 00:31:23.730819 kernel: ACPI: Added _OSI(Linux-Dell-Video) Jul 10 00:31:23.730825 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Jul 10 00:31:23.730832 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Jul 10 00:31:23.730838 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 10 00:31:23.730845 kernel: ACPI: Interpreter enabled Jul 10 00:31:23.730851 kernel: ACPI: Using GIC for interrupt routing Jul 10 00:31:23.730858 kernel: ACPI: MCFG table detected, 1 entries Jul 10 00:31:23.730866 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 10 00:31:23.730873 kernel: printk: console [ttyAMA0] enabled Jul 10 00:31:23.730879 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 10 00:31:23.731011 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 10 00:31:23.731074 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 10 00:31:23.731133 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 10 00:31:23.731191 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 10 00:31:23.731250 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 10 00:31:23.731259 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 10 00:31:23.731266 kernel: PCI host bridge to bus 0000:00 Jul 10 00:31:23.731332 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 10 00:31:23.731413 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 10 00:31:23.731471 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 10 00:31:23.731523 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 10 00:31:23.731610 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Jul 10 00:31:23.731683 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Jul 10 00:31:23.731749 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Jul 10 00:31:23.731810 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Jul 10 00:31:23.731870 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Jul 10 00:31:23.731932 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Jul 10 00:31:23.731992 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Jul 10 00:31:23.732059 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Jul 10 00:31:23.732113 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 10 00:31:23.732165 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 10 00:31:23.732219 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 10 00:31:23.732228 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 10 00:31:23.732235 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 10 00:31:23.732241 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 10 00:31:23.732248 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 10 00:31:23.732257 kernel: iommu: Default domain type: Translated Jul 10 00:31:23.732263 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 10 00:31:23.732270 kernel: vgaarb: loaded Jul 10 00:31:23.732276 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 10 00:31:23.732283 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 10 00:31:23.732290 kernel: PTP clock support registered Jul 10 00:31:23.732296 kernel: Registered efivars operations Jul 10 00:31:23.732303 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 10 00:31:23.732309 kernel: VFS: Disk quotas dquot_6.6.0 Jul 10 00:31:23.732317 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 10 00:31:23.732324 kernel: pnp: PnP ACPI init Jul 10 00:31:23.732403 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 10 00:31:23.732414 kernel: pnp: PnP ACPI: found 1 devices Jul 10 00:31:23.732421 kernel: NET: Registered PF_INET protocol family Jul 10 00:31:23.732427 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 10 00:31:23.732438 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 10 00:31:23.732445 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 10 00:31:23.732453 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 10 00:31:23.732460 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Jul 10 00:31:23.732467 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 10 00:31:23.732474 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 10 00:31:23.732480 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 10 00:31:23.732487 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 10 00:31:23.732493 kernel: PCI: CLS 0 bytes, default 64 Jul 10 00:31:23.732500 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Jul 10 00:31:23.732507 kernel: kvm [1]: HYP mode not available Jul 10 00:31:23.732514 kernel: Initialise system trusted keyrings Jul 10 00:31:23.732521 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 10 00:31:23.732527 kernel: Key type asymmetric registered Jul 10 00:31:23.732534 kernel: Asymmetric key parser 'x509' registered Jul 10 00:31:23.732545 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 10 00:31:23.732552 kernel: io scheduler mq-deadline registered Jul 10 00:31:23.732558 kernel: io scheduler kyber registered Jul 10 00:31:23.732565 kernel: io scheduler bfq registered Jul 10 00:31:23.732572 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 10 00:31:23.732579 kernel: ACPI: button: Power Button [PWRB] Jul 10 00:31:23.732586 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 10 00:31:23.732651 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 10 00:31:23.732661 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 10 00:31:23.732667 kernel: thunder_xcv, ver 1.0 Jul 10 00:31:23.732674 kernel: thunder_bgx, ver 1.0 Jul 10 00:31:23.732680 kernel: nicpf, ver 1.0 Jul 10 00:31:23.732687 kernel: nicvf, ver 1.0 Jul 10 00:31:23.732753 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 10 00:31:23.732811 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-10T00:31:23 UTC (1752107483) Jul 10 00:31:23.732820 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 10 00:31:23.732827 kernel: NET: Registered PF_INET6 protocol family Jul 10 00:31:23.732833 kernel: Segment Routing with IPv6 Jul 10 00:31:23.732844 kernel: In-situ OAM (IOAM) with IPv6 Jul 10 00:31:23.732850 kernel: NET: Registered PF_PACKET protocol family Jul 10 00:31:23.732857 kernel: Key type dns_resolver registered Jul 10 00:31:23.732863 kernel: registered taskstats version 1 Jul 10 00:31:23.732872 kernel: Loading compiled-in X.509 certificates Jul 10 00:31:23.732878 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.186-flatcar: 9e274a0dc4fc3d34232d90d226b034c4fe0e3e22' Jul 10 00:31:23.732885 kernel: Key type .fscrypt registered Jul 10 00:31:23.732894 kernel: Key type fscrypt-provisioning registered Jul 10 00:31:23.732901 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 10 00:31:23.732907 kernel: ima: Allocated hash algorithm: sha1 Jul 10 00:31:23.732914 kernel: ima: No architecture policies found Jul 10 00:31:23.732920 kernel: clk: Disabling unused clocks Jul 10 00:31:23.732927 kernel: Freeing unused kernel memory: 36416K Jul 10 00:31:23.732935 kernel: Run /init as init process Jul 10 00:31:23.732941 kernel: with arguments: Jul 10 00:31:23.732948 kernel: /init Jul 10 00:31:23.732954 kernel: with environment: Jul 10 00:31:23.732961 kernel: HOME=/ Jul 10 00:31:23.732968 kernel: TERM=linux Jul 10 00:31:23.732974 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 10 00:31:23.732982 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 10 00:31:23.732992 systemd[1]: Detected virtualization kvm. Jul 10 00:31:23.732999 systemd[1]: Detected architecture arm64. Jul 10 00:31:23.733006 systemd[1]: Running in initrd. Jul 10 00:31:23.733013 systemd[1]: No hostname configured, using default hostname. Jul 10 00:31:23.733020 systemd[1]: Hostname set to . Jul 10 00:31:23.733027 systemd[1]: Initializing machine ID from VM UUID. Jul 10 00:31:23.733046 systemd[1]: Queued start job for default target initrd.target. Jul 10 00:31:23.733053 systemd[1]: Started systemd-ask-password-console.path. Jul 10 00:31:23.733061 systemd[1]: Reached target cryptsetup.target. Jul 10 00:31:23.733068 systemd[1]: Reached target paths.target. Jul 10 00:31:23.733075 systemd[1]: Reached target slices.target. Jul 10 00:31:23.733082 systemd[1]: Reached target swap.target. Jul 10 00:31:23.733089 systemd[1]: Reached target timers.target. Jul 10 00:31:23.733096 systemd[1]: Listening on iscsid.socket. Jul 10 00:31:23.733103 systemd[1]: Listening on iscsiuio.socket. Jul 10 00:31:23.733111 systemd[1]: Listening on systemd-journald-audit.socket. Jul 10 00:31:23.733119 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 10 00:31:23.733126 systemd[1]: Listening on systemd-journald.socket. Jul 10 00:31:23.733133 systemd[1]: Listening on systemd-networkd.socket. Jul 10 00:31:23.733140 systemd[1]: Listening on systemd-udevd-control.socket. Jul 10 00:31:23.733147 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 10 00:31:23.733154 systemd[1]: Reached target sockets.target. Jul 10 00:31:23.733161 systemd[1]: Starting kmod-static-nodes.service... Jul 10 00:31:23.733168 systemd[1]: Finished network-cleanup.service. Jul 10 00:31:23.733175 systemd[1]: Starting systemd-fsck-usr.service... Jul 10 00:31:23.733183 systemd[1]: Starting systemd-journald.service... Jul 10 00:31:23.733190 systemd[1]: Starting systemd-modules-load.service... Jul 10 00:31:23.733197 systemd[1]: Starting systemd-resolved.service... Jul 10 00:31:23.733204 systemd[1]: Starting systemd-vconsole-setup.service... Jul 10 00:31:23.733211 systemd[1]: Finished kmod-static-nodes.service. Jul 10 00:31:23.733218 systemd[1]: Finished systemd-fsck-usr.service. Jul 10 00:31:23.733225 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 10 00:31:23.733231 systemd[1]: Finished systemd-vconsole-setup.service. Jul 10 00:31:23.733240 systemd[1]: Starting dracut-cmdline-ask.service... Jul 10 00:31:23.733250 systemd-journald[289]: Journal started Jul 10 00:31:23.733291 systemd-journald[289]: Runtime Journal (/run/log/journal/7ab93245feb74bf8bc9ce22ce2127415) is 6.0M, max 48.7M, 42.6M free. Jul 10 00:31:23.724797 systemd-modules-load[290]: Inserted module 'overlay' Jul 10 00:31:23.739402 systemd[1]: Started systemd-journald.service. Jul 10 00:31:23.739441 kernel: audit: type=1130 audit(1752107483.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 10 00:31:23.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 10 00:31:23.737612 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 10 00:31:23.745703 kernel: audit: type=1130 audit(1752107483.738: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 10 00:31:23.738000 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 10 00:31:23.748652 systemd[1]: Finished dracut-cmdline-ask.service. Jul 10 00:31:23.754654 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 10 00:31:23.754673 kernel: audit: type=1130 audit(1752107483.750:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 10 00:31:23.750000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 10 00:31:23.751814 systemd[1]: Starting dracut-cmdline.service... Jul 10 00:31:23.751818 systemd-resolved[291]: Positive Trust Anchors: Jul 10 00:31:23.758099 kernel: Bridge firewalling registered Jul 10 00:31:23.751825 systemd-resolved[291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 10 00:31:23.763682 kernel: audit: type=1130 audit(1752107483.758:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 10 00:31:23.758000 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 10 00:31:23.751853 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 10 00:31:23.756217 systemd-resolved[291]: Defaulting to hostname 'linux'. Jul 10 00:31:23.769621 dracut-cmdline[308]: dracut-dracut-053 Jul 10 00:31:23.769621 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=97626bbec4e8c603c151f40dbbae5fabba3cda417023e06335ea30183b36a27f Jul 10 00:31:23.775122 kernel: SCSI subsystem initialized Jul 10 00:31:23.756809 systemd-modules-load[290]: Inserted module 'br_netfilter' Jul 10 00:31:23.757515 systemd[1]: Started systemd-resolved.service. Jul 10 00:31:23.759020 systemd[1]: Reached target nss-lookup.target. Jul 10 00:31:23.779541 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 10 00:31:23.779568 kernel: device-mapper: uevent: version 1.0.3 Jul 10 00:31:23.780651 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Jul 10 00:31:23.783023 systemd-modules-load[290]: Inserted module 'dm_multipath' Jul 10 00:31:23.783907 systemd[1]: Finished systemd-modules-load.service. Jul 10 00:31:23.784000 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 10 00:31:23.785581 systemd[1]: Starting systemd-sysctl.service... Jul 10 00:31:23.788705 kernel: audit: type=1130 audit(1752107483.784: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 10 00:31:23.794137 systemd[1]: Finished systemd-sysctl.service. Jul 10 00:31:23.795000 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 10 00:31:23.798400 kernel: audit: type=1130 audit(1752107483.795: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 10 00:31:23.828404 kernel: Loading iSCSI transport class v2.0-870. Jul 10 00:31:23.840402 kernel: iscsi: registered transport (tcp) Jul 10 00:31:23.855402 kernel: iscsi: registered transport (qla4xxx) Jul 10 00:31:23.855449 kernel: QLogic iSCSI HBA Driver Jul 10 00:31:23.897101 systemd[1]: Finished dracut-cmdline.service. Jul 10 00:31:23.898000 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 10 00:31:23.899666 systemd[1]: Starting dracut-pre-udev.service... Jul 10 00:31:23.902628 kernel: audit: type=1130 audit(1752107483.898: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 10 00:31:23.945401 kernel: raid6: neonx8 gen() 13722 MB/s Jul 10 00:31:23.962383 kernel: raid6: neonx8 xor() 10759 MB/s Jul 10 00:31:23.979390 kernel: raid6: neonx4 gen() 13536 MB/s Jul 10 00:31:23.996381 kernel: raid6: neonx4 xor() 11024 MB/s Jul 10 00:31:24.013392 kernel: raid6: neonx2 gen() 12878 MB/s Jul 10 00:31:24.030385 kernel: raid6: neonx2 xor() 10392 MB/s Jul 10 00:31:24.047392 kernel: raid6: neonx1 gen() 10555 MB/s Jul 10 00:31:24.064385 kernel: raid6: neonx1 xor() 8768 MB/s Jul 10 00:31:24.081386 kernel: raid6: int64x8 gen() 6262 MB/s Jul 10 00:31:24.098388 kernel: raid6: int64x8 xor() 3533 MB/s Jul 10 00:31:24.115389 kernel: raid6: int64x4 gen() 7195 MB/s Jul 10 00:31:24.132384 kernel: raid6: int64x4 xor() 3847 MB/s Jul 10 00:31:24.149387 kernel: raid6: int64x2 gen() 6142 MB/s Jul 10 00:31:24.166389 kernel: raid6: int64x2 xor() 3314 MB/s Jul 10 00:31:24.183390 kernel: raid6: int64x1 gen() 5025 MB/s Jul 10 00:31:24.200486 kernel: raid6: int64x1 xor() 2639 MB/s Jul 10 00:31:24.200512 kernel: raid6: using algorithm neonx8 gen() 13722 MB/s Jul 10 00:31:24.200521 kernel: raid6: .... xor() 10759 MB/s, rmw enabled Jul 10 00:31:24.201578 kernel: raid6: using neon recovery algorithm Jul 10 00:31:24.213689 kernel: xor: measuring software checksum speed Jul 10 00:31:24.213727 kernel: 8regs : 17188 MB/sec Jul 10 00:31:24.214387 kernel: 32regs : 20691 MB/sec Jul 10 00:31:24.215635 kernel: arm64_neon : 24579 MB/sec Jul 10 00:31:24.215662 kernel: xor: using function: arm64_neon (24579 MB/sec) Jul 10 00:31:24.270402 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Jul 10 00:31:24.283210 systemd[1]: Finished dracut-pre-udev.service. Jul 10 00:31:24.283000 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 10 00:31:24.287000 audit: BPF prog-id=7 op=LOAD Jul 10 00:31:24.290891 systemd[1]: Starting systemd-udevd.service... Jul 10 00:31:24.292322 kernel: audit: type=1130 audit(1752107484.283: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 10 00:31:24.292344 kernel: audit: type=1334 audit(1752107484.287:10): prog-id=7 op=LOAD Jul 10 00:31:24.289000 audit: BPF prog-id=8 op=LOAD Jul 10 00:31:24.304694 systemd-udevd[491]: Using default interface naming scheme 'v252'. Jul 10 00:31:24.308034 systemd[1]: Started systemd-udevd.service. Jul 10 00:31:24.311090 systemd[1]: Starting dracut-pre-trigger.service... Jul 10 00:31:24.308000 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 10 00:31:24.326237 dracut-pre-trigger[507]: rd.md=0: removing MD RAID activation Jul 10 00:31:24.362113 systemd[1]: Finished dracut-pre-trigger.service. Jul 10 00:31:24.362000 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 10 00:31:24.367005 systemd[1]: Starting systemd-udev-trigger.service... Jul 10 00:31:24.400405 systemd[1]: Finished systemd-udev-trigger.service. Jul 10 00:31:24.405000 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 10 00:31:24.446600 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 10 00:31:24.452127 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 10 00:31:24.452144 kernel: GPT:9289727 != 19775487 Jul 10 00:31:24.452161 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 10 00:31:24.452170 kernel: GPT:9289727 != 19775487 Jul 10 00:31:24.452179 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 10 00:31:24.452187 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 10 00:31:24.473395 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by (udev-worker) (560) Jul 10 00:31:24.475830 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Jul 10 00:31:24.476976 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Jul 10 00:31:24.481984 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Jul 10 00:31:24.485800 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Jul 10 00:31:24.489661 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 10 00:31:24.491429 systemd[1]: Starting disk-uuid.service... Jul 10 00:31:24.497168 disk-uuid[568]: Primary Header is updated. Jul 10 00:31:24.497168 disk-uuid[568]: Secondary Entries is updated. Jul 10 00:31:24.497168 disk-uuid[568]: Secondary Header is updated. Jul 10 00:31:24.502393 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 10 00:31:24.507386 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 10 00:31:24.509379 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 10 00:31:25.512679 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 10 00:31:25.512744 disk-uuid[569]: The operation has completed successfully. Jul 10 00:31:25.548950 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 10 00:31:25.549000 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 10 00:31:25.549000 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 10 00:31:25.549060 systemd[1]: Finished disk-uuid.service. Jul 10 00:31:25.550798 systemd[1]: Starting verity-setup.service... Jul 10 00:31:25.565399 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jul 10 00:31:25.599347 systemd[1]: Found device dev-mapper-usr.device. Jul 10 00:31:25.602055 systemd[1]: Mounting sysusr-usr.mount... Jul 10 00:31:25.605276 systemd[1]: Finished verity-setup.service. Jul 10 00:31:25.606000 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 10 00:31:25.652318 systemd[1]: Mounted sysusr-usr.mount. Jul 10 00:31:25.653668 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Jul 10 00:31:25.653188 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Jul 10 00:31:25.653915 systemd[1]: Starting ignition-setup.service... Jul 10 00:31:25.656142 systemd[1]: Starting parse-ip-for-networkd.service... Jul 10 00:31:25.670703 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 10 00:31:25.670747 kernel: BTRFS info (device vda6): using free space tree Jul 10 00:31:25.670758 kernel: BTRFS info (device vda6): has skinny extents Jul 10 00:31:25.681487 systemd[1]: mnt-oem.mount: Deactivated successfully. Jul 10 00:31:25.687502 systemd[1]: Finished ignition-setup.service. Jul 10 00:31:25.687000 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 10 00:31:25.689128 systemd[1]: Starting ignition-fetch-offline.service... Jul 10 00:31:25.747581 systemd[1]: Finished parse-ip-for-networkd.service. Jul 10 00:31:25.748000 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 10 00:31:25.748000 audit: BPF prog-id=9 op=LOAD Jul 10 00:31:25.749796 systemd[1]: Starting systemd-networkd.service... Jul 10 00:31:25.774509 systemd-networkd[745]: lo: Link UP Jul 10 00:31:25.774522 systemd-networkd[745]: lo: Gained carrier Jul 10 00:31:25.776000 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 10 00:31:25.774937 systemd-networkd[745]: Enumeration completed Jul 10 00:31:25.775071 systemd[1]: Started systemd-networkd.service. Jul 10 00:31:25.775127 systemd-networkd[745]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 10 00:31:25.776303 systemd-networkd[745]: eth0: Link UP Jul 10 00:31:25.776307 systemd-networkd[745]: eth0: Gained carrier Jul 10 00:31:25.776467 systemd[1]: Reached target network.target. Jul 10 00:31:25.778627 systemd[1]: Starting iscsiuio.service... Jul 10 00:31:25.785672 ignition[672]: Ignition 2.14.0 Jul 10 00:31:25.785681 ignition[672]: Stage: fetch-offline Jul 10 00:31:25.785720 ignition[672]: no configs at "/usr/lib/ignition/base.d" Jul 10 00:31:25.785728 ignition[672]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 10 00:31:25.785853 ignition[672]: parsed url from cmdline: "" Jul 10 00:31:25.785857 ignition[672]: no config URL provided Jul 10 00:31:25.785861 ignition[672]: reading system config file "/usr/lib/ignition/user.ign" Jul 10 00:31:25.792000 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 10 00:31:25.785871 ignition[672]: no config at "/usr/lib/ignition/user.ign" Jul 10 00:31:25.792105 systemd[1]: Started iscsiuio.service. Jul 10 00:31:25.785890 ignition[672]: op(1): [started] loading QEMU firmware config module Jul 10 00:31:25.793870 systemd[1]: Starting iscsid.service... Jul 10 00:31:25.785894 ignition[672]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 10 00:31:25.794675 ignition[672]: op(1): [finished] loading QEMU firmware config module Jul 10 00:31:25.798403 iscsid[752]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Jul 10 00:31:25.798403 iscsid[752]: 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 10 00:31:25.798403 iscsid[752]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Jul 10 00:31:25.798403 iscsid[752]: If using hardware iscsi like qla4xxx this message can be ignored. Jul 10 00:31:25.798403 iscsid[752]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Jul 10 00:31:25.798403 iscsid[752]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Jul 10 00:31:25.805000 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 10 00:31:25.800401 systemd[1]: Started iscsid.service. Jul 10 00:31:25.804687 systemd-networkd[745]: eth0: DHCPv4 address 10.0.0.58/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 10 00:31:25.806759 systemd[1]: Starting dracut-initqueue.service... Jul 10 00:31:25.814580 ignition[672]: parsing config with SHA512: c19e08f473952103231c84f5b5cf1b12e422592875ff7cd906129363ee4643bb80cb5fa54b077e0123653d5b25dfccecd8e8b97970020c2f43ea6abd1322a9b8 Jul 10 00:31:25.817033 systemd[1]: Finished dracut-initqueue.service. Jul 10 00:31:25.817000 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 10 00:31:25.817979 systemd[1]: Reached target remote-fs-pre.target. Jul 10 00:31:25.819541 systemd[1]: Reached target remote-cryptsetup.target. Jul 10 00:31:25.821137 systemd[1]: Reached target remote-fs.target. Jul 10 00:31:25.823418 systemd[1]: Starting dracut-pre-mount.service... Jul 10 00:31:25.824603 ignition[672]: fetch-offline: fetch-offline passed Jul 10 00:31:25.824287 unknown[672]: fetched base config from "system" Jul 10 00:31:25.824654 ignition[672]: Ignition finished successfully Jul 10 00:31:25.826000 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 10 00:31:25.824293 unknown[672]: fetched user config from "qemu" Jul 10 00:31:25.826099 systemd[1]: Finished ignition-fetch-offline.service. Jul 10 00:31:25.827490 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 10 00:31:25.828194 systemd[1]: Starting ignition-kargs.service... Jul 10 00:31:25.832582 systemd[1]: Finished dracut-pre-mount.service. Jul 10 00:31:25.833000 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 10 00:31:25.836724 ignition[764]: Ignition 2.14.0 Jul 10 00:31:25.836735 ignition[764]: Stage: kargs Jul 10 00:31:25.836824 ignition[764]: no configs at "/usr/lib/ignition/base.d" Jul 10 00:31:25.836833 ignition[764]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 10 00:31:25.839155 ignition[764]: kargs: kargs passed Jul 10 00:31:25.840980 systemd[1]: Finished ignition-kargs.service. Jul 10 00:31:25.841000 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 10 00:31:25.839203 ignition[764]: Ignition finished successfully Jul 10 00:31:25.842977 systemd[1]: Starting ignition-disks.service... Jul 10 00:31:25.849632 ignition[773]: Ignition 2.14.0 Jul 10 00:31:25.849641 ignition[773]: Stage: disks Jul 10 00:31:25.849730 ignition[773]: no configs at "/usr/lib/ignition/base.d" Jul 10 00:31:25.851101 systemd[1]: Finished ignition-disks.service. Jul 10 00:31:25.852000 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 10 00:31:25.849739 ignition[773]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 10 00:31:25.852694 systemd[1]: Reached target initrd-root-device.target. Jul 10 00:31:25.850330 ignition[773]: disks: disks passed Jul 10 00:31:25.853958 systemd[1]: Reached target local-fs-pre.target. Jul 10 00:31:25.850382 ignition[773]: Ignition finished successfully Jul 10 00:31:25.855536 systemd[1]: Reached target local-fs.target. Jul 10 00:31:25.856884 systemd[1]: Reached target sysinit.target. Jul 10 00:31:25.858046 systemd[1]: Reached target basic.target. Jul 10 00:31:25.860149 systemd[1]: Starting systemd-fsck-root.service... Jul 10 00:31:25.871651 systemd-fsck[781]: ROOT: clean, 619/553520 files, 56022/553472 blocks Jul 10 00:31:25.880105 systemd[1]: Finished systemd-fsck-root.service. Jul 10 00:31:25.880000 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 10 00:31:25.882683 systemd[1]: Mounting sysroot.mount... Jul 10 00:31:25.892292 systemd[1]: Mounted sysroot.mount. Jul 10 00:31:25.893565 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Jul 10 00:31:25.893081 systemd[1]: Reached target initrd-root-fs.target. Jul 10 00:31:25.895269 systemd[1]: Mounting sysroot-usr.mount... Jul 10 00:31:25.896173 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Jul 10 00:31:25.896213 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 10 00:31:25.896237 systemd[1]: Reached target ignition-diskful.target. Jul 10 00:31:25.898102 systemd[1]: Mounted sysroot-usr.mount. Jul 10 00:31:25.900048 systemd[1]: Starting initrd-setup-root.service... Jul 10 00:31:25.904465 initrd-setup-root[791]: cut: /sysroot/etc/passwd: No such file or directory Jul 10 00:31:25.908348 initrd-setup-root[799]: cut: /sysroot/etc/group: No such file or directory Jul 10 00:31:25.911462 initrd-setup-root[807]: cut: /sysroot/etc/shadow: No such file or directory Jul 10 00:31:25.916015 initrd-setup-root[815]: cut: /sysroot/etc/gshadow: No such file or directory Jul 10 00:31:25.943325 systemd[1]: Finished initrd-setup-root.service. Jul 10 00:31:25.943000 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 10 00:31:25.944966 systemd[1]: Starting ignition-mount.service... Jul 10 00:31:25.946314 systemd[1]: Starting sysroot-boot.service... Jul 10 00:31:25.950159 bash[832]: umount: /sysroot/usr/share/oem: not mounted. Jul 10 00:31:25.957625 ignition[833]: INFO : Ignition 2.14.0 Jul 10 00:31:25.957625 ignition[833]: INFO : Stage: mount Jul 10 00:31:25.959120 ignition[833]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 10 00:31:25.959120 ignition[833]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 10 00:31:25.959120 ignition[833]: INFO : mount: mount passed Jul 10 00:31:25.959120 ignition[833]: INFO : Ignition finished successfully Jul 10 00:31:25.960000 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 10 00:31:25.959908 systemd[1]: Finished ignition-mount.service. Jul 10 00:31:25.966255 systemd[1]: Finished sysroot-boot.service. Jul 10 00:31:25.966000 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 10 00:31:26.612754 systemd[1]: Mounting sysroot-usr-share-oem.mount... Jul 10 00:31:26.620092 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (844) Jul 10 00:31:26.620137 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 10 00:31:26.620147 kernel: BTRFS info (device vda6): using free space tree Jul 10 00:31:26.620732 kernel: BTRFS info (device vda6): has skinny extents Jul 10 00:31:26.625754 systemd[1]: Mounted sysroot-usr-share-oem.mount. Jul 10 00:31:26.627382 systemd[1]: Starting ignition-files.service... Jul 10 00:31:26.641579 ignition[864]: INFO : Ignition 2.14.0 Jul 10 00:31:26.641579 ignition[864]: INFO : Stage: files Jul 10 00:31:26.643345 ignition[864]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 10 00:31:26.643345 ignition[864]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 10 00:31:26.643345 ignition[864]: DEBUG : files: compiled without relabeling support, skipping Jul 10 00:31:26.646652 ignition[864]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 10 00:31:26.646652 ignition[864]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 10 00:31:26.650758 ignition[864]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 10 00:31:26.652094 ignition[864]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 10 00:31:26.653600 unknown[864]: wrote ssh authorized keys file for user: core Jul 10 00:31:26.654645 ignition[864]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 10 00:31:26.654645 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 10 00:31:26.654645 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 10 00:31:26.659412 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 10 00:31:26.659412 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 10 00:31:26.659412 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 10 00:31:26.659412 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 10 00:31:26.659412 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 10 00:31:26.659412 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Jul 10 00:31:27.141808 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 10 00:31:27.232892 systemd-networkd[745]: eth0: Gained IPv6LL Jul 10 00:31:27.657729 ignition[864]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 10 00:31:27.657729 ignition[864]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Jul 10 00:31:27.661415 ignition[864]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 10 00:31:27.661415 ignition[864]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 10 00:31:27.661415 ignition[864]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Jul 10 00:31:27.661415 ignition[864]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Jul 10 00:31:27.661415 ignition[864]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 10 00:31:27.715027 ignition[864]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 10 00:31:27.717532 ignition[864]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Jul 10 00:31:27.717532 ignition[864]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 10 00:31:27.717532 ignition[864]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 10 00:31:27.717532 ignition[864]: INFO : files: files passed Jul 10 00:31:27.717532 ignition[864]: INFO : Ignition finished successfully Jul 10 00:31:27.718000 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 10 00:31:27.717521 systemd[1]: Finished ignition-files.service. Jul 10 00:31:27.726000 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 10 00:31:27.726000 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 10 00:31:27.719273 systemd[1]: Starting initrd-setup-root-after-ignition.service... Jul 10 00:31:27.720756 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Jul 10 00:31:27.729000 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 10 00:31:27.731175 initrd-setup-root-after-ignition[889]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Jul 10 00:31:27.721444 systemd[1]: Starting ignition-quench.service... Jul 10 00:31:27.734583 initrd-setup-root-after-ignition[892]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 10 00:31:27.725754 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 10 00:31:27.725839 systemd[1]: Finished ignition-quench.service. Jul 10 00:31:27.728091 systemd[1]: Finished initrd-setup-root-after-ignition.service. Jul 10 00:31:27.729556 systemd[1]: Reached target ignition-complete.target. Jul 10 00:31:27.732627 systemd[1]: Starting initrd-parse-etc.service... Jul 10 00:31:27.745321 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 10 00:31:27.745424 systemd[1]: Finished initrd-parse-etc.service. Jul 10 00:31:27.747000 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 10 00:31:27.747000 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 10 00:31:27.747284 systemd[1]: Reached target initrd-fs.target. Jul 10 00:31:27.748811 systemd[1]: Reached target initrd.target. Jul 10 00:31:27.750121 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Jul 10 00:31:27.750943 systemd[1]: Starting dracut-pre-pivot.service... Jul 10 00:31:27.764350 systemd[1]: Finished dracut-pre-pivot.service. Jul 10 00:31:27.764000 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 10 00:31:27.766162 systemd[1]: Starting initrd-cleanup.service... Jul 10 00:31:27.779952 systemd[1]: Stopped target nss-lookup.target. Jul 10 00:31:27.780895 systemd[1]: Stopped target remote-cryptsetup.target. Jul 10 00:31:27.782444 systemd[1]: Stopped target timers.target. Jul 10 00:31:27.783914 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 10 00:31:27.784000 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 10 00:31:27.784033 systemd[1]: Stopped dracut-pre-pivot.service. Jul 10 00:31:27.785422 systemd[1]: Stopped target initrd.target. Jul 10 00:31:27.786879 systemd[1]: Stopped target basic.target. Jul 10 00:31:27.788199 systemd[1]: Stopped target ignition-complete.target. Jul 10 00:31:27.789589 systemd[1]: Stopped target ignition-diskful.target. Jul 10 00:31:27.790943 systemd[1]: Stopped target initrd-root-device.target. Jul 10 00:31:27.792459 systemd[1]: Stopped target remote-fs.target. Jul 10 00:31:27.793871 systemd[1]: Stopped target remote-fs-pre.target. Jul 10 00:31:27.795311 systemd[1]: Stopped target sysinit.target. Jul 10 00:31:27.796626 systemd[1]: Stopped target local-fs.target. Jul 10 00:31:27.797974 systemd[1]: Stopped target local-fs-pre.target. Jul 10 00:31:27.799301 systemd[1]: Stopped target swap.target. Jul 10 00:31:27.801000 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 10 00:31:27.800589 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 10 00:31:27.800714 systemd[1]: Stopped dracut-pre-mount.service. Jul 10 00:31:27.804000 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 10 00:31:27.802044 systemd[1]: Stopped target cryptsetup.target. Jul 10 00:31:27.806000 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 10 00:31:27.803232 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 10 00:31:27.803334 systemd[1]: Stopped dracut-initqueue.service. Jul 10 00:31:27.804835 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 10 00:31:27.804928 systemd[1]: Stopped ignition-fetch-offline.service. Jul 10 00:31:27.806284 systemd[1]: Stopped target paths.target. Jul 10 00:31:27.807522 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 10 00:31:27.811428 systemd[1]: Stopped systemd-ask-password-console.path. Jul 10 00:31:27.812720 systemd[1]: Stopped target slices.target. Jul 10 00:31:27.814268 systemd[1]: Stopped target sockets.target. Jul 10 00:31:27.815718 systemd[1]: iscsid.socket: Deactivated successfully. Jul 10 00:31:27.818000 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 10 00:31:27.815795 systemd[1]: Closed iscsid.socket. Jul 10 00:31:27.819000 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 10 00:31:27.816994 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 10 00:31:27.817102 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Jul 10 00:31:27.818538 systemd[1]: ignition-files.service: Deactivated successfully. Jul 10 00:31:27.818629 systemd[1]: Stopped ignition-files.service. Jul 10 00:31:27.820835 systemd[1]: Stopping ignition-mount.service... Jul 10 00:31:27.826000 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 10 00:31:27.822867 systemd[1]: Stopping iscsiuio.service... Jul 10 00:31:27.825181 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 10 00:31:27.825309 systemd[1]: Stopped kmod-static-nodes.service. Jul 10 00:31:27.829000 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 10 00:31:27.827763 systemd[1]: Stopping sysroot-boot.service... Jul 10 00:31:27.831000 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 10 00:31:27.828704 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 10 00:31:27.828828 systemd[1]: Stopped systemd-udev-trigger.service. Jul 10 00:31:27.833000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 10 00:31:27.830493 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 10 00:31:27.830604 systemd[1]: Stopped dracut-pre-trigger.service. Jul 10 00:31:27.837000 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 10 00:31:27.837000 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 10 00:31:27.838730 ignition[905]: INFO : Ignition 2.14.0 Jul 10 00:31:27.838730 ignition[905]: INFO : Stage: umount Jul 10 00:31:27.838730 ignition[905]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 10 00:31:27.838730 ignition[905]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 10 00:31:27.838730 ignition[905]: INFO : umount: umount passed Jul 10 00:31:27.838730 ignition[905]: INFO : Ignition finished successfully Jul 10 00:31:27.843000 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 10 00:31:27.833410 systemd[1]: iscsiuio.service: Deactivated successfully. Jul 10 00:31:27.846000 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 10 00:31:27.833564 systemd[1]: Stopped iscsiuio.service. Jul 10 00:31:27.848000 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 10 00:31:27.834767 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 10 00:31:27.849000 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 10 00:31:27.834831 systemd[1]: Closed iscsiuio.socket. Jul 10 00:31:27.836179 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 10 00:31:27.836286 systemd[1]: Finished initrd-cleanup.service. Jul 10 00:31:27.841219 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 10 00:31:27.841661 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 10 00:31:27.841753 systemd[1]: Stopped ignition-mount.service. Jul 10 00:31:27.844330 systemd[1]: Stopped target network.target. Jul 10 00:31:27.845694 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 10 00:31:27.845749 systemd[1]: Stopped ignition-disks.service. Jul 10 00:31:27.862000 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 10 00:31:27.847286 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 10 00:31:27.864000 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 10 00:31:27.847328 systemd[1]: Stopped ignition-kargs.service. Jul 10 00:31:27.848892 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 10 00:31:27.848931 systemd[1]: Stopped ignition-setup.service. Jul 10 00:31:27.850317 systemd[1]: Stopping systemd-networkd.service... Jul 10 00:31:27.870000 audit: BPF prog-id=6 op=UNLOAD Jul 10 00:31:27.870000 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 10 00:31:27.852111 systemd[1]: Stopping systemd-resolved.service... Jul 10 00:31:27.872000 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 10 00:31:27.858787 systemd-networkd[745]: eth0: DHCPv6 lease lost Jul 10 00:31:27.873000 audit: BPF prog-id=9 op=UNLOAD Jul 10 00:31:27.874000 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 10 00:31:27.859870 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 10 00:31:27.859979 systemd[1]: Stopped systemd-networkd.service. Jul 10 00:31:27.863705 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 10 00:31:27.863794 systemd[1]: Stopped systemd-resolved.service. Jul 10 00:31:27.865191 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 10 00:31:27.865225 systemd[1]: Closed systemd-networkd.socket. Jul 10 00:31:27.883000 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 10 00:31:27.867564 systemd[1]: Stopping network-cleanup.service... Jul 10 00:31:27.884000 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 10 00:31:27.869244 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 10 00:31:27.886000 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 10 00:31:27.869309 systemd[1]: Stopped parse-ip-for-networkd.service. Jul 10 00:31:27.871339 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 10 00:31:27.889000 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 10 00:31:27.871401 systemd[1]: Stopped systemd-sysctl.service. Jul 10 00:31:27.873695 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 10 00:31:27.873742 systemd[1]: Stopped systemd-modules-load.service. Jul 10 00:31:27.893000 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 10 00:31:27.874757 systemd[1]: Stopping systemd-udevd.service... Jul 10 00:31:27.895000 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 10 00:31:27.879086 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 10 00:31:27.896000 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 10 00:31:27.881926 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 10 00:31:27.882016 systemd[1]: Stopped network-cleanup.service. Jul 10 00:31:27.883964 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 10 00:31:27.884045 systemd[1]: Stopped sysroot-boot.service. Jul 10 00:31:27.885220 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 10 00:31:27.885258 systemd[1]: Stopped initrd-setup-root.service. Jul 10 00:31:27.900000 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 10 00:31:27.888015 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 10 00:31:27.905000 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 10 00:31:27.905000 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 10 00:31:27.888131 systemd[1]: Stopped systemd-udevd.service. Jul 10 00:31:27.889774 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 10 00:31:27.889809 systemd[1]: Closed systemd-udevd-control.socket. Jul 10 00:31:27.891202 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 10 00:31:27.891238 systemd[1]: Closed systemd-udevd-kernel.socket. Jul 10 00:31:27.892706 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 10 00:31:27.892759 systemd[1]: Stopped dracut-pre-udev.service. Jul 10 00:31:27.894385 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 10 00:31:27.894426 systemd[1]: Stopped dracut-cmdline.service. Jul 10 00:31:27.895879 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 10 00:31:27.895919 systemd[1]: Stopped dracut-cmdline-ask.service. Jul 10 00:31:27.898094 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Jul 10 00:31:27.899204 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 10 00:31:27.899266 systemd[1]: Stopped systemd-vconsole-setup.service. Jul 10 00:31:27.904371 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 10 00:31:27.904480 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Jul 10 00:31:27.905901 systemd[1]: Reached target initrd-switch-root.target. Jul 10 00:31:27.908210 systemd[1]: Starting initrd-switch-root.service... Jul 10 00:31:27.915235 systemd[1]: Switching root. Jul 10 00:31:27.930953 iscsid[752]: iscsid shutting down. Jul 10 00:31:27.931682 systemd-journald[289]: Received SIGTERM from PID 1 (systemd). Jul 10 00:31:27.931730 systemd-journald[289]: Journal stopped Jul 10 00:31:30.033841 kernel: SELinux: Class mctp_socket not defined in policy. Jul 10 00:31:30.033905 kernel: SELinux: Class anon_inode not defined in policy. Jul 10 00:31:30.033918 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 10 00:31:30.033930 kernel: SELinux: policy capability network_peer_controls=1 Jul 10 00:31:30.033944 kernel: SELinux: policy capability open_perms=1 Jul 10 00:31:30.033955 kernel: SELinux: policy capability extended_socket_class=1 Jul 10 00:31:30.033965 kernel: SELinux: policy capability always_check_network=0 Jul 10 00:31:30.033980 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 10 00:31:30.033994 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 10 00:31:30.034004 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 10 00:31:30.034014 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 10 00:31:30.034030 systemd[1]: Successfully loaded SELinux policy in 37.162ms. Jul 10 00:31:30.034043 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.213ms. Jul 10 00:31:30.034060 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 10 00:31:30.034073 systemd[1]: Detected virtualization kvm. Jul 10 00:31:30.034090 systemd[1]: Detected architecture arm64. Jul 10 00:31:30.034101 systemd[1]: Detected first boot. Jul 10 00:31:30.034113 systemd[1]: Initializing machine ID from VM UUID. Jul 10 00:31:30.034123 kernel: kauditd_printk_skb: 63 callbacks suppressed Jul 10 00:31:30.034135 kernel: audit: type=1400 audit(1752107488.065:74): 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 10 00:31:30.034146 kernel: audit: type=1400 audit(1752107488.065:75): 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 10 00:31:30.034156 kernel: audit: type=1334 audit(1752107488.066:76): prog-id=10 op=LOAD Jul 10 00:31:30.034167 kernel: audit: type=1334 audit(1752107488.066:77): prog-id=10 op=UNLOAD Jul 10 00:31:30.034177 kernel: audit: type=1334 audit(1752107488.068:78): prog-id=11 op=LOAD Jul 10 00:31:30.034188 kernel: audit: type=1334 audit(1752107488.068:79): prog-id=11 op=UNLOAD Jul 10 00:31:30.034199 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 10 00:31:30.034210 kernel: audit: type=1400 audit(1752107488.122:80): avc: denied { associate } for pid=938 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Jul 10 00:31:30.034221 kernel: audit: type=1300 audit(1752107488.122:80): arch=c00000b7 syscall=5 success=yes exit=0 a0=40001cd89c a1=4000150de0 a2=40001570c0 a3=32 items=0 ppid=921 pid=938 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 10 00:31:30.034233 kernel: audit: type=1327 audit(1752107488.122:80): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 10 00:31:30.034246 kernel: audit: type=1400 audit(1752107488.123:81): avc: denied { associate } for pid=938 comm="torcx-generator" name="bin" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 10 00:31:30.034258 systemd[1]: Populated /etc with preset unit settings. Jul 10 00:31:30.034270 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 10 00:31:30.034283 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 10 00:31:30.034296 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 10 00:31:30.034308 systemd[1]: iscsid.service: Deactivated successfully. Jul 10 00:31:30.034319 systemd[1]: Stopped iscsid.service. Jul 10 00:31:30.034330 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 10 00:31:30.034341 systemd[1]: Stopped initrd-switch-root.service. Jul 10 00:31:30.034352 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 10 00:31:30.034385 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 10 00:31:30.034397 systemd[1]: Created slice system-addon\x2drun.slice. Jul 10 00:31:30.034408 systemd[1]: Created slice system-getty.slice. Jul 10 00:31:30.034419 systemd[1]: Created slice system-modprobe.slice. Jul 10 00:31:30.034430 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 10 00:31:30.034441 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 10 00:31:30.034452 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 10 00:31:30.034463 systemd[1]: Created slice user.slice. Jul 10 00:31:30.034475 systemd[1]: Started systemd-ask-password-console.path. Jul 10 00:31:30.034486 systemd[1]: Started systemd-ask-password-wall.path. Jul 10 00:31:30.034496 systemd[1]: Set up automount boot.automount. Jul 10 00:31:30.034507 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 10 00:31:30.034522 systemd[1]: Stopped target initrd-switch-root.target. Jul 10 00:31:30.034534 systemd[1]: Stopped target initrd-fs.target. Jul 10 00:31:30.034546 systemd[1]: Stopped target initrd-root-fs.target. Jul 10 00:31:30.034559 systemd[1]: Reached target integritysetup.target. Jul 10 00:31:30.034569 systemd[1]: Reached target remote-cryptsetup.target. Jul 10 00:31:30.034580 systemd[1]: Reached target remote-fs.target. Jul 10 00:31:30.034590 systemd[1]: Reached target slices.target. Jul 10 00:31:30.034602 systemd[1]: Reached target swap.target. Jul 10 00:31:30.034613 systemd[1]: Reached target torcx.target. Jul 10 00:31:30.034623 systemd[1]: Reached target veritysetup.target. Jul 10 00:31:30.034634 systemd[1]: Listening on systemd-coredump.socket. Jul 10 00:31:30.034646 systemd[1]: Listening on systemd-initctl.socket. Jul 10 00:31:30.034657 systemd[1]: Listening on systemd-networkd.socket. Jul 10 00:31:30.034669 systemd[1]: Listening on systemd-udevd-control.socket. Jul 10 00:31:30.034681 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 10 00:31:30.034692 systemd[1]: Listening on systemd-userdbd.socket. Jul 10 00:31:30.034704 systemd[1]: Mounting dev-hugepages.mount... Jul 10 00:31:30.034715 systemd[1]: Mounting dev-mqueue.mount... Jul 10 00:31:30.034727 systemd[1]: Mounting media.mount... Jul 10 00:31:30.034738 systemd[1]: Mounting sys-kernel-debug.mount... Jul 10 00:31:30.034750 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 10 00:31:30.034761 systemd[1]: Mounting tmp.mount... Jul 10 00:31:30.034772 systemd[1]: Starting flatcar-tmpfiles.service... Jul 10 00:31:30.034783 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 10 00:31:30.034794 systemd[1]: Starting kmod-static-nodes.service... Jul 10 00:31:30.034804 systemd[1]: Starting modprobe@configfs.service... Jul 10 00:31:30.034814 systemd[1]: Starting modprobe@dm_mod.service... Jul 10 00:31:30.034826 systemd[1]: Starting modprobe@drm.service... Jul 10 00:31:30.034836 systemd[1]: Starting modprobe@efi_pstore.service... Jul 10 00:31:30.034852 systemd[1]: Starting modprobe@fuse.service... Jul 10 00:31:30.034862 systemd[1]: Starting modprobe@loop.service... Jul 10 00:31:30.034874 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 10 00:31:30.034885 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 10 00:31:30.034896 systemd[1]: Stopped systemd-fsck-root.service. Jul 10 00:31:30.034906 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 10 00:31:30.034918 systemd[1]: Stopped systemd-fsck-usr.service. Jul 10 00:31:30.034929 systemd[1]: Stopped systemd-journald.service. Jul 10 00:31:30.034939 kernel: loop: module loaded Jul 10 00:31:30.034952 kernel: fuse: init (API version 7.34) Jul 10 00:31:30.034962 systemd[1]: Starting systemd-journald.service... Jul 10 00:31:30.034973 systemd[1]: Starting systemd-modules-load.service... Jul 10 00:31:30.034984 systemd[1]: Starting systemd-network-generator.service... Jul 10 00:31:30.034999 systemd[1]: Starting systemd-remount-fs.service... Jul 10 00:31:30.035011 systemd[1]: Starting systemd-udev-trigger.service... Jul 10 00:31:30.035022 systemd[1]: verity-setup.service: Deactivated successfully. Jul 10 00:31:30.035033 systemd[1]: Stopped verity-setup.service. Jul 10 00:31:30.035044 systemd[1]: Mounted dev-hugepages.mount. Jul 10 00:31:30.035055 systemd[1]: Mounted dev-mqueue.mount. Jul 10 00:31:30.035067 systemd[1]: Mounted media.mount. Jul 10 00:31:30.035078 systemd[1]: Mounted sys-kernel-debug.mount. Jul 10 00:31:30.035089 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 10 00:31:30.035101 systemd[1]: Mounted tmp.mount. Jul 10 00:31:30.035112 systemd[1]: Finished kmod-static-nodes.service. Jul 10 00:31:30.035122 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 10 00:31:30.035133 systemd[1]: Finished modprobe@configfs.service. Jul 10 00:31:30.035144 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:31:30.035154 systemd[1]: Finished modprobe@dm_mod.service. Jul 10 00:31:30.035166 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 10 00:31:30.035176 systemd[1]: Finished modprobe@drm.service. Jul 10 00:31:30.035187 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:31:30.035198 systemd[1]: Finished modprobe@efi_pstore.service. Jul 10 00:31:30.035209 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 10 00:31:30.035221 systemd[1]: Finished modprobe@fuse.service. Jul 10 00:31:30.035232 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:31:30.035243 systemd[1]: Finished modprobe@loop.service. Jul 10 00:31:30.035253 systemd[1]: Finished systemd-modules-load.service. Jul 10 00:31:30.035263 systemd[1]: Finished systemd-network-generator.service. Jul 10 00:31:30.035274 systemd[1]: Finished systemd-remount-fs.service. Jul 10 00:31:30.035286 systemd[1]: Reached target network-pre.target. Jul 10 00:31:30.035298 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 10 00:31:30.035309 systemd[1]: Mounting sys-kernel-config.mount... Jul 10 00:31:30.035321 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 10 00:31:30.035331 systemd[1]: Starting systemd-hwdb-update.service... Jul 10 00:31:30.035348 systemd-journald[996]: Journal started Jul 10 00:31:30.035405 systemd-journald[996]: Runtime Journal (/run/log/journal/7ab93245feb74bf8bc9ce22ce2127415) is 6.0M, max 48.7M, 42.6M free. Jul 10 00:31:27.999000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 10 00:31:28.065000 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 10 00:31:28.065000 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 10 00:31:28.066000 audit: BPF prog-id=10 op=LOAD Jul 10 00:31:28.066000 audit: BPF prog-id=10 op=UNLOAD Jul 10 00:31:28.068000 audit: BPF prog-id=11 op=LOAD Jul 10 00:31:28.068000 audit: BPF prog-id=11 op=UNLOAD Jul 10 00:31:28.122000 audit[938]: AVC avc: denied { associate } for pid=938 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Jul 10 00:31:28.122000 audit[938]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001cd89c a1=4000150de0 a2=40001570c0 a3=32 items=0 ppid=921 pid=938 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 10 00:31:28.122000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 10 00:31:28.123000 audit[938]: AVC avc: denied { associate } for pid=938 comm="torcx-generator" name="bin" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 10 00:31:28.123000 audit[938]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001cd975 a2=1ed a3=0 items=2 ppid=921 pid=938 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 10 00:31:28.123000 audit: CWD cwd="/" Jul 10 00:31:28.123000 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 10 00:31:28.123000 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 10 00:31:28.123000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 10 00:31:29.831000 audit: BPF prog-id=12 op=LOAD Jul 10 00:31:29.831000 audit: BPF prog-id=3 op=UNLOAD Jul 10 00:31:29.832000 audit: BPF prog-id=13 op=LOAD Jul 10 00:31:29.832000 audit: BPF prog-id=14 op=LOAD Jul 10 00:31:29.832000 audit: BPF prog-id=4 op=UNLOAD Jul 10 00:31:29.832000 audit: BPF prog-id=5 op=UNLOAD Jul 10 00:31:29.833000 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 10 00:31:29.836000 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 10 00:31:29.839000 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 10 00:31:29.839000 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 10 00:31:29.853000 audit: BPF prog-id=12 op=UNLOAD Jul 10 00:31:29.939000 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 10 00:31:29.942000 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 10 00:31:29.943000 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 10 00:31:29.943000 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 10 00:31:29.945000 audit: BPF prog-id=15 op=LOAD Jul 10 00:31:29.949000 audit: BPF prog-id=16 op=LOAD Jul 10 00:31:29.949000 audit: BPF prog-id=17 op=LOAD Jul 10 00:31:29.949000 audit: BPF prog-id=13 op=UNLOAD Jul 10 00:31:29.949000 audit: BPF prog-id=14 op=UNLOAD Jul 10 00:31:29.974000 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 10 00:31:29.987000 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 10 00:31:29.991000 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 10 00:31:29.991000 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 10 00:31:29.993000 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 10 00:31:29.993000 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 10 00:31:29.997000 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 10 00:31:29.997000 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 10 00:31:30.006000 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 10 00:31:30.006000 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 10 00:31:30.009000 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 10 00:31:30.009000 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 10 00:31:30.012000 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 10 00:31:30.012000 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 10 00:31:30.015000 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 10 00:31:30.017000 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 10 00:31:30.019000 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 10 00:31:30.019000 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 10 00:31:30.019000 audit[996]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=5 a1=ffffd8f22e90 a2=4000 a3=1 items=0 ppid=1 pid=996 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 10 00:31:30.019000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 10 00:31:28.118160 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" 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 10 00:31:29.830173 systemd[1]: Queued start job for default target multi-user.target. Jul 10 00:31:28.120886 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 10 00:31:29.830187 systemd[1]: Unnecessary job was removed for dev-vda6.device. Jul 10 00:31:28.120907 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 10 00:31:29.833598 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 10 00:31:28.120939 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Jul 10 00:31:28.120949 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=debug msg="skipped missing lower profile" missing profile=oem Jul 10 00:31:28.120980 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Jul 10 00:31:28.120993 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Jul 10 00:31:28.121195 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Jul 10 00:31:28.121229 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 10 00:31:28.121241 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 10 00:31:28.122187 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" 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 10 00:31:28.122222 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" 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 10 00:31:28.122243 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" 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 10 00:31:28.122257 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Jul 10 00:31:28.122275 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" 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 10 00:31:28.122289 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:28Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Jul 10 00:31:29.554332 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:29Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 10 00:31:29.554629 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:29Z" 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 10 00:31:29.554724 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:29Z" 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 10 00:31:29.554881 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:29Z" 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 10 00:31:29.554931 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:29Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Jul 10 00:31:29.554986 /usr/lib/systemd/system-generators/torcx-generator[938]: time="2025-07-10T00:31:29Z" 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 10 00:31:30.039445 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 10 00:31:30.041452 systemd[1]: Starting systemd-random-seed.service... Jul 10 00:31:30.043395 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 10 00:31:30.045391 systemd[1]: Starting systemd-sysctl.service... Jul 10 00:31:30.053378 systemd[1]: Started systemd-journald.service. Jul 10 00:31:30.053000 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 10 00:31:30.056000 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 10 00:31:30.057000 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 10 00:31:30.054768 systemd[1]: Finished systemd-udev-trigger.service. Jul 10 00:31:30.056617 systemd[1]: Finished flatcar-tmpfiles.service. Jul 10 00:31:30.057664 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 10 00:31:30.058691 systemd[1]: Mounted sys-kernel-config.mount. Jul 10 00:31:30.060052 systemd[1]: Finished systemd-random-seed.service. Jul 10 00:31:30.060000 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 10 00:31:30.061857 systemd[1]: Reached target first-boot-complete.target. Jul 10 00:31:30.064058 systemd[1]: Starting systemd-journal-flush.service... Jul 10 00:31:30.066419 systemd[1]: Starting systemd-sysusers.service... Jul 10 00:31:30.073335 systemd-journald[996]: Time spent on flushing to /var/log/journal/7ab93245feb74bf8bc9ce22ce2127415 is 11.627ms for 977 entries. Jul 10 00:31:30.073335 systemd-journald[996]: System Journal (/var/log/journal/7ab93245feb74bf8bc9ce22ce2127415) is 8.0M, max 195.6M, 187.6M free. Jul 10 00:31:30.095604 systemd-journald[996]: Received client request to flush runtime journal. Jul 10 00:31:30.075000 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 10 00:31:30.068313 systemd[1]: Starting systemd-udev-settle.service... Jul 10 00:31:30.071973 systemd[1]: Finished systemd-sysctl.service. Jul 10 00:31:30.095988 udevadm[1039]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 10 00:31:30.096620 systemd[1]: Finished systemd-journal-flush.service. Jul 10 00:31:30.097000 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 10 00:31:30.099924 systemd[1]: Finished systemd-sysusers.service. Jul 10 00:31:30.100000 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 10 00:31:30.431532 systemd[1]: Finished systemd-hwdb-update.service. Jul 10 00:31:30.432000 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 10 00:31:30.432000 audit: BPF prog-id=18 op=LOAD Jul 10 00:31:30.432000 audit: BPF prog-id=19 op=LOAD Jul 10 00:31:30.432000 audit: BPF prog-id=7 op=UNLOAD Jul 10 00:31:30.432000 audit: BPF prog-id=8 op=UNLOAD Jul 10 00:31:30.433894 systemd[1]: Starting systemd-udevd.service... Jul 10 00:31:30.452668 systemd-udevd[1041]: Using default interface naming scheme 'v252'. Jul 10 00:31:30.464393 systemd[1]: Started systemd-udevd.service. Jul 10 00:31:30.464000 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 10 00:31:30.466000 audit: BPF prog-id=20 op=LOAD Jul 10 00:31:30.468311 systemd[1]: Starting systemd-networkd.service... Jul 10 00:31:30.487203 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Jul 10 00:31:30.488000 audit: BPF prog-id=21 op=LOAD Jul 10 00:31:30.488000 audit: BPF prog-id=22 op=LOAD Jul 10 00:31:30.488000 audit: BPF prog-id=23 op=LOAD Jul 10 00:31:30.489302 systemd[1]: Starting systemd-userdbd.service... Jul 10 00:31:30.521306 systemd[1]: Started systemd-userdbd.service. Jul 10 00:31:30.522000 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 10 00:31:30.533642 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 10 00:31:30.576653 systemd-networkd[1050]: lo: Link UP Jul 10 00:31:30.576666 systemd-networkd[1050]: lo: Gained carrier Jul 10 00:31:30.577035 systemd-networkd[1050]: Enumeration completed Jul 10 00:31:30.577149 systemd-networkd[1050]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 10 00:31:30.577150 systemd[1]: Started systemd-networkd.service. Jul 10 00:31:30.576000 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 10 00:31:30.579753 systemd[1]: Finished systemd-udev-settle.service. Jul 10 00:31:30.580317 systemd-networkd[1050]: eth0: Link UP Jul 10 00:31:30.580328 systemd-networkd[1050]: eth0: Gained carrier Jul 10 00:31:30.580000 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 10 00:31:30.582132 systemd[1]: Starting lvm2-activation-early.service... Jul 10 00:31:30.592279 lvm[1074]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 10 00:31:30.598532 systemd-networkd[1050]: eth0: DHCPv4 address 10.0.0.58/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 10 00:31:30.620259 systemd[1]: Finished lvm2-activation-early.service. Jul 10 00:31:30.620000 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 10 00:31:30.621468 systemd[1]: Reached target cryptsetup.target. Jul 10 00:31:30.623602 systemd[1]: Starting lvm2-activation.service... Jul 10 00:31:30.627750 lvm[1075]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 10 00:31:30.661395 systemd[1]: Finished lvm2-activation.service. Jul 10 00:31:30.661000 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 10 00:31:30.662506 systemd[1]: Reached target local-fs-pre.target. Jul 10 00:31:30.663418 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 10 00:31:30.663453 systemd[1]: Reached target local-fs.target. Jul 10 00:31:30.664285 systemd[1]: Reached target machines.target. Jul 10 00:31:30.666434 systemd[1]: Starting ldconfig.service... Jul 10 00:31:30.673301 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 10 00:31:30.673459 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 10 00:31:30.674778 systemd[1]: Starting systemd-boot-update.service... Jul 10 00:31:30.677004 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 10 00:31:30.679522 systemd[1]: Starting systemd-machine-id-commit.service... Jul 10 00:31:30.682735 systemd[1]: Starting systemd-sysext.service... Jul 10 00:31:30.684276 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1077 (bootctl) Jul 10 00:31:30.688570 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 10 00:31:30.690210 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 10 00:31:30.691000 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 10 00:31:30.698754 systemd[1]: Unmounting usr-share-oem.mount... Jul 10 00:31:30.703896 systemd[1]: usr-share-oem.mount: Deactivated successfully. Jul 10 00:31:30.704104 systemd[1]: Unmounted usr-share-oem.mount. Jul 10 00:31:30.724404 kernel: loop0: detected capacity change from 0 to 207008 Jul 10 00:31:30.775018 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 10 00:31:30.775656 systemd[1]: Finished systemd-machine-id-commit.service. Jul 10 00:31:30.776000 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 10 00:31:30.786401 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 10 00:31:30.804089 systemd-fsck[1085]: fsck.fat 4.2 (2021-01-31) Jul 10 00:31:30.804089 systemd-fsck[1085]: /dev/vda1: 236 files, 117310/258078 clusters Jul 10 00:31:30.809395 kernel: loop1: detected capacity change from 0 to 207008 Jul 10 00:31:30.810923 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 10 00:31:30.812000 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 10 00:31:30.814095 systemd[1]: Mounting boot.mount... Jul 10 00:31:30.817620 (sd-sysext)[1090]: Using extensions 'kubernetes'. Jul 10 00:31:30.817991 (sd-sysext)[1090]: Merged extensions into '/usr'. Jul 10 00:31:30.835511 systemd[1]: Mounted boot.mount. Jul 10 00:31:30.836916 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 10 00:31:30.838394 systemd[1]: Starting modprobe@dm_mod.service... Jul 10 00:31:30.840819 systemd[1]: Starting modprobe@efi_pstore.service... Jul 10 00:31:30.843278 systemd[1]: Starting modprobe@loop.service... Jul 10 00:31:30.844307 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 10 00:31:30.844513 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 10 00:31:30.845480 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:31:30.845642 systemd[1]: Finished modprobe@dm_mod.service. Jul 10 00:31:30.846000 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 10 00:31:30.846000 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 10 00:31:30.847190 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:31:30.847320 systemd[1]: Finished modprobe@efi_pstore.service. Jul 10 00:31:30.848000 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 10 00:31:30.848000 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 10 00:31:30.848840 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:31:30.848966 systemd[1]: Finished modprobe@loop.service. Jul 10 00:31:30.849000 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 10 00:31:30.849000 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 10 00:31:30.850412 systemd[1]: Finished systemd-boot-update.service. Jul 10 00:31:30.851000 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 10 00:31:30.851924 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 10 00:31:30.852037 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 10 00:31:30.931143 ldconfig[1076]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 10 00:31:30.934465 systemd[1]: Finished ldconfig.service. Jul 10 00:31:30.934000 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 10 00:31:30.976818 systemd[1]: Mounting usr-share-oem.mount... Jul 10 00:31:30.981815 systemd[1]: Mounted usr-share-oem.mount. Jul 10 00:31:30.984074 systemd[1]: Finished systemd-sysext.service. Jul 10 00:31:30.984000 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 10 00:31:30.986355 systemd[1]: Starting ensure-sysext.service... Jul 10 00:31:30.988199 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 10 00:31:30.994024 systemd[1]: Reloading. Jul 10 00:31:31.011450 systemd-tmpfiles[1098]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 10 00:31:31.014301 systemd-tmpfiles[1098]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 10 00:31:31.019195 systemd-tmpfiles[1098]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 10 00:31:31.031521 /usr/lib/systemd/system-generators/torcx-generator[1118]: time="2025-07-10T00:31:31Z" 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 10 00:31:31.032595 /usr/lib/systemd/system-generators/torcx-generator[1118]: time="2025-07-10T00:31:31Z" level=info msg="torcx already run" Jul 10 00:31:31.093138 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 10 00:31:31.093489 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 10 00:31:31.108858 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 10 00:31:31.151000 audit: BPF prog-id=24 op=LOAD Jul 10 00:31:31.151000 audit: BPF prog-id=21 op=UNLOAD Jul 10 00:31:31.151000 audit: BPF prog-id=25 op=LOAD Jul 10 00:31:31.151000 audit: BPF prog-id=26 op=LOAD Jul 10 00:31:31.151000 audit: BPF prog-id=22 op=UNLOAD Jul 10 00:31:31.151000 audit: BPF prog-id=23 op=UNLOAD Jul 10 00:31:31.153000 audit: BPF prog-id=27 op=LOAD Jul 10 00:31:31.153000 audit: BPF prog-id=15 op=UNLOAD Jul 10 00:31:31.154000 audit: BPF prog-id=28 op=LOAD Jul 10 00:31:31.154000 audit: BPF prog-id=29 op=LOAD Jul 10 00:31:31.154000 audit: BPF prog-id=16 op=UNLOAD Jul 10 00:31:31.154000 audit: BPF prog-id=17 op=UNLOAD Jul 10 00:31:31.155000 audit: BPF prog-id=30 op=LOAD Jul 10 00:31:31.155000 audit: BPF prog-id=20 op=UNLOAD Jul 10 00:31:31.155000 audit: BPF prog-id=31 op=LOAD Jul 10 00:31:31.155000 audit: BPF prog-id=32 op=LOAD Jul 10 00:31:31.155000 audit: BPF prog-id=18 op=UNLOAD Jul 10 00:31:31.155000 audit: BPF prog-id=19 op=UNLOAD Jul 10 00:31:31.158357 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 10 00:31:31.158000 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 10 00:31:31.162851 systemd[1]: Starting audit-rules.service... Jul 10 00:31:31.164872 systemd[1]: Starting clean-ca-certificates.service... Jul 10 00:31:31.167221 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 10 00:31:31.170000 audit: BPF prog-id=33 op=LOAD Jul 10 00:31:31.174339 systemd[1]: Starting systemd-resolved.service... Jul 10 00:31:31.178000 audit: BPF prog-id=34 op=LOAD Jul 10 00:31:31.179656 systemd[1]: Starting systemd-timesyncd.service... Jul 10 00:31:31.181592 systemd[1]: Starting systemd-update-utmp.service... Jul 10 00:31:31.183430 systemd[1]: Finished clean-ca-certificates.service. Jul 10 00:31:31.184000 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 10 00:31:31.186841 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 10 00:31:31.190062 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.189000 audit[1167]: SYSTEM_BOOT pid=1167 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 10 00:31:31.191548 systemd[1]: Starting modprobe@dm_mod.service... Jul 10 00:31:31.193674 systemd[1]: Starting modprobe@efi_pstore.service... Jul 10 00:31:31.195843 systemd[1]: Starting modprobe@loop.service... Jul 10 00:31:31.196732 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.196894 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 10 00:31:31.196997 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 10 00:31:31.197794 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:31:31.197955 systemd[1]: Finished modprobe@dm_mod.service. Jul 10 00:31:31.198000 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 10 00:31:31.198000 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 10 00:31:31.199249 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:31:31.199415 systemd[1]: Finished modprobe@efi_pstore.service. Jul 10 00:31:31.199000 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 10 00:31:31.199000 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 10 00:31:31.200737 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 10 00:31:31.201000 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 10 00:31:31.202186 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:31:31.202297 systemd[1]: Finished modprobe@loop.service. Jul 10 00:31:31.202000 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 10 00:31:31.202000 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 10 00:31:31.206817 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.208092 systemd[1]: Starting modprobe@dm_mod.service... Jul 10 00:31:31.210144 systemd[1]: Starting modprobe@efi_pstore.service... Jul 10 00:31:31.212025 systemd[1]: Starting modprobe@loop.service... Jul 10 00:31:31.212826 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.212941 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 10 00:31:31.214208 systemd[1]: Starting systemd-update-done.service... Jul 10 00:31:31.215087 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 10 00:31:31.216170 systemd[1]: Finished systemd-update-utmp.service. Jul 10 00:31:31.216000 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 10 00:31:31.217619 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:31:31.217736 systemd[1]: Finished modprobe@dm_mod.service. Jul 10 00:31:31.218000 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 10 00:31:31.218000 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 10 00:31:31.218929 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:31:31.219040 systemd[1]: Finished modprobe@efi_pstore.service. Jul 10 00:31:31.219000 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 10 00:31:31.219000 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 10 00:31:31.220305 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:31:31.220424 systemd[1]: Finished modprobe@loop.service. Jul 10 00:31:31.220000 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 10 00:31:31.220000 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 10 00:31:31.222000 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 10 00:31:31.221662 systemd[1]: Finished systemd-update-done.service. Jul 10 00:31:31.226869 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.228689 systemd[1]: Starting modprobe@dm_mod.service... Jul 10 00:31:31.229000 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 10 00:31:31.229000 audit[1184]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe353cc50 a2=420 a3=0 items=0 ppid=1156 pid=1184 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 10 00:31:31.229000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 10 00:31:31.230525 augenrules[1184]: No rules Jul 10 00:31:31.231062 systemd[1]: Starting modprobe@drm.service... Jul 10 00:31:31.233022 systemd[1]: Starting modprobe@efi_pstore.service... Jul 10 00:31:31.235132 systemd[1]: Starting modprobe@loop.service... Jul 10 00:31:31.236030 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.236182 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 10 00:31:31.237576 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 10 00:31:31.238596 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 10 00:31:31.239916 systemd[1]: Finished audit-rules.service. Jul 10 00:31:31.241095 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:31:31.241215 systemd[1]: Finished modprobe@dm_mod.service. Jul 10 00:31:31.242469 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 10 00:31:31.242600 systemd[1]: Finished modprobe@drm.service. Jul 10 00:31:31.243898 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:31:31.244018 systemd[1]: Finished modprobe@efi_pstore.service. Jul 10 00:31:31.245297 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:31:31.245425 systemd[1]: Finished modprobe@loop.service. Jul 10 00:31:31.246744 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 10 00:31:31.246844 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.248098 systemd[1]: Finished ensure-sysext.service. Jul 10 00:31:31.248417 systemd-resolved[1160]: Positive Trust Anchors: Jul 10 00:31:31.248426 systemd-resolved[1160]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 10 00:31:31.248453 systemd-resolved[1160]: 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 10 00:31:31.253807 systemd[1]: Started systemd-timesyncd.service. Jul 10 00:31:31.668613 systemd-timesyncd[1166]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 10 00:31:31.668671 systemd-timesyncd[1166]: Initial clock synchronization to Thu 2025-07-10 00:31:31.668524 UTC. Jul 10 00:31:31.669133 systemd[1]: Reached target time-set.target. Jul 10 00:31:31.671588 systemd-resolved[1160]: Defaulting to hostname 'linux'. Jul 10 00:31:31.673019 systemd[1]: Started systemd-resolved.service. Jul 10 00:31:31.674518 systemd[1]: Reached target network.target. Jul 10 00:31:31.675323 systemd[1]: Reached target nss-lookup.target. Jul 10 00:31:31.676211 systemd[1]: Reached target sysinit.target. Jul 10 00:31:31.677109 systemd[1]: Started motdgen.path. Jul 10 00:31:31.678216 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 10 00:31:31.679966 systemd[1]: Started logrotate.timer. Jul 10 00:31:31.680855 systemd[1]: Started mdadm.timer. Jul 10 00:31:31.681541 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 10 00:31:31.682356 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 10 00:31:31.682400 systemd[1]: Reached target paths.target. Jul 10 00:31:31.683133 systemd[1]: Reached target timers.target. Jul 10 00:31:31.684224 systemd[1]: Listening on dbus.socket. Jul 10 00:31:31.686133 systemd[1]: Starting docker.socket... Jul 10 00:31:31.689515 systemd[1]: Listening on sshd.socket. Jul 10 00:31:31.690364 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 10 00:31:31.690837 systemd[1]: Listening on docker.socket. Jul 10 00:31:31.692541 systemd[1]: Reached target sockets.target. Jul 10 00:31:31.693307 systemd[1]: Reached target basic.target. Jul 10 00:31:31.694111 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.694144 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 10 00:31:31.695188 systemd[1]: Starting containerd.service... Jul 10 00:31:31.697130 systemd[1]: Starting dbus.service... Jul 10 00:31:31.698998 systemd[1]: Starting enable-oem-cloudinit.service... Jul 10 00:31:31.701062 systemd[1]: Starting extend-filesystems.service... Jul 10 00:31:31.701935 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 10 00:31:31.703294 systemd[1]: Starting motdgen.service... Jul 10 00:31:31.705330 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 10 00:31:31.708221 systemd[1]: Starting sshd-keygen.service... Jul 10 00:31:31.713208 systemd[1]: Starting systemd-logind.service... Jul 10 00:31:31.714072 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 10 00:31:31.714170 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 10 00:31:31.714765 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 10 00:31:31.715605 systemd[1]: Starting update-engine.service... Jul 10 00:31:31.718370 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 10 00:31:31.720587 jq[1213]: true Jul 10 00:31:31.722333 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 10 00:31:31.722530 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 10 00:31:31.724681 extend-filesystems[1199]: Found loop1 Jul 10 00:31:31.724681 extend-filesystems[1199]: Found vda Jul 10 00:31:31.724681 extend-filesystems[1199]: Found vda1 Jul 10 00:31:31.724681 extend-filesystems[1199]: Found vda2 Jul 10 00:31:31.727847 extend-filesystems[1199]: Found vda3 Jul 10 00:31:31.727847 extend-filesystems[1199]: Found usr Jul 10 00:31:31.727847 extend-filesystems[1199]: Found vda4 Jul 10 00:31:31.727847 extend-filesystems[1199]: Found vda6 Jul 10 00:31:31.727847 extend-filesystems[1199]: Found vda7 Jul 10 00:31:31.727847 extend-filesystems[1199]: Found vda9 Jul 10 00:31:31.727847 extend-filesystems[1199]: Checking size of /dev/vda9 Jul 10 00:31:31.749175 jq[1198]: false Jul 10 00:31:31.741035 dbus-daemon[1197]: [system] SELinux support is enabled Jul 10 00:31:31.732956 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 10 00:31:31.758591 jq[1219]: true Jul 10 00:31:31.733160 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 10 00:31:31.741238 systemd[1]: Started dbus.service. Jul 10 00:31:31.743730 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 10 00:31:31.743754 systemd[1]: Reached target system-config.target. Jul 10 00:31:31.746936 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 10 00:31:31.746953 systemd[1]: Reached target user-config.target. Jul 10 00:31:31.748610 systemd[1]: motdgen.service: Deactivated successfully. Jul 10 00:31:31.748777 systemd[1]: Finished motdgen.service. Jul 10 00:31:31.774342 extend-filesystems[1199]: Resized partition /dev/vda9 Jul 10 00:31:31.779756 extend-filesystems[1245]: resize2fs 1.46.5 (30-Dec-2021) Jul 10 00:31:31.790024 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 10 00:31:31.821784 update_engine[1211]: I0710 00:31:31.821506 1211 main.cc:92] Flatcar Update Engine starting Jul 10 00:31:31.830445 update_engine[1211]: I0710 00:31:31.825529 1211 update_check_scheduler.cc:74] Next update check in 3m39s Jul 10 00:31:31.825485 systemd[1]: Started update-engine.service. Jul 10 00:31:31.828423 systemd[1]: Started locksmithd.service. Jul 10 00:31:31.830792 systemd-logind[1209]: Watching system buttons on /dev/input/event0 (Power Button) Jul 10 00:31:31.831110 systemd-logind[1209]: New seat seat0. Jul 10 00:31:31.832484 systemd[1]: Started systemd-logind.service. Jul 10 00:31:31.845838 env[1220]: time="2025-07-10T00:31:31.845774754Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 10 00:31:31.858779 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 10 00:31:31.885042 env[1220]: time="2025-07-10T00:31:31.864400714Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 10 00:31:31.885451 extend-filesystems[1245]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 10 00:31:31.885451 extend-filesystems[1245]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 10 00:31:31.885451 extend-filesystems[1245]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 10 00:31:31.893544 extend-filesystems[1199]: Resized filesystem in /dev/vda9 Jul 10 00:31:31.888261 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 10 00:31:31.896360 bash[1236]: Updated "/home/core/.ssh/authorized_keys" Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.887142994Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.890279314Z" 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 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.890309474Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.890747514Z" 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 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.890768114Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.890781034Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.890790994Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.890891154Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.891347354Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 10 00:31:31.896461 env[1220]: time="2025-07-10T00:31:31.891673354Z" 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 10 00:31:31.888447 systemd[1]: Finished extend-filesystems.service. Jul 10 00:31:31.896784 env[1220]: time="2025-07-10T00:31:31.891699074Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 10 00:31:31.896784 env[1220]: time="2025-07-10T00:31:31.891763754Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 10 00:31:31.896784 env[1220]: time="2025-07-10T00:31:31.891776874Z" level=info msg="metadata content store policy set" policy=shared Jul 10 00:31:31.892477 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.897698234Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.897735274Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.897749234Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.897786874Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.897802234Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.897816114Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.897828594Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.898204274Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.898223074Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.898236634Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.898249514Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.898262674Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.898365794Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 10 00:31:31.901056 env[1220]: time="2025-07-10T00:31:31.898488274Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.898757474Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.898793034Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.898807434Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.898917754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.898931194Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.898942634Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.898954194Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899009794Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899024434Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899039874Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899051274Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899063674Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899187074Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899215314Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901472 env[1220]: time="2025-07-10T00:31:31.899228794Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901773 env[1220]: time="2025-07-10T00:31:31.899240314Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 10 00:31:31.901773 env[1220]: time="2025-07-10T00:31:31.899254954Z" 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 10 00:31:31.901773 env[1220]: time="2025-07-10T00:31:31.899265474Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 10 00:31:31.901773 env[1220]: time="2025-07-10T00:31:31.899287074Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 10 00:31:31.901773 env[1220]: time="2025-07-10T00:31:31.899320994Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 10 00:31:31.901880 env[1220]: time="2025-07-10T00:31:31.899548754Z" 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 10 00:31:31.901880 env[1220]: time="2025-07-10T00:31:31.899603434Z" level=info msg="Connect containerd service" Jul 10 00:31:31.901880 env[1220]: time="2025-07-10T00:31:31.899630954Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 10 00:31:31.902919 env[1220]: time="2025-07-10T00:31:31.902776114Z" 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 10 00:31:31.903012 env[1220]: time="2025-07-10T00:31:31.902958034Z" level=info msg="Start subscribing containerd event" Jul 10 00:31:31.903012 env[1220]: time="2025-07-10T00:31:31.903008914Z" level=info msg="Start recovering state" Jul 10 00:31:31.903592 env[1220]: time="2025-07-10T00:31:31.903069914Z" level=info msg="Start event monitor" Jul 10 00:31:31.903592 env[1220]: time="2025-07-10T00:31:31.903092674Z" level=info msg="Start snapshots syncer" Jul 10 00:31:31.903592 env[1220]: time="2025-07-10T00:31:31.903102474Z" level=info msg="Start cni network conf syncer for default" Jul 10 00:31:31.903592 env[1220]: time="2025-07-10T00:31:31.903109794Z" level=info msg="Start streaming server" Jul 10 00:31:31.903592 env[1220]: time="2025-07-10T00:31:31.903478474Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 10 00:31:31.903592 env[1220]: time="2025-07-10T00:31:31.903515074Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 10 00:31:31.903592 env[1220]: time="2025-07-10T00:31:31.903566914Z" level=info msg="containerd successfully booted in 0.058487s" Jul 10 00:31:31.903633 systemd[1]: Started containerd.service. Jul 10 00:31:31.912087 locksmithd[1246]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 10 00:31:32.318578 systemd-networkd[1050]: eth0: Gained IPv6LL Jul 10 00:31:32.320239 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 10 00:31:32.321506 systemd[1]: Reached target network-online.target. Jul 10 00:31:32.323768 systemd[1]: Starting kubelet.service... Jul 10 00:31:32.993898 systemd[1]: Started kubelet.service. Jul 10 00:31:33.443158 kubelet[1262]: E0710 00:31:33.443040 1262 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 10 00:31:33.445088 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:31:33.445215 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 10 00:31:34.906000 sshd_keygen[1212]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 10 00:31:34.924736 systemd[1]: Finished sshd-keygen.service. Jul 10 00:31:34.927046 systemd[1]: Starting issuegen.service... Jul 10 00:31:34.931702 systemd[1]: issuegen.service: Deactivated successfully. Jul 10 00:31:34.931870 systemd[1]: Finished issuegen.service. Jul 10 00:31:34.934176 systemd[1]: Starting systemd-user-sessions.service... Jul 10 00:31:34.940446 systemd[1]: Finished systemd-user-sessions.service. Jul 10 00:31:34.942903 systemd[1]: Started getty@tty1.service. Jul 10 00:31:34.945077 systemd[1]: Started serial-getty@ttyAMA0.service. Jul 10 00:31:34.946274 systemd[1]: Reached target getty.target. Jul 10 00:31:34.947120 systemd[1]: Reached target multi-user.target. Jul 10 00:31:34.949225 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 10 00:31:34.956171 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 10 00:31:34.956350 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 10 00:31:34.957452 systemd[1]: Startup finished in 597ms (kernel) + 4.384s (initrd) + 6.584s (userspace) = 11.566s. Jul 10 00:31:36.596505 systemd[1]: Created slice system-sshd.slice. Jul 10 00:31:36.597552 systemd[1]: Started sshd@0-10.0.0.58:22-10.0.0.1:52150.service. Jul 10 00:31:36.639772 sshd[1285]: Accepted publickey for core from 10.0.0.1 port 52150 ssh2: RSA SHA256:qOVwIcEhxIMbnnVzVACNg4ZPFMKwsyA0M9qFZXlj7es Jul 10 00:31:36.641768 sshd[1285]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 10 00:31:36.650962 systemd-logind[1209]: New session 1 of user core. Jul 10 00:31:36.652168 systemd[1]: Created slice user-500.slice. Jul 10 00:31:36.653271 systemd[1]: Starting user-runtime-dir@500.service... Jul 10 00:31:36.661730 systemd[1]: Finished user-runtime-dir@500.service. Jul 10 00:31:36.663091 systemd[1]: Starting user@500.service... Jul 10 00:31:36.668559 (systemd)[1288]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 10 00:31:36.729551 systemd[1288]: Queued start job for default target default.target. Jul 10 00:31:36.730061 systemd[1288]: Reached target paths.target. Jul 10 00:31:36.730099 systemd[1288]: Reached target sockets.target. Jul 10 00:31:36.730111 systemd[1288]: Reached target timers.target. Jul 10 00:31:36.730121 systemd[1288]: Reached target basic.target. Jul 10 00:31:36.730161 systemd[1288]: Reached target default.target. Jul 10 00:31:36.730186 systemd[1288]: Startup finished in 55ms. Jul 10 00:31:36.730454 systemd[1]: Started user@500.service. Jul 10 00:31:36.733773 systemd[1]: Started session-1.scope. Jul 10 00:31:36.788717 systemd[1]: Started sshd@1-10.0.0.58:22-10.0.0.1:52158.service. Jul 10 00:31:36.828986 sshd[1297]: Accepted publickey for core from 10.0.0.1 port 52158 ssh2: RSA SHA256:qOVwIcEhxIMbnnVzVACNg4ZPFMKwsyA0M9qFZXlj7es Jul 10 00:31:36.830586 sshd[1297]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 10 00:31:36.834282 systemd-logind[1209]: New session 2 of user core. Jul 10 00:31:36.835525 systemd[1]: Started session-2.scope. Jul 10 00:31:36.896300 sshd[1297]: pam_unix(sshd:session): session closed for user core Jul 10 00:31:36.900111 systemd[1]: Started sshd@2-10.0.0.58:22-10.0.0.1:52172.service. Jul 10 00:31:36.903007 systemd[1]: sshd@1-10.0.0.58:22-10.0.0.1:52158.service: Deactivated successfully. Jul 10 00:31:36.903697 systemd[1]: session-2.scope: Deactivated successfully. Jul 10 00:31:36.905444 systemd-logind[1209]: Session 2 logged out. Waiting for processes to exit. Jul 10 00:31:36.906802 systemd-logind[1209]: Removed session 2. Jul 10 00:31:36.937939 sshd[1302]: Accepted publickey for core from 10.0.0.1 port 52172 ssh2: RSA SHA256:qOVwIcEhxIMbnnVzVACNg4ZPFMKwsyA0M9qFZXlj7es Jul 10 00:31:36.939547 sshd[1302]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 10 00:31:36.944582 systemd[1]: Started session-3.scope. Jul 10 00:31:36.945721 systemd-logind[1209]: New session 3 of user core. Jul 10 00:31:36.997445 sshd[1302]: pam_unix(sshd:session): session closed for user core Jul 10 00:31:37.001246 systemd[1]: sshd@2-10.0.0.58:22-10.0.0.1:52172.service: Deactivated successfully. Jul 10 00:31:37.001854 systemd[1]: session-3.scope: Deactivated successfully. Jul 10 00:31:37.003287 systemd[1]: Started sshd@3-10.0.0.58:22-10.0.0.1:52186.service. Jul 10 00:31:37.007897 systemd-logind[1209]: Session 3 logged out. Waiting for processes to exit. Jul 10 00:31:37.009582 systemd-logind[1209]: Removed session 3. Jul 10 00:31:37.040899 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 52186 ssh2: RSA SHA256:qOVwIcEhxIMbnnVzVACNg4ZPFMKwsyA0M9qFZXlj7es Jul 10 00:31:37.042457 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 10 00:31:37.048105 systemd[1]: Started session-4.scope. Jul 10 00:31:37.048543 systemd-logind[1209]: New session 4 of user core. Jul 10 00:31:37.106074 sshd[1309]: pam_unix(sshd:session): session closed for user core Jul 10 00:31:37.110362 systemd[1]: sshd@3-10.0.0.58:22-10.0.0.1:52186.service: Deactivated successfully. Jul 10 00:31:37.110939 systemd[1]: session-4.scope: Deactivated successfully. Jul 10 00:31:37.113465 systemd-logind[1209]: Session 4 logged out. Waiting for processes to exit. Jul 10 00:31:37.115349 systemd[1]: Started sshd@4-10.0.0.58:22-10.0.0.1:52200.service. Jul 10 00:31:37.117808 systemd-logind[1209]: Removed session 4. Jul 10 00:31:37.155153 sshd[1315]: Accepted publickey for core from 10.0.0.1 port 52200 ssh2: RSA SHA256:qOVwIcEhxIMbnnVzVACNg4ZPFMKwsyA0M9qFZXlj7es Jul 10 00:31:37.157125 sshd[1315]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 10 00:31:37.163118 systemd[1]: Started session-5.scope. Jul 10 00:31:37.163578 systemd-logind[1209]: New session 5 of user core. Jul 10 00:31:37.224429 sudo[1318]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 10 00:31:37.224671 sudo[1318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jul 10 00:31:37.238827 systemd[1]: Starting coreos-metadata.service... Jul 10 00:31:37.272070 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 10 00:31:37.272251 systemd[1]: Finished coreos-metadata.service. Jul 10 00:31:37.836152 systemd[1]: Stopped kubelet.service. Jul 10 00:31:37.838101 systemd[1]: Starting kubelet.service... Jul 10 00:31:37.861501 systemd[1]: Reloading. Jul 10 00:31:37.903000 /usr/lib/systemd/system-generators/torcx-generator[1378]: time="2025-07-10T00:31:37Z" 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 10 00:31:37.903032 /usr/lib/systemd/system-generators/torcx-generator[1378]: time="2025-07-10T00:31:37Z" level=info msg="torcx already run" Jul 10 00:31:38.052149 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 10 00:31:38.052171 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 10 00:31:38.067911 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 10 00:31:38.136717 systemd[1]: Started kubelet.service. Jul 10 00:31:38.138441 systemd[1]: Stopping kubelet.service... Jul 10 00:31:38.138697 systemd[1]: kubelet.service: Deactivated successfully. Jul 10 00:31:38.138868 systemd[1]: Stopped kubelet.service. Jul 10 00:31:38.140237 systemd[1]: Starting kubelet.service... Jul 10 00:31:38.230797 systemd[1]: Started kubelet.service. Jul 10 00:31:38.271469 kubelet[1423]: 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 10 00:31:38.271469 kubelet[1423]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Jul 10 00:31:38.271469 kubelet[1423]: 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 10 00:31:38.271804 kubelet[1423]: I0710 00:31:38.271572 1423 server.go:215] "--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 10 00:31:38.953175 kubelet[1423]: I0710 00:31:38.953138 1423 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Jul 10 00:31:38.953310 kubelet[1423]: I0710 00:31:38.953299 1423 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 10 00:31:38.953665 kubelet[1423]: I0710 00:31:38.953644 1423 server.go:954] "Client rotation is on, will bootstrap in background" Jul 10 00:31:39.004674 kubelet[1423]: I0710 00:31:39.004633 1423 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 10 00:31:39.014016 kubelet[1423]: E0710 00:31:39.013981 1423 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Jul 10 00:31:39.014016 kubelet[1423]: I0710 00:31:39.014014 1423 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Jul 10 00:31:39.018506 kubelet[1423]: I0710 00:31:39.018476 1423 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 10 00:31:39.019845 kubelet[1423]: I0710 00:31:39.019795 1423 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 10 00:31:39.020013 kubelet[1423]: I0710 00:31:39.019839 1423 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.58","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Jul 10 00:31:39.020093 kubelet[1423]: I0710 00:31:39.020071 1423 topology_manager.go:138] "Creating topology manager with none policy" Jul 10 00:31:39.020093 kubelet[1423]: I0710 00:31:39.020082 1423 container_manager_linux.go:304] "Creating device plugin manager" Jul 10 00:31:39.020296 kubelet[1423]: I0710 00:31:39.020268 1423 state_mem.go:36] "Initialized new in-memory state store" Jul 10 00:31:39.022933 kubelet[1423]: I0710 00:31:39.022914 1423 kubelet.go:446] "Attempting to sync node with API server" Jul 10 00:31:39.022971 kubelet[1423]: I0710 00:31:39.022938 1423 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 10 00:31:39.022971 kubelet[1423]: I0710 00:31:39.022960 1423 kubelet.go:352] "Adding apiserver pod source" Jul 10 00:31:39.022971 kubelet[1423]: I0710 00:31:39.022970 1423 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 10 00:31:39.023093 kubelet[1423]: E0710 00:31:39.023066 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:39.023127 kubelet[1423]: E0710 00:31:39.023111 1423 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:39.027147 kubelet[1423]: I0710 00:31:39.027127 1423 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Jul 10 00:31:39.027754 kubelet[1423]: I0710 00:31:39.027741 1423 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 10 00:31:39.027884 kubelet[1423]: W0710 00:31:39.027871 1423 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 10 00:31:39.028732 kubelet[1423]: I0710 00:31:39.028715 1423 watchdog_linux.go:99] "Systemd watchdog is not enabled" Jul 10 00:31:39.028785 kubelet[1423]: I0710 00:31:39.028749 1423 server.go:1287] "Started kubelet" Jul 10 00:31:39.028872 kubelet[1423]: I0710 00:31:39.028844 1423 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Jul 10 00:31:39.029792 kubelet[1423]: I0710 00:31:39.029771 1423 server.go:479] "Adding debug handlers to kubelet server" Jul 10 00:31:39.030950 kubelet[1423]: W0710 00:31:39.030910 1423 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Jul 10 00:31:39.031012 kubelet[1423]: E0710 00:31:39.030955 1423 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Jul 10 00:31:39.032985 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Jul 10 00:31:39.033044 kubelet[1423]: W0710 00:31:39.032449 1423 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.58" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 10 00:31:39.033044 kubelet[1423]: E0710 00:31:39.032482 1423 reflector.go:166] "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.58\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Jul 10 00:31:39.033259 kubelet[1423]: I0710 00:31:39.033241 1423 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 10 00:31:39.035611 kubelet[1423]: I0710 00:31:39.035579 1423 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 10 00:31:39.035976 kubelet[1423]: I0710 00:31:39.035900 1423 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 10 00:31:39.036303 kubelet[1423]: I0710 00:31:39.036273 1423 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 10 00:31:39.037247 kubelet[1423]: E0710 00:31:39.037220 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.037279 kubelet[1423]: I0710 00:31:39.037259 1423 volume_manager.go:297] "Starting Kubelet Volume Manager" Jul 10 00:31:39.037472 kubelet[1423]: I0710 00:31:39.037449 1423 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Jul 10 00:31:39.037526 kubelet[1423]: I0710 00:31:39.037507 1423 reconciler.go:26] "Reconciler: start to sync state" Jul 10 00:31:39.037832 kubelet[1423]: E0710 00:31:39.037806 1423 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 10 00:31:39.037980 kubelet[1423]: I0710 00:31:39.037958 1423 factory.go:221] Registration of the systemd container factory successfully Jul 10 00:31:39.038062 kubelet[1423]: I0710 00:31:39.038046 1423 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 10 00:31:39.039077 kubelet[1423]: I0710 00:31:39.039051 1423 factory.go:221] Registration of the containerd container factory successfully Jul 10 00:31:39.054824 kubelet[1423]: I0710 00:31:39.054793 1423 cpu_manager.go:221] "Starting CPU manager" policy="none" Jul 10 00:31:39.054824 kubelet[1423]: I0710 00:31:39.054812 1423 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Jul 10 00:31:39.054824 kubelet[1423]: I0710 00:31:39.054831 1423 state_mem.go:36] "Initialized new in-memory state store" Jul 10 00:31:39.055261 kubelet[1423]: E0710 00:31:39.055238 1423 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.58\" not found" node="10.0.0.58" Jul 10 00:31:39.137546 kubelet[1423]: E0710 00:31:39.137501 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.142074 kubelet[1423]: I0710 00:31:39.142035 1423 policy_none.go:49] "None policy: Start" Jul 10 00:31:39.142074 kubelet[1423]: I0710 00:31:39.142061 1423 memory_manager.go:186] "Starting memorymanager" policy="None" Jul 10 00:31:39.142074 kubelet[1423]: I0710 00:31:39.142073 1423 state_mem.go:35] "Initializing new in-memory state store" Jul 10 00:31:39.146827 systemd[1]: Created slice kubepods.slice. Jul 10 00:31:39.150651 systemd[1]: Created slice kubepods-burstable.slice. Jul 10 00:31:39.152839 systemd[1]: Created slice kubepods-besteffort.slice. Jul 10 00:31:39.163145 kubelet[1423]: I0710 00:31:39.163117 1423 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 10 00:31:39.163274 kubelet[1423]: I0710 00:31:39.163260 1423 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 10 00:31:39.163313 kubelet[1423]: I0710 00:31:39.163277 1423 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 10 00:31:39.164157 kubelet[1423]: I0710 00:31:39.163806 1423 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 10 00:31:39.164736 kubelet[1423]: E0710 00:31:39.164716 1423 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Jul 10 00:31:39.164798 kubelet[1423]: E0710 00:31:39.164754 1423 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.58\" not found" Jul 10 00:31:39.215602 kubelet[1423]: I0710 00:31:39.215491 1423 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 10 00:31:39.217598 kubelet[1423]: I0710 00:31:39.216741 1423 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 10 00:31:39.217598 kubelet[1423]: I0710 00:31:39.216768 1423 status_manager.go:227] "Starting to sync pod status with apiserver" Jul 10 00:31:39.217598 kubelet[1423]: I0710 00:31:39.216789 1423 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Jul 10 00:31:39.217598 kubelet[1423]: I0710 00:31:39.216794 1423 kubelet.go:2382] "Starting kubelet main sync loop" Jul 10 00:31:39.217598 kubelet[1423]: E0710 00:31:39.216838 1423 kubelet.go:2406] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Jul 10 00:31:39.264975 kubelet[1423]: I0710 00:31:39.264940 1423 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.58" Jul 10 00:31:39.273352 kubelet[1423]: I0710 00:31:39.273309 1423 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.58" Jul 10 00:31:39.273352 kubelet[1423]: E0710 00:31:39.273348 1423 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.58\": node \"10.0.0.58\" not found" Jul 10 00:31:39.279501 kubelet[1423]: I0710 00:31:39.279456 1423 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 10 00:31:39.279906 env[1220]: time="2025-07-10T00:31:39.279863034Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 10 00:31:39.280100 kubelet[1423]: I0710 00:31:39.280058 1423 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 10 00:31:39.287418 kubelet[1423]: E0710 00:31:39.287386 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.387607 kubelet[1423]: E0710 00:31:39.387576 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.488404 kubelet[1423]: E0710 00:31:39.488308 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.589440 kubelet[1423]: E0710 00:31:39.589392 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.690111 kubelet[1423]: E0710 00:31:39.690073 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.753503 sudo[1318]: pam_unix(sudo:session): session closed for user root Jul 10 00:31:39.755757 sshd[1315]: pam_unix(sshd:session): session closed for user core Jul 10 00:31:39.758995 systemd[1]: sshd@4-10.0.0.58:22-10.0.0.1:52200.service: Deactivated successfully. Jul 10 00:31:39.759715 systemd[1]: session-5.scope: Deactivated successfully. Jul 10 00:31:39.760227 systemd-logind[1209]: Session 5 logged out. Waiting for processes to exit. Jul 10 00:31:39.760865 systemd-logind[1209]: Removed session 5. Jul 10 00:31:39.790708 kubelet[1423]: E0710 00:31:39.790649 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.891317 kubelet[1423]: E0710 00:31:39.891278 1423 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.58\" not found" Jul 10 00:31:39.956105 kubelet[1423]: I0710 00:31:39.956033 1423 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 10 00:31:39.956277 kubelet[1423]: W0710 00:31:39.956246 1423 reflector.go:492] 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 10 00:31:39.956308 kubelet[1423]: W0710 00:31:39.956276 1423 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Node 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 10 00:31:39.956332 kubelet[1423]: W0710 00:31:39.956297 1423 reflector.go:492] 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 10 00:31:40.023932 kubelet[1423]: I0710 00:31:40.023798 1423 apiserver.go:52] "Watching apiserver" Jul 10 00:31:40.024915 kubelet[1423]: E0710 00:31:40.024161 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:40.037714 kubelet[1423]: I0710 00:31:40.037689 1423 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Jul 10 00:31:40.040280 systemd[1]: Created slice kubepods-besteffort-podcccf09c1_d55a_42e3_ac7d_07b0fa142d7e.slice. Jul 10 00:31:40.045131 kubelet[1423]: I0710 00:31:40.045098 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vmw7k\" (UniqueName: \"kubernetes.io/projected/cccf09c1-d55a-42e3-ac7d-07b0fa142d7e-kube-api-access-vmw7k\") pod \"kube-proxy-dqdst\" (UID: \"cccf09c1-d55a-42e3-ac7d-07b0fa142d7e\") " pod="kube-system/kube-proxy-dqdst" Jul 10 00:31:40.045189 kubelet[1423]: I0710 00:31:40.045151 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/ff8709cf-3eda-490a-bc19-2dba8b72e8d1-run\") pod \"kube-flannel-ds-7mhhk\" (UID: \"ff8709cf-3eda-490a-bc19-2dba8b72e8d1\") " pod="kube-flannel/kube-flannel-ds-7mhhk" Jul 10 00:31:40.045189 kubelet[1423]: I0710 00:31:40.045184 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/ff8709cf-3eda-490a-bc19-2dba8b72e8d1-cni-plugin\") pod \"kube-flannel-ds-7mhhk\" (UID: \"ff8709cf-3eda-490a-bc19-2dba8b72e8d1\") " pod="kube-flannel/kube-flannel-ds-7mhhk" Jul 10 00:31:40.045260 kubelet[1423]: I0710 00:31:40.045208 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/ff8709cf-3eda-490a-bc19-2dba8b72e8d1-cni\") pod \"kube-flannel-ds-7mhhk\" (UID: \"ff8709cf-3eda-490a-bc19-2dba8b72e8d1\") " pod="kube-flannel/kube-flannel-ds-7mhhk" Jul 10 00:31:40.045260 kubelet[1423]: I0710 00:31:40.045223 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/ff8709cf-3eda-490a-bc19-2dba8b72e8d1-flannel-cfg\") pod \"kube-flannel-ds-7mhhk\" (UID: \"ff8709cf-3eda-490a-bc19-2dba8b72e8d1\") " pod="kube-flannel/kube-flannel-ds-7mhhk" Jul 10 00:31:40.045260 kubelet[1423]: I0710 00:31:40.045237 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/ff8709cf-3eda-490a-bc19-2dba8b72e8d1-xtables-lock\") pod \"kube-flannel-ds-7mhhk\" (UID: \"ff8709cf-3eda-490a-bc19-2dba8b72e8d1\") " pod="kube-flannel/kube-flannel-ds-7mhhk" Jul 10 00:31:40.045260 kubelet[1423]: I0710 00:31:40.045252 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/cccf09c1-d55a-42e3-ac7d-07b0fa142d7e-kube-proxy\") pod \"kube-proxy-dqdst\" (UID: \"cccf09c1-d55a-42e3-ac7d-07b0fa142d7e\") " pod="kube-system/kube-proxy-dqdst" Jul 10 00:31:40.045340 kubelet[1423]: I0710 00:31:40.045268 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/cccf09c1-d55a-42e3-ac7d-07b0fa142d7e-lib-modules\") pod \"kube-proxy-dqdst\" (UID: \"cccf09c1-d55a-42e3-ac7d-07b0fa142d7e\") " pod="kube-system/kube-proxy-dqdst" Jul 10 00:31:40.045340 kubelet[1423]: I0710 00:31:40.045283 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xkwzc\" (UniqueName: \"kubernetes.io/projected/ff8709cf-3eda-490a-bc19-2dba8b72e8d1-kube-api-access-xkwzc\") pod \"kube-flannel-ds-7mhhk\" (UID: \"ff8709cf-3eda-490a-bc19-2dba8b72e8d1\") " pod="kube-flannel/kube-flannel-ds-7mhhk" Jul 10 00:31:40.045340 kubelet[1423]: I0710 00:31:40.045297 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/cccf09c1-d55a-42e3-ac7d-07b0fa142d7e-xtables-lock\") pod \"kube-proxy-dqdst\" (UID: \"cccf09c1-d55a-42e3-ac7d-07b0fa142d7e\") " pod="kube-system/kube-proxy-dqdst" Jul 10 00:31:40.050227 systemd[1]: Created slice kubepods-burstable-podff8709cf_3eda_490a_bc19_2dba8b72e8d1.slice. Jul 10 00:31:40.155193 kubelet[1423]: I0710 00:31:40.155156 1423 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 10 00:31:40.353053 kubelet[1423]: E0710 00:31:40.351759 1423 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 10 00:31:40.353349 env[1220]: time="2025-07-10T00:31:40.352654434Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-dqdst,Uid:cccf09c1-d55a-42e3-ac7d-07b0fa142d7e,Namespace:kube-system,Attempt:0,}" Jul 10 00:31:40.361671 kubelet[1423]: E0710 00:31:40.361634 1423 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 10 00:31:40.362142 env[1220]: time="2025-07-10T00:31:40.362072634Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7mhhk,Uid:ff8709cf-3eda-490a-bc19-2dba8b72e8d1,Namespace:kube-flannel,Attempt:0,}" Jul 10 00:31:40.909014 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1922938336.mount: Deactivated successfully. Jul 10 00:31:40.914143 env[1220]: time="2025-07-10T00:31:40.914088954Z" 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 10 00:31:40.918395 env[1220]: time="2025-07-10T00:31:40.918336074Z" 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 10 00:31:40.920966 env[1220]: time="2025-07-10T00:31:40.920929314Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:40.921681 env[1220]: time="2025-07-10T00:31:40.921653914Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:40.923892 env[1220]: time="2025-07-10T00:31:40.923852194Z" 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 10 00:31:40.926253 env[1220]: time="2025-07-10T00:31:40.926223154Z" 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 10 00:31:40.927092 env[1220]: time="2025-07-10T00:31:40.927069634Z" 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 10 00:31:40.927805 env[1220]: time="2025-07-10T00:31:40.927782154Z" 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 10 00:31:40.949566 env[1220]: time="2025-07-10T00:31:40.947739074Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 10 00:31:40.949566 env[1220]: time="2025-07-10T00:31:40.947798474Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 10 00:31:40.949566 env[1220]: time="2025-07-10T00:31:40.947809994Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 10 00:31:40.949566 env[1220]: time="2025-07-10T00:31:40.948063994Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/cb2189360807e21a9b7be8375e99e5c41370109cb1abf09d74d81fa3ce8c92b1 pid=1485 runtime=io.containerd.runc.v2 Jul 10 00:31:40.951670 env[1220]: time="2025-07-10T00:31:40.951611274Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 10 00:31:40.951670 env[1220]: time="2025-07-10T00:31:40.951641874Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 10 00:31:40.951670 env[1220]: time="2025-07-10T00:31:40.951658354Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 10 00:31:40.952319 env[1220]: time="2025-07-10T00:31:40.951923314Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034 pid=1486 runtime=io.containerd.runc.v2 Jul 10 00:31:40.979123 systemd[1]: Started cri-containerd-086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034.scope. Jul 10 00:31:40.980286 systemd[1]: Started cri-containerd-cb2189360807e21a9b7be8375e99e5c41370109cb1abf09d74d81fa3ce8c92b1.scope. Jul 10 00:31:41.024649 kubelet[1423]: E0710 00:31:41.024590 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:41.029996 env[1220]: time="2025-07-10T00:31:41.029948914Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7mhhk,Uid:ff8709cf-3eda-490a-bc19-2dba8b72e8d1,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034\"" Jul 10 00:31:41.030987 kubelet[1423]: E0710 00:31:41.030960 1423 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 10 00:31:41.032062 env[1220]: time="2025-07-10T00:31:41.032020754Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 10 00:31:41.034895 env[1220]: time="2025-07-10T00:31:41.034836154Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-dqdst,Uid:cccf09c1-d55a-42e3-ac7d-07b0fa142d7e,Namespace:kube-system,Attempt:0,} returns sandbox id \"cb2189360807e21a9b7be8375e99e5c41370109cb1abf09d74d81fa3ce8c92b1\"" Jul 10 00:31:41.035433 kubelet[1423]: E0710 00:31:41.035375 1423 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 10 00:31:42.024822 kubelet[1423]: E0710 00:31:42.024778 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:42.181153 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3928597742.mount: Deactivated successfully. Jul 10 00:31:42.218333 env[1220]: time="2025-07-10T00:31:42.218275474Z" 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 10 00:31:42.221135 env[1220]: time="2025-07-10T00:31:42.220333354Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:42.222041 env[1220]: time="2025-07-10T00:31:42.222004114Z" 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 10 00:31:42.223658 env[1220]: time="2025-07-10T00:31:42.223569194Z" 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 10 00:31:42.224443 env[1220]: time="2025-07-10T00:31:42.224401914Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 10 00:31:42.226125 env[1220]: time="2025-07-10T00:31:42.226095314Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.6\"" Jul 10 00:31:42.226981 env[1220]: time="2025-07-10T00:31:42.226940634Z" level=info msg="CreateContainer within sandbox \"086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 10 00:31:42.239528 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3351859964.mount: Deactivated successfully. Jul 10 00:31:42.247106 env[1220]: time="2025-07-10T00:31:42.247023754Z" level=info msg="CreateContainer within sandbox \"086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0\"" Jul 10 00:31:42.247721 env[1220]: time="2025-07-10T00:31:42.247680874Z" level=info msg="StartContainer for \"c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0\"" Jul 10 00:31:42.271427 systemd[1]: Started cri-containerd-c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0.scope. Jul 10 00:31:42.311593 env[1220]: time="2025-07-10T00:31:42.310965954Z" level=info msg="StartContainer for \"c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0\" returns successfully" Jul 10 00:31:42.313762 systemd[1]: cri-containerd-c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0.scope: Deactivated successfully. Jul 10 00:31:42.349013 env[1220]: time="2025-07-10T00:31:42.348949274Z" level=info msg="shim disconnected" id=c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0 Jul 10 00:31:42.349616 env[1220]: time="2025-07-10T00:31:42.349554794Z" level=warning msg="cleaning up after shim disconnected" id=c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0 namespace=k8s.io Jul 10 00:31:42.349706 env[1220]: time="2025-07-10T00:31:42.349691714Z" level=info msg="cleaning up dead shim" Jul 10 00:31:42.357302 env[1220]: time="2025-07-10T00:31:42.357271394Z" level=warning msg="cleanup warnings time=\"2025-07-10T00:31:42Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1596 runtime=io.containerd.runc.v2\n" Jul 10 00:31:43.025562 kubelet[1423]: E0710 00:31:43.025527 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:43.181912 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c10054104f34eeb62e8048f5a81f00416be62b492bab99ef9ecb0b338ab42cd0-rootfs.mount: Deactivated successfully. Jul 10 00:31:43.227263 kubelet[1423]: E0710 00:31:43.227234 1423 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 10 00:31:43.259857 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1415391351.mount: Deactivated successfully. Jul 10 00:31:43.738637 env[1220]: time="2025-07-10T00:31:43.738585354Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.32.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:43.740420 env[1220]: time="2025-07-10T00:31:43.740352474Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:43.741550 env[1220]: time="2025-07-10T00:31:43.741519314Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.32.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:43.742737 env[1220]: time="2025-07-10T00:31:43.742686034Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:b13d9da413b983d130bf090b83fce12e1ccc704e95f366da743c18e964d9d7e9,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:43.743820 env[1220]: time="2025-07-10T00:31:43.743758994Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.6\" returns image reference \"sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9\"" Jul 10 00:31:43.745277 env[1220]: time="2025-07-10T00:31:43.745230554Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 10 00:31:43.746185 env[1220]: time="2025-07-10T00:31:43.746154674Z" level=info msg="CreateContainer within sandbox \"cb2189360807e21a9b7be8375e99e5c41370109cb1abf09d74d81fa3ce8c92b1\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 10 00:31:43.762623 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2413119872.mount: Deactivated successfully. Jul 10 00:31:43.771053 env[1220]: time="2025-07-10T00:31:43.770997874Z" level=info msg="CreateContainer within sandbox \"cb2189360807e21a9b7be8375e99e5c41370109cb1abf09d74d81fa3ce8c92b1\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"a49bf4eff6f5d6c30d0070c0d62bc0ba309c68283e8d13f9dea274734ea93dd0\"" Jul 10 00:31:43.771576 env[1220]: time="2025-07-10T00:31:43.771548274Z" level=info msg="StartContainer for \"a49bf4eff6f5d6c30d0070c0d62bc0ba309c68283e8d13f9dea274734ea93dd0\"" Jul 10 00:31:43.790072 systemd[1]: Started cri-containerd-a49bf4eff6f5d6c30d0070c0d62bc0ba309c68283e8d13f9dea274734ea93dd0.scope. Jul 10 00:31:43.835531 env[1220]: time="2025-07-10T00:31:43.835470834Z" level=info msg="StartContainer for \"a49bf4eff6f5d6c30d0070c0d62bc0ba309c68283e8d13f9dea274734ea93dd0\" returns successfully" Jul 10 00:31:44.026593 kubelet[1423]: E0710 00:31:44.026487 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:44.230094 kubelet[1423]: E0710 00:31:44.230068 1423 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 10 00:31:44.972429 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3594839764.mount: Deactivated successfully. Jul 10 00:31:45.026694 kubelet[1423]: E0710 00:31:45.026651 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:45.231889 kubelet[1423]: E0710 00:31:45.231206 1423 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 10 00:31:45.664029 env[1220]: time="2025-07-10T00:31:45.663697794Z" 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 10 00:31:45.665116 env[1220]: time="2025-07-10T00:31:45.665083754Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:45.668134 env[1220]: time="2025-07-10T00:31:45.667361634Z" 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 10 00:31:45.671502 env[1220]: time="2025-07-10T00:31:45.670231674Z" 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 10 00:31:45.671502 env[1220]: time="2025-07-10T00:31:45.670577514Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 10 00:31:45.673019 env[1220]: time="2025-07-10T00:31:45.672989274Z" level=info msg="CreateContainer within sandbox \"086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 10 00:31:45.683271 env[1220]: time="2025-07-10T00:31:45.683214874Z" level=info msg="CreateContainer within sandbox \"086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342\"" Jul 10 00:31:45.683813 env[1220]: time="2025-07-10T00:31:45.683742674Z" level=info msg="StartContainer for \"83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342\"" Jul 10 00:31:45.715464 systemd[1]: Started cri-containerd-83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342.scope. Jul 10 00:31:45.764154 env[1220]: time="2025-07-10T00:31:45.764102434Z" level=info msg="StartContainer for \"83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342\" returns successfully" Jul 10 00:31:45.765398 systemd[1]: cri-containerd-83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342.scope: Deactivated successfully. Jul 10 00:31:45.779649 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342-rootfs.mount: Deactivated successfully. Jul 10 00:31:45.796053 kubelet[1423]: I0710 00:31:45.796013 1423 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Jul 10 00:31:46.004910 env[1220]: time="2025-07-10T00:31:46.003463314Z" level=info msg="shim disconnected" id=83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342 Jul 10 00:31:46.004910 env[1220]: time="2025-07-10T00:31:46.004830954Z" level=warning msg="cleaning up after shim disconnected" id=83ed679a24d9396de93b70623188e1e8a723e0e6de829913c6a6cc212fa80342 namespace=k8s.io Jul 10 00:31:46.004910 env[1220]: time="2025-07-10T00:31:46.004841554Z" level=info msg="cleaning up dead shim" Jul 10 00:31:46.012233 env[1220]: time="2025-07-10T00:31:46.012171594Z" level=warning msg="cleanup warnings time=\"2025-07-10T00:31:46Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1828 runtime=io.containerd.runc.v2\n" Jul 10 00:31:46.027471 kubelet[1423]: E0710 00:31:46.027429 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:46.235024 kubelet[1423]: E0710 00:31:46.234986 1423 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 10 00:31:46.236813 env[1220]: time="2025-07-10T00:31:46.236766874Z" level=info msg="CreateContainer within sandbox \"086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 10 00:31:46.248833 kubelet[1423]: I0710 00:31:46.248165 1423 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-dqdst" podStartSLOduration=4.539909514 podStartE2EDuration="7.248150674s" podCreationTimestamp="2025-07-10 00:31:39 +0000 UTC" firstStartedPulling="2025-07-10 00:31:41.036244394 +0000 UTC m=+2.801790521" lastFinishedPulling="2025-07-10 00:31:43.744485554 +0000 UTC m=+5.510031681" observedRunningTime="2025-07-10 00:31:44.249170914 +0000 UTC m=+6.014717041" watchObservedRunningTime="2025-07-10 00:31:46.248150674 +0000 UTC m=+8.013696761" Jul 10 00:31:46.251826 env[1220]: time="2025-07-10T00:31:46.251789474Z" level=info msg="CreateContainer within sandbox \"086bdd2263655e45d0de13b2695fdb7760ac0aa67ce5e2c49c23d3c17c3d7034\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"6c19f18169350b90cb0d76bd36ba43a878aed1fd888fc3fedd3745403103265f\"" Jul 10 00:31:46.252479 env[1220]: time="2025-07-10T00:31:46.252447074Z" level=info msg="StartContainer for \"6c19f18169350b90cb0d76bd36ba43a878aed1fd888fc3fedd3745403103265f\"" Jul 10 00:31:46.270696 systemd[1]: Started cri-containerd-6c19f18169350b90cb0d76bd36ba43a878aed1fd888fc3fedd3745403103265f.scope. Jul 10 00:31:46.316160 env[1220]: time="2025-07-10T00:31:46.316112834Z" level=info msg="StartContainer for \"6c19f18169350b90cb0d76bd36ba43a878aed1fd888fc3fedd3745403103265f\" returns successfully" Jul 10 00:31:47.028348 kubelet[1423]: E0710 00:31:47.028301 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:47.239423 kubelet[1423]: E0710 00:31:47.239332 1423 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 10 00:31:47.403735 systemd-networkd[1050]: flannel.1: Link UP Jul 10 00:31:47.403743 systemd-networkd[1050]: flannel.1: Gained carrier Jul 10 00:31:48.028780 kubelet[1423]: E0710 00:31:48.028707 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:48.241293 kubelet[1423]: E0710 00:31:48.241250 1423 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 10 00:31:48.510614 systemd-networkd[1050]: flannel.1: Gained IPv6LL Jul 10 00:31:49.029483 kubelet[1423]: E0710 00:31:49.029428 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:50.030526 kubelet[1423]: E0710 00:31:50.030455 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:50.478106 kubelet[1423]: I0710 00:31:50.477782 1423 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7mhhk" podStartSLOduration=6.837503954 podStartE2EDuration="11.477753914s" podCreationTimestamp="2025-07-10 00:31:39 +0000 UTC" firstStartedPulling="2025-07-10 00:31:41.031625874 +0000 UTC m=+2.797172001" lastFinishedPulling="2025-07-10 00:31:45.671875834 +0000 UTC m=+7.437421961" observedRunningTime="2025-07-10 00:31:47.283060994 +0000 UTC m=+9.048607121" watchObservedRunningTime="2025-07-10 00:31:50.477753914 +0000 UTC m=+12.243300001" Jul 10 00:31:50.484591 systemd[1]: Created slice kubepods-besteffort-podd848453b_5a81_42a5_9e1e_30979d1e95b9.slice. Jul 10 00:31:50.516452 kubelet[1423]: I0710 00:31:50.516399 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-j65qn\" (UniqueName: \"kubernetes.io/projected/d848453b-5a81-42a5-9e1e-30979d1e95b9-kube-api-access-j65qn\") pod \"nginx-deployment-7fcdb87857-6tlmc\" (UID: \"d848453b-5a81-42a5-9e1e-30979d1e95b9\") " pod="default/nginx-deployment-7fcdb87857-6tlmc" Jul 10 00:31:50.788082 env[1220]: time="2025-07-10T00:31:50.787680594Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6tlmc,Uid:d848453b-5a81-42a5-9e1e-30979d1e95b9,Namespace:default,Attempt:0,}" Jul 10 00:31:50.814606 systemd-networkd[1050]: cni0: Link UP Jul 10 00:31:50.814612 systemd-networkd[1050]: cni0: Gained carrier Jul 10 00:31:50.815233 systemd-networkd[1050]: cni0: Lost carrier Jul 10 00:31:50.821497 systemd-networkd[1050]: vethea5ae5c0: Link UP Jul 10 00:31:50.823653 kernel: cni0: port 1(vethea5ae5c0) entered blocking state Jul 10 00:31:50.823744 kernel: cni0: port 1(vethea5ae5c0) entered disabled state Jul 10 00:31:50.824414 kernel: device vethea5ae5c0 entered promiscuous mode Jul 10 00:31:50.824486 kernel: cni0: port 1(vethea5ae5c0) entered blocking state Jul 10 00:31:50.826654 kernel: cni0: port 1(vethea5ae5c0) entered forwarding state Jul 10 00:31:50.828422 kernel: cni0: port 1(vethea5ae5c0) entered disabled state Jul 10 00:31:50.838867 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethea5ae5c0: link becomes ready Jul 10 00:31:50.838935 kernel: cni0: port 1(vethea5ae5c0) entered blocking state Jul 10 00:31:50.838957 kernel: cni0: port 1(vethea5ae5c0) entered forwarding state Jul 10 00:31:50.839619 systemd-networkd[1050]: vethea5ae5c0: Gained carrier Jul 10 00:31:50.839881 systemd-networkd[1050]: cni0: Gained carrier Jul 10 00:31:50.841118 env[1220]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001a928), "name":"cbr0", "type":"bridge"} Jul 10 00:31:50.841118 env[1220]: delegateAdd: netconf sent to delegate plugin: Jul 10 00:31:50.859462 env[1220]: {"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-10T00:31:50.859401354Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 10 00:31:50.859571 env[1220]: time="2025-07-10T00:31:50.859444474Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 10 00:31:50.859571 env[1220]: time="2025-07-10T00:31:50.859455194Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 10 00:31:50.859671 env[1220]: time="2025-07-10T00:31:50.859640234Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/84b0c21543046cfd2f770e0bbcb9dad946f3ff2df410339f956444643e2c0311 pid=2013 runtime=io.containerd.runc.v2 Jul 10 00:31:50.871022 systemd[1]: Started cri-containerd-84b0c21543046cfd2f770e0bbcb9dad946f3ff2df410339f956444643e2c0311.scope. Jul 10 00:31:50.891356 systemd-resolved[1160]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 10 00:31:50.907432 env[1220]: time="2025-07-10T00:31:50.907395634Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6tlmc,Uid:d848453b-5a81-42a5-9e1e-30979d1e95b9,Namespace:default,Attempt:0,} returns sandbox id \"84b0c21543046cfd2f770e0bbcb9dad946f3ff2df410339f956444643e2c0311\"" Jul 10 00:31:50.908336 env[1220]: time="2025-07-10T00:31:50.908293114Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 10 00:31:51.031628 kubelet[1423]: E0710 00:31:51.031582 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:52.032388 kubelet[1423]: E0710 00:31:52.032337 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:52.478530 systemd-networkd[1050]: vethea5ae5c0: Gained IPv6LL Jul 10 00:31:52.734557 systemd-networkd[1050]: cni0: Gained IPv6LL Jul 10 00:31:52.765560 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3510344237.mount: Deactivated successfully. Jul 10 00:31:53.033183 kubelet[1423]: E0710 00:31:53.032782 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:54.001423 env[1220]: time="2025-07-10T00:31:54.001358114Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:54.002798 env[1220]: time="2025-07-10T00:31:54.002772874Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:54.004750 env[1220]: time="2025-07-10T00:31:54.004723554Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:31:54.006408 env[1220]: time="2025-07-10T00:31:54.006364514Z" 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 10 00:31:54.007166 env[1220]: time="2025-07-10T00:31:54.007137554Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 10 00:31:54.009648 env[1220]: time="2025-07-10T00:31:54.009618634Z" level=info msg="CreateContainer within sandbox \"84b0c21543046cfd2f770e0bbcb9dad946f3ff2df410339f956444643e2c0311\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 10 00:31:54.020721 env[1220]: time="2025-07-10T00:31:54.020676994Z" level=info msg="CreateContainer within sandbox \"84b0c21543046cfd2f770e0bbcb9dad946f3ff2df410339f956444643e2c0311\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"628dea4b4fd553faebbd40513d58b20678fb82c8cc5666dd4f420c23e6914569\"" Jul 10 00:31:54.021127 env[1220]: time="2025-07-10T00:31:54.021091954Z" level=info msg="StartContainer for \"628dea4b4fd553faebbd40513d58b20678fb82c8cc5666dd4f420c23e6914569\"" Jul 10 00:31:54.035225 kubelet[1423]: E0710 00:31:54.033826 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:54.035559 systemd[1]: run-containerd-runc-k8s.io-628dea4b4fd553faebbd40513d58b20678fb82c8cc5666dd4f420c23e6914569-runc.8D5XkT.mount: Deactivated successfully. Jul 10 00:31:54.037773 systemd[1]: Started cri-containerd-628dea4b4fd553faebbd40513d58b20678fb82c8cc5666dd4f420c23e6914569.scope. Jul 10 00:31:54.071686 env[1220]: time="2025-07-10T00:31:54.071646114Z" level=info msg="StartContainer for \"628dea4b4fd553faebbd40513d58b20678fb82c8cc5666dd4f420c23e6914569\" returns successfully" Jul 10 00:31:54.260483 kubelet[1423]: I0710 00:31:54.259852 1423 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-6tlmc" podStartSLOduration=1.159357994 podStartE2EDuration="4.259835394s" podCreationTimestamp="2025-07-10 00:31:50 +0000 UTC" firstStartedPulling="2025-07-10 00:31:50.908069034 +0000 UTC m=+12.673615121" lastFinishedPulling="2025-07-10 00:31:54.008546354 +0000 UTC m=+15.774092521" observedRunningTime="2025-07-10 00:31:54.259788474 +0000 UTC m=+16.025334601" watchObservedRunningTime="2025-07-10 00:31:54.259835394 +0000 UTC m=+16.025381521" Jul 10 00:31:55.034443 kubelet[1423]: E0710 00:31:55.034375 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:56.034985 kubelet[1423]: E0710 00:31:56.034946 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:56.781321 systemd[1]: Created slice kubepods-besteffort-pod1b7a5253_a361_4488_8a46_c60a62dea528.slice. Jul 10 00:31:56.850062 kubelet[1423]: I0710 00:31:56.850006 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1b7a5253-a361-4488-8a46-c60a62dea528-data\") pod \"nfs-server-provisioner-0\" (UID: \"1b7a5253-a361-4488-8a46-c60a62dea528\") " pod="default/nfs-server-provisioner-0" Jul 10 00:31:56.850062 kubelet[1423]: I0710 00:31:56.850055 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6j4f8\" (UniqueName: \"kubernetes.io/projected/1b7a5253-a361-4488-8a46-c60a62dea528-kube-api-access-6j4f8\") pod \"nfs-server-provisioner-0\" (UID: \"1b7a5253-a361-4488-8a46-c60a62dea528\") " pod="default/nfs-server-provisioner-0" Jul 10 00:31:57.035864 kubelet[1423]: E0710 00:31:57.035756 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:57.084881 env[1220]: time="2025-07-10T00:31:57.084827634Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1b7a5253-a361-4488-8a46-c60a62dea528,Namespace:default,Attempt:0,}" Jul 10 00:31:57.111427 systemd-networkd[1050]: veth9283465d: Link UP Jul 10 00:31:57.117867 kernel: cni0: port 2(veth9283465d) entered blocking state Jul 10 00:31:57.117961 kernel: cni0: port 2(veth9283465d) entered disabled state Jul 10 00:31:57.117990 kernel: device veth9283465d entered promiscuous mode Jul 10 00:31:57.124456 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 10 00:31:57.124557 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth9283465d: link becomes ready Jul 10 00:31:57.124580 kernel: cni0: port 2(veth9283465d) entered blocking state Jul 10 00:31:57.125900 kernel: cni0: port 2(veth9283465d) entered forwarding state Jul 10 00:31:57.126079 systemd-networkd[1050]: veth9283465d: Gained carrier Jul 10 00:31:57.127782 env[1220]: 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)(0x4000014928), "name":"cbr0", "type":"bridge"} Jul 10 00:31:57.127782 env[1220]: delegateAdd: netconf sent to delegate plugin: Jul 10 00:31:57.140400 env[1220]: {"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-10T00:31:57.140311194Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 10 00:31:57.140558 env[1220]: time="2025-07-10T00:31:57.140354914Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 10 00:31:57.140558 env[1220]: time="2025-07-10T00:31:57.140366474Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 10 00:31:57.140764 env[1220]: time="2025-07-10T00:31:57.140728474Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/7e931991db57682f758849eb51c710ae2961f3099c4b48659d898469b13afad2 pid=2180 runtime=io.containerd.runc.v2 Jul 10 00:31:57.154674 systemd[1]: Started cri-containerd-7e931991db57682f758849eb51c710ae2961f3099c4b48659d898469b13afad2.scope. Jul 10 00:31:57.185088 systemd-resolved[1160]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 10 00:31:57.199826 env[1220]: time="2025-07-10T00:31:57.199772314Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1b7a5253-a361-4488-8a46-c60a62dea528,Namespace:default,Attempt:0,} returns sandbox id \"7e931991db57682f758849eb51c710ae2961f3099c4b48659d898469b13afad2\"" Jul 10 00:31:57.201330 env[1220]: time="2025-07-10T00:31:57.201295314Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 10 00:31:58.036906 kubelet[1423]: E0710 00:31:58.036854 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:58.430629 systemd-networkd[1050]: veth9283465d: Gained IPv6LL Jul 10 00:31:59.023851 kubelet[1423]: E0710 00:31:59.023810 1423 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:59.037233 kubelet[1423]: E0710 00:31:59.037192 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:31:59.264208 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount91293672.mount: Deactivated successfully. Jul 10 00:32:00.038897 kubelet[1423]: E0710 00:32:00.038838 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:01.039840 kubelet[1423]: E0710 00:32:01.039793 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:01.064739 env[1220]: time="2025-07-10T00:32:01.064684594Z" 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 10 00:32:01.067196 env[1220]: time="2025-07-10T00:32:01.067150474Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:32:01.068729 env[1220]: time="2025-07-10T00:32:01.068697234Z" 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 10 00:32:01.070526 env[1220]: time="2025-07-10T00:32:01.070487794Z" 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 10 00:32:01.071416 env[1220]: time="2025-07-10T00:32:01.071354794Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 10 00:32:01.073961 env[1220]: time="2025-07-10T00:32:01.073912154Z" level=info msg="CreateContainer within sandbox \"7e931991db57682f758849eb51c710ae2961f3099c4b48659d898469b13afad2\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 10 00:32:01.089756 env[1220]: time="2025-07-10T00:32:01.089699874Z" level=info msg="CreateContainer within sandbox \"7e931991db57682f758849eb51c710ae2961f3099c4b48659d898469b13afad2\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"61eacecfb0fbae9595a1baa9dfee2489f26ca14c1eff8642eaffc222d4960ce2\"" Jul 10 00:32:01.090180 env[1220]: time="2025-07-10T00:32:01.090148834Z" level=info msg="StartContainer for \"61eacecfb0fbae9595a1baa9dfee2489f26ca14c1eff8642eaffc222d4960ce2\"" Jul 10 00:32:01.105676 systemd[1]: Started cri-containerd-61eacecfb0fbae9595a1baa9dfee2489f26ca14c1eff8642eaffc222d4960ce2.scope. Jul 10 00:32:01.139655 env[1220]: time="2025-07-10T00:32:01.139595114Z" level=info msg="StartContainer for \"61eacecfb0fbae9595a1baa9dfee2489f26ca14c1eff8642eaffc222d4960ce2\" returns successfully" Jul 10 00:32:01.276400 kubelet[1423]: I0710 00:32:01.276331 1423 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.404709874 podStartE2EDuration="5.276311474s" podCreationTimestamp="2025-07-10 00:31:56 +0000 UTC" firstStartedPulling="2025-07-10 00:31:57.201040114 +0000 UTC m=+18.966586241" lastFinishedPulling="2025-07-10 00:32:01.072641714 +0000 UTC m=+22.838187841" observedRunningTime="2025-07-10 00:32:01.276139874 +0000 UTC m=+23.041686001" watchObservedRunningTime="2025-07-10 00:32:01.276311474 +0000 UTC m=+23.041857601" Jul 10 00:32:02.040550 kubelet[1423]: E0710 00:32:02.040496 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:03.041102 kubelet[1423]: E0710 00:32:03.041047 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:04.041891 kubelet[1423]: E0710 00:32:04.041834 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:05.042679 kubelet[1423]: E0710 00:32:05.042637 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:06.043098 kubelet[1423]: E0710 00:32:06.043058 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:07.044183 kubelet[1423]: E0710 00:32:07.044139 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:08.044454 kubelet[1423]: E0710 00:32:08.044401 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:09.044581 kubelet[1423]: E0710 00:32:09.044542 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:10.045459 kubelet[1423]: E0710 00:32:10.045417 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:11.045961 kubelet[1423]: E0710 00:32:11.045924 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:11.251013 systemd[1]: Created slice kubepods-besteffort-pod94a73821_13d8_4fdf_8451_05deae509a16.slice. Jul 10 00:32:11.329838 kubelet[1423]: I0710 00:32:11.328608 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-bc9e2534-1dbc-42d7-b437-2103d6eb0747\" (UniqueName: \"kubernetes.io/nfs/94a73821-13d8-4fdf-8451-05deae509a16-pvc-bc9e2534-1dbc-42d7-b437-2103d6eb0747\") pod \"test-pod-1\" (UID: \"94a73821-13d8-4fdf-8451-05deae509a16\") " pod="default/test-pod-1" Jul 10 00:32:11.329838 kubelet[1423]: I0710 00:32:11.329280 1423 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-974kx\" (UniqueName: \"kubernetes.io/projected/94a73821-13d8-4fdf-8451-05deae509a16-kube-api-access-974kx\") pod \"test-pod-1\" (UID: \"94a73821-13d8-4fdf-8451-05deae509a16\") " pod="default/test-pod-1" Jul 10 00:32:11.457630 kernel: FS-Cache: Loaded Jul 10 00:32:11.487634 kernel: RPC: Registered named UNIX socket transport module. Jul 10 00:32:11.487752 kernel: RPC: Registered udp transport module. Jul 10 00:32:11.487778 kernel: RPC: Registered tcp transport module. Jul 10 00:32:11.488923 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 10 00:32:11.539451 kernel: FS-Cache: Netfs 'nfs' registered for caching Jul 10 00:32:11.673453 kernel: NFS: Registering the id_resolver key type Jul 10 00:32:11.673578 kernel: Key type id_resolver registered Jul 10 00:32:11.673602 kernel: Key type id_legacy registered Jul 10 00:32:11.714552 nfsidmap[2364]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 10 00:32:11.717868 nfsidmap[2367]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 10 00:32:11.854769 env[1220]: time="2025-07-10T00:32:11.854709850Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:94a73821-13d8-4fdf-8451-05deae509a16,Namespace:default,Attempt:0,}" Jul 10 00:32:11.871539 systemd-networkd[1050]: veth06936c7e: Link UP Jul 10 00:32:11.873860 kernel: cni0: port 3(veth06936c7e) entered blocking state Jul 10 00:32:11.873930 kernel: cni0: port 3(veth06936c7e) entered disabled state Jul 10 00:32:11.873954 kernel: device veth06936c7e entered promiscuous mode Jul 10 00:32:11.883042 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 10 00:32:11.883157 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth06936c7e: link becomes ready Jul 10 00:32:11.883182 kernel: cni0: port 3(veth06936c7e) entered blocking state Jul 10 00:32:11.883786 kernel: cni0: port 3(veth06936c7e) entered forwarding state Jul 10 00:32:11.884668 systemd-networkd[1050]: veth06936c7e: Gained carrier Jul 10 00:32:11.886349 env[1220]: 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)(0x40000928e8), "name":"cbr0", "type":"bridge"} Jul 10 00:32:11.886349 env[1220]: delegateAdd: netconf sent to delegate plugin: Jul 10 00:32:11.899354 env[1220]: {"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-10T00:32:11.899274651Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 10 00:32:11.899354 env[1220]: time="2025-07-10T00:32:11.899318451Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 10 00:32:11.899354 env[1220]: time="2025-07-10T00:32:11.899337131Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 10 00:32:11.899794 env[1220]: time="2025-07-10T00:32:11.899734292Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/3bdf1ee2ebf3c289e876b25767cd1d260bcc6ec38806c32d2a53c28b51f89b31 pid=2416 runtime=io.containerd.runc.v2 Jul 10 00:32:11.911461 systemd[1]: Started cri-containerd-3bdf1ee2ebf3c289e876b25767cd1d260bcc6ec38806c32d2a53c28b51f89b31.scope. Jul 10 00:32:11.936116 systemd-resolved[1160]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 10 00:32:11.953043 env[1220]: time="2025-07-10T00:32:11.952975028Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:94a73821-13d8-4fdf-8451-05deae509a16,Namespace:default,Attempt:0,} returns sandbox id \"3bdf1ee2ebf3c289e876b25767cd1d260bcc6ec38806c32d2a53c28b51f89b31\"" Jul 10 00:32:11.954235 env[1220]: time="2025-07-10T00:32:11.954099630Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 10 00:32:12.047185 kubelet[1423]: E0710 00:32:12.047120 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:12.188150 env[1220]: time="2025-07-10T00:32:12.187758792Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:32:12.189287 env[1220]: time="2025-07-10T00:32:12.189228435Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:32:12.191901 env[1220]: time="2025-07-10T00:32:12.191867079Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Jul 10 00:32:12.193858 env[1220]: time="2025-07-10T00:32:12.193822443Z" 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 10 00:32:12.194713 env[1220]: time="2025-07-10T00:32:12.194671244Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 10 00:32:12.197804 env[1220]: time="2025-07-10T00:32:12.197660409Z" level=info msg="CreateContainer within sandbox \"3bdf1ee2ebf3c289e876b25767cd1d260bcc6ec38806c32d2a53c28b51f89b31\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 10 00:32:12.208572 env[1220]: time="2025-07-10T00:32:12.208512547Z" level=info msg="CreateContainer within sandbox \"3bdf1ee2ebf3c289e876b25767cd1d260bcc6ec38806c32d2a53c28b51f89b31\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"734959b26ae040cbc087cc9f6ad1ce614283cb40d471830db189a123f04654fa\"" Jul 10 00:32:12.209133 env[1220]: time="2025-07-10T00:32:12.209106708Z" level=info msg="StartContainer for \"734959b26ae040cbc087cc9f6ad1ce614283cb40d471830db189a123f04654fa\"" Jul 10 00:32:12.224066 systemd[1]: Started cri-containerd-734959b26ae040cbc087cc9f6ad1ce614283cb40d471830db189a123f04654fa.scope. Jul 10 00:32:12.270324 env[1220]: time="2025-07-10T00:32:12.270265372Z" level=info msg="StartContainer for \"734959b26ae040cbc087cc9f6ad1ce614283cb40d471830db189a123f04654fa\" returns successfully" Jul 10 00:32:13.047503 kubelet[1423]: E0710 00:32:13.047438 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 10 00:32:13.854595 systemd-networkd[1050]: veth06936c7e: Gained IPv6LL Jul 10 00:32:14.048338 kubelet[1423]: E0710 00:32:14.048274 1423 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"