May 16 00:41:25.741429 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 16 00:41:25.741448 kernel: Linux version 5.15.181-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Thu May 15 23:21:39 -00 2025 May 16 00:41:25.741456 kernel: efi: EFI v2.70 by EDK II May 16 00:41:25.741462 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 May 16 00:41:25.741467 kernel: random: crng init done May 16 00:41:25.741473 kernel: ACPI: Early table checksum verification disabled May 16 00:41:25.741479 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) May 16 00:41:25.741486 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) May 16 00:41:25.741491 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741497 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741502 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741508 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741513 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741518 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741526 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741532 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741538 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:41:25.741543 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 16 00:41:25.741549 kernel: NUMA: Failed to initialise from firmware May 16 00:41:25.741555 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 16 00:41:25.741560 kernel: NUMA: NODE_DATA [mem 0xdcb0c900-0xdcb11fff] May 16 00:41:25.741566 kernel: Zone ranges: May 16 00:41:25.741572 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 16 00:41:25.741578 kernel: DMA32 empty May 16 00:41:25.741584 kernel: Normal empty May 16 00:41:25.741589 kernel: Movable zone start for each node May 16 00:41:25.741595 kernel: Early memory node ranges May 16 00:41:25.741600 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] May 16 00:41:25.741606 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] May 16 00:41:25.741612 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] May 16 00:41:25.741618 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] May 16 00:41:25.741623 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] May 16 00:41:25.741629 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] May 16 00:41:25.741634 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] May 16 00:41:25.741640 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 16 00:41:25.741647 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 16 00:41:25.741652 kernel: psci: probing for conduit method from ACPI. May 16 00:41:25.741658 kernel: psci: PSCIv1.1 detected in firmware. May 16 00:41:25.741663 kernel: psci: Using standard PSCI v0.2 function IDs May 16 00:41:25.741669 kernel: psci: Trusted OS migration not required May 16 00:41:25.741677 kernel: psci: SMC Calling Convention v1.1 May 16 00:41:25.741683 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 16 00:41:25.741690 kernel: ACPI: SRAT not present May 16 00:41:25.741697 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 May 16 00:41:25.741703 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 May 16 00:41:25.741709 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 16 00:41:25.741715 kernel: Detected PIPT I-cache on CPU0 May 16 00:41:25.741740 kernel: CPU features: detected: GIC system register CPU interface May 16 00:41:25.741746 kernel: CPU features: detected: Hardware dirty bit management May 16 00:41:25.741752 kernel: CPU features: detected: Spectre-v4 May 16 00:41:25.741758 kernel: CPU features: detected: Spectre-BHB May 16 00:41:25.741766 kernel: CPU features: kernel page table isolation forced ON by KASLR May 16 00:41:25.741772 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 16 00:41:25.741778 kernel: CPU features: detected: ARM erratum 1418040 May 16 00:41:25.741784 kernel: CPU features: detected: SSBS not fully self-synchronizing May 16 00:41:25.741790 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 16 00:41:25.741796 kernel: Policy zone: DMA May 16 00:41:25.741803 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=2d88e96fdc9dc9b028836e57c250f3fd2abd3e6490e27ecbf72d8b216e3efce8 May 16 00:41:25.741809 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 16 00:41:25.741816 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 16 00:41:25.741822 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 16 00:41:25.741828 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 16 00:41:25.741835 kernel: Memory: 2457344K/2572288K available (9792K kernel code, 2094K rwdata, 7584K rodata, 36480K init, 777K bss, 114944K reserved, 0K cma-reserved) May 16 00:41:25.741842 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 16 00:41:25.741848 kernel: trace event string verifier disabled May 16 00:41:25.741854 kernel: rcu: Preemptible hierarchical RCU implementation. May 16 00:41:25.741860 kernel: rcu: RCU event tracing is enabled. May 16 00:41:25.741866 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 16 00:41:25.741873 kernel: Trampoline variant of Tasks RCU enabled. May 16 00:41:25.741879 kernel: Tracing variant of Tasks RCU enabled. May 16 00:41:25.741886 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 16 00:41:25.741892 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 16 00:41:25.741898 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 16 00:41:25.741905 kernel: GICv3: 256 SPIs implemented May 16 00:41:25.741911 kernel: GICv3: 0 Extended SPIs implemented May 16 00:41:25.741917 kernel: GICv3: Distributor has no Range Selector support May 16 00:41:25.741923 kernel: Root IRQ handler: gic_handle_irq May 16 00:41:25.741929 kernel: GICv3: 16 PPIs implemented May 16 00:41:25.741935 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 16 00:41:25.741947 kernel: ACPI: SRAT not present May 16 00:41:25.741954 kernel: ITS [mem 0x08080000-0x0809ffff] May 16 00:41:25.741960 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) May 16 00:41:25.741967 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) May 16 00:41:25.741973 kernel: GICv3: using LPI property table @0x00000000400d0000 May 16 00:41:25.741979 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 May 16 00:41:25.741987 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 16 00:41:25.741993 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 16 00:41:25.742000 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 16 00:41:25.742006 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 16 00:41:25.742012 kernel: arm-pv: using stolen time PV May 16 00:41:25.742018 kernel: Console: colour dummy device 80x25 May 16 00:41:25.742025 kernel: ACPI: Core revision 20210730 May 16 00:41:25.742031 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 16 00:41:25.742038 kernel: pid_max: default: 32768 minimum: 301 May 16 00:41:25.742044 kernel: LSM: Security Framework initializing May 16 00:41:25.742052 kernel: SELinux: Initializing. May 16 00:41:25.742058 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 16 00:41:25.742065 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 16 00:41:25.742071 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 16 00:41:25.742077 kernel: rcu: Hierarchical SRCU implementation. May 16 00:41:25.742083 kernel: Platform MSI: ITS@0x8080000 domain created May 16 00:41:25.742089 kernel: PCI/MSI: ITS@0x8080000 domain created May 16 00:41:25.742095 kernel: Remapping and enabling EFI services. May 16 00:41:25.742102 kernel: smp: Bringing up secondary CPUs ... May 16 00:41:25.742109 kernel: Detected PIPT I-cache on CPU1 May 16 00:41:25.742115 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 16 00:41:25.742122 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 May 16 00:41:25.742128 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 16 00:41:25.742134 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 16 00:41:25.742140 kernel: Detected PIPT I-cache on CPU2 May 16 00:41:25.742148 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 16 00:41:25.742158 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 May 16 00:41:25.742164 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 16 00:41:25.742170 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 16 00:41:25.742178 kernel: Detected PIPT I-cache on CPU3 May 16 00:41:25.742184 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 16 00:41:25.742190 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 May 16 00:41:25.742196 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 16 00:41:25.742207 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 16 00:41:25.742215 kernel: smp: Brought up 1 node, 4 CPUs May 16 00:41:25.742222 kernel: SMP: Total of 4 processors activated. May 16 00:41:25.742228 kernel: CPU features: detected: 32-bit EL0 Support May 16 00:41:25.742235 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 16 00:41:25.742241 kernel: CPU features: detected: Common not Private translations May 16 00:41:25.742248 kernel: CPU features: detected: CRC32 instructions May 16 00:41:25.742254 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 16 00:41:25.742262 kernel: CPU features: detected: LSE atomic instructions May 16 00:41:25.742269 kernel: CPU features: detected: Privileged Access Never May 16 00:41:25.742275 kernel: CPU features: detected: RAS Extension Support May 16 00:41:25.742281 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 16 00:41:25.742288 kernel: CPU: All CPU(s) started at EL1 May 16 00:41:25.742296 kernel: alternatives: patching kernel code May 16 00:41:25.742302 kernel: devtmpfs: initialized May 16 00:41:25.742309 kernel: KASLR enabled May 16 00:41:25.742315 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 16 00:41:25.742322 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 16 00:41:25.742329 kernel: pinctrl core: initialized pinctrl subsystem May 16 00:41:25.742335 kernel: SMBIOS 3.0.0 present. May 16 00:41:25.742342 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 May 16 00:41:25.742348 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 16 00:41:25.742356 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 16 00:41:25.742363 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 16 00:41:25.742369 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 16 00:41:25.742376 kernel: audit: initializing netlink subsys (disabled) May 16 00:41:25.742383 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 May 16 00:41:25.742389 kernel: thermal_sys: Registered thermal governor 'step_wise' May 16 00:41:25.742396 kernel: cpuidle: using governor menu May 16 00:41:25.742402 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 16 00:41:25.742409 kernel: ASID allocator initialised with 32768 entries May 16 00:41:25.742416 kernel: ACPI: bus type PCI registered May 16 00:41:25.742423 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 16 00:41:25.742429 kernel: Serial: AMBA PL011 UART driver May 16 00:41:25.742436 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages May 16 00:41:25.742442 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages May 16 00:41:25.742449 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages May 16 00:41:25.742456 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages May 16 00:41:25.742462 kernel: cryptd: max_cpu_qlen set to 1000 May 16 00:41:25.742469 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 16 00:41:25.742477 kernel: ACPI: Added _OSI(Module Device) May 16 00:41:25.742484 kernel: ACPI: Added _OSI(Processor Device) May 16 00:41:25.742491 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 16 00:41:25.742497 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 16 00:41:25.742504 kernel: ACPI: Added _OSI(Linux-Dell-Video) May 16 00:41:25.742510 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) May 16 00:41:25.742517 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) May 16 00:41:25.742523 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 16 00:41:25.742530 kernel: ACPI: Interpreter enabled May 16 00:41:25.742537 kernel: ACPI: Using GIC for interrupt routing May 16 00:41:25.742544 kernel: ACPI: MCFG table detected, 1 entries May 16 00:41:25.742550 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 16 00:41:25.742557 kernel: printk: console [ttyAMA0] enabled May 16 00:41:25.742564 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 16 00:41:25.742741 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 16 00:41:25.743298 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 16 00:41:25.743377 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 16 00:41:25.743433 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 16 00:41:25.743489 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 16 00:41:25.743498 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 16 00:41:25.743505 kernel: PCI host bridge to bus 0000:00 May 16 00:41:25.743573 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 16 00:41:25.743626 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 16 00:41:25.743680 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 16 00:41:25.743754 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 16 00:41:25.743835 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 16 00:41:25.743904 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 16 00:41:25.743982 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 16 00:41:25.744047 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 16 00:41:25.744109 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 16 00:41:25.744172 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 16 00:41:25.744229 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 16 00:41:25.744289 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 16 00:41:25.744341 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 16 00:41:25.744406 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 16 00:41:25.744457 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 16 00:41:25.744466 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 16 00:41:25.744472 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 16 00:41:25.744481 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 16 00:41:25.744488 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 16 00:41:25.744495 kernel: iommu: Default domain type: Translated May 16 00:41:25.744501 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 16 00:41:25.744508 kernel: vgaarb: loaded May 16 00:41:25.744515 kernel: pps_core: LinuxPPS API ver. 1 registered May 16 00:41:25.744521 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 16 00:41:25.744528 kernel: PTP clock support registered May 16 00:41:25.744534 kernel: Registered efivars operations May 16 00:41:25.744542 kernel: clocksource: Switched to clocksource arch_sys_counter May 16 00:41:25.744548 kernel: VFS: Disk quotas dquot_6.6.0 May 16 00:41:25.744555 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 16 00:41:25.744562 kernel: pnp: PnP ACPI init May 16 00:41:25.744631 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 16 00:41:25.744640 kernel: pnp: PnP ACPI: found 1 devices May 16 00:41:25.744647 kernel: NET: Registered PF_INET protocol family May 16 00:41:25.744654 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 16 00:41:25.744662 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 16 00:41:25.744669 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 16 00:41:25.744676 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 16 00:41:25.744683 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) May 16 00:41:25.744690 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 16 00:41:25.744696 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 16 00:41:25.744703 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 16 00:41:25.746696 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 16 00:41:25.746704 kernel: PCI: CLS 0 bytes, default 64 May 16 00:41:25.746715 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 16 00:41:25.746748 kernel: kvm [1]: HYP mode not available May 16 00:41:25.746755 kernel: Initialise system trusted keyrings May 16 00:41:25.746762 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 16 00:41:25.746769 kernel: Key type asymmetric registered May 16 00:41:25.746776 kernel: Asymmetric key parser 'x509' registered May 16 00:41:25.746783 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 16 00:41:25.746790 kernel: io scheduler mq-deadline registered May 16 00:41:25.746796 kernel: io scheduler kyber registered May 16 00:41:25.746805 kernel: io scheduler bfq registered May 16 00:41:25.746812 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 16 00:41:25.746818 kernel: ACPI: button: Power Button [PWRB] May 16 00:41:25.746825 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 16 00:41:25.746918 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 16 00:41:25.746929 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 16 00:41:25.746935 kernel: thunder_xcv, ver 1.0 May 16 00:41:25.746949 kernel: thunder_bgx, ver 1.0 May 16 00:41:25.746957 kernel: nicpf, ver 1.0 May 16 00:41:25.746966 kernel: nicvf, ver 1.0 May 16 00:41:25.747040 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 16 00:41:25.747094 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-16T00:41:25 UTC (1747356085) May 16 00:41:25.747103 kernel: hid: raw HID events driver (C) Jiri Kosina May 16 00:41:25.747110 kernel: NET: Registered PF_INET6 protocol family May 16 00:41:25.747117 kernel: Segment Routing with IPv6 May 16 00:41:25.747123 kernel: In-situ OAM (IOAM) with IPv6 May 16 00:41:25.747130 kernel: NET: Registered PF_PACKET protocol family May 16 00:41:25.747138 kernel: Key type dns_resolver registered May 16 00:41:25.747144 kernel: registered taskstats version 1 May 16 00:41:25.747151 kernel: Loading compiled-in X.509 certificates May 16 00:41:25.747158 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.181-flatcar: 2793d535c1de6f1789b22ef06bd5666144f4eeb2' May 16 00:41:25.747164 kernel: Key type .fscrypt registered May 16 00:41:25.747171 kernel: Key type fscrypt-provisioning registered May 16 00:41:25.747177 kernel: ima: No TPM chip found, activating TPM-bypass! May 16 00:41:25.747184 kernel: ima: Allocated hash algorithm: sha1 May 16 00:41:25.747190 kernel: ima: No architecture policies found May 16 00:41:25.747198 kernel: clk: Disabling unused clocks May 16 00:41:25.747205 kernel: Freeing unused kernel memory: 36480K May 16 00:41:25.747212 kernel: Run /init as init process May 16 00:41:25.747218 kernel: with arguments: May 16 00:41:25.747225 kernel: /init May 16 00:41:25.747231 kernel: with environment: May 16 00:41:25.747238 kernel: HOME=/ May 16 00:41:25.747244 kernel: TERM=linux May 16 00:41:25.747250 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 16 00:41:25.747260 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 16 00:41:25.747269 systemd[1]: Detected virtualization kvm. May 16 00:41:25.747277 systemd[1]: Detected architecture arm64. May 16 00:41:25.747284 systemd[1]: Running in initrd. May 16 00:41:25.747290 systemd[1]: No hostname configured, using default hostname. May 16 00:41:25.747297 systemd[1]: Hostname set to . May 16 00:41:25.747304 systemd[1]: Initializing machine ID from VM UUID. May 16 00:41:25.747313 systemd[1]: Queued start job for default target initrd.target. May 16 00:41:25.747320 systemd[1]: Started systemd-ask-password-console.path. May 16 00:41:25.747327 systemd[1]: Reached target cryptsetup.target. May 16 00:41:25.747334 systemd[1]: Reached target paths.target. May 16 00:41:25.747341 systemd[1]: Reached target slices.target. May 16 00:41:25.747348 systemd[1]: Reached target swap.target. May 16 00:41:25.747354 systemd[1]: Reached target timers.target. May 16 00:41:25.747362 systemd[1]: Listening on iscsid.socket. May 16 00:41:25.747370 systemd[1]: Listening on iscsiuio.socket. May 16 00:41:25.747377 systemd[1]: Listening on systemd-journald-audit.socket. May 16 00:41:25.747384 systemd[1]: Listening on systemd-journald-dev-log.socket. May 16 00:41:25.747391 systemd[1]: Listening on systemd-journald.socket. May 16 00:41:25.747399 systemd[1]: Listening on systemd-networkd.socket. May 16 00:41:25.747405 systemd[1]: Listening on systemd-udevd-control.socket. May 16 00:41:25.747413 systemd[1]: Listening on systemd-udevd-kernel.socket. May 16 00:41:25.747420 systemd[1]: Reached target sockets.target. May 16 00:41:25.747428 systemd[1]: Starting kmod-static-nodes.service... May 16 00:41:25.747435 systemd[1]: Finished network-cleanup.service. May 16 00:41:25.747442 systemd[1]: Starting systemd-fsck-usr.service... May 16 00:41:25.747449 systemd[1]: Starting systemd-journald.service... May 16 00:41:25.747456 systemd[1]: Starting systemd-modules-load.service... May 16 00:41:25.747463 systemd[1]: Starting systemd-resolved.service... May 16 00:41:25.747470 systemd[1]: Starting systemd-vconsole-setup.service... May 16 00:41:25.747477 systemd[1]: Finished kmod-static-nodes.service. May 16 00:41:25.747484 systemd[1]: Finished systemd-fsck-usr.service. May 16 00:41:25.747493 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... May 16 00:41:25.747500 systemd[1]: Finished systemd-vconsole-setup.service. May 16 00:41:25.747507 systemd[1]: Starting dracut-cmdline-ask.service... May 16 00:41:25.747514 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. May 16 00:41:25.747521 kernel: audit: type=1130 audit(1747356085.743: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' May 16 00:41:25.747532 systemd-journald[290]: Journal started May 16 00:41:25.747576 systemd-journald[290]: Runtime Journal (/run/log/journal/7d8045f81c304b74a600827436ac0536) is 6.0M, max 48.7M, 42.6M free. May 16 00:41:25.743000 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' May 16 00:41:25.735950 systemd-modules-load[291]: Inserted module 'overlay' May 16 00:41:25.749755 systemd[1]: Started systemd-journald.service. May 16 00:41:25.749000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:25.751724 kernel: audit: type=1130 audit(1747356085.749: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' May 16 00:41:25.755737 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 16 00:41:25.761608 systemd[1]: Finished dracut-cmdline-ask.service. May 16 00:41:25.765814 kernel: Bridge firewalling registered May 16 00:41:25.765834 kernel: audit: type=1130 audit(1747356085.762: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' May 16 00:41:25.762000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:25.762798 systemd-modules-load[291]: Inserted module 'br_netfilter' May 16 00:41:25.763601 systemd[1]: Starting dracut-cmdline.service... May 16 00:41:25.766125 systemd-resolved[292]: Positive Trust Anchors: May 16 00:41:25.766133 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 16 00:41:25.766163 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 May 16 00:41:25.780522 kernel: SCSI subsystem initialized May 16 00:41:25.780544 kernel: audit: type=1130 audit(1747356085.772: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' May 16 00:41:25.772000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:25.770929 systemd-resolved[292]: Defaulting to hostname 'linux'. May 16 00:41:25.772409 systemd[1]: Started systemd-resolved.service. May 16 00:41:25.773378 systemd[1]: Reached target nss-lookup.target. May 16 00:41:25.784237 dracut-cmdline[308]: dracut-dracut-053 May 16 00:41:25.786247 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=2d88e96fdc9dc9b028836e57c250f3fd2abd3e6490e27ecbf72d8b216e3efce8 May 16 00:41:25.792078 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 16 00:41:25.792102 kernel: device-mapper: uevent: version 1.0.3 May 16 00:41:25.792110 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com May 16 00:41:25.792306 systemd-modules-load[291]: Inserted module 'dm_multipath' May 16 00:41:25.793074 systemd[1]: Finished systemd-modules-load.service. May 16 00:41:25.794000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:25.795075 systemd[1]: Starting systemd-sysctl.service... May 16 00:41:25.797908 kernel: audit: type=1130 audit(1747356085.794: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' May 16 00:41:25.803591 systemd[1]: Finished systemd-sysctl.service. May 16 00:41:25.804000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:25.806731 kernel: audit: type=1130 audit(1747356085.804:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:25.851745 kernel: Loading iSCSI transport class v2.0-870. May 16 00:41:25.864737 kernel: iscsi: registered transport (tcp) May 16 00:41:25.879780 kernel: iscsi: registered transport (qla4xxx) May 16 00:41:25.879811 kernel: QLogic iSCSI HBA Driver May 16 00:41:25.916408 systemd[1]: Finished dracut-cmdline.service. May 16 00:41:25.916000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:25.917915 systemd[1]: Starting dracut-pre-udev.service... May 16 00:41:25.920229 kernel: audit: type=1130 audit(1747356085.916: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' May 16 00:41:25.964751 kernel: raid6: neonx8 gen() 13749 MB/s May 16 00:41:25.981731 kernel: raid6: neonx8 xor() 10784 MB/s May 16 00:41:25.998730 kernel: raid6: neonx4 gen() 13558 MB/s May 16 00:41:26.015730 kernel: raid6: neonx4 xor() 11184 MB/s May 16 00:41:26.032730 kernel: raid6: neonx2 gen() 12943 MB/s May 16 00:41:26.049730 kernel: raid6: neonx2 xor() 10450 MB/s May 16 00:41:26.066740 kernel: raid6: neonx1 gen() 10580 MB/s May 16 00:41:26.083729 kernel: raid6: neonx1 xor() 8781 MB/s May 16 00:41:26.100735 kernel: raid6: int64x8 gen() 6273 MB/s May 16 00:41:26.117734 kernel: raid6: int64x8 xor() 3541 MB/s May 16 00:41:26.134737 kernel: raid6: int64x4 gen() 7218 MB/s May 16 00:41:26.151729 kernel: raid6: int64x4 xor() 3852 MB/s May 16 00:41:26.168728 kernel: raid6: int64x2 gen() 6152 MB/s May 16 00:41:26.185732 kernel: raid6: int64x2 xor() 3319 MB/s May 16 00:41:26.202733 kernel: raid6: int64x1 gen() 5044 MB/s May 16 00:41:26.220041 kernel: raid6: int64x1 xor() 2646 MB/s May 16 00:41:26.220060 kernel: raid6: using algorithm neonx8 gen() 13749 MB/s May 16 00:41:26.220068 kernel: raid6: .... xor() 10784 MB/s, rmw enabled May 16 00:41:26.220084 kernel: raid6: using neon recovery algorithm May 16 00:41:26.230827 kernel: xor: measuring software checksum speed May 16 00:41:26.230846 kernel: 8regs : 17191 MB/sec May 16 00:41:26.231862 kernel: 32regs : 20272 MB/sec May 16 00:41:26.231875 kernel: arm64_neon : 27757 MB/sec May 16 00:41:26.231884 kernel: xor: using function: arm64_neon (27757 MB/sec) May 16 00:41:26.287739 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no May 16 00:41:26.298157 systemd[1]: Finished dracut-pre-udev.service. May 16 00:41:26.301832 kernel: audit: type=1130 audit(1747356086.298: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' May 16 00:41:26.301852 kernel: audit: type=1334 audit(1747356086.300:10): prog-id=7 op=LOAD May 16 00:41:26.298000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:26.300000 audit: BPF prog-id=7 op=LOAD May 16 00:41:26.301000 audit: BPF prog-id=8 op=LOAD May 16 00:41:26.302191 systemd[1]: Starting systemd-udevd.service... May 16 00:41:26.314699 systemd-udevd[490]: Using default interface naming scheme 'v252'. May 16 00:41:26.317974 systemd[1]: Started systemd-udevd.service. May 16 00:41:26.318000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:26.319867 systemd[1]: Starting dracut-pre-trigger.service... May 16 00:41:26.331376 dracut-pre-trigger[500]: rd.md=0: removing MD RAID activation May 16 00:41:26.360520 systemd[1]: Finished dracut-pre-trigger.service. May 16 00:41:26.360000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:26.362000 systemd[1]: Starting systemd-udev-trigger.service... May 16 00:41:26.395470 systemd[1]: Finished systemd-udev-trigger.service. May 16 00:41:26.395000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:26.430829 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 16 00:41:26.436466 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 16 00:41:26.436488 kernel: GPT:9289727 != 19775487 May 16 00:41:26.436496 kernel: GPT:Alternate GPT header not at the end of the disk. May 16 00:41:26.436505 kernel: GPT:9289727 != 19775487 May 16 00:41:26.436514 kernel: GPT: Use GNU Parted to correct GPT errors. May 16 00:41:26.436522 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 16 00:41:26.448023 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. May 16 00:41:26.454062 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. May 16 00:41:26.455652 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (546) May 16 00:41:26.457815 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. May 16 00:41:26.458630 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. May 16 00:41:26.467279 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 16 00:41:26.468755 systemd[1]: Starting disk-uuid.service... May 16 00:41:26.474365 disk-uuid[561]: Primary Header is updated. May 16 00:41:26.474365 disk-uuid[561]: Secondary Entries is updated. May 16 00:41:26.474365 disk-uuid[561]: Secondary Header is updated. May 16 00:41:26.480748 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 16 00:41:27.493666 disk-uuid[562]: The operation has completed successfully. May 16 00:41:27.494795 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 16 00:41:27.517637 systemd[1]: disk-uuid.service: Deactivated successfully. May 16 00:41:27.517747 systemd[1]: Finished disk-uuid.service. May 16 00:41:27.517000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.517000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.519456 systemd[1]: Starting verity-setup.service... May 16 00:41:27.535764 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 16 00:41:27.559166 systemd[1]: Found device dev-mapper-usr.device. May 16 00:41:27.560741 systemd[1]: Mounting sysusr-usr.mount... May 16 00:41:27.561565 systemd[1]: Finished verity-setup.service. May 16 00:41:27.561000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.608745 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. May 16 00:41:27.609159 systemd[1]: Mounted sysusr-usr.mount. May 16 00:41:27.610033 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. May 16 00:41:27.610785 systemd[1]: Starting ignition-setup.service... May 16 00:41:27.613182 systemd[1]: Starting parse-ip-for-networkd.service... May 16 00:41:27.619940 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 16 00:41:27.619999 kernel: BTRFS info (device vda6): using free space tree May 16 00:41:27.620010 kernel: BTRFS info (device vda6): has skinny extents May 16 00:41:27.629200 systemd[1]: mnt-oem.mount: Deactivated successfully. May 16 00:41:27.636471 systemd[1]: Finished ignition-setup.service. May 16 00:41:27.636000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.638319 systemd[1]: Starting ignition-fetch-offline.service... May 16 00:41:27.709907 systemd[1]: Finished parse-ip-for-networkd.service. May 16 00:41:27.710000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.710000 audit: BPF prog-id=9 op=LOAD May 16 00:41:27.712619 systemd[1]: Starting systemd-networkd.service... May 16 00:41:27.731896 ignition[649]: Ignition 2.14.0 May 16 00:41:27.732780 ignition[649]: Stage: fetch-offline May 16 00:41:27.733541 ignition[649]: no configs at "/usr/lib/ignition/base.d" May 16 00:41:27.734485 ignition[649]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:41:27.735758 ignition[649]: parsed url from cmdline: "" May 16 00:41:27.735820 ignition[649]: no config URL provided May 16 00:41:27.736470 ignition[649]: reading system config file "/usr/lib/ignition/user.ign" May 16 00:41:27.736484 ignition[649]: no config at "/usr/lib/ignition/user.ign" May 16 00:41:27.736506 ignition[649]: op(1): [started] loading QEMU firmware config module May 16 00:41:27.736511 ignition[649]: op(1): executing: "modprobe" "qemu_fw_cfg" May 16 00:41:27.740432 systemd-networkd[738]: lo: Link UP May 16 00:41:27.740446 systemd-networkd[738]: lo: Gained carrier May 16 00:41:27.741000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.740840 systemd-networkd[738]: Enumeration completed May 16 00:41:27.741039 systemd-networkd[738]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 16 00:41:27.741142 systemd[1]: Started systemd-networkd.service. May 16 00:41:27.742293 systemd-networkd[738]: eth0: Link UP May 16 00:41:27.742297 systemd-networkd[738]: eth0: Gained carrier May 16 00:41:27.742752 systemd[1]: Reached target network.target. May 16 00:41:27.745690 systemd[1]: Starting iscsiuio.service... May 16 00:41:27.751850 ignition[649]: op(1): [finished] loading QEMU firmware config module May 16 00:41:27.754972 systemd[1]: Started iscsiuio.service. May 16 00:41:27.755000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.757516 systemd-networkd[738]: eth0: DHCPv4 address 10.0.0.70/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 16 00:41:27.757666 systemd[1]: Starting iscsid.service... May 16 00:41:27.760819 ignition[649]: parsing config with SHA512: 8eee45ad6e5cf9fb20cf349e1b3682a49f0f234f2536d884e97960d20730be4bb05e02372720bd0ccb59214970676220672b5cc93dbf3f079c0d366ef72c3400 May 16 00:41:27.761068 iscsid[745]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi May 16 00:41:27.761068 iscsid[745]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. May 16 00:41:27.761068 iscsid[745]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. May 16 00:41:27.761068 iscsid[745]: If using hardware iscsi like qla4xxx this message can be ignored. May 16 00:41:27.761068 iscsid[745]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi May 16 00:41:27.761068 iscsid[745]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf May 16 00:41:27.769000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.768084 systemd[1]: Started iscsid.service. May 16 00:41:27.770185 systemd[1]: Starting dracut-initqueue.service... May 16 00:41:27.775932 unknown[649]: fetched base config from "system" May 16 00:41:27.776326 ignition[649]: fetch-offline: fetch-offline passed May 16 00:41:27.778000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.775953 unknown[649]: fetched user config from "qemu" May 16 00:41:27.776394 ignition[649]: Ignition finished successfully May 16 00:41:27.777232 systemd[1]: Finished ignition-fetch-offline.service. May 16 00:41:27.778243 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 16 00:41:27.779078 systemd[1]: Starting ignition-kargs.service... May 16 00:41:27.784000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.783338 systemd[1]: Finished dracut-initqueue.service. May 16 00:41:27.784424 systemd[1]: Reached target remote-fs-pre.target. May 16 00:41:27.785785 systemd[1]: Reached target remote-cryptsetup.target. May 16 00:41:27.787305 systemd[1]: Reached target remote-fs.target. May 16 00:41:27.788202 ignition[753]: Ignition 2.14.0 May 16 00:41:27.789540 systemd[1]: Starting dracut-pre-mount.service... May 16 00:41:27.788208 ignition[753]: Stage: kargs May 16 00:41:27.791179 systemd[1]: Finished ignition-kargs.service. May 16 00:41:27.792000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.788312 ignition[753]: no configs at "/usr/lib/ignition/base.d" May 16 00:41:27.793122 systemd[1]: Starting ignition-disks.service... May 16 00:41:27.788322 ignition[753]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:41:27.789399 ignition[753]: kargs: kargs passed May 16 00:41:27.789446 ignition[753]: Ignition finished successfully May 16 00:41:27.799036 systemd[1]: Finished dracut-pre-mount.service. May 16 00:41:27.800000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.801906 ignition[762]: Ignition 2.14.0 May 16 00:41:27.801916 ignition[762]: Stage: disks May 16 00:41:27.802044 ignition[762]: no configs at "/usr/lib/ignition/base.d" May 16 00:41:27.802055 ignition[762]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:41:27.804000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.803592 systemd[1]: Finished ignition-disks.service. May 16 00:41:27.802777 ignition[762]: disks: disks passed May 16 00:41:27.805053 systemd[1]: Reached target initrd-root-device.target. May 16 00:41:27.802823 ignition[762]: Ignition finished successfully May 16 00:41:27.806648 systemd[1]: Reached target local-fs-pre.target. May 16 00:41:27.808031 systemd[1]: Reached target local-fs.target. May 16 00:41:27.809292 systemd[1]: Reached target sysinit.target. May 16 00:41:27.810747 systemd[1]: Reached target basic.target. May 16 00:41:27.813195 systemd[1]: Starting systemd-fsck-root.service... May 16 00:41:27.826274 systemd-fsck[774]: ROOT: clean, 619/553520 files, 56022/553472 blocks May 16 00:41:27.829326 systemd[1]: Finished systemd-fsck-root.service. May 16 00:41:27.830000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.831323 systemd[1]: Mounting sysroot.mount... May 16 00:41:27.836735 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. May 16 00:41:27.837424 systemd[1]: Mounted sysroot.mount. May 16 00:41:27.838345 systemd[1]: Reached target initrd-root-fs.target. May 16 00:41:27.840678 systemd[1]: Mounting sysroot-usr.mount... May 16 00:41:27.841682 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. May 16 00:41:27.841736 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 16 00:41:27.841764 systemd[1]: Reached target ignition-diskful.target. May 16 00:41:27.843697 systemd[1]: Mounted sysroot-usr.mount. May 16 00:41:27.845591 systemd[1]: Starting initrd-setup-root.service... May 16 00:41:27.850518 initrd-setup-root[784]: cut: /sysroot/etc/passwd: No such file or directory May 16 00:41:27.855887 initrd-setup-root[792]: cut: /sysroot/etc/group: No such file or directory May 16 00:41:27.860439 initrd-setup-root[800]: cut: /sysroot/etc/shadow: No such file or directory May 16 00:41:27.865826 initrd-setup-root[808]: cut: /sysroot/etc/gshadow: No such file or directory May 16 00:41:27.898276 systemd[1]: Finished initrd-setup-root.service. May 16 00:41:27.898000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.899858 systemd[1]: Starting ignition-mount.service... May 16 00:41:27.901188 systemd[1]: Starting sysroot-boot.service... May 16 00:41:27.906105 bash[825]: umount: /sysroot/usr/share/oem: not mounted. May 16 00:41:27.914856 ignition[827]: INFO : Ignition 2.14.0 May 16 00:41:27.914856 ignition[827]: INFO : Stage: mount May 16 00:41:27.916639 ignition[827]: INFO : no configs at "/usr/lib/ignition/base.d" May 16 00:41:27.916639 ignition[827]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:41:27.916639 ignition[827]: INFO : mount: mount passed May 16 00:41:27.916639 ignition[827]: INFO : Ignition finished successfully May 16 00:41:27.916000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:27.916401 systemd[1]: Finished ignition-mount.service. May 16 00:41:27.922126 systemd[1]: Finished sysroot-boot.service. May 16 00:41:27.922000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:28.568820 systemd[1]: Mounting sysroot-usr-share-oem.mount... May 16 00:41:28.574736 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (836) May 16 00:41:28.576139 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 16 00:41:28.576153 kernel: BTRFS info (device vda6): using free space tree May 16 00:41:28.576163 kernel: BTRFS info (device vda6): has skinny extents May 16 00:41:28.579667 systemd[1]: Mounted sysroot-usr-share-oem.mount. May 16 00:41:28.581258 systemd[1]: Starting ignition-files.service... May 16 00:41:28.595961 ignition[856]: INFO : Ignition 2.14.0 May 16 00:41:28.595961 ignition[856]: INFO : Stage: files May 16 00:41:28.597620 ignition[856]: INFO : no configs at "/usr/lib/ignition/base.d" May 16 00:41:28.597620 ignition[856]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:41:28.597620 ignition[856]: DEBUG : files: compiled without relabeling support, skipping May 16 00:41:28.602801 ignition[856]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 16 00:41:28.602801 ignition[856]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 16 00:41:28.608143 ignition[856]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 16 00:41:28.609598 ignition[856]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 16 00:41:28.609598 ignition[856]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 16 00:41:28.609598 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 16 00:41:28.609598 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 16 00:41:28.608910 unknown[856]: wrote ssh authorized keys file for user: core May 16 00:41:28.616915 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 16 00:41:28.616915 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 16 00:41:28.616915 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" May 16 00:41:28.616915 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" May 16 00:41:28.616915 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" May 16 00:41:28.616915 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-arm64.raw: attempt #1 May 16 00:41:29.082471 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 16 00:41:29.209022 systemd-networkd[738]: eth0: Gained IPv6LL May 16 00:41:29.635296 ignition[856]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" May 16 00:41:29.635296 ignition[856]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 16 00:41:29.639850 ignition[856]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 16 00:41:29.639850 ignition[856]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 16 00:41:29.639850 ignition[856]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 16 00:41:29.639850 ignition[856]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 16 00:41:29.639850 ignition[856]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 16 00:41:29.666891 ignition[856]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 16 00:41:29.668511 ignition[856]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 16 00:41:29.668511 ignition[856]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 16 00:41:29.668511 ignition[856]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 16 00:41:29.668511 ignition[856]: INFO : files: files passed May 16 00:41:29.668511 ignition[856]: INFO : Ignition finished successfully May 16 00:41:29.669000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.669366 systemd[1]: Finished ignition-files.service. May 16 00:41:29.676000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.676000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.671478 systemd[1]: Starting initrd-setup-root-after-ignition.service... May 16 00:41:29.679022 initrd-setup-root-after-ignition[880]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory May 16 00:41:29.678000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.672782 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). May 16 00:41:29.683136 initrd-setup-root-after-ignition[883]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 16 00:41:29.673394 systemd[1]: Starting ignition-quench.service... May 16 00:41:29.676513 systemd[1]: ignition-quench.service: Deactivated successfully. May 16 00:41:29.676607 systemd[1]: Finished ignition-quench.service. May 16 00:41:29.678215 systemd[1]: Finished initrd-setup-root-after-ignition.service. May 16 00:41:29.679670 systemd[1]: Reached target ignition-complete.target. May 16 00:41:29.681943 systemd[1]: Starting initrd-parse-etc.service... May 16 00:41:29.693544 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 16 00:41:29.693636 systemd[1]: Finished initrd-parse-etc.service. May 16 00:41:29.694000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.694000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.695327 systemd[1]: Reached target initrd-fs.target. May 16 00:41:29.696361 systemd[1]: Reached target initrd.target. May 16 00:41:29.697542 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. May 16 00:41:29.698292 systemd[1]: Starting dracut-pre-pivot.service... May 16 00:41:29.708295 systemd[1]: Finished dracut-pre-pivot.service. May 16 00:41:29.708000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.709875 systemd[1]: Starting initrd-cleanup.service... May 16 00:41:29.718217 systemd[1]: Stopped target nss-lookup.target. May 16 00:41:29.718929 systemd[1]: Stopped target remote-cryptsetup.target. May 16 00:41:29.720197 systemd[1]: Stopped target timers.target. May 16 00:41:29.721425 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 16 00:41:29.721000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.721541 systemd[1]: Stopped dracut-pre-pivot.service. May 16 00:41:29.722656 systemd[1]: Stopped target initrd.target. May 16 00:41:29.723875 systemd[1]: Stopped target basic.target. May 16 00:41:29.725016 systemd[1]: Stopped target ignition-complete.target. May 16 00:41:29.726245 systemd[1]: Stopped target ignition-diskful.target. May 16 00:41:29.727444 systemd[1]: Stopped target initrd-root-device.target. May 16 00:41:29.728822 systemd[1]: Stopped target remote-fs.target. May 16 00:41:29.730031 systemd[1]: Stopped target remote-fs-pre.target. May 16 00:41:29.731315 systemd[1]: Stopped target sysinit.target. May 16 00:41:29.732434 systemd[1]: Stopped target local-fs.target. May 16 00:41:29.733600 systemd[1]: Stopped target local-fs-pre.target. May 16 00:41:29.734779 systemd[1]: Stopped target swap.target. May 16 00:41:29.737000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.735893 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 16 00:41:29.736020 systemd[1]: Stopped dracut-pre-mount.service. May 16 00:41:29.738000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.737224 systemd[1]: Stopped target cryptsetup.target. May 16 00:41:29.740000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.738270 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 16 00:41:29.738376 systemd[1]: Stopped dracut-initqueue.service. May 16 00:41:29.739782 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 16 00:41:29.739892 systemd[1]: Stopped ignition-fetch-offline.service. May 16 00:41:29.741041 systemd[1]: Stopped target paths.target. May 16 00:41:29.742089 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 16 00:41:29.746745 systemd[1]: Stopped systemd-ask-password-console.path. May 16 00:41:29.748008 systemd[1]: Stopped target slices.target. May 16 00:41:29.749276 systemd[1]: Stopped target sockets.target. May 16 00:41:29.750424 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 16 00:41:29.750000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.750539 systemd[1]: Stopped initrd-setup-root-after-ignition.service. May 16 00:41:29.753000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.751707 systemd[1]: ignition-files.service: Deactivated successfully. May 16 00:41:29.751832 systemd[1]: Stopped ignition-files.service. May 16 00:41:29.756129 iscsid[745]: iscsid shutting down. May 16 00:41:29.754145 systemd[1]: Stopping ignition-mount.service... May 16 00:41:29.756923 systemd[1]: Stopping iscsid.service... May 16 00:41:29.758122 systemd[1]: Stopping sysroot-boot.service... May 16 00:41:29.759000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.758658 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 16 00:41:29.760000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.761946 ignition[896]: INFO : Ignition 2.14.0 May 16 00:41:29.761946 ignition[896]: INFO : Stage: umount May 16 00:41:29.761946 ignition[896]: INFO : no configs at "/usr/lib/ignition/base.d" May 16 00:41:29.761946 ignition[896]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:41:29.762000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.764000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.758830 systemd[1]: Stopped systemd-udev-trigger.service. May 16 00:41:29.767000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.768668 ignition[896]: INFO : umount: umount passed May 16 00:41:29.768668 ignition[896]: INFO : Ignition finished successfully May 16 00:41:29.768000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.770000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.759982 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 16 00:41:29.760084 systemd[1]: Stopped dracut-pre-trigger.service. May 16 00:41:29.773000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.773000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.762551 systemd[1]: iscsid.service: Deactivated successfully. May 16 00:41:29.762653 systemd[1]: Stopped iscsid.service. May 16 00:41:29.776000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.763850 systemd[1]: ignition-mount.service: Deactivated successfully. May 16 00:41:29.763931 systemd[1]: Stopped ignition-mount.service. May 16 00:41:29.765125 systemd[1]: iscsid.socket: Deactivated successfully. May 16 00:41:29.765205 systemd[1]: Closed iscsid.socket. May 16 00:41:29.766275 systemd[1]: ignition-disks.service: Deactivated successfully. May 16 00:41:29.766318 systemd[1]: Stopped ignition-disks.service. May 16 00:41:29.768026 systemd[1]: ignition-kargs.service: Deactivated successfully. May 16 00:41:29.768070 systemd[1]: Stopped ignition-kargs.service. May 16 00:41:29.769411 systemd[1]: ignition-setup.service: Deactivated successfully. May 16 00:41:29.769455 systemd[1]: Stopped ignition-setup.service. May 16 00:41:29.771664 systemd[1]: Stopping iscsiuio.service... May 16 00:41:29.772595 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 16 00:41:29.791000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.772679 systemd[1]: Finished initrd-cleanup.service. May 16 00:41:29.793000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.775011 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 16 00:41:29.775425 systemd[1]: iscsiuio.service: Deactivated successfully. May 16 00:41:29.775517 systemd[1]: Stopped iscsiuio.service. May 16 00:41:29.797000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.777792 systemd[1]: Stopped target network.target. May 16 00:41:29.798000 audit: BPF prog-id=6 op=UNLOAD May 16 00:41:29.798000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.779348 systemd[1]: iscsiuio.socket: Deactivated successfully. May 16 00:41:29.779382 systemd[1]: Closed iscsiuio.socket. May 16 00:41:29.800000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.780690 systemd[1]: Stopping systemd-networkd.service... May 16 00:41:29.782580 systemd[1]: Stopping systemd-resolved.service... May 16 00:41:29.788764 systemd-networkd[738]: eth0: DHCPv6 lease lost May 16 00:41:29.789765 systemd[1]: systemd-networkd.service: Deactivated successfully. May 16 00:41:29.806000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.789853 systemd[1]: Stopped systemd-networkd.service. May 16 00:41:29.807000 audit: BPF prog-id=9 op=UNLOAD May 16 00:41:29.792738 systemd[1]: systemd-resolved.service: Deactivated successfully. May 16 00:41:29.808000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.792831 systemd[1]: Stopped systemd-resolved.service. May 16 00:41:29.794096 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 16 00:41:29.810000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.794126 systemd[1]: Closed systemd-networkd.socket. May 16 00:41:29.795841 systemd[1]: Stopping network-cleanup.service... May 16 00:41:29.796373 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 16 00:41:29.796426 systemd[1]: Stopped parse-ip-for-networkd.service. May 16 00:41:29.798093 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 16 00:41:29.798134 systemd[1]: Stopped systemd-sysctl.service. May 16 00:41:29.799897 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 16 00:41:29.800190 systemd[1]: Stopped systemd-modules-load.service. May 16 00:41:29.801494 systemd[1]: Stopping systemd-udevd.service... May 16 00:41:29.805519 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 16 00:41:29.806003 systemd[1]: sysroot-boot.service: Deactivated successfully. May 16 00:41:29.822000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.806082 systemd[1]: Stopped sysroot-boot.service. May 16 00:41:29.807480 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 16 00:41:29.807527 systemd[1]: Stopped initrd-setup-root.service. May 16 00:41:29.826000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.809801 systemd[1]: network-cleanup.service: Deactivated successfully. May 16 00:41:29.828000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.809884 systemd[1]: Stopped network-cleanup.service. May 16 00:41:29.829000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.812811 systemd[1]: systemd-udevd.service: Deactivated successfully. May 16 00:41:29.820779 systemd[1]: Stopped systemd-udevd.service. May 16 00:41:29.822457 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 16 00:41:29.834000 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' May 16 00:41:29.822497 systemd[1]: Closed systemd-udevd-control.socket. May 16 00:41:29.835000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.823982 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 16 00:41:29.837000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.824019 systemd[1]: Closed systemd-udevd-kernel.socket. May 16 00:41:29.825382 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 16 00:41:29.840000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.840000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:29.825433 systemd[1]: Stopped dracut-pre-udev.service. May 16 00:41:29.827092 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 16 00:41:29.827141 systemd[1]: Stopped dracut-cmdline.service. May 16 00:41:29.828477 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 16 00:41:29.828520 systemd[1]: Stopped dracut-cmdline-ask.service. May 16 00:41:29.830996 systemd[1]: Starting initrd-udevadm-cleanup-db.service... May 16 00:41:29.832616 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 16 00:41:29.832693 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. May 16 00:41:29.835134 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 16 00:41:29.835179 systemd[1]: Stopped kmod-static-nodes.service. May 16 00:41:29.836089 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 16 00:41:29.836133 systemd[1]: Stopped systemd-vconsole-setup.service. May 16 00:41:29.838543 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 16 00:41:29.839051 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 16 00:41:29.839136 systemd[1]: Finished initrd-udevadm-cleanup-db.service. May 16 00:41:29.840455 systemd[1]: Reached target initrd-switch-root.target. May 16 00:41:29.842686 systemd[1]: Starting initrd-switch-root.service... May 16 00:41:29.849198 systemd[1]: Switching root. May 16 00:41:29.868993 systemd-journald[290]: Journal stopped May 16 00:41:31.804860 systemd-journald[290]: Received SIGTERM from PID 1 (systemd). May 16 00:41:31.804933 kernel: SELinux: Class mctp_socket not defined in policy. May 16 00:41:31.804950 kernel: SELinux: Class anon_inode not defined in policy. May 16 00:41:31.804968 kernel: SELinux: the above unknown classes and permissions will be allowed May 16 00:41:31.804986 kernel: SELinux: policy capability network_peer_controls=1 May 16 00:41:31.804997 kernel: SELinux: policy capability open_perms=1 May 16 00:41:31.805010 kernel: SELinux: policy capability extended_socket_class=1 May 16 00:41:31.805021 kernel: SELinux: policy capability always_check_network=0 May 16 00:41:31.805031 kernel: SELinux: policy capability cgroup_seclabel=1 May 16 00:41:31.805040 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 16 00:41:31.805061 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 16 00:41:31.805071 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 16 00:41:31.805081 systemd[1]: Successfully loaded SELinux policy in 30.711ms. May 16 00:41:31.805099 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.929ms. May 16 00:41:31.805111 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 16 00:41:31.805122 systemd[1]: Detected virtualization kvm. May 16 00:41:31.805132 systemd[1]: Detected architecture arm64. May 16 00:41:31.805142 systemd[1]: Detected first boot. May 16 00:41:31.805153 systemd[1]: Initializing machine ID from VM UUID. May 16 00:41:31.805162 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). May 16 00:41:31.805174 systemd[1]: Populated /etc with preset unit settings. May 16 00:41:31.805185 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 16 00:41:31.805196 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 16 00:41:31.805208 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 16 00:41:31.805218 kernel: kauditd_printk_skb: 80 callbacks suppressed May 16 00:41:31.805228 kernel: audit: type=1334 audit(1747356091.684:84): prog-id=12 op=LOAD May 16 00:41:31.805237 kernel: audit: type=1334 audit(1747356091.685:85): prog-id=3 op=UNLOAD May 16 00:41:31.805247 kernel: audit: type=1334 audit(1747356091.685:86): prog-id=13 op=LOAD May 16 00:41:31.805258 kernel: audit: type=1334 audit(1747356091.686:87): prog-id=14 op=LOAD May 16 00:41:31.805267 kernel: audit: type=1334 audit(1747356091.686:88): prog-id=4 op=UNLOAD May 16 00:41:31.805277 kernel: audit: type=1334 audit(1747356091.686:89): prog-id=5 op=UNLOAD May 16 00:41:31.805287 kernel: audit: type=1334 audit(1747356091.687:90): prog-id=15 op=LOAD May 16 00:41:31.805296 kernel: audit: type=1334 audit(1747356091.687:91): prog-id=12 op=UNLOAD May 16 00:41:31.805305 kernel: audit: type=1334 audit(1747356091.687:92): prog-id=16 op=LOAD May 16 00:41:31.805317 kernel: audit: type=1334 audit(1747356091.688:93): prog-id=17 op=LOAD May 16 00:41:31.805329 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 16 00:41:31.805339 systemd[1]: Stopped initrd-switch-root.service. May 16 00:41:31.805350 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 16 00:41:31.805362 systemd[1]: Created slice system-addon\x2dconfig.slice. May 16 00:41:31.805372 systemd[1]: Created slice system-addon\x2drun.slice. May 16 00:41:31.805403 systemd[1]: Created slice system-getty.slice. May 16 00:41:31.805416 systemd[1]: Created slice system-modprobe.slice. May 16 00:41:31.805426 systemd[1]: Created slice system-serial\x2dgetty.slice. May 16 00:41:31.805437 systemd[1]: Created slice system-system\x2dcloudinit.slice. May 16 00:41:31.805447 systemd[1]: Created slice system-systemd\x2dfsck.slice. May 16 00:41:31.805461 systemd[1]: Created slice user.slice. May 16 00:41:31.805472 systemd[1]: Started systemd-ask-password-console.path. May 16 00:41:31.805482 systemd[1]: Started systemd-ask-password-wall.path. May 16 00:41:31.805492 systemd[1]: Set up automount boot.automount. May 16 00:41:31.805504 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. May 16 00:41:31.805514 systemd[1]: Stopped target initrd-switch-root.target. May 16 00:41:31.805526 systemd[1]: Stopped target initrd-fs.target. May 16 00:41:31.805536 systemd[1]: Stopped target initrd-root-fs.target. May 16 00:41:31.805547 systemd[1]: Reached target integritysetup.target. May 16 00:41:31.805557 systemd[1]: Reached target remote-cryptsetup.target. May 16 00:41:31.805567 systemd[1]: Reached target remote-fs.target. May 16 00:41:31.805578 systemd[1]: Reached target slices.target. May 16 00:41:31.805588 systemd[1]: Reached target swap.target. May 16 00:41:31.805598 systemd[1]: Reached target torcx.target. May 16 00:41:31.805609 systemd[1]: Reached target veritysetup.target. May 16 00:41:31.805619 systemd[1]: Listening on systemd-coredump.socket. May 16 00:41:31.805631 systemd[1]: Listening on systemd-initctl.socket. May 16 00:41:31.805642 systemd[1]: Listening on systemd-networkd.socket. May 16 00:41:31.805652 systemd[1]: Listening on systemd-udevd-control.socket. May 16 00:41:31.805662 systemd[1]: Listening on systemd-udevd-kernel.socket. May 16 00:41:31.805672 systemd[1]: Listening on systemd-userdbd.socket. May 16 00:41:31.805682 systemd[1]: Mounting dev-hugepages.mount... May 16 00:41:31.805693 systemd[1]: Mounting dev-mqueue.mount... May 16 00:41:31.805704 systemd[1]: Mounting media.mount... May 16 00:41:31.805714 systemd[1]: Mounting sys-kernel-debug.mount... May 16 00:41:31.805749 systemd[1]: Mounting sys-kernel-tracing.mount... May 16 00:41:31.805760 systemd[1]: Mounting tmp.mount... May 16 00:41:31.805775 systemd[1]: Starting flatcar-tmpfiles.service... May 16 00:41:31.805786 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 16 00:41:31.805796 systemd[1]: Starting kmod-static-nodes.service... May 16 00:41:31.805806 systemd[1]: Starting modprobe@configfs.service... May 16 00:41:31.805817 systemd[1]: Starting modprobe@dm_mod.service... May 16 00:41:31.805827 systemd[1]: Starting modprobe@drm.service... May 16 00:41:31.805837 systemd[1]: Starting modprobe@efi_pstore.service... May 16 00:41:31.805849 systemd[1]: Starting modprobe@fuse.service... May 16 00:41:31.805859 systemd[1]: Starting modprobe@loop.service... May 16 00:41:31.805869 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 16 00:41:31.805880 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 16 00:41:31.805890 systemd[1]: Stopped systemd-fsck-root.service. May 16 00:41:31.805901 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 16 00:41:31.805911 systemd[1]: Stopped systemd-fsck-usr.service. May 16 00:41:31.805921 systemd[1]: Stopped systemd-journald.service. May 16 00:41:31.805931 systemd[1]: Starting systemd-journald.service... May 16 00:41:31.805942 kernel: loop: module loaded May 16 00:41:31.805953 systemd[1]: Starting systemd-modules-load.service... May 16 00:41:31.805968 kernel: fuse: init (API version 7.34) May 16 00:41:31.805979 systemd[1]: Starting systemd-network-generator.service... May 16 00:41:31.805990 systemd[1]: Starting systemd-remount-fs.service... May 16 00:41:31.806001 systemd[1]: Starting systemd-udev-trigger.service... May 16 00:41:31.806012 systemd[1]: verity-setup.service: Deactivated successfully. May 16 00:41:31.806022 systemd[1]: Stopped verity-setup.service. May 16 00:41:31.806031 systemd[1]: Mounted dev-hugepages.mount. May 16 00:41:31.806043 systemd[1]: Mounted dev-mqueue.mount. May 16 00:41:31.806053 systemd[1]: Mounted media.mount. May 16 00:41:31.806063 systemd[1]: Mounted sys-kernel-debug.mount. May 16 00:41:31.806073 systemd[1]: Mounted sys-kernel-tracing.mount. May 16 00:41:31.806084 systemd[1]: Mounted tmp.mount. May 16 00:41:31.806094 systemd[1]: Finished kmod-static-nodes.service. May 16 00:41:31.806108 systemd-journald[987]: Journal started May 16 00:41:31.806148 systemd-journald[987]: Runtime Journal (/run/log/journal/7d8045f81c304b74a600827436ac0536) is 6.0M, max 48.7M, 42.6M free. May 16 00:41:29.925000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 May 16 00:41:29.992000 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 May 16 00:41:29.992000 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 May 16 00:41:29.992000 audit: BPF prog-id=10 op=LOAD May 16 00:41:29.992000 audit: BPF prog-id=10 op=UNLOAD May 16 00:41:29.992000 audit: BPF prog-id=11 op=LOAD May 16 00:41:29.992000 audit: BPF prog-id=11 op=UNLOAD May 16 00:41:30.029000 audit[929]: AVC avc: denied { associate } for pid=929 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" May 16 00:41:30.029000 audit[929]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001058cc a1=4000028e40 a2=4000027100 a3=32 items=0 ppid=912 pid=929 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) May 16 00:41:30.029000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 May 16 00:41:30.030000 audit[929]: AVC avc: denied { associate } for pid=929 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 May 16 00:41:30.030000 audit[929]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001059a5 a2=1ed a3=0 items=2 ppid=912 pid=929 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) May 16 00:41:30.030000 audit: CWD cwd="/" May 16 00:41:30.030000 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 May 16 00:41:30.030000 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 May 16 00:41:30.030000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 May 16 00:41:31.684000 audit: BPF prog-id=12 op=LOAD May 16 00:41:31.685000 audit: BPF prog-id=3 op=UNLOAD May 16 00:41:31.685000 audit: BPF prog-id=13 op=LOAD May 16 00:41:31.686000 audit: BPF prog-id=14 op=LOAD May 16 00:41:31.686000 audit: BPF prog-id=4 op=UNLOAD May 16 00:41:31.686000 audit: BPF prog-id=5 op=UNLOAD May 16 00:41:31.687000 audit: BPF prog-id=15 op=LOAD May 16 00:41:31.687000 audit: BPF prog-id=12 op=UNLOAD May 16 00:41:31.687000 audit: BPF prog-id=16 op=LOAD May 16 00:41:31.688000 audit: BPF prog-id=17 op=LOAD May 16 00:41:31.688000 audit: BPF prog-id=13 op=UNLOAD May 16 00:41:31.688000 audit: BPF prog-id=14 op=UNLOAD May 16 00:41:31.688000 audit: BPF prog-id=18 op=LOAD May 16 00:41:31.688000 audit: BPF prog-id=15 op=UNLOAD May 16 00:41:31.689000 audit: BPF prog-id=19 op=LOAD May 16 00:41:31.690000 audit: BPF prog-id=20 op=LOAD May 16 00:41:31.690000 audit: BPF prog-id=16 op=UNLOAD May 16 00:41:31.690000 audit: BPF prog-id=17 op=UNLOAD May 16 00:41:31.690000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.694000 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' May 16 00:41:31.694000 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' May 16 00:41:31.700000 audit: BPF prog-id=18 op=UNLOAD May 16 00:41:31.775000 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' May 16 00:41:31.777000 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' May 16 00:41:31.778000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.778000 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' May 16 00:41:31.778000 audit: BPF prog-id=21 op=LOAD May 16 00:41:31.778000 audit: BPF prog-id=22 op=LOAD May 16 00:41:31.778000 audit: BPF prog-id=23 op=LOAD May 16 00:41:31.778000 audit: BPF prog-id=19 op=UNLOAD May 16 00:41:31.807095 systemd[1]: Started systemd-journald.service. May 16 00:41:31.778000 audit: BPF prog-id=20 op=UNLOAD May 16 00:41:31.796000 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' May 16 00:41:31.802000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 May 16 00:41:31.802000 audit[987]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffee73ea70 a2=4000 a3=1 items=0 ppid=1 pid=987 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) May 16 00:41:31.802000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" May 16 00:41:31.805000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:30.029163 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" May 16 00:41:31.684450 systemd[1]: Queued start job for default target multi-user.target. May 16 00:41:30.029430 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json May 16 00:41:31.684461 systemd[1]: Unnecessary job was removed for dev-vda6.device. May 16 00:41:30.029449 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json May 16 00:41:31.691351 systemd[1]: systemd-journald.service: Deactivated successfully. May 16 00:41:30.029477 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" May 16 00:41:31.807000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:30.029487 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="skipped missing lower profile" missing profile=oem May 16 00:41:30.029517 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" May 16 00:41:30.029529 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= May 16 00:41:30.029746 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack May 16 00:41:31.808036 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 16 00:41:30.029787 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json May 16 00:41:31.808171 systemd[1]: Finished modprobe@configfs.service. May 16 00:41:30.029800 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json May 16 00:41:30.030227 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" 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 May 16 00:41:30.030267 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" 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 May 16 00:41:30.030286 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" 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 May 16 00:41:30.030300 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store May 16 00:41:30.030317 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" 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 May 16 00:41:30.030330 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:30Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store May 16 00:41:31.452380 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:31Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl May 16 00:41:31.808000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.808000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.452629 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:31Z" 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 May 16 00:41:31.452737 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:31Z" 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 May 16 00:41:31.452920 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:31Z" 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 May 16 00:41:31.809283 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:41:31.452980 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:31Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= May 16 00:41:31.453039 /usr/lib/systemd/system-generators/torcx-generator[929]: time="2025-05-16T00:41:31Z" 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 May 16 00:41:31.809446 systemd[1]: Finished modprobe@dm_mod.service. May 16 00:41:31.809000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.809000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.810473 systemd[1]: modprobe@drm.service: Deactivated successfully. May 16 00:41:31.810850 systemd[1]: Finished modprobe@drm.service. May 16 00:41:31.810000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.810000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.811669 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:41:31.811838 systemd[1]: Finished modprobe@efi_pstore.service. May 16 00:41:31.811000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.811000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.812738 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 16 00:41:31.812914 systemd[1]: Finished modprobe@fuse.service. May 16 00:41:31.812000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.812000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.813746 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:41:31.813902 systemd[1]: Finished modprobe@loop.service. May 16 00:41:31.813000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.813000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.814907 systemd[1]: Finished systemd-modules-load.service. May 16 00:41:31.814000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.817045 systemd[1]: Finished systemd-network-generator.service. May 16 00:41:31.816000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.818019 systemd[1]: Finished systemd-remount-fs.service. May 16 00:41:31.817000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.819880 systemd[1]: Reached target network-pre.target. May 16 00:41:31.821788 systemd[1]: Mounting sys-fs-fuse-connections.mount... May 16 00:41:31.823605 systemd[1]: Mounting sys-kernel-config.mount... May 16 00:41:31.824225 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 16 00:41:31.825877 systemd[1]: Starting systemd-hwdb-update.service... May 16 00:41:31.827570 systemd[1]: Starting systemd-journal-flush.service... May 16 00:41:31.828253 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 16 00:41:31.829275 systemd[1]: Starting systemd-random-seed.service... May 16 00:41:31.829923 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 16 00:41:31.831039 systemd[1]: Starting systemd-sysctl.service... May 16 00:41:31.833733 systemd[1]: Mounted sys-fs-fuse-connections.mount. May 16 00:41:31.835419 systemd[1]: Mounted sys-kernel-config.mount. May 16 00:41:31.837834 systemd-journald[987]: Time spent on flushing to /var/log/journal/7d8045f81c304b74a600827436ac0536 is 14.737ms for 986 entries. May 16 00:41:31.837834 systemd-journald[987]: System Journal (/var/log/journal/7d8045f81c304b74a600827436ac0536) is 8.0M, max 195.6M, 187.6M free. May 16 00:41:31.862060 systemd-journald[987]: Received client request to flush runtime journal. May 16 00:41:31.843000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.847000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.857000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.858000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.843300 systemd[1]: Finished systemd-udev-trigger.service. May 16 00:41:31.862554 udevadm[1027]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 16 00:41:31.845143 systemd[1]: Starting systemd-udev-settle.service... May 16 00:41:31.846541 systemd[1]: Finished systemd-random-seed.service. May 16 00:41:31.847409 systemd[1]: Reached target first-boot-complete.target. May 16 00:41:31.858046 systemd[1]: Finished flatcar-tmpfiles.service. May 16 00:41:31.858910 systemd[1]: Finished systemd-sysctl.service. May 16 00:41:31.860558 systemd[1]: Starting systemd-sysusers.service... May 16 00:41:31.862950 systemd[1]: Finished systemd-journal-flush.service. May 16 00:41:31.862000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.873620 systemd[1]: Finished systemd-sysusers.service. May 16 00:41:31.874000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:31.875413 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... May 16 00:41:31.890290 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. May 16 00:41:31.890000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.190000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.190601 systemd[1]: Finished systemd-hwdb-update.service. May 16 00:41:32.191000 audit: BPF prog-id=24 op=LOAD May 16 00:41:32.191000 audit: BPF prog-id=25 op=LOAD May 16 00:41:32.191000 audit: BPF prog-id=7 op=UNLOAD May 16 00:41:32.191000 audit: BPF prog-id=8 op=UNLOAD May 16 00:41:32.192673 systemd[1]: Starting systemd-udevd.service... May 16 00:41:32.208994 systemd-udevd[1034]: Using default interface naming scheme 'v252'. May 16 00:41:32.223942 systemd[1]: Started systemd-udevd.service. May 16 00:41:32.223000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.225000 audit: BPF prog-id=26 op=LOAD May 16 00:41:32.226421 systemd[1]: Starting systemd-networkd.service... May 16 00:41:32.233000 audit: BPF prog-id=27 op=LOAD May 16 00:41:32.233000 audit: BPF prog-id=28 op=LOAD May 16 00:41:32.233000 audit: BPF prog-id=29 op=LOAD May 16 00:41:32.235075 systemd[1]: Starting systemd-userdbd.service... May 16 00:41:32.256538 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. May 16 00:41:32.269037 systemd[1]: Started systemd-userdbd.service. May 16 00:41:32.269000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.277751 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. May 16 00:41:32.326344 systemd[1]: Finished systemd-udev-settle.service. May 16 00:41:32.326397 systemd-networkd[1035]: lo: Link UP May 16 00:41:32.326401 systemd-networkd[1035]: lo: Gained carrier May 16 00:41:32.328594 systemd[1]: Starting lvm2-activation-early.service... May 16 00:41:32.326000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.330240 systemd-networkd[1035]: Enumeration completed May 16 00:41:32.330351 systemd[1]: Started systemd-networkd.service. May 16 00:41:32.330354 systemd-networkd[1035]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 16 00:41:32.330000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.331653 systemd-networkd[1035]: eth0: Link UP May 16 00:41:32.331662 systemd-networkd[1035]: eth0: Gained carrier May 16 00:41:32.341224 lvm[1067]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 16 00:41:32.355839 systemd-networkd[1035]: eth0: DHCPv4 address 10.0.0.70/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 16 00:41:32.373560 systemd[1]: Finished lvm2-activation-early.service. May 16 00:41:32.373000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.374641 systemd[1]: Reached target cryptsetup.target. May 16 00:41:32.376678 systemd[1]: Starting lvm2-activation.service... May 16 00:41:32.380424 lvm[1068]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 16 00:41:32.424681 systemd[1]: Finished lvm2-activation.service. May 16 00:41:32.424000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.425710 systemd[1]: Reached target local-fs-pre.target. May 16 00:41:32.426624 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 16 00:41:32.426661 systemd[1]: Reached target local-fs.target. May 16 00:41:32.427527 systemd[1]: Reached target machines.target. May 16 00:41:32.429629 systemd[1]: Starting ldconfig.service... May 16 00:41:32.430749 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 16 00:41:32.430865 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 16 00:41:32.432441 systemd[1]: Starting systemd-boot-update.service... May 16 00:41:32.435026 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... May 16 00:41:32.438854 systemd[1]: Starting systemd-machine-id-commit.service... May 16 00:41:32.440935 systemd[1]: Starting systemd-sysext.service... May 16 00:41:32.441898 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1070 (bootctl) May 16 00:41:32.443075 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... May 16 00:41:32.455550 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. May 16 00:41:32.455000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.462121 systemd[1]: Unmounting usr-share-oem.mount... May 16 00:41:32.469978 systemd[1]: usr-share-oem.mount: Deactivated successfully. May 16 00:41:32.470729 systemd[1]: Unmounted usr-share-oem.mount. May 16 00:41:32.475325 systemd-fsck[1078]: fsck.fat 4.2 (2021-01-31) May 16 00:41:32.475325 systemd-fsck[1078]: /dev/vda1: 236 files, 117310/258078 clusters May 16 00:41:32.476911 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. May 16 00:41:32.477000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.517750 kernel: loop0: detected capacity change from 0 to 203944 May 16 00:41:32.519655 systemd[1]: Finished systemd-machine-id-commit.service. May 16 00:41:32.520000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.528737 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 16 00:41:32.545735 kernel: loop1: detected capacity change from 0 to 203944 May 16 00:41:32.550931 (sd-sysext)[1086]: Using extensions 'kubernetes'. May 16 00:41:32.551257 (sd-sysext)[1086]: Merged extensions into '/usr'. May 16 00:41:32.569473 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 16 00:41:32.571009 systemd[1]: Starting modprobe@dm_mod.service... May 16 00:41:32.572972 systemd[1]: Starting modprobe@efi_pstore.service... May 16 00:41:32.574925 systemd[1]: Starting modprobe@loop.service... May 16 00:41:32.575506 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 16 00:41:32.575628 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 16 00:41:32.576474 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:41:32.576612 systemd[1]: Finished modprobe@dm_mod.service. May 16 00:41:32.577000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.577000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.577681 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:41:32.577806 systemd[1]: Finished modprobe@efi_pstore.service. May 16 00:41:32.577000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.577000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.578882 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:41:32.578995 systemd[1]: Finished modprobe@loop.service. May 16 00:41:32.578000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.579000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.580035 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 16 00:41:32.580130 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 16 00:41:32.610212 ldconfig[1069]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 16 00:41:32.613906 systemd[1]: Finished ldconfig.service. May 16 00:41:32.613000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.797940 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 16 00:41:32.799769 systemd[1]: Mounting boot.mount... May 16 00:41:32.801553 systemd[1]: Mounting usr-share-oem.mount... May 16 00:41:32.807806 systemd[1]: Mounted boot.mount. May 16 00:41:32.810056 systemd[1]: Mounted usr-share-oem.mount. May 16 00:41:32.812048 systemd[1]: Finished systemd-sysext.service. May 16 00:41:32.812000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.814561 systemd[1]: Starting ensure-sysext.service... May 16 00:41:32.816336 systemd[1]: Starting systemd-tmpfiles-setup.service... May 16 00:41:32.819150 systemd[1]: Finished systemd-boot-update.service. May 16 00:41:32.819000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.821598 systemd[1]: Reloading. May 16 00:41:32.826286 systemd-tmpfiles[1094]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. May 16 00:41:32.827369 systemd-tmpfiles[1094]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 16 00:41:32.828919 systemd-tmpfiles[1094]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 16 00:41:32.856256 /usr/lib/systemd/system-generators/torcx-generator[1114]: time="2025-05-16T00:41:32Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" May 16 00:41:32.856286 /usr/lib/systemd/system-generators/torcx-generator[1114]: time="2025-05-16T00:41:32Z" level=info msg="torcx already run" May 16 00:41:32.920589 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 16 00:41:32.920612 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 16 00:41:32.936327 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 16 00:41:32.978000 audit: BPF prog-id=30 op=LOAD May 16 00:41:32.978000 audit: BPF prog-id=27 op=UNLOAD May 16 00:41:32.978000 audit: BPF prog-id=31 op=LOAD May 16 00:41:32.978000 audit: BPF prog-id=32 op=LOAD May 16 00:41:32.978000 audit: BPF prog-id=28 op=UNLOAD May 16 00:41:32.978000 audit: BPF prog-id=29 op=UNLOAD May 16 00:41:32.979000 audit: BPF prog-id=33 op=LOAD May 16 00:41:32.979000 audit: BPF prog-id=21 op=UNLOAD May 16 00:41:32.979000 audit: BPF prog-id=34 op=LOAD May 16 00:41:32.979000 audit: BPF prog-id=35 op=LOAD May 16 00:41:32.979000 audit: BPF prog-id=22 op=UNLOAD May 16 00:41:32.979000 audit: BPF prog-id=23 op=UNLOAD May 16 00:41:32.979000 audit: BPF prog-id=36 op=LOAD May 16 00:41:32.980000 audit: BPF prog-id=37 op=LOAD May 16 00:41:32.980000 audit: BPF prog-id=24 op=UNLOAD May 16 00:41:32.980000 audit: BPF prog-id=25 op=UNLOAD May 16 00:41:32.981000 audit: BPF prog-id=38 op=LOAD May 16 00:41:32.981000 audit: BPF prog-id=26 op=UNLOAD May 16 00:41:32.983858 systemd[1]: Finished systemd-tmpfiles-setup.service. May 16 00:41:32.983000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.988856 systemd[1]: Starting audit-rules.service... May 16 00:41:32.990515 systemd[1]: Starting clean-ca-certificates.service... May 16 00:41:32.992586 systemd[1]: Starting systemd-journal-catalog-update.service... May 16 00:41:32.993000 audit: BPF prog-id=39 op=LOAD May 16 00:41:32.995175 systemd[1]: Starting systemd-resolved.service... May 16 00:41:32.997000 audit: BPF prog-id=40 op=LOAD May 16 00:41:32.998837 systemd[1]: Starting systemd-timesyncd.service... May 16 00:41:33.000678 systemd[1]: Starting systemd-update-utmp.service... May 16 00:41:33.005110 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 16 00:41:33.006000 audit[1159]: SYSTEM_BOOT pid=1159 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' May 16 00:41:33.006268 systemd[1]: Starting modprobe@dm_mod.service... May 16 00:41:33.008372 systemd[1]: Starting modprobe@efi_pstore.service... May 16 00:41:33.010336 systemd[1]: Starting modprobe@loop.service... May 16 00:41:33.011027 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 16 00:41:33.011188 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 16 00:41:33.012232 systemd[1]: Finished clean-ca-certificates.service. May 16 00:41:33.012000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.013348 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:41:33.014000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.014000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.013464 systemd[1]: Finished modprobe@dm_mod.service. May 16 00:41:33.014484 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:41:33.014602 systemd[1]: Finished modprobe@efi_pstore.service. May 16 00:41:33.015000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.015000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.015773 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:41:33.015881 systemd[1]: Finished modprobe@loop.service. May 16 00:41:33.015000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.015000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.018460 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 16 00:41:33.018591 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 16 00:41:33.018700 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 16 00:41:33.020873 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 16 00:41:33.022173 systemd[1]: Starting modprobe@dm_mod.service... May 16 00:41:33.027000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.023921 systemd[1]: Starting modprobe@efi_pstore.service... May 16 00:41:33.025565 systemd[1]: Starting modprobe@loop.service... May 16 00:41:33.026192 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 16 00:41:33.026303 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 16 00:41:33.026389 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 16 00:41:33.027168 systemd[1]: Finished systemd-journal-catalog-update.service. May 16 00:41:33.028338 systemd[1]: Finished systemd-update-utmp.service. May 16 00:41:33.028000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.029457 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:41:33.029569 systemd[1]: Finished modprobe@dm_mod.service. May 16 00:41:33.030000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.030000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.030595 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:41:33.030699 systemd[1]: Finished modprobe@efi_pstore.service. May 16 00:41:33.030000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.030000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.031829 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:41:33.031944 systemd[1]: Finished modprobe@loop.service. May 16 00:41:33.031000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.031000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.035491 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. May 16 00:41:33.036596 systemd[1]: Starting modprobe@dm_mod.service... May 16 00:41:33.038407 systemd[1]: Starting modprobe@drm.service... May 16 00:41:33.040224 systemd[1]: Starting modprobe@efi_pstore.service... May 16 00:41:33.042010 systemd[1]: Starting modprobe@loop.service... May 16 00:41:33.042666 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. May 16 00:41:33.042848 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 16 00:41:33.044101 systemd[1]: Starting systemd-networkd-wait-online.service... May 16 00:41:33.046063 systemd[1]: Starting systemd-update-done.service... May 16 00:41:33.048000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.048000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.046847 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 16 00:41:33.049000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.049000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.047937 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:41:33.048079 systemd[1]: Finished modprobe@dm_mod.service. May 16 00:41:33.049217 systemd[1]: modprobe@drm.service: Deactivated successfully. May 16 00:41:33.049323 systemd[1]: Finished modprobe@drm.service. May 16 00:41:33.051000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.051000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.052000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.052000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.050399 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:41:33.050513 systemd[1]: Finished modprobe@efi_pstore.service. May 16 00:41:33.051571 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:41:33.051673 systemd[1]: Finished modprobe@loop.service. May 16 00:41:33.053000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.052675 systemd[1]: Finished systemd-update-done.service. May 16 00:41:33.054021 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 16 00:41:33.054110 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. May 16 00:41:33.055087 systemd[1]: Finished ensure-sysext.service. May 16 00:41:33.054000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ensure-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:33.073683 systemd-resolved[1157]: Positive Trust Anchors: May 16 00:41:33.073693 systemd-resolved[1157]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 16 00:41:33.073731 systemd-resolved[1157]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test May 16 00:41:32.589196 systemd-timesyncd[1158]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 16 00:41:32.608349 systemd-journald[987]: Time jumped backwards, rotating. May 16 00:41:32.592000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' May 16 00:41:32.593000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 May 16 00:41:32.593000 audit[1186]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffcc26ebe0 a2=420 a3=0 items=0 ppid=1153 pid=1186 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) May 16 00:41:32.593000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 May 16 00:41:32.608564 augenrules[1186]: No rules May 16 00:41:32.590378 systemd-timesyncd[1158]: Initial clock synchronization to Fri 2025-05-16 00:41:32.589120 UTC. May 16 00:41:32.591767 systemd[1]: Started systemd-timesyncd.service. May 16 00:41:32.592761 systemd[1]: Reached target time-set.target. May 16 00:41:32.594523 systemd[1]: Finished audit-rules.service. May 16 00:41:32.596891 systemd-resolved[1157]: Defaulting to hostname 'linux'. May 16 00:41:32.598178 systemd[1]: Started systemd-resolved.service. May 16 00:41:32.598900 systemd[1]: Reached target network.target. May 16 00:41:32.599483 systemd[1]: Reached target nss-lookup.target. May 16 00:41:32.601090 systemd[1]: Reached target sysinit.target. May 16 00:41:32.601756 systemd[1]: Started motdgen.path. May 16 00:41:32.602295 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. May 16 00:41:32.603216 systemd[1]: Started logrotate.timer. May 16 00:41:32.604085 systemd[1]: Started mdadm.timer. May 16 00:41:32.604610 systemd[1]: Started systemd-tmpfiles-clean.timer. May 16 00:41:32.605190 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 16 00:41:32.605214 systemd[1]: Reached target paths.target. May 16 00:41:32.605767 systemd[1]: Reached target timers.target. May 16 00:41:32.606606 systemd[1]: Listening on dbus.socket. May 16 00:41:32.608302 systemd[1]: Starting docker.socket... May 16 00:41:32.611314 systemd[1]: Listening on sshd.socket. May 16 00:41:32.612000 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 16 00:41:32.612424 systemd[1]: Listening on docker.socket. May 16 00:41:32.613068 systemd[1]: Reached target sockets.target. May 16 00:41:32.613721 systemd[1]: Reached target basic.target. May 16 00:41:32.614295 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. May 16 00:41:32.614324 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. May 16 00:41:32.615259 systemd[1]: Starting containerd.service... May 16 00:41:32.616821 systemd[1]: Starting dbus.service... May 16 00:41:32.618310 systemd[1]: Starting enable-oem-cloudinit.service... May 16 00:41:32.620062 systemd[1]: Starting extend-filesystems.service... May 16 00:41:32.620810 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). May 16 00:41:32.622103 systemd[1]: Starting motdgen.service... May 16 00:41:32.623924 systemd[1]: Starting ssh-key-proc-cmdline.service... May 16 00:41:32.625760 systemd[1]: Starting sshd-keygen.service... May 16 00:41:32.631739 jq[1197]: false May 16 00:41:32.631464 systemd[1]: Starting systemd-logind.service... May 16 00:41:32.632285 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 16 00:41:32.632479 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 16 00:41:32.633223 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 16 00:41:32.637037 systemd[1]: Starting update-engine.service... May 16 00:41:32.639116 systemd[1]: Starting update-ssh-keys-after-ignition.service... May 16 00:41:32.641776 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 16 00:41:32.642185 jq[1211]: true May 16 00:41:32.641970 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. May 16 00:41:32.642444 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 16 00:41:32.642602 systemd[1]: Finished ssh-key-proc-cmdline.service. May 16 00:41:32.656289 systemd[1]: motdgen.service: Deactivated successfully. May 16 00:41:32.656466 systemd[1]: Finished motdgen.service. May 16 00:41:32.660623 jq[1213]: true May 16 00:41:32.665525 extend-filesystems[1198]: Found loop1 May 16 00:41:32.665525 extend-filesystems[1198]: Found vda May 16 00:41:32.665525 extend-filesystems[1198]: Found vda1 May 16 00:41:32.665525 extend-filesystems[1198]: Found vda2 May 16 00:41:32.665525 extend-filesystems[1198]: Found vda3 May 16 00:41:32.665525 extend-filesystems[1198]: Found usr May 16 00:41:32.665525 extend-filesystems[1198]: Found vda4 May 16 00:41:32.665525 extend-filesystems[1198]: Found vda6 May 16 00:41:32.665525 extend-filesystems[1198]: Found vda7 May 16 00:41:32.665525 extend-filesystems[1198]: Found vda9 May 16 00:41:32.665525 extend-filesystems[1198]: Checking size of /dev/vda9 May 16 00:41:32.696868 extend-filesystems[1198]: Resized partition /dev/vda9 May 16 00:41:32.691455 systemd[1]: Started dbus.service. May 16 00:41:32.691099 dbus-daemon[1196]: [system] SELinux support is enabled May 16 00:41:32.694683 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 16 00:41:32.694706 systemd[1]: Reached target system-config.target. May 16 00:41:32.695453 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 16 00:41:32.695466 systemd[1]: Reached target user-config.target. May 16 00:41:32.698630 extend-filesystems[1242]: resize2fs 1.46.5 (30-Dec-2021) May 16 00:41:32.704953 systemd-logind[1205]: Watching system buttons on /dev/input/event0 (Power Button) May 16 00:41:32.712500 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 16 00:41:32.717360 systemd-logind[1205]: New seat seat0. May 16 00:41:32.719030 systemd[1]: Started systemd-logind.service. May 16 00:41:32.724814 update_engine[1208]: I0516 00:41:32.724601 1208 main.cc:92] Flatcar Update Engine starting May 16 00:41:32.730767 systemd[1]: Started update-engine.service. May 16 00:41:32.730982 update_engine[1208]: I0516 00:41:32.730810 1208 update_check_scheduler.cc:74] Next update check in 4m31s May 16 00:41:32.735530 systemd[1]: Started locksmithd.service. May 16 00:41:32.740297 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 16 00:41:32.742945 bash[1244]: Updated "/home/core/.ssh/authorized_keys" May 16 00:41:32.746078 systemd[1]: Finished update-ssh-keys-after-ignition.service. May 16 00:41:32.754924 extend-filesystems[1242]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 16 00:41:32.754924 extend-filesystems[1242]: old_desc_blocks = 1, new_desc_blocks = 1 May 16 00:41:32.754924 extend-filesystems[1242]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 16 00:41:32.758154 extend-filesystems[1198]: Resized filesystem in /dev/vda9 May 16 00:41:32.758885 env[1215]: time="2025-05-16T00:41:32.755788227Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 May 16 00:41:32.758684 systemd[1]: extend-filesystems.service: Deactivated successfully. May 16 00:41:32.758849 systemd[1]: Finished extend-filesystems.service. May 16 00:41:32.778145 env[1215]: time="2025-05-16T00:41:32.778094107Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 16 00:41:32.778293 env[1215]: time="2025-05-16T00:41:32.778260107Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 16 00:41:32.780106 env[1215]: time="2025-05-16T00:41:32.780058747Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.181-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 16 00:41:32.780106 env[1215]: time="2025-05-16T00:41:32.780099067Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 16 00:41:32.780372 env[1215]: time="2025-05-16T00:41:32.780336147Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 16 00:41:32.780372 env[1215]: time="2025-05-16T00:41:32.780358347Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 16 00:41:32.780372 env[1215]: time="2025-05-16T00:41:32.780373067Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" May 16 00:41:32.780444 env[1215]: time="2025-05-16T00:41:32.780383067Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 16 00:41:32.780478 env[1215]: time="2025-05-16T00:41:32.780458547Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 16 00:41:32.780717 env[1215]: time="2025-05-16T00:41:32.780684867Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 16 00:41:32.780833 env[1215]: time="2025-05-16T00:41:32.780813227Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 16 00:41:32.780861 env[1215]: time="2025-05-16T00:41:32.780832347Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 16 00:41:32.780901 env[1215]: time="2025-05-16T00:41:32.780885187Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" May 16 00:41:32.780933 env[1215]: time="2025-05-16T00:41:32.780901827Z" level=info msg="metadata content store policy set" policy=shared May 16 00:41:32.784206 env[1215]: time="2025-05-16T00:41:32.784166987Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 16 00:41:32.784261 env[1215]: time="2025-05-16T00:41:32.784202467Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 16 00:41:32.784328 env[1215]: time="2025-05-16T00:41:32.784263547Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 16 00:41:32.784328 env[1215]: time="2025-05-16T00:41:32.784317667Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 16 00:41:32.784368 env[1215]: time="2025-05-16T00:41:32.784333307Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 16 00:41:32.784804 env[1215]: time="2025-05-16T00:41:32.784363387Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 16 00:41:32.784804 env[1215]: time="2025-05-16T00:41:32.784423627Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 16 00:41:32.784891 env[1215]: time="2025-05-16T00:41:32.784868707Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 16 00:41:32.784913 env[1215]: time="2025-05-16T00:41:32.784893307Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 May 16 00:41:32.784953 env[1215]: time="2025-05-16T00:41:32.784909067Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 16 00:41:32.784953 env[1215]: time="2025-05-16T00:41:32.784949467Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 16 00:41:32.785048 env[1215]: time="2025-05-16T00:41:32.784963747Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 16 00:41:32.785168 env[1215]: time="2025-05-16T00:41:32.785147707Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 16 00:41:32.785292 env[1215]: time="2025-05-16T00:41:32.785253587Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 16 00:41:32.785559 env[1215]: time="2025-05-16T00:41:32.785539827Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 16 00:41:32.785598 env[1215]: time="2025-05-16T00:41:32.785571747Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785598 env[1215]: time="2025-05-16T00:41:32.785588387Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 16 00:41:32.785773 env[1215]: time="2025-05-16T00:41:32.785758307Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785805 env[1215]: time="2025-05-16T00:41:32.785774787Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785805 env[1215]: time="2025-05-16T00:41:32.785788027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785805 env[1215]: time="2025-05-16T00:41:32.785799627Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785867 env[1215]: time="2025-05-16T00:41:32.785811987Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785867 env[1215]: time="2025-05-16T00:41:32.785824547Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785867 env[1215]: time="2025-05-16T00:41:32.785839227Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785960 env[1215]: time="2025-05-16T00:41:32.785868107Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 16 00:41:32.785960 env[1215]: time="2025-05-16T00:41:32.785891267Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 16 00:41:32.786145 env[1215]: time="2025-05-16T00:41:32.786124027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 16 00:41:32.786177 env[1215]: time="2025-05-16T00:41:32.786150187Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 16 00:41:32.786177 env[1215]: time="2025-05-16T00:41:32.786164347Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 16 00:41:32.786177 env[1215]: time="2025-05-16T00:41:32.786175467Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 16 00:41:32.786233 env[1215]: time="2025-05-16T00:41:32.786190747Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 May 16 00:41:32.786233 env[1215]: time="2025-05-16T00:41:32.786201987Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 16 00:41:32.786233 env[1215]: time="2025-05-16T00:41:32.786219707Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" May 16 00:41:32.786318 env[1215]: time="2025-05-16T00:41:32.786253027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 16 00:41:32.786505 env[1215]: time="2025-05-16T00:41:32.786458227Z" 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}" May 16 00:41:32.787309 env[1215]: time="2025-05-16T00:41:32.786525147Z" level=info msg="Connect containerd service" May 16 00:41:32.787309 env[1215]: time="2025-05-16T00:41:32.786556067Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 16 00:41:32.787519 env[1215]: time="2025-05-16T00:41:32.787481347Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 16 00:41:32.787721 env[1215]: time="2025-05-16T00:41:32.787693827Z" level=info msg="Start subscribing containerd event" May 16 00:41:32.787827 env[1215]: time="2025-05-16T00:41:32.787812027Z" level=info msg="Start recovering state" May 16 00:41:32.787903 env[1215]: time="2025-05-16T00:41:32.787886147Z" level=info msg="Start event monitor" May 16 00:41:32.787961 env[1215]: time="2025-05-16T00:41:32.787909107Z" level=info msg="Start snapshots syncer" May 16 00:41:32.787961 env[1215]: time="2025-05-16T00:41:32.787919507Z" level=info msg="Start cni network conf syncer for default" May 16 00:41:32.787961 env[1215]: time="2025-05-16T00:41:32.787926667Z" level=info msg="Start streaming server" May 16 00:41:32.788459 env[1215]: time="2025-05-16T00:41:32.788437187Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 16 00:41:32.788550 env[1215]: time="2025-05-16T00:41:32.788534987Z" level=info msg=serving... address=/run/containerd/containerd.sock May 16 00:41:32.788601 env[1215]: time="2025-05-16T00:41:32.788588067Z" level=info msg="containerd successfully booted in 0.038914s" May 16 00:41:32.788677 systemd[1]: Started containerd.service. May 16 00:41:32.790392 locksmithd[1245]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 16 00:41:33.520427 systemd-networkd[1035]: eth0: Gained IPv6LL May 16 00:41:33.522916 systemd[1]: Finished systemd-networkd-wait-online.service. May 16 00:41:33.524010 systemd[1]: Reached target network-online.target. May 16 00:41:33.526156 systemd[1]: Starting kubelet.service... May 16 00:41:34.146877 systemd[1]: Started kubelet.service. May 16 00:41:34.444924 sshd_keygen[1218]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 16 00:41:34.462931 systemd[1]: Finished sshd-keygen.service. May 16 00:41:34.465102 systemd[1]: Starting issuegen.service... May 16 00:41:34.469776 systemd[1]: issuegen.service: Deactivated successfully. May 16 00:41:34.469933 systemd[1]: Finished issuegen.service. May 16 00:41:34.471874 systemd[1]: Starting systemd-user-sessions.service... May 16 00:41:34.478344 systemd[1]: Finished systemd-user-sessions.service. May 16 00:41:34.480541 systemd[1]: Started getty@tty1.service. May 16 00:41:34.482528 systemd[1]: Started serial-getty@ttyAMA0.service. May 16 00:41:34.483449 systemd[1]: Reached target getty.target. May 16 00:41:34.484221 systemd[1]: Reached target multi-user.target. May 16 00:41:34.486597 systemd[1]: Starting systemd-update-utmp-runlevel.service... May 16 00:41:34.493152 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 16 00:41:34.493334 systemd[1]: Finished systemd-update-utmp-runlevel.service. May 16 00:41:34.494236 systemd[1]: Startup finished in 559ms (kernel) + 4.315s (initrd) + 5.089s (userspace) = 9.964s. May 16 00:41:34.597661 kubelet[1259]: E0516 00:41:34.597605 1259 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" May 16 00:41:34.599505 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 16 00:41:34.599642 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 16 00:41:38.261531 systemd[1]: Created slice system-sshd.slice. May 16 00:41:38.262609 systemd[1]: Started sshd@0-10.0.0.70:22-10.0.0.1:42994.service. May 16 00:41:38.313006 sshd[1281]: Accepted publickey for core from 10.0.0.1 port 42994 ssh2: RSA SHA256:czXyODm5lEdSCdxgc4UKYE1H3sjGZqNxHBxH/SPqyp4 May 16 00:41:38.315369 sshd[1281]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 16 00:41:38.324250 systemd-logind[1205]: New session 1 of user core. May 16 00:41:38.325172 systemd[1]: Created slice user-500.slice. May 16 00:41:38.326318 systemd[1]: Starting user-runtime-dir@500.service... May 16 00:41:38.334711 systemd[1]: Finished user-runtime-dir@500.service. May 16 00:41:38.336133 systemd[1]: Starting user@500.service... May 16 00:41:38.339115 (systemd)[1284]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 16 00:41:38.400249 systemd[1284]: Queued start job for default target default.target. May 16 00:41:38.400775 systemd[1284]: Reached target paths.target. May 16 00:41:38.400808 systemd[1284]: Reached target sockets.target. May 16 00:41:38.400819 systemd[1284]: Reached target timers.target. May 16 00:41:38.400828 systemd[1284]: Reached target basic.target. May 16 00:41:38.400866 systemd[1284]: Reached target default.target. May 16 00:41:38.400889 systemd[1284]: Startup finished in 55ms. May 16 00:41:38.401103 systemd[1]: Started user@500.service. May 16 00:41:38.402135 systemd[1]: Started session-1.scope. May 16 00:41:38.453691 systemd[1]: Started sshd@1-10.0.0.70:22-10.0.0.1:43000.service. May 16 00:41:38.504807 sshd[1293]: Accepted publickey for core from 10.0.0.1 port 43000 ssh2: RSA SHA256:czXyODm5lEdSCdxgc4UKYE1H3sjGZqNxHBxH/SPqyp4 May 16 00:41:38.506016 sshd[1293]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 16 00:41:38.509436 systemd-logind[1205]: New session 2 of user core. May 16 00:41:38.510628 systemd[1]: Started session-2.scope. May 16 00:41:38.564046 sshd[1293]: pam_unix(sshd:session): session closed for user core May 16 00:41:38.566555 systemd[1]: sshd@1-10.0.0.70:22-10.0.0.1:43000.service: Deactivated successfully. May 16 00:41:38.567119 systemd[1]: session-2.scope: Deactivated successfully. May 16 00:41:38.567632 systemd-logind[1205]: Session 2 logged out. Waiting for processes to exit. May 16 00:41:38.568628 systemd[1]: Started sshd@2-10.0.0.70:22-10.0.0.1:43008.service. May 16 00:41:38.569236 systemd-logind[1205]: Removed session 2. May 16 00:41:38.605634 sshd[1299]: Accepted publickey for core from 10.0.0.1 port 43008 ssh2: RSA SHA256:czXyODm5lEdSCdxgc4UKYE1H3sjGZqNxHBxH/SPqyp4 May 16 00:41:38.606731 sshd[1299]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 16 00:41:38.609826 systemd-logind[1205]: New session 3 of user core. May 16 00:41:38.610564 systemd[1]: Started session-3.scope. May 16 00:41:38.659005 sshd[1299]: pam_unix(sshd:session): session closed for user core May 16 00:41:38.661333 systemd[1]: sshd@2-10.0.0.70:22-10.0.0.1:43008.service: Deactivated successfully. May 16 00:41:38.661836 systemd[1]: session-3.scope: Deactivated successfully. May 16 00:41:38.662283 systemd-logind[1205]: Session 3 logged out. Waiting for processes to exit. May 16 00:41:38.663202 systemd[1]: Started sshd@3-10.0.0.70:22-10.0.0.1:43024.service. May 16 00:41:38.663835 systemd-logind[1205]: Removed session 3. May 16 00:41:38.699700 sshd[1305]: Accepted publickey for core from 10.0.0.1 port 43024 ssh2: RSA SHA256:czXyODm5lEdSCdxgc4UKYE1H3sjGZqNxHBxH/SPqyp4 May 16 00:41:38.700733 sshd[1305]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 16 00:41:38.703934 systemd-logind[1205]: New session 4 of user core. May 16 00:41:38.704666 systemd[1]: Started session-4.scope. May 16 00:41:38.757319 sshd[1305]: pam_unix(sshd:session): session closed for user core May 16 00:41:38.761485 systemd[1]: sshd@3-10.0.0.70:22-10.0.0.1:43024.service: Deactivated successfully. May 16 00:41:38.762049 systemd[1]: session-4.scope: Deactivated successfully. May 16 00:41:38.762645 systemd-logind[1205]: Session 4 logged out. Waiting for processes to exit. May 16 00:41:38.763676 systemd[1]: Started sshd@4-10.0.0.70:22-10.0.0.1:43032.service. May 16 00:41:38.764357 systemd-logind[1205]: Removed session 4. May 16 00:41:38.799599 sshd[1311]: Accepted publickey for core from 10.0.0.1 port 43032 ssh2: RSA SHA256:czXyODm5lEdSCdxgc4UKYE1H3sjGZqNxHBxH/SPqyp4 May 16 00:41:38.801150 sshd[1311]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) May 16 00:41:38.804433 systemd-logind[1205]: New session 5 of user core. May 16 00:41:38.805204 systemd[1]: Started session-5.scope. May 16 00:41:38.865202 sudo[1314]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 16 00:41:38.866056 sudo[1314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) May 16 00:41:38.878148 systemd[1]: Starting coreos-metadata.service... May 16 00:41:38.884435 systemd[1]: coreos-metadata.service: Deactivated successfully. May 16 00:41:38.884591 systemd[1]: Finished coreos-metadata.service. May 16 00:41:39.361806 systemd[1]: Stopped kubelet.service. May 16 00:41:39.363739 systemd[1]: Starting kubelet.service... May 16 00:41:39.385500 systemd[1]: Reloading. May 16 00:41:39.436437 /usr/lib/systemd/system-generators/torcx-generator[1373]: time="2025-05-16T00:41:39Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" May 16 00:41:39.436470 /usr/lib/systemd/system-generators/torcx-generator[1373]: time="2025-05-16T00:41:39Z" level=info msg="torcx already run" May 16 00:41:39.609964 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. May 16 00:41:39.609985 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. May 16 00:41:39.625051 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 16 00:41:39.688461 systemd[1]: Started kubelet.service. May 16 00:41:39.689756 systemd[1]: Stopping kubelet.service... May 16 00:41:39.689984 systemd[1]: kubelet.service: Deactivated successfully. May 16 00:41:39.690137 systemd[1]: Stopped kubelet.service. May 16 00:41:39.691649 systemd[1]: Starting kubelet.service... May 16 00:41:39.780895 systemd[1]: Started kubelet.service. May 16 00:41:39.816290 kubelet[1418]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 16 00:41:39.816962 kubelet[1418]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 16 00:41:39.817034 kubelet[1418]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 16 00:41:39.817254 kubelet[1418]: I0516 00:41:39.817219 1418 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 16 00:41:41.523817 kubelet[1418]: I0516 00:41:41.523761 1418 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" May 16 00:41:41.523817 kubelet[1418]: I0516 00:41:41.523799 1418 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 16 00:41:41.524148 kubelet[1418]: I0516 00:41:41.524039 1418 server.go:934] "Client rotation is on, will bootstrap in background" May 16 00:41:41.592567 kubelet[1418]: I0516 00:41:41.592522 1418 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 16 00:41:41.597711 kubelet[1418]: E0516 00:41:41.597684 1418 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 16 00:41:41.597807 kubelet[1418]: I0516 00:41:41.597793 1418 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 16 00:41:41.601674 kubelet[1418]: I0516 00:41:41.601628 1418 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 16 00:41:41.602769 kubelet[1418]: I0516 00:41:41.602743 1418 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 16 00:41:41.602998 kubelet[1418]: I0516 00:41:41.602973 1418 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 16 00:41:41.603232 kubelet[1418]: I0516 00:41:41.603067 1418 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.70","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} May 16 00:41:41.603437 kubelet[1418]: I0516 00:41:41.603423 1418 topology_manager.go:138] "Creating topology manager with none policy" May 16 00:41:41.603493 kubelet[1418]: I0516 00:41:41.603484 1418 container_manager_linux.go:300] "Creating device plugin manager" May 16 00:41:41.603778 kubelet[1418]: I0516 00:41:41.603764 1418 state_mem.go:36] "Initialized new in-memory state store" May 16 00:41:41.607133 kubelet[1418]: I0516 00:41:41.607105 1418 kubelet.go:408] "Attempting to sync node with API server" May 16 00:41:41.607316 kubelet[1418]: I0516 00:41:41.607303 1418 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 16 00:41:41.607392 kubelet[1418]: I0516 00:41:41.607382 1418 kubelet.go:314] "Adding apiserver pod source" May 16 00:41:41.607577 kubelet[1418]: I0516 00:41:41.607561 1418 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 16 00:41:41.607683 kubelet[1418]: E0516 00:41:41.607566 1418 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:41.607683 kubelet[1418]: E0516 00:41:41.607596 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:41.614072 kubelet[1418]: W0516 00:41:41.614045 1418 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.70" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 16 00:41:41.614248 kubelet[1418]: E0516 00:41:41.614193 1418 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.70\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 16 00:41:41.615700 kubelet[1418]: I0516 00:41:41.615677 1418 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" May 16 00:41:41.616380 kubelet[1418]: I0516 00:41:41.616365 1418 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 16 00:41:41.616547 kubelet[1418]: W0516 00:41:41.616534 1418 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 16 00:41:41.617476 kubelet[1418]: I0516 00:41:41.617441 1418 server.go:1274] "Started kubelet" May 16 00:41:41.617691 kubelet[1418]: I0516 00:41:41.617633 1418 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 16 00:41:41.619625 kubelet[1418]: I0516 00:41:41.619571 1418 server.go:449] "Adding debug handlers to kubelet server" May 16 00:41:41.620540 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). May 16 00:41:41.620599 kubelet[1418]: I0516 00:41:41.617688 1418 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 16 00:41:41.620770 kubelet[1418]: I0516 00:41:41.620735 1418 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 16 00:41:41.621333 kubelet[1418]: I0516 00:41:41.621314 1418 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 16 00:41:41.621434 kubelet[1418]: I0516 00:41:41.620626 1418 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 16 00:41:41.623008 kubelet[1418]: I0516 00:41:41.622560 1418 volume_manager.go:289] "Starting Kubelet Volume Manager" May 16 00:41:41.623008 kubelet[1418]: I0516 00:41:41.622660 1418 desired_state_of_world_populator.go:147] "Desired state populator starts to run" May 16 00:41:41.623008 kubelet[1418]: I0516 00:41:41.622750 1418 reconciler.go:26] "Reconciler: start to sync state" May 16 00:41:41.623008 kubelet[1418]: E0516 00:41:41.622948 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:41.623997 kubelet[1418]: E0516 00:41:41.623975 1418 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 16 00:41:41.625756 kubelet[1418]: I0516 00:41:41.625721 1418 factory.go:221] Registration of the containerd container factory successfully May 16 00:41:41.625756 kubelet[1418]: I0516 00:41:41.625741 1418 factory.go:221] Registration of the systemd container factory successfully May 16 00:41:41.625871 kubelet[1418]: I0516 00:41:41.625821 1418 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory May 16 00:41:41.630177 kubelet[1418]: E0516 00:41:41.630140 1418 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.70\" not found" node="10.0.0.70" May 16 00:41:41.634651 kubelet[1418]: I0516 00:41:41.634631 1418 cpu_manager.go:214] "Starting CPU manager" policy="none" May 16 00:41:41.634651 kubelet[1418]: I0516 00:41:41.634647 1418 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 16 00:41:41.634735 kubelet[1418]: I0516 00:41:41.634676 1418 state_mem.go:36] "Initialized new in-memory state store" May 16 00:41:41.703135 kubelet[1418]: I0516 00:41:41.703103 1418 policy_none.go:49] "None policy: Start" May 16 00:41:41.703894 kubelet[1418]: I0516 00:41:41.703861 1418 memory_manager.go:170] "Starting memorymanager" policy="None" May 16 00:41:41.704012 kubelet[1418]: I0516 00:41:41.703902 1418 state_mem.go:35] "Initializing new in-memory state store" May 16 00:41:41.711680 systemd[1]: Created slice kubepods.slice. May 16 00:41:41.715631 systemd[1]: Created slice kubepods-burstable.slice. May 16 00:41:41.717938 systemd[1]: Created slice kubepods-besteffort.slice. May 16 00:41:41.723205 kubelet[1418]: E0516 00:41:41.723171 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:41.724151 kubelet[1418]: I0516 00:41:41.724121 1418 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 16 00:41:41.724986 kubelet[1418]: I0516 00:41:41.724969 1418 eviction_manager.go:189] "Eviction manager: starting control loop" May 16 00:41:41.725101 kubelet[1418]: I0516 00:41:41.724988 1418 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 16 00:41:41.725657 kubelet[1418]: I0516 00:41:41.725373 1418 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 16 00:41:41.726325 kubelet[1418]: E0516 00:41:41.726304 1418 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.70\" not found" May 16 00:41:41.772766 kubelet[1418]: I0516 00:41:41.772718 1418 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 16 00:41:41.773755 kubelet[1418]: I0516 00:41:41.773736 1418 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 16 00:41:41.773804 kubelet[1418]: I0516 00:41:41.773766 1418 status_manager.go:217] "Starting to sync pod status with apiserver" May 16 00:41:41.773804 kubelet[1418]: I0516 00:41:41.773788 1418 kubelet.go:2321] "Starting kubelet main sync loop" May 16 00:41:41.773882 kubelet[1418]: E0516 00:41:41.773834 1418 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" May 16 00:41:41.826588 kubelet[1418]: I0516 00:41:41.826559 1418 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.70" May 16 00:41:41.831141 kubelet[1418]: I0516 00:41:41.831112 1418 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.70" May 16 00:41:41.831141 kubelet[1418]: E0516 00:41:41.831140 1418 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.70\": node \"10.0.0.70\" not found" May 16 00:41:41.838548 kubelet[1418]: E0516 00:41:41.838497 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:41.939067 kubelet[1418]: E0516 00:41:41.939015 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:42.040174 kubelet[1418]: E0516 00:41:42.040042 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:42.141031 kubelet[1418]: E0516 00:41:42.140972 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:42.241485 kubelet[1418]: E0516 00:41:42.241449 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:42.306763 sudo[1314]: pam_unix(sudo:session): session closed for user root May 16 00:41:42.309474 sshd[1311]: pam_unix(sshd:session): session closed for user core May 16 00:41:42.311980 systemd[1]: sshd@4-10.0.0.70:22-10.0.0.1:43032.service: Deactivated successfully. May 16 00:41:42.312762 systemd[1]: session-5.scope: Deactivated successfully. May 16 00:41:42.313302 systemd-logind[1205]: Session 5 logged out. Waiting for processes to exit. May 16 00:41:42.313944 systemd-logind[1205]: Removed session 5. May 16 00:41:42.341996 kubelet[1418]: E0516 00:41:42.341960 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:42.442516 kubelet[1418]: E0516 00:41:42.442489 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:42.525920 kubelet[1418]: I0516 00:41:42.525890 1418 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 16 00:41:42.526203 kubelet[1418]: W0516 00:41:42.526096 1418 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 16 00:41:42.526203 kubelet[1418]: W0516 00:41:42.526100 1418 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 16 00:41:42.526203 kubelet[1418]: W0516 00:41:42.526122 1418 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 16 00:41:42.543178 kubelet[1418]: E0516 00:41:42.543155 1418 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.70\" not found" May 16 00:41:42.609157 kubelet[1418]: I0516 00:41:42.608779 1418 apiserver.go:52] "Watching apiserver" May 16 00:41:42.609157 kubelet[1418]: E0516 00:41:42.608816 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:42.617111 systemd[1]: Created slice kubepods-besteffort-pod48c4cdd1_a4e1_4e62_9f20_24c96f3a9eb5.slice. May 16 00:41:42.623886 kubelet[1418]: I0516 00:41:42.623861 1418 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" May 16 00:41:42.629390 kubelet[1418]: I0516 00:41:42.629365 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/bb520f55-7282-4f56-9284-040d90d48f94-cni\") pod \"kube-flannel-ds-kgrfn\" (UID: \"bb520f55-7282-4f56-9284-040d90d48f94\") " pod="kube-flannel/kube-flannel-ds-kgrfn" May 16 00:41:42.629461 kubelet[1418]: I0516 00:41:42.629401 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/bb520f55-7282-4f56-9284-040d90d48f94-xtables-lock\") pod \"kube-flannel-ds-kgrfn\" (UID: \"bb520f55-7282-4f56-9284-040d90d48f94\") " pod="kube-flannel/kube-flannel-ds-kgrfn" May 16 00:41:42.629461 kubelet[1418]: I0516 00:41:42.629423 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5fx85\" (UniqueName: \"kubernetes.io/projected/bb520f55-7282-4f56-9284-040d90d48f94-kube-api-access-5fx85\") pod \"kube-flannel-ds-kgrfn\" (UID: \"bb520f55-7282-4f56-9284-040d90d48f94\") " pod="kube-flannel/kube-flannel-ds-kgrfn" May 16 00:41:42.629461 kubelet[1418]: I0516 00:41:42.629440 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5-lib-modules\") pod \"kube-proxy-lhgd8\" (UID: \"48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5\") " pod="kube-system/kube-proxy-lhgd8" May 16 00:41:42.629461 kubelet[1418]: I0516 00:41:42.629455 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5-xtables-lock\") pod \"kube-proxy-lhgd8\" (UID: \"48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5\") " pod="kube-system/kube-proxy-lhgd8" May 16 00:41:42.629605 kubelet[1418]: I0516 00:41:42.629587 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kmpgs\" (UniqueName: \"kubernetes.io/projected/48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5-kube-api-access-kmpgs\") pod \"kube-proxy-lhgd8\" (UID: \"48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5\") " pod="kube-system/kube-proxy-lhgd8" May 16 00:41:42.629704 kubelet[1418]: I0516 00:41:42.629688 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/bb520f55-7282-4f56-9284-040d90d48f94-run\") pod \"kube-flannel-ds-kgrfn\" (UID: \"bb520f55-7282-4f56-9284-040d90d48f94\") " pod="kube-flannel/kube-flannel-ds-kgrfn" May 16 00:41:42.629741 kubelet[1418]: I0516 00:41:42.629714 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/bb520f55-7282-4f56-9284-040d90d48f94-cni-plugin\") pod \"kube-flannel-ds-kgrfn\" (UID: \"bb520f55-7282-4f56-9284-040d90d48f94\") " pod="kube-flannel/kube-flannel-ds-kgrfn" May 16 00:41:42.629741 kubelet[1418]: I0516 00:41:42.629735 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/bb520f55-7282-4f56-9284-040d90d48f94-flannel-cfg\") pod \"kube-flannel-ds-kgrfn\" (UID: \"bb520f55-7282-4f56-9284-040d90d48f94\") " pod="kube-flannel/kube-flannel-ds-kgrfn" May 16 00:41:42.629794 kubelet[1418]: I0516 00:41:42.629750 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5-kube-proxy\") pod \"kube-proxy-lhgd8\" (UID: \"48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5\") " pod="kube-system/kube-proxy-lhgd8" May 16 00:41:42.631364 systemd[1]: Created slice kubepods-burstable-podbb520f55_7282_4f56_9284_040d90d48f94.slice. May 16 00:41:42.644595 kubelet[1418]: I0516 00:41:42.644572 1418 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 16 00:41:42.644937 env[1215]: time="2025-05-16T00:41:42.644891747Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 16 00:41:42.645343 kubelet[1418]: I0516 00:41:42.645323 1418 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 16 00:41:42.738763 kubelet[1418]: I0516 00:41:42.738729 1418 swap_util.go:74] "error creating dir to test if tmpfs noswap is enabled. Assuming not supported" mount path="" error="stat /var/lib/kubelet/plugins/kubernetes.io/empty-dir: no such file or directory" May 16 00:41:42.930035 kubelet[1418]: E0516 00:41:42.929465 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:42.930421 env[1215]: time="2025-05-16T00:41:42.930373387Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-lhgd8,Uid:48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5,Namespace:kube-system,Attempt:0,}" May 16 00:41:42.942448 kubelet[1418]: E0516 00:41:42.942410 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:42.942874 env[1215]: time="2025-05-16T00:41:42.942835667Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-kgrfn,Uid:bb520f55-7282-4f56-9284-040d90d48f94,Namespace:kube-flannel,Attempt:0,}" May 16 00:41:43.466360 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4088307845.mount: Deactivated successfully. May 16 00:41:43.471207 env[1215]: time="2025-05-16T00:41:43.471162787Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.472094 env[1215]: time="2025-05-16T00:41:43.472069107Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.473592 env[1215]: time="2025-05-16T00:41:43.473558867Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.475965 env[1215]: time="2025-05-16T00:41:43.475924947Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.477094 env[1215]: time="2025-05-16T00:41:43.477062547Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.479206 env[1215]: time="2025-05-16T00:41:43.479175627Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.479955 env[1215]: time="2025-05-16T00:41:43.479917467Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.482602 env[1215]: time="2025-05-16T00:41:43.482572867Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:43.505112 env[1215]: time="2025-05-16T00:41:43.505011747Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:41:43.505112 env[1215]: time="2025-05-16T00:41:43.505058267Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:41:43.505112 env[1215]: time="2025-05-16T00:41:43.505079867Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:41:43.505405 env[1215]: time="2025-05-16T00:41:43.505360027Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:41:43.505460 env[1215]: time="2025-05-16T00:41:43.505396667Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda pid=1481 runtime=io.containerd.runc.v2 May 16 00:41:43.505460 env[1215]: time="2025-05-16T00:41:43.505436547Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:41:43.505511 env[1215]: time="2025-05-16T00:41:43.505463747Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:41:43.505659 env[1215]: time="2025-05-16T00:41:43.505622267Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/d490b058c977369c7d4f50494676cc556914ef1b9253ce754636ba43d55dcf95 pid=1480 runtime=io.containerd.runc.v2 May 16 00:41:43.535184 systemd[1]: Started cri-containerd-13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda.scope. May 16 00:41:43.536445 systemd[1]: Started cri-containerd-d490b058c977369c7d4f50494676cc556914ef1b9253ce754636ba43d55dcf95.scope. May 16 00:41:43.587800 env[1215]: time="2025-05-16T00:41:43.587442307Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-lhgd8,Uid:48c4cdd1-a4e1-4e62-9f20-24c96f3a9eb5,Namespace:kube-system,Attempt:0,} returns sandbox id \"d490b058c977369c7d4f50494676cc556914ef1b9253ce754636ba43d55dcf95\"" May 16 00:41:43.588315 kubelet[1418]: E0516 00:41:43.588292 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:43.589437 env[1215]: time="2025-05-16T00:41:43.589404347Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.9\"" May 16 00:41:43.589838 env[1215]: time="2025-05-16T00:41:43.589803627Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-kgrfn,Uid:bb520f55-7282-4f56-9284-040d90d48f94,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda\"" May 16 00:41:43.590760 kubelet[1418]: E0516 00:41:43.590592 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:43.609711 kubelet[1418]: E0516 00:41:43.609684 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:44.609470 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount936587632.mount: Deactivated successfully. May 16 00:41:44.610922 kubelet[1418]: E0516 00:41:44.610872 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:45.060028 env[1215]: time="2025-05-16T00:41:45.059970427Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.31.9,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:45.061162 env[1215]: time="2025-05-16T00:41:45.061133947Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:cbfba5e6542fe387b24d9e73bf5a054a6b07b95af1392268fd82b6f449ef1c27,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:45.062410 env[1215]: time="2025-05-16T00:41:45.062388147Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.31.9,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:45.063449 env[1215]: time="2025-05-16T00:41:45.063418587Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:fdf026cf2434537e499e9c739d189ca8fc57101d929ac5ccd8e24f979a9738c1,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:45.064044 env[1215]: time="2025-05-16T00:41:45.064014667Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.9\" returns image reference \"sha256:cbfba5e6542fe387b24d9e73bf5a054a6b07b95af1392268fd82b6f449ef1c27\"" May 16 00:41:45.065613 env[1215]: time="2025-05-16T00:41:45.065336067Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 16 00:41:45.066265 env[1215]: time="2025-05-16T00:41:45.066238427Z" level=info msg="CreateContainer within sandbox \"d490b058c977369c7d4f50494676cc556914ef1b9253ce754636ba43d55dcf95\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 16 00:41:45.076453 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1969874664.mount: Deactivated successfully. May 16 00:41:45.081422 env[1215]: time="2025-05-16T00:41:45.081389987Z" level=info msg="CreateContainer within sandbox \"d490b058c977369c7d4f50494676cc556914ef1b9253ce754636ba43d55dcf95\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"d26ee7fb2c2039e85b6ec1db5c6a96acb8e5def887a8ca544fd1528642f6b3dc\"" May 16 00:41:45.081991 env[1215]: time="2025-05-16T00:41:45.081968587Z" level=info msg="StartContainer for \"d26ee7fb2c2039e85b6ec1db5c6a96acb8e5def887a8ca544fd1528642f6b3dc\"" May 16 00:41:45.099230 systemd[1]: Started cri-containerd-d26ee7fb2c2039e85b6ec1db5c6a96acb8e5def887a8ca544fd1528642f6b3dc.scope. May 16 00:41:45.135064 env[1215]: time="2025-05-16T00:41:45.135021787Z" level=info msg="StartContainer for \"d26ee7fb2c2039e85b6ec1db5c6a96acb8e5def887a8ca544fd1528642f6b3dc\" returns successfully" May 16 00:41:45.611166 kubelet[1418]: E0516 00:41:45.611088 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:45.785073 kubelet[1418]: E0516 00:41:45.785044 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:45.794201 kubelet[1418]: I0516 00:41:45.794147 1418 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-lhgd8" podStartSLOduration=3.318189067 podStartE2EDuration="4.794133267s" podCreationTimestamp="2025-05-16 00:41:41 +0000 UTC" firstStartedPulling="2025-05-16 00:41:43.588996387 +0000 UTC m=+3.804742601" lastFinishedPulling="2025-05-16 00:41:45.064940547 +0000 UTC m=+5.280686801" observedRunningTime="2025-05-16 00:41:45.794049987 +0000 UTC m=+6.009796241" watchObservedRunningTime="2025-05-16 00:41:45.794133267 +0000 UTC m=+6.009879521" May 16 00:41:46.188768 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3642717198.mount: Deactivated successfully. May 16 00:41:46.230433 env[1215]: time="2025-05-16T00:41:46.230379027Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:46.231672 env[1215]: time="2025-05-16T00:41:46.231639667Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:46.233015 env[1215]: time="2025-05-16T00:41:46.232986627Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel-cni-plugin:v1.1.2,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:46.234920 env[1215]: time="2025-05-16T00:41:46.234894667Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:46.235413 env[1215]: time="2025-05-16T00:41:46.235384827Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 16 00:41:46.237570 env[1215]: time="2025-05-16T00:41:46.237530747Z" level=info msg="CreateContainer within sandbox \"13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 16 00:41:46.247613 env[1215]: time="2025-05-16T00:41:46.247571867Z" level=info msg="CreateContainer within sandbox \"13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"0ac3742360b6694218fe22ab04659af4c326f484b5c7eb940587739dffea7bbe\"" May 16 00:41:46.248177 env[1215]: time="2025-05-16T00:41:46.248104347Z" level=info msg="StartContainer for \"0ac3742360b6694218fe22ab04659af4c326f484b5c7eb940587739dffea7bbe\"" May 16 00:41:46.262175 systemd[1]: Started cri-containerd-0ac3742360b6694218fe22ab04659af4c326f484b5c7eb940587739dffea7bbe.scope. May 16 00:41:46.296372 env[1215]: time="2025-05-16T00:41:46.296323507Z" level=info msg="StartContainer for \"0ac3742360b6694218fe22ab04659af4c326f484b5c7eb940587739dffea7bbe\" returns successfully" May 16 00:41:46.301096 systemd[1]: cri-containerd-0ac3742360b6694218fe22ab04659af4c326f484b5c7eb940587739dffea7bbe.scope: Deactivated successfully. May 16 00:41:46.454299 env[1215]: time="2025-05-16T00:41:46.454167067Z" level=info msg="shim disconnected" id=0ac3742360b6694218fe22ab04659af4c326f484b5c7eb940587739dffea7bbe May 16 00:41:46.454299 env[1215]: time="2025-05-16T00:41:46.454219107Z" level=warning msg="cleaning up after shim disconnected" id=0ac3742360b6694218fe22ab04659af4c326f484b5c7eb940587739dffea7bbe namespace=k8s.io May 16 00:41:46.454299 env[1215]: time="2025-05-16T00:41:46.454232747Z" level=info msg="cleaning up dead shim" May 16 00:41:46.461879 env[1215]: time="2025-05-16T00:41:46.461821067Z" level=warning msg="cleanup warnings time=\"2025-05-16T00:41:46Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1761 runtime=io.containerd.runc.v2\n" May 16 00:41:46.611312 kubelet[1418]: E0516 00:41:46.611231 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:46.787619 kubelet[1418]: E0516 00:41:46.787592 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:46.788348 kubelet[1418]: E0516 00:41:46.788330 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:46.789243 env[1215]: time="2025-05-16T00:41:46.789207907Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 16 00:41:47.611928 kubelet[1418]: E0516 00:41:47.611876 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:48.080446 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1863192609.mount: Deactivated successfully. May 16 00:41:48.612136 kubelet[1418]: E0516 00:41:48.612096 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:48.748475 env[1215]: time="2025-05-16T00:41:48.748433027Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:48.751026 env[1215]: time="2025-05-16T00:41:48.750998507Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:48.752788 env[1215]: time="2025-05-16T00:41:48.752751067Z" level=info msg="ImageUpdate event &ImageUpdate{Name:docker.io/flannel/flannel:v0.22.0,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:48.755289 env[1215]: time="2025-05-16T00:41:48.755243147Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:48.757943 env[1215]: time="2025-05-16T00:41:48.757904987Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 16 00:41:48.763751 env[1215]: time="2025-05-16T00:41:48.763707987Z" level=info msg="CreateContainer within sandbox \"13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 16 00:41:48.772439 env[1215]: time="2025-05-16T00:41:48.772404547Z" level=info msg="CreateContainer within sandbox \"13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f\"" May 16 00:41:48.772983 env[1215]: time="2025-05-16T00:41:48.772958107Z" level=info msg="StartContainer for \"466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f\"" May 16 00:41:48.790015 systemd[1]: Started cri-containerd-466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f.scope. May 16 00:41:48.835769 env[1215]: time="2025-05-16T00:41:48.835716667Z" level=info msg="StartContainer for \"466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f\" returns successfully" May 16 00:41:48.838722 systemd[1]: cri-containerd-466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f.scope: Deactivated successfully. May 16 00:41:48.850167 kubelet[1418]: I0516 00:41:48.849977 1418 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 16 00:41:48.961978 env[1215]: time="2025-05-16T00:41:48.961559387Z" level=info msg="shim disconnected" id=466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f May 16 00:41:48.961978 env[1215]: time="2025-05-16T00:41:48.961611027Z" level=warning msg="cleaning up after shim disconnected" id=466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f namespace=k8s.io May 16 00:41:48.961978 env[1215]: time="2025-05-16T00:41:48.961624387Z" level=info msg="cleaning up dead shim" May 16 00:41:48.969057 env[1215]: time="2025-05-16T00:41:48.969009387Z" level=warning msg="cleanup warnings time=\"2025-05-16T00:41:48Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1819 runtime=io.containerd.runc.v2\n" May 16 00:41:49.080489 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-466ce48286e164f429c4d7adcc4586d0588c459da4a40e182b47e0a301e85d2f-rootfs.mount: Deactivated successfully. May 16 00:41:49.612515 kubelet[1418]: E0516 00:41:49.612470 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:49.795250 kubelet[1418]: E0516 00:41:49.795221 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:49.797118 env[1215]: time="2025-05-16T00:41:49.797081067Z" level=info msg="CreateContainer within sandbox \"13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 16 00:41:49.809462 env[1215]: time="2025-05-16T00:41:49.809423627Z" level=info msg="CreateContainer within sandbox \"13a8817ffeb92e2d728ff2dd144bcfc994de9785fb1225690b84dd4c922b5eda\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"3fde34a34f98adf1e0483012c93394615177f94f85f661e801845beb59b78a44\"" May 16 00:41:49.810024 env[1215]: time="2025-05-16T00:41:49.809993267Z" level=info msg="StartContainer for \"3fde34a34f98adf1e0483012c93394615177f94f85f661e801845beb59b78a44\"" May 16 00:41:49.826590 systemd[1]: Started cri-containerd-3fde34a34f98adf1e0483012c93394615177f94f85f661e801845beb59b78a44.scope. May 16 00:41:49.890766 env[1215]: time="2025-05-16T00:41:49.887105667Z" level=info msg="StartContainer for \"3fde34a34f98adf1e0483012c93394615177f94f85f661e801845beb59b78a44\" returns successfully" May 16 00:41:50.613449 kubelet[1418]: E0516 00:41:50.613402 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:50.798838 kubelet[1418]: E0516 00:41:50.798735 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:50.958975 systemd-networkd[1035]: flannel.1: Link UP May 16 00:41:50.958979 systemd-networkd[1035]: flannel.1: Gained carrier May 16 00:41:51.614393 kubelet[1418]: E0516 00:41:51.614351 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:51.799784 kubelet[1418]: E0516 00:41:51.799750 1418 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 16 00:41:52.016443 systemd-networkd[1035]: flannel.1: Gained IPv6LL May 16 00:41:52.615165 kubelet[1418]: E0516 00:41:52.615136 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:53.013154 kubelet[1418]: I0516 00:41:53.013090 1418 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-kgrfn" podStartSLOduration=6.844673227 podStartE2EDuration="12.013070707s" podCreationTimestamp="2025-05-16 00:41:41 +0000 UTC" firstStartedPulling="2025-05-16 00:41:43.590948627 +0000 UTC m=+3.806694881" lastFinishedPulling="2025-05-16 00:41:48.759346107 +0000 UTC m=+8.975092361" observedRunningTime="2025-05-16 00:41:50.809640547 +0000 UTC m=+11.025386801" watchObservedRunningTime="2025-05-16 00:41:53.013070707 +0000 UTC m=+13.228816961" May 16 00:41:53.019130 systemd[1]: Created slice kubepods-besteffort-pod611926dc_9c5e_484b_b25b_ec31a75ed07a.slice. May 16 00:41:53.090366 kubelet[1418]: I0516 00:41:53.090292 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mpr97\" (UniqueName: \"kubernetes.io/projected/611926dc-9c5e-484b-b25b-ec31a75ed07a-kube-api-access-mpr97\") pod \"nginx-deployment-8587fbcb89-2555t\" (UID: \"611926dc-9c5e-484b-b25b-ec31a75ed07a\") " pod="default/nginx-deployment-8587fbcb89-2555t" May 16 00:41:53.322555 env[1215]: time="2025-05-16T00:41:53.322452787Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-2555t,Uid:611926dc-9c5e-484b-b25b-ec31a75ed07a,Namespace:default,Attempt:0,}" May 16 00:41:53.349937 systemd-networkd[1035]: cni0: Link UP May 16 00:41:53.349942 systemd-networkd[1035]: cni0: Gained carrier May 16 00:41:53.350266 systemd-networkd[1035]: cni0: Lost carrier May 16 00:41:53.358285 systemd-networkd[1035]: vethcc635ed8: Link UP May 16 00:41:53.358612 kernel: cni0: port 1(vethcc635ed8) entered blocking state May 16 00:41:53.358663 kernel: cni0: port 1(vethcc635ed8) entered disabled state May 16 00:41:53.358685 kernel: device vethcc635ed8 entered promiscuous mode May 16 00:41:53.358704 kernel: cni0: port 1(vethcc635ed8) entered blocking state May 16 00:41:53.359713 kernel: cni0: port 1(vethcc635ed8) entered forwarding state May 16 00:41:53.360352 kernel: cni0: port 1(vethcc635ed8) entered disabled state May 16 00:41:53.372769 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethcc635ed8: link becomes ready May 16 00:41:53.372841 kernel: cni0: port 1(vethcc635ed8) entered blocking state May 16 00:41:53.372860 kernel: cni0: port 1(vethcc635ed8) entered forwarding state May 16 00:41:53.373318 systemd-networkd[1035]: vethcc635ed8: Gained carrier May 16 00:41:53.373499 systemd-networkd[1035]: cni0: Gained carrier May 16 00:41:53.375238 env[1215]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} May 16 00:41:53.375238 env[1215]: delegateAdd: netconf sent to delegate plugin: May 16 00:41:53.392247 env[1215]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-16T00:41:53.392182187Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:41:53.392387 env[1215]: time="2025-05-16T00:41:53.392258587Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:41:53.392387 env[1215]: time="2025-05-16T00:41:53.392329467Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:41:53.392554 env[1215]: time="2025-05-16T00:41:53.392514707Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/77aee28b6ddcb52e1429ef80b6baa30bef70fb96a72c8ec5d6add5e28b7a315e pid=2002 runtime=io.containerd.runc.v2 May 16 00:41:53.407052 systemd[1]: Started cri-containerd-77aee28b6ddcb52e1429ef80b6baa30bef70fb96a72c8ec5d6add5e28b7a315e.scope. May 16 00:41:53.425843 systemd-resolved[1157]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 16 00:41:53.441933 env[1215]: time="2025-05-16T00:41:53.441897107Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-2555t,Uid:611926dc-9c5e-484b-b25b-ec31a75ed07a,Namespace:default,Attempt:0,} returns sandbox id \"77aee28b6ddcb52e1429ef80b6baa30bef70fb96a72c8ec5d6add5e28b7a315e\"" May 16 00:41:53.443105 env[1215]: time="2025-05-16T00:41:53.443079227Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 16 00:41:53.616147 kubelet[1418]: E0516 00:41:53.616055 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:54.512398 systemd-networkd[1035]: cni0: Gained IPv6LL May 16 00:41:54.616741 kubelet[1418]: E0516 00:41:54.616688 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:54.640400 systemd-networkd[1035]: vethcc635ed8: Gained IPv6LL May 16 00:41:55.336797 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount771281708.mount: Deactivated successfully. May 16 00:41:55.617793 kubelet[1418]: E0516 00:41:55.617658 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:56.547215 env[1215]: time="2025-05-16T00:41:56.547162067Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:56.548355 env[1215]: time="2025-05-16T00:41:56.548330587Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:56.550296 env[1215]: time="2025-05-16T00:41:56.550256467Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:56.552190 env[1215]: time="2025-05-16T00:41:56.552159347Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:41:56.552898 env[1215]: time="2025-05-16T00:41:56.552872027Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 16 00:41:56.555085 env[1215]: time="2025-05-16T00:41:56.555056107Z" level=info msg="CreateContainer within sandbox \"77aee28b6ddcb52e1429ef80b6baa30bef70fb96a72c8ec5d6add5e28b7a315e\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 16 00:41:56.564791 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3713898487.mount: Deactivated successfully. May 16 00:41:56.568420 env[1215]: time="2025-05-16T00:41:56.568385267Z" level=info msg="CreateContainer within sandbox \"77aee28b6ddcb52e1429ef80b6baa30bef70fb96a72c8ec5d6add5e28b7a315e\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"61d3d9025a28967ed9fa317435c7aa68612f4299670d7dfcd2d8eda239c5ef42\"" May 16 00:41:56.568897 env[1215]: time="2025-05-16T00:41:56.568849707Z" level=info msg="StartContainer for \"61d3d9025a28967ed9fa317435c7aa68612f4299670d7dfcd2d8eda239c5ef42\"" May 16 00:41:56.584932 systemd[1]: Started cri-containerd-61d3d9025a28967ed9fa317435c7aa68612f4299670d7dfcd2d8eda239c5ef42.scope. May 16 00:41:56.618814 kubelet[1418]: E0516 00:41:56.618776 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:56.623216 env[1215]: time="2025-05-16T00:41:56.623173027Z" level=info msg="StartContainer for \"61d3d9025a28967ed9fa317435c7aa68612f4299670d7dfcd2d8eda239c5ef42\" returns successfully" May 16 00:41:57.619400 kubelet[1418]: E0516 00:41:57.619347 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:58.619896 kubelet[1418]: E0516 00:41:58.619852 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:59.621024 kubelet[1418]: E0516 00:41:59.620952 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:41:59.742826 kubelet[1418]: I0516 00:41:59.742705 1418 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-2555t" podStartSLOduration=3.631556587 podStartE2EDuration="6.742686067s" podCreationTimestamp="2025-05-16 00:41:53 +0000 UTC" firstStartedPulling="2025-05-16 00:41:53.442871427 +0000 UTC m=+13.658617681" lastFinishedPulling="2025-05-16 00:41:56.554000907 +0000 UTC m=+16.769747161" observedRunningTime="2025-05-16 00:41:56.815961787 +0000 UTC m=+17.031708041" watchObservedRunningTime="2025-05-16 00:41:59.742686067 +0000 UTC m=+19.958432321" May 16 00:41:59.747173 systemd[1]: Created slice kubepods-besteffort-podd98a1d5f_cbd8_439a_955c_b584da9e026a.slice. May 16 00:41:59.832191 kubelet[1418]: I0516 00:41:59.832054 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/d98a1d5f-cbd8-439a-955c-b584da9e026a-data\") pod \"nfs-server-provisioner-0\" (UID: \"d98a1d5f-cbd8-439a-955c-b584da9e026a\") " pod="default/nfs-server-provisioner-0" May 16 00:41:59.832191 kubelet[1418]: I0516 00:41:59.832098 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bw97m\" (UniqueName: \"kubernetes.io/projected/d98a1d5f-cbd8-439a-955c-b584da9e026a-kube-api-access-bw97m\") pod \"nfs-server-provisioner-0\" (UID: \"d98a1d5f-cbd8-439a-955c-b584da9e026a\") " pod="default/nfs-server-provisioner-0" May 16 00:42:00.050404 env[1215]: time="2025-05-16T00:42:00.050334907Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d98a1d5f-cbd8-439a-955c-b584da9e026a,Namespace:default,Attempt:0,}" May 16 00:42:00.076340 systemd-networkd[1035]: veth21794596: Link UP May 16 00:42:00.077790 kernel: cni0: port 2(veth21794596) entered blocking state May 16 00:42:00.077857 kernel: cni0: port 2(veth21794596) entered disabled state May 16 00:42:00.078359 kernel: device veth21794596 entered promiscuous mode May 16 00:42:00.083552 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 16 00:42:00.083625 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth21794596: link becomes ready May 16 00:42:00.083645 kernel: cni0: port 2(veth21794596) entered blocking state May 16 00:42:00.083662 kernel: cni0: port 2(veth21794596) entered forwarding state May 16 00:42:00.085231 systemd-networkd[1035]: veth21794596: Gained carrier May 16 00:42:00.086393 env[1215]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014928), "name":"cbr0", "type":"bridge"} May 16 00:42:00.086393 env[1215]: delegateAdd: netconf sent to delegate plugin: May 16 00:42:00.101299 env[1215]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-16T00:42:00.101198707Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:42:00.101299 env[1215]: time="2025-05-16T00:42:00.101251147Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:42:00.101472 env[1215]: time="2025-05-16T00:42:00.101262467Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:42:00.102522 env[1215]: time="2025-05-16T00:42:00.101731387Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/a5d95a03798dfe5111c9735e6a7825e87e0e915d8e9b1a9d3eb98289501a6be0 pid=2172 runtime=io.containerd.runc.v2 May 16 00:42:00.114980 systemd[1]: Started cri-containerd-a5d95a03798dfe5111c9735e6a7825e87e0e915d8e9b1a9d3eb98289501a6be0.scope. May 16 00:42:00.135051 systemd-resolved[1157]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 16 00:42:00.151770 env[1215]: time="2025-05-16T00:42:00.151714187Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d98a1d5f-cbd8-439a-955c-b584da9e026a,Namespace:default,Attempt:0,} returns sandbox id \"a5d95a03798dfe5111c9735e6a7825e87e0e915d8e9b1a9d3eb98289501a6be0\"" May 16 00:42:00.153241 env[1215]: time="2025-05-16T00:42:00.153177387Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 16 00:42:00.621120 kubelet[1418]: E0516 00:42:00.621051 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:01.361174 systemd-networkd[1035]: veth21794596: Gained IPv6LL May 16 00:42:01.608160 kubelet[1418]: E0516 00:42:01.608112 1418 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:01.621887 kubelet[1418]: E0516 00:42:01.621569 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:02.196009 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3174018379.mount: Deactivated successfully. May 16 00:42:02.622375 kubelet[1418]: E0516 00:42:02.622325 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:03.622792 kubelet[1418]: E0516 00:42:03.622749 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:03.949441 env[1215]: time="2025-05-16T00:42:03.949119067Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:03.951346 env[1215]: time="2025-05-16T00:42:03.951306947Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:03.953263 env[1215]: time="2025-05-16T00:42:03.953226667Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:03.954753 env[1215]: time="2025-05-16T00:42:03.954722147Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:03.956203 env[1215]: time="2025-05-16T00:42:03.956173747Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 16 00:42:03.958115 env[1215]: time="2025-05-16T00:42:03.958083987Z" level=info msg="CreateContainer within sandbox \"a5d95a03798dfe5111c9735e6a7825e87e0e915d8e9b1a9d3eb98289501a6be0\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 16 00:42:03.967291 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4055529957.mount: Deactivated successfully. May 16 00:42:03.970850 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount60843547.mount: Deactivated successfully. May 16 00:42:03.972420 env[1215]: time="2025-05-16T00:42:03.972373307Z" level=info msg="CreateContainer within sandbox \"a5d95a03798dfe5111c9735e6a7825e87e0e915d8e9b1a9d3eb98289501a6be0\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"75f1a309f53d369e0c72746a199463d8ec6ff72814058bba01599604a89a8f1b\"" May 16 00:42:03.973010 env[1215]: time="2025-05-16T00:42:03.972976787Z" level=info msg="StartContainer for \"75f1a309f53d369e0c72746a199463d8ec6ff72814058bba01599604a89a8f1b\"" May 16 00:42:03.989675 systemd[1]: Started cri-containerd-75f1a309f53d369e0c72746a199463d8ec6ff72814058bba01599604a89a8f1b.scope. May 16 00:42:04.039277 env[1215]: time="2025-05-16T00:42:04.039217747Z" level=info msg="StartContainer for \"75f1a309f53d369e0c72746a199463d8ec6ff72814058bba01599604a89a8f1b\" returns successfully" May 16 00:42:04.623743 kubelet[1418]: E0516 00:42:04.623703 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:05.625159 kubelet[1418]: E0516 00:42:05.625075 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:06.625351 kubelet[1418]: E0516 00:42:06.625295 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:07.625685 kubelet[1418]: E0516 00:42:07.625650 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:08.627116 kubelet[1418]: E0516 00:42:08.627080 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:09.628371 kubelet[1418]: E0516 00:42:09.628333 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:10.629681 kubelet[1418]: E0516 00:42:10.629637 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:11.630163 kubelet[1418]: E0516 00:42:11.630125 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:12.631245 kubelet[1418]: E0516 00:42:12.631203 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:13.632383 kubelet[1418]: E0516 00:42:13.632334 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:13.866303 kubelet[1418]: I0516 00:42:13.866182 1418 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.062175745 podStartE2EDuration="14.866090825s" podCreationTimestamp="2025-05-16 00:41:59 +0000 UTC" firstStartedPulling="2025-05-16 00:42:00.152884707 +0000 UTC m=+20.368630961" lastFinishedPulling="2025-05-16 00:42:03.956799787 +0000 UTC m=+24.172546041" observedRunningTime="2025-05-16 00:42:04.837844027 +0000 UTC m=+25.053590241" watchObservedRunningTime="2025-05-16 00:42:13.866090825 +0000 UTC m=+34.081837079" May 16 00:42:13.875653 systemd[1]: Created slice kubepods-besteffort-podb79d726f_2770_4376_ac73_c9eac9b56e5a.slice. May 16 00:42:13.919827 kubelet[1418]: I0516 00:42:13.919708 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-ec870528-4807-41a1-bfdd-937fa56df7f8\" (UniqueName: \"kubernetes.io/nfs/b79d726f-2770-4376-ac73-c9eac9b56e5a-pvc-ec870528-4807-41a1-bfdd-937fa56df7f8\") pod \"test-pod-1\" (UID: \"b79d726f-2770-4376-ac73-c9eac9b56e5a\") " pod="default/test-pod-1" May 16 00:42:13.919827 kubelet[1418]: I0516 00:42:13.919757 1418 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qdm6f\" (UniqueName: \"kubernetes.io/projected/b79d726f-2770-4376-ac73-c9eac9b56e5a-kube-api-access-qdm6f\") pod \"test-pod-1\" (UID: \"b79d726f-2770-4376-ac73-c9eac9b56e5a\") " pod="default/test-pod-1" May 16 00:42:14.061003 kernel: FS-Cache: Loaded May 16 00:42:14.091782 kernel: RPC: Registered named UNIX socket transport module. May 16 00:42:14.091895 kernel: RPC: Registered udp transport module. May 16 00:42:14.091922 kernel: RPC: Registered tcp transport module. May 16 00:42:14.093046 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 16 00:42:14.145308 kernel: FS-Cache: Netfs 'nfs' registered for caching May 16 00:42:14.276367 kernel: NFS: Registering the id_resolver key type May 16 00:42:14.276507 kernel: Key type id_resolver registered May 16 00:42:14.276532 kernel: Key type id_legacy registered May 16 00:42:14.303027 nfsidmap[2351]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 16 00:42:14.308313 nfsidmap[2354]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 16 00:42:14.479758 env[1215]: time="2025-05-16T00:42:14.479705076Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b79d726f-2770-4376-ac73-c9eac9b56e5a,Namespace:default,Attempt:0,}" May 16 00:42:14.497767 systemd-networkd[1035]: veth59f50a4c: Link UP May 16 00:42:14.499284 kernel: cni0: port 3(veth59f50a4c) entered blocking state May 16 00:42:14.499353 kernel: cni0: port 3(veth59f50a4c) entered disabled state May 16 00:42:14.499376 kernel: device veth59f50a4c entered promiscuous mode May 16 00:42:14.504410 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 16 00:42:14.504483 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth59f50a4c: link becomes ready May 16 00:42:14.504505 kernel: cni0: port 3(veth59f50a4c) entered blocking state May 16 00:42:14.504521 kernel: cni0: port 3(veth59f50a4c) entered forwarding state May 16 00:42:14.504945 systemd-networkd[1035]: veth59f50a4c: Gained carrier May 16 00:42:14.509069 env[1215]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014928), "name":"cbr0", "type":"bridge"} May 16 00:42:14.509069 env[1215]: delegateAdd: netconf sent to delegate plugin: May 16 00:42:14.521137 env[1215]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-16T00:42:14.521066498Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:42:14.521137 env[1215]: time="2025-05-16T00:42:14.521114338Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:42:14.521307 env[1215]: time="2025-05-16T00:42:14.521264457Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:42:14.521562 env[1215]: time="2025-05-16T00:42:14.521518777Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/aa7fb54f037944e35d9a7f8e7446bfcbe519e4947a9d1dc94a3696cc36051a79 pid=2407 runtime=io.containerd.runc.v2 May 16 00:42:14.532225 systemd[1]: Started cri-containerd-aa7fb54f037944e35d9a7f8e7446bfcbe519e4947a9d1dc94a3696cc36051a79.scope. May 16 00:42:14.555519 systemd-resolved[1157]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 16 00:42:14.573581 env[1215]: time="2025-05-16T00:42:14.573532454Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b79d726f-2770-4376-ac73-c9eac9b56e5a,Namespace:default,Attempt:0,} returns sandbox id \"aa7fb54f037944e35d9a7f8e7446bfcbe519e4947a9d1dc94a3696cc36051a79\"" May 16 00:42:14.575036 env[1215]: time="2025-05-16T00:42:14.575002611Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 16 00:42:14.635715 kubelet[1418]: E0516 00:42:14.635643 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:15.040399 env[1215]: time="2025-05-16T00:42:15.040360397Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:15.041605 env[1215]: time="2025-05-16T00:42:15.041576114Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:15.043192 env[1215]: time="2025-05-16T00:42:15.043171111Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:15.044916 env[1215]: time="2025-05-16T00:42:15.044887587Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" May 16 00:42:15.045693 env[1215]: time="2025-05-16T00:42:15.045662785Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 16 00:42:15.048013 env[1215]: time="2025-05-16T00:42:15.047973700Z" level=info msg="CreateContainer within sandbox \"aa7fb54f037944e35d9a7f8e7446bfcbe519e4947a9d1dc94a3696cc36051a79\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 16 00:42:15.058145 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4098603308.mount: Deactivated successfully. May 16 00:42:15.062183 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3274582219.mount: Deactivated successfully. May 16 00:42:15.071399 env[1215]: time="2025-05-16T00:42:15.071346209Z" level=info msg="CreateContainer within sandbox \"aa7fb54f037944e35d9a7f8e7446bfcbe519e4947a9d1dc94a3696cc36051a79\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"7630beb18898b8d3d3a010bba19174223568634d28d7d0831852da4327716320\"" May 16 00:42:15.071826 env[1215]: time="2025-05-16T00:42:15.071801288Z" level=info msg="StartContainer for \"7630beb18898b8d3d3a010bba19174223568634d28d7d0831852da4327716320\"" May 16 00:42:15.086261 systemd[1]: Started cri-containerd-7630beb18898b8d3d3a010bba19174223568634d28d7d0831852da4327716320.scope. May 16 00:42:15.117887 env[1215]: time="2025-05-16T00:42:15.117818066Z" level=info msg="StartContainer for \"7630beb18898b8d3d3a010bba19174223568634d28d7d0831852da4327716320\" returns successfully" May 16 00:42:15.636175 kubelet[1418]: E0516 00:42:15.636113 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:15.849708 kubelet[1418]: I0516 00:42:15.849626 1418 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=16.377707115 podStartE2EDuration="16.849610487s" podCreationTimestamp="2025-05-16 00:41:59 +0000 UTC" firstStartedPulling="2025-05-16 00:42:14.574759171 +0000 UTC m=+34.790505425" lastFinishedPulling="2025-05-16 00:42:15.046662583 +0000 UTC m=+35.262408797" observedRunningTime="2025-05-16 00:42:15.849477248 +0000 UTC m=+36.065223502" watchObservedRunningTime="2025-05-16 00:42:15.849610487 +0000 UTC m=+36.065356741" May 16 00:42:16.272398 systemd-networkd[1035]: veth59f50a4c: Gained IPv6LL May 16 00:42:16.637310 kubelet[1418]: E0516 00:42:16.637189 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:17.532778 update_engine[1208]: I0516 00:42:17.532707 1208 update_attempter.cc:509] Updating boot flags... May 16 00:42:17.637652 kubelet[1418]: E0516 00:42:17.637615 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:18.638674 kubelet[1418]: E0516 00:42:18.638626 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:19.639577 kubelet[1418]: E0516 00:42:19.639516 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:20.640615 kubelet[1418]: E0516 00:42:20.640545 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:21.607516 kubelet[1418]: E0516 00:42:21.607455 1418 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:21.640937 kubelet[1418]: E0516 00:42:21.640904 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:42:22.641204 kubelet[1418]: E0516 00:42:22.641161 1418 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"