Oct 29 00:45:30.699526 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 00:45:30.699549 kernel: Linux version 5.15.192-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 Tue Oct 28 23:18:12 -00 2025 Oct 29 00:45:30.699563 kernel: efi: EFI v2.70 by EDK II Oct 29 00:45:30.699570 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Oct 29 00:45:30.699575 kernel: random: crng init done Oct 29 00:45:30.699581 kernel: ACPI: Early table checksum verification disabled Oct 29 00:45:30.699588 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Oct 29 00:45:30.699595 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 00:45:30.699600 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699606 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699611 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699616 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699622 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699627 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699635 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699641 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699647 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 00:45:30.699653 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 00:45:30.699659 kernel: NUMA: Failed to initialise from firmware Oct 29 00:45:30.699665 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 00:45:30.699671 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Oct 29 00:45:30.699676 kernel: Zone ranges: Oct 29 00:45:30.699682 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 00:45:30.699689 kernel: DMA32 empty Oct 29 00:45:30.699694 kernel: Normal empty Oct 29 00:45:30.699700 kernel: Movable zone start for each node Oct 29 00:45:30.699706 kernel: Early memory node ranges Oct 29 00:45:30.699711 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Oct 29 00:45:30.699717 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Oct 29 00:45:30.699723 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Oct 29 00:45:30.699728 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Oct 29 00:45:30.699734 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Oct 29 00:45:30.699740 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Oct 29 00:45:30.699745 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Oct 29 00:45:30.699751 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 00:45:30.699758 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 29 00:45:30.699764 kernel: psci: probing for conduit method from ACPI. Oct 29 00:45:30.699770 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 00:45:30.699776 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 00:45:30.699794 kernel: psci: Trusted OS migration not required Oct 29 00:45:30.699803 kernel: psci: SMC Calling Convention v1.1 Oct 29 00:45:30.699810 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 29 00:45:30.699817 kernel: ACPI: SRAT not present Oct 29 00:45:30.699823 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Oct 29 00:45:30.699829 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Oct 29 00:45:30.699836 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 00:45:30.699842 kernel: Detected PIPT I-cache on CPU0 Oct 29 00:45:30.699848 kernel: CPU features: detected: GIC system register CPU interface Oct 29 00:45:30.699854 kernel: CPU features: detected: Hardware dirty bit management Oct 29 00:45:30.699860 kernel: CPU features: detected: Spectre-v4 Oct 29 00:45:30.699866 kernel: CPU features: detected: Spectre-BHB Oct 29 00:45:30.699873 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 00:45:30.699879 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 00:45:30.699885 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 00:45:30.699891 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 29 00:45:30.699898 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 00:45:30.699904 kernel: Policy zone: DMA Oct 29 00:45:30.699911 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=ddcdcc5923a51dfb24bee27c235aa754769d72fd417f60397f96d58c38c7a3e3 Oct 29 00:45:30.699917 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 29 00:45:30.699923 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 00:45:30.699929 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 00:45:30.699936 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 00:45:30.699944 kernel: Memory: 2457340K/2572288K available (9792K kernel code, 2094K rwdata, 7592K rodata, 36416K init, 777K bss, 114948K reserved, 0K cma-reserved) Oct 29 00:45:30.699950 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 00:45:30.699956 kernel: trace event string verifier disabled Oct 29 00:45:30.699962 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 00:45:30.699969 kernel: rcu: RCU event tracing is enabled. Oct 29 00:45:30.699975 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 00:45:30.699981 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 00:45:30.699988 kernel: Tracing variant of Tasks RCU enabled. Oct 29 00:45:30.699994 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 00:45:30.700000 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 00:45:30.700006 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 00:45:30.700014 kernel: GICv3: 256 SPIs implemented Oct 29 00:45:30.700020 kernel: GICv3: 0 Extended SPIs implemented Oct 29 00:45:30.700026 kernel: GICv3: Distributor has no Range Selector support Oct 29 00:45:30.700032 kernel: Root IRQ handler: gic_handle_irq Oct 29 00:45:30.700038 kernel: GICv3: 16 PPIs implemented Oct 29 00:45:30.700045 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 00:45:30.700051 kernel: ACPI: SRAT not present Oct 29 00:45:30.700057 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 00:45:30.700063 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 00:45:30.700069 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 00:45:30.700075 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 00:45:30.700081 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 00:45:30.700088 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 00:45:30.700095 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 00:45:30.700101 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 00:45:30.700108 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 00:45:30.700114 kernel: arm-pv: using stolen time PV Oct 29 00:45:30.700120 kernel: Console: colour dummy device 80x25 Oct 29 00:45:30.700127 kernel: ACPI: Core revision 20210730 Oct 29 00:45:30.700133 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 00:45:30.700140 kernel: pid_max: default: 32768 minimum: 301 Oct 29 00:45:30.700146 kernel: LSM: Security Framework initializing Oct 29 00:45:30.700153 kernel: SELinux: Initializing. Oct 29 00:45:30.700159 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 00:45:30.700166 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 00:45:30.700172 kernel: rcu: Hierarchical SRCU implementation. Oct 29 00:45:30.700178 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 00:45:30.700184 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 00:45:30.700190 kernel: Remapping and enabling EFI services. Oct 29 00:45:30.700196 kernel: smp: Bringing up secondary CPUs ... Oct 29 00:45:30.700202 kernel: Detected PIPT I-cache on CPU1 Oct 29 00:45:30.700210 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 00:45:30.700216 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 00:45:30.700222 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 00:45:30.700229 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 00:45:30.700242 kernel: Detected PIPT I-cache on CPU2 Oct 29 00:45:30.700251 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 00:45:30.700264 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 00:45:30.700272 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 00:45:30.700279 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 00:45:30.700285 kernel: Detected PIPT I-cache on CPU3 Oct 29 00:45:30.700292 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 00:45:30.700299 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 00:45:30.700305 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 00:45:30.700312 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 00:45:30.700323 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 00:45:30.700331 kernel: SMP: Total of 4 processors activated. Oct 29 00:45:30.700337 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 00:45:30.700344 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 00:45:30.700351 kernel: CPU features: detected: Common not Private translations Oct 29 00:45:30.700357 kernel: CPU features: detected: CRC32 instructions Oct 29 00:45:30.700364 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 29 00:45:30.700370 kernel: CPU features: detected: LSE atomic instructions Oct 29 00:45:30.700378 kernel: CPU features: detected: Privileged Access Never Oct 29 00:45:30.700385 kernel: CPU features: detected: RAS Extension Support Oct 29 00:45:30.700391 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 00:45:30.700398 kernel: CPU: All CPU(s) started at EL1 Oct 29 00:45:30.700404 kernel: alternatives: patching kernel code Oct 29 00:45:30.700412 kernel: devtmpfs: initialized Oct 29 00:45:30.700419 kernel: KASLR enabled Oct 29 00:45:30.700426 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 00:45:30.700432 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 00:45:30.700439 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 00:45:30.700446 kernel: SMBIOS 3.0.0 present. Oct 29 00:45:30.700453 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Oct 29 00:45:30.700460 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 29 00:45:30.700466 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 00:45:30.700474 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 00:45:30.700481 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 00:45:30.700488 kernel: audit: initializing netlink subsys (disabled) Oct 29 00:45:30.700494 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Oct 29 00:45:30.700501 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 00:45:30.700507 kernel: cpuidle: using governor menu Oct 29 00:45:30.700514 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 00:45:30.700520 kernel: ASID allocator initialised with 32768 entries Oct 29 00:45:30.700527 kernel: ACPI: bus type PCI registered Oct 29 00:45:30.700534 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 00:45:30.700541 kernel: Serial: AMBA PL011 UART driver Oct 29 00:45:30.700547 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 00:45:30.700554 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 00:45:30.700560 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 00:45:30.700567 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 00:45:30.700574 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 00:45:30.700581 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 29 00:45:30.700587 kernel: ACPI: Added _OSI(Module Device) Oct 29 00:45:30.700595 kernel: ACPI: Added _OSI(Processor Device) Oct 29 00:45:30.700601 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 00:45:30.700608 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 00:45:30.700614 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 00:45:30.700621 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 00:45:30.700627 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 00:45:30.700634 kernel: ACPI: Interpreter enabled Oct 29 00:45:30.700641 kernel: ACPI: Using GIC for interrupt routing Oct 29 00:45:30.700647 kernel: ACPI: MCFG table detected, 1 entries Oct 29 00:45:30.700656 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 00:45:30.700662 kernel: printk: console [ttyAMA0] enabled Oct 29 00:45:30.700669 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 00:45:30.700808 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 00:45:30.700876 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 00:45:30.700937 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 00:45:30.700995 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 00:45:30.701057 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 00:45:30.701066 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 00:45:30.701072 kernel: PCI host bridge to bus 0000:00 Oct 29 00:45:30.701150 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 00:45:30.701206 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 00:45:30.701267 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 00:45:30.701323 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 00:45:30.701401 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 00:45:30.701477 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 00:45:30.701541 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 00:45:30.701607 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 00:45:30.701670 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 00:45:30.701731 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 00:45:30.701802 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 00:45:30.701880 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 00:45:30.701937 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 00:45:30.701993 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 00:45:30.702049 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 00:45:30.702057 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 29 00:45:30.702064 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 29 00:45:30.702071 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 29 00:45:30.702079 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 29 00:45:30.702085 kernel: iommu: Default domain type: Translated Oct 29 00:45:30.702092 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 29 00:45:30.702099 kernel: vgaarb: loaded Oct 29 00:45:30.702105 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 29 00:45:30.702112 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 29 00:45:30.702119 kernel: PTP clock support registered Oct 29 00:45:30.702126 kernel: Registered efivars operations Oct 29 00:45:30.702132 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 00:45:30.702139 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 00:45:30.702148 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 00:45:30.702154 kernel: pnp: PnP ACPI init Oct 29 00:45:30.702249 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 00:45:30.702260 kernel: pnp: PnP ACPI: found 1 devices Oct 29 00:45:30.702267 kernel: NET: Registered PF_INET protocol family Oct 29 00:45:30.702274 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 00:45:30.702281 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 00:45:30.702287 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 29 00:45:30.702296 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 00:45:30.702303 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 00:45:30.702310 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 00:45:30.702316 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 00:45:30.702323 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 00:45:30.702330 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 29 00:45:30.702336 kernel: PCI: CLS 0 bytes, default 64 Oct 29 00:45:30.702343 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 00:45:30.702350 kernel: kvm [1]: HYP mode not available Oct 29 00:45:30.702358 kernel: Initialise system trusted keyrings Oct 29 00:45:30.702365 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 00:45:30.702371 kernel: Key type asymmetric registered Oct 29 00:45:30.702378 kernel: Asymmetric key parser 'x509' registered Oct 29 00:45:30.702385 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 29 00:45:30.702391 kernel: io scheduler mq-deadline registered Oct 29 00:45:30.702398 kernel: io scheduler kyber registered Oct 29 00:45:30.702405 kernel: io scheduler bfq registered Oct 29 00:45:30.702411 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 00:45:30.702420 kernel: ACPI: button: Power Button [PWRB] Oct 29 00:45:30.702426 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 29 00:45:30.702490 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 00:45:30.702499 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 00:45:30.702506 kernel: thunder_xcv, ver 1.0 Oct 29 00:45:30.702512 kernel: thunder_bgx, ver 1.0 Oct 29 00:45:30.702519 kernel: nicpf, ver 1.0 Oct 29 00:45:30.702525 kernel: nicvf, ver 1.0 Oct 29 00:45:30.702594 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 00:45:30.702655 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T00:45:30 UTC (1761698730) Oct 29 00:45:30.702664 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 00:45:30.702670 kernel: NET: Registered PF_INET6 protocol family Oct 29 00:45:30.702677 kernel: Segment Routing with IPv6 Oct 29 00:45:30.702683 kernel: In-situ OAM (IOAM) with IPv6 Oct 29 00:45:30.702690 kernel: NET: Registered PF_PACKET protocol family Oct 29 00:45:30.702697 kernel: Key type dns_resolver registered Oct 29 00:45:30.703256 kernel: registered taskstats version 1 Oct 29 00:45:30.703302 kernel: Loading compiled-in X.509 certificates Oct 29 00:45:30.703310 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.192-flatcar: 365034a3270fb89208cc05b5e556df135e9c6322' Oct 29 00:45:30.703317 kernel: Key type .fscrypt registered Oct 29 00:45:30.703323 kernel: Key type fscrypt-provisioning registered Oct 29 00:45:30.703330 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 00:45:30.703337 kernel: ima: Allocated hash algorithm: sha1 Oct 29 00:45:30.703343 kernel: ima: No architecture policies found Oct 29 00:45:30.703350 kernel: clk: Disabling unused clocks Oct 29 00:45:30.703356 kernel: Freeing unused kernel memory: 36416K Oct 29 00:45:30.703364 kernel: Run /init as init process Oct 29 00:45:30.703371 kernel: with arguments: Oct 29 00:45:30.703379 kernel: /init Oct 29 00:45:30.703387 kernel: with environment: Oct 29 00:45:30.703393 kernel: HOME=/ Oct 29 00:45:30.703400 kernel: TERM=linux Oct 29 00:45:30.703407 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 00:45:30.703416 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) Oct 29 00:45:30.703427 systemd[1]: Detected virtualization kvm. Oct 29 00:45:30.703435 systemd[1]: Detected architecture arm64. Oct 29 00:45:30.703445 systemd[1]: Running in initrd. Oct 29 00:45:30.703452 systemd[1]: No hostname configured, using default hostname. Oct 29 00:45:30.703459 systemd[1]: Hostname set to . Oct 29 00:45:30.703467 systemd[1]: Initializing machine ID from VM UUID. Oct 29 00:45:30.703474 systemd[1]: Queued start job for default target initrd.target. Oct 29 00:45:30.703481 systemd[1]: Started systemd-ask-password-console.path. Oct 29 00:45:30.703490 systemd[1]: Reached target cryptsetup.target. Oct 29 00:45:30.703497 systemd[1]: Reached target paths.target. Oct 29 00:45:30.703504 systemd[1]: Reached target slices.target. Oct 29 00:45:30.703511 systemd[1]: Reached target swap.target. Oct 29 00:45:30.703518 systemd[1]: Reached target timers.target. Oct 29 00:45:30.703526 systemd[1]: Listening on iscsid.socket. Oct 29 00:45:30.703533 systemd[1]: Listening on iscsiuio.socket. Oct 29 00:45:30.703542 systemd[1]: Listening on systemd-journald-audit.socket. Oct 29 00:45:30.703550 systemd[1]: Listening on systemd-journald-dev-log.socket. Oct 29 00:45:30.703557 systemd[1]: Listening on systemd-journald.socket. Oct 29 00:45:30.703565 systemd[1]: Listening on systemd-networkd.socket. Oct 29 00:45:30.703573 systemd[1]: Listening on systemd-udevd-control.socket. Oct 29 00:45:30.703580 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 29 00:45:30.703587 systemd[1]: Reached target sockets.target. Oct 29 00:45:30.703594 systemd[1]: Starting kmod-static-nodes.service... Oct 29 00:45:30.703602 systemd[1]: Finished network-cleanup.service. Oct 29 00:45:30.703610 systemd[1]: Starting systemd-fsck-usr.service... Oct 29 00:45:30.703617 systemd[1]: Starting systemd-journald.service... Oct 29 00:45:30.703625 systemd[1]: Starting systemd-modules-load.service... Oct 29 00:45:30.703632 systemd[1]: Starting systemd-resolved.service... Oct 29 00:45:30.703639 systemd[1]: Starting systemd-vconsole-setup.service... Oct 29 00:45:30.703646 systemd[1]: Finished kmod-static-nodes.service. Oct 29 00:45:30.703653 systemd[1]: Finished systemd-fsck-usr.service. Oct 29 00:45:30.703662 kernel: audit: type=1130 audit(1761698730.699:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:30.703669 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Oct 29 00:45:30.703681 systemd-journald[290]: Journal started Oct 29 00:45:30.703735 systemd-journald[290]: Runtime Journal (/run/log/journal/1a02d2882adc4882b60c6c132b8102de) is 6.0M, max 48.7M, 42.6M free. Oct 29 00:45:30.699000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:30.708453 systemd[1]: Started systemd-journald.service. Oct 29 00:45:30.706000 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' Oct 29 00:45:30.708574 systemd-modules-load[291]: Inserted module 'overlay' Oct 29 00:45:30.714195 kernel: audit: type=1130 audit(1761698730.706: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' Oct 29 00:45:30.708742 systemd[1]: Finished systemd-vconsole-setup.service. Oct 29 00:45:30.712591 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Oct 29 00:45:30.722640 kernel: audit: type=1130 audit(1761698730.712:4): 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' Oct 29 00:45:30.722665 kernel: audit: type=1130 audit(1761698730.713:5): 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' Oct 29 00:45:30.712000 audit[1]: SERVICE_START 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' Oct 29 00:45:30.713000 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' Oct 29 00:45:30.714442 systemd[1]: Starting dracut-cmdline-ask.service... Oct 29 00:45:30.717801 systemd-resolved[292]: Positive Trust Anchors: Oct 29 00:45:30.727814 kernel: audit: type=1130 audit(1761698730.724:6): 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' Oct 29 00:45:30.724000 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' Oct 29 00:45:30.717808 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 00:45:30.717835 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 Oct 29 00:45:30.722163 systemd-resolved[292]: Defaulting to hostname 'linux'. Oct 29 00:45:30.737915 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 29 00:45:30.723072 systemd[1]: Started systemd-resolved.service. Oct 29 00:45:30.737000 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' Oct 29 00:45:30.727824 systemd[1]: Reached target nss-lookup.target. Oct 29 00:45:30.743181 kernel: audit: type=1130 audit(1761698730.737:7): 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' Oct 29 00:45:30.743200 kernel: Bridge firewalling registered Oct 29 00:45:30.737606 systemd[1]: Finished dracut-cmdline-ask.service. Oct 29 00:45:30.742755 systemd[1]: Starting dracut-cmdline.service... Oct 29 00:45:30.743184 systemd-modules-load[291]: Inserted module 'br_netfilter' Oct 29 00:45:30.752128 dracut-cmdline[307]: dracut-dracut-053 Oct 29 00:45:30.754405 dracut-cmdline[307]: 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=ddcdcc5923a51dfb24bee27c235aa754769d72fd417f60397f96d58c38c7a3e3 Oct 29 00:45:30.760578 kernel: SCSI subsystem initialized Oct 29 00:45:30.763123 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 29 00:45:30.763156 kernel: device-mapper: uevent: version 1.0.3 Oct 29 00:45:30.764291 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Oct 29 00:45:30.766515 systemd-modules-load[291]: Inserted module 'dm_multipath' Oct 29 00:45:30.767296 systemd[1]: Finished systemd-modules-load.service. Oct 29 00:45:30.768000 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' Oct 29 00:45:30.769282 systemd[1]: Starting systemd-sysctl.service... Oct 29 00:45:30.773307 kernel: audit: type=1130 audit(1761698730.768:8): 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' Oct 29 00:45:30.778682 systemd[1]: Finished systemd-sysctl.service. Oct 29 00:45:30.779000 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' Oct 29 00:45:30.783809 kernel: audit: type=1130 audit(1761698730.779:9): 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' Oct 29 00:45:30.818805 kernel: Loading iSCSI transport class v2.0-870. Oct 29 00:45:30.830797 kernel: iscsi: registered transport (tcp) Oct 29 00:45:30.846819 kernel: iscsi: registered transport (qla4xxx) Oct 29 00:45:30.846870 kernel: QLogic iSCSI HBA Driver Oct 29 00:45:30.882096 systemd[1]: Finished dracut-cmdline.service. Oct 29 00:45:30.882000 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' Oct 29 00:45:30.883704 systemd[1]: Starting dracut-pre-udev.service... Oct 29 00:45:30.887497 kernel: audit: type=1130 audit(1761698730.882:10): 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' Oct 29 00:45:30.925819 kernel: raid6: neonx8 gen() 13788 MB/s Oct 29 00:45:30.942808 kernel: raid6: neonx8 xor() 10644 MB/s Oct 29 00:45:30.959807 kernel: raid6: neonx4 gen() 13420 MB/s Oct 29 00:45:30.976808 kernel: raid6: neonx4 xor() 10935 MB/s Oct 29 00:45:30.993810 kernel: raid6: neonx2 gen() 11828 MB/s Oct 29 00:45:31.010808 kernel: raid6: neonx2 xor() 10252 MB/s Oct 29 00:45:31.027815 kernel: raid6: neonx1 gen() 10486 MB/s Oct 29 00:45:31.044809 kernel: raid6: neonx1 xor() 8767 MB/s Oct 29 00:45:31.061809 kernel: raid6: int64x8 gen() 6253 MB/s Oct 29 00:45:31.078809 kernel: raid6: int64x8 xor() 3538 MB/s Oct 29 00:45:31.095798 kernel: raid6: int64x4 gen() 7183 MB/s Oct 29 00:45:31.112806 kernel: raid6: int64x4 xor() 3855 MB/s Oct 29 00:45:31.129805 kernel: raid6: int64x2 gen() 6130 MB/s Oct 29 00:45:31.146804 kernel: raid6: int64x2 xor() 3314 MB/s Oct 29 00:45:31.163805 kernel: raid6: int64x1 gen() 5037 MB/s Oct 29 00:45:31.181001 kernel: raid6: int64x1 xor() 2643 MB/s Oct 29 00:45:31.181015 kernel: raid6: using algorithm neonx8 gen() 13788 MB/s Oct 29 00:45:31.181024 kernel: raid6: .... xor() 10644 MB/s, rmw enabled Oct 29 00:45:31.182181 kernel: raid6: using neon recovery algorithm Oct 29 00:45:31.192879 kernel: xor: measuring software checksum speed Oct 29 00:45:31.192915 kernel: 8regs : 16560 MB/sec Oct 29 00:45:31.194210 kernel: 32regs : 20702 MB/sec Oct 29 00:45:31.194223 kernel: arm64_neon : 27682 MB/sec Oct 29 00:45:31.194236 kernel: xor: using function: arm64_neon (27682 MB/sec) Oct 29 00:45:31.246811 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Oct 29 00:45:31.257063 systemd[1]: Finished dracut-pre-udev.service. Oct 29 00:45:31.256000 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' Oct 29 00:45:31.257000 audit: BPF prog-id=7 op=LOAD Oct 29 00:45:31.257000 audit: BPF prog-id=8 op=LOAD Oct 29 00:45:31.258881 systemd[1]: Starting systemd-udevd.service... Oct 29 00:45:31.270805 systemd-udevd[490]: Using default interface naming scheme 'v252'. Oct 29 00:45:31.274111 systemd[1]: Started systemd-udevd.service. Oct 29 00:45:31.274000 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' Oct 29 00:45:31.276005 systemd[1]: Starting dracut-pre-trigger.service... Oct 29 00:45:31.287343 dracut-pre-trigger[497]: rd.md=0: removing MD RAID activation Oct 29 00:45:31.314892 systemd[1]: Finished dracut-pre-trigger.service. Oct 29 00:45:31.314000 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' Oct 29 00:45:31.316414 systemd[1]: Starting systemd-udev-trigger.service... Oct 29 00:45:31.350427 systemd[1]: Finished systemd-udev-trigger.service. Oct 29 00:45:31.351000 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' Oct 29 00:45:31.380316 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Oct 29 00:45:31.385815 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 29 00:45:31.385830 kernel: GPT:9289727 != 19775487 Oct 29 00:45:31.385839 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 29 00:45:31.385847 kernel: GPT:9289727 != 19775487 Oct 29 00:45:31.385855 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 29 00:45:31.385863 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 00:45:31.397818 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Oct 29 00:45:31.400585 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (537) Oct 29 00:45:31.405794 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Oct 29 00:45:31.411004 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Oct 29 00:45:31.411963 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Oct 29 00:45:31.416276 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 29 00:45:31.417943 systemd[1]: Starting disk-uuid.service... Oct 29 00:45:31.423627 disk-uuid[561]: Primary Header is updated. Oct 29 00:45:31.423627 disk-uuid[561]: Secondary Entries is updated. Oct 29 00:45:31.423627 disk-uuid[561]: Secondary Header is updated. Oct 29 00:45:31.426805 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 00:45:31.429793 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 00:45:32.432821 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 00:45:32.432883 disk-uuid[562]: The operation has completed successfully. Oct 29 00:45:32.455961 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 00:45:32.455000 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' Oct 29 00:45:32.455000 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' Oct 29 00:45:32.456059 systemd[1]: Finished disk-uuid.service. Oct 29 00:45:32.457593 systemd[1]: Starting verity-setup.service... Oct 29 00:45:32.469804 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 00:45:32.489574 systemd[1]: Found device dev-mapper-usr.device. Oct 29 00:45:32.491823 systemd[1]: Mounting sysusr-usr.mount... Oct 29 00:45:32.493658 systemd[1]: Finished verity-setup.service. Oct 29 00:45:32.493000 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' Oct 29 00:45:32.537805 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Oct 29 00:45:32.538178 systemd[1]: Mounted sysusr-usr.mount. Oct 29 00:45:32.538935 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Oct 29 00:45:32.539636 systemd[1]: Starting ignition-setup.service... Oct 29 00:45:32.541716 systemd[1]: Starting parse-ip-for-networkd.service... Oct 29 00:45:32.549382 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 29 00:45:32.549523 kernel: BTRFS info (device vda6): using free space tree Oct 29 00:45:32.549571 kernel: BTRFS info (device vda6): has skinny extents Oct 29 00:45:32.556976 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 29 00:45:32.563000 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' Oct 29 00:45:32.562497 systemd[1]: Finished ignition-setup.service. Oct 29 00:45:32.564093 systemd[1]: Starting ignition-fetch-offline.service... Oct 29 00:45:32.614740 ignition[648]: Ignition 2.14.0 Oct 29 00:45:32.614750 ignition[648]: Stage: fetch-offline Oct 29 00:45:32.614800 ignition[648]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:45:32.614810 ignition[648]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 00:45:32.614938 ignition[648]: parsed url from cmdline: "" Oct 29 00:45:32.614942 ignition[648]: no config URL provided Oct 29 00:45:32.614946 ignition[648]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 00:45:32.614954 ignition[648]: no config at "/usr/lib/ignition/user.ign" Oct 29 00:45:32.614971 ignition[648]: op(1): [started] loading QEMU firmware config module Oct 29 00:45:32.614976 ignition[648]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 29 00:45:32.621363 ignition[648]: op(1): [finished] loading QEMU firmware config module Oct 29 00:45:32.628097 systemd[1]: Finished parse-ip-for-networkd.service. Oct 29 00:45:32.628000 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' Oct 29 00:45:32.628000 audit: BPF prog-id=9 op=LOAD Oct 29 00:45:32.630032 systemd[1]: Starting systemd-networkd.service... Oct 29 00:45:32.631009 ignition[648]: parsing config with SHA512: 52738fa53ab4bd87218042bd6e867b527a533728635ee07f8b714a58b269c9a7bd8543db7bbb3493b494a784147be7db2928b4425fbbdeed4ab83970e4b5e2a1 Oct 29 00:45:32.642668 unknown[648]: fetched base config from "system" Oct 29 00:45:32.642686 unknown[648]: fetched user config from "qemu" Oct 29 00:45:32.643042 ignition[648]: fetch-offline: fetch-offline passed Oct 29 00:45:32.643111 ignition[648]: Ignition finished successfully Oct 29 00:45:32.646216 systemd[1]: Finished ignition-fetch-offline.service. Oct 29 00:45:32.646000 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' Oct 29 00:45:32.650728 systemd-networkd[742]: lo: Link UP Oct 29 00:45:32.650742 systemd-networkd[742]: lo: Gained carrier Oct 29 00:45:32.652000 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' Oct 29 00:45:32.651145 systemd-networkd[742]: Enumeration completed Oct 29 00:45:32.651347 systemd-networkd[742]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 00:45:32.651427 systemd[1]: Started systemd-networkd.service. Oct 29 00:45:32.652338 systemd-networkd[742]: eth0: Link UP Oct 29 00:45:32.652342 systemd-networkd[742]: eth0: Gained carrier Oct 29 00:45:32.652434 systemd[1]: Reached target network.target. Oct 29 00:45:32.654307 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 29 00:45:32.655080 systemd[1]: Starting ignition-kargs.service... Oct 29 00:45:32.656601 systemd[1]: Starting iscsiuio.service... Oct 29 00:45:32.663774 systemd[1]: Started iscsiuio.service. Oct 29 00:45:32.664000 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' Oct 29 00:45:32.664202 ignition[744]: Ignition 2.14.0 Oct 29 00:45:32.664208 ignition[744]: Stage: kargs Oct 29 00:45:32.666433 systemd[1]: Starting iscsid.service... Oct 29 00:45:32.664322 ignition[744]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:45:32.667000 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' Oct 29 00:45:32.669240 iscsid[753]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 00:45:32.669240 iscsid[753]: 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 Oct 29 00:45:32.669240 iscsid[753]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 29 00:45:32.669240 iscsid[753]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 00:45:32.669240 iscsid[753]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 00:45:32.669240 iscsid[753]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 00:45:32.669240 iscsid[753]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 00:45:32.674000 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' Oct 29 00:45:32.678000 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' Oct 29 00:45:32.667577 systemd[1]: Finished ignition-kargs.service. Oct 29 00:45:32.664331 ignition[744]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 00:45:32.669261 systemd[1]: Starting ignition-disks.service... Oct 29 00:45:32.665622 ignition[744]: kargs: kargs passed Oct 29 00:45:32.687000 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' Oct 29 00:45:32.671927 systemd[1]: Started iscsid.service. Oct 29 00:45:32.665667 ignition[744]: Ignition finished successfully Oct 29 00:45:32.673895 systemd-networkd[742]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 29 00:45:32.675662 ignition[754]: Ignition 2.14.0 Oct 29 00:45:32.675700 systemd[1]: Starting dracut-initqueue.service... Oct 29 00:45:32.675668 ignition[754]: Stage: disks Oct 29 00:45:32.677808 systemd[1]: Finished ignition-disks.service. Oct 29 00:45:32.675751 ignition[754]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:45:32.679290 systemd[1]: Reached target initrd-root-device.target. Oct 29 00:45:32.675760 ignition[754]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 00:45:32.680745 systemd[1]: Reached target local-fs-pre.target. Oct 29 00:45:32.676492 ignition[754]: disks: disks passed Oct 29 00:45:32.682505 systemd[1]: Reached target local-fs.target. Oct 29 00:45:32.676531 ignition[754]: Ignition finished successfully Oct 29 00:45:32.699000 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' Oct 29 00:45:32.684220 systemd[1]: Reached target sysinit.target. Oct 29 00:45:32.685396 systemd[1]: Reached target basic.target. Oct 29 00:45:32.687110 systemd[1]: Finished dracut-initqueue.service. Oct 29 00:45:32.688103 systemd[1]: Reached target remote-fs-pre.target. Oct 29 00:45:32.689189 systemd[1]: Reached target remote-cryptsetup.target. Oct 29 00:45:32.690363 systemd[1]: Reached target remote-fs.target. Oct 29 00:45:32.692199 systemd[1]: Starting dracut-pre-mount.service... Oct 29 00:45:32.699922 systemd[1]: Finished dracut-pre-mount.service. Oct 29 00:45:32.701314 systemd[1]: Starting systemd-fsck-root.service... Oct 29 00:45:32.712382 systemd-fsck[776]: ROOT: clean, 637/553520 files, 56031/553472 blocks Oct 29 00:45:32.715725 systemd[1]: Finished systemd-fsck-root.service. Oct 29 00:45:32.715000 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' Oct 29 00:45:32.717815 systemd[1]: Mounting sysroot.mount... Oct 29 00:45:32.724808 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Oct 29 00:45:32.724857 systemd[1]: Mounted sysroot.mount. Oct 29 00:45:32.725549 systemd[1]: Reached target initrd-root-fs.target. Oct 29 00:45:32.727640 systemd[1]: Mounting sysroot-usr.mount... Oct 29 00:45:32.728500 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Oct 29 00:45:32.728537 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 29 00:45:32.728559 systemd[1]: Reached target ignition-diskful.target. Oct 29 00:45:32.730494 systemd[1]: Mounted sysroot-usr.mount. Oct 29 00:45:32.732091 systemd[1]: Starting initrd-setup-root.service... Oct 29 00:45:32.736392 initrd-setup-root[786]: cut: /sysroot/etc/passwd: No such file or directory Oct 29 00:45:32.740304 initrd-setup-root[794]: cut: /sysroot/etc/group: No such file or directory Oct 29 00:45:32.744102 initrd-setup-root[802]: cut: /sysroot/etc/shadow: No such file or directory Oct 29 00:45:32.748112 initrd-setup-root[810]: cut: /sysroot/etc/gshadow: No such file or directory Oct 29 00:45:32.775558 systemd[1]: Finished initrd-setup-root.service. Oct 29 00:45:32.775000 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' Oct 29 00:45:32.777139 systemd[1]: Starting ignition-mount.service... Oct 29 00:45:32.778404 systemd[1]: Starting sysroot-boot.service... Oct 29 00:45:32.782902 bash[827]: umount: /sysroot/usr/share/oem: not mounted. Oct 29 00:45:32.791374 ignition[829]: INFO : Ignition 2.14.0 Oct 29 00:45:32.791374 ignition[829]: INFO : Stage: mount Oct 29 00:45:32.794359 ignition[829]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:45:32.794359 ignition[829]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 00:45:32.794359 ignition[829]: INFO : mount: mount passed Oct 29 00:45:32.794359 ignition[829]: INFO : Ignition finished successfully Oct 29 00:45:32.793000 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' Oct 29 00:45:32.794000 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' Oct 29 00:45:32.793063 systemd[1]: Finished ignition-mount.service. Oct 29 00:45:32.794256 systemd[1]: Finished sysroot-boot.service. Oct 29 00:45:33.500696 systemd[1]: Mounting sysroot-usr-share-oem.mount... Oct 29 00:45:33.506807 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (839) Oct 29 00:45:33.509334 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 29 00:45:33.509354 kernel: BTRFS info (device vda6): using free space tree Oct 29 00:45:33.509364 kernel: BTRFS info (device vda6): has skinny extents Oct 29 00:45:33.512077 systemd[1]: Mounted sysroot-usr-share-oem.mount. Oct 29 00:45:33.513480 systemd[1]: Starting ignition-files.service... Oct 29 00:45:33.526777 ignition[859]: INFO : Ignition 2.14.0 Oct 29 00:45:33.526777 ignition[859]: INFO : Stage: files Oct 29 00:45:33.528354 ignition[859]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:45:33.528354 ignition[859]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 00:45:33.528354 ignition[859]: DEBUG : files: compiled without relabeling support, skipping Oct 29 00:45:33.531594 ignition[859]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 29 00:45:33.531594 ignition[859]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 29 00:45:33.535313 ignition[859]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 29 00:45:33.536571 ignition[859]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 29 00:45:33.537946 unknown[859]: wrote ssh authorized keys file for user: core Oct 29 00:45:33.538988 ignition[859]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 00:45:33.538988 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Oct 29 00:45:33.538988 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Oct 29 00:45:33.538988 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Oct 29 00:45:33.538988 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Oct 29 00:45:33.538988 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 00:45:33.538988 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 00:45:33.549870 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 00:45:33.549870 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 00:45:33.549870 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 00:45:33.549870 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Oct 29 00:45:33.968987 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Oct 29 00:45:34.278317 ignition[859]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 00:45:34.278317 ignition[859]: INFO : files: op(8): [started] processing unit "containerd.service" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(8): [finished] processing unit "containerd.service" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(a): [started] processing unit "coreos-metadata.service" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(a): op(b): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(a): op(b): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(a): [finished] processing unit "coreos-metadata.service" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(c): [started] setting preset to disabled for "coreos-metadata.service" Oct 29 00:45:34.281489 ignition[859]: INFO : files: op(c): op(d): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 29 00:45:34.316143 ignition[859]: INFO : files: op(c): op(d): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 29 00:45:34.317674 ignition[859]: INFO : files: op(c): [finished] setting preset to disabled for "coreos-metadata.service" Oct 29 00:45:34.317674 ignition[859]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 29 00:45:34.317674 ignition[859]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 29 00:45:34.317674 ignition[859]: INFO : files: files passed Oct 29 00:45:34.317674 ignition[859]: INFO : Ignition finished successfully Oct 29 00:45:34.318000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:34.317700 systemd[1]: Finished ignition-files.service. Oct 29 00:45:34.320560 systemd[1]: Starting initrd-setup-root-after-ignition.service... Oct 29 00:45:34.326000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:34.326000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:34.321945 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Oct 29 00:45:34.329664 initrd-setup-root-after-ignition[885]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Oct 29 00:45:34.330000 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' Oct 29 00:45:34.322605 systemd[1]: Starting ignition-quench.service... Oct 29 00:45:34.332897 initrd-setup-root-after-ignition[887]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 29 00:45:34.326174 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 00:45:34.326273 systemd[1]: Finished ignition-quench.service. Oct 29 00:45:34.328816 systemd[1]: Finished initrd-setup-root-after-ignition.service. Oct 29 00:45:34.330552 systemd[1]: Reached target ignition-complete.target. Oct 29 00:45:34.332882 systemd[1]: Starting initrd-parse-etc.service... Oct 29 00:45:34.344675 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 00:45:34.344763 systemd[1]: Finished initrd-parse-etc.service. Oct 29 00:45:34.346000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:34.346000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:34.346337 systemd[1]: Reached target initrd-fs.target. Oct 29 00:45:34.347473 systemd[1]: Reached target initrd.target. Oct 29 00:45:34.348639 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Oct 29 00:45:34.349332 systemd[1]: Starting dracut-pre-pivot.service... Oct 29 00:45:34.359309 systemd[1]: Finished dracut-pre-pivot.service. Oct 29 00:45:34.359000 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' Oct 29 00:45:34.360721 systemd[1]: Starting initrd-cleanup.service... Oct 29 00:45:34.368408 systemd[1]: Stopped target nss-lookup.target. Oct 29 00:45:34.369207 systemd[1]: Stopped target remote-cryptsetup.target. Oct 29 00:45:34.370525 systemd[1]: Stopped target timers.target. Oct 29 00:45:34.371714 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 00:45:34.372000 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' Oct 29 00:45:34.371867 systemd[1]: Stopped dracut-pre-pivot.service. Oct 29 00:45:34.372991 systemd[1]: Stopped target initrd.target. Oct 29 00:45:34.374273 systemd[1]: Stopped target basic.target. Oct 29 00:45:34.375414 systemd[1]: Stopped target ignition-complete.target. Oct 29 00:45:34.376573 systemd[1]: Stopped target ignition-diskful.target. Oct 29 00:45:34.377740 systemd[1]: Stopped target initrd-root-device.target. Oct 29 00:45:34.379085 systemd[1]: Stopped target remote-fs.target. Oct 29 00:45:34.380307 systemd[1]: Stopped target remote-fs-pre.target. Oct 29 00:45:34.381527 systemd[1]: Stopped target sysinit.target. Oct 29 00:45:34.382633 systemd[1]: Stopped target local-fs.target. Oct 29 00:45:34.383811 systemd[1]: Stopped target local-fs-pre.target. Oct 29 00:45:34.384985 systemd[1]: Stopped target swap.target. Oct 29 00:45:34.386000 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' Oct 29 00:45:34.386055 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 00:45:34.386160 systemd[1]: Stopped dracut-pre-mount.service. Oct 29 00:45:34.389000 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' Oct 29 00:45:34.387368 systemd[1]: Stopped target cryptsetup.target. Oct 29 00:45:34.390000 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' Oct 29 00:45:34.388422 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 00:45:34.388514 systemd[1]: Stopped dracut-initqueue.service. Oct 29 00:45:34.389841 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 29 00:45:34.389937 systemd[1]: Stopped ignition-fetch-offline.service. Oct 29 00:45:34.391086 systemd[1]: Stopped target paths.target. Oct 29 00:45:34.392129 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 00:45:34.395828 systemd[1]: Stopped systemd-ask-password-console.path. Oct 29 00:45:34.396874 systemd[1]: Stopped target slices.target. Oct 29 00:45:34.398351 systemd[1]: Stopped target sockets.target. Oct 29 00:45:34.400000 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' Oct 29 00:45:34.399679 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 29 00:45:34.401000 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' Oct 29 00:45:34.399799 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Oct 29 00:45:34.401017 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 00:45:34.405001 iscsid[753]: iscsid shutting down. Oct 29 00:45:34.401106 systemd[1]: Stopped ignition-files.service. Oct 29 00:45:34.403015 systemd[1]: Stopping ignition-mount.service... Oct 29 00:45:34.404443 systemd[1]: Stopping iscsid.service... Oct 29 00:45:34.406209 systemd[1]: Stopping sysroot-boot.service... Oct 29 00:45:34.408000 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' Oct 29 00:45:34.408000 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' Oct 29 00:45:34.407149 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 00:45:34.407274 systemd[1]: Stopped systemd-udev-trigger.service. Oct 29 00:45:34.411000 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' Oct 29 00:45:34.408536 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 00:45:34.408634 systemd[1]: Stopped dracut-pre-trigger.service. Oct 29 00:45:34.416174 ignition[900]: INFO : Ignition 2.14.0 Oct 29 00:45:34.416174 ignition[900]: INFO : Stage: umount Oct 29 00:45:34.416174 ignition[900]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:45:34.416174 ignition[900]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 00:45:34.416174 ignition[900]: INFO : umount: umount passed Oct 29 00:45:34.416174 ignition[900]: INFO : Ignition finished successfully Oct 29 00:45:34.417000 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' Oct 29 00:45:34.418000 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' Oct 29 00:45:34.418000 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' Oct 29 00:45:34.419000 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' Oct 29 00:45:34.410901 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 00:45:34.410991 systemd[1]: Stopped iscsid.service. Oct 29 00:45:34.424000 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' Oct 29 00:45:34.412107 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 00:45:34.425000 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' Oct 29 00:45:34.412167 systemd[1]: Closed iscsid.socket. Oct 29 00:45:34.427000 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' Oct 29 00:45:34.413175 systemd[1]: Stopping iscsiuio.service... Oct 29 00:45:34.416312 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 29 00:45:34.416713 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 00:45:34.416851 systemd[1]: Stopped iscsiuio.service. Oct 29 00:45:34.418047 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 00:45:34.418131 systemd[1]: Finished initrd-cleanup.service. Oct 29 00:45:34.419221 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 29 00:45:34.419307 systemd[1]: Stopped ignition-mount.service. Oct 29 00:45:34.421177 systemd[1]: Stopped target network.target. Oct 29 00:45:34.422218 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 00:45:34.422257 systemd[1]: Closed iscsiuio.socket. Oct 29 00:45:34.436000 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' Oct 29 00:45:34.423337 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 00:45:34.423374 systemd[1]: Stopped ignition-disks.service. Oct 29 00:45:34.425647 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 29 00:45:34.441000 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' Oct 29 00:45:34.425692 systemd[1]: Stopped ignition-kargs.service. Oct 29 00:45:34.443000 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' Oct 29 00:45:34.426708 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 00:45:34.445000 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' Oct 29 00:45:34.426742 systemd[1]: Stopped ignition-setup.service. Oct 29 00:45:34.428296 systemd[1]: Stopping systemd-networkd.service... Oct 29 00:45:34.429135 systemd[1]: Stopping systemd-resolved.service... Oct 29 00:45:34.434845 systemd-networkd[742]: eth0: DHCPv6 lease lost Oct 29 00:45:34.450000 audit: BPF prog-id=9 op=UNLOAD Oct 29 00:45:34.451000 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' Oct 29 00:45:34.436399 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 00:45:34.436497 systemd[1]: Stopped systemd-networkd.service. Oct 29 00:45:34.437911 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 00:45:34.437941 systemd[1]: Closed systemd-networkd.socket. Oct 29 00:45:34.439414 systemd[1]: Stopping network-cleanup.service... Oct 29 00:45:34.440137 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 29 00:45:34.453000 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' Oct 29 00:45:34.455000 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' Oct 29 00:45:34.457000 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' Oct 29 00:45:34.440188 systemd[1]: Stopped parse-ip-for-networkd.service. Oct 29 00:45:34.442009 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 00:45:34.459000 audit: BPF prog-id=6 op=UNLOAD Oct 29 00:45:34.442107 systemd[1]: Stopped systemd-sysctl.service. Oct 29 00:45:34.444753 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 29 00:45:34.444808 systemd[1]: Stopped systemd-modules-load.service. Oct 29 00:45:34.445999 systemd[1]: Stopping systemd-udevd.service... Oct 29 00:45:34.462000 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' Oct 29 00:45:34.450276 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 29 00:45:34.450711 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 00:45:34.450814 systemd[1]: Stopped systemd-resolved.service. Oct 29 00:45:34.466000 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' Oct 29 00:45:34.452734 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 00:45:34.467000 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' Oct 29 00:45:34.452828 systemd[1]: Stopped sysroot-boot.service. Oct 29 00:45:34.470000 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' Oct 29 00:45:34.454915 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 00:45:34.454966 systemd[1]: Stopped initrd-setup-root.service. Oct 29 00:45:34.457044 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 00:45:34.472000 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' Oct 29 00:45:34.457132 systemd[1]: Stopped network-cleanup.service. Oct 29 00:45:34.474000 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' Oct 29 00:45:34.462186 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 00:45:34.476000 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' Oct 29 00:45:34.462309 systemd[1]: Stopped systemd-udevd.service. Oct 29 00:45:34.463633 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 00:45:34.478000 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' Oct 29 00:45:34.478000 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' Oct 29 00:45:34.463668 systemd[1]: Closed systemd-udevd-control.socket. Oct 29 00:45:34.464619 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 00:45:34.464647 systemd[1]: Closed systemd-udevd-kernel.socket. Oct 29 00:45:34.466262 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 00:45:34.466304 systemd[1]: Stopped dracut-pre-udev.service. Oct 29 00:45:34.467622 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 00:45:34.467658 systemd[1]: Stopped dracut-cmdline.service. Oct 29 00:45:34.468857 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 00:45:34.468896 systemd[1]: Stopped dracut-cmdline-ask.service. Oct 29 00:45:34.470861 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Oct 29 00:45:34.472267 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 00:45:34.472319 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Oct 29 00:45:34.474296 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 00:45:34.474334 systemd[1]: Stopped kmod-static-nodes.service. Oct 29 00:45:34.490000 audit: BPF prog-id=8 op=UNLOAD Oct 29 00:45:34.490000 audit: BPF prog-id=7 op=UNLOAD Oct 29 00:45:34.490000 audit: BPF prog-id=5 op=UNLOAD Oct 29 00:45:34.490000 audit: BPF prog-id=4 op=UNLOAD Oct 29 00:45:34.490000 audit: BPF prog-id=3 op=UNLOAD Oct 29 00:45:34.475069 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 00:45:34.475106 systemd[1]: Stopped systemd-vconsole-setup.service. Oct 29 00:45:34.477063 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 29 00:45:34.477465 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 00:45:34.477542 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Oct 29 00:45:34.478560 systemd[1]: Reached target initrd-switch-root.target. Oct 29 00:45:34.480561 systemd[1]: Starting initrd-switch-root.service... Oct 29 00:45:34.487408 systemd[1]: Switching root. Oct 29 00:45:34.508300 systemd-journald[290]: Journal stopped Oct 29 00:45:36.481490 systemd-journald[290]: Received SIGTERM from PID 1 (systemd). Oct 29 00:45:36.481544 kernel: SELinux: Class mctp_socket not defined in policy. Oct 29 00:45:36.481557 kernel: SELinux: Class anon_inode not defined in policy. Oct 29 00:45:36.481573 kernel: SELinux: the above unknown classes and permissions will be allowed Oct 29 00:45:36.481583 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 00:45:36.481593 kernel: SELinux: policy capability open_perms=1 Oct 29 00:45:36.481604 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 00:45:36.481616 kernel: SELinux: policy capability always_check_network=0 Oct 29 00:45:36.481626 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 00:45:36.481636 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 00:45:36.481645 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 00:45:36.481654 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 29 00:45:36.481663 systemd[1]: Successfully loaded SELinux policy in 34.080ms. Oct 29 00:45:36.481682 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.648ms. Oct 29 00:45:36.481693 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) Oct 29 00:45:36.481706 systemd[1]: Detected virtualization kvm. Oct 29 00:45:36.481716 systemd[1]: Detected architecture arm64. Oct 29 00:45:36.481726 systemd[1]: Detected first boot. Oct 29 00:45:36.481736 systemd[1]: Initializing machine ID from VM UUID. Oct 29 00:45:36.481747 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Oct 29 00:45:36.481756 systemd[1]: Populated /etc with preset unit settings. Oct 29 00:45:36.481768 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Oct 29 00:45:36.481791 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Oct 29 00:45:36.481805 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. Oct 29 00:45:36.481816 systemd[1]: Queued start job for default target multi-user.target. Oct 29 00:45:36.481826 systemd[1]: Unnecessary job was removed for dev-vda6.device. Oct 29 00:45:36.481837 systemd[1]: Created slice system-addon\x2dconfig.slice. Oct 29 00:45:36.481846 systemd[1]: Created slice system-addon\x2drun.slice. Oct 29 00:45:36.481856 systemd[1]: Created slice system-getty.slice. Oct 29 00:45:36.481867 systemd[1]: Created slice system-modprobe.slice. Oct 29 00:45:36.481879 systemd[1]: Created slice system-serial\x2dgetty.slice. Oct 29 00:45:36.481889 systemd[1]: Created slice system-system\x2dcloudinit.slice. Oct 29 00:45:36.481900 systemd[1]: Created slice system-systemd\x2dfsck.slice. Oct 29 00:45:36.481910 systemd[1]: Created slice user.slice. Oct 29 00:45:36.481920 systemd[1]: Started systemd-ask-password-console.path. Oct 29 00:45:36.481930 systemd[1]: Started systemd-ask-password-wall.path. Oct 29 00:45:36.481941 systemd[1]: Set up automount boot.automount. Oct 29 00:45:36.481952 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Oct 29 00:45:36.481962 systemd[1]: Reached target integritysetup.target. Oct 29 00:45:36.481976 systemd[1]: Reached target remote-cryptsetup.target. Oct 29 00:45:36.481987 systemd[1]: Reached target remote-fs.target. Oct 29 00:45:36.481998 systemd[1]: Reached target slices.target. Oct 29 00:45:36.482010 systemd[1]: Reached target swap.target. Oct 29 00:45:36.482020 systemd[1]: Reached target torcx.target. Oct 29 00:45:36.482032 systemd[1]: Reached target veritysetup.target. Oct 29 00:45:36.482042 systemd[1]: Listening on systemd-coredump.socket. Oct 29 00:45:36.482052 systemd[1]: Listening on systemd-initctl.socket. Oct 29 00:45:36.482061 kernel: kauditd_printk_skb: 82 callbacks suppressed Oct 29 00:45:36.482071 systemd[1]: Listening on systemd-journald-audit.socket. Oct 29 00:45:36.482082 kernel: audit: type=1400 audit(1761698736.403:86): avc: denied { audit_read } for pid=1 comm="systemd" capability=37 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 00:45:36.482092 kernel: audit: type=1335 audit(1761698736.403:87): pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Oct 29 00:45:36.482105 systemd[1]: Listening on systemd-journald-dev-log.socket. Oct 29 00:45:36.482119 systemd[1]: Listening on systemd-journald.socket. Oct 29 00:45:36.482129 systemd[1]: Listening on systemd-networkd.socket. Oct 29 00:45:36.482144 systemd[1]: Listening on systemd-udevd-control.socket. Oct 29 00:45:36.482154 systemd[1]: Listening on systemd-udevd-kernel.socket. Oct 29 00:45:36.482164 systemd[1]: Listening on systemd-userdbd.socket. Oct 29 00:45:36.482175 systemd[1]: Mounting dev-hugepages.mount... Oct 29 00:45:36.482185 systemd[1]: Mounting dev-mqueue.mount... Oct 29 00:45:36.482196 systemd[1]: Mounting media.mount... Oct 29 00:45:36.482212 systemd[1]: Mounting sys-kernel-debug.mount... Oct 29 00:45:36.482224 systemd[1]: Mounting sys-kernel-tracing.mount... Oct 29 00:45:36.482235 systemd[1]: Mounting tmp.mount... Oct 29 00:45:36.482246 systemd[1]: Starting flatcar-tmpfiles.service... Oct 29 00:45:36.482257 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 29 00:45:36.482267 systemd[1]: Starting kmod-static-nodes.service... Oct 29 00:45:36.482277 systemd[1]: Starting modprobe@configfs.service... Oct 29 00:45:36.482286 systemd[1]: Starting modprobe@dm_mod.service... Oct 29 00:45:36.482296 systemd[1]: Starting modprobe@drm.service... Oct 29 00:45:36.482306 systemd[1]: Starting modprobe@efi_pstore.service... Oct 29 00:45:36.482319 systemd[1]: Starting modprobe@fuse.service... Oct 29 00:45:36.482330 systemd[1]: Starting modprobe@loop.service... Oct 29 00:45:36.482346 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 29 00:45:36.482359 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Oct 29 00:45:36.482371 systemd[1]: (This warning is only shown for the first unit using IP firewalling.) Oct 29 00:45:36.482380 kernel: fuse: init (API version 7.34) Oct 29 00:45:36.482390 systemd[1]: Starting systemd-journald.service... Oct 29 00:45:36.482399 systemd[1]: Starting systemd-modules-load.service... Oct 29 00:45:36.482410 systemd[1]: Starting systemd-network-generator.service... Oct 29 00:45:36.482420 systemd[1]: Starting systemd-remount-fs.service... Oct 29 00:45:36.482431 systemd[1]: Starting systemd-udev-trigger.service... Oct 29 00:45:36.482442 systemd[1]: Mounted dev-hugepages.mount. Oct 29 00:45:36.482452 systemd[1]: Mounted dev-mqueue.mount. Oct 29 00:45:36.482461 kernel: audit: type=1305 audit(1761698736.480:88): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 00:45:36.482474 systemd-journald[1035]: Journal started Oct 29 00:45:36.482513 systemd-journald[1035]: Runtime Journal (/run/log/journal/1a02d2882adc4882b60c6c132b8102de) is 6.0M, max 48.7M, 42.6M free. Oct 29 00:45:36.403000 audit[1]: AVC avc: denied { audit_read } for pid=1 comm="systemd" capability=37 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 29 00:45:36.403000 audit[1]: EVENT_LISTENER pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Oct 29 00:45:36.480000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 00:45:36.480000 audit[1035]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffc78aa050 a2=4000 a3=1 items=0 ppid=1 pid=1035 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) Oct 29 00:45:36.487504 kernel: audit: type=1300 audit(1761698736.480:88): arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffc78aa050 a2=4000 a3=1 items=0 ppid=1 pid=1035 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) Oct 29 00:45:36.487558 kernel: loop: module loaded Oct 29 00:45:36.487572 kernel: audit: type=1327 audit(1761698736.480:88): proctitle="/usr/lib/systemd/systemd-journald" Oct 29 00:45:36.487585 systemd[1]: Started systemd-journald.service. Oct 29 00:45:36.480000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Oct 29 00:45:36.491097 kernel: audit: type=1130 audit(1761698736.490:89): 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' Oct 29 00:45:36.490000 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' Oct 29 00:45:36.491092 systemd[1]: Mounted media.mount. Oct 29 00:45:36.494437 systemd[1]: Mounted sys-kernel-debug.mount. Oct 29 00:45:36.495276 systemd[1]: Mounted sys-kernel-tracing.mount. Oct 29 00:45:36.496081 systemd[1]: Mounted tmp.mount. Oct 29 00:45:36.497280 systemd[1]: Finished kmod-static-nodes.service. Oct 29 00:45:36.497000 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' Oct 29 00:45:36.498278 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 00:45:36.498483 systemd[1]: Finished modprobe@configfs.service. Oct 29 00:45:36.500000 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' Oct 29 00:45:36.501976 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:45:36.502175 systemd[1]: Finished modprobe@dm_mod.service. Oct 29 00:45:36.504666 kernel: audit: type=1130 audit(1761698736.497:90): 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' Oct 29 00:45:36.504712 kernel: audit: type=1130 audit(1761698736.500:91): 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' Oct 29 00:45:36.504731 kernel: audit: type=1131 audit(1761698736.500:92): 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' Oct 29 00:45:36.500000 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' Oct 29 00:45:36.508000 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' Oct 29 00:45:36.510123 systemd[1]: Finished flatcar-tmpfiles.service. Oct 29 00:45:36.508000 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' Oct 29 00:45:36.512954 kernel: audit: type=1130 audit(1761698736.508:93): 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' Oct 29 00:45:36.513000 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' Oct 29 00:45:36.513638 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 00:45:36.513858 systemd[1]: Finished modprobe@drm.service. Oct 29 00:45:36.513000 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' Oct 29 00:45:36.513000 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' Oct 29 00:45:36.514761 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:45:36.514959 systemd[1]: Finished modprobe@efi_pstore.service. Oct 29 00:45:36.514000 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' Oct 29 00:45:36.514000 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' Oct 29 00:45:36.515931 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 00:45:36.516107 systemd[1]: Finished modprobe@fuse.service. Oct 29 00:45:36.516000 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' Oct 29 00:45:36.516000 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' Oct 29 00:45:36.517211 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:45:36.517378 systemd[1]: Finished modprobe@loop.service. Oct 29 00:45:36.517000 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' Oct 29 00:45:36.517000 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' Oct 29 00:45:36.518555 systemd[1]: Finished systemd-modules-load.service. Oct 29 00:45:36.519000 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' Oct 29 00:45:36.519768 systemd[1]: Finished systemd-network-generator.service. Oct 29 00:45:36.519000 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' Oct 29 00:45:36.521008 systemd[1]: Finished systemd-remount-fs.service. Oct 29 00:45:36.520000 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' Oct 29 00:45:36.522327 systemd[1]: Reached target network-pre.target. Oct 29 00:45:36.524264 systemd[1]: Mounting sys-fs-fuse-connections.mount... Oct 29 00:45:36.526230 systemd[1]: Mounting sys-kernel-config.mount... Oct 29 00:45:36.526945 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 29 00:45:36.528598 systemd[1]: Starting systemd-hwdb-update.service... Oct 29 00:45:36.530664 systemd[1]: Starting systemd-journal-flush.service... Oct 29 00:45:36.531654 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:45:36.532737 systemd[1]: Starting systemd-random-seed.service... Oct 29 00:45:36.533728 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Oct 29 00:45:36.534775 systemd[1]: Starting systemd-sysctl.service... Oct 29 00:45:36.536478 systemd[1]: Starting systemd-sysusers.service... Oct 29 00:45:36.540349 systemd-journald[1035]: Time spent on flushing to /var/log/journal/1a02d2882adc4882b60c6c132b8102de is 19.344ms for 922 entries. Oct 29 00:45:36.540349 systemd-journald[1035]: System Journal (/var/log/journal/1a02d2882adc4882b60c6c132b8102de) is 8.0M, max 195.6M, 187.6M free. Oct 29 00:45:36.570008 systemd-journald[1035]: Received client request to flush runtime journal. Oct 29 00:45:36.542000 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' Oct 29 00:45:36.549000 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' Oct 29 00:45:36.555000 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' Oct 29 00:45:36.559000 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' Oct 29 00:45:36.540511 systemd[1]: Finished systemd-udev-trigger.service. Oct 29 00:45:36.570485 udevadm[1081]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 29 00:45:36.543175 systemd[1]: Mounted sys-fs-fuse-connections.mount. Oct 29 00:45:36.544228 systemd[1]: Mounted sys-kernel-config.mount. Oct 29 00:45:36.546313 systemd[1]: Starting systemd-udev-settle.service... Oct 29 00:45:36.548903 systemd[1]: Finished systemd-random-seed.service. Oct 29 00:45:36.550137 systemd[1]: Reached target first-boot-complete.target. Oct 29 00:45:36.555770 systemd[1]: Finished systemd-sysctl.service. Oct 29 00:45:36.559798 systemd[1]: Finished systemd-sysusers.service. Oct 29 00:45:36.561638 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Oct 29 00:45:36.570985 systemd[1]: Finished systemd-journal-flush.service. Oct 29 00:45:36.571000 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' Oct 29 00:45:36.581675 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Oct 29 00:45:36.582000 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' Oct 29 00:45:36.908329 systemd[1]: Finished systemd-hwdb-update.service. Oct 29 00:45:36.908000 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' Oct 29 00:45:36.910375 systemd[1]: Starting systemd-udevd.service... Oct 29 00:45:36.925892 systemd-udevd[1092]: Using default interface naming scheme 'v252'. Oct 29 00:45:36.937633 systemd[1]: Started systemd-udevd.service. Oct 29 00:45:36.937000 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' Oct 29 00:45:36.939977 systemd[1]: Starting systemd-networkd.service... Oct 29 00:45:36.945547 systemd[1]: Starting systemd-userdbd.service... Oct 29 00:45:36.971027 systemd[1]: Found device dev-ttyAMA0.device. Oct 29 00:45:36.982677 systemd[1]: Started systemd-userdbd.service. Oct 29 00:45:36.982000 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' Oct 29 00:45:37.021677 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Oct 29 00:45:37.026896 systemd-networkd[1100]: lo: Link UP Oct 29 00:45:37.026908 systemd-networkd[1100]: lo: Gained carrier Oct 29 00:45:37.027278 systemd-networkd[1100]: Enumeration completed Oct 29 00:45:37.027389 systemd-networkd[1100]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 00:45:37.027389 systemd[1]: Started systemd-networkd.service. Oct 29 00:45:37.027000 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' Oct 29 00:45:37.028574 systemd-networkd[1100]: eth0: Link UP Oct 29 00:45:37.028583 systemd-networkd[1100]: eth0: Gained carrier Oct 29 00:45:37.034262 systemd[1]: Finished systemd-udev-settle.service. Oct 29 00:45:37.034000 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' Oct 29 00:45:37.036271 systemd[1]: Starting lvm2-activation-early.service... Oct 29 00:45:37.044669 lvm[1127]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 00:45:37.048937 systemd-networkd[1100]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 29 00:45:37.071723 systemd[1]: Finished lvm2-activation-early.service. Oct 29 00:45:37.071000 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' Oct 29 00:45:37.072688 systemd[1]: Reached target cryptsetup.target. Oct 29 00:45:37.074609 systemd[1]: Starting lvm2-activation.service... Oct 29 00:45:37.078218 lvm[1129]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 00:45:37.109805 systemd[1]: Finished lvm2-activation.service. Oct 29 00:45:37.109000 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' Oct 29 00:45:37.110657 systemd[1]: Reached target local-fs-pre.target. Oct 29 00:45:37.111519 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 29 00:45:37.111550 systemd[1]: Reached target local-fs.target. Oct 29 00:45:37.112266 systemd[1]: Reached target machines.target. Oct 29 00:45:37.114233 systemd[1]: Starting ldconfig.service... Oct 29 00:45:37.115157 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.115231 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). Oct 29 00:45:37.116383 systemd[1]: Starting systemd-boot-update.service... Oct 29 00:45:37.118258 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Oct 29 00:45:37.120333 systemd[1]: Starting systemd-machine-id-commit.service... Oct 29 00:45:37.122480 systemd[1]: Starting systemd-sysext.service... Oct 29 00:45:37.123864 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1132 (bootctl) Oct 29 00:45:37.125028 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Oct 29 00:45:37.130758 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Oct 29 00:45:37.130000 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' Oct 29 00:45:37.133847 systemd[1]: Unmounting usr-share-oem.mount... Oct 29 00:45:37.138326 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 29 00:45:37.138578 systemd[1]: Unmounted usr-share-oem.mount. Oct 29 00:45:37.155820 kernel: loop0: detected capacity change from 0 to 207008 Oct 29 00:45:37.193613 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 00:45:37.194312 systemd[1]: Finished systemd-machine-id-commit.service. Oct 29 00:45:37.194000 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' Oct 29 00:45:37.202799 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 29 00:45:37.210598 systemd-fsck[1144]: fsck.fat 4.2 (2021-01-31) Oct 29 00:45:37.210598 systemd-fsck[1144]: /dev/vda1: 236 files, 117310/258078 clusters Oct 29 00:45:37.212247 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Oct 29 00:45:37.213000 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' Oct 29 00:45:37.215228 systemd[1]: Mounting boot.mount... Oct 29 00:45:37.220816 kernel: loop1: detected capacity change from 0 to 207008 Oct 29 00:45:37.225161 systemd[1]: Mounted boot.mount. Oct 29 00:45:37.231706 (sd-sysext)[1151]: Using extensions 'kubernetes'. Oct 29 00:45:37.232248 (sd-sysext)[1151]: Merged extensions into '/usr'. Oct 29 00:45:37.234711 systemd[1]: Finished systemd-boot-update.service. Oct 29 00:45:37.234000 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' Oct 29 00:45:37.249100 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.250472 systemd[1]: Starting modprobe@dm_mod.service... Oct 29 00:45:37.252429 systemd[1]: Starting modprobe@efi_pstore.service... Oct 29 00:45:37.254343 systemd[1]: Starting modprobe@loop.service... Oct 29 00:45:37.255087 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.255227 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). Oct 29 00:45:37.255930 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:45:37.256093 systemd[1]: Finished modprobe@dm_mod.service. Oct 29 00:45:37.256000 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' Oct 29 00:45:37.256000 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' Oct 29 00:45:37.257713 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:45:37.257880 systemd[1]: Finished modprobe@efi_pstore.service. Oct 29 00:45:37.258000 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' Oct 29 00:45:37.258000 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' Oct 29 00:45:37.259354 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:45:37.259518 systemd[1]: Finished modprobe@loop.service. Oct 29 00:45:37.259000 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' Oct 29 00:45:37.259000 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' Oct 29 00:45:37.260881 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:45:37.260979 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.311352 ldconfig[1131]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 29 00:45:37.314894 systemd[1]: Finished ldconfig.service. Oct 29 00:45:37.314000 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' Oct 29 00:45:37.479843 systemd[1]: Mounting usr-share-oem.mount... Oct 29 00:45:37.485132 systemd[1]: Mounted usr-share-oem.mount. Oct 29 00:45:37.487000 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' Oct 29 00:45:37.487076 systemd[1]: Finished systemd-sysext.service. Oct 29 00:45:37.489302 systemd[1]: Starting ensure-sysext.service... Oct 29 00:45:37.491293 systemd[1]: Starting systemd-tmpfiles-setup.service... Oct 29 00:45:37.496088 systemd[1]: Reloading. Oct 29 00:45:37.500382 systemd-tmpfiles[1168]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 00:45:37.501722 systemd-tmpfiles[1168]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 29 00:45:37.503144 systemd-tmpfiles[1168]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 00:45:37.531809 /usr/lib/systemd/system-generators/torcx-generator[1187]: time="2025-10-29T00:45:37Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.8 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.8 /var/lib/torcx/store]" Oct 29 00:45:37.531839 /usr/lib/systemd/system-generators/torcx-generator[1187]: time="2025-10-29T00:45:37Z" level=info msg="torcx already run" Oct 29 00:45:37.597712 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Oct 29 00:45:37.597736 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Oct 29 00:45:37.614715 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. Oct 29 00:45:37.656727 systemd[1]: Finished systemd-tmpfiles-setup.service. Oct 29 00:45:37.656000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:37.660601 systemd[1]: Starting audit-rules.service... Oct 29 00:45:37.662400 systemd[1]: Starting clean-ca-certificates.service... Oct 29 00:45:37.664536 systemd[1]: Starting systemd-journal-catalog-update.service... Oct 29 00:45:37.667006 systemd[1]: Starting systemd-resolved.service... Oct 29 00:45:37.669074 systemd[1]: Starting systemd-timesyncd.service... Oct 29 00:45:37.671519 systemd[1]: Starting systemd-update-utmp.service... Oct 29 00:45:37.673423 systemd[1]: Finished clean-ca-certificates.service. Oct 29 00:45:37.674000 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' Oct 29 00:45:37.675000 audit[1246]: SYSTEM_BOOT pid=1246 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' Oct 29 00:45:37.676633 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 29 00:45:37.680020 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.681342 systemd[1]: Starting modprobe@dm_mod.service... Oct 29 00:45:37.683256 systemd[1]: Starting modprobe@efi_pstore.service... Oct 29 00:45:37.685166 systemd[1]: Starting modprobe@loop.service... Oct 29 00:45:37.685885 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.686050 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). Oct 29 00:45:37.686228 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 29 00:45:37.687508 systemd[1]: Finished systemd-journal-catalog-update.service. Oct 29 00:45:37.688000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:37.689062 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:45:37.689219 systemd[1]: Finished modprobe@dm_mod.service. Oct 29 00:45:37.689000 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' Oct 29 00:45:37.689000 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' Oct 29 00:45:37.690595 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:45:37.690728 systemd[1]: Finished modprobe@loop.service. Oct 29 00:45:37.691000 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' Oct 29 00:45:37.691000 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' Oct 29 00:45:37.692391 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.693727 systemd[1]: Starting systemd-update-done.service... Oct 29 00:45:37.695560 systemd[1]: Finished systemd-update-utmp.service. Oct 29 00:45:37.696000 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' Oct 29 00:45:37.697000 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' Oct 29 00:45:37.697000 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' Oct 29 00:45:37.696969 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:45:37.697141 systemd[1]: Finished modprobe@efi_pstore.service. Oct 29 00:45:37.700000 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' Oct 29 00:45:37.700133 systemd[1]: Finished systemd-update-done.service. Oct 29 00:45:37.701499 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.702597 systemd[1]: Starting modprobe@dm_mod.service... Oct 29 00:45:37.704727 systemd[1]: Starting modprobe@efi_pstore.service... Oct 29 00:45:37.706731 systemd[1]: Starting modprobe@loop.service... Oct 29 00:45:37.707566 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.707715 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). Oct 29 00:45:37.707828 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 29 00:45:37.708687 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:45:37.708843 systemd[1]: Finished modprobe@efi_pstore.service. Oct 29 00:45:37.708000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:37.708000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Oct 29 00:45:37.710250 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:45:37.711000 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' Oct 29 00:45:37.711000 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' Oct 29 00:45:37.710386 systemd[1]: Finished modprobe@loop.service. Oct 29 00:45:37.711547 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:45:37.713716 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.715185 systemd[1]: Starting modprobe@drm.service... Oct 29 00:45:37.717402 systemd[1]: Starting modprobe@efi_pstore.service... Oct 29 00:45:37.719554 systemd[1]: Starting modprobe@loop.service... Oct 29 00:45:37.720487 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.720646 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). Oct 29 00:45:37.722179 systemd[1]: Starting systemd-networkd-wait-online.service... Oct 29 00:45:37.723012 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 29 00:45:37.724567 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:45:37.724725 systemd[1]: Finished modprobe@dm_mod.service. Oct 29 00:45:37.725000 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' Oct 29 00:45:37.725000 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' Oct 29 00:45:37.726032 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 00:45:37.726174 systemd[1]: Finished modprobe@drm.service. Oct 29 00:45:37.726000 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' Oct 29 00:45:37.726000 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' Oct 29 00:45:37.727353 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:45:37.727502 systemd[1]: Finished modprobe@efi_pstore.service. Oct 29 00:45:37.728000 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' Oct 29 00:45:37.728000 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' Oct 29 00:45:37.728798 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:45:37.729000 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' Oct 29 00:45:37.729987 systemd[1]: Finished ensure-sysext.service. Oct 29 00:45:37.731059 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:45:37.731243 systemd[1]: Finished modprobe@loop.service. Oct 29 00:45:37.734000 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' Oct 29 00:45:37.734000 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' Oct 29 00:45:37.735095 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.736000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 29 00:45:37.736000 audit[1271]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffceabf980 a2=420 a3=0 items=0 ppid=1234 pid=1271 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) Oct 29 00:45:37.736000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Oct 29 00:45:37.737279 augenrules[1271]: No rules Oct 29 00:45:37.738075 systemd[1]: Finished audit-rules.service. Oct 29 00:45:37.746439 systemd[1]: Started systemd-timesyncd.service. Oct 29 00:45:37.747372 systemd-timesyncd[1245]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 29 00:45:37.747672 systemd[1]: Reached target time-set.target. Oct 29 00:45:37.747777 systemd-timesyncd[1245]: Initial clock synchronization to Wed 2025-10-29 00:45:37.751599 UTC. Oct 29 00:45:37.747822 systemd-resolved[1242]: Positive Trust Anchors: Oct 29 00:45:37.747829 systemd-resolved[1242]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 00:45:37.747857 systemd-resolved[1242]: 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 Oct 29 00:45:37.756283 systemd-resolved[1242]: Defaulting to hostname 'linux'. Oct 29 00:45:37.757720 systemd[1]: Started systemd-resolved.service. Oct 29 00:45:37.758607 systemd[1]: Reached target network.target. Oct 29 00:45:37.759368 systemd[1]: Reached target nss-lookup.target. Oct 29 00:45:37.760100 systemd[1]: Reached target sysinit.target. Oct 29 00:45:37.760864 systemd[1]: Started motdgen.path. Oct 29 00:45:37.761508 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Oct 29 00:45:37.762715 systemd[1]: Started logrotate.timer. Oct 29 00:45:37.763542 systemd[1]: Started mdadm.timer. Oct 29 00:45:37.764181 systemd[1]: Started systemd-tmpfiles-clean.timer. Oct 29 00:45:37.764951 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 29 00:45:37.764982 systemd[1]: Reached target paths.target. Oct 29 00:45:37.765642 systemd[1]: Reached target timers.target. Oct 29 00:45:37.766675 systemd[1]: Listening on dbus.socket. Oct 29 00:45:37.768537 systemd[1]: Starting docker.socket... Oct 29 00:45:37.770234 systemd[1]: Listening on sshd.socket. Oct 29 00:45:37.770966 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). Oct 29 00:45:37.771290 systemd[1]: Listening on docker.socket. Oct 29 00:45:37.772005 systemd[1]: Reached target sockets.target. Oct 29 00:45:37.772689 systemd[1]: Reached target basic.target. Oct 29 00:45:37.773558 systemd[1]: System is tainted: cgroupsv1 Oct 29 00:45:37.773607 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.773629 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Oct 29 00:45:37.774614 systemd[1]: Starting containerd.service... Oct 29 00:45:37.776354 systemd[1]: Starting dbus.service... Oct 29 00:45:37.778034 systemd[1]: Starting enable-oem-cloudinit.service... Oct 29 00:45:37.779909 systemd[1]: Starting extend-filesystems.service... Oct 29 00:45:37.780964 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Oct 29 00:45:37.783047 jq[1293]: false Oct 29 00:45:37.782156 systemd[1]: Starting motdgen.service... Oct 29 00:45:37.784030 systemd[1]: Starting ssh-key-proc-cmdline.service... Oct 29 00:45:37.785960 systemd[1]: Starting sshd-keygen.service... Oct 29 00:45:37.788356 systemd[1]: Starting systemd-logind.service... Oct 29 00:45:37.789105 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Oct 29 00:45:37.789180 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 29 00:45:37.790357 systemd[1]: Starting update-engine.service... Oct 29 00:45:37.793002 systemd[1]: Starting update-ssh-keys-after-ignition.service... Oct 29 00:45:37.795455 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 29 00:45:37.800974 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Oct 29 00:45:37.801361 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 00:45:37.806572 systemd[1]: Finished ssh-key-proc-cmdline.service. Oct 29 00:45:37.807968 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 00:45:37.810216 extend-filesystems[1294]: Found loop1 Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda1 Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda2 Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda3 Oct 29 00:45:37.810216 extend-filesystems[1294]: Found usr Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda4 Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda6 Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda7 Oct 29 00:45:37.810216 extend-filesystems[1294]: Found vda9 Oct 29 00:45:37.810216 extend-filesystems[1294]: Checking size of /dev/vda9 Oct 29 00:45:37.808183 systemd[1]: Finished motdgen.service. Oct 29 00:45:37.818472 dbus-daemon[1292]: [system] SELinux support is enabled Oct 29 00:45:37.818963 systemd[1]: Started dbus.service. Oct 29 00:45:37.831123 jq[1308]: true Oct 29 00:45:37.822595 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). Oct 29 00:45:37.831429 jq[1321]: true Oct 29 00:45:37.822618 systemd[1]: Reached target system-config.target. Oct 29 00:45:37.823604 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 29 00:45:37.823617 systemd[1]: Reached target user-config.target. Oct 29 00:45:37.842808 extend-filesystems[1294]: Resized partition /dev/vda9 Oct 29 00:45:37.844521 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Oct 29 00:45:37.844548 extend-filesystems[1334]: resize2fs 1.46.5 (30-Dec-2021) Oct 29 00:45:37.860472 systemd-logind[1302]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 00:45:37.860723 systemd-logind[1302]: New seat seat0. Oct 29 00:45:37.862764 systemd[1]: Started systemd-logind.service. Oct 29 00:45:37.867598 update_engine[1305]: I1029 00:45:37.867395 1305 main.cc:92] Flatcar Update Engine starting Oct 29 00:45:37.869803 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Oct 29 00:45:37.871114 systemd[1]: Started update-engine.service. Oct 29 00:45:37.871207 update_engine[1305]: I1029 00:45:37.871148 1305 update_check_scheduler.cc:74] Next update check in 10m24s Oct 29 00:45:37.874729 systemd[1]: Started locksmithd.service. Oct 29 00:45:37.883137 extend-filesystems[1334]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 29 00:45:37.883137 extend-filesystems[1334]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 29 00:45:37.883137 extend-filesystems[1334]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Oct 29 00:45:37.887913 extend-filesystems[1294]: Resized filesystem in /dev/vda9 Oct 29 00:45:37.891951 bash[1346]: Updated "/home/core/.ssh/authorized_keys" Oct 29 00:45:37.883824 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 00:45:37.892073 env[1318]: time="2025-10-29T00:45:37.891458880Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Oct 29 00:45:37.884056 systemd[1]: Finished extend-filesystems.service. Oct 29 00:45:37.887056 systemd[1]: Finished update-ssh-keys-after-ignition.service. Oct 29 00:45:37.909831 env[1318]: time="2025-10-29T00:45:37.909791160Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 29 00:45:37.910058 env[1318]: time="2025-10-29T00:45:37.910039120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 29 00:45:37.911537 env[1318]: time="2025-10-29T00:45:37.911504200Z" 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.192-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 29 00:45:37.911537 env[1318]: time="2025-10-29T00:45:37.911536160Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 29 00:45:37.911883 env[1318]: time="2025-10-29T00:45:37.911860200Z" 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 Oct 29 00:45:37.911934 env[1318]: time="2025-10-29T00:45:37.911884200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 29 00:45:37.911934 env[1318]: time="2025-10-29T00:45:37.911899000Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Oct 29 00:45:37.911934 env[1318]: time="2025-10-29T00:45:37.911909640Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 29 00:45:37.912022 env[1318]: time="2025-10-29T00:45:37.912003440Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 29 00:45:37.912291 env[1318]: time="2025-10-29T00:45:37.912270920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 29 00:45:37.912480 env[1318]: time="2025-10-29T00:45:37.912429000Z" 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 Oct 29 00:45:37.912515 env[1318]: time="2025-10-29T00:45:37.912481360Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 29 00:45:37.912559 env[1318]: time="2025-10-29T00:45:37.912542680Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Oct 29 00:45:37.912588 env[1318]: time="2025-10-29T00:45:37.912563160Z" level=info msg="metadata content store policy set" policy=shared Oct 29 00:45:37.915738 env[1318]: time="2025-10-29T00:45:37.915699800Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 29 00:45:37.915816 env[1318]: time="2025-10-29T00:45:37.915740800Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 29 00:45:37.915816 env[1318]: time="2025-10-29T00:45:37.915756840Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 29 00:45:37.915816 env[1318]: time="2025-10-29T00:45:37.915797400Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.915816 env[1318]: time="2025-10-29T00:45:37.915811840Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.915899 env[1318]: time="2025-10-29T00:45:37.915825120Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.915899 env[1318]: time="2025-10-29T00:45:37.915837640Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.916245 env[1318]: time="2025-10-29T00:45:37.916210160Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.916245 env[1318]: time="2025-10-29T00:45:37.916238760Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.916327 env[1318]: time="2025-10-29T00:45:37.916270800Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.916327 env[1318]: time="2025-10-29T00:45:37.916284920Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.916327 env[1318]: time="2025-10-29T00:45:37.916298240Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 29 00:45:37.916437 env[1318]: time="2025-10-29T00:45:37.916418120Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 29 00:45:37.916559 env[1318]: time="2025-10-29T00:45:37.916543720Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 29 00:45:37.916989 env[1318]: time="2025-10-29T00:45:37.916905200Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 29 00:45:37.916989 env[1318]: time="2025-10-29T00:45:37.916987000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917060 env[1318]: time="2025-10-29T00:45:37.917004560Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 29 00:45:37.917170 env[1318]: time="2025-10-29T00:45:37.917154680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917204 env[1318]: time="2025-10-29T00:45:37.917174240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917204 env[1318]: time="2025-10-29T00:45:37.917189080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917244 env[1318]: time="2025-10-29T00:45:37.917210240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917244 env[1318]: time="2025-10-29T00:45:37.917223560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917244 env[1318]: time="2025-10-29T00:45:37.917236080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917302 env[1318]: time="2025-10-29T00:45:37.917247640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917302 env[1318]: time="2025-10-29T00:45:37.917259360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917302 env[1318]: time="2025-10-29T00:45:37.917272680Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 29 00:45:37.917485 env[1318]: time="2025-10-29T00:45:37.917451360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917512 env[1318]: time="2025-10-29T00:45:37.917490680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917512 env[1318]: time="2025-10-29T00:45:37.917505880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917550 env[1318]: time="2025-10-29T00:45:37.917517160Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 29 00:45:37.917550 env[1318]: time="2025-10-29T00:45:37.917532920Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Oct 29 00:45:37.917550 env[1318]: time="2025-10-29T00:45:37.917543680Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 29 00:45:37.917604 env[1318]: time="2025-10-29T00:45:37.917559720Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Oct 29 00:45:37.917625 env[1318]: time="2025-10-29T00:45:37.917598960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 29 00:45:37.917889 env[1318]: time="2025-10-29T00:45:37.917833480Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 29 00:45:37.918553 env[1318]: time="2025-10-29T00:45:37.917896800Z" level=info msg="Connect containerd service" Oct 29 00:45:37.918553 env[1318]: time="2025-10-29T00:45:37.917933400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 29 00:45:37.918631 env[1318]: time="2025-10-29T00:45:37.918597920Z" 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" Oct 29 00:45:37.918959 env[1318]: time="2025-10-29T00:45:37.918929080Z" level=info msg="Start subscribing containerd event" Oct 29 00:45:37.919011 env[1318]: time="2025-10-29T00:45:37.918985040Z" level=info msg="Start recovering state" Oct 29 00:45:37.919094 env[1318]: time="2025-10-29T00:45:37.919078360Z" level=info msg="Start event monitor" Oct 29 00:45:37.919130 env[1318]: time="2025-10-29T00:45:37.919108320Z" level=info msg="Start snapshots syncer" Oct 29 00:45:37.919130 env[1318]: time="2025-10-29T00:45:37.919119120Z" level=info msg="Start cni network conf syncer for default" Oct 29 00:45:37.919130 env[1318]: time="2025-10-29T00:45:37.919127760Z" level=info msg="Start streaming server" Oct 29 00:45:37.919618 env[1318]: time="2025-10-29T00:45:37.919597360Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 29 00:45:37.919671 env[1318]: time="2025-10-29T00:45:37.919644400Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 29 00:45:37.921022 env[1318]: time="2025-10-29T00:45:37.919702120Z" level=info msg="containerd successfully booted in 0.029055s" Oct 29 00:45:37.919811 systemd[1]: Started containerd.service. Oct 29 00:45:37.933028 locksmithd[1348]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 00:45:38.841900 systemd-networkd[1100]: eth0: Gained IPv6LL Oct 29 00:45:38.843593 systemd[1]: Finished systemd-networkd-wait-online.service. Oct 29 00:45:38.844774 systemd[1]: Reached target network-online.target. Oct 29 00:45:38.847129 systemd[1]: Starting kubelet.service... Oct 29 00:45:39.213611 sshd_keygen[1309]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 29 00:45:39.231121 systemd[1]: Finished sshd-keygen.service. Oct 29 00:45:39.233363 systemd[1]: Starting issuegen.service... Oct 29 00:45:39.238102 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 00:45:39.238304 systemd[1]: Finished issuegen.service. Oct 29 00:45:39.240507 systemd[1]: Starting systemd-user-sessions.service... Oct 29 00:45:39.246394 systemd[1]: Finished systemd-user-sessions.service. Oct 29 00:45:39.248851 systemd[1]: Started getty@tty1.service. Oct 29 00:45:39.250749 systemd[1]: Started serial-getty@ttyAMA0.service. Oct 29 00:45:39.252017 systemd[1]: Reached target getty.target. Oct 29 00:45:39.430670 systemd[1]: Started kubelet.service. Oct 29 00:45:39.431999 systemd[1]: Reached target multi-user.target. Oct 29 00:45:39.434397 systemd[1]: Starting systemd-update-utmp-runlevel.service... Oct 29 00:45:39.440055 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 00:45:39.440262 systemd[1]: Finished systemd-update-utmp-runlevel.service. Oct 29 00:45:39.441310 systemd[1]: Startup finished in 4.574s (kernel) + 4.888s (userspace) = 9.462s. Oct 29 00:45:39.817626 kubelet[1386]: E1029 00:45:39.817559 1386 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" Oct 29 00:45:39.819173 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 29 00:45:39.819301 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 29 00:45:43.107725 systemd[1]: Created slice system-sshd.slice. Oct 29 00:45:43.108908 systemd[1]: Started sshd@0-10.0.0.129:22-10.0.0.1:56044.service. Oct 29 00:45:43.160413 sshd[1396]: Accepted publickey for core from 10.0.0.1 port 56044 ssh2: RSA SHA256:pYB66aLjdXbBwWKgZ+jLlT0UVkYzJYHWsaqS3PI+gyo Oct 29 00:45:43.162655 sshd[1396]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:45:43.172501 systemd[1]: Created slice user-500.slice. Oct 29 00:45:43.173460 systemd[1]: Starting user-runtime-dir@500.service... Oct 29 00:45:43.178770 systemd-logind[1302]: New session 1 of user core. Oct 29 00:45:43.186610 systemd[1]: Finished user-runtime-dir@500.service. Oct 29 00:45:43.187888 systemd[1]: Starting user@500.service... Oct 29 00:45:43.191446 (systemd)[1401]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:45:43.258177 systemd[1401]: Queued start job for default target default.target. Oct 29 00:45:43.258394 systemd[1401]: Reached target paths.target. Oct 29 00:45:43.258421 systemd[1401]: Reached target sockets.target. Oct 29 00:45:43.258432 systemd[1401]: Reached target timers.target. Oct 29 00:45:43.258442 systemd[1401]: Reached target basic.target. Oct 29 00:45:43.258485 systemd[1401]: Reached target default.target. Oct 29 00:45:43.258507 systemd[1401]: Startup finished in 61ms. Oct 29 00:45:43.258583 systemd[1]: Started user@500.service. Oct 29 00:45:43.259573 systemd[1]: Started session-1.scope. Oct 29 00:45:43.310211 systemd[1]: Started sshd@1-10.0.0.129:22-10.0.0.1:56058.service. Oct 29 00:45:43.354462 sshd[1410]: Accepted publickey for core from 10.0.0.1 port 56058 ssh2: RSA SHA256:pYB66aLjdXbBwWKgZ+jLlT0UVkYzJYHWsaqS3PI+gyo Oct 29 00:45:43.356004 sshd[1410]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:45:43.359491 systemd-logind[1302]: New session 2 of user core. Oct 29 00:45:43.360307 systemd[1]: Started session-2.scope. Oct 29 00:45:43.416335 sshd[1410]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:43.419323 systemd[1]: Started sshd@2-10.0.0.129:22-10.0.0.1:56074.service. Oct 29 00:45:43.420626 systemd[1]: sshd@1-10.0.0.129:22-10.0.0.1:56058.service: Deactivated successfully. Oct 29 00:45:43.421648 systemd-logind[1302]: Session 2 logged out. Waiting for processes to exit. Oct 29 00:45:43.421688 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 00:45:43.422695 systemd-logind[1302]: Removed session 2. Oct 29 00:45:43.462488 sshd[1415]: Accepted publickey for core from 10.0.0.1 port 56074 ssh2: RSA SHA256:pYB66aLjdXbBwWKgZ+jLlT0UVkYzJYHWsaqS3PI+gyo Oct 29 00:45:43.463750 sshd[1415]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:45:43.467854 systemd-logind[1302]: New session 3 of user core. Oct 29 00:45:43.468181 systemd[1]: Started session-3.scope. Oct 29 00:45:43.519312 sshd[1415]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:43.521539 systemd[1]: Started sshd@3-10.0.0.129:22-10.0.0.1:56086.service. Oct 29 00:45:43.522045 systemd[1]: sshd@2-10.0.0.129:22-10.0.0.1:56074.service: Deactivated successfully. Oct 29 00:45:43.523322 systemd-logind[1302]: Session 3 logged out. Waiting for processes to exit. Oct 29 00:45:43.523411 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 00:45:43.525596 systemd-logind[1302]: Removed session 3. Oct 29 00:45:43.566948 sshd[1422]: Accepted publickey for core from 10.0.0.1 port 56086 ssh2: RSA SHA256:pYB66aLjdXbBwWKgZ+jLlT0UVkYzJYHWsaqS3PI+gyo Oct 29 00:45:43.568216 sshd[1422]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:45:43.573345 systemd[1]: Started session-4.scope. Oct 29 00:45:43.573881 systemd-logind[1302]: New session 4 of user core. Oct 29 00:45:43.639271 sshd[1422]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:43.641241 systemd[1]: Started sshd@4-10.0.0.129:22-10.0.0.1:56092.service. Oct 29 00:45:43.644702 systemd[1]: sshd@3-10.0.0.129:22-10.0.0.1:56086.service: Deactivated successfully. Oct 29 00:45:43.645385 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 00:45:43.646238 systemd-logind[1302]: Session 4 logged out. Waiting for processes to exit. Oct 29 00:45:43.648778 systemd-logind[1302]: Removed session 4. Oct 29 00:45:43.690467 sshd[1429]: Accepted publickey for core from 10.0.0.1 port 56092 ssh2: RSA SHA256:pYB66aLjdXbBwWKgZ+jLlT0UVkYzJYHWsaqS3PI+gyo Oct 29 00:45:43.691680 sshd[1429]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:45:43.699835 systemd-logind[1302]: New session 5 of user core. Oct 29 00:45:43.700696 systemd[1]: Started session-5.scope. Oct 29 00:45:43.765912 sudo[1435]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 29 00:45:43.766504 sudo[1435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 00:45:43.778953 systemd[1]: Starting coreos-metadata.service... Oct 29 00:45:43.789324 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 29 00:45:43.789554 systemd[1]: Finished coreos-metadata.service. Oct 29 00:45:44.216116 systemd[1]: Stopped kubelet.service. Oct 29 00:45:44.218239 systemd[1]: Starting kubelet.service... Oct 29 00:45:44.240988 systemd[1]: Reloading. Oct 29 00:45:44.293995 /usr/lib/systemd/system-generators/torcx-generator[1497]: time="2025-10-29T00:45:44Z" 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.8 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.8 /var/lib/torcx/store]" Oct 29 00:45:44.294029 /usr/lib/systemd/system-generators/torcx-generator[1497]: time="2025-10-29T00:45:44Z" level=info msg="torcx already run" Oct 29 00:45:44.507612 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Oct 29 00:45:44.507640 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Oct 29 00:45:44.524960 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. Oct 29 00:45:44.581409 systemd[1]: Started kubelet.service. Oct 29 00:45:44.584342 systemd[1]: Stopping kubelet.service... Oct 29 00:45:44.584862 systemd[1]: kubelet.service: Deactivated successfully. Oct 29 00:45:44.585101 systemd[1]: Stopped kubelet.service. Oct 29 00:45:44.587110 systemd[1]: Starting kubelet.service... Oct 29 00:45:44.676466 systemd[1]: Started kubelet.service. Oct 29 00:45:44.715090 kubelet[1556]: 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. Oct 29 00:45:44.715090 kubelet[1556]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 29 00:45:44.715090 kubelet[1556]: 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. Oct 29 00:45:44.715454 kubelet[1556]: I1029 00:45:44.715151 1556 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Oct 29 00:45:45.630453 kubelet[1556]: I1029 00:45:45.630410 1556 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Oct 29 00:45:45.630587 kubelet[1556]: I1029 00:45:45.630577 1556 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 29 00:45:45.630962 kubelet[1556]: I1029 00:45:45.630944 1556 server.go:954] "Client rotation is on, will bootstrap in background" Oct 29 00:45:45.654976 kubelet[1556]: I1029 00:45:45.654937 1556 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 29 00:45:45.661631 kubelet[1556]: E1029 00:45:45.661596 1556 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Oct 29 00:45:45.661709 kubelet[1556]: I1029 00:45:45.661636 1556 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Oct 29 00:45:45.664278 kubelet[1556]: I1029 00:45:45.664247 1556 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 29 00:45:45.666090 kubelet[1556]: I1029 00:45:45.666005 1556 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 29 00:45:45.666232 kubelet[1556]: I1029 00:45:45.666054 1556 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.129","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":1} Oct 29 00:45:45.666336 kubelet[1556]: I1029 00:45:45.666301 1556 topology_manager.go:138] "Creating topology manager with none policy" Oct 29 00:45:45.666336 kubelet[1556]: I1029 00:45:45.666311 1556 container_manager_linux.go:304] "Creating device plugin manager" Oct 29 00:45:45.666550 kubelet[1556]: I1029 00:45:45.666489 1556 state_mem.go:36] "Initialized new in-memory state store" Oct 29 00:45:45.672121 kubelet[1556]: I1029 00:45:45.672097 1556 kubelet.go:446] "Attempting to sync node with API server" Oct 29 00:45:45.672121 kubelet[1556]: I1029 00:45:45.672124 1556 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 29 00:45:45.672232 kubelet[1556]: I1029 00:45:45.672142 1556 kubelet.go:352] "Adding apiserver pod source" Oct 29 00:45:45.672232 kubelet[1556]: I1029 00:45:45.672152 1556 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 29 00:45:45.672508 kubelet[1556]: E1029 00:45:45.672365 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:45.672508 kubelet[1556]: E1029 00:45:45.672407 1556 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:45.675079 kubelet[1556]: I1029 00:45:45.675058 1556 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Oct 29 00:45:45.676188 kubelet[1556]: I1029 00:45:45.676167 1556 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Oct 29 00:45:45.676292 kubelet[1556]: W1029 00:45:45.676280 1556 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 29 00:45:45.677112 kubelet[1556]: I1029 00:45:45.677081 1556 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 29 00:45:45.677166 kubelet[1556]: I1029 00:45:45.677119 1556 server.go:1287] "Started kubelet" Oct 29 00:45:45.677271 kubelet[1556]: I1029 00:45:45.677233 1556 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Oct 29 00:45:45.678190 kubelet[1556]: I1029 00:45:45.678170 1556 server.go:479] "Adding debug handlers to kubelet server" Oct 29 00:45:45.683776 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Oct 29 00:45:45.683996 kubelet[1556]: I1029 00:45:45.683975 1556 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 29 00:45:45.685548 kubelet[1556]: I1029 00:45:45.685484 1556 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 29 00:45:45.685919 kubelet[1556]: I1029 00:45:45.685706 1556 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 29 00:45:45.688808 kubelet[1556]: I1029 00:45:45.688653 1556 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 29 00:45:45.691341 kubelet[1556]: E1029 00:45:45.691308 1556 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Oct 29 00:45:45.691409 kubelet[1556]: I1029 00:45:45.691365 1556 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 29 00:45:45.691714 kubelet[1556]: I1029 00:45:45.691556 1556 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 29 00:45:45.691714 kubelet[1556]: I1029 00:45:45.691653 1556 reconciler.go:26] "Reconciler: start to sync state" Oct 29 00:45:45.691935 kubelet[1556]: I1029 00:45:45.691908 1556 factory.go:221] Registration of the systemd container factory successfully Oct 29 00:45:45.692053 kubelet[1556]: I1029 00:45:45.692015 1556 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 Oct 29 00:45:45.692451 kubelet[1556]: E1029 00:45:45.692413 1556 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 29 00:45:45.697888 kubelet[1556]: I1029 00:45:45.697856 1556 factory.go:221] Registration of the containerd container factory successfully Oct 29 00:45:45.725165 kubelet[1556]: E1029 00:45:45.725132 1556 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.129\" not found" node="10.0.0.129" Oct 29 00:45:45.729199 kubelet[1556]: I1029 00:45:45.729180 1556 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 29 00:45:45.729298 kubelet[1556]: I1029 00:45:45.729284 1556 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 29 00:45:45.729356 kubelet[1556]: I1029 00:45:45.729346 1556 state_mem.go:36] "Initialized new in-memory state store" Oct 29 00:45:45.792144 kubelet[1556]: E1029 00:45:45.792085 1556 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Oct 29 00:45:45.803017 kubelet[1556]: I1029 00:45:45.802993 1556 policy_none.go:49] "None policy: Start" Oct 29 00:45:45.803121 kubelet[1556]: I1029 00:45:45.803109 1556 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 29 00:45:45.803176 kubelet[1556]: I1029 00:45:45.803166 1556 state_mem.go:35] "Initializing new in-memory state store" Oct 29 00:45:45.808899 kubelet[1556]: I1029 00:45:45.808875 1556 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Oct 29 00:45:45.809029 kubelet[1556]: I1029 00:45:45.809015 1556 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 29 00:45:45.809059 kubelet[1556]: I1029 00:45:45.809031 1556 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 29 00:45:45.810220 kubelet[1556]: E1029 00:45:45.810196 1556 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 29 00:45:45.810277 kubelet[1556]: E1029 00:45:45.810251 1556 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.129\" not found" Oct 29 00:45:45.810386 kubelet[1556]: I1029 00:45:45.810343 1556 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 29 00:45:45.843849 kubelet[1556]: I1029 00:45:45.843807 1556 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Oct 29 00:45:45.844867 kubelet[1556]: I1029 00:45:45.844840 1556 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Oct 29 00:45:45.844867 kubelet[1556]: I1029 00:45:45.844869 1556 status_manager.go:227] "Starting to sync pod status with apiserver" Oct 29 00:45:45.844954 kubelet[1556]: I1029 00:45:45.844889 1556 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 29 00:45:45.844954 kubelet[1556]: I1029 00:45:45.844922 1556 kubelet.go:2382] "Starting kubelet main sync loop" Oct 29 00:45:45.845016 kubelet[1556]: E1029 00:45:45.844964 1556 kubelet.go:2406] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Oct 29 00:45:45.910460 kubelet[1556]: I1029 00:45:45.910348 1556 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.129" Oct 29 00:45:45.917266 kubelet[1556]: I1029 00:45:45.917224 1556 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.129" Oct 29 00:45:46.038223 kubelet[1556]: I1029 00:45:46.038176 1556 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 29 00:45:46.038554 env[1318]: time="2025-10-29T00:45:46.038500819Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 29 00:45:46.038879 kubelet[1556]: I1029 00:45:46.038690 1556 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 29 00:45:46.632710 kubelet[1556]: I1029 00:45:46.632653 1556 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 29 00:45:46.633138 kubelet[1556]: W1029 00:45:46.633101 1556 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Oct 29 00:45:46.633179 kubelet[1556]: W1029 00:45:46.633143 1556 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Oct 29 00:45:46.633179 kubelet[1556]: W1029 00:45:46.633165 1556 reflector.go:492] 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 Oct 29 00:45:46.673163 kubelet[1556]: I1029 00:45:46.673094 1556 apiserver.go:52] "Watching apiserver" Oct 29 00:45:46.673464 kubelet[1556]: E1029 00:45:46.673420 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:46.692593 kubelet[1556]: I1029 00:45:46.692558 1556 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 29 00:45:46.699666 kubelet[1556]: I1029 00:45:46.699405 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qnmn6\" (UniqueName: \"kubernetes.io/projected/0cd2f3f8-a867-490d-a293-77847c212eaa-kube-api-access-qnmn6\") pod \"kube-proxy-s9dcm\" (UID: \"0cd2f3f8-a867-490d-a293-77847c212eaa\") " pod="kube-system/kube-proxy-s9dcm" Oct 29 00:45:46.699666 kubelet[1556]: I1029 00:45:46.699442 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/31d8bc14-4ace-4748-810a-3f8022fdc944-run\") pod \"kube-flannel-ds-sf7xl\" (UID: \"31d8bc14-4ace-4748-810a-3f8022fdc944\") " pod="kube-flannel/kube-flannel-ds-sf7xl" Oct 29 00:45:46.699666 kubelet[1556]: I1029 00:45:46.699459 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/31d8bc14-4ace-4748-810a-3f8022fdc944-cni-plugin\") pod \"kube-flannel-ds-sf7xl\" (UID: \"31d8bc14-4ace-4748-810a-3f8022fdc944\") " pod="kube-flannel/kube-flannel-ds-sf7xl" Oct 29 00:45:46.699666 kubelet[1556]: I1029 00:45:46.699478 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/31d8bc14-4ace-4748-810a-3f8022fdc944-flannel-cfg\") pod \"kube-flannel-ds-sf7xl\" (UID: \"31d8bc14-4ace-4748-810a-3f8022fdc944\") " pod="kube-flannel/kube-flannel-ds-sf7xl" Oct 29 00:45:46.699666 kubelet[1556]: I1029 00:45:46.699495 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0cd2f3f8-a867-490d-a293-77847c212eaa-xtables-lock\") pod \"kube-proxy-s9dcm\" (UID: \"0cd2f3f8-a867-490d-a293-77847c212eaa\") " pod="kube-system/kube-proxy-s9dcm" Oct 29 00:45:46.699851 kubelet[1556]: I1029 00:45:46.699509 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0cd2f3f8-a867-490d-a293-77847c212eaa-lib-modules\") pod \"kube-proxy-s9dcm\" (UID: \"0cd2f3f8-a867-490d-a293-77847c212eaa\") " pod="kube-system/kube-proxy-s9dcm" Oct 29 00:45:46.699851 kubelet[1556]: I1029 00:45:46.699522 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/31d8bc14-4ace-4748-810a-3f8022fdc944-cni\") pod \"kube-flannel-ds-sf7xl\" (UID: \"31d8bc14-4ace-4748-810a-3f8022fdc944\") " pod="kube-flannel/kube-flannel-ds-sf7xl" Oct 29 00:45:46.699851 kubelet[1556]: I1029 00:45:46.699540 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/31d8bc14-4ace-4748-810a-3f8022fdc944-xtables-lock\") pod \"kube-flannel-ds-sf7xl\" (UID: \"31d8bc14-4ace-4748-810a-3f8022fdc944\") " pod="kube-flannel/kube-flannel-ds-sf7xl" Oct 29 00:45:46.699851 kubelet[1556]: I1029 00:45:46.699555 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xpz9s\" (UniqueName: \"kubernetes.io/projected/31d8bc14-4ace-4748-810a-3f8022fdc944-kube-api-access-xpz9s\") pod \"kube-flannel-ds-sf7xl\" (UID: \"31d8bc14-4ace-4748-810a-3f8022fdc944\") " pod="kube-flannel/kube-flannel-ds-sf7xl" Oct 29 00:45:46.699851 kubelet[1556]: I1029 00:45:46.699570 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/0cd2f3f8-a867-490d-a293-77847c212eaa-kube-proxy\") pod \"kube-proxy-s9dcm\" (UID: \"0cd2f3f8-a867-490d-a293-77847c212eaa\") " pod="kube-system/kube-proxy-s9dcm" Oct 29 00:45:46.716712 sudo[1435]: pam_unix(sudo:session): session closed for user root Oct 29 00:45:46.722549 sshd[1429]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:46.725308 systemd[1]: sshd@4-10.0.0.129:22-10.0.0.1:56092.service: Deactivated successfully. Oct 29 00:45:46.726062 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 00:45:46.727420 systemd-logind[1302]: Session 5 logged out. Waiting for processes to exit. Oct 29 00:45:46.728401 systemd-logind[1302]: Removed session 5. Oct 29 00:45:46.809085 kubelet[1556]: I1029 00:45:46.809040 1556 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" Oct 29 00:45:46.984386 kubelet[1556]: E1029 00:45:46.984234 1556 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" Oct 29 00:45:46.984626 kubelet[1556]: E1029 00:45:46.984599 1556 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" Oct 29 00:45:46.985263 env[1318]: time="2025-10-29T00:45:46.985176238Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-s9dcm,Uid:0cd2f3f8-a867-490d-a293-77847c212eaa,Namespace:kube-system,Attempt:0,}" Oct 29 00:45:46.985364 env[1318]: time="2025-10-29T00:45:46.985176478Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-sf7xl,Uid:31d8bc14-4ace-4748-810a-3f8022fdc944,Namespace:kube-flannel,Attempt:0,}" Oct 29 00:45:47.535450 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2531473174.mount: Deactivated successfully. Oct 29 00:45:47.541622 env[1318]: time="2025-10-29T00:45:47.541571133Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.544151 env[1318]: time="2025-10-29T00:45:47.544122677Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.545165 env[1318]: time="2025-10-29T00:45:47.545131949Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.547292 env[1318]: time="2025-10-29T00:45:47.547267271Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.549060 env[1318]: time="2025-10-29T00:45:47.549001092Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.550089 env[1318]: time="2025-10-29T00:45:47.550053451Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.552364 env[1318]: time="2025-10-29T00:45:47.552323632Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.554940 env[1318]: time="2025-10-29T00:45:47.554912382Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:47.583336 env[1318]: time="2025-10-29T00:45:47.583273774Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 29 00:45:47.583441 env[1318]: time="2025-10-29T00:45:47.583349586Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 29 00:45:47.583441 env[1318]: time="2025-10-29T00:45:47.583378830Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 29 00:45:47.583653 env[1318]: time="2025-10-29T00:45:47.583625387Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597 pid=1619 runtime=io.containerd.runc.v2 Oct 29 00:45:47.584149 env[1318]: time="2025-10-29T00:45:47.584080136Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 29 00:45:47.584149 env[1318]: time="2025-10-29T00:45:47.584112101Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 29 00:45:47.584149 env[1318]: time="2025-10-29T00:45:47.584129023Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 29 00:45:47.584266 env[1318]: time="2025-10-29T00:45:47.584230359Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/432ea7e34e90fe06348a4822de7980393e10468c4ecf31041741d583bca336c6 pid=1621 runtime=io.containerd.runc.v2 Oct 29 00:45:47.634812 env[1318]: time="2025-10-29T00:45:47.634761970Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-sf7xl,Uid:31d8bc14-4ace-4748-810a-3f8022fdc944,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597\"" Oct 29 00:45:47.635767 kubelet[1556]: E1029 00:45:47.635740 1556 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" Oct 29 00:45:47.636698 env[1318]: time="2025-10-29T00:45:47.636669857Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Oct 29 00:45:47.641922 env[1318]: time="2025-10-29T00:45:47.641874881Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-s9dcm,Uid:0cd2f3f8-a867-490d-a293-77847c212eaa,Namespace:kube-system,Attempt:0,} returns sandbox id \"432ea7e34e90fe06348a4822de7980393e10468c4ecf31041741d583bca336c6\"" Oct 29 00:45:47.642629 kubelet[1556]: E1029 00:45:47.642497 1556 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" Oct 29 00:45:47.673762 kubelet[1556]: E1029 00:45:47.673730 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:48.674188 kubelet[1556]: E1029 00:45:48.674127 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:48.781637 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount389036012.mount: Deactivated successfully. Oct 29 00:45:48.819390 env[1318]: time="2025-10-29T00:45:48.819343014Z" 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:[],}" Oct 29 00:45:48.820963 env[1318]: time="2025-10-29T00:45:48.820927398Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:48.822496 env[1318]: time="2025-10-29T00:45:48.822466495Z" 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:[],}" Oct 29 00:45:48.823740 env[1318]: time="2025-10-29T00:45:48.823714911Z" 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:[],}" Oct 29 00:45:48.824373 env[1318]: time="2025-10-29T00:45:48.824340800Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Oct 29 00:45:48.825411 env[1318]: time="2025-10-29T00:45:48.825334780Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\"" Oct 29 00:45:48.826522 env[1318]: time="2025-10-29T00:45:48.826476381Z" level=info msg="CreateContainer within sandbox \"d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 29 00:45:48.837341 env[1318]: time="2025-10-29T00:45:48.837303230Z" level=info msg="CreateContainer within sandbox \"d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"fd441d7094aeb2f50e31b7e7396a6155526b4229ced4493027ea019d012428a6\"" Oct 29 00:45:48.837836 env[1318]: time="2025-10-29T00:45:48.837798540Z" level=info msg="StartContainer for \"fd441d7094aeb2f50e31b7e7396a6155526b4229ced4493027ea019d012428a6\"" Oct 29 00:45:48.882779 env[1318]: time="2025-10-29T00:45:48.882739446Z" level=info msg="StartContainer for \"fd441d7094aeb2f50e31b7e7396a6155526b4229ced4493027ea019d012428a6\" returns successfully" Oct 29 00:45:48.894523 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-fd441d7094aeb2f50e31b7e7396a6155526b4229ced4493027ea019d012428a6-rootfs.mount: Deactivated successfully. Oct 29 00:45:48.918835 env[1318]: time="2025-10-29T00:45:48.918762373Z" level=info msg="shim disconnected" id=fd441d7094aeb2f50e31b7e7396a6155526b4229ced4493027ea019d012428a6 Oct 29 00:45:48.918835 env[1318]: time="2025-10-29T00:45:48.918832703Z" level=warning msg="cleaning up after shim disconnected" id=fd441d7094aeb2f50e31b7e7396a6155526b4229ced4493027ea019d012428a6 namespace=k8s.io Oct 29 00:45:48.918835 env[1318]: time="2025-10-29T00:45:48.918842224Z" level=info msg="cleaning up dead shim" Oct 29 00:45:48.925808 env[1318]: time="2025-10-29T00:45:48.925718596Z" level=warning msg="cleanup warnings time=\"2025-10-29T00:45:48Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1729 runtime=io.containerd.runc.v2\n" Oct 29 00:45:49.675007 kubelet[1556]: E1029 00:45:49.674944 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:49.857388 kubelet[1556]: E1029 00:45:49.857343 1556 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" Oct 29 00:45:50.192456 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount631082210.mount: Deactivated successfully. Oct 29 00:45:50.655348 env[1318]: time="2025-10-29T00:45:50.655294574Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.32.9,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:50.656774 env[1318]: time="2025-10-29T00:45:50.656744754Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:50.658162 env[1318]: time="2025-10-29T00:45:50.658129206Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.32.9,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:50.659878 env[1318]: time="2025-10-29T00:45:50.659845939Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:50.660342 env[1318]: time="2025-10-29T00:45:50.660311877Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\" returns image reference \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\"" Oct 29 00:45:50.661919 env[1318]: time="2025-10-29T00:45:50.661889273Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Oct 29 00:45:50.662664 env[1318]: time="2025-10-29T00:45:50.662631965Z" level=info msg="CreateContainer within sandbox \"432ea7e34e90fe06348a4822de7980393e10468c4ecf31041741d583bca336c6\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 29 00:45:50.673196 env[1318]: time="2025-10-29T00:45:50.673155391Z" level=info msg="CreateContainer within sandbox \"432ea7e34e90fe06348a4822de7980393e10468c4ecf31041741d583bca336c6\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"f96c589dbf8dd98ce2e6dcb9f5ddb8404f9c92a80eac13df462f5598057eaed1\"" Oct 29 00:45:50.673751 env[1318]: time="2025-10-29T00:45:50.673677936Z" level=info msg="StartContainer for \"f96c589dbf8dd98ce2e6dcb9f5ddb8404f9c92a80eac13df462f5598057eaed1\"" Oct 29 00:45:50.675907 kubelet[1556]: E1029 00:45:50.675880 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:50.720249 env[1318]: time="2025-10-29T00:45:50.720206711Z" level=info msg="StartContainer for \"f96c589dbf8dd98ce2e6dcb9f5ddb8404f9c92a80eac13df462f5598057eaed1\" returns successfully" Oct 29 00:45:50.860324 kubelet[1556]: E1029 00:45:50.859910 1556 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" Oct 29 00:45:50.868303 kubelet[1556]: I1029 00:45:50.868254 1556 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-s9dcm" podStartSLOduration=2.849958535 podStartE2EDuration="5.868239404s" podCreationTimestamp="2025-10-29 00:45:45 +0000 UTC" firstStartedPulling="2025-10-29 00:45:47.642886794 +0000 UTC m=+2.962196530" lastFinishedPulling="2025-10-29 00:45:50.661167703 +0000 UTC m=+5.980477399" observedRunningTime="2025-10-29 00:45:50.867762265 +0000 UTC m=+6.187072001" watchObservedRunningTime="2025-10-29 00:45:50.868239404 +0000 UTC m=+6.187549140" Oct 29 00:45:51.676182 kubelet[1556]: E1029 00:45:51.676148 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:51.862261 kubelet[1556]: E1029 00:45:51.862212 1556 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" Oct 29 00:45:52.036185 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2786889528.mount: Deactivated successfully. Oct 29 00:45:52.676877 kubelet[1556]: E1029 00:45:52.676837 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:52.684340 env[1318]: time="2025-10-29T00:45:52.684295498Z" 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:[],}" Oct 29 00:45:52.686165 env[1318]: time="2025-10-29T00:45:52.686134098Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:52.688351 env[1318]: time="2025-10-29T00:45:52.688320937Z" 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:[],}" Oct 29 00:45:52.690271 env[1318]: time="2025-10-29T00:45:52.690235626Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:45:52.691094 env[1318]: time="2025-10-29T00:45:52.691061956Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Oct 29 00:45:52.693887 env[1318]: time="2025-10-29T00:45:52.693857021Z" level=info msg="CreateContainer within sandbox \"d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 29 00:45:52.701948 env[1318]: time="2025-10-29T00:45:52.701910099Z" level=info msg="CreateContainer within sandbox \"d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"12f18c244632a110535e2772635d129870a5af0299ac104c9c639b2472d67dbd\"" Oct 29 00:45:52.702318 env[1318]: time="2025-10-29T00:45:52.702294101Z" level=info msg="StartContainer for \"12f18c244632a110535e2772635d129870a5af0299ac104c9c639b2472d67dbd\"" Oct 29 00:45:52.748109 env[1318]: time="2025-10-29T00:45:52.748066774Z" level=info msg="StartContainer for \"12f18c244632a110535e2772635d129870a5af0299ac104c9c639b2472d67dbd\" returns successfully" Oct 29 00:45:52.757102 kubelet[1556]: I1029 00:45:52.756879 1556 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 29 00:45:52.868519 kubelet[1556]: E1029 00:45:52.868489 1556 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" Oct 29 00:45:52.942696 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-12f18c244632a110535e2772635d129870a5af0299ac104c9c639b2472d67dbd-rootfs.mount: Deactivated successfully. Oct 29 00:45:53.049737 env[1318]: time="2025-10-29T00:45:53.049692905Z" level=info msg="shim disconnected" id=12f18c244632a110535e2772635d129870a5af0299ac104c9c639b2472d67dbd Oct 29 00:45:53.049737 env[1318]: time="2025-10-29T00:45:53.049739550Z" level=warning msg="cleaning up after shim disconnected" id=12f18c244632a110535e2772635d129870a5af0299ac104c9c639b2472d67dbd namespace=k8s.io Oct 29 00:45:53.049933 env[1318]: time="2025-10-29T00:45:53.049751231Z" level=info msg="cleaning up dead shim" Oct 29 00:45:53.056313 env[1318]: time="2025-10-29T00:45:53.056276338Z" level=warning msg="cleanup warnings time=\"2025-10-29T00:45:53Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1954 runtime=io.containerd.runc.v2\n" Oct 29 00:45:53.677690 kubelet[1556]: E1029 00:45:53.677629 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:53.871506 kubelet[1556]: E1029 00:45:53.871459 1556 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" Oct 29 00:45:53.873603 env[1318]: time="2025-10-29T00:45:53.873545881Z" level=info msg="CreateContainer within sandbox \"d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 29 00:45:53.889992 env[1318]: time="2025-10-29T00:45:53.889947438Z" level=info msg="CreateContainer within sandbox \"d80ec57a333cfce5ae4d01b57a1c4713429e6a2ff641ffb5f7b28292c936d597\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"ea2385df990be484c1f148a2c7a4c74efcc0e10ce4c8c6fd3d4045af49f28455\"" Oct 29 00:45:53.890434 env[1318]: time="2025-10-29T00:45:53.890411406Z" level=info msg="StartContainer for \"ea2385df990be484c1f148a2c7a4c74efcc0e10ce4c8c6fd3d4045af49f28455\"" Oct 29 00:45:53.938769 env[1318]: time="2025-10-29T00:45:53.938655940Z" level=info msg="StartContainer for \"ea2385df990be484c1f148a2c7a4c74efcc0e10ce4c8c6fd3d4045af49f28455\" returns successfully" Oct 29 00:45:54.677996 kubelet[1556]: E1029 00:45:54.677945 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:54.875255 kubelet[1556]: E1029 00:45:54.875210 1556 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" Oct 29 00:45:54.885027 kubelet[1556]: I1029 00:45:54.884966 1556 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-sf7xl" podStartSLOduration=4.828831358 podStartE2EDuration="9.88494554s" podCreationTimestamp="2025-10-29 00:45:45 +0000 UTC" firstStartedPulling="2025-10-29 00:45:47.63628892 +0000 UTC m=+2.955598656" lastFinishedPulling="2025-10-29 00:45:52.692403102 +0000 UTC m=+8.011712838" observedRunningTime="2025-10-29 00:45:54.884708997 +0000 UTC m=+10.204018733" watchObservedRunningTime="2025-10-29 00:45:54.88494554 +0000 UTC m=+10.204255276" Oct 29 00:45:55.001812 systemd-networkd[1100]: flannel.1: Link UP Oct 29 00:45:55.001817 systemd-networkd[1100]: flannel.1: Gained carrier Oct 29 00:45:55.678318 kubelet[1556]: E1029 00:45:55.678252 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:55.876647 kubelet[1556]: E1029 00:45:55.876600 1556 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" Oct 29 00:45:56.678602 kubelet[1556]: E1029 00:45:56.678556 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:57.017977 systemd-networkd[1100]: flannel.1: Gained IPv6LL Oct 29 00:45:57.568848 kubelet[1556]: I1029 00:45:57.568812 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h2xz7\" (UniqueName: \"kubernetes.io/projected/2b1dbed1-7b48-4b13-8d63-847282117260-kube-api-access-h2xz7\") pod \"nginx-deployment-7fcdb87857-wp6gl\" (UID: \"2b1dbed1-7b48-4b13-8d63-847282117260\") " pod="default/nginx-deployment-7fcdb87857-wp6gl" Oct 29 00:45:57.679329 kubelet[1556]: E1029 00:45:57.679291 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:57.768408 env[1318]: time="2025-10-29T00:45:57.768364705Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-wp6gl,Uid:2b1dbed1-7b48-4b13-8d63-847282117260,Namespace:default,Attempt:0,}" Oct 29 00:45:57.787871 systemd-networkd[1100]: cni0: Link UP Oct 29 00:45:57.787880 systemd-networkd[1100]: cni0: Gained carrier Oct 29 00:45:57.788113 systemd-networkd[1100]: cni0: Lost carrier Oct 29 00:45:57.798039 systemd-networkd[1100]: vethb9191776: Link UP Oct 29 00:45:57.799998 kernel: cni0: port 1(vethb9191776) entered blocking state Oct 29 00:45:57.800084 kernel: cni0: port 1(vethb9191776) entered disabled state Oct 29 00:45:57.800106 kernel: device vethb9191776 entered promiscuous mode Oct 29 00:45:57.801560 kernel: cni0: port 1(vethb9191776) entered blocking state Oct 29 00:45:57.801607 kernel: cni0: port 1(vethb9191776) entered forwarding state Oct 29 00:45:57.804920 kernel: cni0: port 1(vethb9191776) entered disabled state Oct 29 00:45:57.811602 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethb9191776: link becomes ready Oct 29 00:45:57.811695 kernel: cni0: port 1(vethb9191776) entered blocking state Oct 29 00:45:57.811715 kernel: cni0: port 1(vethb9191776) entered forwarding state Oct 29 00:45:57.811590 systemd-networkd[1100]: vethb9191776: Gained carrier Oct 29 00:45:57.811939 systemd-networkd[1100]: cni0: Gained carrier Oct 29 00:45:57.813370 env[1318]: 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"} Oct 29 00:45:57.813370 env[1318]: delegateAdd: netconf sent to delegate plugin: Oct 29 00:45:57.829418 env[1318]: {"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-10-29T00:45:57.829303679Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 29 00:45:57.829418 env[1318]: time="2025-10-29T00:45:57.829341962Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 29 00:45:57.829418 env[1318]: time="2025-10-29T00:45:57.829352603Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 29 00:45:57.830067 env[1318]: time="2025-10-29T00:45:57.829645906Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/4ccaf5758d98ebc4509abca512ee01a4c2443420d6a79a6b24f0671d5bdccfd7 pid=2139 runtime=io.containerd.runc.v2 Oct 29 00:45:57.857628 systemd-resolved[1242]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 29 00:45:57.875764 env[1318]: time="2025-10-29T00:45:57.875713666Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-wp6gl,Uid:2b1dbed1-7b48-4b13-8d63-847282117260,Namespace:default,Attempt:0,} returns sandbox id \"4ccaf5758d98ebc4509abca512ee01a4c2443420d6a79a6b24f0671d5bdccfd7\"" Oct 29 00:45:57.880516 env[1318]: time="2025-10-29T00:45:57.880103733Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 29 00:45:58.680060 kubelet[1556]: E1029 00:45:58.680014 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:59.449977 systemd-networkd[1100]: vethb9191776: Gained IPv6LL Oct 29 00:45:59.680324 kubelet[1556]: E1029 00:45:59.680270 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:45:59.769949 systemd-networkd[1100]: cni0: Gained IPv6LL Oct 29 00:45:59.795182 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount772657026.mount: Deactivated successfully. Oct 29 00:46:00.681402 kubelet[1556]: E1029 00:46:00.681335 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:01.061700 env[1318]: time="2025-10-29T00:46:01.061658349Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:01.063215 env[1318]: time="2025-10-29T00:46:01.063182242Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:01.065001 env[1318]: time="2025-10-29T00:46:01.064975671Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:01.066854 env[1318]: time="2025-10-29T00:46:01.066829944Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:01.067569 env[1318]: time="2025-10-29T00:46:01.067541468Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 29 00:46:01.069854 env[1318]: time="2025-10-29T00:46:01.069825207Z" level=info msg="CreateContainer within sandbox \"4ccaf5758d98ebc4509abca512ee01a4c2443420d6a79a6b24f0671d5bdccfd7\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 29 00:46:01.079359 env[1318]: time="2025-10-29T00:46:01.079308186Z" level=info msg="CreateContainer within sandbox \"4ccaf5758d98ebc4509abca512ee01a4c2443420d6a79a6b24f0671d5bdccfd7\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"f729056803ff30959212a5c9a6f4a802f79e04252d983dd3137bf62c789d7350\"" Oct 29 00:46:01.079693 env[1318]: time="2025-10-29T00:46:01.079669608Z" level=info msg="StartContainer for \"f729056803ff30959212a5c9a6f4a802f79e04252d983dd3137bf62c789d7350\"" Oct 29 00:46:01.127499 env[1318]: time="2025-10-29T00:46:01.127462085Z" level=info msg="StartContainer for \"f729056803ff30959212a5c9a6f4a802f79e04252d983dd3137bf62c789d7350\" returns successfully" Oct 29 00:46:01.681769 kubelet[1556]: E1029 00:46:01.681711 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:02.682075 kubelet[1556]: E1029 00:46:02.682028 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:03.555907 kubelet[1556]: I1029 00:46:03.555837 1556 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-wp6gl" podStartSLOduration=3.363521675 podStartE2EDuration="6.55581721s" podCreationTimestamp="2025-10-29 00:45:57 +0000 UTC" firstStartedPulling="2025-10-29 00:45:57.87651989 +0000 UTC m=+13.195829626" lastFinishedPulling="2025-10-29 00:46:01.068815425 +0000 UTC m=+16.388125161" observedRunningTime="2025-10-29 00:46:01.896613786 +0000 UTC m=+17.215923482" watchObservedRunningTime="2025-10-29 00:46:03.55581721 +0000 UTC m=+18.875126946" Oct 29 00:46:03.600193 kubelet[1556]: I1029 00:46:03.600160 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/de3c1f87-b5a6-402b-b003-f3b2d9afca36-data\") pod \"nfs-server-provisioner-0\" (UID: \"de3c1f87-b5a6-402b-b003-f3b2d9afca36\") " pod="default/nfs-server-provisioner-0" Oct 29 00:46:03.600193 kubelet[1556]: I1029 00:46:03.600199 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5nb82\" (UniqueName: \"kubernetes.io/projected/de3c1f87-b5a6-402b-b003-f3b2d9afca36-kube-api-access-5nb82\") pod \"nfs-server-provisioner-0\" (UID: \"de3c1f87-b5a6-402b-b003-f3b2d9afca36\") " pod="default/nfs-server-provisioner-0" Oct 29 00:46:03.682770 kubelet[1556]: E1029 00:46:03.682743 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:03.861254 env[1318]: time="2025-10-29T00:46:03.860828811Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:de3c1f87-b5a6-402b-b003-f3b2d9afca36,Namespace:default,Attempt:0,}" Oct 29 00:46:03.879096 systemd-networkd[1100]: veth5d91c940: Link UP Oct 29 00:46:03.880943 kernel: cni0: port 2(veth5d91c940) entered blocking state Oct 29 00:46:03.881015 kernel: cni0: port 2(veth5d91c940) entered disabled state Oct 29 00:46:03.881822 kernel: device veth5d91c940 entered promiscuous mode Oct 29 00:46:03.887153 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 29 00:46:03.887212 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth5d91c940: link becomes ready Oct 29 00:46:03.887233 kernel: cni0: port 2(veth5d91c940) entered blocking state Oct 29 00:46:03.888616 systemd-networkd[1100]: veth5d91c940: Gained carrier Oct 29 00:46:03.889093 kernel: cni0: port 2(veth5d91c940) entered forwarding state Oct 29 00:46:03.891253 env[1318]: 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"} Oct 29 00:46:03.891253 env[1318]: delegateAdd: netconf sent to delegate plugin: Oct 29 00:46:03.902431 env[1318]: {"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-10-29T00:46:03.902355278Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 29 00:46:03.902431 env[1318]: time="2025-10-29T00:46:03.902406201Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 29 00:46:03.902431 env[1318]: time="2025-10-29T00:46:03.902416642Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 29 00:46:03.902857 env[1318]: time="2025-10-29T00:46:03.902811543Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/fc708d7d0918b33bf6968bf2d6947feb08e518516dabbf32eb151c5a8e89ed01 pid=2308 runtime=io.containerd.runc.v2 Oct 29 00:46:03.931137 systemd-resolved[1242]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 29 00:46:03.947656 env[1318]: time="2025-10-29T00:46:03.947612146Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:de3c1f87-b5a6-402b-b003-f3b2d9afca36,Namespace:default,Attempt:0,} returns sandbox id \"fc708d7d0918b33bf6968bf2d6947feb08e518516dabbf32eb151c5a8e89ed01\"" Oct 29 00:46:03.948963 env[1318]: time="2025-10-29T00:46:03.948934177Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 29 00:46:04.683490 kubelet[1556]: E1029 00:46:04.683431 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:04.715933 systemd[1]: run-containerd-runc-k8s.io-fc708d7d0918b33bf6968bf2d6947feb08e518516dabbf32eb151c5a8e89ed01-runc.mNlFmZ.mount: Deactivated successfully. Oct 29 00:46:05.337910 systemd-networkd[1100]: veth5d91c940: Gained IPv6LL Oct 29 00:46:05.673059 kubelet[1556]: E1029 00:46:05.672853 1556 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:05.684320 kubelet[1556]: E1029 00:46:05.684286 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:06.016602 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2192032687.mount: Deactivated successfully. Oct 29 00:46:06.685301 kubelet[1556]: E1029 00:46:06.685260 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:07.685773 kubelet[1556]: E1029 00:46:07.685714 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:07.767950 env[1318]: time="2025-10-29T00:46:07.767902538Z" 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:[],}" Oct 29 00:46:07.769416 env[1318]: time="2025-10-29T00:46:07.769384919Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:07.771764 env[1318]: time="2025-10-29T00:46:07.771729416Z" 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:[],}" Oct 29 00:46:07.774097 env[1318]: time="2025-10-29T00:46:07.774066633Z" 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:[],}" Oct 29 00:46:07.774747 env[1318]: time="2025-10-29T00:46:07.774718100Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 29 00:46:07.781039 env[1318]: time="2025-10-29T00:46:07.780989800Z" level=info msg="CreateContainer within sandbox \"fc708d7d0918b33bf6968bf2d6947feb08e518516dabbf32eb151c5a8e89ed01\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 29 00:46:07.790837 env[1318]: time="2025-10-29T00:46:07.790793366Z" level=info msg="CreateContainer within sandbox \"fc708d7d0918b33bf6968bf2d6947feb08e518516dabbf32eb151c5a8e89ed01\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"44a719dc6020ce23f43103b0024d50239c4df4289fefa0990fe4a3388f8af390\"" Oct 29 00:46:07.791285 env[1318]: time="2025-10-29T00:46:07.791177902Z" level=info msg="StartContainer for \"44a719dc6020ce23f43103b0024d50239c4df4289fefa0990fe4a3388f8af390\"" Oct 29 00:46:07.835284 env[1318]: time="2025-10-29T00:46:07.835241088Z" level=info msg="StartContainer for \"44a719dc6020ce23f43103b0024d50239c4df4289fefa0990fe4a3388f8af390\" returns successfully" Oct 29 00:46:07.910416 kubelet[1556]: I1029 00:46:07.910343 1556 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.082758037 podStartE2EDuration="4.910329999s" podCreationTimestamp="2025-10-29 00:46:03 +0000 UTC" firstStartedPulling="2025-10-29 00:46:03.948520435 +0000 UTC m=+19.267830171" lastFinishedPulling="2025-10-29 00:46:07.776092397 +0000 UTC m=+23.095402133" observedRunningTime="2025-10-29 00:46:07.910089669 +0000 UTC m=+23.229399405" watchObservedRunningTime="2025-10-29 00:46:07.910329999 +0000 UTC m=+23.229639735" Oct 29 00:46:08.688894 kubelet[1556]: E1029 00:46:08.688828 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:09.689638 kubelet[1556]: E1029 00:46:09.689590 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:10.690496 kubelet[1556]: E1029 00:46:10.690412 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:11.691534 kubelet[1556]: E1029 00:46:11.691467 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:12.691705 kubelet[1556]: E1029 00:46:12.691626 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:13.692598 kubelet[1556]: E1029 00:46:13.692469 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:14.692987 kubelet[1556]: E1029 00:46:14.692936 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:15.693856 kubelet[1556]: E1029 00:46:15.693830 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:16.694942 kubelet[1556]: E1029 00:46:16.694880 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:17.189251 kubelet[1556]: I1029 00:46:17.189218 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-daf56619-03f4-4877-9240-dfbc0794bc2c\" (UniqueName: \"kubernetes.io/nfs/9974da1d-adb8-4efc-89c3-6c5ba6fab792-pvc-daf56619-03f4-4877-9240-dfbc0794bc2c\") pod \"test-pod-1\" (UID: \"9974da1d-adb8-4efc-89c3-6c5ba6fab792\") " pod="default/test-pod-1" Oct 29 00:46:17.189452 kubelet[1556]: I1029 00:46:17.189436 1556 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6kwkv\" (UniqueName: \"kubernetes.io/projected/9974da1d-adb8-4efc-89c3-6c5ba6fab792-kube-api-access-6kwkv\") pod \"test-pod-1\" (UID: \"9974da1d-adb8-4efc-89c3-6c5ba6fab792\") " pod="default/test-pod-1" Oct 29 00:46:17.310844 kernel: FS-Cache: Loaded Oct 29 00:46:17.338293 kernel: RPC: Registered named UNIX socket transport module. Oct 29 00:46:17.338367 kernel: RPC: Registered udp transport module. Oct 29 00:46:17.338411 kernel: RPC: Registered tcp transport module. Oct 29 00:46:17.338442 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 29 00:46:17.379809 kernel: FS-Cache: Netfs 'nfs' registered for caching Oct 29 00:46:17.509112 kernel: NFS: Registering the id_resolver key type Oct 29 00:46:17.509215 kernel: Key type id_resolver registered Oct 29 00:46:17.509235 kernel: Key type id_legacy registered Oct 29 00:46:17.536087 nfsidmap[2490]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 29 00:46:17.539026 nfsidmap[2493]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 29 00:46:17.695532 kubelet[1556]: E1029 00:46:17.695491 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:17.782732 env[1318]: time="2025-10-29T00:46:17.782364205Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9974da1d-adb8-4efc-89c3-6c5ba6fab792,Namespace:default,Attempt:0,}" Oct 29 00:46:17.800123 systemd-networkd[1100]: veth5b4a4078: Link UP Oct 29 00:46:17.802182 kernel: cni0: port 3(veth5b4a4078) entered blocking state Oct 29 00:46:17.802254 kernel: cni0: port 3(veth5b4a4078) entered disabled state Oct 29 00:46:17.803065 kernel: device veth5b4a4078 entered promiscuous mode Oct 29 00:46:17.809299 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 29 00:46:17.809372 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth5b4a4078: link becomes ready Oct 29 00:46:17.809391 kernel: cni0: port 3(veth5b4a4078) entered blocking state Oct 29 00:46:17.810901 kernel: cni0: port 3(veth5b4a4078) entered forwarding state Oct 29 00:46:17.811039 systemd-networkd[1100]: veth5b4a4078: Gained carrier Oct 29 00:46:17.812408 env[1318]: 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"} Oct 29 00:46:17.812408 env[1318]: delegateAdd: netconf sent to delegate plugin: Oct 29 00:46:17.824221 env[1318]: {"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-10-29T00:46:17.824142913Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 29 00:46:17.824221 env[1318]: time="2025-10-29T00:46:17.824195074Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 29 00:46:17.824221 env[1318]: time="2025-10-29T00:46:17.824205994Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 29 00:46:17.824536 env[1318]: time="2025-10-29T00:46:17.824454319Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/9be1a99116a745013fc9cff7ad98d1d325d8159faecf95ed54db58617ac15b4a pid=2546 runtime=io.containerd.runc.v2 Oct 29 00:46:17.848956 systemd-resolved[1242]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 29 00:46:17.865982 env[1318]: time="2025-10-29T00:46:17.865912780Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9974da1d-adb8-4efc-89c3-6c5ba6fab792,Namespace:default,Attempt:0,} returns sandbox id \"9be1a99116a745013fc9cff7ad98d1d325d8159faecf95ed54db58617ac15b4a\"" Oct 29 00:46:17.866950 env[1318]: time="2025-10-29T00:46:17.866908762Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 29 00:46:18.099239 env[1318]: time="2025-10-29T00:46:18.098670627Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:18.100601 env[1318]: time="2025-10-29T00:46:18.100562946Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:18.102634 env[1318]: time="2025-10-29T00:46:18.102596427Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:18.104603 env[1318]: time="2025-10-29T00:46:18.104565867Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Oct 29 00:46:18.105323 env[1318]: time="2025-10-29T00:46:18.105299162Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 29 00:46:18.107614 env[1318]: time="2025-10-29T00:46:18.107564688Z" level=info msg="CreateContainer within sandbox \"9be1a99116a745013fc9cff7ad98d1d325d8159faecf95ed54db58617ac15b4a\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 29 00:46:18.118330 env[1318]: time="2025-10-29T00:46:18.118279306Z" level=info msg="CreateContainer within sandbox \"9be1a99116a745013fc9cff7ad98d1d325d8159faecf95ed54db58617ac15b4a\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a1cd299211bd1a837d69e904fc29a154654f3d9ea8441107df7fa87cd3f8e6f5\"" Oct 29 00:46:18.118738 env[1318]: time="2025-10-29T00:46:18.118714555Z" level=info msg="StartContainer for \"a1cd299211bd1a837d69e904fc29a154654f3d9ea8441107df7fa87cd3f8e6f5\"" Oct 29 00:46:18.162098 env[1318]: time="2025-10-29T00:46:18.162062118Z" level=info msg="StartContainer for \"a1cd299211bd1a837d69e904fc29a154654f3d9ea8441107df7fa87cd3f8e6f5\" returns successfully" Oct 29 00:46:18.696951 kubelet[1556]: E1029 00:46:18.696898 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:18.842032 systemd-networkd[1100]: veth5b4a4078: Gained IPv6LL Oct 29 00:46:19.697573 kubelet[1556]: E1029 00:46:19.697518 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:46:20.698196 kubelet[1556]: E1029 00:46:20.698130 1556 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"