Dec 13 14:20:33.727753 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Dec 13 14:20:33.727771 kernel: Linux version 5.15.173-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 Fri Dec 13 12:58:58 -00 2024 Dec 13 14:20:33.727779 kernel: efi: EFI v2.70 by EDK II Dec 13 14:20:33.727785 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Dec 13 14:20:33.727790 kernel: random: crng init done Dec 13 14:20:33.727795 kernel: ACPI: Early table checksum verification disabled Dec 13 14:20:33.727802 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Dec 13 14:20:33.727808 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Dec 13 14:20:33.727813 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727819 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727824 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727830 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727835 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727840 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727848 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727854 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727859 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 14:20:33.727865 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Dec 13 14:20:33.727871 kernel: NUMA: Failed to initialise from firmware Dec 13 14:20:33.727877 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 14:20:33.727883 kernel: NUMA: NODE_DATA [mem 0xdcb0a900-0xdcb0ffff] Dec 13 14:20:33.727889 kernel: Zone ranges: Dec 13 14:20:33.727894 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 14:20:33.727901 kernel: DMA32 empty Dec 13 14:20:33.727907 kernel: Normal empty Dec 13 14:20:33.727912 kernel: Movable zone start for each node Dec 13 14:20:33.727918 kernel: Early memory node ranges Dec 13 14:20:33.727923 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Dec 13 14:20:33.727929 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Dec 13 14:20:33.727934 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Dec 13 14:20:33.727940 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Dec 13 14:20:33.727946 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Dec 13 14:20:33.727952 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Dec 13 14:20:33.727958 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Dec 13 14:20:33.727963 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 14:20:33.727970 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Dec 13 14:20:33.727975 kernel: psci: probing for conduit method from ACPI. Dec 13 14:20:33.727981 kernel: psci: PSCIv1.1 detected in firmware. Dec 13 14:20:33.727987 kernel: psci: Using standard PSCI v0.2 function IDs Dec 13 14:20:33.727992 kernel: psci: Trusted OS migration not required Dec 13 14:20:33.728000 kernel: psci: SMC Calling Convention v1.1 Dec 13 14:20:33.728006 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Dec 13 14:20:33.728013 kernel: ACPI: SRAT not present Dec 13 14:20:33.728020 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Dec 13 14:20:33.728026 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Dec 13 14:20:33.728032 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 13 14:20:33.728038 kernel: Detected PIPT I-cache on CPU0 Dec 13 14:20:33.728044 kernel: CPU features: detected: GIC system register CPU interface Dec 13 14:20:33.728051 kernel: CPU features: detected: Hardware dirty bit management Dec 13 14:20:33.728056 kernel: CPU features: detected: Spectre-v4 Dec 13 14:20:33.728062 kernel: CPU features: detected: Spectre-BHB Dec 13 14:20:33.728069 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 13 14:20:33.728075 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 13 14:20:33.728081 kernel: CPU features: detected: ARM erratum 1418040 Dec 13 14:20:33.728111 kernel: CPU features: detected: SSBS not fully self-synchronizing Dec 13 14:20:33.728119 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Dec 13 14:20:33.728125 kernel: Policy zone: DMA Dec 13 14:20:33.728132 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=5997a8cf94b1df1856dc785f0a7074604bbf4c21fdcca24a1996021471a77601 Dec 13 14:20:33.728139 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Dec 13 14:20:33.728145 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 13 14:20:33.728151 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 13 14:20:33.728157 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 13 14:20:33.728165 kernel: Memory: 2457400K/2572288K available (9792K kernel code, 2092K rwdata, 7576K rodata, 36416K init, 777K bss, 114888K reserved, 0K cma-reserved) Dec 13 14:20:33.728171 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 13 14:20:33.728177 kernel: trace event string verifier disabled Dec 13 14:20:33.728183 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 13 14:20:33.728190 kernel: rcu: RCU event tracing is enabled. Dec 13 14:20:33.728196 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Dec 13 14:20:33.728203 kernel: Trampoline variant of Tasks RCU enabled. Dec 13 14:20:33.728209 kernel: Tracing variant of Tasks RCU enabled. Dec 13 14:20:33.728215 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 13 14:20:33.728221 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Dec 13 14:20:33.728227 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 13 14:20:33.728234 kernel: GICv3: 256 SPIs implemented Dec 13 14:20:33.728240 kernel: GICv3: 0 Extended SPIs implemented Dec 13 14:20:33.728246 kernel: GICv3: Distributor has no Range Selector support Dec 13 14:20:33.728252 kernel: Root IRQ handler: gic_handle_irq Dec 13 14:20:33.728258 kernel: GICv3: 16 PPIs implemented Dec 13 14:20:33.728264 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Dec 13 14:20:33.728270 kernel: ACPI: SRAT not present Dec 13 14:20:33.728276 kernel: ITS [mem 0x08080000-0x0809ffff] Dec 13 14:20:33.728282 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Dec 13 14:20:33.728288 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Dec 13 14:20:33.728294 kernel: GICv3: using LPI property table @0x00000000400d0000 Dec 13 14:20:33.728300 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Dec 13 14:20:33.728308 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 14:20:33.728314 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Dec 13 14:20:33.728320 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Dec 13 14:20:33.728326 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Dec 13 14:20:33.728333 kernel: arm-pv: using stolen time PV Dec 13 14:20:33.728339 kernel: Console: colour dummy device 80x25 Dec 13 14:20:33.728345 kernel: ACPI: Core revision 20210730 Dec 13 14:20:33.728352 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Dec 13 14:20:33.728358 kernel: pid_max: default: 32768 minimum: 301 Dec 13 14:20:33.728365 kernel: LSM: Security Framework initializing Dec 13 14:20:33.728372 kernel: SELinux: Initializing. Dec 13 14:20:33.728378 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 14:20:33.728385 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 14:20:33.728391 kernel: rcu: Hierarchical SRCU implementation. Dec 13 14:20:33.728397 kernel: Platform MSI: ITS@0x8080000 domain created Dec 13 14:20:33.728404 kernel: PCI/MSI: ITS@0x8080000 domain created Dec 13 14:20:33.728410 kernel: Remapping and enabling EFI services. Dec 13 14:20:33.728416 kernel: smp: Bringing up secondary CPUs ... Dec 13 14:20:33.728422 kernel: Detected PIPT I-cache on CPU1 Dec 13 14:20:33.728430 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Dec 13 14:20:33.728437 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Dec 13 14:20:33.728443 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 14:20:33.728449 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Dec 13 14:20:33.728455 kernel: Detected PIPT I-cache on CPU2 Dec 13 14:20:33.728462 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Dec 13 14:20:33.728468 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Dec 13 14:20:33.728474 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 14:20:33.728480 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Dec 13 14:20:33.728486 kernel: Detected PIPT I-cache on CPU3 Dec 13 14:20:33.728494 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Dec 13 14:20:33.728503 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Dec 13 14:20:33.728509 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 14:20:33.728516 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Dec 13 14:20:33.728526 kernel: smp: Brought up 1 node, 4 CPUs Dec 13 14:20:33.728534 kernel: SMP: Total of 4 processors activated. Dec 13 14:20:33.728540 kernel: CPU features: detected: 32-bit EL0 Support Dec 13 14:20:33.728547 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Dec 13 14:20:33.728554 kernel: CPU features: detected: Common not Private translations Dec 13 14:20:33.728560 kernel: CPU features: detected: CRC32 instructions Dec 13 14:20:33.728567 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Dec 13 14:20:33.728573 kernel: CPU features: detected: LSE atomic instructions Dec 13 14:20:33.728581 kernel: CPU features: detected: Privileged Access Never Dec 13 14:20:33.728587 kernel: CPU features: detected: RAS Extension Support Dec 13 14:20:33.728594 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Dec 13 14:20:33.728600 kernel: CPU: All CPU(s) started at EL1 Dec 13 14:20:33.728607 kernel: alternatives: patching kernel code Dec 13 14:20:33.728614 kernel: devtmpfs: initialized Dec 13 14:20:33.728621 kernel: KASLR enabled Dec 13 14:20:33.728627 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 13 14:20:33.728638 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 13 14:20:33.728645 kernel: pinctrl core: initialized pinctrl subsystem Dec 13 14:20:33.728652 kernel: SMBIOS 3.0.0 present. Dec 13 14:20:33.728658 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Dec 13 14:20:33.728665 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 13 14:20:33.728671 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Dec 13 14:20:33.728679 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 13 14:20:33.728686 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 13 14:20:33.728692 kernel: audit: initializing netlink subsys (disabled) Dec 13 14:20:33.728699 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Dec 13 14:20:33.728705 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 13 14:20:33.728712 kernel: cpuidle: using governor menu Dec 13 14:20:33.728718 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 13 14:20:33.728725 kernel: ASID allocator initialised with 32768 entries Dec 13 14:20:33.728731 kernel: ACPI: bus type PCI registered Dec 13 14:20:33.728739 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 13 14:20:33.728745 kernel: Serial: AMBA PL011 UART driver Dec 13 14:20:33.728752 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Dec 13 14:20:33.728759 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Dec 13 14:20:33.728765 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Dec 13 14:20:33.728772 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Dec 13 14:20:33.728778 kernel: cryptd: max_cpu_qlen set to 1000 Dec 13 14:20:33.728785 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Dec 13 14:20:33.728792 kernel: ACPI: Added _OSI(Module Device) Dec 13 14:20:33.728799 kernel: ACPI: Added _OSI(Processor Device) Dec 13 14:20:33.728810 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Dec 13 14:20:33.728817 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 13 14:20:33.728823 kernel: ACPI: Added _OSI(Linux-Dell-Video) Dec 13 14:20:33.728830 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Dec 13 14:20:33.728836 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Dec 13 14:20:33.728843 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 13 14:20:33.728849 kernel: ACPI: Interpreter enabled Dec 13 14:20:33.728856 kernel: ACPI: Using GIC for interrupt routing Dec 13 14:20:33.728863 kernel: ACPI: MCFG table detected, 1 entries Dec 13 14:20:33.728870 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Dec 13 14:20:33.728877 kernel: printk: console [ttyAMA0] enabled Dec 13 14:20:33.728883 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Dec 13 14:20:33.728994 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Dec 13 14:20:33.729057 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Dec 13 14:20:33.729135 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Dec 13 14:20:33.729200 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Dec 13 14:20:33.729257 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Dec 13 14:20:33.729265 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Dec 13 14:20:33.729272 kernel: PCI host bridge to bus 0000:00 Dec 13 14:20:33.729335 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Dec 13 14:20:33.729386 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Dec 13 14:20:33.729438 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Dec 13 14:20:33.729489 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 13 14:20:33.729561 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Dec 13 14:20:33.729627 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Dec 13 14:20:33.729697 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Dec 13 14:20:33.729755 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Dec 13 14:20:33.729811 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Dec 13 14:20:33.729869 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Dec 13 14:20:33.729929 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Dec 13 14:20:33.729988 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Dec 13 14:20:33.730039 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Dec 13 14:20:33.730097 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Dec 13 14:20:33.730164 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Dec 13 14:20:33.730173 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Dec 13 14:20:33.730180 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Dec 13 14:20:33.730186 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Dec 13 14:20:33.730195 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Dec 13 14:20:33.730202 kernel: iommu: Default domain type: Translated Dec 13 14:20:33.730209 kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 13 14:20:33.730215 kernel: vgaarb: loaded Dec 13 14:20:33.730222 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 13 14:20:33.730228 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 13 14:20:33.730235 kernel: PTP clock support registered Dec 13 14:20:33.730242 kernel: Registered efivars operations Dec 13 14:20:33.730248 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 13 14:20:33.730256 kernel: VFS: Disk quotas dquot_6.6.0 Dec 13 14:20:33.730263 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 13 14:20:33.730269 kernel: pnp: PnP ACPI init Dec 13 14:20:33.730337 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Dec 13 14:20:33.730346 kernel: pnp: PnP ACPI: found 1 devices Dec 13 14:20:33.730353 kernel: NET: Registered PF_INET protocol family Dec 13 14:20:33.730360 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 13 14:20:33.730366 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 13 14:20:33.730375 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 13 14:20:33.730381 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 13 14:20:33.730388 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Dec 13 14:20:33.730394 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 13 14:20:33.730401 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 14:20:33.730407 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 14:20:33.730414 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 13 14:20:33.730421 kernel: PCI: CLS 0 bytes, default 64 Dec 13 14:20:33.730427 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Dec 13 14:20:33.730435 kernel: kvm [1]: HYP mode not available Dec 13 14:20:33.730442 kernel: Initialise system trusted keyrings Dec 13 14:20:33.730448 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Dec 13 14:20:33.730455 kernel: Key type asymmetric registered Dec 13 14:20:33.730462 kernel: Asymmetric key parser 'x509' registered Dec 13 14:20:33.730468 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Dec 13 14:20:33.730475 kernel: io scheduler mq-deadline registered Dec 13 14:20:33.730481 kernel: io scheduler kyber registered Dec 13 14:20:33.730488 kernel: io scheduler bfq registered Dec 13 14:20:33.730495 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Dec 13 14:20:33.730502 kernel: ACPI: button: Power Button [PWRB] Dec 13 14:20:33.730509 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Dec 13 14:20:33.730567 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Dec 13 14:20:33.730576 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 13 14:20:33.730583 kernel: thunder_xcv, ver 1.0 Dec 13 14:20:33.730589 kernel: thunder_bgx, ver 1.0 Dec 13 14:20:33.730596 kernel: nicpf, ver 1.0 Dec 13 14:20:33.730602 kernel: nicvf, ver 1.0 Dec 13 14:20:33.730673 kernel: rtc-efi rtc-efi.0: registered as rtc0 Dec 13 14:20:33.730729 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-12-13T14:20:33 UTC (1734099633) Dec 13 14:20:33.730738 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 13 14:20:33.730744 kernel: NET: Registered PF_INET6 protocol family Dec 13 14:20:33.730751 kernel: Segment Routing with IPv6 Dec 13 14:20:33.730757 kernel: In-situ OAM (IOAM) with IPv6 Dec 13 14:20:33.730764 kernel: NET: Registered PF_PACKET protocol family Dec 13 14:20:33.730771 kernel: Key type dns_resolver registered Dec 13 14:20:33.730779 kernel: registered taskstats version 1 Dec 13 14:20:33.730786 kernel: Loading compiled-in X.509 certificates Dec 13 14:20:33.730792 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.173-flatcar: e011ba9949ade5a6d03f7a5e28171f7f59e70f8a' Dec 13 14:20:33.730799 kernel: Key type .fscrypt registered Dec 13 14:20:33.730805 kernel: Key type fscrypt-provisioning registered Dec 13 14:20:33.730812 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 13 14:20:33.730818 kernel: ima: Allocated hash algorithm: sha1 Dec 13 14:20:33.730825 kernel: ima: No architecture policies found Dec 13 14:20:33.730831 kernel: clk: Disabling unused clocks Dec 13 14:20:33.730839 kernel: Freeing unused kernel memory: 36416K Dec 13 14:20:33.730845 kernel: Run /init as init process Dec 13 14:20:33.730852 kernel: with arguments: Dec 13 14:20:33.730858 kernel: /init Dec 13 14:20:33.730864 kernel: with environment: Dec 13 14:20:33.730870 kernel: HOME=/ Dec 13 14:20:33.730877 kernel: TERM=linux Dec 13 14:20:33.730883 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Dec 13 14:20:33.730891 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) Dec 13 14:20:33.730901 systemd[1]: Detected virtualization kvm. Dec 13 14:20:33.730908 systemd[1]: Detected architecture arm64. Dec 13 14:20:33.730915 systemd[1]: Running in initrd. Dec 13 14:20:33.730922 systemd[1]: No hostname configured, using default hostname. Dec 13 14:20:33.730929 systemd[1]: Hostname set to . Dec 13 14:20:33.730936 systemd[1]: Initializing machine ID from VM UUID. Dec 13 14:20:33.730943 systemd[1]: Queued start job for default target initrd.target. Dec 13 14:20:33.730950 systemd[1]: Started systemd-ask-password-console.path. Dec 13 14:20:33.730957 systemd[1]: Reached target cryptsetup.target. Dec 13 14:20:33.730964 systemd[1]: Reached target paths.target. Dec 13 14:20:33.730971 systemd[1]: Reached target slices.target. Dec 13 14:20:33.730978 systemd[1]: Reached target swap.target. Dec 13 14:20:33.730984 systemd[1]: Reached target timers.target. Dec 13 14:20:33.730992 systemd[1]: Listening on iscsid.socket. Dec 13 14:20:33.730999 systemd[1]: Listening on iscsiuio.socket. Dec 13 14:20:33.731006 systemd[1]: Listening on systemd-journald-audit.socket. Dec 13 14:20:33.731013 systemd[1]: Listening on systemd-journald-dev-log.socket. Dec 13 14:20:33.731020 systemd[1]: Listening on systemd-journald.socket. Dec 13 14:20:33.731027 systemd[1]: Listening on systemd-networkd.socket. Dec 13 14:20:33.731034 systemd[1]: Listening on systemd-udevd-control.socket. Dec 13 14:20:33.731041 systemd[1]: Listening on systemd-udevd-kernel.socket. Dec 13 14:20:33.731048 systemd[1]: Reached target sockets.target. Dec 13 14:20:33.731055 systemd[1]: Starting kmod-static-nodes.service... Dec 13 14:20:33.731063 systemd[1]: Finished network-cleanup.service. Dec 13 14:20:33.731070 systemd[1]: Starting systemd-fsck-usr.service... Dec 13 14:20:33.731077 systemd[1]: Starting systemd-journald.service... Dec 13 14:20:33.731084 systemd[1]: Starting systemd-modules-load.service... Dec 13 14:20:33.731118 systemd[1]: Starting systemd-resolved.service... Dec 13 14:20:33.731126 systemd[1]: Starting systemd-vconsole-setup.service... Dec 13 14:20:33.731133 systemd[1]: Finished kmod-static-nodes.service. Dec 13 14:20:33.731140 systemd[1]: Finished systemd-fsck-usr.service. Dec 13 14:20:33.731147 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Dec 13 14:20:33.731155 systemd[1]: Finished systemd-vconsole-setup.service. Dec 13 14:20:33.731162 systemd[1]: Starting dracut-cmdline-ask.service... Dec 13 14:20:33.731169 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Dec 13 14:20:33.731179 systemd-journald[290]: Journal started Dec 13 14:20:33.731218 systemd-journald[290]: Runtime Journal (/run/log/journal/1e1298b82092445d9986d214fa802a10) is 6.0M, max 48.7M, 42.6M free. Dec 13 14:20:33.718921 systemd-modules-load[291]: Inserted module 'overlay' Dec 13 14:20:33.735615 systemd[1]: Started systemd-journald.service. Dec 13 14:20:33.735640 kernel: audit: type=1130 audit(1734099633.731:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:33.731000 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' Dec 13 14:20:33.735521 systemd-resolved[292]: Positive Trust Anchors: Dec 13 14:20:33.739800 kernel: audit: type=1130 audit(1734099633.736: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' Dec 13 14:20:33.736000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:33.735528 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 14:20:33.735558 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 Dec 13 14:20:33.750802 kernel: audit: type=1130 audit(1734099633.741:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:33.750821 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 13 14:20:33.741000 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' Dec 13 14:20:33.739786 systemd-resolved[292]: Defaulting to hostname 'linux'. Dec 13 14:20:33.741294 systemd[1]: Started systemd-resolved.service. Dec 13 14:20:33.742021 systemd[1]: Reached target nss-lookup.target. Dec 13 14:20:33.753387 systemd[1]: Finished dracut-cmdline-ask.service. Dec 13 14:20:33.758580 kernel: audit: type=1130 audit(1734099633.753:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:33.758597 kernel: Bridge firewalling registered Dec 13 14:20:33.753000 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' Dec 13 14:20:33.754756 systemd[1]: Starting dracut-cmdline.service... Dec 13 14:20:33.757405 systemd-modules-load[291]: Inserted module 'br_netfilter' Dec 13 14:20:33.763325 dracut-cmdline[307]: dracut-dracut-053 Dec 13 14:20:33.765265 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=5997a8cf94b1df1856dc785f0a7074604bbf4c21fdcca24a1996021471a77601 Dec 13 14:20:33.771110 kernel: SCSI subsystem initialized Dec 13 14:20:33.778219 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 13 14:20:33.778250 kernel: device-mapper: uevent: version 1.0.3 Dec 13 14:20:33.779229 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Dec 13 14:20:33.781428 systemd-modules-load[291]: Inserted module 'dm_multipath' Dec 13 14:20:33.782145 systemd[1]: Finished systemd-modules-load.service. Dec 13 14:20:33.783000 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' Dec 13 14:20:33.784255 systemd[1]: Starting systemd-sysctl.service... Dec 13 14:20:33.787807 kernel: audit: type=1130 audit(1734099633.783:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:33.792719 systemd[1]: Finished systemd-sysctl.service. Dec 13 14:20:33.796194 kernel: audit: type=1130 audit(1734099633.793:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:33.793000 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' Dec 13 14:20:33.826107 kernel: Loading iSCSI transport class v2.0-870. Dec 13 14:20:33.838112 kernel: iscsi: registered transport (tcp) Dec 13 14:20:33.852124 kernel: iscsi: registered transport (qla4xxx) Dec 13 14:20:33.852143 kernel: QLogic iSCSI HBA Driver Dec 13 14:20:33.882950 systemd[1]: Finished dracut-cmdline.service. Dec 13 14:20:33.883000 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' Dec 13 14:20:33.884379 systemd[1]: Starting dracut-pre-udev.service... Dec 13 14:20:33.887609 kernel: audit: type=1130 audit(1734099633.883:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:33.928119 kernel: raid6: neonx8 gen() 13728 MB/s Dec 13 14:20:33.945112 kernel: raid6: neonx8 xor() 10777 MB/s Dec 13 14:20:33.962107 kernel: raid6: neonx4 gen() 13410 MB/s Dec 13 14:20:33.979105 kernel: raid6: neonx4 xor() 11203 MB/s Dec 13 14:20:33.996110 kernel: raid6: neonx2 gen() 12871 MB/s Dec 13 14:20:34.013107 kernel: raid6: neonx2 xor() 10519 MB/s Dec 13 14:20:34.030114 kernel: raid6: neonx1 gen() 10464 MB/s Dec 13 14:20:34.047109 kernel: raid6: neonx1 xor() 8738 MB/s Dec 13 14:20:34.064111 kernel: raid6: int64x8 gen() 6234 MB/s Dec 13 14:20:34.081110 kernel: raid6: int64x8 xor() 3518 MB/s Dec 13 14:20:34.098108 kernel: raid6: int64x4 gen() 7152 MB/s Dec 13 14:20:34.115106 kernel: raid6: int64x4 xor() 3833 MB/s Dec 13 14:20:34.132118 kernel: raid6: int64x2 gen() 6130 MB/s Dec 13 14:20:34.149116 kernel: raid6: int64x2 xor() 3300 MB/s Dec 13 14:20:34.166109 kernel: raid6: int64x1 gen() 5006 MB/s Dec 13 14:20:34.183559 kernel: raid6: int64x1 xor() 2633 MB/s Dec 13 14:20:34.183578 kernel: raid6: using algorithm neonx8 gen() 13728 MB/s Dec 13 14:20:34.183588 kernel: raid6: .... xor() 10777 MB/s, rmw enabled Dec 13 14:20:34.183596 kernel: raid6: using neon recovery algorithm Dec 13 14:20:34.196304 kernel: xor: measuring software checksum speed Dec 13 14:20:34.196333 kernel: 8regs : 17181 MB/sec Dec 13 14:20:34.197493 kernel: 32regs : 20717 MB/sec Dec 13 14:20:34.197506 kernel: arm64_neon : 27132 MB/sec Dec 13 14:20:34.197515 kernel: xor: using function: arm64_neon (27132 MB/sec) Dec 13 14:20:34.254126 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Dec 13 14:20:34.264083 systemd[1]: Finished dracut-pre-udev.service. Dec 13 14:20:34.264000 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' Dec 13 14:20:34.267000 audit: BPF prog-id=7 op=LOAD Dec 13 14:20:34.268555 kernel: audit: type=1130 audit(1734099634.264:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:34.268575 kernel: audit: type=1334 audit(1734099634.267:10): prog-id=7 op=LOAD Dec 13 14:20:34.268000 audit: BPF prog-id=8 op=LOAD Dec 13 14:20:34.268946 systemd[1]: Starting systemd-udevd.service... Dec 13 14:20:34.282572 systemd-udevd[490]: Using default interface naming scheme 'v252'. Dec 13 14:20:34.285883 systemd[1]: Started systemd-udevd.service. Dec 13 14:20:34.286000 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' Dec 13 14:20:34.287413 systemd[1]: Starting dracut-pre-trigger.service... Dec 13 14:20:34.297850 dracut-pre-trigger[497]: rd.md=0: removing MD RAID activation Dec 13 14:20:34.324121 systemd[1]: Finished dracut-pre-trigger.service. Dec 13 14:20:34.324000 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' Dec 13 14:20:34.325657 systemd[1]: Starting systemd-udev-trigger.service... Dec 13 14:20:34.366968 systemd[1]: Finished systemd-udev-trigger.service. Dec 13 14:20:34.367000 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' Dec 13 14:20:34.403695 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Dec 13 14:20:34.409213 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Dec 13 14:20:34.409227 kernel: GPT:9289727 != 19775487 Dec 13 14:20:34.409241 kernel: GPT:Alternate GPT header not at the end of the disk. Dec 13 14:20:34.409250 kernel: GPT:9289727 != 19775487 Dec 13 14:20:34.409258 kernel: GPT: Use GNU Parted to correct GPT errors. Dec 13 14:20:34.409267 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 14:20:34.419543 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Dec 13 14:20:34.422723 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Dec 13 14:20:34.424376 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (544) Dec 13 14:20:34.423693 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Dec 13 14:20:34.433318 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Dec 13 14:20:34.436664 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Dec 13 14:20:34.438348 systemd[1]: Starting disk-uuid.service... Dec 13 14:20:34.445082 disk-uuid[563]: Primary Header is updated. Dec 13 14:20:34.445082 disk-uuid[563]: Secondary Entries is updated. Dec 13 14:20:34.445082 disk-uuid[563]: Secondary Header is updated. Dec 13 14:20:34.449108 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 14:20:35.461123 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 14:20:35.461450 disk-uuid[564]: The operation has completed successfully. Dec 13 14:20:35.484619 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 13 14:20:35.485000 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' Dec 13 14:20:35.485000 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' Dec 13 14:20:35.484722 systemd[1]: Finished disk-uuid.service. Dec 13 14:20:35.486139 systemd[1]: Starting verity-setup.service... Dec 13 14:20:35.499125 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Dec 13 14:20:35.519727 systemd[1]: Found device dev-mapper-usr.device. Dec 13 14:20:35.521798 systemd[1]: Mounting sysusr-usr.mount... Dec 13 14:20:35.523570 systemd[1]: Finished verity-setup.service. Dec 13 14:20:35.524000 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' Dec 13 14:20:35.570085 systemd[1]: Mounted sysusr-usr.mount. Dec 13 14:20:35.571318 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Dec 13 14:20:35.570887 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Dec 13 14:20:35.571516 systemd[1]: Starting ignition-setup.service... Dec 13 14:20:35.573545 systemd[1]: Starting parse-ip-for-networkd.service... Dec 13 14:20:35.580660 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:20:35.580689 kernel: BTRFS info (device vda6): using free space tree Dec 13 14:20:35.580699 kernel: BTRFS info (device vda6): has skinny extents Dec 13 14:20:35.587873 systemd[1]: mnt-oem.mount: Deactivated successfully. Dec 13 14:20:35.593000 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' Dec 13 14:20:35.593036 systemd[1]: Finished ignition-setup.service. Dec 13 14:20:35.594525 systemd[1]: Starting ignition-fetch-offline.service... Dec 13 14:20:35.654000 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' Dec 13 14:20:35.654000 audit: BPF prog-id=9 op=LOAD Dec 13 14:20:35.653398 systemd[1]: Finished parse-ip-for-networkd.service. Dec 13 14:20:35.655477 systemd[1]: Starting systemd-networkd.service... Dec 13 14:20:35.671704 ignition[651]: Ignition 2.14.0 Dec 13 14:20:35.671713 ignition[651]: Stage: fetch-offline Dec 13 14:20:35.671748 ignition[651]: no configs at "/usr/lib/ignition/base.d" Dec 13 14:20:35.671756 ignition[651]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 14:20:35.671877 ignition[651]: parsed url from cmdline: "" Dec 13 14:20:35.671880 ignition[651]: no config URL provided Dec 13 14:20:35.671884 ignition[651]: reading system config file "/usr/lib/ignition/user.ign" Dec 13 14:20:35.671890 ignition[651]: no config at "/usr/lib/ignition/user.ign" Dec 13 14:20:35.671907 ignition[651]: op(1): [started] loading QEMU firmware config module Dec 13 14:20:35.671911 ignition[651]: op(1): executing: "modprobe" "qemu_fw_cfg" Dec 13 14:20:35.679390 systemd-networkd[740]: lo: Link UP Dec 13 14:20:35.679401 systemd-networkd[740]: lo: Gained carrier Dec 13 14:20:35.680000 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' Dec 13 14:20:35.679966 systemd-networkd[740]: Enumeration completed Dec 13 14:20:35.680054 systemd[1]: Started systemd-networkd.service. Dec 13 14:20:35.680330 systemd-networkd[740]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 14:20:35.683515 ignition[651]: op(1): [finished] loading QEMU firmware config module Dec 13 14:20:35.680899 systemd[1]: Reached target network.target. Dec 13 14:20:35.681672 systemd-networkd[740]: eth0: Link UP Dec 13 14:20:35.681677 systemd-networkd[740]: eth0: Gained carrier Dec 13 14:20:35.682828 systemd[1]: Starting iscsiuio.service... Dec 13 14:20:35.692775 ignition[651]: parsing config with SHA512: acd70a42212b359d55884b29bd6e04b8224d8b4d7616cfccbf7cce23104f7ec2bfc80b8b238b0e7bf3d0cb08b9685e0844c07c1d522bd54ef97eebd460756e85 Dec 13 14:20:35.693408 systemd[1]: Started iscsiuio.service. Dec 13 14:20:35.694863 systemd[1]: Starting iscsid.service... Dec 13 14:20:35.694000 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' Dec 13 14:20:35.697661 unknown[651]: fetched base config from "system" Dec 13 14:20:35.697672 unknown[651]: fetched user config from "qemu" Dec 13 14:20:35.699120 iscsid[748]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Dec 13 14:20:35.699120 iscsid[748]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Dec 13 14:20:35.699120 iscsid[748]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Dec 13 14:20:35.699120 iscsid[748]: If using hardware iscsi like qla4xxx this message can be ignored. Dec 13 14:20:35.699120 iscsid[748]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Dec 13 14:20:35.699120 iscsid[748]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Dec 13 14:20:35.705000 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' Dec 13 14:20:35.706000 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' Dec 13 14:20:35.697932 ignition[651]: fetch-offline: fetch-offline passed Dec 13 14:20:35.701003 systemd[1]: Started iscsid.service. Dec 13 14:20:35.697992 ignition[651]: Ignition finished successfully Dec 13 14:20:35.705160 systemd-networkd[740]: eth0: DHCPv4 address 10.0.0.135/16, gateway 10.0.0.1 acquired from 10.0.0.1 Dec 13 14:20:35.705509 systemd[1]: Finished ignition-fetch-offline.service. Dec 13 14:20:35.707530 systemd[1]: Starting dracut-initqueue.service... Dec 13 14:20:35.708238 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Dec 13 14:20:35.708876 systemd[1]: Starting ignition-kargs.service... Dec 13 14:20:35.718000 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' Dec 13 14:20:35.717326 systemd[1]: Finished dracut-initqueue.service. Dec 13 14:20:35.718316 systemd[1]: Reached target remote-fs-pre.target. Dec 13 14:20:35.718909 ignition[750]: Ignition 2.14.0 Dec 13 14:20:35.719411 systemd[1]: Reached target remote-cryptsetup.target. Dec 13 14:20:35.718915 ignition[750]: Stage: kargs Dec 13 14:20:35.721481 systemd[1]: Reached target remote-fs.target. Dec 13 14:20:35.725000 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' Dec 13 14:20:35.719003 ignition[750]: no configs at "/usr/lib/ignition/base.d" Dec 13 14:20:35.723259 systemd[1]: Starting dracut-pre-mount.service... Dec 13 14:20:35.719011 ignition[750]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 14:20:35.724663 systemd[1]: Finished ignition-kargs.service. Dec 13 14:20:35.719666 ignition[750]: kargs: kargs passed Dec 13 14:20:35.726306 systemd[1]: Starting ignition-disks.service... Dec 13 14:20:35.719702 ignition[750]: Ignition finished successfully Dec 13 14:20:35.732000 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' Dec 13 14:20:35.731644 systemd[1]: Finished dracut-pre-mount.service. Dec 13 14:20:35.733406 ignition[765]: Ignition 2.14.0 Dec 13 14:20:35.733411 ignition[765]: Stage: disks Dec 13 14:20:35.733495 ignition[765]: no configs at "/usr/lib/ignition/base.d" Dec 13 14:20:35.735000 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' Dec 13 14:20:35.734807 systemd[1]: Finished ignition-disks.service. Dec 13 14:20:35.733504 ignition[765]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 14:20:35.735561 systemd[1]: Reached target initrd-root-device.target. Dec 13 14:20:35.734125 ignition[765]: disks: disks passed Dec 13 14:20:35.736780 systemd[1]: Reached target local-fs-pre.target. Dec 13 14:20:35.734163 ignition[765]: Ignition finished successfully Dec 13 14:20:35.738171 systemd[1]: Reached target local-fs.target. Dec 13 14:20:35.739562 systemd[1]: Reached target sysinit.target. Dec 13 14:20:35.740845 systemd[1]: Reached target basic.target. Dec 13 14:20:35.742528 systemd[1]: Starting systemd-fsck-root.service... Dec 13 14:20:35.753024 systemd-fsck[777]: ROOT: clean, 621/553520 files, 56020/553472 blocks Dec 13 14:20:35.755872 systemd[1]: Finished systemd-fsck-root.service. Dec 13 14:20:35.756000 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' Dec 13 14:20:35.757278 systemd[1]: Mounting sysroot.mount... Dec 13 14:20:35.764121 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Dec 13 14:20:35.764659 systemd[1]: Mounted sysroot.mount. Dec 13 14:20:35.765293 systemd[1]: Reached target initrd-root-fs.target. Dec 13 14:20:35.767291 systemd[1]: Mounting sysroot-usr.mount... Dec 13 14:20:35.768043 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Dec 13 14:20:35.768077 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 13 14:20:35.768130 systemd[1]: Reached target ignition-diskful.target. Dec 13 14:20:35.769743 systemd[1]: Mounted sysroot-usr.mount. Dec 13 14:20:35.771373 systemd[1]: Starting initrd-setup-root.service... Dec 13 14:20:35.775305 initrd-setup-root[787]: cut: /sysroot/etc/passwd: No such file or directory Dec 13 14:20:35.778701 initrd-setup-root[795]: cut: /sysroot/etc/group: No such file or directory Dec 13 14:20:35.782638 initrd-setup-root[803]: cut: /sysroot/etc/shadow: No such file or directory Dec 13 14:20:35.786374 initrd-setup-root[811]: cut: /sysroot/etc/gshadow: No such file or directory Dec 13 14:20:35.810877 systemd[1]: Finished initrd-setup-root.service. Dec 13 14:20:35.811000 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' Dec 13 14:20:35.812276 systemd[1]: Starting ignition-mount.service... Dec 13 14:20:35.813425 systemd[1]: Starting sysroot-boot.service... Dec 13 14:20:35.817290 bash[828]: umount: /sysroot/usr/share/oem: not mounted. Dec 13 14:20:35.824396 ignition[829]: INFO : Ignition 2.14.0 Dec 13 14:20:35.824396 ignition[829]: INFO : Stage: mount Dec 13 14:20:35.825808 ignition[829]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 14:20:35.825808 ignition[829]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 14:20:35.825808 ignition[829]: INFO : mount: mount passed Dec 13 14:20:35.825808 ignition[829]: INFO : Ignition finished successfully Dec 13 14:20:35.827000 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' Dec 13 14:20:35.825900 systemd[1]: Finished ignition-mount.service. Dec 13 14:20:35.832576 systemd[1]: Finished sysroot-boot.service. Dec 13 14:20:35.833000 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' Dec 13 14:20:36.530167 systemd[1]: Mounting sysroot-usr-share-oem.mount... Dec 13 14:20:36.536905 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (838) Dec 13 14:20:36.536934 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Dec 13 14:20:36.536943 kernel: BTRFS info (device vda6): using free space tree Dec 13 14:20:36.538110 kernel: BTRFS info (device vda6): has skinny extents Dec 13 14:20:36.540704 systemd[1]: Mounted sysroot-usr-share-oem.mount. Dec 13 14:20:36.542047 systemd[1]: Starting ignition-files.service... Dec 13 14:20:36.555185 ignition[858]: INFO : Ignition 2.14.0 Dec 13 14:20:36.555185 ignition[858]: INFO : Stage: files Dec 13 14:20:36.556799 ignition[858]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 14:20:36.556799 ignition[858]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 14:20:36.556799 ignition[858]: DEBUG : files: compiled without relabeling support, skipping Dec 13 14:20:36.560245 ignition[858]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 13 14:20:36.560245 ignition[858]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 13 14:20:36.562732 ignition[858]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 13 14:20:36.562732 ignition[858]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 13 14:20:36.565280 ignition[858]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 14:20:36.565280 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-arm64.raw: attempt #1 Dec 13 14:20:36.562958 unknown[858]: wrote ssh authorized keys file for user: core Dec 13 14:20:36.915295 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Dec 13 14:20:37.119487 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 14:20:37.119487 ignition[858]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Dec 13 14:20:37.123341 ignition[858]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Dec 13 14:20:37.123341 ignition[858]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Dec 13 14:20:37.123341 ignition[858]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Dec 13 14:20:37.123341 ignition[858]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Dec 13 14:20:37.123341 ignition[858]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Dec 13 14:20:37.190561 ignition[858]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Dec 13 14:20:37.192879 ignition[858]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Dec 13 14:20:37.192879 ignition[858]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 13 14:20:37.192879 ignition[858]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 13 14:20:37.192879 ignition[858]: INFO : files: files passed Dec 13 14:20:37.192879 ignition[858]: INFO : Ignition finished successfully Dec 13 14:20:37.194000 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' Dec 13 14:20:37.193042 systemd[1]: Finished ignition-files.service. Dec 13 14:20:37.195856 systemd[1]: Starting initrd-setup-root-after-ignition.service... Dec 13 14:20:37.202000 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' Dec 13 14:20:37.202000 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' Dec 13 14:20:37.203838 initrd-setup-root-after-ignition[884]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Dec 13 14:20:37.197227 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Dec 13 14:20:37.206000 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' Dec 13 14:20:37.207905 initrd-setup-root-after-ignition[886]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 13 14:20:37.197935 systemd[1]: Starting ignition-quench.service... Dec 13 14:20:37.201543 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 13 14:20:37.201646 systemd[1]: Finished ignition-quench.service. Dec 13 14:20:37.205254 systemd[1]: Finished initrd-setup-root-after-ignition.service. Dec 13 14:20:37.206299 systemd[1]: Reached target ignition-complete.target. Dec 13 14:20:37.209258 systemd[1]: Starting initrd-parse-etc.service... Dec 13 14:20:37.223004 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 13 14:20:37.223108 systemd[1]: Finished initrd-parse-etc.service. Dec 13 14:20:37.224000 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' Dec 13 14:20:37.224000 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' Dec 13 14:20:37.224919 systemd[1]: Reached target initrd-fs.target. Dec 13 14:20:37.226111 systemd[1]: Reached target initrd.target. Dec 13 14:20:37.227350 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Dec 13 14:20:37.228018 systemd[1]: Starting dracut-pre-pivot.service... Dec 13 14:20:37.237703 systemd[1]: Finished dracut-pre-pivot.service. Dec 13 14:20:37.238000 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' Dec 13 14:20:37.239151 systemd[1]: Starting initrd-cleanup.service... Dec 13 14:20:37.246468 systemd[1]: Stopped target nss-lookup.target. Dec 13 14:20:37.247314 systemd[1]: Stopped target remote-cryptsetup.target. Dec 13 14:20:37.248668 systemd[1]: Stopped target timers.target. Dec 13 14:20:37.249953 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 13 14:20:37.251000 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' Dec 13 14:20:37.250052 systemd[1]: Stopped dracut-pre-pivot.service. Dec 13 14:20:37.251341 systemd[1]: Stopped target initrd.target. Dec 13 14:20:37.252681 systemd[1]: Stopped target basic.target. Dec 13 14:20:37.253895 systemd[1]: Stopped target ignition-complete.target. Dec 13 14:20:37.255167 systemd[1]: Stopped target ignition-diskful.target. Dec 13 14:20:37.256420 systemd[1]: Stopped target initrd-root-device.target. Dec 13 14:20:37.257844 systemd[1]: Stopped target remote-fs.target. Dec 13 14:20:37.259164 systemd[1]: Stopped target remote-fs-pre.target. Dec 13 14:20:37.260616 systemd[1]: Stopped target sysinit.target. Dec 13 14:20:37.261845 systemd[1]: Stopped target local-fs.target. Dec 13 14:20:37.263170 systemd[1]: Stopped target local-fs-pre.target. Dec 13 14:20:37.264455 systemd[1]: Stopped target swap.target. Dec 13 14:20:37.266000 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' Dec 13 14:20:37.265657 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 13 14:20:37.265765 systemd[1]: Stopped dracut-pre-mount.service. Dec 13 14:20:37.269000 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' Dec 13 14:20:37.267071 systemd[1]: Stopped target cryptsetup.target. Dec 13 14:20:37.270000 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' Dec 13 14:20:37.268251 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 13 14:20:37.268347 systemd[1]: Stopped dracut-initqueue.service. Dec 13 14:20:37.269745 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 13 14:20:37.269833 systemd[1]: Stopped ignition-fetch-offline.service. Dec 13 14:20:37.271143 systemd[1]: Stopped target paths.target. Dec 13 14:20:37.272287 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 13 14:20:37.277122 systemd[1]: Stopped systemd-ask-password-console.path. Dec 13 14:20:37.278009 systemd[1]: Stopped target slices.target. Dec 13 14:20:37.279435 systemd[1]: Stopped target sockets.target. Dec 13 14:20:37.280640 systemd[1]: iscsid.socket: Deactivated successfully. Dec 13 14:20:37.280710 systemd[1]: Closed iscsid.socket. Dec 13 14:20:37.283000 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' Dec 13 14:20:37.281859 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 13 14:20:37.284000 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' Dec 13 14:20:37.281957 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Dec 13 14:20:37.283424 systemd[1]: ignition-files.service: Deactivated successfully. Dec 13 14:20:37.283509 systemd[1]: Stopped ignition-files.service. Dec 13 14:20:37.288000 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' Dec 13 14:20:37.285443 systemd[1]: Stopping ignition-mount.service... Dec 13 14:20:37.286727 systemd[1]: Stopping iscsiuio.service... Dec 13 14:20:37.292554 ignition[900]: INFO : Ignition 2.14.0 Dec 13 14:20:37.292554 ignition[900]: INFO : Stage: umount Dec 13 14:20:37.287413 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 13 14:20:37.295820 ignition[900]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 14:20:37.295820 ignition[900]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 14:20:37.295820 ignition[900]: INFO : umount: umount passed Dec 13 14:20:37.295820 ignition[900]: INFO : Ignition finished successfully Dec 13 14:20:37.296000 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' Dec 13 14:20:37.298000 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' Dec 13 14:20:37.287526 systemd[1]: Stopped kmod-static-nodes.service. Dec 13 14:20:37.301000 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' Dec 13 14:20:37.289688 systemd[1]: Stopping sysroot-boot.service... Dec 13 14:20:37.294958 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 13 14:20:37.295116 systemd[1]: Stopped systemd-udev-trigger.service. Dec 13 14:20:37.296603 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 13 14:20:37.306000 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' Dec 13 14:20:37.296702 systemd[1]: Stopped dracut-pre-trigger.service. Dec 13 14:20:37.299830 systemd[1]: iscsiuio.service: Deactivated successfully. Dec 13 14:20:37.299961 systemd[1]: Stopped iscsiuio.service. Dec 13 14:20:37.312000 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' Dec 13 14:20:37.304235 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 13 14:20:37.313000 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' Dec 13 14:20:37.304757 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 13 14:20:37.315000 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' Dec 13 14:20:37.304844 systemd[1]: Stopped ignition-mount.service. Dec 13 14:20:37.306645 systemd[1]: Stopped target network.target. Dec 13 14:20:37.308961 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 13 14:20:37.319000 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' Dec 13 14:20:37.319000 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' Dec 13 14:20:37.308995 systemd[1]: Closed iscsiuio.socket. Dec 13 14:20:37.310101 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 13 14:20:37.310143 systemd[1]: Stopped ignition-disks.service. Dec 13 14:20:37.312442 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 13 14:20:37.312482 systemd[1]: Stopped ignition-kargs.service. Dec 13 14:20:37.313637 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 13 14:20:37.325000 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' Dec 13 14:20:37.313674 systemd[1]: Stopped ignition-setup.service. Dec 13 14:20:37.315423 systemd[1]: Stopping systemd-networkd.service... Dec 13 14:20:37.316876 systemd[1]: Stopping systemd-resolved.service... Dec 13 14:20:37.328000 audit: BPF prog-id=6 op=UNLOAD Dec 13 14:20:37.328000 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' Dec 13 14:20:37.318814 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 13 14:20:37.318893 systemd[1]: Finished initrd-cleanup.service. Dec 13 14:20:37.324199 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 13 14:20:37.332000 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' Dec 13 14:20:37.324301 systemd[1]: Stopped systemd-resolved.service. Dec 13 14:20:37.334000 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' Dec 13 14:20:37.325324 systemd-networkd[740]: eth0: DHCPv6 lease lost Dec 13 14:20:37.334000 audit: BPF prog-id=9 op=UNLOAD Dec 13 14:20:37.335000 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' Dec 13 14:20:37.326871 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 13 14:20:37.326964 systemd[1]: Stopped systemd-networkd.service. Dec 13 14:20:37.328741 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 13 14:20:37.341000 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' Dec 13 14:20:37.328768 systemd[1]: Closed systemd-networkd.socket. Dec 13 14:20:37.343000 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' Dec 13 14:20:37.330847 systemd[1]: Stopping network-cleanup.service... Dec 13 14:20:37.331587 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 13 14:20:37.345000 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' Dec 13 14:20:37.331681 systemd[1]: Stopped parse-ip-for-networkd.service. Dec 13 14:20:37.333015 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 13 14:20:37.347000 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' Dec 13 14:20:37.333057 systemd[1]: Stopped systemd-sysctl.service. Dec 13 14:20:37.334971 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 13 14:20:37.335014 systemd[1]: Stopped systemd-modules-load.service. Dec 13 14:20:37.351000 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' Dec 13 14:20:37.336009 systemd[1]: Stopping systemd-udevd.service... Dec 13 14:20:37.353000 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' Dec 13 14:20:37.340378 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Dec 13 14:20:37.354000 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' Dec 13 14:20:37.340854 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 13 14:20:37.340929 systemd[1]: Stopped sysroot-boot.service. Dec 13 14:20:37.342559 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 13 14:20:37.342630 systemd[1]: Stopped initrd-setup-root.service. Dec 13 14:20:37.344364 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 13 14:20:37.357000 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' Dec 13 14:20:37.344441 systemd[1]: Stopped network-cleanup.service. Dec 13 14:20:37.346584 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 13 14:20:37.362000 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' Dec 13 14:20:37.362000 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' Dec 13 14:20:37.346700 systemd[1]: Stopped systemd-udevd.service. Dec 13 14:20:37.347970 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 13 14:20:37.348002 systemd[1]: Closed systemd-udevd-control.socket. Dec 13 14:20:37.349336 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 13 14:20:37.349369 systemd[1]: Closed systemd-udevd-kernel.socket. Dec 13 14:20:37.350563 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 13 14:20:37.350605 systemd[1]: Stopped dracut-pre-udev.service. Dec 13 14:20:37.351996 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 13 14:20:37.352034 systemd[1]: Stopped dracut-cmdline.service. Dec 13 14:20:37.353286 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 14:20:37.353324 systemd[1]: Stopped dracut-cmdline-ask.service. Dec 13 14:20:37.355212 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Dec 13 14:20:37.356165 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 14:20:37.356220 systemd[1]: Stopped systemd-vconsole-setup.service. Dec 13 14:20:37.360736 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 13 14:20:37.360818 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Dec 13 14:20:37.362256 systemd[1]: Reached target initrd-switch-root.target. Dec 13 14:20:37.364272 systemd[1]: Starting initrd-switch-root.service... Dec 13 14:20:37.370415 systemd[1]: Switching root. Dec 13 14:20:37.388401 iscsid[748]: iscsid shutting down. Dec 13 14:20:37.389031 systemd-journald[290]: Journal stopped Dec 13 14:20:39.379756 systemd-journald[290]: Received SIGTERM from PID 1 (systemd). Dec 13 14:20:39.379817 kernel: SELinux: Class mctp_socket not defined in policy. Dec 13 14:20:39.379830 kernel: SELinux: Class anon_inode not defined in policy. Dec 13 14:20:39.379840 kernel: SELinux: the above unknown classes and permissions will be allowed Dec 13 14:20:39.379850 kernel: SELinux: policy capability network_peer_controls=1 Dec 13 14:20:39.379862 kernel: SELinux: policy capability open_perms=1 Dec 13 14:20:39.379875 kernel: SELinux: policy capability extended_socket_class=1 Dec 13 14:20:39.379884 kernel: SELinux: policy capability always_check_network=0 Dec 13 14:20:39.379894 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 13 14:20:39.379903 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 13 14:20:39.379913 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 13 14:20:39.379925 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 13 14:20:39.379935 systemd[1]: Successfully loaded SELinux policy in 35.981ms. Dec 13 14:20:39.379950 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.633ms. Dec 13 14:20:39.379964 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) Dec 13 14:20:39.379975 systemd[1]: Detected virtualization kvm. Dec 13 14:20:39.379986 systemd[1]: Detected architecture arm64. Dec 13 14:20:39.379997 systemd[1]: Detected first boot. Dec 13 14:20:39.380010 systemd[1]: Initializing machine ID from VM UUID. Dec 13 14:20:39.380020 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Dec 13 14:20:39.380031 systemd[1]: Populated /etc with preset unit settings. Dec 13 14:20:39.380043 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Dec 13 14:20:39.380055 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Dec 13 14:20:39.380066 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. Dec 13 14:20:39.380077 kernel: kauditd_printk_skb: 78 callbacks suppressed Dec 13 14:20:39.380086 kernel: audit: type=1334 audit(1734099639.232:82): prog-id=12 op=LOAD Dec 13 14:20:39.380114 kernel: audit: type=1334 audit(1734099639.232:83): prog-id=3 op=UNLOAD Dec 13 14:20:39.380123 kernel: audit: type=1334 audit(1734099639.233:84): prog-id=13 op=LOAD Dec 13 14:20:39.380133 systemd[1]: iscsid.service: Deactivated successfully. Dec 13 14:20:39.380144 kernel: audit: type=1334 audit(1734099639.233:85): prog-id=14 op=LOAD Dec 13 14:20:39.380154 systemd[1]: Stopped iscsid.service. Dec 13 14:20:39.380164 kernel: audit: type=1334 audit(1734099639.233:86): prog-id=4 op=UNLOAD Dec 13 14:20:39.380173 kernel: audit: type=1334 audit(1734099639.233:87): prog-id=5 op=UNLOAD Dec 13 14:20:39.380183 kernel: audit: type=1131 audit(1734099639.235:88): 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' Dec 13 14:20:39.380194 kernel: audit: type=1131 audit(1734099639.242:89): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.380203 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 13 14:20:39.380214 systemd[1]: Stopped initrd-switch-root.service. Dec 13 14:20:39.380226 kernel: audit: type=1130 audit(1734099639.248:90): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.380236 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 13 14:20:39.380247 kernel: audit: type=1131 audit(1734099639.248:91): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.380260 systemd[1]: Created slice system-addon\x2dconfig.slice. Dec 13 14:20:39.380271 systemd[1]: Created slice system-addon\x2drun.slice. Dec 13 14:20:39.380281 systemd[1]: Created slice system-getty.slice. Dec 13 14:20:39.380292 systemd[1]: Created slice system-modprobe.slice. Dec 13 14:20:39.380302 systemd[1]: Created slice system-serial\x2dgetty.slice. Dec 13 14:20:39.380314 systemd[1]: Created slice system-system\x2dcloudinit.slice. Dec 13 14:20:39.380325 systemd[1]: Created slice system-systemd\x2dfsck.slice. Dec 13 14:20:39.380335 systemd[1]: Created slice user.slice. Dec 13 14:20:39.380346 systemd[1]: Started systemd-ask-password-console.path. Dec 13 14:20:39.380357 systemd[1]: Started systemd-ask-password-wall.path. Dec 13 14:20:39.380367 systemd[1]: Set up automount boot.automount. Dec 13 14:20:39.380377 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Dec 13 14:20:39.380387 systemd[1]: Stopped target initrd-switch-root.target. Dec 13 14:20:39.380398 systemd[1]: Stopped target initrd-fs.target. Dec 13 14:20:39.380409 systemd[1]: Stopped target initrd-root-fs.target. Dec 13 14:20:39.380419 systemd[1]: Reached target integritysetup.target. Dec 13 14:20:39.380431 systemd[1]: Reached target remote-cryptsetup.target. Dec 13 14:20:39.380441 systemd[1]: Reached target remote-fs.target. Dec 13 14:20:39.380452 systemd[1]: Reached target slices.target. Dec 13 14:20:39.380463 systemd[1]: Reached target swap.target. Dec 13 14:20:39.380474 systemd[1]: Reached target torcx.target. Dec 13 14:20:39.380484 systemd[1]: Reached target veritysetup.target. Dec 13 14:20:39.380495 systemd[1]: Listening on systemd-coredump.socket. Dec 13 14:20:39.380505 systemd[1]: Listening on systemd-initctl.socket. Dec 13 14:20:39.380515 systemd[1]: Listening on systemd-networkd.socket. Dec 13 14:20:39.380526 systemd[1]: Listening on systemd-udevd-control.socket. Dec 13 14:20:39.380536 systemd[1]: Listening on systemd-udevd-kernel.socket. Dec 13 14:20:39.380547 systemd[1]: Listening on systemd-userdbd.socket. Dec 13 14:20:39.380557 systemd[1]: Mounting dev-hugepages.mount... Dec 13 14:20:39.380569 systemd[1]: Mounting dev-mqueue.mount... Dec 13 14:20:39.380579 systemd[1]: Mounting media.mount... Dec 13 14:20:39.380589 systemd[1]: Mounting sys-kernel-debug.mount... Dec 13 14:20:39.380599 systemd[1]: Mounting sys-kernel-tracing.mount... Dec 13 14:20:39.380610 systemd[1]: Mounting tmp.mount... Dec 13 14:20:39.380626 systemd[1]: Starting flatcar-tmpfiles.service... Dec 13 14:20:39.380638 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Dec 13 14:20:39.380649 systemd[1]: Starting kmod-static-nodes.service... Dec 13 14:20:39.380660 systemd[1]: Starting modprobe@configfs.service... Dec 13 14:20:39.380672 systemd[1]: Starting modprobe@dm_mod.service... Dec 13 14:20:39.380682 systemd[1]: Starting modprobe@drm.service... Dec 13 14:20:39.380693 systemd[1]: Starting modprobe@efi_pstore.service... Dec 13 14:20:39.380703 systemd[1]: Starting modprobe@fuse.service... Dec 13 14:20:39.380713 systemd[1]: Starting modprobe@loop.service... Dec 13 14:20:39.380724 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 13 14:20:39.380734 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 13 14:20:39.380745 systemd[1]: Stopped systemd-fsck-root.service. Dec 13 14:20:39.380755 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 13 14:20:39.380767 systemd[1]: Stopped systemd-fsck-usr.service. Dec 13 14:20:39.380777 systemd[1]: Stopped systemd-journald.service. Dec 13 14:20:39.380788 systemd[1]: Starting systemd-journald.service... Dec 13 14:20:39.380798 kernel: fuse: init (API version 7.34) Dec 13 14:20:39.380808 systemd[1]: Starting systemd-modules-load.service... Dec 13 14:20:39.380818 systemd[1]: Starting systemd-network-generator.service... Dec 13 14:20:39.380828 systemd[1]: Starting systemd-remount-fs.service... Dec 13 14:20:39.380838 systemd[1]: Starting systemd-udev-trigger.service... Dec 13 14:20:39.380848 systemd[1]: verity-setup.service: Deactivated successfully. Dec 13 14:20:39.380859 systemd[1]: Stopped verity-setup.service. Dec 13 14:20:39.380870 systemd[1]: Mounted dev-hugepages.mount. Dec 13 14:20:39.380883 kernel: loop: module loaded Dec 13 14:20:39.380893 systemd[1]: Mounted dev-mqueue.mount. Dec 13 14:20:39.380902 systemd[1]: Mounted media.mount. Dec 13 14:20:39.380913 systemd[1]: Mounted sys-kernel-debug.mount. Dec 13 14:20:39.380923 systemd[1]: Mounted sys-kernel-tracing.mount. Dec 13 14:20:39.380933 systemd[1]: Mounted tmp.mount. Dec 13 14:20:39.380943 systemd[1]: Finished kmod-static-nodes.service. Dec 13 14:20:39.380956 systemd-journald[995]: Journal started Dec 13 14:20:39.380996 systemd-journald[995]: Runtime Journal (/run/log/journal/1e1298b82092445d9986d214fa802a10) is 6.0M, max 48.7M, 42.6M free. Dec 13 14:20:37.454000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 13 14:20:37.523000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Dec 13 14:20:37.523000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Dec 13 14:20:37.523000 audit: BPF prog-id=10 op=LOAD Dec 13 14:20:37.523000 audit: BPF prog-id=10 op=UNLOAD Dec 13 14:20:37.523000 audit: BPF prog-id=11 op=LOAD Dec 13 14:20:37.523000 audit: BPF prog-id=11 op=UNLOAD Dec 13 14:20:37.559000 audit[933]: AVC avc: denied { associate } for pid=933 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Dec 13 14:20:37.559000 audit[933]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001158dc a1=4000028e40 a2=4000027100 a3=32 items=0 ppid=916 pid=933 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:20:37.559000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Dec 13 14:20:37.560000 audit[933]: AVC avc: denied { associate } for pid=933 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Dec 13 14:20:37.560000 audit[933]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001159b5 a2=1ed a3=0 items=2 ppid=916 pid=933 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Dec 13 14:20:37.560000 audit: CWD cwd="/" Dec 13 14:20:37.560000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Dec 13 14:20:37.560000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Dec 13 14:20:37.560000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Dec 13 14:20:39.232000 audit: BPF prog-id=12 op=LOAD Dec 13 14:20:39.232000 audit: BPF prog-id=3 op=UNLOAD Dec 13 14:20:39.233000 audit: BPF prog-id=13 op=LOAD Dec 13 14:20:39.233000 audit: BPF prog-id=14 op=LOAD Dec 13 14:20:39.233000 audit: BPF prog-id=4 op=UNLOAD Dec 13 14:20:39.233000 audit: BPF prog-id=5 op=UNLOAD Dec 13 14:20:39.235000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.242000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.248000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.248000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.257000 audit: BPF prog-id=12 op=UNLOAD Dec 13 14:20:39.338000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.341000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.342000 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' Dec 13 14:20:39.342000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.343000 audit: BPF prog-id=15 op=LOAD Dec 13 14:20:39.343000 audit: BPF prog-id=16 op=LOAD Dec 13 14:20:39.343000 audit: BPF prog-id=17 op=LOAD Dec 13 14:20:39.343000 audit: BPF prog-id=13 op=UNLOAD Dec 13 14:20:39.343000 audit: BPF prog-id=14 op=UNLOAD Dec 13 14:20:39.364000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:39.378000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Dec 13 14:20:39.378000 audit[995]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=4 a1=ffffd3f60d90 a2=4000 a3=1 items=0 ppid=1 pid=995 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) Dec 13 14:20:39.378000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Dec 13 14:20:39.381000 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' Dec 13 14:20:39.230477 systemd[1]: Queued start job for default target multi-user.target. Dec 13 14:20:37.558427 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20: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.6 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.6 /var/lib/torcx/store]" Dec 13 14:20:39.230496 systemd[1]: Unnecessary job was removed for dev-vda6.device. Dec 13 14:20:37.558655 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 14:20:39.234831 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 13 14:20:37.558672 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 14:20:37.558699 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Dec 13 14:20:37.558708 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="skipped missing lower profile" missing profile=oem Dec 13 14:20:37.558733 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Dec 13 14:20:37.558744 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Dec 13 14:20:37.558922 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Dec 13 14:20:37.558957 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 14:20:37.558968 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 14:20:37.559355 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Dec 13 14:20:37.559386 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Dec 13 14:20:37.559402 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.6: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.6 Dec 13 14:20:39.383368 systemd[1]: Started systemd-journald.service. Dec 13 14:20:37.559416 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Dec 13 14:20:37.559434 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.6: no such file or directory" path=/var/lib/torcx/store/3510.3.6 Dec 13 14:20:37.559447 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:37Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Dec 13 14:20:39.383000 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' Dec 13 14:20:38.980085 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:38Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:20:38.980366 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:38Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:20:38.980466 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:38Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:20:38.980640 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:38Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 14:20:38.980689 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:38Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Dec 13 14:20:38.980743 /usr/lib/systemd/system-generators/torcx-generator[933]: time="2024-12-13T14:20:38Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Dec 13 14:20:39.384065 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 13 14:20:39.384202 systemd[1]: Finished modprobe@configfs.service. Dec 13 14:20:39.384000 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' Dec 13 14:20:39.384000 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' Dec 13 14:20:39.385307 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 14:20:39.385560 systemd[1]: Finished modprobe@dm_mod.service. Dec 13 14:20:39.386000 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' Dec 13 14:20:39.386000 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' Dec 13 14:20:39.387000 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' Dec 13 14:20:39.387000 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' Dec 13 14:20:39.386596 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 14:20:39.386753 systemd[1]: Finished modprobe@drm.service. Dec 13 14:20:39.387765 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 14:20:39.387903 systemd[1]: Finished modprobe@efi_pstore.service. Dec 13 14:20:39.388000 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' Dec 13 14:20:39.388000 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' Dec 13 14:20:39.388972 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 13 14:20:39.389230 systemd[1]: Finished modprobe@fuse.service. Dec 13 14:20:39.389000 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' Dec 13 14:20:39.389000 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' Dec 13 14:20:39.390225 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 14:20:39.390368 systemd[1]: Finished modprobe@loop.service. Dec 13 14:20:39.390000 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' Dec 13 14:20:39.391000 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' Dec 13 14:20:39.391511 systemd[1]: Finished systemd-modules-load.service. Dec 13 14:20:39.392000 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' Dec 13 14:20:39.392717 systemd[1]: Finished systemd-network-generator.service. Dec 13 14:20:39.393000 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' Dec 13 14:20:39.393866 systemd[1]: Finished systemd-remount-fs.service. Dec 13 14:20:39.394000 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' Dec 13 14:20:39.395032 systemd[1]: Finished flatcar-tmpfiles.service. Dec 13 14:20:39.395000 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' Dec 13 14:20:39.396218 systemd[1]: Reached target network-pre.target. Dec 13 14:20:39.398022 systemd[1]: Mounting sys-fs-fuse-connections.mount... Dec 13 14:20:39.399916 systemd[1]: Mounting sys-kernel-config.mount... Dec 13 14:20:39.400738 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 13 14:20:39.402136 systemd[1]: Starting systemd-hwdb-update.service... Dec 13 14:20:39.403994 systemd[1]: Starting systemd-journal-flush.service... Dec 13 14:20:39.404968 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 14:20:39.405960 systemd[1]: Starting systemd-random-seed.service... Dec 13 14:20:39.406854 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Dec 13 14:20:39.407834 systemd[1]: Starting systemd-sysctl.service... Dec 13 14:20:39.411958 systemd[1]: Starting systemd-sysusers.service... Dec 13 14:20:39.413402 systemd-journald[995]: Time spent on flushing to /var/log/journal/1e1298b82092445d9986d214fa802a10 is 13.004ms for 968 entries. Dec 13 14:20:39.413402 systemd-journald[995]: System Journal (/var/log/journal/1e1298b82092445d9986d214fa802a10) is 8.0M, max 195.6M, 187.6M free. Dec 13 14:20:39.442193 systemd-journald[995]: Received client request to flush runtime journal. Dec 13 14:20:39.417000 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' Dec 13 14:20:39.427000 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' Dec 13 14:20:39.440000 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' Dec 13 14:20:39.415897 systemd[1]: Finished systemd-udev-trigger.service. Dec 13 14:20:39.417250 systemd[1]: Mounted sys-fs-fuse-connections.mount. Dec 13 14:20:39.443810 udevadm[1033]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Dec 13 14:20:39.418233 systemd[1]: Mounted sys-kernel-config.mount. Dec 13 14:20:39.420073 systemd[1]: Starting systemd-udev-settle.service... Dec 13 14:20:39.444000 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' Dec 13 14:20:39.426400 systemd[1]: Finished systemd-random-seed.service. Dec 13 14:20:39.427537 systemd[1]: Reached target first-boot-complete.target. Dec 13 14:20:39.440119 systemd[1]: Finished systemd-sysctl.service. Dec 13 14:20:39.443307 systemd[1]: Finished systemd-journal-flush.service. Dec 13 14:20:39.450492 systemd[1]: Finished systemd-sysusers.service. Dec 13 14:20:39.451000 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' Dec 13 14:20:39.780689 systemd[1]: Finished systemd-hwdb-update.service. Dec 13 14:20:39.781000 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' Dec 13 14:20:39.782000 audit: BPF prog-id=18 op=LOAD Dec 13 14:20:39.783000 audit: BPF prog-id=19 op=LOAD Dec 13 14:20:39.783000 audit: BPF prog-id=7 op=UNLOAD Dec 13 14:20:39.783000 audit: BPF prog-id=8 op=UNLOAD Dec 13 14:20:39.783899 systemd[1]: Starting systemd-udevd.service... Dec 13 14:20:39.803697 systemd-udevd[1036]: Using default interface naming scheme 'v252'. Dec 13 14:20:39.830840 systemd[1]: Started systemd-udevd.service. Dec 13 14:20:39.831000 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' Dec 13 14:20:39.832000 audit: BPF prog-id=20 op=LOAD Dec 13 14:20:39.835928 systemd[1]: Starting systemd-networkd.service... Dec 13 14:20:39.843000 audit: BPF prog-id=21 op=LOAD Dec 13 14:20:39.843000 audit: BPF prog-id=22 op=LOAD Dec 13 14:20:39.843000 audit: BPF prog-id=23 op=LOAD Dec 13 14:20:39.843958 systemd[1]: Starting systemd-userdbd.service... Dec 13 14:20:39.851909 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Dec 13 14:20:39.881172 systemd[1]: Started systemd-userdbd.service. Dec 13 14:20:39.881000 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' Dec 13 14:20:39.905055 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Dec 13 14:20:39.937960 systemd-networkd[1053]: lo: Link UP Dec 13 14:20:39.937973 systemd-networkd[1053]: lo: Gained carrier Dec 13 14:20:39.938402 systemd-networkd[1053]: Enumeration completed Dec 13 14:20:39.938507 systemd-networkd[1053]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 14:20:39.938511 systemd[1]: Started systemd-networkd.service. Dec 13 14:20:39.939000 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' Dec 13 14:20:39.945431 systemd-networkd[1053]: eth0: Link UP Dec 13 14:20:39.945441 systemd-networkd[1053]: eth0: Gained carrier Dec 13 14:20:39.947562 systemd[1]: Finished systemd-udev-settle.service. Dec 13 14:20:39.948000 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' Dec 13 14:20:39.949456 systemd[1]: Starting lvm2-activation-early.service... Dec 13 14:20:39.961175 lvm[1069]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 14:20:39.963228 systemd-networkd[1053]: eth0: DHCPv4 address 10.0.0.135/16, gateway 10.0.0.1 acquired from 10.0.0.1 Dec 13 14:20:39.995000 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' Dec 13 14:20:39.995966 systemd[1]: Finished lvm2-activation-early.service. Dec 13 14:20:39.996868 systemd[1]: Reached target cryptsetup.target. Dec 13 14:20:39.998733 systemd[1]: Starting lvm2-activation.service... Dec 13 14:20:40.002097 lvm[1070]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 14:20:40.031857 systemd[1]: Finished lvm2-activation.service. Dec 13 14:20:40.032000 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' Dec 13 14:20:40.032684 systemd[1]: Reached target local-fs-pre.target. Dec 13 14:20:40.033449 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Dec 13 14:20:40.033476 systemd[1]: Reached target local-fs.target. Dec 13 14:20:40.034155 systemd[1]: Reached target machines.target. Dec 13 14:20:40.035945 systemd[1]: Starting ldconfig.service... Dec 13 14:20:40.037815 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.037866 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). Dec 13 14:20:40.038975 systemd[1]: Starting systemd-boot-update.service... Dec 13 14:20:40.041330 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Dec 13 14:20:40.043435 systemd[1]: Starting systemd-machine-id-commit.service... Dec 13 14:20:40.045938 systemd[1]: Starting systemd-sysext.service... Dec 13 14:20:40.047113 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1072 (bootctl) Dec 13 14:20:40.048106 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Dec 13 14:20:40.050564 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Dec 13 14:20:40.050000 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' Dec 13 14:20:40.055934 systemd[1]: Unmounting usr-share-oem.mount... Dec 13 14:20:40.064990 systemd[1]: usr-share-oem.mount: Deactivated successfully. Dec 13 14:20:40.065184 systemd[1]: Unmounted usr-share-oem.mount. Dec 13 14:20:40.077112 kernel: loop0: detected capacity change from 0 to 194512 Dec 13 14:20:40.120819 systemd[1]: Finished systemd-machine-id-commit.service. Dec 13 14:20:40.121000 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' Dec 13 14:20:40.132129 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 13 14:20:40.147344 systemd-fsck[1083]: fsck.fat 4.2 (2021-01-31) Dec 13 14:20:40.147344 systemd-fsck[1083]: /dev/vda1: 236 files, 117175/258078 clusters Dec 13 14:20:40.148880 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Dec 13 14:20:40.150000 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' Dec 13 14:20:40.162125 kernel: loop1: detected capacity change from 0 to 194512 Dec 13 14:20:40.172776 (sd-sysext)[1086]: Using extensions 'kubernetes'. Dec 13 14:20:40.173403 (sd-sysext)[1086]: Merged extensions into '/usr'. Dec 13 14:20:40.190171 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.191407 systemd[1]: Starting modprobe@dm_mod.service... Dec 13 14:20:40.193284 systemd[1]: Starting modprobe@efi_pstore.service... Dec 13 14:20:40.195584 systemd[1]: Starting modprobe@loop.service... Dec 13 14:20:40.196387 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.196521 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). Dec 13 14:20:40.197316 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 14:20:40.197442 systemd[1]: Finished modprobe@dm_mod.service. Dec 13 14:20:40.198000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:40.198000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:40.198637 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 14:20:40.198739 systemd[1]: Finished modprobe@efi_pstore.service. Dec 13 14:20:40.199000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:40.199000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Dec 13 14:20:40.199823 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 14:20:40.199916 systemd[1]: Finished modprobe@loop.service. Dec 13 14:20:40.200000 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' Dec 13 14:20:40.200000 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' Dec 13 14:20:40.201157 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 14:20:40.201291 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.323896 ldconfig[1071]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 13 14:20:40.330066 systemd[1]: Finished ldconfig.service. Dec 13 14:20:40.330000 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' Dec 13 14:20:40.365731 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 13 14:20:40.367397 systemd[1]: Mounting boot.mount... Dec 13 14:20:40.369246 systemd[1]: Mounting usr-share-oem.mount... Dec 13 14:20:40.374329 systemd[1]: Mounted usr-share-oem.mount. Dec 13 14:20:40.376506 systemd[1]: Finished systemd-sysext.service. Dec 13 14:20:40.377000 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' Dec 13 14:20:40.378506 systemd[1]: Starting ensure-sysext.service... Dec 13 14:20:40.380069 systemd[1]: Starting systemd-tmpfiles-setup.service... Dec 13 14:20:40.383694 systemd[1]: Mounted boot.mount. Dec 13 14:20:40.385804 systemd[1]: Reloading. Dec 13 14:20:40.396811 systemd-tmpfiles[1094]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Dec 13 14:20:40.403869 systemd-tmpfiles[1094]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 13 14:20:40.407659 systemd-tmpfiles[1094]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 13 14:20:40.422334 /usr/lib/systemd/system-generators/torcx-generator[1114]: time="2024-12-13T14:20:40Z" 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.6 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.6 /var/lib/torcx/store]" Dec 13 14:20:40.422671 /usr/lib/systemd/system-generators/torcx-generator[1114]: time="2024-12-13T14:20:40Z" level=info msg="torcx already run" Dec 13 14:20:40.501845 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Dec 13 14:20:40.501864 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Dec 13 14:20:40.517136 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. Dec 13 14:20:40.561000 audit: BPF prog-id=24 op=LOAD Dec 13 14:20:40.561000 audit: BPF prog-id=15 op=UNLOAD Dec 13 14:20:40.561000 audit: BPF prog-id=25 op=LOAD Dec 13 14:20:40.561000 audit: BPF prog-id=26 op=LOAD Dec 13 14:20:40.561000 audit: BPF prog-id=16 op=UNLOAD Dec 13 14:20:40.561000 audit: BPF prog-id=17 op=UNLOAD Dec 13 14:20:40.561000 audit: BPF prog-id=27 op=LOAD Dec 13 14:20:40.561000 audit: BPF prog-id=21 op=UNLOAD Dec 13 14:20:40.561000 audit: BPF prog-id=28 op=LOAD Dec 13 14:20:40.561000 audit: BPF prog-id=29 op=LOAD Dec 13 14:20:40.561000 audit: BPF prog-id=22 op=UNLOAD Dec 13 14:20:40.561000 audit: BPF prog-id=23 op=UNLOAD Dec 13 14:20:40.563000 audit: BPF prog-id=30 op=LOAD Dec 13 14:20:40.563000 audit: BPF prog-id=20 op=UNLOAD Dec 13 14:20:40.564000 audit: BPF prog-id=31 op=LOAD Dec 13 14:20:40.564000 audit: BPF prog-id=32 op=LOAD Dec 13 14:20:40.564000 audit: BPF prog-id=18 op=UNLOAD Dec 13 14:20:40.564000 audit: BPF prog-id=19 op=UNLOAD Dec 13 14:20:40.566315 systemd[1]: Finished systemd-boot-update.service. Dec 13 14:20:40.567000 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' Dec 13 14:20:40.568395 systemd[1]: Finished systemd-tmpfiles-setup.service. Dec 13 14:20:40.569000 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' Dec 13 14:20:40.571755 systemd[1]: Starting audit-rules.service... Dec 13 14:20:40.573539 systemd[1]: Starting clean-ca-certificates.service... Dec 13 14:20:40.575468 systemd[1]: Starting systemd-journal-catalog-update.service... Dec 13 14:20:40.580000 audit: BPF prog-id=33 op=LOAD Dec 13 14:20:40.581159 systemd[1]: Starting systemd-resolved.service... Dec 13 14:20:40.582000 audit: BPF prog-id=34 op=LOAD Dec 13 14:20:40.583521 systemd[1]: Starting systemd-timesyncd.service... Dec 13 14:20:40.586242 systemd[1]: Starting systemd-update-utmp.service... Dec 13 14:20:40.587487 systemd[1]: Finished clean-ca-certificates.service. Dec 13 14:20:40.588000 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' Dec 13 14:20:40.590291 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Dec 13 14:20:40.591867 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.593072 systemd[1]: Starting modprobe@dm_mod.service... Dec 13 14:20:40.594000 audit[1163]: SYSTEM_BOOT pid=1163 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' Dec 13 14:20:40.595329 systemd[1]: Starting modprobe@efi_pstore.service... Dec 13 14:20:40.597335 systemd[1]: Starting modprobe@loop.service... Dec 13 14:20:40.598592 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.598734 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). Dec 13 14:20:40.598825 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Dec 13 14:20:40.599786 systemd[1]: Finished systemd-journal-catalog-update.service. Dec 13 14:20:40.600000 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' Dec 13 14:20:40.601232 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 14:20:40.601342 systemd[1]: Finished modprobe@dm_mod.service. Dec 13 14:20:40.602000 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' Dec 13 14:20:40.602000 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' Dec 13 14:20:40.602651 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 14:20:40.602769 systemd[1]: Finished modprobe@efi_pstore.service. Dec 13 14:20:40.603000 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' Dec 13 14:20:40.603000 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' Dec 13 14:20:40.603909 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 14:20:40.604022 systemd[1]: Finished modprobe@loop.service. Dec 13 14:20:40.605000 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' Dec 13 14:20:40.605000 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' Dec 13 14:20:40.608297 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.610208 systemd[1]: Starting modprobe@dm_mod.service... Dec 13 14:20:40.612153 systemd[1]: Starting modprobe@efi_pstore.service... Dec 13 14:20:40.614167 systemd[1]: Starting modprobe@loop.service... Dec 13 14:20:40.614823 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.615004 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). Dec 13 14:20:40.616692 systemd[1]: Starting systemd-update-done.service... Dec 13 14:20:40.617449 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Dec 13 14:20:40.618839 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 14:20:40.618970 systemd[1]: Finished modprobe@dm_mod.service. Dec 13 14:20:40.619000 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' Dec 13 14:20:40.619000 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' Dec 13 14:20:40.620328 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 14:20:40.620448 systemd[1]: Finished modprobe@efi_pstore.service. Dec 13 14:20:40.621000 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' Dec 13 14:20:40.621000 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' Dec 13 14:20:40.621744 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 14:20:40.621854 systemd[1]: Finished modprobe@loop.service. Dec 13 14:20:40.622000 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' Dec 13 14:20:40.622000 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' Dec 13 14:20:40.625395 systemd[1]: Finished systemd-update-utmp.service. Dec 13 14:20:40.626000 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' Dec 13 14:20:40.626858 systemd[1]: Finished systemd-update-done.service. Dec 13 14:20:40.627000 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' Dec 13 14:20:40.629329 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.630478 systemd[1]: Starting modprobe@dm_mod.service... Dec 13 14:20:40.632167 systemd[1]: Starting modprobe@drm.service... Dec 13 14:20:40.634036 systemd[1]: Starting modprobe@efi_pstore.service... Dec 13 14:20:40.635935 systemd[1]: Starting modprobe@loop.service... Dec 13 14:20:40.636924 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.636984 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). Dec 13 14:20:40.638063 systemd[1]: Starting systemd-networkd-wait-online.service... Dec 13 14:20:40.639120 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Dec 13 14:20:40.639000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Dec 13 14:20:40.639000 audit[1183]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffda287d30 a2=420 a3=0 items=0 ppid=1152 pid=1183 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) Dec 13 14:20:40.639000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Dec 13 14:20:40.639859 systemd[1]: Finished ensure-sysext.service. Dec 13 14:20:40.639989 augenrules[1183]: No rules Dec 13 14:20:40.641108 systemd[1]: Finished audit-rules.service. Dec 13 14:20:40.641902 systemd[1]: Started systemd-timesyncd.service. Dec 13 14:20:40.643025 systemd-timesyncd[1162]: Contacted time server 10.0.0.1:123 (10.0.0.1). Dec 13 14:20:40.643079 systemd-timesyncd[1162]: Initial clock synchronization to Fri 2024-12-13 14:20:40.733897 UTC. Dec 13 14:20:40.643469 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 14:20:40.643595 systemd[1]: Finished modprobe@dm_mod.service. Dec 13 14:20:40.644716 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 14:20:40.644837 systemd[1]: Finished modprobe@drm.service. Dec 13 14:20:40.645764 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 14:20:40.645885 systemd[1]: Finished modprobe@efi_pstore.service. Dec 13 14:20:40.646979 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 14:20:40.647116 systemd[1]: Finished modprobe@loop.service. Dec 13 14:20:40.648752 systemd[1]: Reached target time-set.target. Dec 13 14:20:40.649450 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 14:20:40.649494 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.653496 systemd-resolved[1158]: Positive Trust Anchors: Dec 13 14:20:40.653506 systemd-resolved[1158]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 14:20:40.653534 systemd-resolved[1158]: 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 Dec 13 14:20:40.668104 systemd-resolved[1158]: Defaulting to hostname 'linux'. Dec 13 14:20:40.669501 systemd[1]: Started systemd-resolved.service. Dec 13 14:20:40.670400 systemd[1]: Reached target network.target. Dec 13 14:20:40.671170 systemd[1]: Reached target nss-lookup.target. Dec 13 14:20:40.671935 systemd[1]: Reached target sysinit.target. Dec 13 14:20:40.672813 systemd[1]: Started motdgen.path. Dec 13 14:20:40.673525 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Dec 13 14:20:40.674787 systemd[1]: Started logrotate.timer. Dec 13 14:20:40.675493 systemd[1]: Started mdadm.timer. Dec 13 14:20:40.676164 systemd[1]: Started systemd-tmpfiles-clean.timer. Dec 13 14:20:40.676887 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 13 14:20:40.676921 systemd[1]: Reached target paths.target. Dec 13 14:20:40.677674 systemd[1]: Reached target timers.target. Dec 13 14:20:40.678736 systemd[1]: Listening on dbus.socket. Dec 13 14:20:40.680494 systemd[1]: Starting docker.socket... Dec 13 14:20:40.683806 systemd[1]: Listening on sshd.socket. Dec 13 14:20:40.684654 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). Dec 13 14:20:40.685079 systemd[1]: Listening on docker.socket. Dec 13 14:20:40.685917 systemd[1]: Reached target sockets.target. Dec 13 14:20:40.686724 systemd[1]: Reached target basic.target. Dec 13 14:20:40.687497 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.687530 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Dec 13 14:20:40.688488 systemd[1]: Starting containerd.service... Dec 13 14:20:40.690202 systemd[1]: Starting dbus.service... Dec 13 14:20:40.691873 systemd[1]: Starting enable-oem-cloudinit.service... Dec 13 14:20:40.693943 systemd[1]: Starting extend-filesystems.service... Dec 13 14:20:40.694856 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Dec 13 14:20:40.696362 systemd[1]: Starting motdgen.service... Dec 13 14:20:40.698297 systemd[1]: Starting ssh-key-proc-cmdline.service... Dec 13 14:20:40.701799 systemd[1]: Starting sshd-keygen.service... Dec 13 14:20:40.705042 jq[1195]: false Dec 13 14:20:40.705690 systemd[1]: Starting systemd-logind.service... Dec 13 14:20:40.706450 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Dec 13 14:20:40.706532 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 13 14:20:40.706948 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 13 14:20:40.707774 systemd[1]: Starting update-engine.service... Dec 13 14:20:40.709647 systemd[1]: Starting update-ssh-keys-after-ignition.service... Dec 13 14:20:40.712717 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 13 14:20:40.716373 jq[1209]: true Dec 13 14:20:40.712902 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Dec 13 14:20:40.713232 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 13 14:20:40.713428 systemd[1]: Finished ssh-key-proc-cmdline.service. Dec 13 14:20:40.727629 jq[1212]: true Dec 13 14:20:40.729374 systemd[1]: motdgen.service: Deactivated successfully. Dec 13 14:20:40.729568 systemd[1]: Finished motdgen.service. Dec 13 14:20:40.729785 extend-filesystems[1196]: Found loop1 Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda1 Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda2 Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda3 Dec 13 14:20:40.731158 extend-filesystems[1196]: Found usr Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda4 Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda6 Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda7 Dec 13 14:20:40.731158 extend-filesystems[1196]: Found vda9 Dec 13 14:20:40.731158 extend-filesystems[1196]: Checking size of /dev/vda9 Dec 13 14:20:40.757508 dbus-daemon[1194]: [system] SELinux support is enabled Dec 13 14:20:40.760341 systemd[1]: Started dbus.service. Dec 13 14:20:40.762074 systemd-logind[1203]: Watching system buttons on /dev/input/event0 (Power Button) Dec 13 14:20:40.762917 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). Dec 13 14:20:40.762940 systemd[1]: Reached target system-config.target. Dec 13 14:20:40.771262 systemd-logind[1203]: New seat seat0. Dec 13 14:20:40.773265 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Dec 13 14:20:40.773289 systemd[1]: Reached target user-config.target. Dec 13 14:20:40.775617 extend-filesystems[1196]: Resized partition /dev/vda9 Dec 13 14:20:40.776854 systemd[1]: Started systemd-logind.service. Dec 13 14:20:40.778732 extend-filesystems[1241]: resize2fs 1.46.5 (30-Dec-2021) Dec 13 14:20:40.805124 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Dec 13 14:20:40.809504 env[1213]: time="2024-12-13T14:20:40.809405840Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Dec 13 14:20:40.812050 bash[1238]: Updated "/home/core/.ssh/authorized_keys" Dec 13 14:20:40.811078 systemd[1]: Finished update-ssh-keys-after-ignition.service. Dec 13 14:20:40.820485 update_engine[1206]: I1213 14:20:40.820233 1206 main.cc:92] Flatcar Update Engine starting Dec 13 14:20:40.823133 systemd[1]: Started update-engine.service. Dec 13 14:20:40.823323 update_engine[1206]: I1213 14:20:40.823303 1206 update_check_scheduler.cc:74] Next update check in 5m46s Dec 13 14:20:40.826727 systemd[1]: Started locksmithd.service. Dec 13 14:20:40.832773 env[1213]: time="2024-12-13T14:20:40.832725440Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Dec 13 14:20:40.832947 env[1213]: time="2024-12-13T14:20:40.832902120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:20:40.834101 env[1213]: time="2024-12-13T14:20:40.834038000Z" 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.173-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Dec 13 14:20:40.834101 env[1213]: time="2024-12-13T14:20:40.834068880Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:20:40.834302 env[1213]: time="2024-12-13T14:20:40.834279520Z" 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 Dec 13 14:20:40.834331 env[1213]: time="2024-12-13T14:20:40.834302880Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Dec 13 14:20:40.834331 env[1213]: time="2024-12-13T14:20:40.834316480Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Dec 13 14:20:40.834331 env[1213]: time="2024-12-13T14:20:40.834326040Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Dec 13 14:20:40.834408 env[1213]: time="2024-12-13T14:20:40.834397680Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:20:40.834697 env[1213]: time="2024-12-13T14:20:40.834667160Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Dec 13 14:20:40.834854 env[1213]: time="2024-12-13T14:20:40.834813840Z" 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 Dec 13 14:20:40.834854 env[1213]: time="2024-12-13T14:20:40.834833200Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Dec 13 14:20:40.834965 env[1213]: time="2024-12-13T14:20:40.834897000Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Dec 13 14:20:40.834965 env[1213]: time="2024-12-13T14:20:40.834909120Z" level=info msg="metadata content store policy set" policy=shared Dec 13 14:20:40.837117 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Dec 13 14:20:40.854009 extend-filesystems[1241]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Dec 13 14:20:40.854009 extend-filesystems[1241]: old_desc_blocks = 1, new_desc_blocks = 1 Dec 13 14:20:40.854009 extend-filesystems[1241]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Dec 13 14:20:40.858682 extend-filesystems[1196]: Resized filesystem in /dev/vda9 Dec 13 14:20:40.854852 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 13 14:20:40.855044 systemd[1]: Finished extend-filesystems.service. Dec 13 14:20:40.861200 env[1213]: time="2024-12-13T14:20:40.861159920Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Dec 13 14:20:40.861263 env[1213]: time="2024-12-13T14:20:40.861209320Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Dec 13 14:20:40.861263 env[1213]: time="2024-12-13T14:20:40.861224800Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Dec 13 14:20:40.861301 env[1213]: time="2024-12-13T14:20:40.861265160Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861301 env[1213]: time="2024-12-13T14:20:40.861282120Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861301 env[1213]: time="2024-12-13T14:20:40.861295800Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861395 env[1213]: time="2024-12-13T14:20:40.861310320Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861758 env[1213]: time="2024-12-13T14:20:40.861735840Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861803 env[1213]: time="2024-12-13T14:20:40.861761920Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861803 env[1213]: time="2024-12-13T14:20:40.861777360Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861803 env[1213]: time="2024-12-13T14:20:40.861791800Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.861868 env[1213]: time="2024-12-13T14:20:40.861806160Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Dec 13 14:20:40.861973 env[1213]: time="2024-12-13T14:20:40.861955920Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Dec 13 14:20:40.862050 env[1213]: time="2024-12-13T14:20:40.862035000Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Dec 13 14:20:40.862330 env[1213]: time="2024-12-13T14:20:40.862313040Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Dec 13 14:20:40.862369 env[1213]: time="2024-12-13T14:20:40.862343560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862369 env[1213]: time="2024-12-13T14:20:40.862359400Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Dec 13 14:20:40.862530 env[1213]: time="2024-12-13T14:20:40.862518200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862561 env[1213]: time="2024-12-13T14:20:40.862534400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862561 env[1213]: time="2024-12-13T14:20:40.862547080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862561 env[1213]: time="2024-12-13T14:20:40.862558360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862657 env[1213]: time="2024-12-13T14:20:40.862638360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862690 env[1213]: time="2024-12-13T14:20:40.862666840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862690 env[1213]: time="2024-12-13T14:20:40.862680080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862729 env[1213]: time="2024-12-13T14:20:40.862693080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862729 env[1213]: time="2024-12-13T14:20:40.862707240Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Dec 13 14:20:40.862851 env[1213]: time="2024-12-13T14:20:40.862830280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862880 env[1213]: time="2024-12-13T14:20:40.862852720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862880 env[1213]: time="2024-12-13T14:20:40.862866320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.862919 env[1213]: time="2024-12-13T14:20:40.862878000Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Dec 13 14:20:40.862919 env[1213]: time="2024-12-13T14:20:40.862893360Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Dec 13 14:20:40.862919 env[1213]: time="2024-12-13T14:20:40.862903800Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Dec 13 14:20:40.862982 env[1213]: time="2024-12-13T14:20:40.862920440Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Dec 13 14:20:40.862982 env[1213]: time="2024-12-13T14:20:40.862953760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Dec 13 14:20:40.863205 env[1213]: time="2024-12-13T14:20:40.863155920Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Dec 13 14:20:40.863947 env[1213]: time="2024-12-13T14:20:40.863214520Z" level=info msg="Connect containerd service" Dec 13 14:20:40.863947 env[1213]: time="2024-12-13T14:20:40.863241640Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Dec 13 14:20:40.864030 env[1213]: time="2024-12-13T14:20:40.863985600Z" 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" Dec 13 14:20:40.864213 env[1213]: time="2024-12-13T14:20:40.864189000Z" level=info msg="Start subscribing containerd event" Dec 13 14:20:40.864246 env[1213]: time="2024-12-13T14:20:40.864234560Z" level=info msg="Start recovering state" Dec 13 14:20:40.864305 env[1213]: time="2024-12-13T14:20:40.864293800Z" level=info msg="Start event monitor" Dec 13 14:20:40.864341 env[1213]: time="2024-12-13T14:20:40.864313400Z" level=info msg="Start snapshots syncer" Dec 13 14:20:40.864341 env[1213]: time="2024-12-13T14:20:40.864323800Z" level=info msg="Start cni network conf syncer for default" Dec 13 14:20:40.864341 env[1213]: time="2024-12-13T14:20:40.864331440Z" level=info msg="Start streaming server" Dec 13 14:20:40.864781 env[1213]: time="2024-12-13T14:20:40.864761560Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 13 14:20:40.864812 env[1213]: time="2024-12-13T14:20:40.864804840Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 13 14:20:40.864970 systemd[1]: Started containerd.service. Dec 13 14:20:40.866296 env[1213]: time="2024-12-13T14:20:40.866268120Z" level=info msg="containerd successfully booted in 0.058634s" Dec 13 14:20:40.885000 locksmithd[1246]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 13 14:20:41.279643 systemd-networkd[1053]: eth0: Gained IPv6LL Dec 13 14:20:41.281328 systemd[1]: Finished systemd-networkd-wait-online.service. Dec 13 14:20:41.282643 systemd[1]: Reached target network-online.target. Dec 13 14:20:41.285036 systemd[1]: Starting kubelet.service... Dec 13 14:20:41.808642 systemd[1]: Started kubelet.service. Dec 13 14:20:42.054741 sshd_keygen[1211]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 13 14:20:42.072722 systemd[1]: Finished sshd-keygen.service. Dec 13 14:20:42.075176 systemd[1]: Starting issuegen.service... Dec 13 14:20:42.079769 systemd[1]: issuegen.service: Deactivated successfully. Dec 13 14:20:42.079918 systemd[1]: Finished issuegen.service. Dec 13 14:20:42.082323 systemd[1]: Starting systemd-user-sessions.service... Dec 13 14:20:42.089315 systemd[1]: Finished systemd-user-sessions.service. Dec 13 14:20:42.091572 systemd[1]: Started getty@tty1.service. Dec 13 14:20:42.093746 systemd[1]: Started serial-getty@ttyAMA0.service. Dec 13 14:20:42.095000 systemd[1]: Reached target getty.target. Dec 13 14:20:42.096055 systemd[1]: Reached target multi-user.target. Dec 13 14:20:42.098365 systemd[1]: Starting systemd-update-utmp-runlevel.service... Dec 13 14:20:42.104820 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 13 14:20:42.104978 systemd[1]: Finished systemd-update-utmp-runlevel.service. Dec 13 14:20:42.106125 systemd[1]: Startup finished in 578ms (kernel) + 3.845s (initrd) + 4.690s (userspace) = 9.114s. Dec 13 14:20:42.349216 kubelet[1258]: E1213 14:20:42.349072 1258 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Dec 13 14:20:42.351566 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Dec 13 14:20:42.351683 systemd[1]: kubelet.service: Failed with result 'exit-code'. Dec 13 14:20:46.440281 systemd[1]: Created slice system-sshd.slice. Dec 13 14:20:46.441377 systemd[1]: Started sshd@0-10.0.0.135:22-10.0.0.1:41940.service. Dec 13 14:20:46.484701 sshd[1281]: Accepted publickey for core from 10.0.0.1 port 41940 ssh2: RSA SHA256:/HJyHm5Z3TKV0xVrRefgtheJNUHxRnoHBht1EzpqsE0 Dec 13 14:20:46.486659 sshd[1281]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:20:46.496452 systemd[1]: Created slice user-500.slice. Dec 13 14:20:46.497520 systemd[1]: Starting user-runtime-dir@500.service... Dec 13 14:20:46.499287 systemd-logind[1203]: New session 1 of user core. Dec 13 14:20:46.505522 systemd[1]: Finished user-runtime-dir@500.service. Dec 13 14:20:46.506817 systemd[1]: Starting user@500.service... Dec 13 14:20:46.509383 (systemd)[1284]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:20:46.572971 systemd[1284]: Queued start job for default target default.target. Dec 13 14:20:46.573436 systemd[1284]: Reached target paths.target. Dec 13 14:20:46.573455 systemd[1284]: Reached target sockets.target. Dec 13 14:20:46.573468 systemd[1284]: Reached target timers.target. Dec 13 14:20:46.573478 systemd[1284]: Reached target basic.target. Dec 13 14:20:46.573530 systemd[1284]: Reached target default.target. Dec 13 14:20:46.573555 systemd[1284]: Startup finished in 58ms. Dec 13 14:20:46.573769 systemd[1]: Started user@500.service. Dec 13 14:20:46.574720 systemd[1]: Started session-1.scope. Dec 13 14:20:46.627602 systemd[1]: Started sshd@1-10.0.0.135:22-10.0.0.1:41950.service. Dec 13 14:20:46.670950 sshd[1293]: Accepted publickey for core from 10.0.0.1 port 41950 ssh2: RSA SHA256:/HJyHm5Z3TKV0xVrRefgtheJNUHxRnoHBht1EzpqsE0 Dec 13 14:20:46.673894 sshd[1293]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:20:46.684267 systemd-logind[1203]: New session 2 of user core. Dec 13 14:20:46.685481 systemd[1]: Started session-2.scope. Dec 13 14:20:46.744958 sshd[1293]: pam_unix(sshd:session): session closed for user core Dec 13 14:20:46.748367 systemd[1]: session-2.scope: Deactivated successfully. Dec 13 14:20:46.748994 systemd-logind[1203]: Session 2 logged out. Waiting for processes to exit. Dec 13 14:20:46.749158 systemd[1]: sshd@1-10.0.0.135:22-10.0.0.1:41950.service: Deactivated successfully. Dec 13 14:20:46.750264 systemd-logind[1203]: Removed session 2. Dec 13 14:20:46.756515 systemd[1]: Started sshd@2-10.0.0.135:22-10.0.0.1:41962.service. Dec 13 14:20:46.804758 sshd[1299]: Accepted publickey for core from 10.0.0.1 port 41962 ssh2: RSA SHA256:/HJyHm5Z3TKV0xVrRefgtheJNUHxRnoHBht1EzpqsE0 Dec 13 14:20:46.806589 sshd[1299]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:20:46.813214 systemd[1]: Started session-3.scope. Dec 13 14:20:46.813505 systemd-logind[1203]: New session 3 of user core. Dec 13 14:20:46.869480 sshd[1299]: pam_unix(sshd:session): session closed for user core Dec 13 14:20:46.874160 systemd[1]: sshd@2-10.0.0.135:22-10.0.0.1:41962.service: Deactivated successfully. Dec 13 14:20:46.874766 systemd[1]: session-3.scope: Deactivated successfully. Dec 13 14:20:46.878754 systemd-logind[1203]: Session 3 logged out. Waiting for processes to exit. Dec 13 14:20:46.883400 systemd[1]: Started sshd@3-10.0.0.135:22-10.0.0.1:41976.service. Dec 13 14:20:46.884244 systemd-logind[1203]: Removed session 3. Dec 13 14:20:46.925112 sshd[1305]: Accepted publickey for core from 10.0.0.1 port 41976 ssh2: RSA SHA256:/HJyHm5Z3TKV0xVrRefgtheJNUHxRnoHBht1EzpqsE0 Dec 13 14:20:46.926499 sshd[1305]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:20:46.931475 systemd[1]: Started session-4.scope. Dec 13 14:20:46.931476 systemd-logind[1203]: New session 4 of user core. Dec 13 14:20:46.989023 sshd[1305]: pam_unix(sshd:session): session closed for user core Dec 13 14:20:46.992056 systemd[1]: sshd@3-10.0.0.135:22-10.0.0.1:41976.service: Deactivated successfully. Dec 13 14:20:46.992753 systemd[1]: session-4.scope: Deactivated successfully. Dec 13 14:20:46.993776 systemd-logind[1203]: Session 4 logged out. Waiting for processes to exit. Dec 13 14:20:46.994926 systemd[1]: Started sshd@4-10.0.0.135:22-10.0.0.1:41988.service. Dec 13 14:20:46.996356 systemd-logind[1203]: Removed session 4. Dec 13 14:20:47.033712 sshd[1311]: Accepted publickey for core from 10.0.0.1 port 41988 ssh2: RSA SHA256:/HJyHm5Z3TKV0xVrRefgtheJNUHxRnoHBht1EzpqsE0 Dec 13 14:20:47.034937 sshd[1311]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 14:20:47.038107 systemd-logind[1203]: New session 5 of user core. Dec 13 14:20:47.039016 systemd[1]: Started session-5.scope. Dec 13 14:20:47.098840 sudo[1314]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Dec 13 14:20:47.099064 sudo[1314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 14:20:47.111447 systemd[1]: Starting coreos-metadata.service... Dec 13 14:20:47.130007 systemd[1]: coreos-metadata.service: Deactivated successfully. Dec 13 14:20:47.130280 systemd[1]: Finished coreos-metadata.service. Dec 13 14:20:47.641971 systemd[1]: Stopped kubelet.service. Dec 13 14:20:47.644305 systemd[1]: Starting kubelet.service... Dec 13 14:20:47.662835 systemd[1]: Reloading. Dec 13 14:20:47.713500 /usr/lib/systemd/system-generators/torcx-generator[1382]: time="2024-12-13T14:20:47Z" 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.6 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.6 /var/lib/torcx/store]" Dec 13 14:20:47.713533 /usr/lib/systemd/system-generators/torcx-generator[1382]: time="2024-12-13T14:20:47Z" level=info msg="torcx already run" Dec 13 14:20:47.864303 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Dec 13 14:20:47.864454 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Dec 13 14:20:47.879824 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. Dec 13 14:20:47.943110 systemd[1]: Started kubelet.service. Dec 13 14:20:47.944536 systemd[1]: Stopping kubelet.service... Dec 13 14:20:47.944898 systemd[1]: kubelet.service: Deactivated successfully. Dec 13 14:20:47.945174 systemd[1]: Stopped kubelet.service. Dec 13 14:20:47.946613 systemd[1]: Starting kubelet.service... Dec 13 14:20:48.026362 systemd[1]: Started kubelet.service. Dec 13 14:20:48.062998 kubelet[1425]: 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. Dec 13 14:20:48.062998 kubelet[1425]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Dec 13 14:20:48.062998 kubelet[1425]: 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. Dec 13 14:20:48.063900 kubelet[1425]: I1213 14:20:48.063840 1425 server.go:204] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Dec 13 14:20:49.169875 kubelet[1425]: I1213 14:20:49.169830 1425 server.go:487] "Kubelet version" kubeletVersion="v1.29.2" Dec 13 14:20:49.169875 kubelet[1425]: I1213 14:20:49.169866 1425 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Dec 13 14:20:49.170220 kubelet[1425]: I1213 14:20:49.170067 1425 server.go:919] "Client rotation is on, will bootstrap in background" Dec 13 14:20:49.197582 kubelet[1425]: I1213 14:20:49.197553 1425 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Dec 13 14:20:49.210337 kubelet[1425]: I1213 14:20:49.210316 1425 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Dec 13 14:20:49.210526 kubelet[1425]: I1213 14:20:49.210516 1425 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Dec 13 14:20:49.210697 kubelet[1425]: I1213 14:20:49.210681 1425 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Dec 13 14:20:49.210781 kubelet[1425]: I1213 14:20:49.210699 1425 topology_manager.go:138] "Creating topology manager with none policy" Dec 13 14:20:49.210781 kubelet[1425]: I1213 14:20:49.210708 1425 container_manager_linux.go:301] "Creating device plugin manager" Dec 13 14:20:49.210825 kubelet[1425]: I1213 14:20:49.210813 1425 state_mem.go:36] "Initialized new in-memory state store" Dec 13 14:20:49.213107 kubelet[1425]: I1213 14:20:49.213079 1425 kubelet.go:396] "Attempting to sync node with API server" Dec 13 14:20:49.213166 kubelet[1425]: I1213 14:20:49.213114 1425 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Dec 13 14:20:49.213166 kubelet[1425]: I1213 14:20:49.213139 1425 kubelet.go:312] "Adding apiserver pod source" Dec 13 14:20:49.213166 kubelet[1425]: I1213 14:20:49.213153 1425 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Dec 13 14:20:49.213309 kubelet[1425]: E1213 14:20:49.213284 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:49.213345 kubelet[1425]: E1213 14:20:49.213318 1425 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:49.214012 kubelet[1425]: I1213 14:20:49.213991 1425 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="1.6.16" apiVersion="v1" Dec 13 14:20:49.214614 kubelet[1425]: I1213 14:20:49.214593 1425 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Dec 13 14:20:49.214728 kubelet[1425]: W1213 14:20:49.214711 1425 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Dec 13 14:20:49.215512 kubelet[1425]: I1213 14:20:49.215482 1425 server.go:1256] "Started kubelet" Dec 13 14:20:49.215986 kubelet[1425]: I1213 14:20:49.215966 1425 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Dec 13 14:20:49.216246 kubelet[1425]: I1213 14:20:49.216225 1425 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Dec 13 14:20:49.216513 kubelet[1425]: I1213 14:20:49.216496 1425 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Dec 13 14:20:49.218574 kernel: SELinux: Context system_u:object_r:container_file_t:s0 is not valid (left unmapped). Dec 13 14:20:49.219687 kubelet[1425]: I1213 14:20:49.219663 1425 server.go:461] "Adding debug handlers to kubelet server" Dec 13 14:20:49.222392 kubelet[1425]: I1213 14:20:49.222368 1425 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Dec 13 14:20:49.223126 kubelet[1425]: I1213 14:20:49.223102 1425 volume_manager.go:291] "Starting Kubelet Volume Manager" Dec 13 14:20:49.224089 kubelet[1425]: I1213 14:20:49.224061 1425 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Dec 13 14:20:49.224153 kubelet[1425]: I1213 14:20:49.224134 1425 reconciler_new.go:29] "Reconciler: start to sync state" Dec 13 14:20:49.226444 kubelet[1425]: E1213 14:20:49.226271 1425 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.135\" not found" Dec 13 14:20:49.230252 kubelet[1425]: E1213 14:20:49.228661 1425 kubelet.go:1462] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Dec 13 14:20:49.231031 kubelet[1425]: I1213 14:20:49.230994 1425 factory.go:221] Registration of the systemd container factory successfully Dec 13 14:20:49.231132 kubelet[1425]: I1213 14:20:49.231112 1425 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 Dec 13 14:20:49.236111 kubelet[1425]: I1213 14:20:49.236069 1425 factory.go:221] Registration of the containerd container factory successfully Dec 13 14:20:49.249343 kubelet[1425]: I1213 14:20:49.249299 1425 cpu_manager.go:214] "Starting CPU manager" policy="none" Dec 13 14:20:49.249343 kubelet[1425]: I1213 14:20:49.249326 1425 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Dec 13 14:20:49.249343 kubelet[1425]: I1213 14:20:49.249348 1425 state_mem.go:36] "Initialized new in-memory state store" Dec 13 14:20:49.249537 kubelet[1425]: E1213 14:20:49.249504 1425 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.135\" not found" node="10.0.0.135" Dec 13 14:20:49.307806 kubelet[1425]: I1213 14:20:49.307769 1425 policy_none.go:49] "None policy: Start" Dec 13 14:20:49.308715 kubelet[1425]: I1213 14:20:49.308697 1425 memory_manager.go:170] "Starting memorymanager" policy="None" Dec 13 14:20:49.308845 kubelet[1425]: I1213 14:20:49.308832 1425 state_mem.go:35] "Initializing new in-memory state store" Dec 13 14:20:49.315566 systemd[1]: Created slice kubepods.slice. Dec 13 14:20:49.320159 systemd[1]: Created slice kubepods-besteffort.slice. Dec 13 14:20:49.327225 kubelet[1425]: I1213 14:20:49.327196 1425 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.135" Dec 13 14:20:49.331424 systemd[1]: Created slice kubepods-burstable.slice. Dec 13 14:20:49.331525 kubelet[1425]: I1213 14:20:49.331484 1425 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.135" Dec 13 14:20:49.332644 kubelet[1425]: I1213 14:20:49.332619 1425 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Dec 13 14:20:49.332839 kubelet[1425]: I1213 14:20:49.332820 1425 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Dec 13 14:20:49.345074 kubelet[1425]: I1213 14:20:49.345029 1425 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Dec 13 14:20:49.345425 env[1213]: time="2024-12-13T14:20:49.345383407Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Dec 13 14:20:49.345650 kubelet[1425]: I1213 14:20:49.345585 1425 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Dec 13 14:20:49.380591 kubelet[1425]: I1213 14:20:49.380565 1425 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Dec 13 14:20:49.381840 kubelet[1425]: I1213 14:20:49.381820 1425 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Dec 13 14:20:49.381931 kubelet[1425]: I1213 14:20:49.381922 1425 status_manager.go:217] "Starting to sync pod status with apiserver" Dec 13 14:20:49.382141 kubelet[1425]: I1213 14:20:49.382129 1425 kubelet.go:2329] "Starting kubelet main sync loop" Dec 13 14:20:49.382257 kubelet[1425]: E1213 14:20:49.382248 1425 kubelet.go:2353] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Dec 13 14:20:50.171845 kubelet[1425]: I1213 14:20:50.171817 1425 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Dec 13 14:20:50.172355 kubelet[1425]: W1213 14:20:50.172332 1425 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.RuntimeClass ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Dec 13 14:20:50.172480 kubelet[1425]: W1213 14:20:50.172383 1425 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.CSIDriver ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Dec 13 14:20:50.172518 kubelet[1425]: W1213 14:20:50.172384 1425 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.Service ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Dec 13 14:20:50.213701 kubelet[1425]: E1213 14:20:50.213676 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:50.213807 kubelet[1425]: I1213 14:20:50.213689 1425 apiserver.go:52] "Watching apiserver" Dec 13 14:20:50.216754 kubelet[1425]: I1213 14:20:50.216728 1425 topology_manager.go:215] "Topology Admit Handler" podUID="845e4956-9f90-4757-ac50-312d2f821b77" podNamespace="kube-flannel" podName="kube-flannel-ds-g26k5" Dec 13 14:20:50.216923 kubelet[1425]: I1213 14:20:50.216904 1425 topology_manager.go:215] "Topology Admit Handler" podUID="6eb8dd86-6d70-43bc-85c3-735acefaa042" podNamespace="kube-system" podName="kube-proxy-b8nsp" Dec 13 14:20:50.221803 systemd[1]: Created slice kubepods-besteffort-pod6eb8dd86_6d70_43bc_85c3_735acefaa042.slice. Dec 13 14:20:50.225254 kubelet[1425]: I1213 14:20:50.225235 1425 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Dec 13 14:20:50.229935 kubelet[1425]: I1213 14:20:50.229914 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/845e4956-9f90-4757-ac50-312d2f821b77-flannel-cfg\") pod \"kube-flannel-ds-g26k5\" (UID: \"845e4956-9f90-4757-ac50-312d2f821b77\") " pod="kube-flannel/kube-flannel-ds-g26k5" Dec 13 14:20:50.230157 kubelet[1425]: I1213 14:20:50.230142 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6eb8dd86-6d70-43bc-85c3-735acefaa042-xtables-lock\") pod \"kube-proxy-b8nsp\" (UID: \"6eb8dd86-6d70-43bc-85c3-735acefaa042\") " pod="kube-system/kube-proxy-b8nsp" Dec 13 14:20:50.230249 kubelet[1425]: I1213 14:20:50.230238 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/6eb8dd86-6d70-43bc-85c3-735acefaa042-kube-proxy\") pod \"kube-proxy-b8nsp\" (UID: \"6eb8dd86-6d70-43bc-85c3-735acefaa042\") " pod="kube-system/kube-proxy-b8nsp" Dec 13 14:20:50.230361 kubelet[1425]: I1213 14:20:50.230349 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/6eb8dd86-6d70-43bc-85c3-735acefaa042-lib-modules\") pod \"kube-proxy-b8nsp\" (UID: \"6eb8dd86-6d70-43bc-85c3-735acefaa042\") " pod="kube-system/kube-proxy-b8nsp" Dec 13 14:20:50.230459 kubelet[1425]: I1213 14:20:50.230450 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4k7jg\" (UniqueName: \"kubernetes.io/projected/6eb8dd86-6d70-43bc-85c3-735acefaa042-kube-api-access-4k7jg\") pod \"kube-proxy-b8nsp\" (UID: \"6eb8dd86-6d70-43bc-85c3-735acefaa042\") " pod="kube-system/kube-proxy-b8nsp" Dec 13 14:20:50.230816 kubelet[1425]: I1213 14:20:50.230801 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/845e4956-9f90-4757-ac50-312d2f821b77-run\") pod \"kube-flannel-ds-g26k5\" (UID: \"845e4956-9f90-4757-ac50-312d2f821b77\") " pod="kube-flannel/kube-flannel-ds-g26k5" Dec 13 14:20:50.230970 kubelet[1425]: I1213 14:20:50.230958 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/845e4956-9f90-4757-ac50-312d2f821b77-cni-plugin\") pod \"kube-flannel-ds-g26k5\" (UID: \"845e4956-9f90-4757-ac50-312d2f821b77\") " pod="kube-flannel/kube-flannel-ds-g26k5" Dec 13 14:20:50.231134 kubelet[1425]: I1213 14:20:50.231080 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/845e4956-9f90-4757-ac50-312d2f821b77-cni\") pod \"kube-flannel-ds-g26k5\" (UID: \"845e4956-9f90-4757-ac50-312d2f821b77\") " pod="kube-flannel/kube-flannel-ds-g26k5" Dec 13 14:20:50.231240 kubelet[1425]: I1213 14:20:50.231230 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/845e4956-9f90-4757-ac50-312d2f821b77-xtables-lock\") pod \"kube-flannel-ds-g26k5\" (UID: \"845e4956-9f90-4757-ac50-312d2f821b77\") " pod="kube-flannel/kube-flannel-ds-g26k5" Dec 13 14:20:50.231366 kubelet[1425]: I1213 14:20:50.231356 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jlm6p\" (UniqueName: \"kubernetes.io/projected/845e4956-9f90-4757-ac50-312d2f821b77-kube-api-access-jlm6p\") pod \"kube-flannel-ds-g26k5\" (UID: \"845e4956-9f90-4757-ac50-312d2f821b77\") " pod="kube-flannel/kube-flannel-ds-g26k5" Dec 13 14:20:50.233613 systemd[1]: Created slice kubepods-burstable-pod845e4956_9f90_4757_ac50_312d2f821b77.slice. Dec 13 14:20:50.533693 kubelet[1425]: E1213 14:20:50.533587 1425 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" Dec 13 14:20:50.534587 env[1213]: time="2024-12-13T14:20:50.534302686Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-b8nsp,Uid:6eb8dd86-6d70-43bc-85c3-735acefaa042,Namespace:kube-system,Attempt:0,}" Dec 13 14:20:50.545144 kubelet[1425]: E1213 14:20:50.544966 1425 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" Dec 13 14:20:50.545664 env[1213]: time="2024-12-13T14:20:50.545616589Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-g26k5,Uid:845e4956-9f90-4757-ac50-312d2f821b77,Namespace:kube-flannel,Attempt:0,}" Dec 13 14:20:50.547904 sudo[1314]: pam_unix(sudo:session): session closed for user root Dec 13 14:20:50.549690 sshd[1311]: pam_unix(sshd:session): session closed for user core Dec 13 14:20:50.552648 systemd[1]: sshd@4-10.0.0.135:22-10.0.0.1:41988.service: Deactivated successfully. Dec 13 14:20:50.553310 systemd[1]: session-5.scope: Deactivated successfully. Dec 13 14:20:50.553790 systemd-logind[1203]: Session 5 logged out. Waiting for processes to exit. Dec 13 14:20:50.554410 systemd-logind[1203]: Removed session 5. Dec 13 14:20:51.088807 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2948957535.mount: Deactivated successfully. Dec 13 14:20:51.095125 env[1213]: time="2024-12-13T14:20:51.094651917Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.098356 env[1213]: time="2024-12-13T14:20:51.098321701Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.101583 env[1213]: time="2024-12-13T14:20:51.101548323Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.102999 env[1213]: time="2024-12-13T14:20:51.102949341Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.104720 env[1213]: time="2024-12-13T14:20:51.104466064Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:7d46a07936af93fcce097459055f93ab07331509aa55f4a2a90d95a3ace1850e,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.106736 env[1213]: time="2024-12-13T14:20:51.106169584Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.108391 env[1213]: time="2024-12-13T14:20:51.108344109Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause:3.6,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.113829 env[1213]: time="2024-12-13T14:20:51.113793680Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/pause@sha256:3d380ca8864549e74af4b29c10f9cb0956236dfb01c40ca076fb6c37253234db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:51.149621 env[1213]: time="2024-12-13T14:20:51.149524433Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 14:20:51.149752 env[1213]: time="2024-12-13T14:20:51.149625735Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 14:20:51.149752 env[1213]: time="2024-12-13T14:20:51.149658112Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 14:20:51.149926 env[1213]: time="2024-12-13T14:20:51.149875681Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da pid=1483 runtime=io.containerd.runc.v2 Dec 13 14:20:51.151345 env[1213]: time="2024-12-13T14:20:51.151277300Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 14:20:51.151345 env[1213]: time="2024-12-13T14:20:51.151311803Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 14:20:51.151345 env[1213]: time="2024-12-13T14:20:51.151321873Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 14:20:51.151464 env[1213]: time="2024-12-13T14:20:51.151437097Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/0255fd8c64d26f4871c7353f4d774bb7b481fda130cae29474381bb18100fbb9 pid=1488 runtime=io.containerd.runc.v2 Dec 13 14:20:51.176752 systemd[1]: Started cri-containerd-0255fd8c64d26f4871c7353f4d774bb7b481fda130cae29474381bb18100fbb9.scope. Dec 13 14:20:51.193926 systemd[1]: Started cri-containerd-d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da.scope. Dec 13 14:20:51.214441 kubelet[1425]: E1213 14:20:51.214398 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:51.217411 env[1213]: time="2024-12-13T14:20:51.217369555Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-b8nsp,Uid:6eb8dd86-6d70-43bc-85c3-735acefaa042,Namespace:kube-system,Attempt:0,} returns sandbox id \"0255fd8c64d26f4871c7353f4d774bb7b481fda130cae29474381bb18100fbb9\"" Dec 13 14:20:51.222716 kubelet[1425]: E1213 14:20:51.222688 1425 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" Dec 13 14:20:51.225020 env[1213]: time="2024-12-13T14:20:51.224987352Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.12\"" Dec 13 14:20:51.233414 env[1213]: time="2024-12-13T14:20:51.233369187Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-g26k5,Uid:845e4956-9f90-4757-ac50-312d2f821b77,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da\"" Dec 13 14:20:51.234402 kubelet[1425]: E1213 14:20:51.234145 1425 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" Dec 13 14:20:52.215249 kubelet[1425]: E1213 14:20:52.215202 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:52.333135 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1425984087.mount: Deactivated successfully. Dec 13 14:20:52.750308 env[1213]: time="2024-12-13T14:20:52.750256268Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy:v1.29.12,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:52.754133 env[1213]: time="2024-12-13T14:20:52.754080771Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:768ee8cfd9311233d038d18430c18136e1ae4dd2e6de40fcf1c670bba2da6d06,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:52.755282 env[1213]: time="2024-12-13T14:20:52.755251186Z" level=info msg="ImageUpdate event &ImageUpdate{Name:registry.k8s.io/kube-proxy:v1.29.12,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:52.756719 env[1213]: time="2024-12-13T14:20:52.756691706Z" level=info msg="ImageCreate event &ImageCreate{Name:registry.k8s.io/kube-proxy@sha256:bc761494b78fa152a759457f42bc9b86ee9d18f5929bb127bd5f72f8e2112c39,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:52.757132 env[1213]: time="2024-12-13T14:20:52.757073703Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.12\" returns image reference \"sha256:768ee8cfd9311233d038d18430c18136e1ae4dd2e6de40fcf1c670bba2da6d06\"" Dec 13 14:20:52.757839 env[1213]: time="2024-12-13T14:20:52.757813354Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Dec 13 14:20:52.759548 env[1213]: time="2024-12-13T14:20:52.759498393Z" level=info msg="CreateContainer within sandbox \"0255fd8c64d26f4871c7353f4d774bb7b481fda130cae29474381bb18100fbb9\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Dec 13 14:20:52.770638 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2630372021.mount: Deactivated successfully. Dec 13 14:20:52.773607 env[1213]: time="2024-12-13T14:20:52.773562946Z" level=info msg="CreateContainer within sandbox \"0255fd8c64d26f4871c7353f4d774bb7b481fda130cae29474381bb18100fbb9\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"9dbaa24db5bb86c9df7e73dd3a89eac98c245270d4201b5aedf21750e13e5f26\"" Dec 13 14:20:52.774570 env[1213]: time="2024-12-13T14:20:52.774527142Z" level=info msg="StartContainer for \"9dbaa24db5bb86c9df7e73dd3a89eac98c245270d4201b5aedf21750e13e5f26\"" Dec 13 14:20:52.794755 systemd[1]: Started cri-containerd-9dbaa24db5bb86c9df7e73dd3a89eac98c245270d4201b5aedf21750e13e5f26.scope. Dec 13 14:20:52.841960 env[1213]: time="2024-12-13T14:20:52.841915768Z" level=info msg="StartContainer for \"9dbaa24db5bb86c9df7e73dd3a89eac98c245270d4201b5aedf21750e13e5f26\" returns successfully" Dec 13 14:20:53.215396 kubelet[1425]: E1213 14:20:53.215296 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:53.392039 kubelet[1425]: E1213 14:20:53.391997 1425 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" Dec 13 14:20:53.400598 kubelet[1425]: I1213 14:20:53.400559 1425 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-b8nsp" podStartSLOduration=2.8665279679999998 podStartE2EDuration="4.400510655s" podCreationTimestamp="2024-12-13 14:20:49 +0000 UTC" firstStartedPulling="2024-12-13 14:20:51.223628098 +0000 UTC m=+3.193870046" lastFinishedPulling="2024-12-13 14:20:52.757610785 +0000 UTC m=+4.727852733" observedRunningTime="2024-12-13 14:20:53.399896372 +0000 UTC m=+5.370138279" watchObservedRunningTime="2024-12-13 14:20:53.400510655 +0000 UTC m=+5.370752602" Dec 13 14:20:53.769535 systemd[1]: run-containerd-runc-k8s.io-9dbaa24db5bb86c9df7e73dd3a89eac98c245270d4201b5aedf21750e13e5f26-runc.YHkSXc.mount: Deactivated successfully. Dec 13 14:20:53.783118 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount186371540.mount: Deactivated successfully. Dec 13 14:20:53.820185 env[1213]: time="2024-12-13T14:20:53.820132998Z" 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:[],}" Dec 13 14:20:53.821500 env[1213]: time="2024-12-13T14:20:53.821470654Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:53.823768 env[1213]: time="2024-12-13T14:20:53.823727330Z" 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:[],}" Dec 13 14:20:53.825421 env[1213]: time="2024-12-13T14:20:53.825398388Z" 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:[],}" Dec 13 14:20:53.825866 env[1213]: time="2024-12-13T14:20:53.825844728Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Dec 13 14:20:53.827246 env[1213]: time="2024-12-13T14:20:53.827216341Z" level=info msg="CreateContainer within sandbox \"d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Dec 13 14:20:53.837840 env[1213]: time="2024-12-13T14:20:53.837800043Z" level=info msg="CreateContainer within sandbox \"d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa\"" Dec 13 14:20:53.838250 env[1213]: time="2024-12-13T14:20:53.838221205Z" level=info msg="StartContainer for \"8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa\"" Dec 13 14:20:53.853481 systemd[1]: Started cri-containerd-8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa.scope. Dec 13 14:20:53.888018 systemd[1]: cri-containerd-8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa.scope: Deactivated successfully. Dec 13 14:20:53.889706 env[1213]: time="2024-12-13T14:20:53.889662176Z" level=info msg="StartContainer for \"8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa\" returns successfully" Dec 13 14:20:54.036782 env[1213]: time="2024-12-13T14:20:54.036668673Z" level=info msg="shim disconnected" id=8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa Dec 13 14:20:54.036982 env[1213]: time="2024-12-13T14:20:54.036961538Z" level=warning msg="cleaning up after shim disconnected" id=8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa namespace=k8s.io Dec 13 14:20:54.037042 env[1213]: time="2024-12-13T14:20:54.037029033Z" level=info msg="cleaning up dead shim" Dec 13 14:20:54.043503 env[1213]: time="2024-12-13T14:20:54.043467308Z" level=warning msg="cleanup warnings time=\"2024-12-13T14:20:54Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1755 runtime=io.containerd.runc.v2\n" Dec 13 14:20:54.215727 kubelet[1425]: E1213 14:20:54.215676 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:54.394956 kubelet[1425]: E1213 14:20:54.394587 1425 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" Dec 13 14:20:54.394956 kubelet[1425]: E1213 14:20:54.394776 1425 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" Dec 13 14:20:54.395857 env[1213]: time="2024-12-13T14:20:54.395822851Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Dec 13 14:20:54.769013 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-8a6b02ac4d02550dc1e04fe76ba4eca9b0bd2a960577fa6f1a6d49e7001042fa-rootfs.mount: Deactivated successfully. Dec 13 14:20:55.215932 kubelet[1425]: E1213 14:20:55.215790 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:55.462200 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount256973964.mount: Deactivated successfully. Dec 13 14:20:56.167931 env[1213]: time="2024-12-13T14:20:56.167880759Z" 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:[],}" Dec 13 14:20:56.169970 env[1213]: time="2024-12-13T14:20:56.169933584Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:56.171973 env[1213]: time="2024-12-13T14:20:56.171938575Z" 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:[],}" Dec 13 14:20:56.173642 env[1213]: time="2024-12-13T14:20:56.173614182Z" level=info msg="ImageCreate event &ImageCreate{Name:docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:20:56.174361 env[1213]: time="2024-12-13T14:20:56.174324029Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Dec 13 14:20:56.176429 env[1213]: time="2024-12-13T14:20:56.176401331Z" level=info msg="CreateContainer within sandbox \"d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Dec 13 14:20:56.185411 env[1213]: time="2024-12-13T14:20:56.185369668Z" level=info msg="CreateContainer within sandbox \"d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6\"" Dec 13 14:20:56.185797 env[1213]: time="2024-12-13T14:20:56.185771724Z" level=info msg="StartContainer for \"d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6\"" Dec 13 14:20:56.202521 systemd[1]: Started cri-containerd-d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6.scope. Dec 13 14:20:56.216263 kubelet[1425]: E1213 14:20:56.216229 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:56.235329 env[1213]: time="2024-12-13T14:20:56.235292499Z" level=info msg="StartContainer for \"d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6\" returns successfully" Dec 13 14:20:56.238999 systemd[1]: cri-containerd-d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6.scope: Deactivated successfully. Dec 13 14:20:56.262493 kubelet[1425]: I1213 14:20:56.262425 1425 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Dec 13 14:20:56.369209 env[1213]: time="2024-12-13T14:20:56.369158472Z" level=info msg="shim disconnected" id=d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6 Dec 13 14:20:56.369209 env[1213]: time="2024-12-13T14:20:56.369213516Z" level=warning msg="cleaning up after shim disconnected" id=d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6 namespace=k8s.io Dec 13 14:20:56.369417 env[1213]: time="2024-12-13T14:20:56.369224493Z" level=info msg="cleaning up dead shim" Dec 13 14:20:56.375429 env[1213]: time="2024-12-13T14:20:56.375389656Z" level=warning msg="cleanup warnings time=\"2024-12-13T14:20:56Z\" level=info msg=\"starting signal loop\" namespace=k8s.io pid=1816 runtime=io.containerd.runc.v2\n" Dec 13 14:20:56.399343 kubelet[1425]: E1213 14:20:56.399314 1425 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" Dec 13 14:20:56.401402 env[1213]: time="2024-12-13T14:20:56.401157847Z" level=info msg="CreateContainer within sandbox \"d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Dec 13 14:20:56.413569 env[1213]: time="2024-12-13T14:20:56.413533484Z" level=info msg="CreateContainer within sandbox \"d33b7dc30a9cb74b4cc0007aad33431b39607728e485bba94fab15dbe41713da\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"e4637fcbab5f9b9bf596c22a1f8588cde40bf4c21984651aac584d0b11a5d6de\"" Dec 13 14:20:56.414162 env[1213]: time="2024-12-13T14:20:56.414132241Z" level=info msg="StartContainer for \"e4637fcbab5f9b9bf596c22a1f8588cde40bf4c21984651aac584d0b11a5d6de\"" Dec 13 14:20:56.426780 systemd[1]: Started cri-containerd-e4637fcbab5f9b9bf596c22a1f8588cde40bf4c21984651aac584d0b11a5d6de.scope. Dec 13 14:20:56.461251 env[1213]: time="2024-12-13T14:20:56.461213800Z" level=info msg="StartContainer for \"e4637fcbab5f9b9bf596c22a1f8588cde40bf4c21984651aac584d0b11a5d6de\" returns successfully" Dec 13 14:20:57.183042 systemd[1]: run-containerd-runc-k8s.io-d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6-runc.CrKypE.mount: Deactivated successfully. Dec 13 14:20:57.183160 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d03fcd953f36ceb980948df880f20652d1a09c4cced096511f17cf0870c611e6-rootfs.mount: Deactivated successfully. Dec 13 14:20:57.216681 kubelet[1425]: E1213 14:20:57.216645 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:57.402160 kubelet[1425]: E1213 14:20:57.402135 1425 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" Dec 13 14:20:57.411342 kubelet[1425]: I1213 14:20:57.411111 1425 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-g26k5" podStartSLOduration=3.470640051 podStartE2EDuration="8.411046884s" podCreationTimestamp="2024-12-13 14:20:49 +0000 UTC" firstStartedPulling="2024-12-13 14:20:51.23478188 +0000 UTC m=+3.205023827" lastFinishedPulling="2024-12-13 14:20:56.175188713 +0000 UTC m=+8.145430660" observedRunningTime="2024-12-13 14:20:57.411003986 +0000 UTC m=+9.381245933" watchObservedRunningTime="2024-12-13 14:20:57.411046884 +0000 UTC m=+9.381288831" Dec 13 14:20:57.532938 systemd-networkd[1053]: flannel.1: Link UP Dec 13 14:20:57.532944 systemd-networkd[1053]: flannel.1: Gained carrier Dec 13 14:20:58.217588 kubelet[1425]: E1213 14:20:58.217552 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:58.403371 kubelet[1425]: E1213 14:20:58.403341 1425 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" Dec 13 14:20:59.218691 kubelet[1425]: E1213 14:20:59.218653 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:20:59.327367 systemd-networkd[1053]: flannel.1: Gained IPv6LL Dec 13 14:21:00.218981 kubelet[1425]: E1213 14:21:00.218923 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:01.219961 kubelet[1425]: E1213 14:21:01.219908 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:01.272755 kubelet[1425]: I1213 14:21:01.272250 1425 topology_manager.go:215] "Topology Admit Handler" podUID="58bff8ff-0a15-4c19-ba0f-e45f5234c31b" podNamespace="default" podName="nginx-deployment-6d5f899847-vnggq" Dec 13 14:21:01.279328 systemd[1]: Created slice kubepods-besteffort-pod58bff8ff_0a15_4c19_ba0f_e45f5234c31b.slice. Dec 13 14:21:01.297332 kubelet[1425]: I1213 14:21:01.297295 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kxh7j\" (UniqueName: \"kubernetes.io/projected/58bff8ff-0a15-4c19-ba0f-e45f5234c31b-kube-api-access-kxh7j\") pod \"nginx-deployment-6d5f899847-vnggq\" (UID: \"58bff8ff-0a15-4c19-ba0f-e45f5234c31b\") " pod="default/nginx-deployment-6d5f899847-vnggq" Dec 13 14:21:01.587780 env[1213]: time="2024-12-13T14:21:01.587526249Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-vnggq,Uid:58bff8ff-0a15-4c19-ba0f-e45f5234c31b,Namespace:default,Attempt:0,}" Dec 13 14:21:01.614396 systemd-networkd[1053]: cni0: Link UP Dec 13 14:21:01.614401 systemd-networkd[1053]: cni0: Gained carrier Dec 13 14:21:01.615753 systemd-networkd[1053]: cni0: Lost carrier Dec 13 14:21:01.621571 systemd-networkd[1053]: veth6658e7cf: Link UP Dec 13 14:21:01.625733 kernel: cni0: port 1(veth6658e7cf) entered blocking state Dec 13 14:21:01.625822 kernel: cni0: port 1(veth6658e7cf) entered disabled state Dec 13 14:21:01.626623 kernel: device veth6658e7cf entered promiscuous mode Dec 13 14:21:01.628106 kernel: cni0: port 1(veth6658e7cf) entered blocking state Dec 13 14:21:01.628159 kernel: cni0: port 1(veth6658e7cf) entered forwarding state Dec 13 14:21:01.630360 kernel: cni0: port 1(veth6658e7cf) entered disabled state Dec 13 14:21:01.639171 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth6658e7cf: link becomes ready Dec 13 14:21:01.639292 kernel: cni0: port 1(veth6658e7cf) entered blocking state Dec 13 14:21:01.639331 kernel: cni0: port 1(veth6658e7cf) entered forwarding state Dec 13 14:21:01.639999 systemd-networkd[1053]: veth6658e7cf: Gained carrier Dec 13 14:21:01.640216 systemd-networkd[1053]: cni0: Gained carrier Dec 13 14:21:01.641974 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000018928), "name":"cbr0", "type":"bridge"} Dec 13 14:21:01.641974 env[1213]: delegateAdd: netconf sent to delegate plugin: Dec 13 14:21:01.655948 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-12-13T14:21:01.655874708Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 14:21:01.655948 env[1213]: time="2024-12-13T14:21:01.655915060Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 14:21:01.655948 env[1213]: time="2024-12-13T14:21:01.655925748Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 14:21:01.656121 env[1213]: time="2024-12-13T14:21:01.656054449Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/800e2bbd359a0d57180064ba8f447b5c89e9531a4943c441259162871422b8d0 pid=1997 runtime=io.containerd.runc.v2 Dec 13 14:21:01.668634 systemd[1]: Started cri-containerd-800e2bbd359a0d57180064ba8f447b5c89e9531a4943c441259162871422b8d0.scope. Dec 13 14:21:01.693241 systemd-resolved[1158]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Dec 13 14:21:01.710992 env[1213]: time="2024-12-13T14:21:01.710948290Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-vnggq,Uid:58bff8ff-0a15-4c19-ba0f-e45f5234c31b,Namespace:default,Attempt:0,} returns sandbox id \"800e2bbd359a0d57180064ba8f447b5c89e9531a4943c441259162871422b8d0\"" Dec 13 14:21:01.712565 env[1213]: time="2024-12-13T14:21:01.712537979Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Dec 13 14:21:02.220114 kubelet[1425]: E1213 14:21:02.220070 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:02.409478 systemd[1]: run-containerd-runc-k8s.io-800e2bbd359a0d57180064ba8f447b5c89e9531a4943c441259162871422b8d0-runc.JZnF0b.mount: Deactivated successfully. Dec 13 14:21:02.911258 systemd-networkd[1053]: veth6658e7cf: Gained IPv6LL Dec 13 14:21:03.220943 kubelet[1425]: E1213 14:21:03.220638 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:03.551286 systemd-networkd[1053]: cni0: Gained IPv6LL Dec 13 14:21:04.200063 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1920090218.mount: Deactivated successfully. Dec 13 14:21:04.221628 kubelet[1425]: E1213 14:21:04.221590 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:05.222702 kubelet[1425]: E1213 14:21:05.222646 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:05.374659 env[1213]: time="2024-12-13T14:21:05.374606151Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:05.375964 env[1213]: time="2024-12-13T14:21:05.375934723Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:05.378494 env[1213]: time="2024-12-13T14:21:05.378458407Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:05.383930 env[1213]: time="2024-12-13T14:21:05.383890231Z" level=info msg="ImageCreate event &ImageCreate{Name:ghcr.io/flatcar/nginx@sha256:e04edf30a4ea4c5a4107110797c72d3ee8a654415f00acd4019be17218afd9a1,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:05.384873 env[1213]: time="2024-12-13T14:21:05.384836387Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de\"" Dec 13 14:21:05.386646 env[1213]: time="2024-12-13T14:21:05.386592397Z" level=info msg="CreateContainer within sandbox \"800e2bbd359a0d57180064ba8f447b5c89e9531a4943c441259162871422b8d0\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Dec 13 14:21:05.399018 env[1213]: time="2024-12-13T14:21:05.398966101Z" level=info msg="CreateContainer within sandbox \"800e2bbd359a0d57180064ba8f447b5c89e9531a4943c441259162871422b8d0\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"52a7902635b32e2e41c9d1919a5791640604c30dfed97b12fead2b639188529a\"" Dec 13 14:21:05.399470 env[1213]: time="2024-12-13T14:21:05.399442401Z" level=info msg="StartContainer for \"52a7902635b32e2e41c9d1919a5791640604c30dfed97b12fead2b639188529a\"" Dec 13 14:21:05.415884 systemd[1]: run-containerd-runc-k8s.io-52a7902635b32e2e41c9d1919a5791640604c30dfed97b12fead2b639188529a-runc.9VA2UQ.mount: Deactivated successfully. Dec 13 14:21:05.417294 systemd[1]: Started cri-containerd-52a7902635b32e2e41c9d1919a5791640604c30dfed97b12fead2b639188529a.scope. Dec 13 14:21:05.451220 env[1213]: time="2024-12-13T14:21:05.451180774Z" level=info msg="StartContainer for \"52a7902635b32e2e41c9d1919a5791640604c30dfed97b12fead2b639188529a\" returns successfully" Dec 13 14:21:06.223839 kubelet[1425]: E1213 14:21:06.223765 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:07.224237 kubelet[1425]: E1213 14:21:07.224204 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:08.071908 kubelet[1425]: I1213 14:21:08.071872 1425 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-vnggq" podStartSLOduration=3.39884393 podStartE2EDuration="7.071830303s" podCreationTimestamp="2024-12-13 14:21:01 +0000 UTC" firstStartedPulling="2024-12-13 14:21:01.712020613 +0000 UTC m=+13.682262560" lastFinishedPulling="2024-12-13 14:21:05.385007026 +0000 UTC m=+17.355248933" observedRunningTime="2024-12-13 14:21:06.429413575 +0000 UTC m=+18.399655522" watchObservedRunningTime="2024-12-13 14:21:08.071830303 +0000 UTC m=+20.042072250" Dec 13 14:21:08.072109 kubelet[1425]: I1213 14:21:08.071976 1425 topology_manager.go:215] "Topology Admit Handler" podUID="0ba8c31e-d19a-4fdf-b4db-7373d29ce6ec" podNamespace="default" podName="nfs-server-provisioner-0" Dec 13 14:21:08.076868 systemd[1]: Created slice kubepods-besteffort-pod0ba8c31e_d19a_4fdf_b4db_7373d29ce6ec.slice. Dec 13 14:21:08.130063 kubelet[1425]: I1213 14:21:08.130027 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/0ba8c31e-d19a-4fdf-b4db-7373d29ce6ec-data\") pod \"nfs-server-provisioner-0\" (UID: \"0ba8c31e-d19a-4fdf-b4db-7373d29ce6ec\") " pod="default/nfs-server-provisioner-0" Dec 13 14:21:08.130206 kubelet[1425]: I1213 14:21:08.130151 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2qzpv\" (UniqueName: \"kubernetes.io/projected/0ba8c31e-d19a-4fdf-b4db-7373d29ce6ec-kube-api-access-2qzpv\") pod \"nfs-server-provisioner-0\" (UID: \"0ba8c31e-d19a-4fdf-b4db-7373d29ce6ec\") " pod="default/nfs-server-provisioner-0" Dec 13 14:21:08.225576 kubelet[1425]: E1213 14:21:08.225537 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:08.380338 env[1213]: time="2024-12-13T14:21:08.380227128Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:0ba8c31e-d19a-4fdf-b4db-7373d29ce6ec,Namespace:default,Attempt:0,}" Dec 13 14:21:08.401937 systemd-networkd[1053]: veth1e863806: Link UP Dec 13 14:21:08.404426 kernel: cni0: port 2(veth1e863806) entered blocking state Dec 13 14:21:08.404506 kernel: cni0: port 2(veth1e863806) entered disabled state Dec 13 14:21:08.404531 kernel: device veth1e863806 entered promiscuous mode Dec 13 14:21:08.414872 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Dec 13 14:21:08.414936 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth1e863806: link becomes ready Dec 13 14:21:08.414962 kernel: cni0: port 2(veth1e863806) entered blocking state Dec 13 14:21:08.416296 kernel: cni0: port 2(veth1e863806) entered forwarding state Dec 13 14:21:08.416356 systemd-networkd[1053]: veth1e863806: Gained carrier Dec 13 14:21:08.417663 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001a928), "name":"cbr0", "type":"bridge"} Dec 13 14:21:08.417663 env[1213]: delegateAdd: netconf sent to delegate plugin: Dec 13 14:21:08.426758 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-12-13T14:21:08.426681998Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 14:21:08.426758 env[1213]: time="2024-12-13T14:21:08.426728413Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 14:21:08.426758 env[1213]: time="2024-12-13T14:21:08.426739536Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 14:21:08.426916 env[1213]: time="2024-12-13T14:21:08.426886981Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/adee8a5698a17f57bccdd1322f3a067936c98438b7362722b2a951b403643034 pid=2183 runtime=io.containerd.runc.v2 Dec 13 14:21:08.444798 systemd[1]: Started cri-containerd-adee8a5698a17f57bccdd1322f3a067936c98438b7362722b2a951b403643034.scope. Dec 13 14:21:08.464531 systemd-resolved[1158]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Dec 13 14:21:08.480441 env[1213]: time="2024-12-13T14:21:08.479517039Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:0ba8c31e-d19a-4fdf-b4db-7373d29ce6ec,Namespace:default,Attempt:0,} returns sandbox id \"adee8a5698a17f57bccdd1322f3a067936c98438b7362722b2a951b403643034\"" Dec 13 14:21:08.481224 env[1213]: time="2024-12-13T14:21:08.481195838Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Dec 13 14:21:09.213976 kubelet[1425]: E1213 14:21:09.213932 1425 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:09.226104 kubelet[1425]: E1213 14:21:09.226068 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:09.242177 systemd[1]: run-containerd-runc-k8s.io-adee8a5698a17f57bccdd1322f3a067936c98438b7362722b2a951b403643034-runc.1GIcPU.mount: Deactivated successfully. Dec 13 14:21:10.227208 kubelet[1425]: E1213 14:21:10.227161 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:10.399253 systemd-networkd[1053]: veth1e863806: Gained IPv6LL Dec 13 14:21:10.514767 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount862267646.mount: Deactivated successfully. Dec 13 14:21:11.227794 kubelet[1425]: E1213 14:21:11.227750 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:12.228079 kubelet[1425]: E1213 14:21:12.228034 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:12.310412 env[1213]: time="2024-12-13T14:21:12.310355562Z" 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:[],}" Dec 13 14:21:12.312723 env[1213]: time="2024-12-13T14:21:12.312681383Z" level=info msg="ImageCreate event &ImageCreate{Name:sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:12.314629 env[1213]: time="2024-12-13T14:21:12.314600251Z" 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:[],}" Dec 13 14:21:12.316497 env[1213]: time="2024-12-13T14:21:12.316472710Z" 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:[],}" Dec 13 14:21:12.317135 env[1213]: time="2024-12-13T14:21:12.317107425Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Dec 13 14:21:12.319082 env[1213]: time="2024-12-13T14:21:12.319055178Z" level=info msg="CreateContainer within sandbox \"adee8a5698a17f57bccdd1322f3a067936c98438b7362722b2a951b403643034\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Dec 13 14:21:12.331496 env[1213]: time="2024-12-13T14:21:12.331458744Z" level=info msg="CreateContainer within sandbox \"adee8a5698a17f57bccdd1322f3a067936c98438b7362722b2a951b403643034\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"82ec46a9845bde0b261ef0a61cd645cf5593017266a63cdb5bc28a6536f30224\"" Dec 13 14:21:12.331926 env[1213]: time="2024-12-13T14:21:12.331887782Z" level=info msg="StartContainer for \"82ec46a9845bde0b261ef0a61cd645cf5593017266a63cdb5bc28a6536f30224\"" Dec 13 14:21:12.348558 systemd[1]: run-containerd-runc-k8s.io-82ec46a9845bde0b261ef0a61cd645cf5593017266a63cdb5bc28a6536f30224-runc.AkIHg7.mount: Deactivated successfully. Dec 13 14:21:12.349919 systemd[1]: Started cri-containerd-82ec46a9845bde0b261ef0a61cd645cf5593017266a63cdb5bc28a6536f30224.scope. Dec 13 14:21:12.411952 env[1213]: time="2024-12-13T14:21:12.411912674Z" level=info msg="StartContainer for \"82ec46a9845bde0b261ef0a61cd645cf5593017266a63cdb5bc28a6536f30224\" returns successfully" Dec 13 14:21:12.446294 kubelet[1425]: I1213 14:21:12.446177 1425 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=0.609375782 podStartE2EDuration="4.446131391s" podCreationTimestamp="2024-12-13 14:21:08 +0000 UTC" firstStartedPulling="2024-12-13 14:21:08.480656631 +0000 UTC m=+20.450898538" lastFinishedPulling="2024-12-13 14:21:12.3174122 +0000 UTC m=+24.287654147" observedRunningTime="2024-12-13 14:21:12.446013129 +0000 UTC m=+24.416255076" watchObservedRunningTime="2024-12-13 14:21:12.446131391 +0000 UTC m=+24.416373338" Dec 13 14:21:13.228383 kubelet[1425]: E1213 14:21:13.228340 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:14.229242 kubelet[1425]: E1213 14:21:14.229199 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:15.230304 kubelet[1425]: E1213 14:21:15.230266 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:16.230457 kubelet[1425]: E1213 14:21:16.230397 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:17.231131 kubelet[1425]: E1213 14:21:17.231082 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:18.231900 kubelet[1425]: E1213 14:21:18.231863 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:19.232991 kubelet[1425]: E1213 14:21:19.232954 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:20.233609 kubelet[1425]: E1213 14:21:20.233554 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:21.233976 kubelet[1425]: E1213 14:21:21.233925 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:22.039564 kubelet[1425]: I1213 14:21:22.039522 1425 topology_manager.go:215] "Topology Admit Handler" podUID="9d01b1f7-37ea-497d-95be-4b9833bdb452" podNamespace="default" podName="test-pod-1" Dec 13 14:21:22.043980 systemd[1]: Created slice kubepods-besteffort-pod9d01b1f7_37ea_497d_95be_4b9833bdb452.slice. Dec 13 14:21:22.099979 kubelet[1425]: I1213 14:21:22.099934 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-adfc11fe-632e-40d5-942d-d8e9eaed5526\" (UniqueName: \"kubernetes.io/nfs/9d01b1f7-37ea-497d-95be-4b9833bdb452-pvc-adfc11fe-632e-40d5-942d-d8e9eaed5526\") pod \"test-pod-1\" (UID: \"9d01b1f7-37ea-497d-95be-4b9833bdb452\") " pod="default/test-pod-1" Dec 13 14:21:22.099979 kubelet[1425]: I1213 14:21:22.099976 1425 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kswkv\" (UniqueName: \"kubernetes.io/projected/9d01b1f7-37ea-497d-95be-4b9833bdb452-kube-api-access-kswkv\") pod \"test-pod-1\" (UID: \"9d01b1f7-37ea-497d-95be-4b9833bdb452\") " pod="default/test-pod-1" Dec 13 14:21:22.224124 kernel: FS-Cache: Loaded Dec 13 14:21:22.234362 kubelet[1425]: E1213 14:21:22.234334 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:22.250551 kernel: RPC: Registered named UNIX socket transport module. Dec 13 14:21:22.250609 kernel: RPC: Registered udp transport module. Dec 13 14:21:22.250627 kernel: RPC: Registered tcp transport module. Dec 13 14:21:22.251656 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 13 14:21:22.291114 kernel: FS-Cache: Netfs 'nfs' registered for caching Dec 13 14:21:22.417461 kernel: NFS: Registering the id_resolver key type Dec 13 14:21:22.417541 kernel: Key type id_resolver registered Dec 13 14:21:22.417562 kernel: Key type id_legacy registered Dec 13 14:21:22.443808 nfsidmap[2343]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Dec 13 14:21:22.446641 nfsidmap[2346]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Dec 13 14:21:22.646013 env[1213]: time="2024-12-13T14:21:22.645968979Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9d01b1f7-37ea-497d-95be-4b9833bdb452,Namespace:default,Attempt:0,}" Dec 13 14:21:22.663210 systemd-networkd[1053]: veth226bd726: Link UP Dec 13 14:21:22.666174 kernel: cni0: port 3(veth226bd726) entered blocking state Dec 13 14:21:22.666240 kernel: cni0: port 3(veth226bd726) entered disabled state Dec 13 14:21:22.667107 kernel: device veth226bd726 entered promiscuous mode Dec 13 14:21:22.674693 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Dec 13 14:21:22.674761 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth226bd726: link becomes ready Dec 13 14:21:22.674789 kernel: cni0: port 3(veth226bd726) entered blocking state Dec 13 14:21:22.674805 kernel: cni0: port 3(veth226bd726) entered forwarding state Dec 13 14:21:22.675778 systemd-networkd[1053]: veth226bd726: Gained carrier Dec 13 14:21:22.677409 env[1213]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Dec 13 14:21:22.677409 env[1213]: delegateAdd: netconf sent to delegate plugin: Dec 13 14:21:22.685744 env[1213]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-12-13T14:21:22.685686020Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 14:21:22.685852 env[1213]: time="2024-12-13T14:21:22.685726184Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 14:21:22.685852 env[1213]: time="2024-12-13T14:21:22.685736704Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 14:21:22.685917 env[1213]: time="2024-12-13T14:21:22.685844393Z" level=info msg="starting signal loop" namespace=k8s.io path=/run/containerd/io.containerd.runtime.v2.task/k8s.io/f4347336e747af476d21177f42dd1a1c0f759f069e2249b7fba2178edcd8b1ca pid=2415 runtime=io.containerd.runc.v2 Dec 13 14:21:22.695998 systemd[1]: Started cri-containerd-f4347336e747af476d21177f42dd1a1c0f759f069e2249b7fba2178edcd8b1ca.scope. Dec 13 14:21:22.716294 systemd-resolved[1158]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Dec 13 14:21:22.730757 env[1213]: time="2024-12-13T14:21:22.730708073Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9d01b1f7-37ea-497d-95be-4b9833bdb452,Namespace:default,Attempt:0,} returns sandbox id \"f4347336e747af476d21177f42dd1a1c0f759f069e2249b7fba2178edcd8b1ca\"" Dec 13 14:21:22.732074 env[1213]: time="2024-12-13T14:21:22.732044817Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Dec 13 14:21:22.943930 env[1213]: time="2024-12-13T14:21:22.943437738Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:22.945640 env[1213]: time="2024-12-13T14:21:22.945609267Z" level=info msg="ImageUpdate event &ImageUpdate{Name:sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:22.950189 env[1213]: time="2024-12-13T14:21:22.950153579Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx:latest,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:22.952215 env[1213]: time="2024-12-13T14:21:22.952190777Z" level=info msg="ImageUpdate event &ImageUpdate{Name:ghcr.io/flatcar/nginx@sha256:e04edf30a4ea4c5a4107110797c72d3ee8a654415f00acd4019be17218afd9a1,Labels:map[string]string{io.cri-containerd.image: managed,},XXX_unrecognized:[],}" Dec 13 14:21:22.952761 env[1213]: time="2024-12-13T14:21:22.952709658Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de\"" Dec 13 14:21:22.954460 env[1213]: time="2024-12-13T14:21:22.954421671Z" level=info msg="CreateContainer within sandbox \"f4347336e747af476d21177f42dd1a1c0f759f069e2249b7fba2178edcd8b1ca\" for container &ContainerMetadata{Name:test,Attempt:0,}" Dec 13 14:21:22.963647 env[1213]: time="2024-12-13T14:21:22.963600943Z" level=info msg="CreateContainer within sandbox \"f4347336e747af476d21177f42dd1a1c0f759f069e2249b7fba2178edcd8b1ca\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"ffa32ee06b6741899e4806a511f5ee2e044d97068fada70c0a037c87a7f1fdb5\"" Dec 13 14:21:22.963960 env[1213]: time="2024-12-13T14:21:22.963924728Z" level=info msg="StartContainer for \"ffa32ee06b6741899e4806a511f5ee2e044d97068fada70c0a037c87a7f1fdb5\"" Dec 13 14:21:22.977305 systemd[1]: Started cri-containerd-ffa32ee06b6741899e4806a511f5ee2e044d97068fada70c0a037c87a7f1fdb5.scope. Dec 13 14:21:23.035150 env[1213]: time="2024-12-13T14:21:23.035046311Z" level=info msg="StartContainer for \"ffa32ee06b6741899e4806a511f5ee2e044d97068fada70c0a037c87a7f1fdb5\" returns successfully" Dec 13 14:21:23.234784 kubelet[1425]: E1213 14:21:23.234625 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:23.463713 kubelet[1425]: I1213 14:21:23.463674 1425 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.242375138 podStartE2EDuration="15.463637625s" podCreationTimestamp="2024-12-13 14:21:08 +0000 UTC" firstStartedPulling="2024-12-13 14:21:22.731684909 +0000 UTC m=+34.701926856" lastFinishedPulling="2024-12-13 14:21:22.952947396 +0000 UTC m=+34.923189343" observedRunningTime="2024-12-13 14:21:23.463235236 +0000 UTC m=+35.433477143" watchObservedRunningTime="2024-12-13 14:21:23.463637625 +0000 UTC m=+35.433879572" Dec 13 14:21:24.234797 kubelet[1425]: E1213 14:21:24.234735 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 14:21:24.607315 systemd-networkd[1053]: veth226bd726: Gained IPv6LL Dec 13 14:21:25.235800 kubelet[1425]: E1213 14:21:25.235711 1425 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"