Mar 17 18:10:52.762086 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Mar 17 18:10:52.762104 kernel: Linux version 5.15.179-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Mon Mar 17 17:11:44 -00 2025 Mar 17 18:10:52.762112 kernel: efi: EFI v2.70 by EDK II Mar 17 18:10:52.762118 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Mar 17 18:10:52.762123 kernel: random: crng init done Mar 17 18:10:52.762128 kernel: ACPI: Early table checksum verification disabled Mar 17 18:10:52.762134 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Mar 17 18:10:52.762141 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Mar 17 18:10:52.762146 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762151 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762157 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762162 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762167 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762173 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762181 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762186 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762192 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:10:52.762198 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Mar 17 18:10:52.762204 kernel: NUMA: Failed to initialise from firmware Mar 17 18:10:52.762209 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Mar 17 18:10:52.762225 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Mar 17 18:10:52.762230 kernel: Zone ranges: Mar 17 18:10:52.762236 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Mar 17 18:10:52.762243 kernel: DMA32 empty Mar 17 18:10:52.762249 kernel: Normal empty Mar 17 18:10:52.762254 kernel: Movable zone start for each node Mar 17 18:10:52.762260 kernel: Early memory node ranges Mar 17 18:10:52.762265 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Mar 17 18:10:52.762271 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Mar 17 18:10:52.762277 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Mar 17 18:10:52.762282 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Mar 17 18:10:52.762288 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Mar 17 18:10:52.762293 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Mar 17 18:10:52.762299 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Mar 17 18:10:52.762305 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Mar 17 18:10:52.762311 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Mar 17 18:10:52.762317 kernel: psci: probing for conduit method from ACPI. Mar 17 18:10:52.762322 kernel: psci: PSCIv1.1 detected in firmware. Mar 17 18:10:52.762328 kernel: psci: Using standard PSCI v0.2 function IDs Mar 17 18:10:52.762334 kernel: psci: Trusted OS migration not required Mar 17 18:10:52.762342 kernel: psci: SMC Calling Convention v1.1 Mar 17 18:10:52.762348 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Mar 17 18:10:52.762368 kernel: ACPI: SRAT not present Mar 17 18:10:52.762375 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Mar 17 18:10:52.762381 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Mar 17 18:10:52.762387 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 17 18:10:52.762393 kernel: Detected PIPT I-cache on CPU0 Mar 17 18:10:52.762399 kernel: CPU features: detected: GIC system register CPU interface Mar 17 18:10:52.762405 kernel: CPU features: detected: Hardware dirty bit management Mar 17 18:10:52.762411 kernel: CPU features: detected: Spectre-v4 Mar 17 18:10:52.762417 kernel: CPU features: detected: Spectre-BHB Mar 17 18:10:52.762424 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 17 18:10:52.762430 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 17 18:10:52.762436 kernel: CPU features: detected: ARM erratum 1418040 Mar 17 18:10:52.762442 kernel: CPU features: detected: SSBS not fully self-synchronizing Mar 17 18:10:52.762448 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Mar 17 18:10:52.762454 kernel: Policy zone: DMA Mar 17 18:10:52.762462 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=e034db32d58fe7496a3db6ba3879dd9052cea2cf1597d65edfc7b26afc92530d Mar 17 18:10:52.762468 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 18:10:52.762474 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 18:10:52.762480 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 18:10:52.762486 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 18:10:52.762493 kernel: Memory: 2457404K/2572288K available (9792K kernel code, 2094K rwdata, 7584K rodata, 36416K init, 777K bss, 114884K reserved, 0K cma-reserved) Mar 17 18:10:52.762500 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 17 18:10:52.762506 kernel: trace event string verifier disabled Mar 17 18:10:52.762511 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 18:10:52.762518 kernel: rcu: RCU event tracing is enabled. Mar 17 18:10:52.762524 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 17 18:10:52.762530 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 18:10:52.762536 kernel: Tracing variant of Tasks RCU enabled. Mar 17 18:10:52.762542 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 18:10:52.762548 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 17 18:10:52.762554 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 17 18:10:52.762561 kernel: GICv3: 256 SPIs implemented Mar 17 18:10:52.762568 kernel: GICv3: 0 Extended SPIs implemented Mar 17 18:10:52.762574 kernel: GICv3: Distributor has no Range Selector support Mar 17 18:10:52.762579 kernel: Root IRQ handler: gic_handle_irq Mar 17 18:10:52.762585 kernel: GICv3: 16 PPIs implemented Mar 17 18:10:52.762591 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Mar 17 18:10:52.762597 kernel: ACPI: SRAT not present Mar 17 18:10:52.762603 kernel: ITS [mem 0x08080000-0x0809ffff] Mar 17 18:10:52.762609 kernel: ITS@0x0000000008080000: allocated 8192 Devices @43cb0000 (indirect, esz 8, psz 64K, shr 1) Mar 17 18:10:52.762615 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @43cc0000 (flat, esz 8, psz 64K, shr 1) Mar 17 18:10:52.762621 kernel: GICv3: using LPI property table @0x0000000043cd0000 Mar 17 18:10:52.762627 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000043ce0000 Mar 17 18:10:52.762635 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:10:52.762641 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Mar 17 18:10:52.762647 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Mar 17 18:10:52.762653 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Mar 17 18:10:52.762659 kernel: arm-pv: using stolen time PV Mar 17 18:10:52.762665 kernel: Console: colour dummy device 80x25 Mar 17 18:10:52.762671 kernel: ACPI: Core revision 20210730 Mar 17 18:10:52.762678 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Mar 17 18:10:52.762684 kernel: pid_max: default: 32768 minimum: 301 Mar 17 18:10:52.762690 kernel: LSM: Security Framework initializing Mar 17 18:10:52.762698 kernel: SELinux: Initializing. Mar 17 18:10:52.762704 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:10:52.762710 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:10:52.762716 kernel: rcu: Hierarchical SRCU implementation. Mar 17 18:10:52.762722 kernel: Platform MSI: ITS@0x8080000 domain created Mar 17 18:10:52.762728 kernel: PCI/MSI: ITS@0x8080000 domain created Mar 17 18:10:52.762734 kernel: Remapping and enabling EFI services. Mar 17 18:10:52.762740 kernel: smp: Bringing up secondary CPUs ... Mar 17 18:10:52.762747 kernel: Detected PIPT I-cache on CPU1 Mar 17 18:10:52.762754 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Mar 17 18:10:52.762760 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000043cf0000 Mar 17 18:10:52.762767 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:10:52.762773 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Mar 17 18:10:52.762779 kernel: Detected PIPT I-cache on CPU2 Mar 17 18:10:52.762785 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Mar 17 18:10:52.762791 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000043d00000 Mar 17 18:10:52.762797 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:10:52.762803 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Mar 17 18:10:52.762809 kernel: Detected PIPT I-cache on CPU3 Mar 17 18:10:52.762817 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Mar 17 18:10:52.762823 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000043d10000 Mar 17 18:10:52.762829 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:10:52.762835 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Mar 17 18:10:52.762845 kernel: smp: Brought up 1 node, 4 CPUs Mar 17 18:10:52.762853 kernel: SMP: Total of 4 processors activated. Mar 17 18:10:52.762859 kernel: CPU features: detected: 32-bit EL0 Support Mar 17 18:10:52.762866 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 17 18:10:52.762872 kernel: CPU features: detected: Common not Private translations Mar 17 18:10:52.762879 kernel: CPU features: detected: CRC32 instructions Mar 17 18:10:52.762885 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Mar 17 18:10:52.762892 kernel: CPU features: detected: LSE atomic instructions Mar 17 18:10:52.762899 kernel: CPU features: detected: Privileged Access Never Mar 17 18:10:52.762906 kernel: CPU features: detected: RAS Extension Support Mar 17 18:10:52.762912 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Mar 17 18:10:52.762919 kernel: CPU: All CPU(s) started at EL1 Mar 17 18:10:52.762925 kernel: alternatives: patching kernel code Mar 17 18:10:52.762933 kernel: devtmpfs: initialized Mar 17 18:10:52.762939 kernel: KASLR enabled Mar 17 18:10:52.762946 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 18:10:52.762952 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 17 18:10:52.762959 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 18:10:52.762965 kernel: SMBIOS 3.0.0 present. Mar 17 18:10:52.762972 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Mar 17 18:10:52.762978 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 18:10:52.762985 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 17 18:10:52.762992 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 17 18:10:52.762999 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 17 18:10:52.763005 kernel: audit: initializing netlink subsys (disabled) Mar 17 18:10:52.763012 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Mar 17 18:10:52.763018 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 18:10:52.763025 kernel: cpuidle: using governor menu Mar 17 18:10:52.763031 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 17 18:10:52.763037 kernel: ASID allocator initialised with 32768 entries Mar 17 18:10:52.763044 kernel: ACPI: bus type PCI registered Mar 17 18:10:52.763056 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 18:10:52.763064 kernel: Serial: AMBA PL011 UART driver Mar 17 18:10:52.763070 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 18:10:52.763077 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Mar 17 18:10:52.763084 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 18:10:52.763090 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Mar 17 18:10:52.763097 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 18:10:52.763103 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Mar 17 18:10:52.763110 kernel: ACPI: Added _OSI(Module Device) Mar 17 18:10:52.763118 kernel: ACPI: Added _OSI(Processor Device) Mar 17 18:10:52.763124 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 18:10:52.763131 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 18:10:52.763137 kernel: ACPI: Added _OSI(Linux-Dell-Video) Mar 17 18:10:52.763144 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Mar 17 18:10:52.763150 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Mar 17 18:10:52.763157 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 18:10:52.763163 kernel: ACPI: Interpreter enabled Mar 17 18:10:52.763170 kernel: ACPI: Using GIC for interrupt routing Mar 17 18:10:52.763178 kernel: ACPI: MCFG table detected, 1 entries Mar 17 18:10:52.763184 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Mar 17 18:10:52.763191 kernel: printk: console [ttyAMA0] enabled Mar 17 18:10:52.763197 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 17 18:10:52.763360 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 17 18:10:52.763425 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Mar 17 18:10:52.763482 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Mar 17 18:10:52.763542 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Mar 17 18:10:52.763597 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Mar 17 18:10:52.763606 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Mar 17 18:10:52.763612 kernel: PCI host bridge to bus 0000:00 Mar 17 18:10:52.763675 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Mar 17 18:10:52.763726 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Mar 17 18:10:52.763779 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Mar 17 18:10:52.763828 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 17 18:10:52.763901 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Mar 17 18:10:52.763973 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Mar 17 18:10:52.764032 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Mar 17 18:10:52.764101 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Mar 17 18:10:52.764161 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Mar 17 18:10:52.768878 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Mar 17 18:10:52.769014 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Mar 17 18:10:52.769092 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Mar 17 18:10:52.769154 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Mar 17 18:10:52.769271 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Mar 17 18:10:52.769332 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Mar 17 18:10:52.769342 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Mar 17 18:10:52.769349 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Mar 17 18:10:52.769356 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Mar 17 18:10:52.769367 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Mar 17 18:10:52.769374 kernel: iommu: Default domain type: Translated Mar 17 18:10:52.769381 kernel: iommu: DMA domain TLB invalidation policy: strict mode Mar 17 18:10:52.769387 kernel: vgaarb: loaded Mar 17 18:10:52.769394 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 17 18:10:52.769401 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 17 18:10:52.769407 kernel: PTP clock support registered Mar 17 18:10:52.769414 kernel: Registered efivars operations Mar 17 18:10:52.769420 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 17 18:10:52.769428 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 18:10:52.769435 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 18:10:52.769441 kernel: pnp: PnP ACPI init Mar 17 18:10:52.769506 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Mar 17 18:10:52.769516 kernel: pnp: PnP ACPI: found 1 devices Mar 17 18:10:52.769523 kernel: NET: Registered PF_INET protocol family Mar 17 18:10:52.769530 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 17 18:10:52.769536 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 17 18:10:52.769545 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 18:10:52.769551 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 17 18:10:52.769559 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Mar 17 18:10:52.769565 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 17 18:10:52.769572 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:10:52.769579 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:10:52.769586 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 18:10:52.769592 kernel: PCI: CLS 0 bytes, default 64 Mar 17 18:10:52.769599 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Mar 17 18:10:52.769607 kernel: kvm [1]: HYP mode not available Mar 17 18:10:52.769613 kernel: Initialise system trusted keyrings Mar 17 18:10:52.769620 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 17 18:10:52.769627 kernel: Key type asymmetric registered Mar 17 18:10:52.769633 kernel: Asymmetric key parser 'x509' registered Mar 17 18:10:52.769640 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 17 18:10:52.769646 kernel: io scheduler mq-deadline registered Mar 17 18:10:52.769653 kernel: io scheduler kyber registered Mar 17 18:10:52.769660 kernel: io scheduler bfq registered Mar 17 18:10:52.769668 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Mar 17 18:10:52.769674 kernel: ACPI: button: Power Button [PWRB] Mar 17 18:10:52.769682 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Mar 17 18:10:52.769739 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Mar 17 18:10:52.769749 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 18:10:52.769755 kernel: thunder_xcv, ver 1.0 Mar 17 18:10:52.769762 kernel: thunder_bgx, ver 1.0 Mar 17 18:10:52.769768 kernel: nicpf, ver 1.0 Mar 17 18:10:52.769779 kernel: nicvf, ver 1.0 Mar 17 18:10:52.769848 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 17 18:10:52.769908 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-03-17T18:10:52 UTC (1742235052) Mar 17 18:10:52.769918 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 18:10:52.769924 kernel: NET: Registered PF_INET6 protocol family Mar 17 18:10:52.769931 kernel: Segment Routing with IPv6 Mar 17 18:10:52.769940 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 18:10:52.769947 kernel: NET: Registered PF_PACKET protocol family Mar 17 18:10:52.769954 kernel: Key type dns_resolver registered Mar 17 18:10:52.769962 kernel: registered taskstats version 1 Mar 17 18:10:52.769971 kernel: Loading compiled-in X.509 certificates Mar 17 18:10:52.769977 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.179-flatcar: c6f3fb83dc6bb7052b07ec5b1ef41d12f9b3f7e4' Mar 17 18:10:52.769984 kernel: Key type .fscrypt registered Mar 17 18:10:52.769993 kernel: Key type fscrypt-provisioning registered Mar 17 18:10:52.769999 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 18:10:52.770006 kernel: ima: Allocated hash algorithm: sha1 Mar 17 18:10:52.770013 kernel: ima: No architecture policies found Mar 17 18:10:52.770019 kernel: clk: Disabling unused clocks Mar 17 18:10:52.770029 kernel: Freeing unused kernel memory: 36416K Mar 17 18:10:52.770036 kernel: Run /init as init process Mar 17 18:10:52.770042 kernel: with arguments: Mar 17 18:10:52.770049 kernel: /init Mar 17 18:10:52.770062 kernel: with environment: Mar 17 18:10:52.770069 kernel: HOME=/ Mar 17 18:10:52.770078 kernel: TERM=linux Mar 17 18:10:52.770084 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 18:10:52.770093 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) Mar 17 18:10:52.770103 systemd[1]: Detected virtualization kvm. Mar 17 18:10:52.770113 systemd[1]: Detected architecture arm64. Mar 17 18:10:52.770120 systemd[1]: Running in initrd. Mar 17 18:10:52.770127 systemd[1]: No hostname configured, using default hostname. Mar 17 18:10:52.770134 systemd[1]: Hostname set to . Mar 17 18:10:52.770143 systemd[1]: Initializing machine ID from VM UUID. Mar 17 18:10:52.770150 systemd[1]: Queued start job for default target initrd.target. Mar 17 18:10:52.770160 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:10:52.770168 systemd[1]: Reached target cryptsetup.target. Mar 17 18:10:52.770175 systemd[1]: Reached target paths.target. Mar 17 18:10:52.770181 systemd[1]: Reached target slices.target. Mar 17 18:10:52.770190 systemd[1]: Reached target swap.target. Mar 17 18:10:52.770197 systemd[1]: Reached target timers.target. Mar 17 18:10:52.770205 systemd[1]: Listening on iscsid.socket. Mar 17 18:10:52.770220 systemd[1]: Listening on iscsiuio.socket. Mar 17 18:10:52.770228 systemd[1]: Listening on systemd-journald-audit.socket. Mar 17 18:10:52.770235 systemd[1]: Listening on systemd-journald-dev-log.socket. Mar 17 18:10:52.770242 systemd[1]: Listening on systemd-journald.socket. Mar 17 18:10:52.770249 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:10:52.770256 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:10:52.770264 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:10:52.770271 systemd[1]: Reached target sockets.target. Mar 17 18:10:52.770278 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:10:52.770287 systemd[1]: Finished network-cleanup.service. Mar 17 18:10:52.770294 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 18:10:52.770301 systemd[1]: Starting systemd-journald.service... Mar 17 18:10:52.770308 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:10:52.770315 systemd[1]: Starting systemd-resolved.service... Mar 17 18:10:52.770322 systemd[1]: Starting systemd-vconsole-setup.service... Mar 17 18:10:52.770329 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:10:52.770338 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 18:10:52.770348 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Mar 17 18:10:52.770368 systemd[1]: Finished systemd-vconsole-setup.service. Mar 17 18:10:52.770375 systemd[1]: Starting dracut-cmdline-ask.service... Mar 17 18:10:52.770382 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Mar 17 18:10:52.770390 kernel: audit: type=1130 audit(1742235052.764:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:10:52.770400 systemd-journald[290]: Journal started Mar 17 18:10:52.770450 systemd-journald[290]: Runtime Journal (/run/log/journal/c37807ad462d44999dd3e677a404ba8c) is 6.0M, max 48.7M, 42.6M free. Mar 17 18:10:52.764000 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' Mar 17 18:10:52.757791 systemd-modules-load[291]: Inserted module 'overlay' Mar 17 18:10:52.772720 systemd[1]: Started systemd-journald.service. Mar 17 18:10:52.773000 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' Mar 17 18:10:52.776240 kernel: audit: type=1130 audit(1742235052.773:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:10:52.782119 systemd[1]: Finished dracut-cmdline-ask.service. Mar 17 18:10:52.786097 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 18:10:52.786124 kernel: audit: type=1130 audit(1742235052.782: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' Mar 17 18:10:52.782000 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' Mar 17 18:10:52.783267 systemd-resolved[292]: Positive Trust Anchors: Mar 17 18:10:52.789811 kernel: Bridge firewalling registered Mar 17 18:10:52.783274 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:10:52.783300 systemd-resolved[292]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Mar 17 18:10:52.793000 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' Mar 17 18:10:52.787024 systemd[1]: Starting dracut-cmdline.service... Mar 17 18:10:52.787470 systemd-resolved[292]: Defaulting to hostname 'linux'. Mar 17 18:10:52.803511 kernel: audit: type=1130 audit(1742235052.793: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' Mar 17 18:10:52.803529 kernel: SCSI subsystem initialized Mar 17 18:10:52.787495 systemd-modules-load[291]: Inserted module 'br_netfilter' Mar 17 18:10:52.791383 systemd[1]: Started systemd-resolved.service. Mar 17 18:10:52.805234 dracut-cmdline[308]: dracut-dracut-053 Mar 17 18:10:52.798359 systemd[1]: Reached target nss-lookup.target. Mar 17 18:10:52.806887 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=e034db32d58fe7496a3db6ba3879dd9052cea2cf1597d65edfc7b26afc92530d Mar 17 18:10:52.813624 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 18:10:52.813658 kernel: device-mapper: uevent: version 1.0.3 Mar 17 18:10:52.814673 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Mar 17 18:10:52.816872 systemd-modules-load[291]: Inserted module 'dm_multipath' Mar 17 18:10:52.817637 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:10:52.818000 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' Mar 17 18:10:52.819263 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:10:52.823353 kernel: audit: type=1130 audit(1742235052.818: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' Mar 17 18:10:52.826701 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:10:52.827000 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' Mar 17 18:10:52.831249 kernel: audit: type=1130 audit(1742235052.827: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' Mar 17 18:10:52.869229 kernel: Loading iSCSI transport class v2.0-870. Mar 17 18:10:52.882231 kernel: iscsi: registered transport (tcp) Mar 17 18:10:52.897240 kernel: iscsi: registered transport (qla4xxx) Mar 17 18:10:52.897268 kernel: QLogic iSCSI HBA Driver Mar 17 18:10:52.930660 systemd[1]: Finished dracut-cmdline.service. Mar 17 18:10:52.931000 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' Mar 17 18:10:52.932321 systemd[1]: Starting dracut-pre-udev.service... Mar 17 18:10:52.935700 kernel: audit: type=1130 audit(1742235052.931: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' Mar 17 18:10:52.979238 kernel: raid6: neonx8 gen() 13832 MB/s Mar 17 18:10:52.994240 kernel: raid6: neonx8 xor() 10830 MB/s Mar 17 18:10:53.011226 kernel: raid6: neonx4 gen() 13517 MB/s Mar 17 18:10:53.028232 kernel: raid6: neonx4 xor() 11284 MB/s Mar 17 18:10:53.045226 kernel: raid6: neonx2 gen() 12959 MB/s Mar 17 18:10:53.062228 kernel: raid6: neonx2 xor() 10491 MB/s Mar 17 18:10:53.079226 kernel: raid6: neonx1 gen() 10536 MB/s Mar 17 18:10:53.096226 kernel: raid6: neonx1 xor() 8793 MB/s Mar 17 18:10:53.113228 kernel: raid6: int64x8 gen() 6275 MB/s Mar 17 18:10:53.130241 kernel: raid6: int64x8 xor() 3541 MB/s Mar 17 18:10:53.147243 kernel: raid6: int64x4 gen() 7198 MB/s Mar 17 18:10:53.164235 kernel: raid6: int64x4 xor() 3854 MB/s Mar 17 18:10:53.181232 kernel: raid6: int64x2 gen() 6150 MB/s Mar 17 18:10:53.198242 kernel: raid6: int64x2 xor() 3321 MB/s Mar 17 18:10:53.215239 kernel: raid6: int64x1 gen() 5043 MB/s Mar 17 18:10:53.232310 kernel: raid6: int64x1 xor() 2646 MB/s Mar 17 18:10:53.232333 kernel: raid6: using algorithm neonx8 gen() 13832 MB/s Mar 17 18:10:53.232349 kernel: raid6: .... xor() 10830 MB/s, rmw enabled Mar 17 18:10:53.233378 kernel: raid6: using neon recovery algorithm Mar 17 18:10:53.243240 kernel: xor: measuring software checksum speed Mar 17 18:10:53.244466 kernel: 8regs : 15262 MB/sec Mar 17 18:10:53.244479 kernel: 32regs : 20712 MB/sec Mar 17 18:10:53.245712 kernel: arm64_neon : 27589 MB/sec Mar 17 18:10:53.245734 kernel: xor: using function: arm64_neon (27589 MB/sec) Mar 17 18:10:53.299237 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Mar 17 18:10:53.309455 systemd[1]: Finished dracut-pre-udev.service. Mar 17 18:10:53.314042 kernel: audit: type=1130 audit(1742235053.309: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' Mar 17 18:10:53.314070 kernel: audit: type=1334 audit(1742235053.313:10): prog-id=7 op=LOAD Mar 17 18:10:53.309000 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' Mar 17 18:10:53.313000 audit: BPF prog-id=7 op=LOAD Mar 17 18:10:53.313000 audit: BPF prog-id=8 op=LOAD Mar 17 18:10:53.314407 systemd[1]: Starting systemd-udevd.service... Mar 17 18:10:53.328391 systemd-udevd[490]: Using default interface naming scheme 'v252'. Mar 17 18:10:53.331643 systemd[1]: Started systemd-udevd.service. Mar 17 18:10:53.333000 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' Mar 17 18:10:53.334582 systemd[1]: Starting dracut-pre-trigger.service... Mar 17 18:10:53.346296 dracut-pre-trigger[501]: rd.md=0: removing MD RAID activation Mar 17 18:10:53.372360 systemd[1]: Finished dracut-pre-trigger.service. Mar 17 18:10:53.372000 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' Mar 17 18:10:53.373934 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:10:53.410578 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:10:53.411000 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' Mar 17 18:10:53.438235 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Mar 17 18:10:53.443256 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Mar 17 18:10:53.443271 kernel: GPT:9289727 != 19775487 Mar 17 18:10:53.443285 kernel: GPT:Alternate GPT header not at the end of the disk. Mar 17 18:10:53.443294 kernel: GPT:9289727 != 19775487 Mar 17 18:10:53.443302 kernel: GPT: Use GNU Parted to correct GPT errors. Mar 17 18:10:53.443311 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 18:10:53.460156 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Mar 17 18:10:53.464229 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (537) Mar 17 18:10:53.465113 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Mar 17 18:10:53.466332 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Mar 17 18:10:53.471083 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Mar 17 18:10:53.477053 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:10:53.478859 systemd[1]: Starting disk-uuid.service... Mar 17 18:10:53.484542 disk-uuid[560]: Primary Header is updated. Mar 17 18:10:53.484542 disk-uuid[560]: Secondary Entries is updated. Mar 17 18:10:53.484542 disk-uuid[560]: Secondary Header is updated. Mar 17 18:10:53.488233 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 18:10:54.497236 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 18:10:54.497318 disk-uuid[561]: The operation has completed successfully. Mar 17 18:10:54.519893 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 18:10:54.520000 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' Mar 17 18:10:54.520000 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' Mar 17 18:10:54.519997 systemd[1]: Finished disk-uuid.service. Mar 17 18:10:54.521564 systemd[1]: Starting verity-setup.service... Mar 17 18:10:54.539121 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Mar 17 18:10:54.557986 systemd[1]: Found device dev-mapper-usr.device. Mar 17 18:10:54.560099 systemd[1]: Mounting sysusr-usr.mount... Mar 17 18:10:54.561806 systemd[1]: Finished verity-setup.service. Mar 17 18:10:54.562000 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' Mar 17 18:10:54.605231 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Mar 17 18:10:54.605307 systemd[1]: Mounted sysusr-usr.mount. Mar 17 18:10:54.606153 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Mar 17 18:10:54.606886 systemd[1]: Starting ignition-setup.service... Mar 17 18:10:54.609198 systemd[1]: Starting parse-ip-for-networkd.service... Mar 17 18:10:54.615373 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:10:54.615410 kernel: BTRFS info (device vda6): using free space tree Mar 17 18:10:54.615420 kernel: BTRFS info (device vda6): has skinny extents Mar 17 18:10:54.624112 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 18:10:54.630542 systemd[1]: Finished ignition-setup.service. Mar 17 18:10:54.631000 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' Mar 17 18:10:54.632308 systemd[1]: Starting ignition-fetch-offline.service... Mar 17 18:10:54.693644 systemd[1]: Finished parse-ip-for-networkd.service. Mar 17 18:10:54.694000 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' Mar 17 18:10:54.695000 audit: BPF prog-id=9 op=LOAD Mar 17 18:10:54.695901 systemd[1]: Starting systemd-networkd.service... Mar 17 18:10:54.712497 ignition[648]: Ignition 2.14.0 Mar 17 18:10:54.712507 ignition[648]: Stage: fetch-offline Mar 17 18:10:54.712546 ignition[648]: no configs at "/usr/lib/ignition/base.d" Mar 17 18:10:54.712554 ignition[648]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 18:10:54.712676 ignition[648]: parsed url from cmdline: "" Mar 17 18:10:54.712679 ignition[648]: no config URL provided Mar 17 18:10:54.712684 ignition[648]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:10:54.712690 ignition[648]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:10:54.712708 ignition[648]: op(1): [started] loading QEMU firmware config module Mar 17 18:10:54.712712 ignition[648]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 17 18:10:54.722267 ignition[648]: op(1): [finished] loading QEMU firmware config module Mar 17 18:10:54.722292 ignition[648]: QEMU firmware config was not found. Ignoring... Mar 17 18:10:54.725044 systemd-networkd[738]: lo: Link UP Mar 17 18:10:54.725054 systemd-networkd[738]: lo: Gained carrier Mar 17 18:10:54.726000 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' Mar 17 18:10:54.725640 systemd-networkd[738]: Enumeration completed Mar 17 18:10:54.725744 systemd[1]: Started systemd-networkd.service. Mar 17 18:10:54.726002 systemd-networkd[738]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:10:54.727131 systemd[1]: Reached target network.target. Mar 17 18:10:54.727474 systemd-networkd[738]: eth0: Link UP Mar 17 18:10:54.727477 systemd-networkd[738]: eth0: Gained carrier Mar 17 18:10:54.729371 systemd[1]: Starting iscsiuio.service... Mar 17 18:10:54.736014 ignition[648]: parsing config with SHA512: 51a5147dc287ff04c5f17429b13d5512a36892ea33879d691b2b3c323932399561cdeaaaffa8340617357676fff394b827cb451dc0bded60cd43e8b552f58425 Mar 17 18:10:54.738058 systemd[1]: Started iscsiuio.service. Mar 17 18:10:54.738000 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' Mar 17 18:10:54.739656 systemd[1]: Starting iscsid.service... Mar 17 18:10:54.743068 iscsid[744]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:10:54.743068 iscsid[744]: 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]. Mar 17 18:10:54.743068 iscsid[744]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Mar 17 18:10:54.743068 iscsid[744]: If using hardware iscsi like qla4xxx this message can be ignored. Mar 17 18:10:54.743068 iscsid[744]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:10:54.743068 iscsid[744]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Mar 17 18:10:54.750000 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' Mar 17 18:10:54.754000 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' Mar 17 18:10:54.745246 ignition[648]: fetch-offline: fetch-offline passed Mar 17 18:10:54.744560 unknown[648]: fetched base config from "system" Mar 17 18:10:54.745307 ignition[648]: Ignition finished successfully Mar 17 18:10:54.744571 unknown[648]: fetched user config from "qemu" Mar 17 18:10:54.745878 systemd[1]: Started iscsid.service. Mar 17 18:10:54.751827 systemd[1]: Starting dracut-initqueue.service... Mar 17 18:10:54.752777 systemd[1]: Finished ignition-fetch-offline.service. Mar 17 18:10:54.763000 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' Mar 17 18:10:54.754600 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 17 18:10:54.763859 ignition[747]: Ignition 2.14.0 Mar 17 18:10:54.755315 systemd[1]: Starting ignition-kargs.service... Mar 17 18:10:54.763865 ignition[747]: Stage: kargs Mar 17 18:10:54.756034 systemd-networkd[738]: eth0: DHCPv4 address 10.0.0.22/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 17 18:10:54.763953 ignition[747]: no configs at "/usr/lib/ignition/base.d" Mar 17 18:10:54.773000 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' Mar 17 18:10:54.763031 systemd[1]: Finished dracut-initqueue.service. Mar 17 18:10:54.763962 ignition[747]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 18:10:54.764175 systemd[1]: Reached target remote-fs-pre.target. Mar 17 18:10:54.764607 ignition[747]: kargs: kargs passed Mar 17 18:10:54.779000 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' Mar 17 18:10:54.765674 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:10:54.764646 ignition[747]: Ignition finished successfully Mar 17 18:10:54.768759 systemd[1]: Reached target remote-fs.target. Mar 17 18:10:54.781940 ignition[761]: Ignition 2.14.0 Mar 17 18:10:54.770768 systemd[1]: Starting dracut-pre-mount.service... Mar 17 18:10:54.784000 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' Mar 17 18:10:54.781946 ignition[761]: Stage: disks Mar 17 18:10:54.772223 systemd[1]: Finished ignition-kargs.service. Mar 17 18:10:54.782050 ignition[761]: no configs at "/usr/lib/ignition/base.d" Mar 17 18:10:54.774830 systemd[1]: Starting ignition-disks.service... Mar 17 18:10:54.782059 ignition[761]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 18:10:54.778433 systemd[1]: Finished dracut-pre-mount.service. Mar 17 18:10:54.782697 ignition[761]: disks: disks passed Mar 17 18:10:54.783376 systemd[1]: Finished ignition-disks.service. Mar 17 18:10:54.782734 ignition[761]: Ignition finished successfully Mar 17 18:10:54.784734 systemd[1]: Reached target initrd-root-device.target. Mar 17 18:10:54.786203 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:10:54.787537 systemd[1]: Reached target local-fs.target. Mar 17 18:10:54.788899 systemd[1]: Reached target sysinit.target. Mar 17 18:10:54.790394 systemd[1]: Reached target basic.target. Mar 17 18:10:54.792713 systemd[1]: Starting systemd-fsck-root.service... Mar 17 18:10:54.805919 systemd-fsck[773]: ROOT: clean, 623/553520 files, 56021/553472 blocks Mar 17 18:10:54.813294 systemd[1]: Finished systemd-fsck-root.service. Mar 17 18:10:54.814000 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' Mar 17 18:10:54.815123 systemd[1]: Mounting sysroot.mount... Mar 17 18:10:54.821237 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Mar 17 18:10:54.821269 systemd[1]: Mounted sysroot.mount. Mar 17 18:10:54.822062 systemd[1]: Reached target initrd-root-fs.target. Mar 17 18:10:54.824932 systemd[1]: Mounting sysroot-usr.mount... Mar 17 18:10:54.825865 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Mar 17 18:10:54.825905 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 18:10:54.825929 systemd[1]: Reached target ignition-diskful.target. Mar 17 18:10:54.827889 systemd[1]: Mounted sysroot-usr.mount. Mar 17 18:10:54.829870 systemd[1]: Starting initrd-setup-root.service... Mar 17 18:10:54.833938 initrd-setup-root[783]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 18:10:54.837553 initrd-setup-root[791]: cut: /sysroot/etc/group: No such file or directory Mar 17 18:10:54.841186 initrd-setup-root[799]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 18:10:54.845165 initrd-setup-root[807]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 18:10:54.872186 systemd[1]: Finished initrd-setup-root.service. Mar 17 18:10:54.872000 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' Mar 17 18:10:54.873821 systemd[1]: Starting ignition-mount.service... Mar 17 18:10:54.875156 systemd[1]: Starting sysroot-boot.service... Mar 17 18:10:54.879611 bash[824]: umount: /sysroot/usr/share/oem: not mounted. Mar 17 18:10:54.888424 ignition[826]: INFO : Ignition 2.14.0 Mar 17 18:10:54.889377 ignition[826]: INFO : Stage: mount Mar 17 18:10:54.890249 ignition[826]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 18:10:54.891269 ignition[826]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 18:10:54.892399 ignition[826]: INFO : mount: mount passed Mar 17 18:10:54.892399 ignition[826]: INFO : Ignition finished successfully Mar 17 18:10:54.893000 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' Mar 17 18:10:54.892766 systemd[1]: Finished ignition-mount.service. Mar 17 18:10:54.895344 systemd[1]: Finished sysroot-boot.service. Mar 17 18:10:54.896000 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' Mar 17 18:10:55.568600 systemd[1]: Mounting sysroot-usr-share-oem.mount... Mar 17 18:10:55.574233 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (834) Mar 17 18:10:55.576479 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:10:55.576498 kernel: BTRFS info (device vda6): using free space tree Mar 17 18:10:55.576508 kernel: BTRFS info (device vda6): has skinny extents Mar 17 18:10:55.579775 systemd[1]: Mounted sysroot-usr-share-oem.mount. Mar 17 18:10:55.581513 systemd[1]: Starting ignition-files.service... Mar 17 18:10:55.595932 ignition[854]: INFO : Ignition 2.14.0 Mar 17 18:10:55.595932 ignition[854]: INFO : Stage: files Mar 17 18:10:55.597600 ignition[854]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 18:10:55.597600 ignition[854]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 18:10:55.597600 ignition[854]: DEBUG : files: compiled without relabeling support, skipping Mar 17 18:10:55.604864 ignition[854]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 18:10:55.604864 ignition[854]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 18:10:55.608677 ignition[854]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 18:10:55.610072 ignition[854]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 18:10:55.611589 unknown[854]: wrote ssh authorized keys file for user: core Mar 17 18:10:55.612759 ignition[854]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 18:10:55.612759 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Mar 17 18:10:55.612759 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Mar 17 18:10:55.612759 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:10:55.612759 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:10:55.621803 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Mar 17 18:10:55.621803 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Mar 17 18:10:55.621803 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Mar 17 18:10:55.621803 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1 Mar 17 18:10:55.975144 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Mar 17 18:10:56.269677 ignition[854]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Mar 17 18:10:56.269677 ignition[854]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Mar 17 18:10:56.273262 ignition[854]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 17 18:10:56.273262 ignition[854]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 17 18:10:56.273262 ignition[854]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Mar 17 18:10:56.273262 ignition[854]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Mar 17 18:10:56.273262 ignition[854]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 17 18:10:56.313687 ignition[854]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 17 18:10:56.316118 ignition[854]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Mar 17 18:10:56.316118 ignition[854]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:10:56.316118 ignition[854]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:10:56.316118 ignition[854]: INFO : files: files passed Mar 17 18:10:56.316118 ignition[854]: INFO : Ignition finished successfully Mar 17 18:10:56.318000 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' Mar 17 18:10:56.316163 systemd[1]: Finished ignition-files.service. Mar 17 18:10:56.319295 systemd[1]: Starting initrd-setup-root-after-ignition.service... Mar 17 18:10:56.326384 initrd-setup-root-after-ignition[880]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Mar 17 18:10:56.326000 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' Mar 17 18:10:56.326000 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' Mar 17 18:10:56.320682 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Mar 17 18:10:56.328000 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' Mar 17 18:10:56.330925 initrd-setup-root-after-ignition[882]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 18:10:56.321404 systemd[1]: Starting ignition-quench.service... Mar 17 18:10:56.325444 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 18:10:56.325540 systemd[1]: Finished ignition-quench.service. Mar 17 18:10:56.327338 systemd[1]: Finished initrd-setup-root-after-ignition.service. Mar 17 18:10:56.329342 systemd[1]: Reached target ignition-complete.target. Mar 17 18:10:56.332412 systemd[1]: Starting initrd-parse-etc.service... Mar 17 18:10:56.344920 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 18:10:56.345034 systemd[1]: Finished initrd-parse-etc.service. Mar 17 18:10:56.346000 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' Mar 17 18:10:56.346000 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' Mar 17 18:10:56.346880 systemd[1]: Reached target initrd-fs.target. Mar 17 18:10:56.348314 systemd[1]: Reached target initrd.target. Mar 17 18:10:56.349756 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Mar 17 18:10:56.350577 systemd[1]: Starting dracut-pre-pivot.service... Mar 17 18:10:56.361509 systemd[1]: Finished dracut-pre-pivot.service. Mar 17 18:10:56.362000 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' Mar 17 18:10:56.363206 systemd[1]: Starting initrd-cleanup.service... Mar 17 18:10:56.371865 systemd[1]: Stopped target nss-lookup.target. Mar 17 18:10:56.372799 systemd[1]: Stopped target remote-cryptsetup.target. Mar 17 18:10:56.374335 systemd[1]: Stopped target timers.target. Mar 17 18:10:56.375700 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 18:10:56.376000 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' Mar 17 18:10:56.375817 systemd[1]: Stopped dracut-pre-pivot.service. Mar 17 18:10:56.377114 systemd[1]: Stopped target initrd.target. Mar 17 18:10:56.378534 systemd[1]: Stopped target basic.target. Mar 17 18:10:56.379807 systemd[1]: Stopped target ignition-complete.target. Mar 17 18:10:56.381144 systemd[1]: Stopped target ignition-diskful.target. Mar 17 18:10:56.382499 systemd[1]: Stopped target initrd-root-device.target. Mar 17 18:10:56.383992 systemd[1]: Stopped target remote-fs.target. Mar 17 18:10:56.385444 systemd[1]: Stopped target remote-fs-pre.target. Mar 17 18:10:56.386903 systemd[1]: Stopped target sysinit.target. Mar 17 18:10:56.388178 systemd[1]: Stopped target local-fs.target. Mar 17 18:10:56.389520 systemd[1]: Stopped target local-fs-pre.target. Mar 17 18:10:56.390832 systemd[1]: Stopped target swap.target. Mar 17 18:10:56.393000 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' Mar 17 18:10:56.392046 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 18:10:56.392160 systemd[1]: Stopped dracut-pre-mount.service. Mar 17 18:10:56.395000 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' Mar 17 18:10:56.393504 systemd[1]: Stopped target cryptsetup.target. Mar 17 18:10:56.397000 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' Mar 17 18:10:56.394649 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 18:10:56.394748 systemd[1]: Stopped dracut-initqueue.service. Mar 17 18:10:56.396207 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 18:10:56.396319 systemd[1]: Stopped ignition-fetch-offline.service. Mar 17 18:10:56.397645 systemd[1]: Stopped target paths.target. Mar 17 18:10:56.398852 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 18:10:56.403241 systemd[1]: Stopped systemd-ask-password-console.path. Mar 17 18:10:56.404761 systemd[1]: Stopped target slices.target. Mar 17 18:10:56.406112 systemd[1]: Stopped target sockets.target. Mar 17 18:10:56.407418 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 18:10:56.408000 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' Mar 17 18:10:56.407524 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Mar 17 18:10:56.410000 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' Mar 17 18:10:56.408909 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 18:10:56.409011 systemd[1]: Stopped ignition-files.service. Mar 17 18:10:56.413669 iscsid[744]: iscsid shutting down. Mar 17 18:10:56.411509 systemd[1]: Stopping ignition-mount.service... Mar 17 18:10:56.413140 systemd[1]: Stopping iscsid.service... Mar 17 18:10:56.414910 systemd[1]: Stopping sysroot-boot.service... Mar 17 18:10:56.417000 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' Mar 17 18:10:56.416277 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 18:10:56.418000 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' Mar 17 18:10:56.420252 ignition[895]: INFO : Ignition 2.14.0 Mar 17 18:10:56.420252 ignition[895]: INFO : Stage: umount Mar 17 18:10:56.420252 ignition[895]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 18:10:56.420252 ignition[895]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 18:10:56.422000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:10:56.423000 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' Mar 17 18:10:56.416401 systemd[1]: Stopped systemd-udev-trigger.service. Mar 17 18:10:56.428000 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' Mar 17 18:10:56.429047 ignition[895]: INFO : umount: umount passed Mar 17 18:10:56.429047 ignition[895]: INFO : Ignition finished successfully Mar 17 18:10:56.429000 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' Mar 17 18:10:56.430000 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' Mar 17 18:10:56.417898 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 18:10:56.418000 systemd[1]: Stopped dracut-pre-trigger.service. Mar 17 18:10:56.420640 systemd[1]: iscsid.service: Deactivated successfully. Mar 17 18:10:56.436000 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' Mar 17 18:10:56.420740 systemd[1]: Stopped iscsid.service. Mar 17 18:10:56.437000 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' Mar 17 18:10:56.437000 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' Mar 17 18:10:56.422910 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 18:10:56.423011 systemd[1]: Stopped ignition-mount.service. Mar 17 18:10:56.424357 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 18:10:56.424423 systemd[1]: Closed iscsid.socket. Mar 17 18:10:56.426402 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 18:10:56.426445 systemd[1]: Stopped ignition-disks.service. Mar 17 18:10:56.428394 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 18:10:56.428435 systemd[1]: Stopped ignition-kargs.service. Mar 17 18:10:56.429774 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 18:10:56.429811 systemd[1]: Stopped ignition-setup.service. Mar 17 18:10:56.450000 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' Mar 17 18:10:56.431291 systemd[1]: Stopping iscsiuio.service... Mar 17 18:10:56.451000 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' Mar 17 18:10:56.433849 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 18:10:56.434282 systemd[1]: iscsiuio.service: Deactivated successfully. Mar 17 18:10:56.453000 audit: BPF prog-id=6 op=UNLOAD Mar 17 18:10:56.434366 systemd[1]: Stopped iscsiuio.service. Mar 17 18:10:56.456000 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' Mar 17 18:10:56.436610 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 18:10:56.457000 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' Mar 17 18:10:56.436691 systemd[1]: Finished initrd-cleanup.service. Mar 17 18:10:56.459000 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' Mar 17 18:10:56.438633 systemd[1]: Stopped target network.target. Mar 17 18:10:56.440180 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 18:10:56.440233 systemd[1]: Closed iscsiuio.socket. Mar 17 18:10:56.465000 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' Mar 17 18:10:56.441639 systemd[1]: Stopping systemd-networkd.service... Mar 17 18:10:56.443104 systemd[1]: Stopping systemd-resolved.service... Mar 17 18:10:56.467000 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' Mar 17 18:10:56.446251 systemd-networkd[738]: eth0: DHCPv6 lease lost Mar 17 18:10:56.468000 audit: BPF prog-id=9 op=UNLOAD Mar 17 18:10:56.448761 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 18:10:56.448862 systemd[1]: Stopped systemd-resolved.service. Mar 17 18:10:56.471000 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' Mar 17 18:10:56.450782 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:10:56.472000 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' Mar 17 18:10:56.450859 systemd[1]: Stopped systemd-networkd.service. Mar 17 18:10:56.452157 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 18:10:56.452185 systemd[1]: Closed systemd-networkd.socket. Mar 17 18:10:56.477000 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' Mar 17 18:10:56.453942 systemd[1]: Stopping network-cleanup.service... Mar 17 18:10:56.478000 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' Mar 17 18:10:56.454914 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 18:10:56.480000 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' Mar 17 18:10:56.454983 systemd[1]: Stopped parse-ip-for-networkd.service. Mar 17 18:10:56.456648 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 18:10:56.456692 systemd[1]: Stopped systemd-sysctl.service. Mar 17 18:10:56.458931 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 18:10:56.483000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:10:56.486000 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' Mar 17 18:10:56.458972 systemd[1]: Stopped systemd-modules-load.service. Mar 17 18:10:56.487000 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' Mar 17 18:10:56.461414 systemd[1]: Stopping systemd-udevd.service... Mar 17 18:10:56.464501 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 17 18:10:56.490000 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' Mar 17 18:10:56.490000 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' Mar 17 18:10:56.464997 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 18:10:56.465085 systemd[1]: Stopped sysroot-boot.service. Mar 17 18:10:56.466752 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 18:10:56.466805 systemd[1]: Stopped initrd-setup-root.service. Mar 17 18:10:56.469981 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 18:10:56.470096 systemd[1]: Stopped systemd-udevd.service. Mar 17 18:10:56.471951 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 18:10:56.472026 systemd[1]: Stopped network-cleanup.service. Mar 17 18:10:56.473125 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 18:10:56.473154 systemd[1]: Closed systemd-udevd-control.socket. Mar 17 18:10:56.474719 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 18:10:56.474751 systemd[1]: Closed systemd-udevd-kernel.socket. Mar 17 18:10:56.476095 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 18:10:56.476139 systemd[1]: Stopped dracut-pre-udev.service. Mar 17 18:10:56.477866 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 18:10:56.477905 systemd[1]: Stopped dracut-cmdline.service. Mar 17 18:10:56.479238 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 18:10:56.479280 systemd[1]: Stopped dracut-cmdline-ask.service. Mar 17 18:10:56.481422 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Mar 17 18:10:56.482399 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 17 18:10:56.482455 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Mar 17 18:10:56.484920 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 18:10:56.484975 systemd[1]: Stopped kmod-static-nodes.service. Mar 17 18:10:56.486588 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 18:10:56.486626 systemd[1]: Stopped systemd-vconsole-setup.service. Mar 17 18:10:56.517473 systemd-journald[290]: Received SIGTERM from PID 1 (n/a). Mar 17 18:10:56.488944 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 17 18:10:56.489386 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 18:10:56.489466 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Mar 17 18:10:56.490771 systemd[1]: Reached target initrd-switch-root.target. Mar 17 18:10:56.493325 systemd[1]: Starting initrd-switch-root.service... Mar 17 18:10:56.499958 systemd[1]: Switching root. Mar 17 18:10:56.523021 systemd-journald[290]: Journal stopped Mar 17 18:10:58.482713 kernel: SELinux: Class mctp_socket not defined in policy. Mar 17 18:10:58.482757 kernel: SELinux: Class anon_inode not defined in policy. Mar 17 18:10:58.482772 kernel: SELinux: the above unknown classes and permissions will be allowed Mar 17 18:10:58.482785 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 18:10:58.482795 kernel: SELinux: policy capability open_perms=1 Mar 17 18:10:58.482808 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 18:10:58.482824 kernel: SELinux: policy capability always_check_network=0 Mar 17 18:10:58.482833 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 18:10:58.482842 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 18:10:58.482851 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 18:10:58.482874 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 18:10:58.482886 systemd[1]: Successfully loaded SELinux policy in 38.208ms. Mar 17 18:10:58.482905 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.031ms. Mar 17 18:10:58.482917 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) Mar 17 18:10:58.482930 systemd[1]: Detected virtualization kvm. Mar 17 18:10:58.482941 systemd[1]: Detected architecture arm64. Mar 17 18:10:58.482951 systemd[1]: Detected first boot. Mar 17 18:10:58.482962 systemd[1]: Initializing machine ID from VM UUID. Mar 17 18:10:58.482972 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Mar 17 18:10:58.482982 systemd[1]: Populated /etc with preset unit settings. Mar 17 18:10:58.482992 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Mar 17 18:10:58.483004 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Mar 17 18:10:58.483017 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. Mar 17 18:10:58.483028 kernel: kauditd_printk_skb: 80 callbacks suppressed Mar 17 18:10:58.483037 kernel: audit: type=1334 audit(1742235058.350:84): prog-id=12 op=LOAD Mar 17 18:10:58.483046 kernel: audit: type=1334 audit(1742235058.350:85): prog-id=3 op=UNLOAD Mar 17 18:10:58.483056 kernel: audit: type=1334 audit(1742235058.351:86): prog-id=13 op=LOAD Mar 17 18:10:58.483066 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 18:10:58.483076 kernel: audit: type=1334 audit(1742235058.351:87): prog-id=14 op=LOAD Mar 17 18:10:58.483086 systemd[1]: Stopped initrd-switch-root.service. Mar 17 18:10:58.483097 kernel: audit: type=1334 audit(1742235058.351:88): prog-id=4 op=UNLOAD Mar 17 18:10:58.483107 kernel: audit: type=1334 audit(1742235058.351:89): prog-id=5 op=UNLOAD Mar 17 18:10:58.483117 kernel: audit: type=1131 audit(1742235058.352:90): 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' Mar 17 18:10:58.483127 kernel: audit: type=1130 audit(1742235058.360:91): 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' Mar 17 18:10:58.483137 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 18:10:58.483147 kernel: audit: type=1131 audit(1742235058.360:92): 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' Mar 17 18:10:58.483157 systemd[1]: Created slice system-addon\x2dconfig.slice. Mar 17 18:10:58.483169 systemd[1]: Created slice system-addon\x2drun.slice. Mar 17 18:10:58.483180 systemd[1]: Created slice system-getty.slice. Mar 17 18:10:58.483191 systemd[1]: Created slice system-modprobe.slice. Mar 17 18:10:58.483201 kernel: audit: type=1334 audit(1742235058.373:93): prog-id=12 op=UNLOAD Mar 17 18:10:58.483226 systemd[1]: Created slice system-serial\x2dgetty.slice. Mar 17 18:10:58.483240 systemd[1]: Created slice system-system\x2dcloudinit.slice. Mar 17 18:10:58.483251 systemd[1]: Created slice system-systemd\x2dfsck.slice. Mar 17 18:10:58.483261 systemd[1]: Created slice user.slice. Mar 17 18:10:58.483271 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:10:58.483283 systemd[1]: Started systemd-ask-password-wall.path. Mar 17 18:10:58.483294 systemd[1]: Set up automount boot.automount. Mar 17 18:10:58.483305 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Mar 17 18:10:58.483316 systemd[1]: Stopped target initrd-switch-root.target. Mar 17 18:10:58.483327 systemd[1]: Stopped target initrd-fs.target. Mar 17 18:10:58.483338 systemd[1]: Stopped target initrd-root-fs.target. Mar 17 18:10:58.483349 systemd[1]: Reached target integritysetup.target. Mar 17 18:10:58.483363 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:10:58.483373 systemd[1]: Reached target remote-fs.target. Mar 17 18:10:58.483384 systemd[1]: Reached target slices.target. Mar 17 18:10:58.483394 systemd[1]: Reached target swap.target. Mar 17 18:10:58.483404 systemd[1]: Reached target torcx.target. Mar 17 18:10:58.483415 systemd[1]: Reached target veritysetup.target. Mar 17 18:10:58.483425 systemd[1]: Listening on systemd-coredump.socket. Mar 17 18:10:58.483436 systemd[1]: Listening on systemd-initctl.socket. Mar 17 18:10:58.483447 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:10:58.483457 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:10:58.483468 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:10:58.483479 systemd[1]: Listening on systemd-userdbd.socket. Mar 17 18:10:58.483489 systemd[1]: Mounting dev-hugepages.mount... Mar 17 18:10:58.483499 systemd[1]: Mounting dev-mqueue.mount... Mar 17 18:10:58.483509 systemd[1]: Mounting media.mount... Mar 17 18:10:58.483520 systemd[1]: Mounting sys-kernel-debug.mount... Mar 17 18:10:58.483530 systemd[1]: Mounting sys-kernel-tracing.mount... Mar 17 18:10:58.483544 systemd[1]: Mounting tmp.mount... Mar 17 18:10:58.483555 systemd[1]: Starting flatcar-tmpfiles.service... Mar 17 18:10:58.483565 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:10:58.483576 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:10:58.483587 systemd[1]: Starting modprobe@configfs.service... Mar 17 18:10:58.483597 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:10:58.483608 systemd[1]: Starting modprobe@drm.service... Mar 17 18:10:58.483618 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:10:58.483628 systemd[1]: Starting modprobe@fuse.service... Mar 17 18:10:58.483638 systemd[1]: Starting modprobe@loop.service... Mar 17 18:10:58.483651 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 18:10:58.483662 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 18:10:58.483672 systemd[1]: Stopped systemd-fsck-root.service. Mar 17 18:10:58.483683 kernel: fuse: init (API version 7.34) Mar 17 18:10:58.483692 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 18:10:58.483703 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 18:10:58.483713 systemd[1]: Stopped systemd-journald.service. Mar 17 18:10:58.483723 kernel: loop: module loaded Mar 17 18:10:58.483734 systemd[1]: Starting systemd-journald.service... Mar 17 18:10:58.483745 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:10:58.483755 systemd[1]: Starting systemd-network-generator.service... Mar 17 18:10:58.483766 systemd[1]: Starting systemd-remount-fs.service... Mar 17 18:10:58.483789 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:10:58.483802 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 18:10:58.483812 systemd[1]: Stopped verity-setup.service. Mar 17 18:10:58.483822 systemd[1]: Mounted dev-hugepages.mount. Mar 17 18:10:58.483833 systemd[1]: Mounted dev-mqueue.mount. Mar 17 18:10:58.483845 systemd-journald[990]: Journal started Mar 17 18:10:58.483895 systemd-journald[990]: Runtime Journal (/run/log/journal/c37807ad462d44999dd3e677a404ba8c) is 6.0M, max 48.7M, 42.6M free. Mar 17 18:10:56.578000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:10:56.660000 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 Mar 17 18:10:56.660000 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 Mar 17 18:10:56.660000 audit: BPF prog-id=10 op=LOAD Mar 17 18:10:56.660000 audit: BPF prog-id=10 op=UNLOAD Mar 17 18:10:56.660000 audit: BPF prog-id=11 op=LOAD Mar 17 18:10:56.660000 audit: BPF prog-id=11 op=UNLOAD Mar 17 18:10:56.703000 audit[928]: AVC avc: denied { associate } for pid=928 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" Mar 17 18:10:56.703000 audit[928]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=400018d8e2 a1=4000028e40 a2=4000027100 a3=32 items=0 ppid=911 pid=928 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) Mar 17 18:10:56.703000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:10:56.704000 audit[928]: AVC avc: denied { associate } for pid=928 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Mar 17 18:10:56.704000 audit[928]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=400018d9b9 a2=1ed a3=0 items=2 ppid=911 pid=928 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) Mar 17 18:10:56.704000 audit: CWD cwd="/" Mar 17 18:10:56.704000 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 Mar 17 18:10:56.704000 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 Mar 17 18:10:56.704000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:10:58.350000 audit: BPF prog-id=12 op=LOAD Mar 17 18:10:58.350000 audit: BPF prog-id=3 op=UNLOAD Mar 17 18:10:58.351000 audit: BPF prog-id=13 op=LOAD Mar 17 18:10:58.351000 audit: BPF prog-id=14 op=LOAD Mar 17 18:10:58.351000 audit: BPF prog-id=4 op=UNLOAD Mar 17 18:10:58.351000 audit: BPF prog-id=5 op=UNLOAD Mar 17 18:10:58.352000 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' Mar 17 18:10:58.360000 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' Mar 17 18:10:58.360000 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' Mar 17 18:10:58.373000 audit: BPF prog-id=12 op=UNLOAD Mar 17 18:10:58.456000 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' Mar 17 18:10:58.459000 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' Mar 17 18:10:58.461000 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' Mar 17 18:10:58.461000 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' Mar 17 18:10:58.463000 audit: BPF prog-id=15 op=LOAD Mar 17 18:10:58.463000 audit: BPF prog-id=16 op=LOAD Mar 17 18:10:58.463000 audit: BPF prog-id=17 op=LOAD Mar 17 18:10:58.463000 audit: BPF prog-id=13 op=UNLOAD Mar 17 18:10:58.463000 audit: BPF prog-id=14 op=UNLOAD Mar 17 18:10:58.479000 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' Mar 17 18:10:58.481000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Mar 17 18:10:58.481000 audit[990]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=fffffabaa800 a2=4000 a3=1 items=0 ppid=1 pid=990 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) Mar 17 18:10:58.481000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Mar 17 18:10:58.348923 systemd[1]: Queued start job for default target multi-user.target. Mar 17 18:10:56.701832 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Mar 17 18:10:58.348935 systemd[1]: Unnecessary job was removed for dev-vda6.device. Mar 17 18:10:56.702073 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:10:58.352767 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 18:10:56.702091 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:10:58.485266 systemd[1]: Started systemd-journald.service. Mar 17 18:10:56.702120 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Mar 17 18:10:56.702130 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="skipped missing lower profile" missing profile=oem Mar 17 18:10:56.702159 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Mar 17 18:10:56.702171 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Mar 17 18:10:56.702388 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Mar 17 18:10:56.702431 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:10:56.702444 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:10:56.702872 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Mar 17 18:10:56.702904 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Mar 17 18:10:56.702933 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" 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 Mar 17 18:10:58.485000 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' Mar 17 18:10:56.702948 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Mar 17 18:10:58.486428 systemd[1]: Mounted media.mount. Mar 17 18:10:56.702965 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" 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 Mar 17 18:10:56.702978 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:56Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Mar 17 18:10:58.108399 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:58Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:10:58.108664 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:58Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:10:58.108774 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:58Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:10:58.108947 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:58Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:10:58.109001 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:58Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Mar 17 18:10:58.109056 /usr/lib/systemd/system-generators/torcx-generator[928]: time="2025-03-17T18:10:58Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Mar 17 18:10:58.487335 systemd[1]: Mounted sys-kernel-debug.mount. Mar 17 18:10:58.488191 systemd[1]: Mounted sys-kernel-tracing.mount. Mar 17 18:10:58.489109 systemd[1]: Mounted tmp.mount. Mar 17 18:10:58.490102 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:10:58.490000 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' Mar 17 18:10:58.491181 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 18:10:58.491344 systemd[1]: Finished modprobe@configfs.service. Mar 17 18:10:58.491000 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' Mar 17 18:10:58.491000 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' Mar 17 18:10:58.492462 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:10:58.492599 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:10:58.493000 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' Mar 17 18:10:58.493000 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' Mar 17 18:10:58.493735 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 18:10:58.493887 systemd[1]: Finished modprobe@drm.service. Mar 17 18:10:58.494000 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' Mar 17 18:10:58.494000 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' Mar 17 18:10:58.494920 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:10:58.495067 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:10:58.495000 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' Mar 17 18:10:58.495000 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' Mar 17 18:10:58.496261 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 18:10:58.496413 systemd[1]: Finished modprobe@fuse.service. Mar 17 18:10:58.496000 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' Mar 17 18:10:58.496000 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' Mar 17 18:10:58.497423 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:10:58.497571 systemd[1]: Finished modprobe@loop.service. Mar 17 18:10:58.498000 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' Mar 17 18:10:58.498000 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' Mar 17 18:10:58.498726 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:10:58.499000 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' Mar 17 18:10:58.500118 systemd[1]: Finished systemd-network-generator.service. Mar 17 18:10:58.500000 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' Mar 17 18:10:58.501355 systemd[1]: Finished systemd-remount-fs.service. Mar 17 18:10:58.501000 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' Mar 17 18:10:58.502773 systemd[1]: Finished flatcar-tmpfiles.service. Mar 17 18:10:58.503000 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' Mar 17 18:10:58.503993 systemd[1]: Reached target network-pre.target. Mar 17 18:10:58.506007 systemd[1]: Mounting sys-fs-fuse-connections.mount... Mar 17 18:10:58.508060 systemd[1]: Mounting sys-kernel-config.mount... Mar 17 18:10:58.508815 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 18:10:58.510192 systemd[1]: Starting systemd-hwdb-update.service... Mar 17 18:10:58.512064 systemd[1]: Starting systemd-journal-flush.service... Mar 17 18:10:58.513082 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:10:58.514070 systemd[1]: Starting systemd-random-seed.service... Mar 17 18:10:58.515074 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:10:58.516188 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:10:58.518045 systemd[1]: Starting systemd-sysusers.service... Mar 17 18:10:58.521389 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:10:58.522249 systemd-journald[990]: Time spent on flushing to /var/log/journal/c37807ad462d44999dd3e677a404ba8c is 16.743ms for 975 entries. Mar 17 18:10:58.522249 systemd-journald[990]: System Journal (/var/log/journal/c37807ad462d44999dd3e677a404ba8c) is 8.0M, max 195.6M, 187.6M free. Mar 17 18:10:58.553428 systemd-journald[990]: Received client request to flush runtime journal. Mar 17 18:10:58.523000 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' Mar 17 18:10:58.529000 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' Mar 17 18:10:58.540000 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' Mar 17 18:10:58.541000 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' Mar 17 18:10:58.523670 systemd[1]: Mounted sys-fs-fuse-connections.mount. Mar 17 18:10:58.524977 systemd[1]: Mounted sys-kernel-config.mount. Mar 17 18:10:58.554624 udevadm[1028]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Mar 17 18:10:58.527530 systemd[1]: Starting systemd-udev-settle.service... Mar 17 18:10:58.528682 systemd[1]: Finished systemd-random-seed.service. Mar 17 18:10:58.529667 systemd[1]: Reached target first-boot-complete.target. Mar 17 18:10:58.540102 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:10:58.541370 systemd[1]: Finished systemd-sysusers.service. Mar 17 18:10:58.543297 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Mar 17 18:10:58.554424 systemd[1]: Finished systemd-journal-flush.service. Mar 17 18:10:58.555000 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' Mar 17 18:10:58.561365 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Mar 17 18:10:58.562000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:10:58.885402 systemd[1]: Finished systemd-hwdb-update.service. Mar 17 18:10:58.886000 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' Mar 17 18:10:58.886000 audit: BPF prog-id=18 op=LOAD Mar 17 18:10:58.886000 audit: BPF prog-id=19 op=LOAD Mar 17 18:10:58.886000 audit: BPF prog-id=7 op=UNLOAD Mar 17 18:10:58.886000 audit: BPF prog-id=8 op=UNLOAD Mar 17 18:10:58.887640 systemd[1]: Starting systemd-udevd.service... Mar 17 18:10:58.903755 systemd-udevd[1034]: Using default interface naming scheme 'v252'. Mar 17 18:10:58.915004 systemd[1]: Started systemd-udevd.service. Mar 17 18:10:58.915000 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' Mar 17 18:10:58.916000 audit: BPF prog-id=20 op=LOAD Mar 17 18:10:58.917616 systemd[1]: Starting systemd-networkd.service... Mar 17 18:10:58.929000 audit: BPF prog-id=21 op=LOAD Mar 17 18:10:58.929000 audit: BPF prog-id=22 op=LOAD Mar 17 18:10:58.929000 audit: BPF prog-id=23 op=LOAD Mar 17 18:10:58.930382 systemd[1]: Starting systemd-userdbd.service... Mar 17 18:10:58.956534 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Mar 17 18:10:58.967654 systemd[1]: Started systemd-userdbd.service. Mar 17 18:10:58.968000 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' Mar 17 18:10:58.977052 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:10:59.020538 systemd[1]: Finished systemd-udev-settle.service. Mar 17 18:10:59.021000 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' Mar 17 18:10:59.022559 systemd-networkd[1043]: lo: Link UP Mar 17 18:10:59.022569 systemd-networkd[1043]: lo: Gained carrier Mar 17 18:10:59.022603 systemd[1]: Starting lvm2-activation-early.service... Mar 17 18:10:59.022912 systemd-networkd[1043]: Enumeration completed Mar 17 18:10:59.023005 systemd-networkd[1043]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:10:59.023530 systemd[1]: Started systemd-networkd.service. Mar 17 18:10:59.024000 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' Mar 17 18:10:59.029713 systemd-networkd[1043]: eth0: Link UP Mar 17 18:10:59.029724 systemd-networkd[1043]: eth0: Gained carrier Mar 17 18:10:59.034998 lvm[1067]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:10:59.053384 systemd-networkd[1043]: eth0: DHCPv4 address 10.0.0.22/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 17 18:10:59.069958 systemd[1]: Finished lvm2-activation-early.service. Mar 17 18:10:59.070000 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' Mar 17 18:10:59.071009 systemd[1]: Reached target cryptsetup.target. Mar 17 18:10:59.072972 systemd[1]: Starting lvm2-activation.service... Mar 17 18:10:59.076417 lvm[1068]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:10:59.103982 systemd[1]: Finished lvm2-activation.service. Mar 17 18:10:59.104000 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' Mar 17 18:10:59.104946 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:10:59.105802 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Mar 17 18:10:59.105843 systemd[1]: Reached target local-fs.target. Mar 17 18:10:59.106632 systemd[1]: Reached target machines.target. Mar 17 18:10:59.108527 systemd[1]: Starting ldconfig.service... Mar 17 18:10:59.109574 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.109623 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). Mar 17 18:10:59.110601 systemd[1]: Starting systemd-boot-update.service... Mar 17 18:10:59.112480 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Mar 17 18:10:59.114569 systemd[1]: Starting systemd-machine-id-commit.service... Mar 17 18:10:59.116551 systemd[1]: Starting systemd-sysext.service... Mar 17 18:10:59.119455 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1070 (bootctl) Mar 17 18:10:59.120479 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Mar 17 18:10:59.130321 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Mar 17 18:10:59.131000 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' Mar 17 18:10:59.138472 systemd[1]: Unmounting usr-share-oem.mount... Mar 17 18:10:59.142682 systemd[1]: usr-share-oem.mount: Deactivated successfully. Mar 17 18:10:59.142860 systemd[1]: Unmounted usr-share-oem.mount. Mar 17 18:10:59.189236 kernel: loop0: detected capacity change from 0 to 194096 Mar 17 18:10:59.190708 systemd[1]: Finished systemd-machine-id-commit.service. Mar 17 18:10:59.191000 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' Mar 17 18:10:59.200611 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 17 18:10:59.205927 systemd-fsck[1079]: fsck.fat 4.2 (2021-01-31) Mar 17 18:10:59.205927 systemd-fsck[1079]: /dev/vda1: 236 files, 117179/258078 clusters Mar 17 18:10:59.208532 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Mar 17 18:10:59.210000 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' Mar 17 18:10:59.215231 kernel: loop1: detected capacity change from 0 to 194096 Mar 17 18:10:59.218931 (sd-sysext)[1085]: Using extensions 'kubernetes'. Mar 17 18:10:59.220179 (sd-sysext)[1085]: Merged extensions into '/usr'. Mar 17 18:10:59.236523 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.237731 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:10:59.239876 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:10:59.241714 systemd[1]: Starting modprobe@loop.service... Mar 17 18:10:59.242551 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.242676 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). Mar 17 18:10:59.243430 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:10:59.243551 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:10:59.244000 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' Mar 17 18:10:59.244000 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' Mar 17 18:10:59.244916 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:10:59.245023 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:10:59.245000 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' Mar 17 18:10:59.245000 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' Mar 17 18:10:59.246437 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:10:59.246539 systemd[1]: Finished modprobe@loop.service. Mar 17 18:10:59.247000 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' Mar 17 18:10:59.247000 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' Mar 17 18:10:59.247953 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:10:59.248047 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.294741 ldconfig[1069]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 18:10:59.302305 systemd[1]: Finished ldconfig.service. Mar 17 18:10:59.302000 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' Mar 17 18:10:59.481380 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 18:10:59.483180 systemd[1]: Mounting boot.mount... Mar 17 18:10:59.485101 systemd[1]: Mounting usr-share-oem.mount... Mar 17 18:10:59.491010 systemd[1]: Mounted boot.mount. Mar 17 18:10:59.492045 systemd[1]: Mounted usr-share-oem.mount. Mar 17 18:10:59.494140 systemd[1]: Finished systemd-sysext.service. Mar 17 18:10:59.494000 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' Mar 17 18:10:59.496477 systemd[1]: Starting ensure-sysext.service... Mar 17 18:10:59.498419 systemd[1]: Starting systemd-tmpfiles-setup.service... Mar 17 18:10:59.501502 systemd[1]: Finished systemd-boot-update.service. Mar 17 18:10:59.502000 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' Mar 17 18:10:59.503989 systemd[1]: Reloading. Mar 17 18:10:59.507360 systemd-tmpfiles[1093]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Mar 17 18:10:59.508356 systemd-tmpfiles[1093]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 18:10:59.509639 systemd-tmpfiles[1093]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 18:10:59.535140 /usr/lib/systemd/system-generators/torcx-generator[1113]: time="2025-03-17T18:10:59Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Mar 17 18:10:59.535170 /usr/lib/systemd/system-generators/torcx-generator[1113]: time="2025-03-17T18:10:59Z" level=info msg="torcx already run" Mar 17 18:10:59.593085 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Mar 17 18:10:59.593106 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Mar 17 18:10:59.609428 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. Mar 17 18:10:59.650000 audit: BPF prog-id=24 op=LOAD Mar 17 18:10:59.650000 audit: BPF prog-id=25 op=LOAD Mar 17 18:10:59.650000 audit: BPF prog-id=18 op=UNLOAD Mar 17 18:10:59.650000 audit: BPF prog-id=19 op=UNLOAD Mar 17 18:10:59.652000 audit: BPF prog-id=26 op=LOAD Mar 17 18:10:59.652000 audit: BPF prog-id=15 op=UNLOAD Mar 17 18:10:59.652000 audit: BPF prog-id=27 op=LOAD Mar 17 18:10:59.652000 audit: BPF prog-id=28 op=LOAD Mar 17 18:10:59.652000 audit: BPF prog-id=16 op=UNLOAD Mar 17 18:10:59.652000 audit: BPF prog-id=17 op=UNLOAD Mar 17 18:10:59.653000 audit: BPF prog-id=29 op=LOAD Mar 17 18:10:59.653000 audit: BPF prog-id=21 op=UNLOAD Mar 17 18:10:59.653000 audit: BPF prog-id=30 op=LOAD Mar 17 18:10:59.653000 audit: BPF prog-id=31 op=LOAD Mar 17 18:10:59.653000 audit: BPF prog-id=22 op=UNLOAD Mar 17 18:10:59.653000 audit: BPF prog-id=23 op=UNLOAD Mar 17 18:10:59.653000 audit: BPF prog-id=32 op=LOAD Mar 17 18:10:59.653000 audit: BPF prog-id=20 op=UNLOAD Mar 17 18:10:59.656075 systemd[1]: Finished systemd-tmpfiles-setup.service. Mar 17 18:10:59.656000 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' Mar 17 18:10:59.660345 systemd[1]: Starting audit-rules.service... Mar 17 18:10:59.662081 systemd[1]: Starting clean-ca-certificates.service... Mar 17 18:10:59.664372 systemd[1]: Starting systemd-journal-catalog-update.service... Mar 17 18:10:59.667000 audit: BPF prog-id=33 op=LOAD Mar 17 18:10:59.668323 systemd[1]: Starting systemd-resolved.service... Mar 17 18:10:59.671000 audit: BPF prog-id=34 op=LOAD Mar 17 18:10:59.672249 systemd[1]: Starting systemd-timesyncd.service... Mar 17 18:10:59.674169 systemd[1]: Starting systemd-update-utmp.service... Mar 17 18:10:59.678367 systemd[1]: Finished clean-ca-certificates.service. Mar 17 18:10:59.679000 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' Mar 17 18:10:59.680766 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.681929 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:10:59.681000 audit[1162]: SYSTEM_BOOT pid=1162 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' Mar 17 18:10:59.683832 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:10:59.685753 systemd[1]: Starting modprobe@loop.service... Mar 17 18:10:59.686588 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.686724 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). Mar 17 18:10:59.686834 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 17 18:10:59.687587 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:10:59.687715 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:10:59.688000 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' Mar 17 18:10:59.688000 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' Mar 17 18:10:59.688905 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:10:59.689009 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:10:59.689000 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' Mar 17 18:10:59.689000 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' Mar 17 18:10:59.690296 systemd[1]: Finished systemd-journal-catalog-update.service. Mar 17 18:10:59.691000 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' Mar 17 18:10:59.691617 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:10:59.691723 systemd[1]: Finished modprobe@loop.service. Mar 17 18:10:59.692000 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' Mar 17 18:10:59.692000 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' Mar 17 18:10:59.695656 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.696898 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:10:59.699026 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:10:59.701039 systemd[1]: Starting modprobe@loop.service... Mar 17 18:10:59.701936 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.702131 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). Mar 17 18:10:59.703839 systemd[1]: Starting systemd-update-done.service... Mar 17 18:10:59.704725 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 17 18:10:59.706026 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:10:59.706144 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:10:59.706000 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' Mar 17 18:10:59.706000 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' Mar 17 18:10:59.707487 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:10:59.707596 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:10:59.708000 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' Mar 17 18:10:59.708000 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' Mar 17 18:10:59.708977 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:10:59.709096 systemd[1]: Finished modprobe@loop.service. Mar 17 18:10:59.709000 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' Mar 17 18:10:59.709000 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' Mar 17 18:10:59.710499 systemd[1]: Finished systemd-update-done.service. Mar 17 18:10:59.711000 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' Mar 17 18:10:59.714184 systemd[1]: Finished systemd-update-utmp.service. Mar 17 18:10:59.715000 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' Mar 17 18:10:59.716756 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.717925 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:10:59.720009 systemd[1]: Starting modprobe@drm.service... Mar 17 18:10:59.721968 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:10:59.723479 augenrules[1180]: No rules Mar 17 18:10:59.723000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Mar 17 18:10:59.723000 audit[1180]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffdbe6eb20 a2=420 a3=0 items=0 ppid=1151 pid=1180 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) Mar 17 18:10:59.723000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Mar 17 18:10:59.723757 systemd[1]: Starting modprobe@loop.service... Mar 17 18:10:59.724681 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.724736 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). Mar 17 18:10:59.725728 systemd[1]: Starting systemd-networkd-wait-online.service... Mar 17 18:10:59.726731 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 17 18:10:59.727348 systemd[1]: Finished ensure-sysext.service. Mar 17 18:10:59.728506 systemd[1]: Finished audit-rules.service. Mar 17 18:10:59.729343 systemd-resolved[1155]: Positive Trust Anchors: Mar 17 18:10:59.729353 systemd-resolved[1155]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:10:59.729379 systemd-resolved[1155]: 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 Mar 17 18:10:59.729517 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:10:59.729620 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:10:59.730893 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 18:10:59.730997 systemd[1]: Finished modprobe@drm.service. Mar 17 18:10:59.732134 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:10:59.732244 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:10:59.733386 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:10:59.733491 systemd[1]: Finished modprobe@loop.service. Mar 17 18:10:59.735622 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:10:59.735680 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.745334 systemd[1]: Started systemd-timesyncd.service. Mar 17 18:10:59.746254 systemd-timesyncd[1161]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 17 18:10:59.746570 systemd-timesyncd[1161]: Initial clock synchronization to Mon 2025-03-17 18:10:59.728885 UTC. Mar 17 18:10:59.746586 systemd[1]: Reached target time-set.target. Mar 17 18:10:59.747407 systemd-resolved[1155]: Defaulting to hostname 'linux'. Mar 17 18:10:59.748734 systemd[1]: Started systemd-resolved.service. Mar 17 18:10:59.749625 systemd[1]: Reached target network.target. Mar 17 18:10:59.750441 systemd[1]: Reached target nss-lookup.target. Mar 17 18:10:59.751258 systemd[1]: Reached target sysinit.target. Mar 17 18:10:59.752098 systemd[1]: Started motdgen.path. Mar 17 18:10:59.752911 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Mar 17 18:10:59.754238 systemd[1]: Started logrotate.timer. Mar 17 18:10:59.755073 systemd[1]: Started mdadm.timer. Mar 17 18:10:59.755784 systemd[1]: Started systemd-tmpfiles-clean.timer. Mar 17 18:10:59.756731 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 18:10:59.756762 systemd[1]: Reached target paths.target. Mar 17 18:10:59.757557 systemd[1]: Reached target timers.target. Mar 17 18:10:59.758704 systemd[1]: Listening on dbus.socket. Mar 17 18:10:59.760567 systemd[1]: Starting docker.socket... Mar 17 18:10:59.763652 systemd[1]: Listening on sshd.socket. Mar 17 18:10:59.764516 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). Mar 17 18:10:59.764967 systemd[1]: Listening on docker.socket. Mar 17 18:10:59.765898 systemd[1]: Reached target sockets.target. Mar 17 18:10:59.766682 systemd[1]: Reached target basic.target. Mar 17 18:10:59.767489 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.767523 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:10:59.768494 systemd[1]: Starting containerd.service... Mar 17 18:10:59.770164 systemd[1]: Starting dbus.service... Mar 17 18:10:59.771923 systemd[1]: Starting enable-oem-cloudinit.service... Mar 17 18:10:59.773840 systemd[1]: Starting extend-filesystems.service... Mar 17 18:10:59.774844 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Mar 17 18:10:59.776183 systemd[1]: Starting motdgen.service... Mar 17 18:10:59.778200 systemd[1]: Starting ssh-key-proc-cmdline.service... Mar 17 18:10:59.782675 systemd[1]: Starting sshd-keygen.service... Mar 17 18:10:59.786900 systemd[1]: Starting systemd-logind.service... Mar 17 18:10:59.787782 jq[1193]: false Mar 17 18:10:59.787833 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:10:59.787914 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 18:10:59.788584 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 18:10:59.789342 systemd[1]: Starting update-engine.service... Mar 17 18:10:59.793759 systemd[1]: Starting update-ssh-keys-after-ignition.service... Mar 17 18:10:59.797532 jq[1210]: true Mar 17 18:10:59.797615 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 18:10:59.797806 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Mar 17 18:10:59.798136 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 18:10:59.798296 systemd[1]: Finished ssh-key-proc-cmdline.service. Mar 17 18:10:59.801451 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 18:10:59.801644 systemd[1]: Finished motdgen.service. Mar 17 18:10:59.806746 jq[1212]: true Mar 17 18:10:59.823557 extend-filesystems[1194]: Found loop1 Mar 17 18:10:59.824623 extend-filesystems[1194]: Found vda Mar 17 18:10:59.825396 extend-filesystems[1194]: Found vda1 Mar 17 18:10:59.826173 extend-filesystems[1194]: Found vda2 Mar 17 18:10:59.827578 extend-filesystems[1194]: Found vda3 Mar 17 18:10:59.828390 extend-filesystems[1194]: Found usr Mar 17 18:10:59.829263 extend-filesystems[1194]: Found vda4 Mar 17 18:10:59.830550 extend-filesystems[1194]: Found vda6 Mar 17 18:10:59.831404 extend-filesystems[1194]: Found vda7 Mar 17 18:10:59.832161 extend-filesystems[1194]: Found vda9 Mar 17 18:10:59.833127 extend-filesystems[1194]: Checking size of /dev/vda9 Mar 17 18:10:59.849856 systemd-logind[1204]: Watching system buttons on /dev/input/event0 (Power Button) Mar 17 18:10:59.850383 systemd-logind[1204]: New seat seat0. Mar 17 18:10:59.864667 extend-filesystems[1194]: Resized partition /dev/vda9 Mar 17 18:10:59.867618 dbus-daemon[1192]: [system] SELinux support is enabled Mar 17 18:10:59.867778 systemd[1]: Started dbus.service. Mar 17 18:10:59.872149 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). Mar 17 18:10:59.872178 systemd[1]: Reached target system-config.target. Mar 17 18:10:59.873345 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Mar 17 18:10:59.873369 systemd[1]: Reached target user-config.target. Mar 17 18:10:59.875359 extend-filesystems[1239]: resize2fs 1.46.5 (30-Dec-2021) Mar 17 18:10:59.877960 systemd[1]: Started systemd-logind.service. Mar 17 18:10:59.879539 bash[1236]: Updated "/home/core/.ssh/authorized_keys" Mar 17 18:10:59.879654 dbus-daemon[1192]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 17 18:10:59.880467 systemd[1]: Finished update-ssh-keys-after-ignition.service. Mar 17 18:10:59.887928 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Mar 17 18:10:59.889887 update_engine[1208]: I0317 18:10:59.889642 1208 main.cc:92] Flatcar Update Engine starting Mar 17 18:10:59.892093 systemd[1]: Started update-engine.service. Mar 17 18:10:59.892198 update_engine[1208]: I0317 18:10:59.892118 1208 update_check_scheduler.cc:74] Next update check in 11m4s Mar 17 18:10:59.895174 systemd[1]: Started locksmithd.service. Mar 17 18:10:59.913238 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Mar 17 18:10:59.923826 extend-filesystems[1239]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Mar 17 18:10:59.923826 extend-filesystems[1239]: old_desc_blocks = 1, new_desc_blocks = 1 Mar 17 18:10:59.923826 extend-filesystems[1239]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Mar 17 18:10:59.927786 extend-filesystems[1194]: Resized filesystem in /dev/vda9 Mar 17 18:10:59.926637 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 18:10:59.929552 env[1213]: time="2025-03-17T18:10:59.924477760Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Mar 17 18:10:59.926835 systemd[1]: Finished extend-filesystems.service. Mar 17 18:10:59.945058 env[1213]: time="2025-03-17T18:10:59.945003280Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 18:10:59.945224 env[1213]: time="2025-03-17T18:10:59.945197120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:10:59.946574 env[1213]: time="2025-03-17T18:10:59.946540520Z" 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.179-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:10:59.946574 env[1213]: time="2025-03-17T18:10:59.946571800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:10:59.946820 env[1213]: time="2025-03-17T18:10:59.946788360Z" 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 Mar 17 18:10:59.946865 env[1213]: time="2025-03-17T18:10:59.946820360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 18:10:59.946865 env[1213]: time="2025-03-17T18:10:59.946835520Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Mar 17 18:10:59.946865 env[1213]: time="2025-03-17T18:10:59.946845280Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 18:10:59.946957 env[1213]: time="2025-03-17T18:10:59.946919160Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:10:59.947203 env[1213]: time="2025-03-17T18:10:59.947183680Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:10:59.947748 env[1213]: time="2025-03-17T18:10:59.947721800Z" 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 Mar 17 18:10:59.947785 env[1213]: time="2025-03-17T18:10:59.947747760Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 18:10:59.947840 env[1213]: time="2025-03-17T18:10:59.947821520Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Mar 17 18:10:59.947892 env[1213]: time="2025-03-17T18:10:59.947840520Z" level=info msg="metadata content store policy set" policy=shared Mar 17 18:10:59.950343 locksmithd[1242]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 18:10:59.951137 env[1213]: time="2025-03-17T18:10:59.951109080Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 18:10:59.951184 env[1213]: time="2025-03-17T18:10:59.951139880Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 18:10:59.951184 env[1213]: time="2025-03-17T18:10:59.951153080Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 18:10:59.951246 env[1213]: time="2025-03-17T18:10:59.951192720Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951290 env[1213]: time="2025-03-17T18:10:59.951208320Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951327 env[1213]: time="2025-03-17T18:10:59.951293640Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951327 env[1213]: time="2025-03-17T18:10:59.951308480Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951644 env[1213]: time="2025-03-17T18:10:59.951625760Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951682 env[1213]: time="2025-03-17T18:10:59.951647040Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951682 env[1213]: time="2025-03-17T18:10:59.951661080Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951682 env[1213]: time="2025-03-17T18:10:59.951674760Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.951737 env[1213]: time="2025-03-17T18:10:59.951686480Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 18:10:59.951814 env[1213]: time="2025-03-17T18:10:59.951791000Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 18:10:59.951900 env[1213]: time="2025-03-17T18:10:59.951883360Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 18:10:59.952161 env[1213]: time="2025-03-17T18:10:59.952141600Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 18:10:59.952194 env[1213]: time="2025-03-17T18:10:59.952172120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952194 env[1213]: time="2025-03-17T18:10:59.952186080Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 18:10:59.952324 env[1213]: time="2025-03-17T18:10:59.952307880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952354 env[1213]: time="2025-03-17T18:10:59.952325720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952354 env[1213]: time="2025-03-17T18:10:59.952338360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952354 env[1213]: time="2025-03-17T18:10:59.952349280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952431 env[1213]: time="2025-03-17T18:10:59.952415120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952464 env[1213]: time="2025-03-17T18:10:59.952433760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952464 env[1213]: time="2025-03-17T18:10:59.952446280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952464 env[1213]: time="2025-03-17T18:10:59.952457800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952525 env[1213]: time="2025-03-17T18:10:59.952470560Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 18:10:59.952610 env[1213]: time="2025-03-17T18:10:59.952592000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952646 env[1213]: time="2025-03-17T18:10:59.952615920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952646 env[1213]: time="2025-03-17T18:10:59.952630160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952646 env[1213]: time="2025-03-17T18:10:59.952642040Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 18:10:59.952701 env[1213]: time="2025-03-17T18:10:59.952655400Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Mar 17 18:10:59.952701 env[1213]: time="2025-03-17T18:10:59.952666080Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 18:10:59.952701 env[1213]: time="2025-03-17T18:10:59.952682600Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Mar 17 18:10:59.952766 env[1213]: time="2025-03-17T18:10:59.952714360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 18:10:59.952955 env[1213]: time="2025-03-17T18:10:59.952904000Z" 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}" Mar 17 18:10:59.953538 env[1213]: time="2025-03-17T18:10:59.952961400Z" level=info msg="Connect containerd service" Mar 17 18:10:59.953538 env[1213]: time="2025-03-17T18:10:59.952989560Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 18:10:59.953629 env[1213]: time="2025-03-17T18:10:59.953600400Z" 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" Mar 17 18:10:59.953776 env[1213]: time="2025-03-17T18:10:59.953735680Z" level=info msg="Start subscribing containerd event" Mar 17 18:10:59.953814 env[1213]: time="2025-03-17T18:10:59.953792360Z" level=info msg="Start recovering state" Mar 17 18:10:59.953878 env[1213]: time="2025-03-17T18:10:59.953859960Z" level=info msg="Start event monitor" Mar 17 18:10:59.953909 env[1213]: time="2025-03-17T18:10:59.953886360Z" level=info msg="Start snapshots syncer" Mar 17 18:10:59.954035 env[1213]: time="2025-03-17T18:10:59.954017680Z" level=info msg="Start cni network conf syncer for default" Mar 17 18:10:59.954069 env[1213]: time="2025-03-17T18:10:59.954035120Z" level=info msg="Start streaming server" Mar 17 18:10:59.954127 env[1213]: time="2025-03-17T18:10:59.954097080Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 18:10:59.954179 env[1213]: time="2025-03-17T18:10:59.954160880Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 18:10:59.954241 env[1213]: time="2025-03-17T18:10:59.954225800Z" level=info msg="containerd successfully booted in 0.032603s" Mar 17 18:10:59.954298 systemd[1]: Started containerd.service. Mar 17 18:11:00.526367 systemd-networkd[1043]: eth0: Gained IPv6LL Mar 17 18:11:00.527953 systemd[1]: Finished systemd-networkd-wait-online.service. Mar 17 18:11:00.529184 systemd[1]: Reached target network-online.target. Mar 17 18:11:00.531556 systemd[1]: Starting kubelet.service... Mar 17 18:11:01.032200 systemd[1]: Started kubelet.service. Mar 17 18:11:01.508787 kubelet[1256]: E0317 18:11:01.508693 1256 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Mar 17 18:11:01.510399 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 17 18:11:01.510519 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 17 18:11:02.339184 sshd_keygen[1214]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 18:11:02.356726 systemd[1]: Finished sshd-keygen.service. Mar 17 18:11:02.359023 systemd[1]: Starting issuegen.service... Mar 17 18:11:02.363687 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 18:11:02.363856 systemd[1]: Finished issuegen.service. Mar 17 18:11:02.366173 systemd[1]: Starting systemd-user-sessions.service... Mar 17 18:11:02.372060 systemd[1]: Finished systemd-user-sessions.service. Mar 17 18:11:02.374386 systemd[1]: Started getty@tty1.service. Mar 17 18:11:02.376491 systemd[1]: Started serial-getty@ttyAMA0.service. Mar 17 18:11:02.377534 systemd[1]: Reached target getty.target. Mar 17 18:11:02.378370 systemd[1]: Reached target multi-user.target. Mar 17 18:11:02.380412 systemd[1]: Starting systemd-update-utmp-runlevel.service... Mar 17 18:11:02.386847 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Mar 17 18:11:02.387007 systemd[1]: Finished systemd-update-utmp-runlevel.service. Mar 17 18:11:02.388125 systemd[1]: Startup finished in 596ms (kernel) + 3.958s (initrd) + 5.849s (userspace) = 10.404s. Mar 17 18:11:05.284363 systemd[1]: Created slice system-sshd.slice. Mar 17 18:11:05.285476 systemd[1]: Started sshd@0-10.0.0.22:22-10.0.0.1:58584.service. Mar 17 18:11:05.325687 sshd[1279]: Accepted publickey for core from 10.0.0.1 port 58584 ssh2: RSA SHA256:hoQCPKafrT/V1URQ18ch5K7mLY85DMM2OIJJf47c8zQ Mar 17 18:11:05.328046 sshd[1279]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:11:05.339387 systemd[1]: Created slice user-500.slice. Mar 17 18:11:05.340438 systemd[1]: Starting user-runtime-dir@500.service... Mar 17 18:11:05.342065 systemd-logind[1204]: New session 1 of user core. Mar 17 18:11:05.348375 systemd[1]: Finished user-runtime-dir@500.service. Mar 17 18:11:05.349639 systemd[1]: Starting user@500.service... Mar 17 18:11:05.352384 (systemd)[1282]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:11:05.411649 systemd[1282]: Queued start job for default target default.target. Mar 17 18:11:05.412096 systemd[1282]: Reached target paths.target. Mar 17 18:11:05.412117 systemd[1282]: Reached target sockets.target. Mar 17 18:11:05.412129 systemd[1282]: Reached target timers.target. Mar 17 18:11:05.412139 systemd[1282]: Reached target basic.target. Mar 17 18:11:05.412191 systemd[1282]: Reached target default.target. Mar 17 18:11:05.412235 systemd[1282]: Startup finished in 53ms. Mar 17 18:11:05.412269 systemd[1]: Started user@500.service. Mar 17 18:11:05.413225 systemd[1]: Started session-1.scope. Mar 17 18:11:05.463285 systemd[1]: Started sshd@1-10.0.0.22:22-10.0.0.1:58588.service. Mar 17 18:11:05.510673 sshd[1291]: Accepted publickey for core from 10.0.0.1 port 58588 ssh2: RSA SHA256:hoQCPKafrT/V1URQ18ch5K7mLY85DMM2OIJJf47c8zQ Mar 17 18:11:05.511849 sshd[1291]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:11:05.515189 systemd-logind[1204]: New session 2 of user core. Mar 17 18:11:05.516341 systemd[1]: Started session-2.scope. Mar 17 18:11:05.569135 sshd[1291]: pam_unix(sshd:session): session closed for user core Mar 17 18:11:05.572424 systemd[1]: Started sshd@2-10.0.0.22:22-10.0.0.1:58594.service. Mar 17 18:11:05.572912 systemd[1]: sshd@1-10.0.0.22:22-10.0.0.1:58588.service: Deactivated successfully. Mar 17 18:11:05.573568 systemd[1]: session-2.scope: Deactivated successfully. Mar 17 18:11:05.574065 systemd-logind[1204]: Session 2 logged out. Waiting for processes to exit. Mar 17 18:11:05.574981 systemd-logind[1204]: Removed session 2. Mar 17 18:11:05.607164 sshd[1296]: Accepted publickey for core from 10.0.0.1 port 58594 ssh2: RSA SHA256:hoQCPKafrT/V1URQ18ch5K7mLY85DMM2OIJJf47c8zQ Mar 17 18:11:05.608396 sshd[1296]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:11:05.611399 systemd-logind[1204]: New session 3 of user core. Mar 17 18:11:05.612171 systemd[1]: Started session-3.scope. Mar 17 18:11:05.660999 sshd[1296]: pam_unix(sshd:session): session closed for user core Mar 17 18:11:05.663739 systemd[1]: sshd@2-10.0.0.22:22-10.0.0.1:58594.service: Deactivated successfully. Mar 17 18:11:05.664340 systemd[1]: session-3.scope: Deactivated successfully. Mar 17 18:11:05.664858 systemd-logind[1204]: Session 3 logged out. Waiting for processes to exit. Mar 17 18:11:05.665919 systemd[1]: Started sshd@3-10.0.0.22:22-10.0.0.1:58608.service. Mar 17 18:11:05.666550 systemd-logind[1204]: Removed session 3. Mar 17 18:11:05.699706 sshd[1303]: Accepted publickey for core from 10.0.0.1 port 58608 ssh2: RSA SHA256:hoQCPKafrT/V1URQ18ch5K7mLY85DMM2OIJJf47c8zQ Mar 17 18:11:05.700952 sshd[1303]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:11:05.704287 systemd-logind[1204]: New session 4 of user core. Mar 17 18:11:05.705112 systemd[1]: Started session-4.scope. Mar 17 18:11:05.758981 sshd[1303]: pam_unix(sshd:session): session closed for user core Mar 17 18:11:05.761678 systemd[1]: sshd@3-10.0.0.22:22-10.0.0.1:58608.service: Deactivated successfully. Mar 17 18:11:05.762320 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 18:11:05.762845 systemd-logind[1204]: Session 4 logged out. Waiting for processes to exit. Mar 17 18:11:05.763973 systemd[1]: Started sshd@4-10.0.0.22:22-10.0.0.1:58618.service. Mar 17 18:11:05.764687 systemd-logind[1204]: Removed session 4. Mar 17 18:11:05.799580 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 58618 ssh2: RSA SHA256:hoQCPKafrT/V1URQ18ch5K7mLY85DMM2OIJJf47c8zQ Mar 17 18:11:05.801291 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:11:05.806185 systemd[1]: Started session-5.scope. Mar 17 18:11:05.806684 systemd-logind[1204]: New session 5 of user core. Mar 17 18:11:05.872090 sudo[1312]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Mar 17 18:11:05.872336 sudo[1312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:11:05.884328 systemd[1]: Starting coreos-metadata.service... Mar 17 18:11:05.890407 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 17 18:11:05.890574 systemd[1]: Finished coreos-metadata.service. Mar 17 18:11:06.418826 systemd[1]: Stopped kubelet.service. Mar 17 18:11:06.420793 systemd[1]: Starting kubelet.service... Mar 17 18:11:06.444897 systemd[1]: Reloading. Mar 17 18:11:06.494069 /usr/lib/systemd/system-generators/torcx-generator[1378]: time="2025-03-17T18:11:06Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Mar 17 18:11:06.494100 /usr/lib/systemd/system-generators/torcx-generator[1378]: time="2025-03-17T18:11:06Z" level=info msg="torcx already run" Mar 17 18:11:06.618413 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Mar 17 18:11:06.618431 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Mar 17 18:11:06.633739 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. Mar 17 18:11:06.697677 systemd[1]: Started kubelet.service. Mar 17 18:11:06.713110 systemd[1]: Stopping kubelet.service... Mar 17 18:11:06.714406 systemd[1]: kubelet.service: Deactivated successfully. Mar 17 18:11:06.714593 systemd[1]: Stopped kubelet.service. Mar 17 18:11:06.716125 systemd[1]: Starting kubelet.service... Mar 17 18:11:06.808871 systemd[1]: Started kubelet.service. Mar 17 18:11:06.845125 kubelet[1426]: 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. Mar 17 18:11:06.845125 kubelet[1426]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Mar 17 18:11:06.845125 kubelet[1426]: 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. Mar 17 18:11:06.846697 kubelet[1426]: I0317 18:11:06.846649 1426 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Mar 17 18:11:07.438016 kubelet[1426]: I0317 18:11:07.437975 1426 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Mar 17 18:11:07.438016 kubelet[1426]: I0317 18:11:07.438005 1426 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Mar 17 18:11:07.438208 kubelet[1426]: I0317 18:11:07.438194 1426 server.go:927] "Client rotation is on, will bootstrap in background" Mar 17 18:11:07.481840 kubelet[1426]: I0317 18:11:07.481812 1426 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Mar 17 18:11:07.495338 kubelet[1426]: I0317 18:11:07.495317 1426 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Mar 17 18:11:07.495764 kubelet[1426]: I0317 18:11:07.495730 1426 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Mar 17 18:11:07.496573 kubelet[1426]: I0317 18:11:07.495755 1426 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.22","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} Mar 17 18:11:07.496697 kubelet[1426]: I0317 18:11:07.496681 1426 topology_manager.go:138] "Creating topology manager with none policy" Mar 17 18:11:07.496738 kubelet[1426]: I0317 18:11:07.496700 1426 container_manager_linux.go:301] "Creating device plugin manager" Mar 17 18:11:07.498523 kubelet[1426]: I0317 18:11:07.498470 1426 state_mem.go:36] "Initialized new in-memory state store" Mar 17 18:11:07.500001 kubelet[1426]: I0317 18:11:07.499976 1426 kubelet.go:400] "Attempting to sync node with API server" Mar 17 18:11:07.500001 kubelet[1426]: I0317 18:11:07.500001 1426 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Mar 17 18:11:07.500276 kubelet[1426]: I0317 18:11:07.500262 1426 kubelet.go:312] "Adding apiserver pod source" Mar 17 18:11:07.500420 kubelet[1426]: I0317 18:11:07.500402 1426 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Mar 17 18:11:07.500420 kubelet[1426]: E0317 18:11:07.500406 1426 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:07.500567 kubelet[1426]: E0317 18:11:07.500546 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:07.501692 kubelet[1426]: I0317 18:11:07.501675 1426 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Mar 17 18:11:07.502676 kubelet[1426]: I0317 18:11:07.502656 1426 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Mar 17 18:11:07.502779 kubelet[1426]: W0317 18:11:07.502767 1426 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Mar 17 18:11:07.503651 kubelet[1426]: I0317 18:11:07.503635 1426 server.go:1264] "Started kubelet" Mar 17 18:11:07.504024 kubelet[1426]: I0317 18:11:07.503993 1426 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Mar 17 18:11:07.504160 kubelet[1426]: I0317 18:11:07.504120 1426 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Mar 17 18:11:07.504443 kubelet[1426]: I0317 18:11:07.504428 1426 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Mar 17 18:11:07.516796 kubelet[1426]: E0317 18:11:07.516773 1426 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Mar 17 18:11:07.517100 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Mar 17 18:11:07.517143 kubelet[1426]: W0317 18:11:07.517098 1426 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Mar 17 18:11:07.517175 kubelet[1426]: E0317 18:11:07.517161 1426 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Mar 17 18:11:07.517227 kubelet[1426]: I0317 18:11:07.517199 1426 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Mar 17 18:11:07.517227 kubelet[1426]: W0317 18:11:07.517223 1426 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.22" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Mar 17 18:11:07.517289 kubelet[1426]: E0317 18:11:07.517236 1426 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.22" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Mar 17 18:11:07.517529 kubelet[1426]: I0317 18:11:07.517467 1426 volume_manager.go:291] "Starting Kubelet Volume Manager" Mar 17 18:11:07.517597 kubelet[1426]: I0317 18:11:07.517561 1426 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Mar 17 18:11:07.521112 kubelet[1426]: I0317 18:11:07.521081 1426 reconciler.go:26] "Reconciler: start to sync state" Mar 17 18:11:07.521478 kubelet[1426]: I0317 18:11:07.521454 1426 factory.go:221] Registration of the systemd container factory successfully Mar 17 18:11:07.521575 kubelet[1426]: I0317 18:11:07.521554 1426 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 Mar 17 18:11:07.521837 kubelet[1426]: I0317 18:11:07.521820 1426 server.go:455] "Adding debug handlers to kubelet server" Mar 17 18:11:07.523795 kubelet[1426]: I0317 18:11:07.523774 1426 factory.go:221] Registration of the containerd container factory successfully Mar 17 18:11:07.533228 kubelet[1426]: E0317 18:11:07.533129 1426 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.22\" not found" node="10.0.0.22" Mar 17 18:11:07.534402 kubelet[1426]: I0317 18:11:07.534388 1426 cpu_manager.go:214] "Starting CPU manager" policy="none" Mar 17 18:11:07.534499 kubelet[1426]: I0317 18:11:07.534486 1426 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Mar 17 18:11:07.534557 kubelet[1426]: I0317 18:11:07.534549 1426 state_mem.go:36] "Initialized new in-memory state store" Mar 17 18:11:07.613049 kubelet[1426]: I0317 18:11:07.613022 1426 policy_none.go:49] "None policy: Start" Mar 17 18:11:07.613968 kubelet[1426]: I0317 18:11:07.613947 1426 memory_manager.go:170] "Starting memorymanager" policy="None" Mar 17 18:11:07.614028 kubelet[1426]: I0317 18:11:07.613974 1426 state_mem.go:35] "Initializing new in-memory state store" Mar 17 18:11:07.617960 kubelet[1426]: I0317 18:11:07.617942 1426 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.22" Mar 17 18:11:07.622663 systemd[1]: Created slice kubepods.slice. Mar 17 18:11:07.625732 kubelet[1426]: I0317 18:11:07.625707 1426 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.22" Mar 17 18:11:07.627940 kubelet[1426]: I0317 18:11:07.627913 1426 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Mar 17 18:11:07.629951 env[1213]: time="2025-03-17T18:11:07.628518039Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Mar 17 18:11:07.629751 systemd[1]: Created slice kubepods-burstable.slice. Mar 17 18:11:07.630235 kubelet[1426]: I0317 18:11:07.630043 1426 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Mar 17 18:11:07.633423 systemd[1]: Created slice kubepods-besteffort.slice. Mar 17 18:11:07.640002 kubelet[1426]: I0317 18:11:07.639979 1426 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Mar 17 18:11:07.640291 kubelet[1426]: I0317 18:11:07.640255 1426 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Mar 17 18:11:07.640570 kubelet[1426]: I0317 18:11:07.640556 1426 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Mar 17 18:11:07.641684 kubelet[1426]: E0317 18:11:07.641651 1426 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.22\" not found" Mar 17 18:11:07.642862 kubelet[1426]: E0317 18:11:07.642827 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:07.700392 kubelet[1426]: I0317 18:11:07.700277 1426 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Mar 17 18:11:07.701169 kubelet[1426]: I0317 18:11:07.701142 1426 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Mar 17 18:11:07.701385 kubelet[1426]: I0317 18:11:07.701369 1426 status_manager.go:217] "Starting to sync pod status with apiserver" Mar 17 18:11:07.701435 kubelet[1426]: I0317 18:11:07.701395 1426 kubelet.go:2337] "Starting kubelet main sync loop" Mar 17 18:11:07.701489 kubelet[1426]: E0317 18:11:07.701473 1426 kubelet.go:2361] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Mar 17 18:11:07.745024 kubelet[1426]: E0317 18:11:07.744981 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:07.832897 sudo[1312]: pam_unix(sudo:session): session closed for user root Mar 17 18:11:07.834593 sshd[1309]: pam_unix(sshd:session): session closed for user core Mar 17 18:11:07.837019 systemd[1]: sshd@4-10.0.0.22:22-10.0.0.1:58618.service: Deactivated successfully. Mar 17 18:11:07.837732 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 18:11:07.838266 systemd-logind[1204]: Session 5 logged out. Waiting for processes to exit. Mar 17 18:11:07.839412 systemd-logind[1204]: Removed session 5. Mar 17 18:11:07.845559 kubelet[1426]: E0317 18:11:07.845529 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:07.946119 kubelet[1426]: E0317 18:11:07.946071 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.047010 kubelet[1426]: E0317 18:11:08.046899 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.147507 kubelet[1426]: E0317 18:11:08.147465 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.248025 kubelet[1426]: E0317 18:11:08.247981 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.348740 kubelet[1426]: E0317 18:11:08.348608 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.439895 kubelet[1426]: I0317 18:11:08.439841 1426 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Mar 17 18:11:08.440062 kubelet[1426]: W0317 18:11:08.440006 1426 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Mar 17 18:11:08.440062 kubelet[1426]: W0317 18:11:08.440039 1426 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Mar 17 18:11:08.449085 kubelet[1426]: E0317 18:11:08.449028 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.501688 kubelet[1426]: E0317 18:11:08.501643 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:08.549424 kubelet[1426]: E0317 18:11:08.549371 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.650197 kubelet[1426]: E0317 18:11:08.650067 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.751201 kubelet[1426]: E0317 18:11:08.751149 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.851479 kubelet[1426]: E0317 18:11:08.851434 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:08.952109 kubelet[1426]: E0317 18:11:08.951963 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:09.052350 kubelet[1426]: E0317 18:11:09.052310 1426 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.22\" not found" Mar 17 18:11:09.502051 kubelet[1426]: E0317 18:11:09.501941 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:09.502051 kubelet[1426]: I0317 18:11:09.502001 1426 apiserver.go:52] "Watching apiserver" Mar 17 18:11:09.518760 kubelet[1426]: I0317 18:11:09.518561 1426 topology_manager.go:215] "Topology Admit Handler" podUID="62844df4-8b7a-4356-abad-74811911b823" podNamespace="kube-flannel" podName="kube-flannel-ds-4rqdd" Mar 17 18:11:09.518905 kubelet[1426]: I0317 18:11:09.518886 1426 topology_manager.go:215] "Topology Admit Handler" podUID="85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40" podNamespace="kube-system" podName="kube-proxy-5ndxl" Mar 17 18:11:09.526233 systemd[1]: Created slice kubepods-besteffort-pod85b8e5cd_ea0a_452a_8d2c_8befa9ef3a40.slice. Mar 17 18:11:09.549706 systemd[1]: Created slice kubepods-burstable-pod62844df4_8b7a_4356_abad_74811911b823.slice. Mar 17 18:11:09.618811 kubelet[1426]: I0317 18:11:09.618763 1426 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Mar 17 18:11:09.633796 kubelet[1426]: I0317 18:11:09.633745 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/62844df4-8b7a-4356-abad-74811911b823-cni\") pod \"kube-flannel-ds-4rqdd\" (UID: \"62844df4-8b7a-4356-abad-74811911b823\") " pod="kube-flannel/kube-flannel-ds-4rqdd" Mar 17 18:11:09.633796 kubelet[1426]: I0317 18:11:09.633801 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/62844df4-8b7a-4356-abad-74811911b823-xtables-lock\") pod \"kube-flannel-ds-4rqdd\" (UID: \"62844df4-8b7a-4356-abad-74811911b823\") " pod="kube-flannel/kube-flannel-ds-4rqdd" Mar 17 18:11:09.633961 kubelet[1426]: I0317 18:11:09.633820 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-d9t5h\" (UniqueName: \"kubernetes.io/projected/62844df4-8b7a-4356-abad-74811911b823-kube-api-access-d9t5h\") pod \"kube-flannel-ds-4rqdd\" (UID: \"62844df4-8b7a-4356-abad-74811911b823\") " pod="kube-flannel/kube-flannel-ds-4rqdd" Mar 17 18:11:09.633961 kubelet[1426]: I0317 18:11:09.633839 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40-kube-proxy\") pod \"kube-proxy-5ndxl\" (UID: \"85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40\") " pod="kube-system/kube-proxy-5ndxl" Mar 17 18:11:09.633961 kubelet[1426]: I0317 18:11:09.633865 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sn4kg\" (UniqueName: \"kubernetes.io/projected/85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40-kube-api-access-sn4kg\") pod \"kube-proxy-5ndxl\" (UID: \"85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40\") " pod="kube-system/kube-proxy-5ndxl" Mar 17 18:11:09.633961 kubelet[1426]: I0317 18:11:09.633882 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/62844df4-8b7a-4356-abad-74811911b823-run\") pod \"kube-flannel-ds-4rqdd\" (UID: \"62844df4-8b7a-4356-abad-74811911b823\") " pod="kube-flannel/kube-flannel-ds-4rqdd" Mar 17 18:11:09.633961 kubelet[1426]: I0317 18:11:09.633897 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/62844df4-8b7a-4356-abad-74811911b823-cni-plugin\") pod \"kube-flannel-ds-4rqdd\" (UID: \"62844df4-8b7a-4356-abad-74811911b823\") " pod="kube-flannel/kube-flannel-ds-4rqdd" Mar 17 18:11:09.634083 kubelet[1426]: I0317 18:11:09.633910 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/62844df4-8b7a-4356-abad-74811911b823-flannel-cfg\") pod \"kube-flannel-ds-4rqdd\" (UID: \"62844df4-8b7a-4356-abad-74811911b823\") " pod="kube-flannel/kube-flannel-ds-4rqdd" Mar 17 18:11:09.634083 kubelet[1426]: I0317 18:11:09.633936 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40-xtables-lock\") pod \"kube-proxy-5ndxl\" (UID: \"85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40\") " pod="kube-system/kube-proxy-5ndxl" Mar 17 18:11:09.634083 kubelet[1426]: I0317 18:11:09.633952 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40-lib-modules\") pod \"kube-proxy-5ndxl\" (UID: \"85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40\") " pod="kube-system/kube-proxy-5ndxl" Mar 17 18:11:09.848987 kubelet[1426]: E0317 18:11:09.848812 1426 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" Mar 17 18:11:09.851723 env[1213]: time="2025-03-17T18:11:09.851657093Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5ndxl,Uid:85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40,Namespace:kube-system,Attempt:0,}" Mar 17 18:11:09.861143 kubelet[1426]: E0317 18:11:09.861067 1426 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" Mar 17 18:11:09.861797 env[1213]: time="2025-03-17T18:11:09.861749149Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4rqdd,Uid:62844df4-8b7a-4356-abad-74811911b823,Namespace:kube-flannel,Attempt:0,}" Mar 17 18:11:10.397946 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2083849934.mount: Deactivated successfully. Mar 17 18:11:10.404341 env[1213]: time="2025-03-17T18:11:10.404280863Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.405634 env[1213]: time="2025-03-17T18:11:10.405579607Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.406677 env[1213]: time="2025-03-17T18:11:10.406642884Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.409908 env[1213]: time="2025-03-17T18:11:10.409869494Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.412436 env[1213]: time="2025-03-17T18:11:10.412394423Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.413771 env[1213]: time="2025-03-17T18:11:10.413732304Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.415656 env[1213]: time="2025-03-17T18:11:10.415616276Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.417775 env[1213]: time="2025-03-17T18:11:10.417746868Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:10.456573 env[1213]: time="2025-03-17T18:11:10.456480586Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 18:11:10.456573 env[1213]: time="2025-03-17T18:11:10.456548747Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 18:11:10.456573 env[1213]: time="2025-03-17T18:11:10.456516766Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 18:11:10.456573 env[1213]: time="2025-03-17T18:11:10.456549707Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 18:11:10.456573 env[1213]: time="2025-03-17T18:11:10.456559501Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 18:11:10.456854 env[1213]: time="2025-03-17T18:11:10.456803963Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 18:11:10.457355 env[1213]: time="2025-03-17T18:11:10.457316512Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4 pid=1486 runtime=io.containerd.runc.v2 Mar 17 18:11:10.457966 env[1213]: time="2025-03-17T18:11:10.456789171Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/7773447f3b4fd0688833b31e7d1775a3e26939aa6adfe0d3085e387b5edc51f3 pid=1487 runtime=io.containerd.runc.v2 Mar 17 18:11:10.476181 systemd[1]: Started cri-containerd-389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4.scope. Mar 17 18:11:10.480061 systemd[1]: Started cri-containerd-7773447f3b4fd0688833b31e7d1775a3e26939aa6adfe0d3085e387b5edc51f3.scope. Mar 17 18:11:10.502472 kubelet[1426]: E0317 18:11:10.502431 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:10.514013 env[1213]: time="2025-03-17T18:11:10.513962634Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5ndxl,Uid:85b8e5cd-ea0a-452a-8d2c-8befa9ef3a40,Namespace:kube-system,Attempt:0,} returns sandbox id \"7773447f3b4fd0688833b31e7d1775a3e26939aa6adfe0d3085e387b5edc51f3\"" Mar 17 18:11:10.519715 kubelet[1426]: E0317 18:11:10.519685 1426 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" Mar 17 18:11:10.521401 env[1213]: time="2025-03-17T18:11:10.521370834Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.11\"" Mar 17 18:11:10.528893 env[1213]: time="2025-03-17T18:11:10.528843037Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4rqdd,Uid:62844df4-8b7a-4356-abad-74811911b823,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4\"" Mar 17 18:11:10.529406 kubelet[1426]: E0317 18:11:10.529388 1426 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" Mar 17 18:11:11.502787 kubelet[1426]: E0317 18:11:11.502733 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:11.543716 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1246400026.mount: Deactivated successfully. Mar 17 18:11:11.973733 env[1213]: time="2025-03-17T18:11:11.973607409Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.30.11,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:11.974945 env[1213]: time="2025-03-17T18:11:11.974918992Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:fe83790bf8a35411788b67fe5f0ce35309056c40530484d516af2ca01375220c,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:11.976023 env[1213]: time="2025-03-17T18:11:11.975990222Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.30.11,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:11.977478 env[1213]: time="2025-03-17T18:11:11.977432495Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:ea4da798040a18ed3f302e8d5f67307c7275a2a53bcf3d51bcec223acda84a55,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:11.978024 env[1213]: time="2025-03-17T18:11:11.977996156Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.11\" returns image reference \"sha256:fe83790bf8a35411788b67fe5f0ce35309056c40530484d516af2ca01375220c\"" Mar 17 18:11:11.980029 env[1213]: time="2025-03-17T18:11:11.979957433Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Mar 17 18:11:11.980980 env[1213]: time="2025-03-17T18:11:11.980942870Z" level=info msg="CreateContainer within sandbox \"7773447f3b4fd0688833b31e7d1775a3e26939aa6adfe0d3085e387b5edc51f3\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Mar 17 18:11:11.990575 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3921426968.mount: Deactivated successfully. Mar 17 18:11:11.995620 env[1213]: time="2025-03-17T18:11:11.995569615Z" level=info msg="CreateContainer within sandbox \"7773447f3b4fd0688833b31e7d1775a3e26939aa6adfe0d3085e387b5edc51f3\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"dfb99865f5210b8c114a31a3193984e27d466a3ec591f4a14d60ac6cbfed55c3\"" Mar 17 18:11:11.996370 env[1213]: time="2025-03-17T18:11:11.996329011Z" level=info msg="StartContainer for \"dfb99865f5210b8c114a31a3193984e27d466a3ec591f4a14d60ac6cbfed55c3\"" Mar 17 18:11:12.011377 systemd[1]: Started cri-containerd-dfb99865f5210b8c114a31a3193984e27d466a3ec591f4a14d60ac6cbfed55c3.scope. Mar 17 18:11:12.044362 env[1213]: time="2025-03-17T18:11:12.044315537Z" level=info msg="StartContainer for \"dfb99865f5210b8c114a31a3193984e27d466a3ec591f4a14d60ac6cbfed55c3\" returns successfully" Mar 17 18:11:12.503941 kubelet[1426]: E0317 18:11:12.503893 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:12.713762 kubelet[1426]: E0317 18:11:12.713684 1426 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" Mar 17 18:11:13.196162 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount277420739.mount: Deactivated successfully. Mar 17 18:11:13.237978 env[1213]: time="2025-03-17T18:11:13.237921547Z" 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:[],}" Mar 17 18:11:13.239485 env[1213]: time="2025-03-17T18:11:13.239449993Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:13.240802 env[1213]: time="2025-03-17T18:11:13.240772176Z" 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:[],}" Mar 17 18:11:13.242558 env[1213]: time="2025-03-17T18:11:13.242521718Z" 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:[],}" Mar 17 18:11:13.243086 env[1213]: time="2025-03-17T18:11:13.243044314Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Mar 17 18:11:13.245273 env[1213]: time="2025-03-17T18:11:13.245241608Z" level=info msg="CreateContainer within sandbox \"389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Mar 17 18:11:13.255473 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2871936125.mount: Deactivated successfully. Mar 17 18:11:13.260362 env[1213]: time="2025-03-17T18:11:13.260320804Z" level=info msg="CreateContainer within sandbox \"389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"a114568ca98f32691e1916dd3464760454002d84078cbbaa9f5da17b9e3595ac\"" Mar 17 18:11:13.260888 env[1213]: time="2025-03-17T18:11:13.260850636Z" level=info msg="StartContainer for \"a114568ca98f32691e1916dd3464760454002d84078cbbaa9f5da17b9e3595ac\"" Mar 17 18:11:13.273896 systemd[1]: Started cri-containerd-a114568ca98f32691e1916dd3464760454002d84078cbbaa9f5da17b9e3595ac.scope. Mar 17 18:11:13.314135 env[1213]: time="2025-03-17T18:11:13.314086607Z" level=info msg="StartContainer for \"a114568ca98f32691e1916dd3464760454002d84078cbbaa9f5da17b9e3595ac\" returns successfully" Mar 17 18:11:13.315018 systemd[1]: cri-containerd-a114568ca98f32691e1916dd3464760454002d84078cbbaa9f5da17b9e3595ac.scope: Deactivated successfully. Mar 17 18:11:13.475180 env[1213]: time="2025-03-17T18:11:13.475051454Z" level=info msg="shim disconnected" id=a114568ca98f32691e1916dd3464760454002d84078cbbaa9f5da17b9e3595ac Mar 17 18:11:13.475180 env[1213]: time="2025-03-17T18:11:13.475100311Z" level=warning msg="cleaning up after shim disconnected" id=a114568ca98f32691e1916dd3464760454002d84078cbbaa9f5da17b9e3595ac namespace=k8s.io Mar 17 18:11:13.475180 env[1213]: time="2025-03-17T18:11:13.475109467Z" level=info msg="cleaning up dead shim" Mar 17 18:11:13.482627 env[1213]: time="2025-03-17T18:11:13.482578098Z" level=warning msg="cleanup warnings time=\"2025-03-17T18:11:13Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1758 runtime=io.containerd.runc.v2\n" Mar 17 18:11:13.504155 kubelet[1426]: E0317 18:11:13.504107 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:13.716229 kubelet[1426]: E0317 18:11:13.715866 1426 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" Mar 17 18:11:13.716377 kubelet[1426]: E0317 18:11:13.716297 1426 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" Mar 17 18:11:13.716661 env[1213]: time="2025-03-17T18:11:13.716624446Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Mar 17 18:11:13.730156 kubelet[1426]: I0317 18:11:13.729930 1426 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-5ndxl" podStartSLOduration=5.271818711 podStartE2EDuration="6.729913238s" podCreationTimestamp="2025-03-17 18:11:07 +0000 UTC" firstStartedPulling="2025-03-17 18:11:10.520980695 +0000 UTC m=+3.708103564" lastFinishedPulling="2025-03-17 18:11:11.979075222 +0000 UTC m=+5.166198091" observedRunningTime="2025-03-17 18:11:12.737113397 +0000 UTC m=+5.924236266" watchObservedRunningTime="2025-03-17 18:11:13.729913238 +0000 UTC m=+6.917036107" Mar 17 18:11:14.119910 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3886718615.mount: Deactivated successfully. Mar 17 18:11:14.505114 kubelet[1426]: E0317 18:11:14.505010 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:14.917904 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3993846693.mount: Deactivated successfully. Mar 17 18:11:15.505427 kubelet[1426]: E0317 18:11:15.505361 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:15.618997 env[1213]: time="2025-03-17T18:11:15.618943214Z" 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:[],}" Mar 17 18:11:15.620964 env[1213]: time="2025-03-17T18:11:15.620935916Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:15.623118 env[1213]: time="2025-03-17T18:11:15.623087713Z" 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:[],}" Mar 17 18:11:15.625625 env[1213]: time="2025-03-17T18:11:15.625595683Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:15.626503 env[1213]: time="2025-03-17T18:11:15.626456090Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Mar 17 18:11:15.632696 env[1213]: time="2025-03-17T18:11:15.632645029Z" level=info msg="CreateContainer within sandbox \"389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Mar 17 18:11:15.646509 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2968675494.mount: Deactivated successfully. Mar 17 18:11:15.653373 env[1213]: time="2025-03-17T18:11:15.653310265Z" level=info msg="CreateContainer within sandbox \"389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd\"" Mar 17 18:11:15.654140 env[1213]: time="2025-03-17T18:11:15.654105339Z" level=info msg="StartContainer for \"eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd\"" Mar 17 18:11:15.676172 systemd[1]: Started cri-containerd-eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd.scope. Mar 17 18:11:15.739943 systemd[1]: cri-containerd-eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd.scope: Deactivated successfully. Mar 17 18:11:15.740105 env[1213]: time="2025-03-17T18:11:15.740042817Z" level=info msg="StartContainer for \"eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd\" returns successfully" Mar 17 18:11:15.765940 kubelet[1426]: I0317 18:11:15.764545 1426 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Mar 17 18:11:15.877906 env[1213]: time="2025-03-17T18:11:15.877858957Z" level=info msg="shim disconnected" id=eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd Mar 17 18:11:15.877906 env[1213]: time="2025-03-17T18:11:15.877903818Z" level=warning msg="cleaning up after shim disconnected" id=eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd namespace=k8s.io Mar 17 18:11:15.877906 env[1213]: time="2025-03-17T18:11:15.877914894Z" level=info msg="cleaning up dead shim" Mar 17 18:11:15.884785 env[1213]: time="2025-03-17T18:11:15.884741691Z" level=warning msg="cleanup warnings time=\"2025-03-17T18:11:15Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1812 runtime=io.containerd.runc.v2\n" Mar 17 18:11:16.506284 kubelet[1426]: E0317 18:11:16.506240 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:16.641938 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-eadd814b90a5b66e4340a5183f6abeaf4feba825c211886e22bf86da32aaa1bd-rootfs.mount: Deactivated successfully. Mar 17 18:11:16.725068 kubelet[1426]: E0317 18:11:16.725028 1426 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" Mar 17 18:11:16.726888 env[1213]: time="2025-03-17T18:11:16.726850190Z" level=info msg="CreateContainer within sandbox \"389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Mar 17 18:11:16.750068 env[1213]: time="2025-03-17T18:11:16.750021192Z" level=info msg="CreateContainer within sandbox \"389fcc5141efd226c7c32d0546d0cdb05760647e204f294d363b94fa3be27df4\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"7467b9d58eb6ef677954fa536e6c4e1d04ee1a46b49efa07c71420c44839bba8\"" Mar 17 18:11:16.750673 env[1213]: time="2025-03-17T18:11:16.750646791Z" level=info msg="StartContainer for \"7467b9d58eb6ef677954fa536e6c4e1d04ee1a46b49efa07c71420c44839bba8\"" Mar 17 18:11:16.765190 systemd[1]: Started cri-containerd-7467b9d58eb6ef677954fa536e6c4e1d04ee1a46b49efa07c71420c44839bba8.scope. Mar 17 18:11:16.802453 env[1213]: time="2025-03-17T18:11:16.802055805Z" level=info msg="StartContainer for \"7467b9d58eb6ef677954fa536e6c4e1d04ee1a46b49efa07c71420c44839bba8\" returns successfully" Mar 17 18:11:17.507295 kubelet[1426]: E0317 18:11:17.507228 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:17.727720 kubelet[1426]: E0317 18:11:17.727659 1426 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" Mar 17 18:11:17.896359 systemd-networkd[1043]: flannel.1: Link UP Mar 17 18:11:17.896366 systemd-networkd[1043]: flannel.1: Gained carrier Mar 17 18:11:18.507739 kubelet[1426]: E0317 18:11:18.507671 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:18.536294 kubelet[1426]: I0317 18:11:18.536203 1426 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-4rqdd" podStartSLOduration=6.441362084 podStartE2EDuration="11.536187111s" podCreationTimestamp="2025-03-17 18:11:07 +0000 UTC" firstStartedPulling="2025-03-17 18:11:10.533122771 +0000 UTC m=+3.720245640" lastFinishedPulling="2025-03-17 18:11:15.627947838 +0000 UTC m=+8.815070667" observedRunningTime="2025-03-17 18:11:17.744006815 +0000 UTC m=+10.931129684" watchObservedRunningTime="2025-03-17 18:11:18.536187111 +0000 UTC m=+11.723309980" Mar 17 18:11:18.536497 kubelet[1426]: I0317 18:11:18.536467 1426 topology_manager.go:215] "Topology Admit Handler" podUID="bfcec591-2b3e-4143-960e-e9ee192eb8de" podNamespace="default" podName="nginx-deployment-85f456d6dd-fn279" Mar 17 18:11:18.541874 systemd[1]: Created slice kubepods-besteffort-podbfcec591_2b3e_4143_960e_e9ee192eb8de.slice. Mar 17 18:11:18.590591 kubelet[1426]: I0317 18:11:18.590540 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-89gjc\" (UniqueName: \"kubernetes.io/projected/bfcec591-2b3e-4143-960e-e9ee192eb8de-kube-api-access-89gjc\") pod \"nginx-deployment-85f456d6dd-fn279\" (UID: \"bfcec591-2b3e-4143-960e-e9ee192eb8de\") " pod="default/nginx-deployment-85f456d6dd-fn279" Mar 17 18:11:18.728741 kubelet[1426]: E0317 18:11:18.728680 1426 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" Mar 17 18:11:18.844434 env[1213]: time="2025-03-17T18:11:18.844310886Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-fn279,Uid:bfcec591-2b3e-4143-960e-e9ee192eb8de,Namespace:default,Attempt:0,}" Mar 17 18:11:18.881632 systemd-networkd[1043]: cni0: Link UP Mar 17 18:11:18.892414 systemd-networkd[1043]: veth28141513: Link UP Mar 17 18:11:18.894548 kernel: cni0: port 1(veth28141513) entered blocking state Mar 17 18:11:18.894618 kernel: cni0: port 1(veth28141513) entered disabled state Mar 17 18:11:18.894660 kernel: device veth28141513 entered promiscuous mode Mar 17 18:11:18.896398 kernel: cni0: port 1(veth28141513) entered blocking state Mar 17 18:11:18.896451 kernel: cni0: port 1(veth28141513) entered forwarding state Mar 17 18:11:18.903395 kernel: cni0: port 1(veth28141513) entered disabled state Mar 17 18:11:18.908226 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth28141513: link becomes ready Mar 17 18:11:18.908331 kernel: cni0: port 1(veth28141513) entered blocking state Mar 17 18:11:18.908351 kernel: cni0: port 1(veth28141513) entered forwarding state Mar 17 18:11:18.908366 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): cni0: link becomes ready Mar 17 18:11:18.909340 systemd-networkd[1043]: veth28141513: Gained carrier Mar 17 18:11:18.909617 systemd-networkd[1043]: cni0: Gained carrier Mar 17 18:11:18.911040 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016928), "name":"cbr0", "type":"bridge"} Mar 17 18:11:18.911040 env[1213]: delegateAdd: netconf sent to delegate plugin: Mar 17 18:11:18.925833 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-03-17T18:11:18.925754657Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 18:11:18.926008 env[1213]: time="2025-03-17T18:11:18.925826672Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 18:11:18.926008 env[1213]: time="2025-03-17T18:11:18.925839908Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 18:11:18.926098 env[1213]: time="2025-03-17T18:11:18.926015928Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/19ed206ca19c3420aa584f8aada6eb2a382ce0b4c68ffb4125f5c5746e7c9961 pid=2000 runtime=io.containerd.runc.v2 Mar 17 18:11:18.939900 systemd[1]: Started cri-containerd-19ed206ca19c3420aa584f8aada6eb2a382ce0b4c68ffb4125f5c5746e7c9961.scope. Mar 17 18:11:18.965664 systemd-resolved[1155]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 17 18:11:18.984055 env[1213]: time="2025-03-17T18:11:18.984008952Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-fn279,Uid:bfcec591-2b3e-4143-960e-e9ee192eb8de,Namespace:default,Attempt:0,} returns sandbox id \"19ed206ca19c3420aa584f8aada6eb2a382ce0b4c68ffb4125f5c5746e7c9961\"" Mar 17 18:11:18.985883 env[1213]: time="2025-03-17T18:11:18.985841372Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 17 18:11:19.150347 systemd-networkd[1043]: flannel.1: Gained IPv6LL Mar 17 18:11:19.508438 kubelet[1426]: E0317 18:11:19.508318 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:20.113180 systemd-networkd[1043]: veth28141513: Gained IPv6LL Mar 17 18:11:20.175629 systemd-networkd[1043]: cni0: Gained IPv6LL Mar 17 18:11:20.509281 kubelet[1426]: E0317 18:11:20.509149 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:21.242883 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3784922304.mount: Deactivated successfully. Mar 17 18:11:21.509896 kubelet[1426]: E0317 18:11:21.509790 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:22.464665 env[1213]: time="2025-03-17T18:11:22.464616500Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:22.466164 env[1213]: time="2025-03-17T18:11:22.466128625Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:f660a383148a8217a75a455efeb8bfd4cbe3afa737712cc0e25f27c03b770dd4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:22.468038 env[1213]: time="2025-03-17T18:11:22.468009213Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:22.470087 env[1213]: time="2025-03-17T18:11:22.470060197Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:b927c62cc716b99bce51774b46a63feb63f5414c6f985fb80cacd1933bbd0e06,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:22.470882 env[1213]: time="2025-03-17T18:11:22.470849591Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f660a383148a8217a75a455efeb8bfd4cbe3afa737712cc0e25f27c03b770dd4\"" Mar 17 18:11:22.473202 env[1213]: time="2025-03-17T18:11:22.473170065Z" level=info msg="CreateContainer within sandbox \"19ed206ca19c3420aa584f8aada6eb2a382ce0b4c68ffb4125f5c5746e7c9961\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Mar 17 18:11:22.482953 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4095877072.mount: Deactivated successfully. Mar 17 18:11:22.485532 env[1213]: time="2025-03-17T18:11:22.485488086Z" level=info msg="CreateContainer within sandbox \"19ed206ca19c3420aa584f8aada6eb2a382ce0b4c68ffb4125f5c5746e7c9961\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"feed8ddfec8207fbd8fcd66052dfac86e80846d80a579f7e8c9ece6ff27cca9b\"" Mar 17 18:11:22.486106 env[1213]: time="2025-03-17T18:11:22.486043701Z" level=info msg="StartContainer for \"feed8ddfec8207fbd8fcd66052dfac86e80846d80a579f7e8c9ece6ff27cca9b\"" Mar 17 18:11:22.501484 systemd[1]: run-containerd-runc-k8s.io-feed8ddfec8207fbd8fcd66052dfac86e80846d80a579f7e8c9ece6ff27cca9b-runc.MBZYmB.mount: Deactivated successfully. Mar 17 18:11:22.503830 systemd[1]: Started cri-containerd-feed8ddfec8207fbd8fcd66052dfac86e80846d80a579f7e8c9ece6ff27cca9b.scope. Mar 17 18:11:22.510205 kubelet[1426]: E0317 18:11:22.510162 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:22.548130 env[1213]: time="2025-03-17T18:11:22.548074414Z" level=info msg="StartContainer for \"feed8ddfec8207fbd8fcd66052dfac86e80846d80a579f7e8c9ece6ff27cca9b\" returns successfully" Mar 17 18:11:22.745857 kubelet[1426]: I0317 18:11:22.745701 1426 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-fn279" podStartSLOduration=1.258993601 podStartE2EDuration="4.745683704s" podCreationTimestamp="2025-03-17 18:11:18 +0000 UTC" firstStartedPulling="2025-03-17 18:11:18.985358135 +0000 UTC m=+12.172481004" lastFinishedPulling="2025-03-17 18:11:22.472048238 +0000 UTC m=+15.659171107" observedRunningTime="2025-03-17 18:11:22.745673346 +0000 UTC m=+15.932796215" watchObservedRunningTime="2025-03-17 18:11:22.745683704 +0000 UTC m=+15.932806573" Mar 17 18:11:23.511076 kubelet[1426]: E0317 18:11:23.511028 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:24.512148 kubelet[1426]: E0317 18:11:24.512097 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:25.305510 kubelet[1426]: I0317 18:11:25.305462 1426 topology_manager.go:215] "Topology Admit Handler" podUID="4fc3b6cc-d894-4331-9282-e2540343ddc2" podNamespace="default" podName="nfs-server-provisioner-0" Mar 17 18:11:25.309871 systemd[1]: Created slice kubepods-besteffort-pod4fc3b6cc_d894_4331_9282_e2540343ddc2.slice. Mar 17 18:11:25.325715 kubelet[1426]: I0317 18:11:25.325681 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fw4mr\" (UniqueName: \"kubernetes.io/projected/4fc3b6cc-d894-4331-9282-e2540343ddc2-kube-api-access-fw4mr\") pod \"nfs-server-provisioner-0\" (UID: \"4fc3b6cc-d894-4331-9282-e2540343ddc2\") " pod="default/nfs-server-provisioner-0" Mar 17 18:11:25.325833 kubelet[1426]: I0317 18:11:25.325733 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/4fc3b6cc-d894-4331-9282-e2540343ddc2-data\") pod \"nfs-server-provisioner-0\" (UID: \"4fc3b6cc-d894-4331-9282-e2540343ddc2\") " pod="default/nfs-server-provisioner-0" Mar 17 18:11:25.513279 kubelet[1426]: E0317 18:11:25.513194 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:25.612825 env[1213]: time="2025-03-17T18:11:25.612435199Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:4fc3b6cc-d894-4331-9282-e2540343ddc2,Namespace:default,Attempt:0,}" Mar 17 18:11:25.630209 kernel: cni0: port 2(vethe6f40f0c) entered blocking state Mar 17 18:11:25.630300 kernel: cni0: port 2(vethe6f40f0c) entered disabled state Mar 17 18:11:25.630322 kernel: device vethe6f40f0c entered promiscuous mode Mar 17 18:11:25.629816 systemd-networkd[1043]: vethe6f40f0c: Link UP Mar 17 18:11:25.635316 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Mar 17 18:11:25.635414 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethe6f40f0c: link becomes ready Mar 17 18:11:25.635436 kernel: cni0: port 2(vethe6f40f0c) entered blocking state Mar 17 18:11:25.636661 kernel: cni0: port 2(vethe6f40f0c) entered forwarding state Mar 17 18:11:25.636806 systemd-networkd[1043]: vethe6f40f0c: Gained carrier Mar 17 18:11:25.641056 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Mar 17 18:11:25.641056 env[1213]: delegateAdd: netconf sent to delegate plugin: Mar 17 18:11:25.651044 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-03-17T18:11:25.650975342Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 18:11:25.651044 env[1213]: time="2025-03-17T18:11:25.651015734Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 18:11:25.651044 env[1213]: time="2025-03-17T18:11:25.651026132Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 18:11:25.651568 env[1213]: time="2025-03-17T18:11:25.651527224Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/c8b33e5c94e0f4fa7830f5df991a2b635c2910f7ad99b2197ac3817f03dce9dd pid=2159 runtime=io.containerd.runc.v2 Mar 17 18:11:25.663981 systemd[1]: Started cri-containerd-c8b33e5c94e0f4fa7830f5df991a2b635c2910f7ad99b2197ac3817f03dce9dd.scope. Mar 17 18:11:25.685926 systemd-resolved[1155]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 17 18:11:25.701182 env[1213]: time="2025-03-17T18:11:25.701135224Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:4fc3b6cc-d894-4331-9282-e2540343ddc2,Namespace:default,Attempt:0,} returns sandbox id \"c8b33e5c94e0f4fa7830f5df991a2b635c2910f7ad99b2197ac3817f03dce9dd\"" Mar 17 18:11:25.703527 env[1213]: time="2025-03-17T18:11:25.702699368Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Mar 17 18:11:26.513800 kubelet[1426]: E0317 18:11:26.513750 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:27.022645 systemd-networkd[1043]: vethe6f40f0c: Gained IPv6LL Mar 17 18:11:27.500826 kubelet[1426]: E0317 18:11:27.500481 1426 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:27.514790 kubelet[1426]: E0317 18:11:27.514763 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:27.899912 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount846444322.mount: Deactivated successfully. Mar 17 18:11:28.515729 kubelet[1426]: E0317 18:11:28.515689 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:29.516729 kubelet[1426]: E0317 18:11:29.516676 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:29.728317 env[1213]: time="2025-03-17T18:11:29.728264213Z" 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:[],}" Mar 17 18:11:29.729670 env[1213]: time="2025-03-17T18:11:29.729636465Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:29.731543 env[1213]: time="2025-03-17T18:11:29.731515112Z" 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:[],}" Mar 17 18:11:29.733545 env[1213]: time="2025-03-17T18:11:29.733518779Z" 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:[],}" Mar 17 18:11:29.734256 env[1213]: time="2025-03-17T18:11:29.734201505Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Mar 17 18:11:29.736467 env[1213]: time="2025-03-17T18:11:29.736431055Z" level=info msg="CreateContainer within sandbox \"c8b33e5c94e0f4fa7830f5df991a2b635c2910f7ad99b2197ac3817f03dce9dd\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Mar 17 18:11:29.755129 env[1213]: time="2025-03-17T18:11:29.755093591Z" level=info msg="CreateContainer within sandbox \"c8b33e5c94e0f4fa7830f5df991a2b635c2910f7ad99b2197ac3817f03dce9dd\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"96d402a3a509afb4951d7e537d5841884c327fa92490b30051d5061f1550054f\"" Mar 17 18:11:29.755486 env[1213]: time="2025-03-17T18:11:29.755462090Z" level=info msg="StartContainer for \"96d402a3a509afb4951d7e537d5841884c327fa92490b30051d5061f1550054f\"" Mar 17 18:11:29.773045 systemd[1]: Started cri-containerd-96d402a3a509afb4951d7e537d5841884c327fa92490b30051d5061f1550054f.scope. Mar 17 18:11:29.838279 env[1213]: time="2025-03-17T18:11:29.838235206Z" level=info msg="StartContainer for \"96d402a3a509afb4951d7e537d5841884c327fa92490b30051d5061f1550054f\" returns successfully" Mar 17 18:11:30.517461 kubelet[1426]: E0317 18:11:30.517412 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:30.762757 kubelet[1426]: I0317 18:11:30.762688 1426 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.72995877 podStartE2EDuration="5.76267261s" podCreationTimestamp="2025-03-17 18:11:25 +0000 UTC" firstStartedPulling="2025-03-17 18:11:25.702358761 +0000 UTC m=+18.889481590" lastFinishedPulling="2025-03-17 18:11:29.735072601 +0000 UTC m=+22.922195430" observedRunningTime="2025-03-17 18:11:30.762623337 +0000 UTC m=+23.949746206" watchObservedRunningTime="2025-03-17 18:11:30.76267261 +0000 UTC m=+23.949795439" Mar 17 18:11:31.517870 kubelet[1426]: E0317 18:11:31.517835 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:32.518350 kubelet[1426]: E0317 18:11:32.518304 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:33.518453 kubelet[1426]: E0317 18:11:33.518413 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:34.518967 kubelet[1426]: E0317 18:11:34.518929 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:35.520226 kubelet[1426]: E0317 18:11:35.520161 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:36.520350 kubelet[1426]: E0317 18:11:36.520280 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:37.521302 kubelet[1426]: E0317 18:11:37.521251 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:38.521958 kubelet[1426]: E0317 18:11:38.521908 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:39.522906 kubelet[1426]: E0317 18:11:39.522866 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:39.595119 kubelet[1426]: I0317 18:11:39.595081 1426 topology_manager.go:215] "Topology Admit Handler" podUID="968f1fdb-f33c-4ff3-8c8f-db2b9064754d" podNamespace="default" podName="test-pod-1" Mar 17 18:11:39.600127 systemd[1]: Created slice kubepods-besteffort-pod968f1fdb_f33c_4ff3_8c8f_db2b9064754d.slice. Mar 17 18:11:39.706543 kubelet[1426]: I0317 18:11:39.706489 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9j8qs\" (UniqueName: \"kubernetes.io/projected/968f1fdb-f33c-4ff3-8c8f-db2b9064754d-kube-api-access-9j8qs\") pod \"test-pod-1\" (UID: \"968f1fdb-f33c-4ff3-8c8f-db2b9064754d\") " pod="default/test-pod-1" Mar 17 18:11:39.706543 kubelet[1426]: I0317 18:11:39.706534 1426 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-f5a4d1c5-69ce-46b4-8ede-89d948b96cca\" (UniqueName: \"kubernetes.io/nfs/968f1fdb-f33c-4ff3-8c8f-db2b9064754d-pvc-f5a4d1c5-69ce-46b4-8ede-89d948b96cca\") pod \"test-pod-1\" (UID: \"968f1fdb-f33c-4ff3-8c8f-db2b9064754d\") " pod="default/test-pod-1" Mar 17 18:11:39.832240 kernel: FS-Cache: Loaded Mar 17 18:11:39.866382 kernel: RPC: Registered named UNIX socket transport module. Mar 17 18:11:39.866525 kernel: RPC: Registered udp transport module. Mar 17 18:11:39.867932 kernel: RPC: Registered tcp transport module. Mar 17 18:11:39.868010 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 17 18:11:39.911251 kernel: FS-Cache: Netfs 'nfs' registered for caching Mar 17 18:11:40.057697 kernel: NFS: Registering the id_resolver key type Mar 17 18:11:40.057835 kernel: Key type id_resolver registered Mar 17 18:11:40.057864 kernel: Key type id_legacy registered Mar 17 18:11:40.087899 nfsidmap[2343]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 17 18:11:40.091071 nfsidmap[2346]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 17 18:11:40.202377 env[1213]: time="2025-03-17T18:11:40.202334689Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:968f1fdb-f33c-4ff3-8c8f-db2b9064754d,Namespace:default,Attempt:0,}" Mar 17 18:11:40.224116 systemd-networkd[1043]: veth19cab955: Link UP Mar 17 18:11:40.227879 kernel: cni0: port 3(veth19cab955) entered blocking state Mar 17 18:11:40.227956 kernel: cni0: port 3(veth19cab955) entered disabled state Mar 17 18:11:40.227989 kernel: device veth19cab955 entered promiscuous mode Mar 17 18:11:40.234728 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Mar 17 18:11:40.234818 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth19cab955: link becomes ready Mar 17 18:11:40.234839 kernel: cni0: port 3(veth19cab955) entered blocking state Mar 17 18:11:40.236227 kernel: cni0: port 3(veth19cab955) entered forwarding state Mar 17 18:11:40.236265 systemd-networkd[1043]: veth19cab955: Gained carrier Mar 17 18:11:40.238067 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000018928), "name":"cbr0", "type":"bridge"} Mar 17 18:11:40.238067 env[1213]: delegateAdd: netconf sent to delegate plugin: Mar 17 18:11:40.247683 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-03-17T18:11:40.247603468Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 18:11:40.247683 env[1213]: time="2025-03-17T18:11:40.247654704Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 18:11:40.247683 env[1213]: time="2025-03-17T18:11:40.247664903Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 18:11:40.247878 env[1213]: time="2025-03-17T18:11:40.247822610Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/5a7e12ba206cd4cb76ab98f837f76a27b47834ac9db0be2fc196f16a1bf32e7b pid=2392 runtime=io.containerd.runc.v2 Mar 17 18:11:40.258428 systemd[1]: Started cri-containerd-5a7e12ba206cd4cb76ab98f837f76a27b47834ac9db0be2fc196f16a1bf32e7b.scope. Mar 17 18:11:40.280111 systemd-resolved[1155]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 17 18:11:40.296266 env[1213]: time="2025-03-17T18:11:40.296224613Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:968f1fdb-f33c-4ff3-8c8f-db2b9064754d,Namespace:default,Attempt:0,} returns sandbox id \"5a7e12ba206cd4cb76ab98f837f76a27b47834ac9db0be2fc196f16a1bf32e7b\"" Mar 17 18:11:40.298014 env[1213]: time="2025-03-17T18:11:40.297964351Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 17 18:11:40.524374 kubelet[1426]: E0317 18:11:40.523748 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:40.582667 env[1213]: time="2025-03-17T18:11:40.582611760Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:40.583870 env[1213]: time="2025-03-17T18:11:40.583834220Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:f660a383148a8217a75a455efeb8bfd4cbe3afa737712cc0e25f27c03b770dd4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:40.585451 env[1213]: time="2025-03-17T18:11:40.585411731Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:40.587994 env[1213]: time="2025-03-17T18:11:40.587958923Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:b927c62cc716b99bce51774b46a63feb63f5414c6f985fb80cacd1933bbd0e06,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Mar 17 18:11:40.588748 env[1213]: time="2025-03-17T18:11:40.588718221Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f660a383148a8217a75a455efeb8bfd4cbe3afa737712cc0e25f27c03b770dd4\"" Mar 17 18:11:40.591118 env[1213]: time="2025-03-17T18:11:40.591088947Z" level=info msg="CreateContainer within sandbox \"5a7e12ba206cd4cb76ab98f837f76a27b47834ac9db0be2fc196f16a1bf32e7b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Mar 17 18:11:40.604555 env[1213]: time="2025-03-17T18:11:40.604494371Z" level=info msg="CreateContainer within sandbox \"5a7e12ba206cd4cb76ab98f837f76a27b47834ac9db0be2fc196f16a1bf32e7b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"e254d610f3e2b9465cbf14662dca4bf47ac02a12f4983e6fb9b1a9f867e1dc25\"" Mar 17 18:11:40.606445 env[1213]: time="2025-03-17T18:11:40.605167196Z" level=info msg="StartContainer for \"e254d610f3e2b9465cbf14662dca4bf47ac02a12f4983e6fb9b1a9f867e1dc25\"" Mar 17 18:11:40.621537 systemd[1]: Started cri-containerd-e254d610f3e2b9465cbf14662dca4bf47ac02a12f4983e6fb9b1a9f867e1dc25.scope. Mar 17 18:11:40.673040 env[1213]: time="2025-03-17T18:11:40.672991771Z" level=info msg="StartContainer for \"e254d610f3e2b9465cbf14662dca4bf47ac02a12f4983e6fb9b1a9f867e1dc25\" returns successfully" Mar 17 18:11:40.777986 kubelet[1426]: I0317 18:11:40.777792 1426 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.485643648 podStartE2EDuration="15.777776125s" podCreationTimestamp="2025-03-17 18:11:25 +0000 UTC" firstStartedPulling="2025-03-17 18:11:40.297632218 +0000 UTC m=+33.484755087" lastFinishedPulling="2025-03-17 18:11:40.589764695 +0000 UTC m=+33.776887564" observedRunningTime="2025-03-17 18:11:40.777309763 +0000 UTC m=+33.964432632" watchObservedRunningTime="2025-03-17 18:11:40.777776125 +0000 UTC m=+33.964898994" Mar 17 18:11:41.486371 systemd-networkd[1043]: veth19cab955: Gained IPv6LL Mar 17 18:11:41.524164 kubelet[1426]: E0317 18:11:41.524110 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 17 18:11:42.524981 kubelet[1426]: E0317 18:11:42.524925 1426 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"